experimental Recursor dnssec=validate crash sig11/SEGV #3390

Closed
ZaphodB opened this Issue Feb 16, 2016 · 4 comments

Projects

None yet

4 participants

@ZaphodB
Contributor
ZaphodB commented Feb 16, 2016

Version: 0.0.615gbf05ce7-1pdns.jessie / Using 64-bits mode. Built using gcc 4.9.2 on Jan 27 2016 09:03:02 by root@420df3ed81f6.
Sadly still no luck getting a core dump but the recursor ran with trace=yes this time around.

Previous deaths:

[434010.680891] pdns_recursor[1803]: segfault at 0 ip 00007f2e58a41c34 sp 00007f2e50946c00 error 4 in pdns_recursor[7f2e589ec000+2d0000]
[1213004.281536] pdns_recursor[10427]: segfault at 0 ip 00007fc6d7395c34 sp 00007fc6ce330e00 error 4 in pdns_recursor[7fc6d7340000+2d0000]
[1644945.295415] pdns_recursor[5838]: segfault at 0 ip 00007fde5f46ec34 sp 00007fde4c7ac6d0 error 4 in pdns_recursor[7fde5f419000+2d0000]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Got 1 answers from ns1.msft.net. (208.84.0.53), rcode=1 (Form Error), aa=0, in 39ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: status=NS ns1.msft.net. (208.84.0.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Failed to resolve via any of the 4 offered NS at level 'gfx.ms.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: failed (res=-1)
Feb 16 13:08:29 rdns1 systemd[1]: pdns-recursor.service: main process exited, code=killed, status=11/SEGV

Config:

allow-from=127.0.0.0/8, 10.0.0.0/8, 100.64.0.0/10, 169.254.0.0/16, 192.168.0.0/16, 172.16.0.0/12, ::1/128, fc00::/7, fe80::/10, 2001:470:51ee::/48
dnssec=validate
dont-query=127.0.0.0/8, 10.0.0.0/8, 100.64.0.0/10, 169.254.0.0/16, 192.168.0.0/16, 172.16.0.0/12, ::1/128, fc00::/7, fe80::/10, 0.0.0.0/8, 192.0.0.0/24, 192.0.2.0/24, 198.51.100.0/24, 203.0.113.0/24, 240.0.0.0/4, ::/96, ::ffff:0:0/96, 100::/64, 2001:db8::/32
edns-subnet-whitelist=0.0.0.0/0,::/0
local-address=127.0.0.1
local-port=5353
loglevel=6
max-cache-entries=250000
max-negative-ttl=600
max-packetcache-entries=250000
setgid=pdns
setuid=pdns
trace=true

Time is actually fine.

[root@rdns1:~]# ntpdate -q ntp0.freenet.de
server 2001:748:100:60::2, stratum 0, offset 0.000000, delay 0.00000
server 194.97.4.214, stratum 0, offset 0.000000, delay 0.00000

The whole output for the question right before the crash:

Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 1 [11545/3] question for 'assets.onestore.ms.|A' from 127.0.0.1
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Looking for CNAME cache hit of 'assets.onestore.ms.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: No CNAME cache hit of 'assets.onestore.ms.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: No cache hit for 'assets.onestore.ms.|A', trying to find an appropriate NS record
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Checking if we have NS in cache for 'assets.onestore.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: no valid/useful NS in cache for 'assets.onestore.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Checking if we have NS in cache for 'onestore.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: no valid/useful NS in cache for 'onestore.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Checking if we have NS in cache for 'ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: no valid/useful NS in cache for 'ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Checking if we have NS in cache for '.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'a.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=86271
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'b.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'c.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'd.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'e.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'f.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'g.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'h.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'i.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'j.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'k.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'l.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: NS (with ip, or non-glue) in cache for '.' -> 'm.root-servers.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: within bailiwick: 1,  in cache, ttl=85350
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: We have NS in cache for '.' (flawedNSSet=0)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Nameservers: b.root-servers.net.(0.00ms), g.root-servers.net.(0.00ms), c.root-servers.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.:              k.root-servers.net.(0.00ms), e.root-servers.net.(0.00ms), i.root-servers.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.:              j.root-servers.net.(0.00ms), d.root-servers.net.(0.00ms), f.root-servers.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.:              l.root-servers.net.(0.00ms), m.root-servers.net.(0.00ms), a.root-servers.net.(0.04ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.:              h.root-servers.net.(14.26ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Trying to resolve NS 'b.root-servers.net.' (1/13)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    b.root-servers.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    b.root-servers.net.: Looking for CNAME cache hit of 'b.root-servers.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    b.root-servers.net.: No CNAME cache hit of 'b.root-servers.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    b.root-servers.net.: Found cache hit for A: 192.228.79.201[ttl=85350]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Resolved '.' NS b.root-servers.net. to: 192.228.79.201
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Trying IP 192.228.79.201:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Got 17 answers from b.root-servers.net. (192.228.79.201), rcode=0 (No Error), aa=0, in 125ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|NS|ms-ns.anycast.pch.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|NS|b.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|NS|a.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|NS|phloem.uoregon.edu.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|NS|mnidns1.mninet.ms.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|NSEC|mt. NS RRSIG NSEC' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms.|RRSIG|NSEC 8 1 86400 20160226050000 20160216040000 54549 . tPZWiIu/Es4PPioVbpZQW+dZ9Tv/dK9yk+0DB7R0c0VqBk94VOD7wqmE0KfF8pBfWC0rTNtVg+kCXbWl0DKGnWkIj9kfkXcZXkqoIkw5W7ww0JpjgnqEgCklTapglvGDN799Ybn5CmUyJ+uJmmkQeI3/SATBIvAk8KaL/PViIWw=' from '.' nameservers? 2 RRSIG - separate
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|A|199.19.5.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|A|199.19.6.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|A|204.61.216.33' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|A|128.223.32.35' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'mnidns1.mninet.ms.|A|199.7.90.2' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|AAAA|2001:500:92::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|AAAA|2001:500:96::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|AAAA|2001:500:14:6033:ad::1' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|AAAA|2001:468:d01:20::80df:2023' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: OPT answer '.' from '.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'ms.' -> 'ms-ns.anycast.pch.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'ms.' -> 'b.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'ms.' -> 'a.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'ms.' -> 'phloem.uoregon.edu.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'ms.' -> 'mnidns1.mninet.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: status=did not resolve, got 5 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Nameservers: a.ms.dyntld.net.(0.00ms), phloem.uoregon.edu.(0.00ms), b.ms.dyntld.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.:              ms-ns.anycast.pch.net.(0.00ms), mnidns1.mninet.ms.(0.00ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Trying to resolve NS 'a.ms.dyntld.net.' (1/5)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    a.ms.dyntld.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    a.ms.dyntld.net.: Looking for CNAME cache hit of 'a.ms.dyntld.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    a.ms.dyntld.net.: No CNAME cache hit of 'a.ms.dyntld.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    a.ms.dyntld.net.: Found cache hit for A: 199.19.5.23[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Resolved 'ms.' NS a.ms.dyntld.net. to: 199.19.5.23
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Trying IP 199.19.5.23:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Got 17 answers from b.root-servers.net. (192.228.79.201), rcode=0 (No Error), aa=0, in 202ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|NS|ms-ns.anycast.pch.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|NS|b.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|NS|a.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|NS|phloem.uoregon.edu.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|NS|mnidns1.mninet.ms.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|NSEC|mt. NS RRSIG NSEC' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms.|RRSIG|NSEC 8 1 86400 20160226050000 20160216040000 54549 . tPZWiIu/Es4PPioVbpZQW+dZ9Tv/dK9yk+0DB7R0c0VqBk94VOD7wqmE0KfF8pBfWC0rTNtVg+kCXbWl0DKGnWkIj9kfkXcZXkqoIkw5W7ww0JpjgnqEgCklTapglvGDN799Ybn5CmUyJ+uJmmkQeI3/SATBIvAk8KaL/PViIWw=' from '.' nameservers? 2 RRSIG - separate
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|A|199.19.5.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|A|199.19.6.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|A|204.61.216.33' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|A|128.223.32.35' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'mnidns1.mninet.ms.|A|199.7.90.2' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|AAAA|2001:500:92::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|AAAA|2001:500:96::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|AAAA|2001:500:14:6033:ad::1' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|AAAA|2001:468:d01:20::80df:2023' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: OPT answer '.' from '.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'ms.' -> 'ms-ns.anycast.pch.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'ms.' -> 'b.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'ms.' -> 'a.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'ms.' -> 'phloem.uoregon.edu.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'ms.' -> 'mnidns1.mninet.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: status=did not resolve, got 5 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Nameservers: a.ms.dyntld.net.(0.00ms), b.ms.dyntld.net.(0.00ms), phloem.uoregon.edu.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.:              ms-ns.anycast.pch.net.(0.00ms), mnidns1.mninet.ms.(0.00ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Trying to resolve NS 'a.ms.dyntld.net.' (1/5)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    a.ms.dyntld.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    a.ms.dyntld.net.: Looking for CNAME cache hit of 'a.ms.dyntld.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    a.ms.dyntld.net.: No CNAME cache hit of 'a.ms.dyntld.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    a.ms.dyntld.net.: Found cache hit for A: 199.19.5.23[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Resolved 'ms.' NS a.ms.dyntld.net. to: 199.19.5.23
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Trying IP 199.19.5.23:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Got 17 answers from b.root-servers.net. (192.228.79.201), rcode=0 (No Error), aa=0, in 125ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|NS|ms-ns.anycast.pch.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|NS|b.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|NS|a.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|NS|phloem.uoregon.edu.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|NS|mnidns1.mninet.ms.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|NSEC|mt. NS RRSIG NSEC' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms.|RRSIG|NSEC 8 1 86400 20160226050000 20160216040000 54549 . tPZWiIu/Es4PPioVbpZQW+dZ9Tv/dK9yk+0DB7R0c0VqBk94VOD7wqmE0KfF8pBfWC0rTNtVg+kCXbWl0DKGnWkIj9kfkXcZXkqoIkw5W7ww0JpjgnqEgCklTapglvGDN799Ybn5CmUyJ+uJmmkQeI3/SATBIvAk8KaL/PViIWw=' from '.' nameservers? 2 RRSIG - separate
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|A|199.19.5.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|A|199.19.6.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|A|204.61.216.33' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|A|128.223.32.35' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'mnidns1.mninet.ms.|A|199.7.90.2' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|AAAA|2001:500:92::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|AAAA|2001:500:96::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|AAAA|2001:500:14:6033:ad::1' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|AAAA|2001:468:d01:20::80df:2023' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: OPT answer '.' from '.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'ms.' -> 'ms-ns.anycast.pch.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'ms.' -> 'b.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'ms.' -> 'a.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'ms.' -> 'phloem.uoregon.edu.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'ms.' -> 'mnidns1.mninet.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: status=did not resolve, got 5 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Nameservers: a.ms.dyntld.net.(0.00ms), phloem.uoregon.edu.(0.00ms), b.ms.dyntld.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.:              ms-ns.anycast.pch.net.(0.00ms), mnidns1.mninet.ms.(0.00ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Trying to resolve NS 'a.ms.dyntld.net.' (1/5)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    a.ms.dyntld.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    a.ms.dyntld.net.: Looking for CNAME cache hit of 'a.ms.dyntld.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    a.ms.dyntld.net.: No CNAME cache hit of 'a.ms.dyntld.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    a.ms.dyntld.net.: Found cache hit for A: 199.19.5.23[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Resolved 'ms.' NS a.ms.dyntld.net. to: 199.19.5.23
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Trying IP 199.19.5.23:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Got 17 answers from b.root-servers.net. (192.228.79.201), rcode=0 (No Error), aa=0, in 202ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|NS|ms-ns.anycast.pch.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|NS|b.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|NS|a.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|NS|phloem.uoregon.edu.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|NS|mnidns1.mninet.ms.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|NSEC|mt. NS RRSIG NSEC' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms.|RRSIG|NSEC 8 1 86400 20160226050000 20160216040000 54549 . tPZWiIu/Es4PPioVbpZQW+dZ9Tv/dK9yk+0DB7R0c0VqBk94VOD7wqmE0KfF8pBfWC0rTNtVg+kCXbWl0DKGnWkIj9kfkXcZXkqoIkw5W7ww0JpjgnqEgCklTapglvGDN799Ybn5CmUyJ+uJmmkQeI3/SATBIvAk8KaL/PViIWw=' from '.' nameservers? 2 RRSIG - separate
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|A|199.19.5.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|A|199.19.6.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|A|204.61.216.33' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|A|128.223.32.35' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'mnidns1.mninet.ms.|A|199.7.90.2' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'a.ms.dyntld.net.|AAAA|2001:500:92::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 17 answers from b.root-servers.net. (192.228.79.201), rcode=0 (No Error), aa=0, in 201ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|NS|ms-ns.anycast.pch.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|NS|mnidns1.mninet.ms.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|NS|a.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|NS|phloem.uoregon.edu.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|NS|b.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|NSEC|mt. NS RRSIG NSEC' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms.|RRSIG|NSEC 8 1 86400 20160226050000 20160216040000 54549 . tPZWiIu/Es4PPioVbpZQW+dZ9Tv/dK9yk+0DB7R0c0VqBk94VOD7wqmE0KfF8pBfWC0rTNtVg+kCXbWl0DKGnWkIj9kfkXcZXkqoIkw5W7ww0JpjgnqEgCklTapglvGDN799Ybn5CmUyJ+uJmmkQeI3/SATBIvAk8KaL/PViIWw=' from '.' nameservers? 2 RRSIG - separate
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'a.ms.dyntld.net.|A|199.19.5.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'b.ms.dyntld.net.|A|199.19.6.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms-ns.anycast.pch.net.|A|204.61.216.33' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'phloem.uoregon.edu.|A|128.223.32.35' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'mnidns1.mninet.ms.|A|199.7.90.2' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'a.ms.dyntld.net.|AAAA|2001:500:92::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'b.ms.dyntld.net.|AAAA|2001:500:96::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'ms-ns.anycast.pch.net.|AAAA|2001:500:14:6033:ad::1' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'phloem.uoregon.edu.|AAAA|2001:468:d01:20::80df:2023' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from '.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'ms.' -> 'ms-ns.anycast.pch.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'ms.' -> 'mnidns1.mninet.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'ms.' -> 'a.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'ms.' -> 'phloem.uoregon.edu.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'ms.' -> 'b.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=did not resolve, got 5 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Nameservers: ms-ns.anycast.pch.net.(0.00ms), phloem.uoregon.edu.(0.00ms), b.ms.dyntld.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.:              mnidns1.mninet.ms.(0.00ms), a.ms.dyntld.net.(0.00ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying to resolve NS 'ms-ns.anycast.pch.net.' (1/5)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ms-ns.anycast.pch.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ms-ns.anycast.pch.net.: Looking for CNAME cache hit of 'ms-ns.anycast.pch.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ms-ns.anycast.pch.net.: No CNAME cache hit of 'ms-ns.anycast.pch.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ms-ns.anycast.pch.net.: Found cache hit for A: 204.61.216.33[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Resolved 'ms.' NS ms-ns.anycast.pch.net. to: 204.61.216.33
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying IP 204.61.216.33:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'b.ms.dyntld.net.|AAAA|2001:500:96::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'ms-ns.anycast.pch.net.|AAAA|2001:500:14:6033:ad::1' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'phloem.uoregon.edu.|AAAA|2001:468:d01:20::80df:2023' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: OPT answer '.' from '.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'ms.' -> 'ms-ns.anycast.pch.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'ms.' -> 'b.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'ms.' -> 'a.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'ms.' -> 'phloem.uoregon.edu.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'ms.' -> 'mnidns1.mninet.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: status=did not resolve, got 5 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Nameservers: a.ms.dyntld.net.(0.00ms), b.ms.dyntld.net.(0.00ms), phloem.uoregon.edu.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.:              ms-ns.anycast.pch.net.(0.00ms), mnidns1.mninet.ms.(0.00ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Trying to resolve NS 'a.ms.dyntld.net.' (1/5)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    a.ms.dyntld.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    a.ms.dyntld.net.: Looking for CNAME cache hit of 'a.ms.dyntld.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    a.ms.dyntld.net.: No CNAME cache hit of 'a.ms.dyntld.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    a.ms.dyntld.net.: Found cache hit for A: 199.19.5.23[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Resolved 'ms.' NS a.ms.dyntld.net. to: 199.19.5.23
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Trying IP 199.19.5.23:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Got 17 answers from b.root-servers.net. (192.228.79.201), rcode=0 (No Error), aa=0, in 201ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|NS|ms-ns.anycast.pch.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|NS|mnidns1.mninet.ms.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|NS|a.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|NS|phloem.uoregon.edu.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|NS|b.ms.dyntld.net.' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|NSEC|mt. NS RRSIG NSEC' from '.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms.|RRSIG|NSEC 8 1 86400 20160226050000 20160216040000 54549 . tPZWiIu/Es4PPioVbpZQW+dZ9Tv/dK9yk+0DB7R0c0VqBk94VOD7wqmE0KfF8pBfWC0rTNtVg+kCXbWl0DKGnWkIj9kfkXcZXkqoIkw5W7ww0JpjgnqEgCklTapglvGDN799Ybn5CmUyJ+uJmmkQeI3/SATBIvAk8KaL/PViIWw=' from '.' nameservers? 2 RRSIG - separate
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'a.ms.dyntld.net.|A|199.19.5.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'b.ms.dyntld.net.|A|199.19.6.23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms-ns.anycast.pch.net.|A|204.61.216.33' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'phloem.uoregon.edu.|A|128.223.32.35' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'mnidns1.mninet.ms.|A|199.7.90.2' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'a.ms.dyntld.net.|AAAA|2001:500:92::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'b.ms.dyntld.net.|AAAA|2001:500:96::23' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'ms-ns.anycast.pch.net.|AAAA|2001:500:14:6033:ad::1' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'phloem.uoregon.edu.|AAAA|2001:468:d01:20::80df:2023' from '.' nameservers? 3 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: OPT answer '.' from '.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'ms.' -> 'ms-ns.anycast.pch.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'ms.' -> 'mnidns1.mninet.ms.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'ms.' -> 'a.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'ms.' -> 'phloem.uoregon.edu.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'ms.' -> 'b.ms.dyntld.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: status=did not resolve, got 5 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Nameservers: ms-ns.anycast.pch.net.(0.00ms), phloem.uoregon.edu.(0.00ms), b.ms.dyntld.net.(0.00ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.:              mnidns1.mninet.ms.(0.00ms), a.ms.dyntld.net.(0.00ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Trying to resolve NS 'ms-ns.anycast.pch.net.' (1/5)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ms-ns.anycast.pch.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ms-ns.anycast.pch.net.: Looking for CNAME cache hit of 'ms-ns.anycast.pch.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ms-ns.anycast.pch.net.: No CNAME cache hit of 'ms-ns.anycast.pch.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ms-ns.anycast.pch.net.: Found cache hit for A: 204.61.216.33[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Resolved 'ms.' NS ms-ns.anycast.pch.net. to: 204.61.216.33
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Trying IP 204.61.216.33:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Got 5 answers from a.ms.dyntld.net. (199.19.5.23), rcode=0 (No Error), aa=0, in 28ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns3.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns2.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns1.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns4.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: OPT answer '.' from 'ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns3.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns2.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns1.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns4.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: status=did not resolve, got 4 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Nameservers: ns4.msft.net.(0.00ms), ns3.msft.net.(0.00ms), ns2.msft.net.(39.98ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.:              ns1.msft.net.(40.44ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Trying to resolve NS 'ns4.msft.net.' (1/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    ns4.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    ns4.msft.net.: Looking for CNAME cache hit of 'ns4.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    ns4.msft.net.: No CNAME cache hit of 'ns4.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]    ns4.msft.net.: Found cache hit for A: 208.76.45.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Resolved 'onestore.MS.' NS ns4.msft.net. to: 208.76.45.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Trying IP 208.76.45.53:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Got 5 answers from a.ms.dyntld.net. (199.19.5.23), rcode=0 (No Error), aa=0, in 34ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns3.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns2.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns1.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns4.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: OPT answer '.' from 'ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns3.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns2.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns1.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns4.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: status=did not resolve, got 4 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Nameservers: ns3.msft.net.(0.00ms), ns4.msft.net.(0.00ms), ns2.msft.net.(39.98ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.:              ns1.msft.net.(40.44ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Trying to resolve NS 'ns3.msft.net.' (1/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    ns3.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    ns3.msft.net.: Looking for CNAME cache hit of 'ns3.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    ns3.msft.net.: No CNAME cache hit of 'ns3.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545]    ns3.msft.net.: Found cache hit for A: 193.221.113.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Resolved 'onestore.MS.' NS ns3.msft.net. to: 193.221.113.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Trying IP 193.221.113.53:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Got 5 answers from a.ms.dyntld.net. (199.19.5.23), rcode=0 (No Error), aa=0, in 28ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns3.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns2.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns1.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns4.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: OPT answer '.' from 'ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns3.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns2.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns1.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns4.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: status=did not resolve, got 4 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Nameservers: ns4.msft.net.(0.00ms), ns3.msft.net.(0.00ms), ns2.msft.net.(39.98ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.:              ns1.msft.net.(40.44ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Trying to resolve NS 'ns4.msft.net.' (1/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    ns4.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    ns4.msft.net.: Looking for CNAME cache hit of 'ns4.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    ns4.msft.net.: No CNAME cache hit of 'ns4.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]    ns4.msft.net.: Found cache hit for A: 208.76.45.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Resolved 'onestore.MS.' NS ns4.msft.net. to: 208.76.45.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Trying IP 208.76.45.53:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Got 5 answers from a.ms.dyntld.net. (199.19.5.23), rcode=0 (No Error), aa=0, in 34ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns3.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns2.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns1.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: accept answer 'onestore.MS.|NS|ns4.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: OPT answer '.' from 'ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns3.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns2.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns1.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: got NS record 'onestore.MS.' -> 'ns4.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: status=did not resolve, got 4 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Nameservers: ns3.msft.net.(0.00ms), ns4.msft.net.(0.00ms), ns2.msft.net.(39.98ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.:              ns1.msft.net.(40.44ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Trying to resolve NS 'ns3.msft.net.' (1/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    ns3.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    ns3.msft.net.: Looking for CNAME cache hit of 'ns3.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    ns3.msft.net.: No CNAME cache hit of 'ns3.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545]    ns3.msft.net.: Found cache hit for A: 193.221.113.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Resolved 'onestore.MS.' NS ns3.msft.net. to: 193.221.113.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11545] assets.onestore.ms.: Trying IP 193.221.113.53:53, asking 'assets.onestore.ms.|A'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 5 answers from ms-ns.anycast.pch.net. (204.61.216.33), rcode=0 (No Error), aa=0, in 48ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns2.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns3.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns4.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns1.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from 'ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns2.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns3.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns4.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns1.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=did not resolve, got 4 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Nameservers: ns4.msft.net.(0.00ms), ns3.msft.net.(0.00ms), ns2.msft.net.(39.96ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.:              ns1.msft.net.(40.42ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying to resolve NS 'ns4.msft.net.' (1/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns4.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns4.msft.net.: Looking for CNAME cache hit of 'ns4.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns4.msft.net.: No CNAME cache hit of 'ns4.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns4.msft.net.: Found cache hit for A: 208.76.45.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns4.msft.net. to: 208.76.45.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying IP 208.76.45.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Got 5 answers from ms-ns.anycast.pch.net. (204.61.216.33), rcode=0 (No Error), aa=0, in 48ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns2.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns3.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns4.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: accept answer 'gfx.ms.|NS|ns1.msft.net.' from 'ms.' nameservers? 2 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: OPT answer '.' from 'ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns2.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns3.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns4.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: got NS record 'gfx.ms.' -> 'ns1.msft.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: status=did not resolve, got 4 NS, looping to them
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Nameservers: ns4.msft.net.(0.00ms), ns3.msft.net.(0.00ms), ns2.msft.net.(39.96ms),
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.:              ns1.msft.net.(40.42ms)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Trying to resolve NS 'ns4.msft.net.' (1/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns4.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns4.msft.net.: Looking for CNAME cache hit of 'ns4.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns4.msft.net.: No CNAME cache hit of 'ns4.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns4.msft.net.: Found cache hit for A: 208.76.45.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns4.msft.net. to: 208.76.45.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Trying IP 208.76.45.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 1 answers from ns4.msft.net. (208.76.45.53), rcode=1 (Form Error), aa=0, in 38ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=NS ns4.msft.net. (208.76.45.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying to resolve NS 'ns3.msft.net.' (2/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns3.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns3.msft.net.: Looking for CNAME cache hit of 'ns3.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns3.msft.net.: No CNAME cache hit of 'ns3.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544]    ns3.msft.net.: Found cache hit for A: 193.221.113.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns3.msft.net. to: 193.221.113.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying IP 193.221.113.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Got 1 answers from ns4.msft.net. (208.76.45.53), rcode=1 (Form Error), aa=0, in 38ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: status=NS ns4.msft.net. (208.76.45.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Trying to resolve NS 'ns3.msft.net.' (2/4)
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns3.msft.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns3.msft.net.: Looking for CNAME cache hit of 'ns3.msft.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns3.msft.net.: No CNAME cache hit of 'ns3.msft.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544]    ns3.msft.net.: Found cache hit for A: 193.221.113.53[ttl=86400]
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns3.msft.net. to: 193.221.113.53
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11544] mem.gfx.ms.: Trying IP 193.221.113.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: Got 2 answers from ns4.msft.net. (208.76.45.53), rcode=0 (No Error), aa=1, in 39ms
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: accept answer 'assets.onestore.ms.|CNAME|assets.onestore.ms.akadns.net.' from 'onestore.MS.' nameservers? 1 YES!
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: OPT answer '.' from 'onestore.MS.' nameservers
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543] assets.onestore.ms.: status=got a CNAME referral, starting over with assets.onestore.ms.akadns.net.
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Wants DNSSEC processing
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Looking for CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: No CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME' found
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: No cache hit for 'assets.onestore.ms.akadns.net.|A', trying to find an appropriate NS record
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'ms.akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'ms.akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'akadns.net.'
Feb 16 13:08:28 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a3-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a7-131.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a11-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a1-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a9-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a5-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a13-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a4-131.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a10-128.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a28-129.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: We have NS in cache for 'akadns.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Cache consultations done, have 10 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Nameservers: a10-128.akadns.org.(0.00ms), a11-129.akadns.net.(0.00ms), a1-128.akadns.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.:              a9-128.akadns.net.(0.00ms), a3-129.akadns.net.(0.00ms), a13-130.akadns.org.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.:              a7-131.akadns.net.(0.02ms), a5-130.akadns.org.(2.86ms), a28-129.akadns.org.(3.51ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.:              a4-131.akadns.org.(25.71ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Trying to resolve NS 'a10-128.akadns.org.' (1/10)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]     a10-128.akadns.org.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]     a10-128.akadns.org.: Looking for CNAME cache hit of 'a10-128.akadns.org.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]     a10-128.akadns.org.: No CNAME cache hit of 'a10-128.akadns.org.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]     a10-128.akadns.org.: Found cache hit for A: 96.7.50.128[ttl=80001]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Resolved 'akadns.net.' NS a10-128.akadns.org. to: 96.7.50.128
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Trying IP 96.7.50.128:53, asking 'assets.onestore.ms.akadns.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: Got 2 answers from ns3.msft.net. (193.221.113.53), rcode=0 (No Error), aa=1, in 41ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: accept answer 'assets.onestore.ms.|CNAME|assets.onestore.ms.akadns.net.' from 'onestore.MS.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: OPT answer '.' from 'onestore.MS.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545] assets.onestore.ms.: status=got a CNAME referral, starting over with assets.onestore.ms.akadns.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Looking for CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: No CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: No cache hit for 'assets.onestore.ms.akadns.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a3-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a7-131.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a11-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a1-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a9-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a5-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a13-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a4-131.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a10-128.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a28-129.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: We have NS in cache for 'akadns.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Cache consultations done, have 10 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Nameservers: a1-128.akadns.net.(0.00ms), a11-129.akadns.net.(0.00ms), a10-128.akadns.org.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.:              a9-128.akadns.net.(0.00ms), a3-129.akadns.net.(0.00ms), a13-130.akadns.org.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.:              a7-131.akadns.net.(0.02ms), a5-130.akadns.org.(2.86ms), a28-129.akadns.org.(3.51ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.:              a4-131.akadns.org.(25.70ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Trying to resolve NS 'a1-128.akadns.net.' (1/10)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]     a1-128.akadns.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]     a1-128.akadns.net.: Looking for CNAME cache hit of 'a1-128.akadns.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]     a1-128.akadns.net.: No CNAME cache hit of 'a1-128.akadns.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]     a1-128.akadns.net.: Found cache hit for A: 193.108.88.128[ttl=21452]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Resolved 'akadns.net.' NS a1-128.akadns.net. to: 193.108.88.128
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Trying IP 193.108.88.128:53, asking 'assets.onestore.ms.akadns.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: Got 2 answers from ns4.msft.net. (208.76.45.53), rcode=0 (No Error), aa=1, in 39ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: accept answer 'assets.onestore.ms.|CNAME|assets.onestore.ms.akadns.net.' from 'onestore.MS.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: OPT answer '.' from 'onestore.MS.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543] assets.onestore.ms.: status=got a CNAME referral, starting over with assets.onestore.ms.akadns.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: Looking for CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: No CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: No cache hit for 'assets.onestore.ms.akadns.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a3-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a7-131.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a11-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a1-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a9-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a5-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a13-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:28 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a4-131.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a10-128.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a28-129.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: We have NS in cache for 'akadns.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: Cache consultations done, have 10 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: Nameservers: a10-128.akadns.org.(0.00ms), a11-129.akadns.net.(0.00ms), a1-128.akadns.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.:              a9-128.akadns.net.(0.00ms), a3-129.akadns.net.(0.00ms), a13-130.akadns.org.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.:              a7-131.akadns.net.(0.02ms), a5-130.akadns.org.(2.86ms), a28-129.akadns.org.(3.51ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.:              a4-131.akadns.org.(25.71ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: Trying to resolve NS 'a10-128.akadns.org.' (1/10)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]     a10-128.akadns.org.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]     a10-128.akadns.org.: Looking for CNAME cache hit of 'a10-128.akadns.org.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]     a10-128.akadns.org.: No CNAME cache hit of 'a10-128.akadns.org.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]     a10-128.akadns.org.: Found cache hit for A: 96.7.50.128[ttl=80001]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: Resolved 'akadns.net.' NS a10-128.akadns.org. to: 96.7.50.128
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: Trying IP 96.7.50.128:53, asking 'assets.onestore.ms.akadns.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545] assets.onestore.ms.: Got 2 answers from ns3.msft.net. (193.221.113.53), rcode=0 (No Error), aa=1, in 41ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545] assets.onestore.ms.: accept answer 'assets.onestore.ms.|CNAME|assets.onestore.ms.akadns.net.' from 'onestore.MS.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545] assets.onestore.ms.: OPT answer '.' from 'onestore.MS.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545] assets.onestore.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545] assets.onestore.ms.: status=got a CNAME referral, starting over with assets.onestore.ms.akadns.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Looking for CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: No CNAME cache hit of 'assets.onestore.ms.akadns.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: No cache hit for 'assets.onestore.ms.akadns.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'assets.onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'onestore.ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: no valid/useful NS in cache for 'ms.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Checking if we have NS in cache for 'akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a3-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a7-131.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a11-129.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a1-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a9-128.akadns.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 1,  in cache, ttl=21452
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a5-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a13-130.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a4-131.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a10-128.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: NS (with ip, or non-glue) in cache for 'akadns.net.' -> 'a28-129.akadns.org.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: We have NS in cache for 'akadns.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Cache consultations done, have 10 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Nameservers: a1-128.akadns.net.(0.00ms), a11-129.akadns.net.(0.00ms), a10-128.akadns.org.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.:              a9-128.akadns.net.(0.00ms), a3-129.akadns.net.(0.00ms), a13-130.akadns.org.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.:              a7-131.akadns.net.(0.02ms), a5-130.akadns.org.(2.86ms), a28-129.akadns.org.(3.51ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.:              a4-131.akadns.org.(25.70ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Trying to resolve NS 'a1-128.akadns.net.' (1/10)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]     a1-128.akadns.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]     a1-128.akadns.net.: Looking for CNAME cache hit of 'a1-128.akadns.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]     a1-128.akadns.net.: No CNAME cache hit of 'a1-128.akadns.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]     a1-128.akadns.net.: Found cache hit for A: 193.108.88.128[ttl=21452]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Resolved 'akadns.net.' NS a1-128.akadns.net. to: 193.108.88.128
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Trying IP 193.108.88.128:53, asking 'assets.onestore.ms.akadns.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 1 answers from ns3.msft.net. (193.221.113.53), rcode=1 (Form Error), aa=0, in 40ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=NS ns3.msft.net. (193.221.113.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying to resolve NS 'ns2.msft.net.' (3/4)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns2.msft.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns2.msft.net.: Looking for CNAME cache hit of 'ns2.msft.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns2.msft.net.: No CNAME cache hit of 'ns2.msft.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns2.msft.net.: Found cache hit for A: 208.84.2.53[ttl=86399]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns2.msft.net. to: 208.84.2.53
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying IP 208.84.2.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Got 1 answers from ns3.msft.net. (193.221.113.53), rcode=1 (Form Error), aa=0, in 40ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: status=NS ns3.msft.net. (193.221.113.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Trying to resolve NS 'ns2.msft.net.' (3/4)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns2.msft.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns2.msft.net.: Looking for CNAME cache hit of 'ns2.msft.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns2.msft.net.: No CNAME cache hit of 'ns2.msft.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns2.msft.net.: Found cache hit for A: 208.84.2.53[ttl=86399]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns2.msft.net. to: 208.84.2.53
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Trying IP 208.84.2.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: Got 2 answers from a10-128.akadns.org. (96.7.50.128), rcode=0 (No Error), aa=1, in 43ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: accept answer 'assets.onestore.ms.akadns.net.|CNAME|assets.onestore.ms.edgekey.net.' from 'akadns.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: OPT answer '.' from 'akadns.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]  assets.onestore.ms.akadns.net.: status=got a CNAME referral, starting over with assets.onestore.ms.edgekey.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Looking for CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: No CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: No cache hit for 'assets.onestore.ms.edgekey.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns1-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'usw6.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'adns1.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns4-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns7-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns5-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a6-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a5-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a16-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a18-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a28-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a13-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a26-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: We have NS in cache for 'edgekey.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Nameservers: ns4-66.akam.net.(0.00ms), ns7-65.akam.net.(0.00ms), a26-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.:              a13-65.akam.net.(0.00ms), ns5-66.akam.net.(0.00ms), a16-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.:              ns1-66.akam.net.(0.00ms), adns1.akam.net.(0.00ms), a28-65.akam.net.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.:              a6-65.akam.net.(0.33ms), a18-65.akam.net.(5.51ms), a5-65.akam.net.(13.87ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.:              usw6.akam.net.(47.18ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Trying to resolve NS 'ns4-66.akam.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]      ns4-66.akam.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]      ns4-66.akam.net.: Looking for CNAME cache hit of 'ns4-66.akam.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]      ns4-66.akam.net.: No CNAME cache hit of 'ns4-66.akam.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]      ns4-66.akam.net.: Found cache hit for A: 84.53.139.66[ttl=21452]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Resolved 'edgekey.net.' NS ns4-66.akam.net. to: 84.53.139.66
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Trying IP 84.53.139.66:53, asking 'assets.onestore.ms.edgekey.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: Got 2 answers from a10-128.akadns.org. (96.7.50.128), rcode=0 (No Error), aa=1, in 43ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: accept answer 'assets.onestore.ms.akadns.net.|CNAME|assets.onestore.ms.edgekey.net.' from 'akadns.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: OPT answer '.' from 'akadns.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]  assets.onestore.ms.akadns.net.: status=got a CNAME referral, starting over with assets.onestore.ms.edgekey.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Looking for CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: No CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: No cache hit for 'assets.onestore.ms.edgekey.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns1-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'usw6.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'adns1.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns4-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns7-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns5-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a6-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a5-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: Got 2 answers from a1-128.akadns.net. (193.108.88.128), rcode=0 (No Error), aa=1, in 46ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: accept answer 'assets.onestore.ms.akadns.net.|CNAME|assets.onestore.ms.edgekey.net.' from 'akadns.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: OPT answer '.' from 'akadns.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]  assets.onestore.ms.akadns.net.: status=got a CNAME referral, starting over with assets.onestore.ms.edgekey.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Looking for CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: No CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: No cache hit for 'assets.onestore.ms.edgekey.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns1-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'usw6.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'adns1.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns4-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns7-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns5-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a6-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a5-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a16-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a18-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a28-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a13-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a26-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: We have NS in cache for 'edgekey.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Nameservers: ns7-65.akam.net.(0.00ms), ns4-66.akam.net.(0.00ms), a26-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.:              a13-65.akam.net.(0.00ms), ns5-66.akam.net.(0.00ms), a16-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.:              ns1-66.akam.net.(0.00ms), adns1.akam.net.(0.00ms), a28-65.akam.net.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.:              a6-65.akam.net.(0.33ms), a18-65.akam.net.(5.51ms), a5-65.akam.net.(13.87ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.:              usw6.akam.net.(47.17ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Trying to resolve NS 'ns7-65.akam.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]      ns7-65.akam.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]      ns7-65.akam.net.: Looking for CNAME cache hit of 'ns7-65.akam.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]      ns7-65.akam.net.: No CNAME cache hit of 'ns7-65.akam.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]      ns7-65.akam.net.: Found cache hit for A: 96.7.49.65[ttl=77711]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Resolved 'edgekey.net.' NS ns7-65.akam.net. to: 96.7.49.65
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Trying IP 96.7.49.65:53, asking 'assets.onestore.ms.edgekey.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a16-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a18-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a28-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a13-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a26-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: We have NS in cache for 'edgekey.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Nameservers: ns4-66.akam.net.(0.00ms), ns7-65.akam.net.(0.00ms), a26-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.:              a13-65.akam.net.(0.00ms), ns5-66.akam.net.(0.00ms), a16-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.:              ns1-66.akam.net.(0.00ms), adns1.akam.net.(0.00ms), a28-65.akam.net.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.:              a6-65.akam.net.(0.33ms), a18-65.akam.net.(5.51ms), a5-65.akam.net.(13.87ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.:              usw6.akam.net.(47.18ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Trying to resolve NS 'ns4-66.akam.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]      ns4-66.akam.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]      ns4-66.akam.net.: Looking for CNAME cache hit of 'ns4-66.akam.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]      ns4-66.akam.net.: No CNAME cache hit of 'ns4-66.akam.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]      ns4-66.akam.net.: Found cache hit for A: 84.53.139.66[ttl=21452]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Resolved 'edgekey.net.' NS ns4-66.akam.net. to: 84.53.139.66
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Trying IP 84.53.139.66:53, asking 'assets.onestore.ms.edgekey.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: Got 2 answers from a1-128.akadns.net. (193.108.88.128), rcode=0 (No Error), aa=1, in 46ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: accept answer 'assets.onestore.ms.akadns.net.|CNAME|assets.onestore.ms.edgekey.net.' from 'akadns.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: OPT answer '.' from 'akadns.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]  assets.onestore.ms.akadns.net.: status=got a CNAME referral, starting over with assets.onestore.ms.edgekey.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Looking for CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: No CNAME cache hit of 'assets.onestore.ms.edgekey.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: No cache hit for 'assets.onestore.ms.edgekey.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'assets.onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'onestore.ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: no valid/useful NS in cache for 'ms.edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Checking if we have NS in cache for 'edgekey.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns1-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'usw6.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'adns1.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns4-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 1 answers from ns2.msft.net. (208.84.2.53), rcode=1 (Form Error), aa=0, in 40ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=NS ns2.msft.net. (208.84.2.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying to resolve NS 'ns1.msft.net.' (4/4)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns1.msft.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns1.msft.net.: Looking for CNAME cache hit of 'ns1.msft.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns1.msft.net.: No CNAME cache hit of 'ns1.msft.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544]    ns1.msft.net.: Found cache hit for A: 208.84.0.53[ttl=86399]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns1.msft.net. to: 208.84.0.53
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Trying IP 208.84.0.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns7-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'ns5-66.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a6-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a5-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a16-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a18-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a28-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a13-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: NS (with ip, or non-glue) in cache for 'edgekey.net.' -> 'a26-65.akam.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: within bailiwick: 0, not in cache / did not look at cache
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: We have NS in cache for 'edgekey.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Nameservers: ns7-65.akam.net.(0.00ms), ns4-66.akam.net.(0.00ms), a26-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.:              a13-65.akam.net.(0.00ms), ns5-66.akam.net.(0.00ms), a16-65.akam.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.:              ns1-66.akam.net.(0.00ms), adns1.akam.net.(0.00ms), a28-65.akam.net.(0.01ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.:              a6-65.akam.net.(0.33ms), a18-65.akam.net.(5.51ms), a5-65.akam.net.(13.87ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.:              usw6.akam.net.(47.17ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Trying to resolve NS 'ns7-65.akam.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]      ns7-65.akam.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]      ns7-65.akam.net.: Looking for CNAME cache hit of 'ns7-65.akam.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]      ns7-65.akam.net.: No CNAME cache hit of 'ns7-65.akam.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]      ns7-65.akam.net.: Found cache hit for A: 96.7.49.65[ttl=77711]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Resolved 'edgekey.net.' NS ns7-65.akam.net. to: 96.7.49.65
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Trying IP 96.7.49.65:53, asking 'assets.onestore.ms.edgekey.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Got 1 answers from ns2.msft.net. (208.84.2.53), rcode=1 (Form Error), aa=0, in 40ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: status=NS ns2.msft.net. (208.84.2.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Trying to resolve NS 'ns1.msft.net.' (4/4)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns1.msft.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns1.msft.net.: Looking for CNAME cache hit of 'ns1.msft.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns1.msft.net.: No CNAME cache hit of 'ns1.msft.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544]    ns1.msft.net.: Found cache hit for A: 208.84.0.53[ttl=86399]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Resolved 'gfx.ms.' NS ns1.msft.net. to: 208.84.0.53
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Trying IP 208.84.0.53:53, asking 'mem.gfx.ms.|AAAA'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: Got 2 answers from ns4-66.akam.net. (84.53.139.66), rcode=0 (No Error), aa=1, in 33ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: accept answer 'assets.onestore.ms.edgekey.net.|CNAME|e10858.dspg.akamaiedge.net.' from 'edgekey.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: OPT answer '.' from 'edgekey.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]   assets.onestore.ms.edgekey.net.: status=got a CNAME referral, starting over with e10858.dspg.akamaiedge.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Looking for CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: No CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: No cache hit for 'e10858.dspg.akamaiedge.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la7.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la3.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar2.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns3-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns2-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns4-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns6-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns7-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns5-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns1-1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: We have NS in cache for 'akamaiedge.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Nameservers: ns4-194.akamaiedge.net.(0.00ms), ns2-194.akamaiedge.net.(0.00ms), ns5-194.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.:              la7.akamaiedge.net.(0.00ms), la1.akamaiedge.net.(0.00ms), ns1-1.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.:              lar2.akamaiedge.net.(0.00ms), ns3-194.akamaiedge.net.(0.00ms), la6.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.:              ns7-194.akamaiedge.net.(0.00ms), ns6-194.akamaiedge.net.(0.00ms), lar6.akamaiedge.net.(5.07ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.:              la3.akamaiedge.net.(33.48ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Trying to resolve NS 'ns4-194.akamaiedge.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]       ns4-194.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]       ns4-194.akamaiedge.net.: Looking for CNAME cache hit of 'ns4-194.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]       ns4-194.akamaiedge.net.: No CNAME cache hit of 'ns4-194.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]       ns4-194.akamaiedge.net.: Found cache hit for A: 84.53.139.194[ttl=21453]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Resolved 'akamaiedge.net.' NS ns4-194.akamaiedge.net. to: 84.53.139.194
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11543]    e10858.dspg.akamaiedge.net.: Trying IP 84.53.139.194:53, asking 'e10858.dspg.akamaiedge.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: Got 2 answers from ns4-66.akam.net. (84.53.139.66), rcode=0 (No Error), aa=1, in 33ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: accept answer 'assets.onestore.ms.edgekey.net.|CNAME|e10858.dspg.akamaiedge.net.' from 'edgekey.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: OPT answer '.' from 'edgekey.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]   assets.onestore.ms.edgekey.net.: status=got a CNAME referral, starting over with e10858.dspg.akamaiedge.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Looking for CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: No CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: No cache hit for 'e10858.dspg.akamaiedge.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la7.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la3.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar2.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns3-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns2-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns4-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns6-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: Got 2 answers from ns7-65.akam.net. (96.7.49.65), rcode=0 (No Error), aa=1, in 32ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: accept answer 'assets.onestore.ms.edgekey.net.|CNAME|e10858.dspg.akamaiedge.net.' from 'edgekey.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: OPT answer '.' from 'edgekey.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]   assets.onestore.ms.edgekey.net.: status=got a CNAME referral, starting over with e10858.dspg.akamaiedge.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Looking for CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: No CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: No cache hit for 'e10858.dspg.akamaiedge.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la7.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la3.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar2.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns3-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns2-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns4-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns6-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns7-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns5-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns1-1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: We have NS in cache for 'akamaiedge.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Nameservers: ns2-194.akamaiedge.net.(0.00ms), ns3-194.akamaiedge.net.(0.00ms), la7.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.:              la1.akamaiedge.net.(0.00ms), lar2.akamaiedge.net.(0.00ms), ns4-194.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.:              ns5-194.akamaiedge.net.(0.00ms), ns1-1.akamaiedge.net.(0.00ms), la6.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.:              ns7-194.akamaiedge.net.(0.00ms), ns6-194.akamaiedge.net.(0.00ms), lar6.akamaiedge.net.(5.07ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.:              la3.akamaiedge.net.(33.48ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Trying to resolve NS 'ns2-194.akamaiedge.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]       ns2-194.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]       ns2-194.akamaiedge.net.: Looking for CNAME cache hit of 'ns2-194.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]       ns2-194.akamaiedge.net.: No CNAME cache hit of 'ns2-194.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]       ns2-194.akamaiedge.net.: Found cache hit for A: 2.22.230.194[ttl=21453]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Resolved 'akamaiedge.net.' NS ns2-194.akamaiedge.net. to: 2.22.230.194
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11545]    e10858.dspg.akamaiedge.net.: Trying IP 2.22.230.194:53, asking 'e10858.dspg.akamaiedge.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns7-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns5-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns1-1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: We have NS in cache for 'akamaiedge.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Nameservers: ns4-194.akamaiedge.net.(0.00ms), ns2-194.akamaiedge.net.(0.00ms), ns5-194.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.:              la7.akamaiedge.net.(0.00ms), la1.akamaiedge.net.(0.00ms), ns1-1.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.:              lar2.akamaiedge.net.(0.00ms), ns3-194.akamaiedge.net.(0.00ms), la6.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.:              ns7-194.akamaiedge.net.(0.00ms), ns6-194.akamaiedge.net.(0.00ms), lar6.akamaiedge.net.(5.07ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.:              la3.akamaiedge.net.(33.48ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Trying to resolve NS 'ns4-194.akamaiedge.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]       ns4-194.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]       ns4-194.akamaiedge.net.: Looking for CNAME cache hit of 'ns4-194.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]       ns4-194.akamaiedge.net.: No CNAME cache hit of 'ns4-194.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]       ns4-194.akamaiedge.net.: Found cache hit for A: 84.53.139.194[ttl=21453]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Resolved 'akamaiedge.net.' NS ns4-194.akamaiedge.net. to: 84.53.139.194
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11543]    e10858.dspg.akamaiedge.net.: Trying IP 84.53.139.194:53, asking 'e10858.dspg.akamaiedge.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: Got 2 answers from ns7-65.akam.net. (96.7.49.65), rcode=0 (No Error), aa=1, in 32ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: accept answer 'assets.onestore.ms.edgekey.net.|CNAME|e10858.dspg.akamaiedge.net.' from 'edgekey.net.' nameservers? 1 YES!
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: OPT answer '.' from 'edgekey.net.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]   assets.onestore.ms.edgekey.net.: status=got a CNAME referral, starting over with e10858.dspg.akamaiedge.net.
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Looking for CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: No CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: No cache hit for 'e10858.dspg.akamaiedge.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la7.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la3.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar2.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns3-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 1 answers from ns1.msft.net. (208.84.0.53), rcode=1 (Form Error), aa=0, in 39ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=NS ns1.msft.net. (208.84.0.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Failed to resolve via any of the 4 offered NS at level 'gfx.ms.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: failed (res=-1)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns2-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns4-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns6-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns7-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns5-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns1-1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: We have NS in cache for 'akamaiedge.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Nameservers: ns2-194.akamaiedge.net.(0.00ms), ns3-194.akamaiedge.net.(0.00ms), la7.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              la1.akamaiedge.net.(0.00ms), lar2.akamaiedge.net.(0.00ms), ns4-194.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              ns5-194.akamaiedge.net.(0.00ms), ns1-1.akamaiedge.net.(0.00ms), la6.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              ns7-194.akamaiedge.net.(0.00ms), ns6-194.akamaiedge.net.(0.00ms), lar6.akamaiedge.net.(5.07ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              la3.akamaiedge.net.(33.48ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Trying to resolve NS 'ns2-194.akamaiedge.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: Looking for CNAME cache hit of 'ns2-194.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: No CNAME cache hit of 'ns2-194.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: Found cache hit for A: 2.22.230.194[ttl=21453]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Resolved 'akamaiedge.net.' NS ns2-194.akamaiedge.net. to: 2.22.230.194
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Looking for CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: No CNAME cache hit of 'e10858.dspg.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: No cache hit for 'e10858.dspg.akamaiedge.net.|A', trying to find an appropriate NS record
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'e10858.dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: no valid/useful NS in cache for 'dspg.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Checking if we have NS in cache for 'akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la7.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'la3.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar6.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'lar2.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns3-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Got 1 answers from ns1.msft.net. (208.84.0.53), rcode=1 (Form Error), aa=0, in 39ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: status=NS ns1.msft.net. (208.84.0.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: Failed to resolve via any of the 4 offered NS at level 'gfx.ms.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: [11544] mem.gfx.ms.: failed (res=-1)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns2-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns4-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns6-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns7-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns5-194.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: NS (with ip, or non-glue) in cache for 'akamaiedge.net.' -> 'ns1-1.akamaiedge.net.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: within bailiwick: 1,  in cache, ttl=21453
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: We have NS in cache for 'akamaiedge.net.' (flawedNSSet=0)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Cache consultations done, have 13 NS to contact
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Nameservers: ns2-194.akamaiedge.net.(0.00ms), ns3-194.akamaiedge.net.(0.00ms), la7.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              la1.akamaiedge.net.(0.00ms), lar2.akamaiedge.net.(0.00ms), ns4-194.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              ns5-194.akamaiedge.net.(0.00ms), ns1-1.akamaiedge.net.(0.00ms), la6.akamaiedge.net.(0.00ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              ns7-194.akamaiedge.net.(0.00ms), ns6-194.akamaiedge.net.(0.00ms), lar6.akamaiedge.net.(5.07ms),
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.:              la3.akamaiedge.net.(33.48ms)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Trying to resolve NS 'ns2-194.akamaiedge.net.' (1/13)
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: Wants DNSSEC processing
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: Looking for CNAME cache hit of 'ns2-194.akamaiedge.net.|CNAME'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: No CNAME cache hit of 'ns2-194.akamaiedge.net.|CNAME' found
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]       ns2-194.akamaiedge.net.: Found cache hit for A: 2.22.230.194[ttl=21453]
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Resolved 'akamaiedge.net.' NS ns2-194.akamaiedge.net. to: 2.22.230.194
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11545]    e10858.dspg.akamaiedge.net.: Trying IP 2.22.230.194:53, asking 'e10858.dspg.akamaiedge.net.|A'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Got 1 answers from ns1.msft.net. (208.84.0.53), rcode=1 (Form Error), aa=0, in 39ms
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: OPT answer '.' from 'gfx.ms.' nameservers
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: determining status after receiving this packet
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: status=NS ns1.msft.net. (208.84.0.53) is lame for 'gfx.ms.', trying sibling IP or NS
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: Failed to resolve via any of the 4 offered NS at level 'gfx.ms.'
Feb 16 13:08:29 rdns1 pdns_recursor[5834]: Feb 16 13:08:29 [11544] mem.gfx.ms.: failed (res=-1)
Feb 16 13:08:29 rdns1 systemd[1]: pdns-recursor.service: main process exited, code=killed, status=11/SEGV
Feb 16 13:08:29 rdns1 systemd[1]: Unit pdns-recursor.service entered failed state.

@ZaphodB
Contributor
ZaphodB commented Feb 16, 2016

Not sure if its relevant but i am running this behind a dnsdist version 0.0.669gbf05ce7-1pdns.jessie with the following configuration:

controlSocket("127.0.0.1")
webserver("127.0.0.1:8083", "thisisasecret")
addLocal("192.168.40.74")
addLocal("2003:a:c69:4311::53")
setKey("thisisasecret")
truncateTC(true) -- fix up possibly badly truncated answers from pdns 2.9.22
-- carbonServer("2001:888:2000:1d::2")
addACL("192.168.40.0/22")
addACL("2001:470:51ee::/48")
addACL("2003:a:c69:4300::/56")

-- define the good servers
newServer({address="127.0.0.1:5353", order=1})
newServer({address="127.0.0.1:5353", order=1, pool="dnssec"})
newServer({address="8.8.8.8", qps=2, order=2})
newServer({address="8.8.8.8", qps=2, order=2, pool="dnssec"})
newServer({address="8.8.4.4", qps=2, order=2})
newServer({address="8.8.4.4", qps=2, order=2, pool="dnssec"})
newServer({address="2001:4860:4860::8888", qps=2, order=2})
newServer({address="2001:4860:4860::8844", qps=2, order=2, pool="dnssec"})
setDNSSECPool("dnssec")
topRule()
@Habbie Habbie added rec defect labels Feb 16, 2016
@Habbie Habbie added this to the rec-4-alpha2 milestone Feb 16, 2016
@ZaphodB
Contributor
ZaphodB commented Feb 18, 2016

This is the latest crash on 0.0.673g6bb53fa-1pdns.jessie. IPv6 connectivity might have been flaky (timeouts) while IPv4 was unaffected. Still no luck with coredumps, upgraded to 0.0.678g74fb0cc-1pdns.jessie and have it running in gdb now.

Feb 18 14:04:30 rdns1 pdns_recursor[4794]: Feb 18 14:04:30 [4614]    ns1.hover.com.: Wants DNSSEC processing
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: Feb 18 14:04:30 [4614]    ns1.hover.com.: Looking for CNAME cache hit of 'ns1.hover.com.|CNAME'
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: Feb 18 14:04:30 [4614]    ns1.hover.com.: No CNAME cache hit of 'ns1.hover.com.|CNAME' found
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: Feb 18 14:04:30 [4614]    ns1.hover.com.: Found cache hit for A: 216.40.47.26[ttl=86400]
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: Feb 18 14:04:30 [4614] dord.horse.: Resolved 'dord.horse.' NS ns1.hover.com. to: 216.40.47.26
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: Feb 18 14:04:30 [4614] dord.horse.: Trying IP 216.40.47.26:53, asking 'dord.horse.|A'
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: [4615] www.alicegrove.com.: Got 0 answers from ns1.hover.com. (216.40.47.26), rcode=0 (No Error), aa=1, in 140ms
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: [4615] www.alicegrove.com.: determining status after receiving this packet
Feb 18 14:04:30 rdns1 pdns_recursor[4794]: [4615] www.alicegrove.com.: status=noerror, other types may exist, but we are done (have aa bit)
Feb 18 14:04:30 rdns1 systemd[1]: pdns-recursor.service: main process exited, code=killed, status=11/SEGV
@pieterlexis
Member

We now return BOGUS (SERVFAIL) for assets.onestore.ms instead of crashing on this. dnsviz shows the CNAME mess. This name is insecure and we need to detect that

@ahupowerdns
Member

3e9c6c0 addresses this

@ahupowerdns ahupowerdns added a commit that closed this issue Mar 23, 2016
@ahupowerdns ahupowerdns Closes #3390 by fixing validation of provably insecure delegation for…
… NSEC records, plus improving our DNSSEC validation logging with --trace output on
3e9c6c0
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment