Dec 8 10:38:00 newsyslog 13705 logfile turned over due to size>1000K Dec 8 10:38:00 newsyslog 13705 logfile turned over due to size>1000K Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 5 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.0110592 median[50%]=0.0139264 [75%]=0.08192 Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.051152 sec Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 7 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: pythonmod: clear, id: 0, pq:0x2589e574d010 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query took 0.014149 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: python module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:1] debug: return error response SERVFAIL Dec 8 10:37:28 unbound 32597 [32597:1] debug: Failed to get a delegation, giving up Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass Dec 8 10:37:28 unbound 32597 [32597:1] debug: pythonmod: clear, id: 0, pq:0x2589e574d020 Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: python module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:1] debug: request has exceeded the maximum number of sends with 33 Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 44695 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=44695 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 199.9.14.201#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=10984 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=12423 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 30 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=22502 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 9664 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=f256 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 198.41.0.4#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=61684 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 27 Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 23653 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=23653 Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=af78 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 202.12.27.33#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=52763 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 25 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=17568 Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=8b95 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 17895 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=17895 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=65248 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 22 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=12442 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 10531 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=0e9b Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.58.128.30#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=13137 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 19 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=6525 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 199.7.83.42#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 29340 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=eaf4 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.33.4.12#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 30230 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=a019 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.33.4.12#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=20861 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 15 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=7350 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 11646 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=97ed Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.36.148.17#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 43783 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=f854 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=45305 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 11 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=6158 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 40559 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=1603 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 198.97.190.53#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=956b Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 199.7.91.13#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=10531 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 42635 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=7ed3 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 198.41.0.4#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 26040 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=589a Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=31411 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 4 Dec 8 10:37:28 unbound 32597 [32597:1] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 63477 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=0af3 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.203.230.10#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 38405 Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=38405 Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=36763 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.203.230.10#53 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: attempt to get extra 2 targets Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:1] debug: worker svcd callback for qstate 0x2589cd1d8c10 Dec 8 10:37:28 unbound 32597 [32597:1] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:1] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:1] debug: close of port 32812 Dec 8 10:37:28 unbound 32597 [32597:1] notice: remote address is ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:1] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:1] debug: opened UDP if=0 port=32812 Dec 8 10:37:28 unbound 32597 [32597:1] debug: inserted new pending reply id=c3e3 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:1] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:1] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:1] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:1] info: 0.008192 0.016384 4 Dec 8 10:37:28 unbound 32597 [32597:1] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:1] info: [25%]=0.011264 median[50%]=0.014336 [75%]=0.098304 Dec 8 10:37:28 unbound 32597 [32597:1] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:1] info: average recursion processing time 0.057320 sec Dec 8 10:37:28 unbound 32597 [32597:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:1] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:1] debug: sending to target: <.> 192.5.5.241#53 Dec 8 10:37:28 unbound 32597 [32597:1] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:1] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:1] debug: attempt to get extra 2 targets Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:1] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:1] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (0 result, 13 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Dec 8 10:37:28 unbound 32597 [32597:1] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:1] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: iterator module exit state is module_wait_subquery Dec 8 10:37:28 unbound 32597 [32597:1] info: priming . IN NS Dec 8 10:37:28 unbound 32597 [32597:1] debug: request has dependency depth of 0 Dec 8 10:37:28 unbound 32597 [32597:1] info: resolving time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:1] debug: iter_handle processing q with state INIT REQUEST STATE Dec 8 10:37:28 unbound 32597 [32597:1] debug: process_request: new external request event Dec 8 10:37:28 unbound 32597 [32597:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: python module exit state is module_wait_module Dec 8 10:37:28 unbound 32597 [32597:1] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:1] debug: using localzone home.arpa. transparent Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.008192 0.016384 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.107514 sec Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 3 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: pythonmod: clear, id: 0, pq:0x2589ea0cd010 Dec 8 10:37:28 unbound 32597 [32597:19] debug: query took 0.014286 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: python module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:19] debug: return error response SERVFAIL Dec 8 10:37:28 unbound 32597 [32597:19] debug: Failed to get a delegation, giving up Dec 8 10:37:28 unbound 32597 [32597:19] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass Dec 8 10:37:28 unbound 32597 [32597:19] debug: pythonmod: clear, id: 0, pq:0x2589ea0cd028 Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: python module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_finished Dec 8 10:37:28 unbound 32597 [32597:19] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 33 Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 54496 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=c612 Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.154128 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=63412 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 198.41.0.4#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 31 Dec 8 10:37:28 unbound 32597 [32597:19] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=28876 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 198.97.190.53#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 30 Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 43455 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=f3e0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:19] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (12 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 8551 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=855d Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.154128 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=44641 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 199.9.14.201#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=24773 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 192.203.230.10#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 26 Dec 8 10:37:28 unbound 32597 [32597:19] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=18495 Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=8687 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 192.36.148.17#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=55864 Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.154128 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 192.33.4.12#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=64303 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:19] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 49090 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=99c4 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:19] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 50150 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=25c0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.154128 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:19] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 40431 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=8272 Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 1RDdc mod1 rep time.nist.gov.home.arpa. A IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:19] info: sending query: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: J.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: B.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: process_response: new external response event Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 4477 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: inserted new pending reply id=f529 Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.154128 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 192.33.4.12#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: F.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: processQueryTargets: . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: worker svcd callback for qstate 0x2589cb97a550 Dec 8 10:37:28 unbound 32597 [32597:19] debug: close fd 125 Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=31094 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: histogram of recursion processing times Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 198.41.0.4#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rpz: iterator module callback: have_rpz=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: L.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: I.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: G.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: D.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: A.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 18 Dec 8 10:37:28 unbound 32597 [32597:19] debug: query response was timeout Dec 8 10:37:28 unbound 32597 [32597:19] info: iterator operate: query . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: start Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] notice: remote address is ip4 192.36.148.17 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=32918 Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced query UDP timeout=376 msec Dec 8 10:37:28 unbound 32597 [32597:19] debug: serviced send timer Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0pvCD mod1 . NS IN Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.065536 0.131072 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: [25%]=0 median[50%]=0 [75%]=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out Dec 8 10:37:28 unbound 32597 [32597:19] debug: dnssec status: not expected Dec 8 10:37:28 unbound 32597 [32597:19] debug: selrtt 376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 202.12.27.33 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 193.0.14.129 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.112.36.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.5.5.241 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.41.0.4 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 199.7.83.42 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.58.128.30 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.203.230.10 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] info: M.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: K.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: H.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: E.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: C.ROOT-SERVERS.NET. * A Dec 8 10:37:28 unbound 32597 [32597:19] info: DelegationPoint<.>: 13 names (0 missing), 13 addrs (13 result, 0 avail) parentNS Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY TARGETS STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: iter_handle processing q with state QUERY RESPONSE STATE Dec 8 10:37:28 unbound 32597 [32597:19] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks start Dec 8 10:37:28 unbound 32597 [32597:19] debug: close of port 20158 Dec 8 10:37:28 unbound 32597 [32597:19] notice: send failed: Can't assign requested address Dec 8 10:37:28 unbound 32597 [32597:19] debug: comm point start listening 125 (-1 msec) Dec 8 10:37:28 unbound 32597 [32597:19] debug: opened UDP if=0 port=20158 Dec 8 10:37:28 unbound 32597 [32597:19] debug: EDNS lookup known=0 vs=0 Dec 8 10:37:28 unbound 32597 [32597:19] debug: svcd callbacks end Dec 8 10:37:28 unbound 32597 [32597:19] debug: cache memory msg=528664 rrset=528664 infra=29285 val=0 Dec 8 10:37:28 unbound 32597 [32597:19] info: 0.131072 0.262144 1 Dec 8 10:37:28 unbound 32597 [32597:19] info: lower(secs) upper(secs) recursions Dec 8 10:37:28 unbound 32597 [32597:19] info: average recursion processing time 0.154128 sec Dec 8 10:37:28 unbound 32597 [32597:19] debug: mesh_run: iterator module exit state is module_wait_reply Dec 8 10:37:28 unbound 32597 [32597:19] debug: sending to target: <.> 192.58.128.30#53 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.9.14.201 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.33.4.12 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 199.7.91.13 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: rtt=376 Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 198.97.190.53 port 53 (len 16) Dec 8 10:37:28 unbound 32597 [32597:19] debug: servselect ip4 192.36.148.17 port 53 (len 16)