[1539426235] unbound[13250:0] notice: Start of unbound 1.5.10.
[1539426235] unbound[13250:0] debug: chdir to /var/unbound
[1539426235] unbound[13250:0] debug: chroot to /var/unbound
[1539426235] unbound[13250:0] debug: drop user privileges, run as unbound
[1539426235] unbound[13250:0] debug: switching log to stderr
[1539426235] unbound[13250:0] debug: module config: "validator iterator"
[1539426235] unbound[13250:0] notice: init module 0: validator
[1539426235] unbound[13250:0] notice: init module 1: iterator
[1539426235] unbound[13250:0] debug: target fetch policy for level 0 is 3
[1539426235] unbound[13250:0] debug: target fetch policy for level 1 is 2
[1539426235] unbound[13250:0] debug: target fetch policy for level 2 is 1
[1539426235] unbound[13250:0] debug: target fetch policy for level 3 is 0
[1539426235] unbound[13250:0] debug: target fetch policy for level 4 is 0
[1539426235] unbound[13250:0] debug: cache memory msg=66072 rrset=66072 infra=2632 val=66280
[1539426235] unbound[13250:0] info: start of service (unbound 1.5.10).
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
[1539426235] unbound[13250:0] info: validator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426235] unbound[13250:0] info: resolving . DNSKEY IN
[1539426235] unbound[13250:0] info: priming . IN NS
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426235] unbound[13250:0] info: iterator operate: query . NS IN
[1539426235] unbound[13250:0] info: processQueryTargets: . NS IN
[1539426235] unbound[13250:0] info: new target G.ROOT-SERVERS.NET. AAAA IN
[1539426235] unbound[13250:0] info: sending query: . NS IN
[1539426235] unbound[13250:0] debug: sending to target: <.> 2001:503:c27::2:30#53
[1539426235] unbound[13250:0] info: error sending query to auth server 2001:503:c27::2:30 port 53
[1539426235] unbound[13250:0] info: processQueryTargets: . NS IN
[1539426235] unbound[13250:0] info: sending query: . NS IN
[1539426235] unbound[13250:0] debug: sending to target: <.> 2001:500:2f::f#53
[1539426235] unbound[13250:0] info: error sending query to auth server 2001:500:2f::f port 53
[1539426235] unbound[13250:0] info: processQueryTargets: . NS IN
[1539426235] unbound[13250:0] info: sending query: . NS IN
[1539426235] unbound[13250:0] debug: sending to target: <.> 199.7.91.13#53
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426235] unbound[13250:0] info: iterator operate: query G.ROOT-SERVERS.NET. AAAA IN
[1539426235] unbound[13250:0] info: resolving G.ROOT-SERVERS.NET. AAAA IN
[1539426235] unbound[13250:0] info: priming . IN NS
[1539426235] unbound[13250:0] debug: return error response REFUSED
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426235] unbound[13250:0] info: validator operate: query G.ROOT-SERVERS.NET. AAAA IN
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
[1539426235] unbound[13250:0] info: iterator operate: query . NS IN
[1539426235] unbound[13250:0] info: processQueryTargets: . NS IN
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426235] unbound[13250:0] info: iterator operate: query . NS IN
[1539426235] unbound[13250:0] info: response for . NS IN
[1539426235] unbound[13250:0] info: reply from <.> 199.7.91.13#53
[1539426235] unbound[13250:0] info: query response was ANSWER
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426235] unbound[13250:0] info: validator operate: query . NS IN
[1539426235] unbound[13250:0] info: priming successful for . NS IN
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
[1539426235] unbound[13250:0] info: iterator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] info: resolving (init part 2):  . DNSKEY IN
[1539426235] unbound[13250:0] info: resolving (init part 3):  . DNSKEY IN
[1539426235] unbound[13250:0] info: processQueryTargets: . DNSKEY IN
[1539426235] unbound[13250:0] info: sending query: . DNSKEY IN
[1539426235] unbound[13250:0] debug: sending to target: <.> 2001:7fe::53#53
[1539426235] unbound[13250:0] info: error sending query to auth server 2001:7fe::53 port 53
[1539426235] unbound[13250:0] info: processQueryTargets: . DNSKEY IN
[1539426235] unbound[13250:0] info: sending query: . DNSKEY IN
[1539426235] unbound[13250:0] debug: sending to target: <.> 192.203.230.10#53
[1539426235] unbound[13250:0] debug: cache memory msg=66873 rrset=72316 infra=3877 val=66280
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426235] unbound[13250:0] info: iterator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] info: response for . DNSKEY IN
[1539426235] unbound[13250:0] info: reply from <.> 192.203.230.10#53
[1539426235] unbound[13250:0] info: query response was ANSWER
[1539426235] unbound[13250:0] info: finishing processing for . DNSKEY IN
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426235] unbound[13250:0] info: validator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] info: prime trust anchor
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
[1539426235] unbound[13250:0] info: validator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426235] unbound[13250:0] info: resolving . DNSKEY IN
[1539426235] unbound[13250:0] info: finishing processing for . DNSKEY IN
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426235] unbound[13250:0] info: validator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] info: validate keys with anchor(DS): sec_status_secure
[1539426235] unbound[13250:0] info: Successfully primed trust anchor . DNSKEY IN
[1539426235] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
[1539426235] unbound[13250:0] info: validator operate: query . DNSKEY IN
[1539426235] unbound[13250:0] info: validate(positive): sec_status_secure
[1539426235] unbound[13250:0] info: validation success . DNSKEY IN
[1539426235] unbound[13250:0] debug: cache memory msg=67050 rrset=73632 infra=3877 val=67628
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
[1539426239] unbound[13250:0] info: validator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: resolving www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sending query: www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 192.36.148.17#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=73632 infra=4126 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: response for www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: reply from <.> 192.36.148.17#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sending query: www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: sending to target: <org.> 199.19.53.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=77100 infra=4379 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: response for www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: reply from <org.> 199.19.53.1#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: new target ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: new target ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: new target ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: new target ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: resolving ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:12::d0d#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:12::d0d port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:12::d0d#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:12::d0d port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:dc3::35#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:dc3::35 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:2f::f#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:2f::f port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 192.36.148.17#53
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: resolving ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:2::c#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:2::c port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 192.58.128.30#53
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: resolving ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 192.36.148.17#53
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: resolving ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 199.9.14.201#53
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: resolving ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:9f::42#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:9f::42 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:2::c#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:2::c port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:1::53#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:1::53 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 199.7.91.13#53
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: resolving ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:7fd::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:7fd::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:2::c#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:2::c port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 2001:500:2::c#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:2::c port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 192.203.230.10#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=77783 infra=6371 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: response for ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: reply from <.> 192.203.230.10#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <net.> 192.12.94.30#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=84242 infra=6624 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: response for ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: reply from <.> 192.36.148.17#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <net.> 2001:501:b1f9::30#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:501:b1f9::30 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <net.> 2001:501:b1f9::30#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:501:b1f9::30 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <net.> 192.48.79.30#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=84242 infra=7130 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: response for ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: reply from <.> 192.36.148.17#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] debug: sending to target: <com.> 192.31.80.30#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=85501 infra=7383 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: reply from <.> 199.7.91.13#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <info.> 199.254.31.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=88989 infra=7637 val=67628
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: response for ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: reply from <net.> 192.12.94.30#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: new target ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: new target ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.net.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.net.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.net.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.net.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.net.> 72.52.71.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=90509 infra=8159 val=68436
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: response for ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: reply from <net.> 192.48.79.30#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: new target ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: new target ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sending query: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.net.> 72.52.71.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=90509 infra=8159 val=68436
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: response for ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: reply from <com.> 192.31.80.30#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.com.> 63.243.194.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67050 rrset=91826 infra=8420 val=69107
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: reply from <isc-sns.net.> 72.52.71.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sending query: www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: sending to target: <freebsd.org.> 72.52.71.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67291 rrset=92381 infra=8681 val=69107
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: reply from <isc-sns.net.> 72.52.71.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: cache memory msg=67532 rrset=92381 infra=8681 val=69107
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: reply from <isc-sns.com.> 63.243.194.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: cache memory msg=67773 rrset=93139 infra=8681 val=69107
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sanitize: removing extraneous answer RRset: wfe0.nyi.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: reply from <freebsd.org.> 72.52.71.1#53
[1539426239] unbound[13250:0] info: query response was CNAME
[1539426239] unbound[13250:0] info: resolving www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: wfe0.nyi.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: sending to target: <freebsd.org.> 2001:5a0:10::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:5a0:10::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sending query: wfe0.nyi.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: sending to target: <freebsd.org.> 72.52.71.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=67773 rrset=93974 infra=8942 val=69107
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: iterator operate: chased to wfe0.nyi.freebsd.org. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: reply from <freebsd.org.> 72.52.71.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: validated DS org. DS IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: validator operate: query org. DNSKEY IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: resolving org. DNSKEY IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  org. DNSKEY IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  org. DNSKEY IN
[1539426239] unbound[13250:0] info: processQueryTargets: org. DNSKEY IN
[1539426239] unbound[13250:0] info: sending query: org. DNSKEY IN
[1539426239] unbound[13250:0] debug: sending to target: <org.> 2001:500:48::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:48::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: org. DNSKEY IN
[1539426239] unbound[13250:0] info: sending query: org. DNSKEY IN
[1539426239] unbound[13250:0] debug: sending to target: <org.> 2001:500:b::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:500:b::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: org. DNSKEY IN
[1539426239] unbound[13250:0] info: sending query: org. DNSKEY IN
[1539426239] unbound[13250:0] debug: sending to target: <org.> 199.19.54.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=68236 rrset=94483 infra=9701 val=69107
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query org. DNSKEY IN
[1539426239] unbound[13250:0] info: response for org. DNSKEY IN
[1539426239] unbound[13250:0] info: reply from <org.> 199.19.54.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for org. DNSKEY IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query org. DNSKEY IN
[1539426239] unbound[13250:0] info: validated DNSKEY org. DNSKEY IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
[1539426239] unbound[13250:0] info: validator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: validated DS freebsd.org. DS IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: validator operate: query freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: resolving freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: processQueryTargets: freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] debug: sending to target: <freebsd.org.> 63.243.194.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=68417 rrset=96323 infra=9962 val=70979
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: response for ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: reply from <.> 199.9.14.201#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: sending to target: <info.> 199.249.121.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=68417 rrset=96323 infra=10216 val=70979
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: response for freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: reply from <freebsd.org.> 63.243.194.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] info: validated DNSKEY freebsd.org. DNSKEY IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
[1539426239] unbound[13250:0] info: validator operate: query www.freebsd.org. A IN
[1539426239] unbound[13250:0] info: validate(cname): sec_status_secure
[1539426239] unbound[13250:0] info: validate(positive): sec_status_secure
[1539426239] unbound[13250:0] info: validation success www.freebsd.org. A IN
[1539426239] unbound[13250:0] debug: cache memory msg=68606 rrset=97373 infra=10216 val=72061
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
[1539426239] unbound[13250:0] info: validator operate: query wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: resolving wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: processQueryTargets: wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: new target ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <freebsd.org.> 63.243.194.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=68606 rrset=97373 infra=10216 val=72061
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: response for ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: reply from <info.> 199.249.121.1#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:5a0:10::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:5a0:10::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 63.243.194.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=68606 rrset=98908 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: reply from <freebsd.org.> 63.243.194.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] info: validate(positive): sec_status_secure
[1539426239] unbound[13250:0] info: validation success wfe0.nyi.freebsd.org. AAAA IN
[1539426239] unbound[13250:0] debug: cache memory msg=68828 rrset=99429 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
[1539426239] unbound[13250:0] info: validator operate: query wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1539426239] unbound[13250:0] info: resolving wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: resolving (init part 2):  wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: resolving (init part 3):  wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: processQueryTargets: wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: sending query: wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] debug: sending to target: <freebsd.org.> 63.243.194.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=68828 rrset=99429 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: response for ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: reply from <isc-sns.info.> 63.243.194.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] debug: cache memory msg=69070 rrset=99987 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: reply from <freebsd.org.> 63.243.194.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] info: validate(positive): sec_status_secure
[1539426239] unbound[13250:0] info: validation success wfe0.nyi.freebsd.org. MX IN
[1539426239] unbound[13250:0] debug: cache memory msg=69292 rrset=100495 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: response for ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: reply from <info.> 199.254.31.1#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:5a0:10::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:5a0:10::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:5a0:10::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:5a0:10::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 2001:470:1a::1#53
[1539426239] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426239] unbound[13250:0] info: processQueryTargets: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <isc-sns.info.> 63.243.194.1#53
[1539426239] unbound[13250:0] debug: cache memory msg=69292 rrset=100495 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. A IN
[1539426239] unbound[13250:0] info: sanitize: removing potential poison RRset: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: response for ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] info: reply from <isc-sns.info.> 63.243.194.1#53
[1539426239] unbound[13250:0] info: query response was ANSWER
[1539426239] unbound[13250:0] info: finishing processing for ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426239] unbound[13250:0] info: validator operate: query ns3.isc-sns.info. AAAA IN
[1539426239] unbound[13250:0] debug: cache memory msg=69534 rrset=100495 infra=11002 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <.> 193.0.14.129#53
[1539426239] unbound[13250:0] debug: cache memory msg=69534 rrset=100495 infra=11251 val=72736
[1539426239] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426239] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: response for ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: reply from <.> 193.0.14.129#53
[1539426239] unbound[13250:0] info: query response was REFERRAL
[1539426239] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] info: sending query: ns2.isc-sns.com. AAAA IN
[1539426239] unbound[13250:0] debug: sending to target: <com.> 192.35.51.30#53
[1539426239] unbound[13250:0] debug: cache memory msg=69534 rrset=100495 infra=11504 val=72736
[1539426240] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426240] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426240] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426240] unbound[13250:0] info: response for ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] info: reply from <com.> 192.35.51.30#53
[1539426240] unbound[13250:0] info: query response was REFERRAL
[1539426240] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] info: sending query: ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] debug: sending to target: <isc-sns.com.> 2001:470:1a::1#53
[1539426240] unbound[13250:0] info: error sending query to auth server 2001:470:1a::1 port 53
[1539426240] unbound[13250:0] info: processQueryTargets: ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] info: sending query: ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] debug: sending to target: <isc-sns.com.> 72.52.71.1#53
[1539426240] unbound[13250:0] debug: cache memory msg=69534 rrset=100495 infra=12026 val=72736
[1539426240] unbound[13250:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1539426240] unbound[13250:0] info: iterator operate: query ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. A IN
[1539426240] unbound[13250:0] info: sanitize: removing potential poison RRset: ns1.isc-sns.net. AAAA IN
[1539426240] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. A IN
[1539426240] unbound[13250:0] info: sanitize: removing potential poison RRset: ns3.isc-sns.info. AAAA IN
[1539426240] unbound[13250:0] info: response for ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] info: reply from <isc-sns.com.> 72.52.71.1#53
[1539426240] unbound[13250:0] info: query response was ANSWER
[1539426240] unbound[13250:0] info: finishing processing for ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
[1539426240] unbound[13250:0] info: validator operate: query ns2.isc-sns.com. AAAA IN
[1539426240] unbound[13250:0] debug: cache memory msg=69775 rrset=100495 infra=12026 val=72736
[1539426241] unbound[13250:0] info: service stopped (unbound 1.5.10).
[1539426241] unbound[13250:0] info: server stats for thread 0: 3 queries, 0 answers from cache, 3 recursions, 0 prefetch
[1539426241] unbound[13250:0] info: server stats for thread 0: requestlist max 3 avg 2 exceeded 0 jostled 0
[1539426241] unbound[13250:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 3 recursion replies sent, 0 replies dropped, 0 states jostled out
[1539426241] unbound[13250:0] info: average recursion processing time 0.124011 sec
[1539426241] unbound[13250:0] info: histogram of recursion processing times
[1539426241] unbound[13250:0] info: [25%]=0 median[50%]=0 [75%]=0
[1539426241] unbound[13250:0] info: lower(secs) upper(secs) recursions
[1539426241] unbound[13250:0] info:    0.016384    0.032768 2
[1539426241] unbound[13250:0] info:    0.262144    0.524288 1
[1539426241] unbound[13250:0] debug: cache memory msg=66072 rrset=66072 infra=12026 val=72736
[1539426241] unbound[13250:0] debug: switching log to stderr
