Mar 29 14:57:42 unbound 99799 [99799:1] debug: close fd 25 Mar 29 14:57:42 unbound 99799 [99799:1] debug: comm_point_close of 25: event_del Mar 29 14:57:42 unbound 99799 [99799:1] debug: close of port 60663 Mar 29 14:57:42 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:57:42 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:42 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:42 unbound 99799 [99799:1] info: 32.000000 64.000000 2 Mar 29 14:57:42 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:42 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.032768 0.065536 63 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:42 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:42 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:42 unbound 99799 [99799:1] info: [25%]=0.0512325 median[50%]=0.10879 [75%]=0.257775 Mar 29 14:57:42 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:42 unbound 99799 [99799:1] info: average recursion processing time 1.944246 sec Mar 29 14:57:42 unbound 99799 [99799:1] info: mesh_run: end 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 242 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:42 unbound 99799 [99799:1] info: 127.0.0.1 www.broadcom.com. A IN SERVFAIL 46.237603 0 45 Mar 29 14:57:42 unbound 99799 [99799:1] debug: query took 46.237603 sec Mar 29 14:57:42 unbound 99799 [99799:1] info: 127.0.0.1 www.broadcom.com. A IN SERVFAIL 41.235890 0 45 Mar 29 14:57:42 unbound 99799 [99799:1] debug: query took 41.235890 sec Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINISHED_STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: Could not establish a chain of trust to keys for broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: val handle processing q with state VAL_VALIDATE_STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] info: Missing DNSKEY RRset in response to DNSKEY query. Mar 29 14:57:42 unbound 99799 [99799:1] info: super is www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: inform_super, sub is broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: return error response SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] debug: store error response in message cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: out of query targets -- returning SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side NS in cache broadcom.com. NS IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: No more query targets, attempting last resort Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache blacklisted, going to the network Mar 29 14:57:42 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:42 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:57:42 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:57:42 unbound 99799 [99799:1] info: generate request broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: DS RRset broadcom.com. DS IN Mar 29 14:57:42 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist add: cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist cache Mar 29 14:57:42 unbound 99799 [99799:1] info: Missing DNSKEY RRset in response to DNSKEY query. Mar 29 14:57:42 unbound 99799 [99799:1] info: super is www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: inform_super, sub is broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: return error response SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] debug: store error response in message cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: out of query targets -- returning SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side NS in cache broadcom.com. NS IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: No more query targets, attempting last resort Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache blacklisted, going to the network Mar 29 14:57:42 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:42 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:57:42 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:57:42 unbound 99799 [99799:1] info: generate request broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: DS RRset broadcom.com. DS IN Mar 29 14:57:42 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist add: cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist cache Mar 29 14:57:42 unbound 99799 [99799:1] info: Missing DNSKEY RRset in response to DNSKEY query. Mar 29 14:57:42 unbound 99799 [99799:1] info: super is www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: inform_super, sub is broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: return error response SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] debug: store error response in message cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: out of query targets -- returning SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side NS in cache broadcom.com. NS IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: No more query targets, attempting last resort Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache blacklisted, going to the network Mar 29 14:57:42 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:42 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:57:42 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:57:42 unbound 99799 [99799:1] info: generate request broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: DS RRset broadcom.com. DS IN Mar 29 14:57:42 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist add: cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist cache Mar 29 14:57:42 unbound 99799 [99799:1] info: Missing DNSKEY RRset in response to DNSKEY query. Mar 29 14:57:42 unbound 99799 [99799:1] info: super is www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: inform_super, sub is broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: return error response SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] debug: store error response in message cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: out of query targets -- returning SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side NS in cache broadcom.com. NS IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: No more query targets, attempting last resort Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:57:42 unbound 99799 [99799:1] debug: cache blacklisted, going to the network Mar 29 14:57:42 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: resolving broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:42 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:57:42 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:57:42 unbound 99799 [99799:1] info: generate request broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: DS RRset broadcom.com. DS IN Mar 29 14:57:42 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:57:42 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist add: cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: blacklist cache Mar 29 14:57:42 unbound 99799 [99799:1] info: Missing DNSKEY RRset in response to DNSKEY query. Mar 29 14:57:42 unbound 99799 [99799:1] info: super is www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: validator: inform_super, sub is broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:42 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:1] debug: return error response SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] debug: store error response in message cache Mar 29 14:57:42 unbound 99799 [99799:1] debug: out of query targets -- returning SERVFAIL Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns1.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found in cache pdns2.cscdns.net. A IN Mar 29 14:57:42 unbound 99799 [99799:1] info: found parent-side NS in cache broadcom.com. NS IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: No more query targets, attempting last resort Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:42 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:42 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 2 Mar 29 14:57:42 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:42 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:57:42 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:42 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:57:42 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:57:42 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:42 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:57:42 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:57:42 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:57:42 unbound 99799 [99799:0] debug: close fd 27 Mar 29 14:57:42 unbound 99799 [99799:0] debug: comm_point_close of 27: event_del Mar 29 14:57:42 unbound 99799 [99799:0] debug: close of port 15733 Mar 29 14:57:42 unbound 99799 [99799:0] debug: serviced_delete Mar 29 14:57:42 unbound 99799 [99799:0] debug: svcd callbacks end Mar 29 14:57:42 unbound 99799 [99799:0] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:42 unbound 99799 [99799:0] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:0] info: 32.000000 64.000000 2 Mar 29 14:57:42 unbound 99799 [99799:0] info: 1.000000 2.000000 3 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.524288 1.000000 16 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.262144 0.524288 52 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.131072 0.262144 73 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.065536 0.131072 74 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.032768 0.065536 92 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.016384 0.032768 14 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.008192 0.016384 2 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.000512 0.001024 1 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.000000 0.000001 22 Mar 29 14:57:42 unbound 99799 [99799:0] info: lower(secs) upper(secs) recursions Mar 29 14:57:42 unbound 99799 [99799:0] info: [25%]=0.0501315 median[50%]=0.104946 [75%]=0.23566 Mar 29 14:57:42 unbound 99799 [99799:0] info: histogram of recursion processing times Mar 29 14:57:42 unbound 99799 [99799:0] info: average recursion processing time 0.406206 sec Mar 29 14:57:42 unbound 99799 [99799:0] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 351 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:42 unbound 99799 [99799:0] info: 10.2.44.105 game-assets.haydaygame.com. A IN NOERROR 0.056841 0 108 Mar 29 14:57:42 unbound 99799 [99799:0] debug: query took 0.056841 sec Mar 29 14:57:42 unbound 99799 [99799:0] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:0] debug: val handle processing q with state VAL_FINISHED_STATE Mar 29 14:57:42 unbound 99799 [99799:0] info: Verified that unsigned response is INSECURE Mar 29 14:57:42 unbound 99799 [99799:0] debug: val handle processing q with state VAL_VALIDATE_STATE Mar 29 14:57:42 unbound 99799 [99799:0] info: NSEC3s for the referral proved no DS. Mar 29 14:57:42 unbound 99799 [99799:0] info: ce candidate com. TYPE0 CLASS0 Mar 29 14:57:42 unbound 99799 [99799:0] debug: nsec3: keysize 1280 bits, max iterations 500 Mar 29 14:57:42 unbound 99799 [99799:0] debug: Process cached DS response Mar 29 14:57:42 unbound 99799 [99799:0] debug: No DS RRset Mar 29 14:57:42 unbound 99799 [99799:0] info: next keyname haydaygame.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: striplab 1 Mar 29 14:57:42 unbound 99799 [99799:0] info: target keyname game-assets.haydaygame.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:0] info: current keyname com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:0] info: validator: FindKey game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:42 unbound 99799 [99799:0] info: no signer, using game-assets.haydaygame.com. TYPE0 CLASS0 Mar 29 14:57:42 unbound 99799 [99799:0] debug: validator classification positive Mar 29 14:57:42 unbound 99799 [99799:0] debug: val handle processing q with state VAL_INIT_STATE Mar 29 14:57:42 unbound 99799 [99799:0] debug: validator: nextmodule returned Mar 29 14:57:42 unbound 99799 [99799:0] info: validator operate: query game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:42 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:42 unbound 99799 [99799:0] info: finishing processing for game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: iter_handle processing q with state FINISHED RESPONSE STATE Mar 29 14:57:42 unbound 99799 [99799:0] info: query response was ANSWER Mar 29 14:57:42 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:42 unbound 99799 [99799:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 4, AUTHORITY: 4, ADDITIONAL: 0 ;; QUESTION SECTION: game-assets.haydaygame.com. IN A ;; ANSWER SECTION: game-assets.haydaygame.com. 60 IN A 143.204.55.79 game-assets.haydaygame.com. 60 IN A 143.204.55.75 game-assets.haydaygame.com. 60 IN A 143.204.55.65 game-assets.haydaygame.com. 60 IN A 143.204.55.103 ;; AUTHORITY SECTION: haydaygame.com. 86400 IN NS ns-213.awsdns-26.com. haydaygame.com. 86400 IN NS ns-939.awsdns-53.net. haydaygame.com. 86400 IN NS ns-1107.awsdns-10.org. haydaygame.com. 86400 IN NS ns-1828.awsdns-36.co.uk. ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 245 Mar 29 14:57:42 unbound 99799 [99799:0] info: reply from 205.251.195.171#53 Mar 29 14:57:42 unbound 99799 [99799:0] info: response for game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] info: scrub for haydaygame.com. NS IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: process_response: new external response event Mar 29 14:57:42 unbound 99799 [99799:0] info: iterator operate: query game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Mar 29 14:57:42 unbound 99799 [99799:0] debug: mesh_run: start Mar 29 14:57:42 unbound 99799 [99799:0] debug: worker svcd callback for qstate 0x8024d8050 Mar 29 14:57:42 unbound 99799 [99799:0] debug: svcd callbacks start Mar 29 14:57:42 unbound 99799 [99799:0] debug: measured roundtrip at 56 msec Mar 29 14:57:42 unbound 99799 [99799:0] debug: serviced query: EDNS works for ip4 205.251.195.171 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: outnet handle udp reply Mar 29 14:57:42 unbound 99799 [99799:0] debug: udp message[256:0] C77B842000010004000400010B67616D652D6173736574730A68617964617967616D6503636F6D0000010001C00C000100010000003C00048FCC3741C00C000100010000003C00048FCC3767C00C000100010000003C00048FCC374FC00C000100010000003C00048FCC374BC018000200010002A3000017076E732D3131303709617773646E732D3130036F726700C018000200010002A3000019076E732D3138323809617773646E732D333602636F02756B00C018000200010002A3000013066E732D32313309617773646E732D3236C023C018000200010002A3000016066E732D39333909617773646E732D3533036E6574000000291000000080000000 Mar 29 14:57:42 unbound 99799 [99799:0] debug: received udp reply. Mar 29 14:57:42 unbound 99799 [99799:0] debug: lookup size is 2 entries Mar 29 14:57:42 unbound 99799 [99799:0] debug: Incoming reply addr = ip4 205.251.195.171 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: Incoming reply id = c77b Mar 29 14:57:42 unbound 99799 [99799:0] debug: answer cb Mar 29 14:57:42 unbound 99799 [99799:0] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:42 unbound 99799 [99799:0] info: 2RDd mod1 rep game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:42 unbound 99799 [99799:0] info: 32.000000 64.000000 2 Mar 29 14:57:42 unbound 99799 [99799:0] info: 1.000000 2.000000 3 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.524288 1.000000 16 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.262144 0.524288 52 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.131072 0.262144 73 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.065536 0.131072 74 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.032768 0.065536 91 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.016384 0.032768 14 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.008192 0.016384 2 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.000512 0.001024 1 Mar 29 14:57:42 unbound 99799 [99799:0] info: 0.000000 0.000001 22 Mar 29 14:57:42 unbound 99799 [99799:0] info: lower(secs) upper(secs) recursions Mar 29 14:57:42 unbound 99799 [99799:0] info: [25%]=0.0502323 median[50%]=0.105389 [75%]=0.236109 Mar 29 14:57:42 unbound 99799 [99799:0] info: histogram of recursion processing times Mar 29 14:57:42 unbound 99799 [99799:0] info: average recursion processing time 0.407205 sec Mar 29 14:57:42 unbound 99799 [99799:0] info: mesh_run: end 3 recursion states (2 with reply, 0 detached), 2 waiting replies, 350 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:42 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:57:42 unbound 99799 [99799:0] debug: comm point start listening 27 (-1 msec) Mar 29 14:57:42 unbound 99799 [99799:0] debug: opened UDP if=0 port=15733 Mar 29 14:57:42 unbound 99799 [99799:0] debug: inserted new pending reply id=c77b Mar 29 14:57:42 unbound 99799 [99799:0] debug: serviced query UDP timeout=376 msec Mar 29 14:57:42 unbound 99799 [99799:0] debug: EDNS lookup known=0 vs=0 Mar 29 14:57:42 unbound 99799 [99799:0] debug: dnssec status: not expected Mar 29 14:57:42 unbound 99799 [99799:0] debug: sending to target: 205.251.195.171#53 Mar 29 14:57:42 unbound 99799 [99799:0] info: sending query: game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: selrtt 261 Mar 29 14:57:42 unbound 99799 [99799:0] debug: rtt=261 Mar 29 14:57:42 unbound 99799 [99799:0] debug: servselect ip4 205.251.196.83 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: rtt=321 Mar 29 14:57:42 unbound 99799 [99799:0] debug: servselect ip4 205.251.199.36 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: attempt to get extra 3 targets Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.195.171 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.192.213 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.199.36 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.196.83 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-1107.awsdns-10.org. * A Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-1828.awsdns-36.co.uk. * A Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-213.awsdns-26.com. * A Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-939.awsdns-53.net. * A Mar 29 14:57:42 unbound 99799 [99799:0] info: DelegationPoint: 4 names (0 missing), 4 addrs (0 result, 4 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:42 unbound 99799 [99799:0] info: processQueryTargets: game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:42 unbound 99799 [99799:0] info: resolving (init part 3): game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:42 unbound 99799 [99799:0] info: resolving (init part 2): game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.195.171 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.192.213 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.199.36 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: ip4 205.251.196.83 port 53 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-1107.awsdns-10.org. A Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-1828.awsdns-36.co.uk. A Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-213.awsdns-26.com. A Mar 29 14:57:42 unbound 99799 [99799:0] info: ns-939.awsdns-53.net. A Mar 29 14:57:42 unbound 99799 [99799:0] info: DelegationPoint: 4 names (4 missing), 4 addrs (0 result, 4 avail) cacheNS Mar 29 14:57:42 unbound 99799 [99799:0] debug: cache delegation returns delegpt Mar 29 14:57:42 unbound 99799 [99799:0] debug: request has dependency depth of 0 Mar 29 14:57:42 unbound 99799 [99799:0] info: resolving game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:42 unbound 99799 [99799:0] debug: process_request: new external request event Mar 29 14:57:42 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:42 unbound 99799 [99799:0] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:42 unbound 99799 [99799:0] debug: validator: pass to next module Mar 29 14:57:42 unbound 99799 [99799:0] info: validator operate: query game-assets.haydaygame.com. A IN Mar 29 14:57:42 unbound 99799 [99799:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new Mar 29 14:57:42 unbound 99799 [99799:0] debug: mesh_run: start Mar 29 14:57:42 unbound 99799 [99799:0] debug: udp request from ip4 10.2.44.105 port 63779 (len 16) Mar 29 14:57:42 unbound 99799 [99799:0] debug: answer from the cache failed Mar 29 14:57:42 unbound 99799 [99799:0] info: 10.2.44.105 game-assets.haydaygame.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: close fd 27 Mar 29 14:57:32 unbound 99799 [99799:1] debug: comm_point_close of 27: event_del Mar 29 14:57:32 unbound 99799 [99799:1] debug: close of port 39631 Mar 29 14:57:32 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:57:32 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:57:32 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:32 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:32 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.032768 0.065536 63 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:32 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:32 unbound 99799 [99799:1] info: [25%]=0.0509724 median[50%]=0.107479 [75%]=0.253406 Mar 29 14:57:32 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:32 unbound 99799 [99799:1] info: average recursion processing time 1.595975 sec Mar 29 14:57:32 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 2 waiting replies, 240 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:32 unbound 99799 [99799:1] info: 10.2.44.103 www.google.com. A IN NOERROR 0.037340 0 48 Mar 29 14:57:32 unbound 99799 [99799:1] debug: query took 0.037340 sec Mar 29 14:57:32 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:32 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINISHED_STATE Mar 29 14:57:32 unbound 99799 [99799:1] info: Verified that unsigned response is INSECURE Mar 29 14:57:32 unbound 99799 [99799:1] debug: val handle processing q with state VAL_VALIDATE_STATE Mar 29 14:57:32 unbound 99799 [99799:1] info: NSEC3s for the referral proved no DS. Mar 29 14:57:32 unbound 99799 [99799:1] info: ce candidate com. TYPE0 CLASS0 Mar 29 14:57:32 unbound 99799 [99799:1] debug: nsec3: keysize 1280 bits, max iterations 500 Mar 29 14:57:32 unbound 99799 [99799:1] debug: Process cached DS response Mar 29 14:57:32 unbound 99799 [99799:1] debug: No DS RRset Mar 29 14:57:32 unbound 99799 [99799:1] info: next keyname google.com. DNSKEY IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: striplab 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: target keyname www.google.com. DNSKEY IN Mar 29 14:57:32 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:57:32 unbound 99799 [99799:1] info: validator: FindKey www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:32 unbound 99799 [99799:1] info: no signer, using www.google.com. TYPE0 CLASS0 Mar 29 14:57:32 unbound 99799 [99799:1] debug: validator classification positive Mar 29 14:57:32 unbound 99799 [99799:1] debug: val handle processing q with state VAL_INIT_STATE Mar 29 14:57:32 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:32 unbound 99799 [99799:1] info: validator operate: query www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:32 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:32 unbound 99799 [99799:1] info: finishing processing for www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: iter_handle processing q with state FINISHED RESPONSE STATE Mar 29 14:57:32 unbound 99799 [99799:1] info: query response was ANSWER Mar 29 14:57:32 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:32 unbound 99799 [99799:1] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.google.com. IN A ;; ANSWER SECTION: www.google.com. 300 IN A 172.217.21.132 ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 48 Mar 29 14:57:32 unbound 99799 [99799:1] info: reply from 216.239.36.10#53 Mar 29 14:57:32 unbound 99799 [99799:1] info: response for www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] info: scrub for google.com. NS IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:57:32 unbound 99799 [99799:1] info: iterator operate: query www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Mar 29 14:57:32 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:32 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x802fea510 Mar 29 14:57:32 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:57:32 unbound 99799 [99799:1] debug: measured roundtrip at 37 msec Mar 29 14:57:32 unbound 99799 [99799:1] debug: serviced query: EDNS works for ip4 216.239.36.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: outnet handle udp reply Mar 29 14:57:32 unbound 99799 [99799:1] debug: udp message[59:0] 8665840000010001000000010377777706676F6F676C6503636F6D0000010001C00C000100010000012C0004ACD915840000290200000080000000 Mar 29 14:57:32 unbound 99799 [99799:1] debug: received udp reply. Mar 29 14:57:32 unbound 99799 [99799:1] debug: lookup size is 2 entries Mar 29 14:57:32 unbound 99799 [99799:1] debug: Incoming reply addr = ip4 216.239.36.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: Incoming reply id = 8665 Mar 29 14:57:32 unbound 99799 [99799:1] debug: answer cb Mar 29 14:57:32 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:32 unbound 99799 [99799:1] info: 2RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] info: 1RDd mod1 rep www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:32 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:32 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:32 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:32 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:57:32 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:32 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:57:32 unbound 99799 [99799:1] info: mesh_run: end 3 recursion states (2 with reply, 0 detached), 3 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:32 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:57:32 unbound 99799 [99799:1] debug: comm point start listening 27 (-1 msec) Mar 29 14:57:32 unbound 99799 [99799:1] debug: opened UDP if=0 port=39631 Mar 29 14:57:32 unbound 99799 [99799:1] debug: inserted new pending reply id=8665 Mar 29 14:57:32 unbound 99799 [99799:1] debug: serviced query UDP timeout=376 msec Mar 29 14:57:32 unbound 99799 [99799:1] debug: EDNS lookup known=0 vs=0 Mar 29 14:57:32 unbound 99799 [99799:1] debug: dnssec status: not expected Mar 29 14:57:32 unbound 99799 [99799:1] debug: sending to target: 216.239.36.10#53 Mar 29 14:57:32 unbound 99799 [99799:1] info: sending query: www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: selrtt 242 Mar 29 14:57:32 unbound 99799 [99799:1] debug: rtt=294 Mar 29 14:57:32 unbound 99799 [99799:1] debug: servselect ip4 216.239.34.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: rtt=311 Mar 29 14:57:32 unbound 99799 [99799:1] debug: servselect ip4 216.239.32.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: rtt=242 Mar 29 14:57:32 unbound 99799 [99799:1] debug: servselect ip4 216.239.38.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.38.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:38::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.36.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:36::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.32.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:32::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.34.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:34::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] info: ns2.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: ns1.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: ns3.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: ns4.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: DelegationPoint: 4 names (0 missing), 8 addrs (0 result, 8 avail) cacheNS Mar 29 14:57:32 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:32 unbound 99799 [99799:1] info: processQueryTargets: www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:32 unbound 99799 [99799:1] info: resolving (init part 3): www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:32 unbound 99799 [99799:1] info: resolving (init part 2): www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.38.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:38::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.36.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:36::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.32.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:32::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip4 216.239.34.10 port 53 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: ip6 2001:4860:4802:34::a port 53 (len 28) Mar 29 14:57:32 unbound 99799 [99799:1] info: ns2.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: ns1.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: ns3.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: ns4.google.com. * A AAAA Mar 29 14:57:32 unbound 99799 [99799:1] info: DelegationPoint: 4 names (0 missing), 8 addrs (0 result, 8 avail) cacheNS Mar 29 14:57:32 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:57:32 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:57:32 unbound 99799 [99799:1] info: resolving www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:32 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:57:32 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:32 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:32 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:57:32 unbound 99799 [99799:1] info: validator operate: query www.google.com. A IN Mar 29 14:57:32 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new Mar 29 14:57:32 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:32 unbound 99799 [99799:1] debug: udp request from ip4 10.2.44.103 port 33964 (len 16) Mar 29 14:57:32 unbound 99799 [99799:1] debug: answer from the cache failed Mar 29 14:57:32 unbound 99799 [99799:1] info: 10.2.44.103 www.google.com. A IN Mar 29 14:57:21 unbound 99799 [99799:1] debug: close fd 27 Mar 29 14:57:21 unbound 99799 [99799:1] debug: comm_point_close of 27: event_del Mar 29 14:57:21 unbound 99799 [99799:1] debug: close of port 44806 Mar 29 14:57:21 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:57:21 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:57:21 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:21 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:21 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:21 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:21 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:21 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:21 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:21 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:21 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:57:21 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:21 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:57:21 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 2 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:21 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:57:21 unbound 99799 [99799:1] debug: comm point start listening 25 (-1 msec) Mar 29 14:57:21 unbound 99799 [99799:1] debug: opened UDP if=0 port=60663 Mar 29 14:57:21 unbound 99799 [99799:1] debug: inserted new pending reply id=838d Mar 29 14:57:21 unbound 99799 [99799:1] debug: serviced query UDP timeout=20736 msec Mar 29 14:57:21 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:57:21 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:57:21 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:57:21 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:57:21 unbound 99799 [99799:1] debug: selrtt 20736 Mar 29 14:57:21 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:21 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:21 unbound 99799 [99799:1] debug: rtt=20736 Mar 29 14:57:21 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:21 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:21 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:21 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:21 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:21 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:21 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS Mar 29 14:57:21 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1 Mar 29 14:57:21 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:21 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:21 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:57:21 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:21 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:57:21 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:21 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:57:21 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:21 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:57:21 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:57:21 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:57:11 unbound 99799 [99799:1] debug: close fd 25 Mar 29 14:57:11 unbound 99799 [99799:1] debug: comm_point_close of 25: event_del Mar 29 14:57:11 unbound 99799 [99799:1] debug: close of port 24513 Mar 29 14:57:11 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:57:11 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:57:11 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:11 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:11 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:11 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:11 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:11 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:11 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:11 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:57:11 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:11 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:57:11 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 2 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:11 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:57:11 unbound 99799 [99799:1] debug: comm point start listening 27 (-1 msec) Mar 29 14:57:11 unbound 99799 [99799:1] debug: opened UDP if=0 port=44806 Mar 29 14:57:11 unbound 99799 [99799:1] debug: inserted new pending reply id=a1f3 Mar 29 14:57:11 unbound 99799 [99799:1] debug: serviced query UDP timeout=10368 msec Mar 29 14:57:11 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:57:11 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:57:11 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:57:11 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: selrtt 10368 Mar 29 14:57:11 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:11 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] debug: rtt=10368 Mar 29 14:57:11 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:57:11 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:11 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:11 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:11 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:57:11 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:11 unbound 99799 [99799:1] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:57:11 unbound 99799 [99799:1] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:57:11 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] info: pdns1.cscdns.net. A Mar 29 14:57:11 unbound 99799 [99799:1] info: pdns2.cscdns.net. A Mar 29 14:57:11 unbound 99799 [99799:1] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:57:11 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:57:11 unbound 99799 [99799:1] debug: cache blacklisted, going to the network Mar 29 14:57:11 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:57:11 unbound 99799 [99799:1] info: resolving broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:57:11 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:57:11 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:11 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:57:11 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:57:11 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:57:11 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:57:11 unbound 99799 [99799:1] info: generate request broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] info: DS RRset broadcom.com. DS IN Mar 29 14:57:11 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:57:11 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:57:11 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass Mar 29 14:57:11 unbound 99799 [99799:1] debug: blacklist add: cache Mar 29 14:57:11 unbound 99799 [99799:1] info: Missing DNSKEY RRset in response to DNSKEY query. Mar 29 14:57:11 unbound 99799 [99799:1] info: super is www.broadcom.com. A IN Mar 29 14:57:11 unbound 99799 [99799:1] info: validator: inform_super, sub is broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_finished Mar 29 14:57:11 unbound 99799 [99799:1] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:57:11 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:57:11 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:57:11 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:57:11 unbound 99799 [99799:1] debug: return error response SERVFAIL Mar 29 14:57:11 unbound 99799 [99799:1] debug: store error response in message cache Mar 29 14:57:11 unbound 99799 [99799:1] debug: out of query targets -- returning SERVFAIL Mar 29 14:57:11 unbound 99799 [99799:1] info: found parent-side pdns1.cscdns.net. A IN Mar 29 14:57:11 unbound 99799 [99799:1] info: found parent-side pdns2.cscdns.net. A IN Mar 29 14:57:11 unbound 99799 [99799:1] info: found in cache pdns1.cscdns.net. A IN Mar 29 14:57:11 unbound 99799 [99799:1] info: found in cache pdns2.cscdns.net. A IN Mar 29 14:57:11 unbound 99799 [99799:1] info: found parent-side NS in cache broadcom.com. NS IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: No more query targets, attempting last resort Mar 29 14:57:11 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:11 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:11 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:11 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:11 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (1 result, 0 avail) cacheNS Mar 29 14:57:11 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 5 Mar 29 14:57:11 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:11 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:57:11 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:11 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:57:11 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:11 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:57:11 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:11 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:57:11 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:57:11 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:57:06 unbound 99799 [99799:1] debug: close fd 27 Mar 29 14:57:06 unbound 99799 [99799:1] debug: comm_point_close of 27: event_del Mar 29 14:57:06 unbound 99799 [99799:1] debug: close of port 36901 Mar 29 14:57:06 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:57:06 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:57:06 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:06 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:06 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:06 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:06 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:06 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:06 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:06 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:06 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:57:06 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:06 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:57:06 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 2 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:06 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:57:06 unbound 99799 [99799:1] debug: comm point start listening 25 (-1 msec) Mar 29 14:57:06 unbound 99799 [99799:1] debug: opened UDP if=0 port=24513 Mar 29 14:57:06 unbound 99799 [99799:1] debug: inserted new pending reply id=51df Mar 29 14:57:06 unbound 99799 [99799:1] debug: serviced query UDP timeout=5184 msec Mar 29 14:57:06 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:57:06 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:57:06 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:57:06 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:57:06 unbound 99799 [99799:1] debug: selrtt 5184 Mar 29 14:57:06 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:06 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:06 unbound 99799 [99799:1] debug: rtt=5184 Mar 29 14:57:06 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:06 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:06 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:06 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:06 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:06 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS Mar 29 14:57:06 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 4 Mar 29 14:57:06 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:06 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:06 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:57:06 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:06 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:57:06 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:06 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:57:06 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:06 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:57:06 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:57:06 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:57:03 unbound 99799 [99799:1] debug: close fd 25 Mar 29 14:57:03 unbound 99799 [99799:1] debug: comm_point_close of 25: event_del Mar 29 14:57:03 unbound 99799 [99799:1] debug: close of port 12440 Mar 29 14:57:03 unbound 99799 [99799:1] debug: comm point start listening 27 (-1 msec) Mar 29 14:57:03 unbound 99799 [99799:1] debug: opened UDP if=0 port=36901 Mar 29 14:57:03 unbound 99799 [99799:1] debug: inserted new pending reply id=4d72 Mar 29 14:57:03 unbound 99799 [99799:1] debug: serviced query UDP timeout=2592 msec Mar 29 14:57:03 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:57:03 unbound 99799 [99799:1] debug: try edns1xx0 156.154.131.100#53 Mar 29 14:57:03 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:57:01 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:01 unbound 99799 [99799:1] debug: udp request from ip4 127.0.0.1 port 30020 (len 16) Mar 29 14:57:01 unbound 99799 [99799:1] debug: answer from the cache failed Mar 29 14:57:01 unbound 99799 [99799:1] debug: Cache reply: unchecked entry needs validation Mar 29 14:57:01 unbound 99799 [99799:1] info: 127.0.0.1 www.broadcom.com. A IN Mar 29 14:57:00 unbound 99799 [99799:1] debug: close fd 27 Mar 29 14:57:00 unbound 99799 [99799:1] debug: comm_point_close of 27: event_del Mar 29 14:57:00 unbound 99799 [99799:1] debug: close of port 22660 Mar 29 14:57:00 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:57:00 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:57:00 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:57:00 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:57:00 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:57:00 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:57:00 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:57:00 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:57:00 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:57:00 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:57:00 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:57:00 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:57:00 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:57:00 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:57:00 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:57:00 unbound 99799 [99799:1] debug: comm point start listening 25 (-1 msec) Mar 29 14:57:00 unbound 99799 [99799:1] debug: opened UDP if=0 port=12440 Mar 29 14:57:00 unbound 99799 [99799:1] debug: inserted new pending reply id=e624 Mar 29 14:57:00 unbound 99799 [99799:1] debug: serviced query UDP timeout=2592 msec Mar 29 14:57:00 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:57:00 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:57:00 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:57:00 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:57:00 unbound 99799 [99799:1] debug: selrtt 2592 Mar 29 14:57:00 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:57:00 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:00 unbound 99799 [99799:1] debug: rtt=2592 Mar 29 14:57:00 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:00 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:57:00 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:57:00 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:57:00 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:57:00 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS Mar 29 14:57:00 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 3 Mar 29 14:57:00 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:57:00 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:57:00 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:57:00 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:57:00 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:57:00 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:57:00 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:57:00 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:57:00 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:57:00 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:57:00 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:57:00 newsyslog 74863 logfile turned over due to size>1000K Mar 29 14:57:00 newsyslog 74863 logfile turned over due to size>1000K Mar 29 14:56:59 unbound 99799 [99799:1] debug: close fd 25 Mar 29 14:56:59 unbound 99799 [99799:1] debug: comm_point_close of 25: event_del Mar 29 14:56:59 unbound 99799 [99799:1] debug: close of port 51506 Mar 29 14:56:59 unbound 99799 [99799:1] debug: comm point start listening 27 (-1 msec) Mar 29 14:56:59 unbound 99799 [99799:1] debug: opened UDP if=0 port=22660 Mar 29 14:56:59 unbound 99799 [99799:1] debug: inserted new pending reply id=d7e5 Mar 29 14:56:59 unbound 99799 [99799:1] debug: serviced query UDP timeout=1296 msec Mar 29 14:56:59 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:56:59 unbound 99799 [99799:1] debug: try edns1xx0 156.154.131.100#53 Mar 29 14:56:59 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:56:58 unbound 99799 [99799:1] debug: close fd 27 Mar 29 14:56:58 unbound 99799 [99799:1] debug: comm_point_close of 27: event_del Mar 29 14:56:58 unbound 99799 [99799:1] debug: close of port 39926 Mar 29 14:56:58 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:56:58 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:56:58 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:56:58 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:56:58 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:56:58 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:56:58 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:56:58 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:56:58 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:56:58 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:56:58 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:56:58 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:56:58 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:56:58 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:58 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:58 unbound 99799 [99799:1] debug: comm point start listening 25 (-1 msec) Mar 29 14:56:58 unbound 99799 [99799:1] debug: opened UDP if=0 port=51506 Mar 29 14:56:58 unbound 99799 [99799:1] debug: inserted new pending reply id=4d4d Mar 29 14:56:58 unbound 99799 [99799:1] debug: serviced query UDP timeout=1296 msec Mar 29 14:56:58 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:56:58 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:56:58 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:56:58 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:56:58 unbound 99799 [99799:1] debug: selrtt 1296 Mar 29 14:56:58 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:56:58 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:58 unbound 99799 [99799:1] debug: rtt=1296 Mar 29 14:56:58 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:58 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:56:58 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:58 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:58 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:56:58 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:56:58 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS Mar 29 14:56:58 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 2 Mar 29 14:56:58 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:56:58 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:58 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:56:58 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:56:58 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:56:58 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:56:58 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:56:58 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:56:58 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:56:58 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:56:58 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:56:57 unbound 99799 [99799:1] debug: close fd 25 Mar 29 14:56:57 unbound 99799 [99799:1] debug: comm_point_close of 25: event_del Mar 29 14:56:57 unbound 99799 [99799:1] debug: close of port 39201 Mar 29 14:56:57 unbound 99799 [99799:1] debug: comm point start listening 27 (-1 msec) Mar 29 14:56:57 unbound 99799 [99799:1] debug: opened UDP if=0 port=39926 Mar 29 14:56:57 unbound 99799 [99799:1] debug: inserted new pending reply id=0bcd Mar 29 14:56:57 unbound 99799 [99799:1] debug: serviced query UDP timeout=648 msec Mar 29 14:56:57 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:56:57 unbound 99799 [99799:1] debug: try edns1xx0 156.154.131.100#53 Mar 29 14:56:57 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:56:56 unbound 99799 [99799:1] debug: close fd 27 Mar 29 14:56:56 unbound 99799 [99799:1] debug: comm_point_close of 27: event_del Mar 29 14:56:56 unbound 99799 [99799:1] debug: close of port 41156 Mar 29 14:56:56 unbound 99799 [99799:1] debug: serviced_delete Mar 29 14:56:56 unbound 99799 [99799:1] debug: svcd callbacks end Mar 29 14:56:56 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:56:56 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:56:56 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:56:56 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:56:56 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:56:56 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:56:56 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:56 unbound 99799 [99799:1] debug: comm point start listening 25 (-1 msec) Mar 29 14:56:56 unbound 99799 [99799:1] debug: opened UDP if=0 port=39201 Mar 29 14:56:56 unbound 99799 [99799:1] debug: inserted new pending reply id=8ef6 Mar 29 14:56:56 unbound 99799 [99799:1] debug: serviced query UDP timeout=648 msec Mar 29 14:56:56 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:56:56 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:56:56 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:56:56 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: selrtt 648 Mar 29 14:56:56 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:56:56 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: rtt=648 Mar 29 14:56:56 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:56:56 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:56:56 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:56:56 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS Mar 29 14:56:56 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:56 unbound 99799 [99799:1] debug: query response was timeout Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:56:56 unbound 99799 [99799:1] debug: process_response: new external response event Mar 29 14:56:56 unbound 99799 [99799:1] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:56:56 unbound 99799 [99799:1] debug: worker svcd callback for qstate 0x803008890 Mar 29 14:56:56 unbound 99799 [99799:1] debug: svcd callbacks start Mar 29 14:56:56 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:56:56 unbound 99799 [99799:1] debug: close fd 25 Mar 29 14:56:56 unbound 99799 [99799:1] debug: comm_point_close of 25: event_del Mar 29 14:56:56 unbound 99799 [99799:1] debug: close of port 41954 Mar 29 14:56:56 unbound 99799 [99799:1] debug: comm point start listening 27 (-1 msec) Mar 29 14:56:56 unbound 99799 [99799:1] debug: opened UDP if=0 port=41156 Mar 29 14:56:56 unbound 99799 [99799:1] debug: inserted new pending reply id=f408 Mar 29 14:56:56 unbound 99799 [99799:1] debug: serviced query UDP timeout=324 msec Mar 29 14:56:56 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:56:56 unbound 99799 [99799:1] debug: try edns1xx0 156.154.131.100#53 Mar 29 14:56:56 unbound 99799 [99799:1] debug: timeout udp Mar 29 14:56:56 unbound 99799 [99799:1] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:56:56 unbound 99799 [99799:1] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: 256.000000 512.000000 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: 2.000000 4.000000 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: 1.000000 2.000000 4 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.524288 1.000000 13 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.262144 0.524288 38 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.131072 0.262144 45 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.065536 0.131072 50 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.032768 0.065536 62 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.016384 0.032768 11 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.004096 0.008192 1 Mar 29 14:56:56 unbound 99799 [99799:1] info: 0.000000 0.000001 13 Mar 29 14:56:56 unbound 99799 [99799:1] info: lower(secs) upper(secs) recursions Mar 29 14:56:56 unbound 99799 [99799:1] info: [25%]=0.0511339 median[50%]=0.108134 [75%]=0.254134 Mar 29 14:56:56 unbound 99799 [99799:1] info: histogram of recursion processing times Mar 29 14:56:56 unbound 99799 [99799:1] info: average recursion processing time 1.602497 sec Mar 29 14:56:56 unbound 99799 [99799:1] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 239 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:56 unbound 99799 [99799:1] debug: comm point start listening 25 (-1 msec) Mar 29 14:56:56 unbound 99799 [99799:1] debug: opened UDP if=0 port=41954 Mar 29 14:56:56 unbound 99799 [99799:1] debug: inserted new pending reply id=a781 Mar 29 14:56:56 unbound 99799 [99799:1] debug: serviced query UDP timeout=324 msec Mar 29 14:56:56 unbound 99799 [99799:1] debug: EDNS lookup known=1 vs=0 Mar 29 14:56:56 unbound 99799 [99799:1] debug: dnssec status: expected Mar 29 14:56:56 unbound 99799 [99799:1] debug: sending to target: 156.154.131.100#53 Mar 29 14:56:56 unbound 99799 [99799:1] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: selrtt 324 Mar 29 14:56:56 unbound 99799 [99799:1] debug: rtt=120000 Mar 29 14:56:56 unbound 99799 [99799:1] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: rtt=324 Mar 29 14:56:56 unbound 99799 [99799:1] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: attempt to get extra 3 targets Mar 29 14:56:56 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] info: pdns1.cscdns.net. * A Mar 29 14:56:56 unbound 99799 [99799:1] info: pdns2.cscdns.net. * A Mar 29 14:56:56 unbound 99799 [99799:1] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:56:56 unbound 99799 [99799:1] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:56:56 unbound 99799 [99799:1] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:56 unbound 99799 [99799:1] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:56:56 unbound 99799 [99799:1] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:56:56 unbound 99799 [99799:1] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] info: pdns1.cscdns.net. A Mar 29 14:56:56 unbound 99799 [99799:1] info: pdns2.cscdns.net. A Mar 29 14:56:56 unbound 99799 [99799:1] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:56:56 unbound 99799 [99799:1] debug: cache delegation returns delegpt Mar 29 14:56:56 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:56:56 unbound 99799 [99799:1] info: resolving broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:56 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:56:56 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:56:56 unbound 99799 [99799:1] info: validator operate: query broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:56:56 unbound 99799 [99799:1] info: generate request broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: DS RRset broadcom.com. DS IN Mar 29 14:56:56 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:56:56 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:56:56 unbound 99799 [99799:1] info: validated DS broadcom.com. DS IN Mar 29 14:56:56 unbound 99799 [99799:1] info: verify rrset cached broadcom.com. DS IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: Process cached DS response Mar 29 14:56:56 unbound 99799 [99799:1] debug: No DS RRset Mar 29 14:56:56 unbound 99799 [99799:1] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: striplab 0 Mar 29 14:56:56 unbound 99799 [99799:1] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: current keyname com. DNSKEY IN Mar 29 14:56:56 unbound 99799 [99799:1] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:56:56 unbound 99799 [99799:1] info: chased extract ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ra ; QUERY: 1, ANSWER: 4, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.broadcom.com. IN A ;; ANSWER SECTION: www.broadcom.com. 295 IN CNAME cdn.broadcom.com. www.broadcom.com. 295 IN RRSIG CNAME 8 3 300 20210402001539 20210328232028 52716 broadcom.com. FCuB5Vnk/GOtxIQPp84A0yCY0n/R/hvPl1KAj+4DT70fkNPPMJlbTdHjO6tpvsx6mHQiHY6LgfbUTDJqj/aUx5FKRPXRHp9dsyI/y+M8x06EJeTVewHHyR4dsUEi3yAudNRw+vSHeibmiKyLq+mNS6YEgkEY9JiL/RFrix0kZrJsjcv8Go/+iuQwr2Ry1NEFXmVDZuanaVpXP88T1xmIaZaDQPVW/+A7Pq337myMmWwGyFrM7150Cq1zYYc2HA9KLRZ9JvwqyZ/QEtA0nY+b6zSHi4Qk+GZVw9Ajfa2SgV3R0IhTv2LBcEMf6ZowjwFhI/48GrrAh4ksRBnwzkrQyQ== ;{id = 52716} cdn.broadcom.com. 1418 IN CNAME www.broadcom.com.cdn.cloudflare.net. cdn.broadcom.com. 1418 IN RRSIG CNAME 8 3 3600 20210401224511 20210328223250 52716 broadcom.com. F3QWF0hmxtfBm99RXGkG/Sf9MYrNi8qK3oR5f+FZCsOtD/rzFjR7YMJW56y7lH8tFzLnpLKTtTasXfcdKBlUKmbvMJHUEagh6cQqoXpUpBBlk4fCr7JvI/7Sxgm0vxrLDv8m6njtfLsRYG63EG8arnRf4De2VhIaomDrJOmaTpYZMMTSetcHd+F3wjNRHx4H0YVz+i1z571mlrfY59J9xzQZwhcK4YhZbrERRyRTwDxOy3lmeCJPJ42u2qM0Gi90mtU6mRbq5uxgDLiSLVuceL9E7Ot28Y4Yz+H9LOshlOMFRH8KTzWBWMU5YlgUj0TOC90yk0FbUqxhA0JCRCbyJg== ;{id = 52716} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 701 Mar 29 14:56:56 unbound 99799 [99799:1] info: signer is broadcom.com. TYPE0 CLASS0 Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator classification cname Mar 29 14:56:56 unbound 99799 [99799:1] debug: val handle processing q with state VAL_INIT_STATE Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator: nextmodule returned Mar 29 14:56:56 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:56:56 unbound 99799 [99799:1] info: finishing processing for www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state FINISHED RESPONSE STATE Mar 29 14:56:56 unbound 99799 [99799:1] debug: returning answer from cache. Mar 29 14:56:56 unbound 99799 [99799:1] debug: msg ttl is 295, prefetch ttl 265 Mar 29 14:56:56 unbound 99799 [99799:1] info: msg from cache lookup ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ra ; QUERY: 1, ANSWER: 7, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.broadcom.com. IN A ;; ANSWER SECTION: www.broadcom.com. 295 IN CNAME cdn.broadcom.com. www.broadcom.com. 295 IN RRSIG CNAME 8 3 300 20210402001539 20210328232028 52716 broadcom.com. FCuB5Vnk/GOtxIQPp84A0yCY0n/R/hvPl1KAj+4DT70fkNPPMJlbTdHjO6tpvsx6mHQiHY6LgfbUTDJqj/aUx5FKRPXRHp9dsyI/y+M8x06EJeTVewHHyR4dsUEi3yAudNRw+vSHeibmiKyLq+mNS6YEgkEY9JiL/RFrix0kZrJsjcv8Go/+iuQwr2Ry1NEFXmVDZuanaVpXP88T1xmIaZaDQPVW/+A7Pq337myMmWwGyFrM7150Cq1zYYc2HA9KLRZ9JvwqyZ/QEtA0nY+b6zSHi4Qk+GZVw9Ajfa2SgV3R0IhTv2LBcEMf6ZowjwFhI/48GrrAh4ksRBnwzkrQyQ== ;{id = 52716} cdn.broadcom.com. 1418 IN CNAME www.broadcom.com.cdn.cloudflare.net. cdn.broadcom.com. 1418 IN RRSIG CNAME 8 3 3600 20210401224511 20210328223250 52716 broadcom.com. F3QWF0hmxtfBm99RXGkG/Sf9MYrNi8qK3oR5f+FZCsOtD/rzFjR7YMJW56y7lH8tFzLnpLKTtTasXfcdKBlUKmbvMJHUEagh6cQqoXpUpBBlk4fCr7JvI/7Sxgm0vxrLDv8m6njtfLsRYG63EG8arnRf4De2VhIaomDrJOmaTpYZMMTSetcHd+F3wjNRHx4H0YVz+i1z571mlrfY59J9xzQZwhcK4YhZbrERRyRTwDxOy3lmeCJPJ42u2qM0Gi90mtU6mRbq5uxgDLiSLVuceL9E7Ot28Y4Yz+H9LOshlOMFRH8KTzWBWMU5YlgUj0TOC90yk0FbUqxhA0JCRCbyJg== ;{id = 52716} www.broadcom.com.cdn.cloudflare.net. 295 IN A 104.18.4.158 www.broadcom.com.cdn.cloudflare.net. 295 IN A 104.18.5.158 www.broadcom.com.cdn.cloudflare.net. 295 IN RRSIG A 13 6 300 20210330135651 20210328115651 34505 cloudflare.net. TxrKLJHyecWZ+1+Eub1Yf3oKH7e1zvJ5Gakb97M8Y/tPYjfreYcfCOyRzUFRvJf489j+07VwFHo1Nh1mnxcupw== ;{id = 34505} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 843 Mar 29 14:56:56 unbound 99799 [99799:1] debug: request has dependency depth of 0 Mar 29 14:56:56 unbound 99799 [99799:1] info: resolving www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:56 unbound 99799 [99799:1] debug: process_request: new external request event Mar 29 14:56:56 unbound 99799 [99799:1] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator: pass to next module Mar 29 14:56:56 unbound 99799 [99799:1] info: validator operate: query www.broadcom.com. A IN Mar 29 14:56:56 unbound 99799 [99799:1] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new Mar 29 14:56:56 unbound 99799 [99799:1] debug: mesh_run: start Mar 29 14:56:56 unbound 99799 [99799:1] debug: udp request from ip4 127.0.0.1 port 30020 (len 16) Mar 29 14:56:56 unbound 99799 [99799:1] debug: answer from the cache failed Mar 29 14:56:56 unbound 99799 [99799:1] debug: Cache reply: unchecked entry needs validation Mar 29 14:56:56 unbound 99799 [99799:1] info: 127.0.0.1 www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: close fd 25 Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm_point_close of 25: event_del Mar 29 14:56:51 unbound 99799 [99799:0] debug: close of port 18051 Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced_delete Mar 29 14:56:51 unbound 99799 [99799:0] debug: svcd callbacks end Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1RDdc mod0 rep www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 0vRDCD mod1 broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 32.000000 64.000000 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1.000000 2.000000 3 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.524288 1.000000 16 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.262144 0.524288 52 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.131072 0.262144 73 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.065536 0.131072 74 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.032768 0.065536 91 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.016384 0.032768 14 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.008192 0.016384 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000512 0.001024 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000000 0.000001 22 Mar 29 14:56:51 unbound 99799 [99799:0] info: lower(secs) upper(secs) recursions Mar 29 14:56:51 unbound 99799 [99799:0] info: [25%]=0.0502323 median[50%]=0.105389 [75%]=0.236109 Mar 29 14:56:51 unbound 99799 [99799:0] info: histogram of recursion processing times Mar 29 14:56:51 unbound 99799 [99799:0] info: average recursion processing time 0.407205 sec Mar 29 14:56:51 unbound 99799 [99799:0] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 350 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: validator module exit state is module_wait_subquery Mar 29 14:56:51 unbound 99799 [99799:0] info: generate request broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: DS RRset broadcom.com. DS IN Mar 29 14:56:51 unbound 99799 [99799:0] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: striplab 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: current keyname com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: validated DS broadcom.com. DS IN Mar 29 14:56:51 unbound 99799 [99799:0] info: verify rrset cached broadcom.com. DS IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: Process cached DS response Mar 29 14:56:51 unbound 99799 [99799:0] debug: No DS RRset Mar 29 14:56:51 unbound 99799 [99799:0] info: next keyname broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: striplab 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: target keyname broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: current keyname com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: validator: FindKey www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: val handle processing q with state VAL_FINDKEY_STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: chased extract ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ra ; QUERY: 1, ANSWER: 4, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.broadcom.com. IN A ;; ANSWER SECTION: www.broadcom.com. 300 IN CNAME cdn.broadcom.com. www.broadcom.com. 300 IN RRSIG CNAME 8 3 300 20210402001539 20210328232028 52716 broadcom.com. FCuB5Vnk/GOtxIQPp84A0yCY0n/R/hvPl1KAj+4DT70fkNPPMJlbTdHjO6tpvsx6mHQiHY6LgfbUTDJqj/aUx5FKRPXRHp9dsyI/y+M8x06EJeTVewHHyR4dsUEi3yAudNRw+vSHeibmiKyLq+mNS6YEgkEY9JiL/RFrix0kZrJsjcv8Go/+iuQwr2Ry1NEFXmVDZuanaVpXP88T1xmIaZaDQPVW/+A7Pq337myMmWwGyFrM7150Cq1zYYc2HA9KLRZ9JvwqyZ/QEtA0nY+b6zSHi4Qk+GZVw9Ajfa2SgV3R0IhTv2LBcEMf6ZowjwFhI/48GrrAh4ksRBnwzkrQyQ== ;{id = 52716} cdn.broadcom.com. 1423 IN CNAME www.broadcom.com.cdn.cloudflare.net. cdn.broadcom.com. 1423 IN RRSIG CNAME 8 3 3600 20210401224511 20210328223250 52716 broadcom.com. F3QWF0hmxtfBm99RXGkG/Sf9MYrNi8qK3oR5f+FZCsOtD/rzFjR7YMJW56y7lH8tFzLnpLKTtTasXfcdKBlUKmbvMJHUEagh6cQqoXpUpBBlk4fCr7JvI/7Sxgm0vxrLDv8m6njtfLsRYG63EG8arnRf4De2VhIaomDrJOmaTpYZMMTSetcHd+F3wjNRHx4H0YVz+i1z571mlrfY59J9xzQZwhcK4YhZbrERRyRTwDxOy3lmeCJPJ42u2qM0Gi90mtU6mRbq5uxgDLiSLVuceL9E7Ot28Y4Yz+H9LOshlOMFRH8KTzWBWMU5YlgUj0TOC90yk0FbUqxhA0JCRCbyJg== ;{id = 52716} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 701 Mar 29 14:56:51 unbound 99799 [99799:0] info: signer is broadcom.com. TYPE0 CLASS0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator classification cname Mar 29 14:56:51 unbound 99799 [99799:0] debug: val handle processing q with state VAL_INIT_STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator: nextmodule returned Mar 29 14:56:51 unbound 99799 [99799:0] info: validator operate: query www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:56:51 unbound 99799 [99799:0] debug: prepending 2 rrsets Mar 29 14:56:51 unbound 99799 [99799:0] info: finishing processing for www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state FINISHED RESPONSE STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: query response was ANSWER Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.broadcom.com.cdn.cloudflare.net. IN A ;; ANSWER SECTION: www.broadcom.com.cdn.cloudflare.net. 300 IN A 104.18.5.158 www.broadcom.com.cdn.cloudflare.net. 300 IN A 104.18.4.158 www.broadcom.com.cdn.cloudflare.net. 300 IN RRSIG A 13 6 300 20210330135651 20210328115651 34505 cloudflare.net. TxrKLJHyecWZ+1+Eub1Yf3oKH7e1zvJ5Gakb97M8Y/tPYjfreYcfCOyRzUFRvJf489j+07VwFHo1Nh1mnxcupw== ;{id = 34505} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 195 Mar 29 14:56:51 unbound 99799 [99799:0] info: reply from 198.41.222.131#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: response for www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: scrub for cloudflare.net. NS IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: process_response: new external response event Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: chased to www.broadcom.com.cdn.cloudflare.net. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: query www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: start Mar 29 14:56:51 unbound 99799 [99799:0] debug: worker svcd callback for qstate 0x8024e17d0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: svcd callbacks start Mar 29 14:56:51 unbound 99799 [99799:0] debug: measured roundtrip at 29 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query: EDNS works for ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: outnet handle udp reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: udp message[206:0] 1EF184100001000300000001037777770862726F6164636F6D03636F6D0363646E0A636C6F7564666C617265036E65740000010001C00C000100010000012C00046812049EC00C000100010000012C00046812059EC00C002E00010000012C006200010D060000012C60632E2360606F0386C90A636C6F7564666C617265036E6574004F1ACA2C91F279C599FB5F84B9BD587F7A0A1FB7B5CEF27919A91BF7B33C63FB4F6237EB79871F08EC91CD4151BC97F8F3D8FED3B570147A35361D669F172EA700002904D0000080000000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: received udp reply. Mar 29 14:56:51 unbound 99799 [99799:0] debug: lookup size is 2 entries Mar 29 14:56:51 unbound 99799 [99799:0] debug: Incoming reply addr = ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: Incoming reply id = 1ef1 Mar 29 14:56:51 unbound 99799 [99799:0] debug: answer cb Mar 29 14:56:51 unbound 99799 [99799:0] debug: close fd 27 Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm_point_close of 27: event_del Mar 29 14:56:51 unbound 99799 [99799:0] debug: close of port 19544 Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced_delete Mar 29 14:56:51 unbound 99799 [99799:0] debug: svcd callbacks end Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache memory msg=279320 rrset=951577 infra=232072 val=107040 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1RDd mod1 rep www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 0vRDCDd mod1 broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 32.000000 64.000000 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1.000000 2.000000 3 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.524288 1.000000 16 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.262144 0.524288 52 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.131072 0.262144 73 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.065536 0.131072 74 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.032768 0.065536 91 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.016384 0.032768 14 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.008192 0.016384 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000512 0.001024 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000000 0.000001 22 Mar 29 14:56:51 unbound 99799 [99799:0] info: lower(secs) upper(secs) recursions Mar 29 14:56:51 unbound 99799 [99799:0] info: [25%]=0.0502323 median[50%]=0.105389 [75%]=0.236109 Mar 29 14:56:51 unbound 99799 [99799:0] info: histogram of recursion processing times Mar 29 14:56:51 unbound 99799 [99799:0] info: average recursion processing time 0.407205 sec Mar 29 14:56:51 unbound 99799 [99799:0] info: mesh_run: end 2 recursion states (1 with reply, 1 detached), 1 waiting replies, 350 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm point start listening 25 (-1 msec) Mar 29 14:56:51 unbound 99799 [99799:0] debug: opened UDP if=0 port=18051 Mar 29 14:56:51 unbound 99799 [99799:0] debug: inserted new pending reply id=1ef1 Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query UDP timeout=376 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: EDNS lookup known=0 vs=0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: dnssec status: expected Mar 29 14:56:51 unbound 99799 [99799:0] debug: sending to target: 198.41.222.131#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: sending query: www.broadcom.com.cdn.cloudflare.net. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: selrtt 315 Mar 29 14:56:51 unbound 99799 [99799:0] debug: rtt=315 Mar 29 14:56:51 unbound 99799 [99799:0] debug: servselect ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: attempt to get extra 3 targets Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 173.245.59.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::adf5:3b1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] info: ns1.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns2.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns3.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns4.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns5.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 5 names (0 missing), 10 addrs (10 result, 0 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: processQueryTargets: www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: query response was ANSWER Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: broadcom.com.cdn.cloudflare.net. IN A ;; ANSWER SECTION: broadcom.com.cdn.cloudflare.net. 300 IN A 104.17.239.92 broadcom.com.cdn.cloudflare.net. 300 IN A 104.17.240.92 broadcom.com.cdn.cloudflare.net. 300 IN RRSIG A 13 5 300 20210330135651 20210328115651 34505 cloudflare.net. e9nx1TxPN3Z/8e+40ajaSqPWXxBeashe7eGNG/nGOfUJzppZgXhX+Pre79dy/tvoqjSd8gPnt3Ups6SFihI7WA== ;{id = 34505} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 191 Mar 29 14:56:51 unbound 99799 [99799:0] info: reply from 198.41.222.31#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: response for www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: scrub for cloudflare.net. NS IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: process_response: new external response event Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: chased to www.broadcom.com.cdn.cloudflare.net. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: query www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: start Mar 29 14:56:51 unbound 99799 [99799:0] debug: worker svcd callback for qstate 0x8024e17d0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: svcd callbacks start Mar 29 14:56:51 unbound 99799 [99799:0] debug: measured roundtrip at 30 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query: EDNS works for ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: outnet handle udp reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: udp message[202:0] 9156841000010003000000010862726F6164636F6D03636F6D0363646E0A636C6F7564666C617265036E65740000010001C00C000100010000012C00046811F05CC00C000100010000012C00046811EF5CC00C002E00010000012C006200010D050000012C60632E2360606F0386C90A636C6F7564666C617265036E6574007BD9F1D53C4F37767FF1EFB8D1A8DA4AA3D65F105E6AC85EEDE18D1BF9C639F509CE9A59817857F8FADEEFD772FEDBE8AA349DF203E7B77529B3A4858A123B5800002904D0000080000000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: received udp reply. Mar 29 14:56:51 unbound 99799 [99799:0] debug: lookup size is 2 entries Mar 29 14:56:51 unbound 99799 [99799:0] debug: Incoming reply addr = ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: Incoming reply id = 9156 Mar 29 14:56:51 unbound 99799 [99799:0] debug: answer cb Mar 29 14:56:51 unbound 99799 [99799:0] debug: close fd 25 Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm_point_close of 25: event_del Mar 29 14:56:51 unbound 99799 [99799:0] debug: close of port 22278 Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced_delete Mar 29 14:56:51 unbound 99799 [99799:0] debug: svcd callbacks end Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache memory msg=279320 rrset=951577 infra=231808 val=107040 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1RDd mod1 rep www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 0vRDCDd mod1 broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 32.000000 64.000000 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1.000000 2.000000 3 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.524288 1.000000 16 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.262144 0.524288 52 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.131072 0.262144 73 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.065536 0.131072 74 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.032768 0.065536 91 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.016384 0.032768 14 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.008192 0.016384 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000512 0.001024 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000000 0.000001 22 Mar 29 14:56:51 unbound 99799 [99799:0] info: lower(secs) upper(secs) recursions Mar 29 14:56:51 unbound 99799 [99799:0] info: [25%]=0.0502323 median[50%]=0.105389 [75%]=0.236109 Mar 29 14:56:51 unbound 99799 [99799:0] info: histogram of recursion processing times Mar 29 14:56:51 unbound 99799 [99799:0] info: average recursion processing time 0.407205 sec Mar 29 14:56:51 unbound 99799 [99799:0] info: mesh_run: end 2 recursion states (1 with reply, 1 detached), 1 waiting replies, 350 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: validator module exit state is module_finished Mar 29 14:56:51 unbound 99799 [99799:0] debug: not validating response, is valrec(validation recursion lookup) Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator: nextmodule returned Mar 29 14:56:51 unbound 99799 [99799:0] info: validator operate: query cloudflare.net. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_finished Mar 29 14:56:51 unbound 99799 [99799:0] info: finishing processing for cloudflare.net. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state FINISHED RESPONSE STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: returning answer from cache. Mar 29 14:56:51 unbound 99799 [99799:0] debug: msg ttl is 1423, prefetch ttl 1063 Mar 29 14:56:51 unbound 99799 [99799:0] info: msg from cache lookup ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ra ; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: cloudflare.net. IN DNSKEY ;; ANSWER SECTION: cloudflare.net. 1423 IN DNSKEY 256 3 13 oJMRESz5E4gYzS/q6XDrvU1qMPYIjCWzJaOau8XNEZeqCYKD5ar0IRd8KqXXFJkqmVfRvMGPmM1x8fGAa2XhSA== ;{id = 34505 (zsk), size = 256b} cloudflare.net. 1423 IN DNSKEY 257 3 13 mdsswUyr3DPW132mOi8V9xESWE8jTo0dxCjjnopKl+GqJxpVXckHAeF+KkxLbxILfDLUT0rAK9iUzy1L53eKGQ== ;{id = 2371 (ksk), size = 256b} cloudflare.net. 1423 IN RRSIG DNSKEY 13 2 3600 20210421062846 20210220062846 2371 cloudflare.net. PgFeDPUdvhKLaeAvGSMCF3LgOqEJvwBWdQXv36xoiY3zmZs4hbM+uiiQdVPTUpNa0W4mc6liQEW/39Q9HCvlsA== ;{id = 2371} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 302 Mar 29 14:56:51 unbound 99799 [99799:0] debug: request has dependency depth of 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving cloudflare.net. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: query cloudflare.net. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm point start listening 27 (-1 msec) Mar 29 14:56:51 unbound 99799 [99799:0] debug: opened UDP if=0 port=19544 Mar 29 14:56:51 unbound 99799 [99799:0] debug: inserted new pending reply id=9156 Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query UDP timeout=376 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: EDNS lookup known=0 vs=0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: dnssec status: expected Mar 29 14:56:51 unbound 99799 [99799:0] debug: sending to target: 198.41.222.31#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: sending query: broadcom.com.cdn.cloudflare.net. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: selrtt 376 Mar 29 14:56:51 unbound 99799 [99799:0] debug: attempt to get extra 3 targets Mar 29 14:56:51 unbound 99799 [99799:0] debug: removing 1 labels Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 173.245.59.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::adf5:3b1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] info: ns1.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns2.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns3.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns4.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns5.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 5 names (0 missing), 10 addrs (0 result, 10 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: processQueryTargets: www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: removing 2 labels Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 173.245.59.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::adf5:3b1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] info: ns1.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns2.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns3.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns4.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns5.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 5 names (0 missing), 10 addrs (0 result, 10 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: processQueryTargets: www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: removing 3 labels Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 173.245.59.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::adf5:3b1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] info: ns1.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns2.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns3.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns4.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns5.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 5 names (0 missing), 10 addrs (0 result, 10 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: processQueryTargets: www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: schedule dnskey prefetch cloudflare.net. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving (init part 3): www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving (init part 2): www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.223.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:df83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 198.41.222.131 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::c629:de83 port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 173.245.59.31 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip6 2400:cb00:2049:1::adf5:3b1f port 53 (len 28) Mar 29 14:56:51 unbound 99799 [99799:0] info: ns1.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns2.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns3.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns4.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: ns5.cloudflare.net. * A AAAA Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 5 names (0 missing), 10 addrs (0 result, 10 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache delegation returns delegpt Mar 29 14:56:51 unbound 99799 [99799:0] debug: request has dependency depth of 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: returning CNAME response from cache Mar 29 14:56:51 unbound 99799 [99799:0] debug: msg ttl is 1423, prefetch ttl 1281 Mar 29 14:56:51 unbound 99799 [99799:0] info: msg from cache lookup ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ; QUERY: 1, ANSWER: 2, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: cdn.broadcom.com. IN A ;; ANSWER SECTION: cdn.broadcom.com. 1423 IN CNAME www.broadcom.com.cdn.cloudflare.net. cdn.broadcom.com. 1423 IN RRSIG CNAME 8 3 3600 20210401224511 20210328223250 52716 broadcom.com. F3QWF0hmxtfBm99RXGkG/Sf9MYrNi8qK3oR5f+FZCsOtD/rzFjR7YMJW56y7lH8tFzLnpLKTtTasXfcdKBlUKmbvMJHUEagh6cQqoXpUpBBlk4fCr7JvI/7Sxgm0vxrLDv8m6njtfLsRYG63EG8arnRf4De2VhIaomDrJOmaTpYZMMTSetcHd+F3wjNRHx4H0YVz+i1z571mlrfY59J9xzQZwhcK4YhZbrERRyRTwDxOy3lmeCJPJ42u2qM0Gi90mtU6mRbq5uxgDLiSLVuceL9E7Ot28Y4Yz+H9LOshlOMFRH8KTzWBWMU5YlgUj0TOC90yk0FbUqxhA0JCRCbyJg== ;{id = 52716} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 383 Mar 29 14:56:51 unbound 99799 [99799:0] debug: request has dependency depth of 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: cleared outbound list for query restart Mar 29 14:56:51 unbound 99799 [99799:0] info: cname msg ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 2, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.broadcom.com. IN A ;; ANSWER SECTION: www.broadcom.com. 300 IN CNAME cdn.broadcom.com. www.broadcom.com. 300 IN RRSIG CNAME 8 3 300 20210402001539 20210328232028 52716 broadcom.com. FCuB5Vnk/GOtxIQPp84A0yCY0n/R/hvPl1KAj+4DT70fkNPPMJlbTdHjO6tpvsx6mHQiHY6LgfbUTDJqj/aUx5FKRPXRHp9dsyI/y+M8x06EJeTVewHHyR4dsUEi3yAudNRw+vSHeibmiKyLq+mNS6YEgkEY9JiL/RFrix0kZrJsjcv8Go/+iuQwr2Ry1NEFXmVDZuanaVpXP88T1xmIaZaDQPVW/+A7Pq337myMmWwGyFrM7150Cq1zYYc2HA9KLRZ9JvwqyZ/QEtA0nY+b6zSHi4Qk+GZVw9Ajfa2SgV3R0IhTv2LBcEMf6ZowjwFhI/48GrrAh4ksRBnwzkrQyQ== ;{id = 52716} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 352 Mar 29 14:56:51 unbound 99799 [99799:0] info: query response was CNAME Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 2, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: www.broadcom.com. IN A ;; ANSWER SECTION: www.broadcom.com. 300 IN CNAME cdn.broadcom.com. www.broadcom.com. 300 IN RRSIG CNAME 8 3 300 20210402001539 20210328232028 52716 broadcom.com. FCuB5Vnk/GOtxIQPp84A0yCY0n/R/hvPl1KAj+4DT70fkNPPMJlbTdHjO6tpvsx6mHQiHY6LgfbUTDJqj/aUx5FKRPXRHp9dsyI/y+M8x06EJeTVewHHyR4dsUEi3yAudNRw+vSHeibmiKyLq+mNS6YEgkEY9JiL/RFrix0kZrJsjcv8Go/+iuQwr2Ry1NEFXmVDZuanaVpXP88T1xmIaZaDQPVW/+A7Pq337myMmWwGyFrM7150Cq1zYYc2HA9KLRZ9JvwqyZ/QEtA0nY+b6zSHi4Qk+GZVw9Ajfa2SgV3R0IhTv2LBcEMf6ZowjwFhI/48GrrAh4ksRBnwzkrQyQ== ;{id = 52716} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 352 Mar 29 14:56:51 unbound 99799 [99799:0] info: reply from 156.154.131.100#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: response for www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: sanitize: removing extraneous answer RRset: cdn.broadcom.com. CNAME IN Mar 29 14:56:51 unbound 99799 [99799:0] info: scrub for broadcom.com. NS IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: process_response: new external response event Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: query www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: start Mar 29 14:56:51 unbound 99799 [99799:0] debug: worker svcd callback for qstate 0x8024e17d0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: svcd callbacks start Mar 29 14:56:51 unbound 99799 [99799:0] debug: measured roundtrip at 36 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query: EDNS works for ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: outnet handle udp reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: udp message[716:512] C7914A44F5D11E9F5DB3223FCBE33CC74E8425E4D57B01C7C91E1DB14122DF202E74D470FAF4877A26E688AC8BABE98D4BA604824118F4988BFD116B8B1D2466B26C8DCBFC1A8FFE8AE430AF6472D4D1055E654366E6A7695A573FCF13D7198869968340F556FFE03B3EADF7EE6C8C996C06C85ACCEF5E740AAD736187361C0F4A2D167D26FC2AC99FD012D0349D8F9BEB34878B8424F86655C3D0237DAD92815DD1D08853BF62C170431FE99A308F016123FE3C1ABAC087892C4419F0CE4AD0C90000291000000080000000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: udp message[716:256] B7106F1AAE745FE037B656121AA260EB24E99A4E961930C4D27AD70777E177C233511F1E07D18573FA2D73E7BD6696B7D8E7D27DC73419C2170AE188596EB111472453C03C4ECB796678224F278DAEDAA3341A2F749AD53A9916EAE6EC600CB8922D5B9C78BF44ECEB76F18E18CFE1FD2CEB2194E305447F0A4F358158C5396258148F44CE0BDD3293415B52AC610342424426F226C00C002E00010000012C0120000508030000012C6066622B60610F3CCDEC0862726F6164636F6D03636F6D00142B81E559E4FC63ADC4840FA7CE00D32098D27FD1FE1BCF9752808FEE034FBD1F90D3CF30995B4DD1E33BAB69BECC7A9874221D8E8B81F6D44C326A8FF694 Mar 29 14:56:51 unbound 99799 [99799:0] debug: udp message[716:0] 358E84000001000400000001037777770862726F6164636F6D03636F6D0000010001C00C000500010000012C00060363646EC0100363646EC0100005000100000E100025037777770862726F6164636F6D03636F6D0363646E0A636C6F7564666C617265036E657400C02E002E000100000E1001200005080300000E1060664CF760610412CDEC0862726F6164636F6D03636F6D00177416174866C6D7C19BDF515C6906FD27FD318ACD8BCA8ADE84797FE1590AC3AD0FFAF316347B60C256E7ACBB947F2D1732E7A4B293B536AC5DF71D2819542A66EF3091D411A821E9C42AA17A54A410659387C2AFB26F23FED2C609B4BF1ACB0EFF26EA78ED7CBB11606E Mar 29 14:56:51 unbound 99799 [99799:0] debug: received udp reply. Mar 29 14:56:51 unbound 99799 [99799:0] debug: lookup size is 2 entries Mar 29 14:56:51 unbound 99799 [99799:0] debug: Incoming reply addr = ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: Incoming reply id = 358e Mar 29 14:56:51 unbound 99799 [99799:0] debug: answer cb Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache memory msg=279320 rrset=951577 infra=231808 val=107040 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1RDd mod1 rep www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 0vRDCDd mod1 broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: 32.000000 64.000000 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 1.000000 2.000000 3 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.524288 1.000000 16 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.262144 0.524288 52 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.131072 0.262144 73 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.065536 0.131072 74 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.032768 0.065536 91 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.016384 0.032768 14 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.008192 0.016384 2 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000512 0.001024 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: 0.000000 0.000001 22 Mar 29 14:56:51 unbound 99799 [99799:0] info: lower(secs) upper(secs) recursions Mar 29 14:56:51 unbound 99799 [99799:0] info: [25%]=0.0502323 median[50%]=0.105389 [75%]=0.236109 Mar 29 14:56:51 unbound 99799 [99799:0] info: histogram of recursion processing times Mar 29 14:56:51 unbound 99799 [99799:0] info: average recursion processing time 0.407205 sec Mar 29 14:56:51 unbound 99799 [99799:0] info: mesh_run: end 2 recursion states (1 with reply, 1 detached), 1 waiting replies, 350 recursion replies sent, 0 replies dropped, 0 states jostled out Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm point start listening 26 (-1 msec) Mar 29 14:56:51 unbound 99799 [99799:0] debug: opened UDP if=0 port=11688 Mar 29 14:56:51 unbound 99799 [99799:0] debug: inserted new pending reply id=d8a5 Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query UDP timeout=120000 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: EDNS lookup known=0 vs=0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: dnssec status: expected Mar 29 14:56:51 unbound 99799 [99799:0] debug: sending to target: 156.154.130.100#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: sending query: broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: selrtt 119000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: rtt=119000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: rtt=120000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: attempt to get extra 2 targets Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns1.cscdns.net. * A Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns2.cscdns.net. * A Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: processQueryTargets: broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving (init part 3): broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving (init part 2): broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns1.cscdns.net. A Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns2.cscdns.net. A Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache delegation returns delegpt Mar 29 14:56:51 unbound 99799 [99799:0] debug: request has dependency depth of 1 Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: iterator operate: query broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: iterator module exit state is module_wait_reply Mar 29 14:56:51 unbound 99799 [99799:0] debug: comm point start listening 25 (-1 msec) Mar 29 14:56:51 unbound 99799 [99799:0] debug: opened UDP if=0 port=22278 Mar 29 14:56:51 unbound 99799 [99799:0] debug: inserted new pending reply id=358e Mar 29 14:56:51 unbound 99799 [99799:0] debug: serviced query UDP timeout=120000 msec Mar 29 14:56:51 unbound 99799 [99799:0] debug: EDNS lookup known=0 vs=0 Mar 29 14:56:51 unbound 99799 [99799:0] debug: dnssec status: expected Mar 29 14:56:51 unbound 99799 [99799:0] debug: sending to target: 156.154.131.100#53 Mar 29 14:56:51 unbound 99799 [99799:0] info: sending query: www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: selrtt 119000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: rtt=119000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: servselect ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: rtt=119000 Mar 29 14:56:51 unbound 99799 [99799:0] debug: servselect ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: attempt to get extra 3 targets Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns1.cscdns.net. * A Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns2.cscdns.net. * A Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: processQueryTargets: www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state QUERY TARGETS STATE Mar 29 14:56:51 unbound 99799 [99799:0] info: schedule dnskey prefetch broadcom.com. DNSKEY IN Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving (init part 3): www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving (init part 2): www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.131.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: ip4 156.154.130.100 port 53 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns1.cscdns.net. A Mar 29 14:56:51 unbound 99799 [99799:0] info: pdns2.cscdns.net. A Mar 29 14:56:51 unbound 99799 [99799:0] info: DelegationPoint: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS Mar 29 14:56:51 unbound 99799 [99799:0] debug: cache delegation returns delegpt Mar 29 14:56:51 unbound 99799 [99799:0] debug: request has dependency depth of 0 Mar 29 14:56:51 unbound 99799 [99799:0] info: resolving www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: iter_handle processing q with state INIT REQUEST STATE Mar 29 14:56:51 unbound 99799 [99799:0] debug: process_request: new external request event Mar 29 14:56:51 unbound 99799 [99799:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: validator module exit state is module_wait_module Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator: pass to next module Mar 29 14:56:51 unbound 99799 [99799:0] info: validator operate: query www.broadcom.com. A IN Mar 29 14:56:51 unbound 99799 [99799:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new Mar 29 14:56:51 unbound 99799 [99799:0] debug: mesh_run: start Mar 29 14:56:51 unbound 99799 [99799:0] debug: udp request from ip4 127.0.0.1 port 30020 (len 16) Mar 29 14:56:51 unbound 99799 [99799:0] debug: answer from the cache failed Mar 29 14:56:51 unbound 99799 [99799:0] info: 127.0.0.1 www.broadcom.com. A IN