Date: Sat, 13 Oct 2018 12:31:08 +0200 From: =?utf-8?Q?Dag-Erling_Sm=C3=B8rgrav?= <des@des.no> To: Eugene Grosbein <eugen@grosbein.net> Cc: freebsd-net <freebsd-net@freebsd.org> Subject: Re: DNS KSK rollover, local_unbound and 11.2-STABLE Message-ID: <867eim2l5f.fsf@next.des.no> In-Reply-To: <86ftxa2m0s.fsf@next.des.no> ("Dag-Erling =?utf-8?Q?Sm=C3=B8r?= =?utf-8?Q?grav=22's?= message of "Sat, 13 Oct 2018 12:12:19 %2B0200") References: <5BC046FB.9080906@grosbein.net> <861s8uaodn.fsf@next.des.no> <d3f2d5cb-20a2-6868-55ae-9dd5181d997a@grosbein.net> <86ftxa2m0s.fsf@next.des.no>
next in thread | previous in thread | raw e-mail | index | archive | help
--=-=-=
Content-Type: text/plain; charset=utf-8
Content-Transfer-Encoding: quoted-printable
Just to show that there is no difference between a forwarding setup and
a recursing setup:
# truncate -s0 /etc/resolv.conf=20=20=20=20=20=20
# service local_unbound setup=20=20
Performing initial setup.
Extracting forwarders from /etc/resolv.conf.
No forwarders found in resolv.conf, unbound will recurse.
/var/unbound/lan-zones.conf created
/var/unbound/control.conf created
/var/unbound/unbound.conf created
/etc/resolvconf.conf not modified
original /etc/resolv.conf saved as /etc/resolv.conf.20181013.122338
# service local_unbound anchor
# cat /var/unbound/root.key=20
; created by unbound-anchor on Sat Oct 13 12:23:48 2018
. IN DS 19036 8 2 49AAC11D7B6F6446702E54A1607371607A1A41855200FD2CE1CDDE32F=
24E8FB5
. IN DS 20326 8 2 E06D44B80B8F1D39A95C0B0D7C65D08458E880409BBC683457104237C=
7F8EC8D
# /usr/sbin/unbound -ddvv >& tmp/local_unbound-112p3-recursing.log &
[1] 13249
# host www.freebsd.org
www.freebsd.org is an alias for wfe0.nyi.freebsd.org.
wfe0.nyi.freebsd.org has address 96.47.72.84
wfe0.nyi.freebsd.org has IPv6 address 2610:1c1:1:606c::50:15
wfe0.nyi.freebsd.org mail is handled by 0 .
# fg
[1] + running sudo /usr/sbin/unbound -ddvv >&tmp/local_unbound-112p3-re=
cursing.log
^C#
# cat /var/unbound/root.key=20
; autotrust trust anchor file
;;id: . 1
;;last_queried: 1539426235 ;;Sat Oct 13 12:23:55 2018
;;last_success: 1539426235 ;;Sat Oct 13 12:23:55 2018
;;next_probe_time: 1539469164 ;;Sun Oct 14 00:19:24 2018
;;query_failed: 0
;;query_interval: 43200
;;retry_time: 8640
. 172800 IN DNSKEY 257 3 8 AwEAAaz/tAm8yTn4Mfeh5eyI96WSVexTBAvkMgJzkKTOiW1v=
kIbzxeF3+/4RgWOq7HrxRixHlFlExOLAJr5emLvN7SWXgnLh4+B5xQlNVz8Og8kvArMtNROxVQu=
CaSnIDdD5LKyWbRd2n9WGe2R8PzgCmr3EgVLrjyBxWezF0jLHwVN8efS3rCj/EWgvIWgb9tarpV=
UDK/b58Da+sqqls3eNbuv7pr+eoZG+SrDK6nWeL3c6H5Apxz7LjVc1uTIdsIXxuOLYA4/ilBmSV=
IzuDWfdRUfhHdY6+cn8HFRm+2hM8AnXGXws9555KrUB5qihylGa8subX2Nn6UwNR1AkUTV74bU=
=3D ;{id =3D 20326 (ksk), size =3D 2048b} ;;state=3D2 [ VALID ] ;;count=
=3D0 ;;lastchange=3D1539426235 ;;Sat Oct 13 12:23:55 2018
. 172800 IN DNSKEY 257 3 8 AwEAAagAIKlVZrpC6Ia7gEzahOR+9W29euxhJhVVLOyQbSEW=
0O8gcCjFFVQUTf6v58fLjwBd0YI0EzrAcQqBGCzh/RStIoO8g0NfnfL2MTJRkxoXbfDaUeVPQuY=
Ehg37NZWAJQ9VnMVDxP/VHL496M/QZxkjf5/Efucp2gaDX6RS6CXpoY68LsvPVjR0ZSwzz1apAz=
vN9dlzEheX7ICJBBtuA6G3LQpzW5hOA2hzCTMjJPJ8LbqF6dsV6DoBQzgul0sGIcGOYl7OyQdXf=
Z57relSQageu+ipAdTTJ25AsRTAoub8ONGcLmqrAmRLKBP1dfwhYB4N7knNnulqQxA+Uk1ihz0=
=3D ;{id =3D 19036 (ksk), size =3D 2048b} ;;state=3D1 [ ADDPEND ] ;;count=
=3D1 ;;lastchange=3D1539426235 ;;Sat Oct 13 12:23:55 2018
DES
--=20
Dag-Erling Sm=C3=B8rgrav - des@des.no
--=-=-=
Content-Type: text/plain
Content-Disposition: attachment; filename=local_unbound-112p3-recursing.log
[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
--=-=-=--
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?867eim2l5f.fsf>