Skip to content

Instantly share code, notes, and snippets.

@jedisct1
Created May 16, 2012 23:25
Show Gist options
  • Select an option

  • Save jedisct1/2714875 to your computer and use it in GitHub Desktop.

Select an option

Save jedisct1/2714875 to your computer and use it in GitHub Desktop.
dnsrequestfromhell
[1337210514] unbound[81693:0] debug: mesh_run: start
[1337210514] unbound[81693:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
[1337210514] unbound[81693:0] info: validator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: validator: pass to next module
[1337210514] unbound[81693:0] debug: mesh_run: validator module exit state is module_wait_module
[1337210514] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
[1337210514] unbound[81693:0] debug: process_request: new external request event
[1337210514] unbound[81693:0] debug: iter_handle processing q with state INIT REQUEST STATE
[1337210514] unbound[81693:0] info: resolving Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: request has dependency depth of 0
[1337210514] unbound[81693:0] debug: cache delegation returns delegpt
[1337210514] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (2 missing), 2 addrs (0 result, 2 avail) cacheNS
[1337210514] unbound[81693:0] info: b1.skype.net. A
[1337210514] unbound[81693:0] info: b2.skype.net. A
[1337210514] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2)
[1337210514] unbound[81693:0] info: resolving (init part 2): Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3)
[1337210514] unbound[81693:0] info: resolving (init part 3): Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210514] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
[1337210514] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (0 result, 2 avail) cacheNS
[1337210514] unbound[81693:0] info: b1.skype.net. * A
[1337210514] unbound[81693:0] info: b2.skype.net. * A
[1337210514] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: attempt to get extra 3 targets
[1337210514] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: rtt=677
[1337210514] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: rtt=688
[1337210514] unbound[81693:0] debug: selrtt 677
[1337210514] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: sending to target: <sa.skype.net.> 195.46.253.244#53
[1337210514] unbound[81693:0] debug: dnssec status: not expected
[1337210514] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210514] unbound[81693:0] debug: qname perturbed to AUckNQWgXadsmEKTW21Eg1ZFV2XgTaqs312Xvaf54J0ex4OlY6UmoKv510Ra.3aUGqanX502ftQbMv0auBNWXvRPd5bbbG3TyAXw2z2PsZp2XPXE3kbDcpj35.yT3wsv3cl4SOVyqbBaDHg4UNKT2KDj5vGXJJczFesYlL00FfvakFp5eTPX21.EN1K5c6OtSkovmkTVh63SDKqtOPag2XRGWT1E2Xmmv4PFtfnBo0M.Sa.SkyPe.neT.
[1337210514] unbound[81693:0] debug: serviced query UDP timeout=677 msec
[1337210514] unbound[81693:0] debug: inserted new pending reply id=5aff
[1337210514] unbound[81693:0] debug: opened UDP if=0 port=6528
[1337210514] unbound[81693:0] debug: comm point start listening 8
[1337210514] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210514] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210514] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210514] unbound[81693:0] info: histogram of recursion processing times
[1337210514] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210514] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210514] unbound[81693:0] info: 0.016384 0.032768 2
[1337210514] unbound[81693:0] info: 0.524288 1.000000 2
[1337210514] unbound[81693:0] info: 1.000000 2.000000 1
[1337210514] unbound[81693:0] info: 2.000000 4.000000 1
[1337210514] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210514] unbound[81693:0] debug: answer cb
[1337210514] unbound[81693:0] debug: Incoming reply id = 5aff
[1337210514] unbound[81693:0] debug: Incoming reply addr = ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: lookup size is 1 entries
[1337210514] unbound[81693:0] debug: received udp reply.
[1337210514] unbound[81693:0] debug: udp message[12:0] 5AFF84030000000000000000
[1337210514] unbound[81693:0] debug: outnet handle udp reply
[1337210514] unbound[81693:0] debug: measured roundtrip at 184 msec
[1337210514] unbound[81693:0] debug: svcd callbacks start
[1337210514] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210514] unbound[81693:0] info: from server ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] info: for packet[12:0] 5AFF84030000000000000000
[1337210514] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210514] unbound[81693:0] debug: mesh_run: start
[1337210514] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210514] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: process_response: new external response event
[1337210514] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210514] unbound[81693:0] debug: query response was timeout
[1337210514] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210514] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1
[1337210514] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS
[1337210514] unbound[81693:0] info: b1.skype.net. * A
[1337210514] unbound[81693:0] info: b2.skype.net. * A
[1337210514] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: attempt to get extra 3 targets
[1337210514] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: rtt=688
[1337210514] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: rtt=622
[1337210514] unbound[81693:0] debug: selrtt 622
[1337210514] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: sending to target: <sa.skype.net.> 212.187.172.27#53
[1337210514] unbound[81693:0] debug: dnssec status: not expected
[1337210514] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210514] unbound[81693:0] debug: qname perturbed to AUcKnQWGXAdsMEKtw21eg1ZFv2XgtaQS312Xvaf54J0Ex4Oly6umoKv510rA.3AuGqANX502FtQbmV0aubNwXVRpD5BbBG3TyAXw2Z2pszp2XPxe3KBDcpJ35.yt3WSv3cL4soVYQbbADhG4uNkT2Kdj5vgxJJCzfEsyLL00FfVaKFp5EtPX21.En1k5c6otsKoVmKTVh63sdkqtopag2xrGwt1e2XmMv4pFtfnbO0M.SA.SkypE.NEt.
[1337210514] unbound[81693:0] debug: serviced query UDP timeout=688 msec
[1337210514] unbound[81693:0] debug: inserted new pending reply id=1d39
[1337210514] unbound[81693:0] debug: opened UDP if=0 port=16750
[1337210514] unbound[81693:0] debug: comm point start listening 9
[1337210514] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210514] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210514] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210514] unbound[81693:0] info: histogram of recursion processing times
[1337210514] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210514] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210514] unbound[81693:0] info: 0.016384 0.032768 2
[1337210514] unbound[81693:0] info: 0.524288 1.000000 2
[1337210514] unbound[81693:0] info: 1.000000 2.000000 1
[1337210514] unbound[81693:0] info: 2.000000 4.000000 1
[1337210514] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210514] unbound[81693:0] debug: svcd callbacks end
[1337210514] unbound[81693:0] debug: close of port 6528
[1337210514] unbound[81693:0] debug: close fd 8
[1337210514] unbound[81693:0] debug: answer cb
[1337210514] unbound[81693:0] debug: Incoming reply id = 1d39
[1337210514] unbound[81693:0] debug: Incoming reply addr = ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: lookup size is 1 entries
[1337210514] unbound[81693:0] debug: received udp reply.
[1337210514] unbound[81693:0] debug: udp message[12:0] 1D3984030000000000000000
[1337210514] unbound[81693:0] debug: outnet handle udp reply
[1337210514] unbound[81693:0] debug: measured roundtrip at 267 msec
[1337210514] unbound[81693:0] debug: svcd callbacks start
[1337210514] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210514] unbound[81693:0] info: from server ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] info: for packet[12:0] 1D3984030000000000000000
[1337210514] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210514] unbound[81693:0] debug: mesh_run: start
[1337210514] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210514] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: process_response: new external response event
[1337210514] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210514] unbound[81693:0] debug: query response was timeout
[1337210514] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210514] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 2
[1337210514] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS
[1337210514] unbound[81693:0] info: b1.skype.net. * A
[1337210514] unbound[81693:0] info: b2.skype.net. * A
[1337210514] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: attempt to get extra 3 targets
[1337210514] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210514] unbound[81693:0] debug: rtt=622
[1337210514] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210514] unbound[81693:0] debug: rtt=724
[1337210514] unbound[81693:0] debug: selrtt 622
[1337210514] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: sending to target: <sa.skype.net.> 212.187.172.27#53
[1337210514] unbound[81693:0] debug: dnssec status: not expected
[1337210514] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210514] unbound[81693:0] debug: qname perturbed to AUcknQWGxaDsMEkTw21Eg1ZFv2xgTAqS312XVaf54J0ex4OLY6UMOkV510RA.3aUgQANx502FtqbmV0auBnwXVrPD5BBbg3TYaxw2z2Pszp2xpxe3kBdcpJ35.Yt3wSV3cl4SOvYqbBaDHG4unKT2kdj5vGxJjCzfeSYll00fFVAkFp5ETPX21.eN1k5c6otskoVMKtVH63sdKQtOpaG2xrgWt1e2XMmV4PftFnBO0m.sa.SKype.neT.
[1337210514] unbound[81693:0] debug: serviced query UDP timeout=724 msec
[1337210514] unbound[81693:0] debug: inserted new pending reply id=3096
[1337210514] unbound[81693:0] debug: opened UDP if=0 port=30300
[1337210514] unbound[81693:0] debug: comm point start listening 8
[1337210514] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210514] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210514] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210514] unbound[81693:0] info: histogram of recursion processing times
[1337210514] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210514] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210514] unbound[81693:0] info: 0.016384 0.032768 2
[1337210514] unbound[81693:0] info: 0.524288 1.000000 2
[1337210514] unbound[81693:0] info: 1.000000 2.000000 1
[1337210514] unbound[81693:0] info: 2.000000 4.000000 1
[1337210514] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210514] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210514] unbound[81693:0] debug: svcd callbacks end
[1337210514] unbound[81693:0] debug: close of port 16750
[1337210514] unbound[81693:0] debug: close fd 9
[1337210515] unbound[81693:0] debug: answer cb
[1337210515] unbound[81693:0] debug: Incoming reply id = 3096
[1337210515] unbound[81693:0] debug: Incoming reply addr = ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: lookup size is 1 entries
[1337210515] unbound[81693:0] debug: received udp reply.
[1337210515] unbound[81693:0] debug: udp message[12:0] 309684030000000000000000
[1337210515] unbound[81693:0] debug: outnet handle udp reply
[1337210515] unbound[81693:0] debug: measured roundtrip at 227 msec
[1337210515] unbound[81693:0] debug: svcd callbacks start
[1337210515] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210515] unbound[81693:0] info: from server ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] info: for packet[12:0] 309684030000000000000000
[1337210515] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210515] unbound[81693:0] debug: mesh_run: start
[1337210515] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210515] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: process_response: new external response event
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210515] unbound[81693:0] debug: query response was timeout
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210515] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 3
[1337210515] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS
[1337210515] unbound[81693:0] info: b1.skype.net. * A
[1337210515] unbound[81693:0] info: b2.skype.net. * A
[1337210515] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=692
[1337210515] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=622
[1337210515] unbound[81693:0] debug: selrtt 622
[1337210515] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: sending to target: <sa.skype.net.> 195.46.253.244#53
[1337210515] unbound[81693:0] debug: dnssec status: not expected
[1337210515] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210515] unbound[81693:0] debug: qname perturbed to AUcKNQWgxAdSmEKtw21EG1zfv2XGtAQS312XvAF54J0eX4oLY6UMOKv510rA.3aUgqAnx502FTqBMv0AUbNwXVrpd5bbbG3TYAxW2Z2pSZP2XPXE3kBDCPJ35.yt3WSv3cl4sOVyqBbaDHG4Unkt2kdj5vgxJJCZFESYLL00FFvAkFP5ETPX21.EN1k5c6OtSKoVmkTVh63SdKQtOpAg2xRgWT1e2xMMV4PFTfNBO0M.SA.SkYPe.NeT.
[1337210515] unbound[81693:0] debug: serviced query UDP timeout=622 msec
[1337210515] unbound[81693:0] debug: inserted new pending reply id=8bca
[1337210515] unbound[81693:0] debug: opened UDP if=0 port=21204
[1337210515] unbound[81693:0] debug: comm point start listening 9
[1337210515] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210515] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210515] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210515] unbound[81693:0] info: histogram of recursion processing times
[1337210515] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210515] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210515] unbound[81693:0] info: 0.016384 0.032768 2
[1337210515] unbound[81693:0] info: 0.524288 1.000000 2
[1337210515] unbound[81693:0] info: 1.000000 2.000000 1
[1337210515] unbound[81693:0] info: 2.000000 4.000000 1
[1337210515] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210515] unbound[81693:0] debug: svcd callbacks end
[1337210515] unbound[81693:0] debug: close of port 30300
[1337210515] unbound[81693:0] debug: close fd 8
[1337210515] unbound[81693:0] debug: answer cb
[1337210515] unbound[81693:0] debug: Incoming reply id = 8bca
[1337210515] unbound[81693:0] debug: Incoming reply addr = ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: lookup size is 1 entries
[1337210515] unbound[81693:0] debug: received udp reply.
[1337210515] unbound[81693:0] debug: udp message[12:0] 8BCA84030000000000000000
[1337210515] unbound[81693:0] debug: outnet handle udp reply
[1337210515] unbound[81693:0] debug: measured roundtrip at 225 msec
[1337210515] unbound[81693:0] debug: svcd callbacks start
[1337210515] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210515] unbound[81693:0] info: from server ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] info: for packet[12:0] 8BCA84030000000000000000
[1337210515] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210515] unbound[81693:0] debug: mesh_run: start
[1337210515] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210515] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: process_response: new external response event
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210515] unbound[81693:0] debug: query response was timeout
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210515] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 4
[1337210515] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS
[1337210515] unbound[81693:0] info: b1.skype.net. * A
[1337210515] unbound[81693:0] info: b2.skype.net. * A
[1337210515] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=619
[1337210515] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=692
[1337210515] unbound[81693:0] debug: selrtt 619
[1337210515] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: sending to target: <sa.skype.net.> 195.46.253.244#53
[1337210515] unbound[81693:0] debug: dnssec status: not expected
[1337210515] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210515] unbound[81693:0] debug: qname perturbed to auCkNqWGxAdsMEktW21eG1zfV2xGtaqS312XVaf54J0ex4OlY6UMoKv510Ra.3augQaNx502FtqBMv0aubNWXvRpd5bbbG3tYaXW2Z2PsZP2xPxE3kBdCPj35.YT3wsv3Cl4SOVYqBBaDHg4unkt2kDj5VgxjJCZFesYLL00FfvAKFp5ETpx21.EN1K5c6otsKOvmktvh63SDKqtOPAG2xRGWt1E2XmMv4pftFNbO0m.Sa.skYpe.nEt.
[1337210515] unbound[81693:0] debug: serviced query UDP timeout=619 msec
[1337210515] unbound[81693:0] debug: inserted new pending reply id=a8aa
[1337210515] unbound[81693:0] debug: opened UDP if=0 port=25931
[1337210515] unbound[81693:0] debug: comm point start listening 8
[1337210515] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210515] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210515] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210515] unbound[81693:0] info: histogram of recursion processing times
[1337210515] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210515] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210515] unbound[81693:0] info: 0.016384 0.032768 2
[1337210515] unbound[81693:0] info: 0.524288 1.000000 2
[1337210515] unbound[81693:0] info: 1.000000 2.000000 1
[1337210515] unbound[81693:0] info: 2.000000 4.000000 1
[1337210515] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210515] unbound[81693:0] debug: svcd callbacks end
[1337210515] unbound[81693:0] debug: close of port 21204
[1337210515] unbound[81693:0] debug: close fd 9
[1337210515] unbound[81693:0] debug: answer cb
[1337210515] unbound[81693:0] debug: Incoming reply id = a8aa
[1337210515] unbound[81693:0] debug: Incoming reply addr = ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: lookup size is 1 entries
[1337210515] unbound[81693:0] debug: received udp reply.
[1337210515] unbound[81693:0] debug: udp message[12:0] A8AA84030000000000000000
[1337210515] unbound[81693:0] debug: outnet handle udp reply
[1337210515] unbound[81693:0] debug: measured roundtrip at 197 msec
[1337210515] unbound[81693:0] debug: svcd callbacks start
[1337210515] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210515] unbound[81693:0] info: from server ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] info: for packet[12:0] A8AA84030000000000000000
[1337210515] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210515] unbound[81693:0] debug: mesh_run: start
[1337210515] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210515] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: process_response: new external response event
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210515] unbound[81693:0] debug: query response was timeout
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210515] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 5
[1337210515] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS
[1337210515] unbound[81693:0] info: b1.skype.net. * A
[1337210515] unbound[81693:0] info: b2.skype.net. * A
[1337210515] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=692
[1337210515] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=571
[1337210515] unbound[81693:0] debug: selrtt 571
[1337210515] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: sending to target: <sa.skype.net.> 195.46.253.244#53
[1337210515] unbound[81693:0] debug: dnssec status: not expected
[1337210515] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210515] unbound[81693:0] debug: qname perturbed to aUCkNQwGXaDsMeKTW21eg1Zfv2XgTaqS312xvAF54J0eX4oLy6uMoKV510ra.3AuGQANx502FtQBMv0auBNWxVRpD5bbbg3tYAXW2z2PSzp2XpXe3KbdCPJ35.yt3wSv3Cl4SOVyqbBAdhG4unKt2Kdj5vGxjjCzFeSyll00FFVAkfP5etPX21.en1K5C6OTSKOVMkTvh63SDkQtOPaG2XRGWT1E2XMmV4PfTFnbo0m.Sa.sKYpE.nEt.
[1337210515] unbound[81693:0] debug: serviced query UDP timeout=571 msec
[1337210515] unbound[81693:0] debug: inserted new pending reply id=3bdb
[1337210515] unbound[81693:0] debug: opened UDP if=0 port=58823
[1337210515] unbound[81693:0] debug: comm point start listening 9
[1337210515] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210515] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210515] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210515] unbound[81693:0] info: histogram of recursion processing times
[1337210515] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210515] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210515] unbound[81693:0] info: 0.016384 0.032768 2
[1337210515] unbound[81693:0] info: 0.524288 1.000000 2
[1337210515] unbound[81693:0] info: 1.000000 2.000000 1
[1337210515] unbound[81693:0] info: 2.000000 4.000000 1
[1337210515] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210515] unbound[81693:0] debug: svcd callbacks end
[1337210515] unbound[81693:0] debug: close of port 25931
[1337210515] unbound[81693:0] debug: close fd 8
[1337210515] unbound[81693:0] debug: answer cb
[1337210515] unbound[81693:0] debug: Incoming reply id = 3bdb
[1337210515] unbound[81693:0] debug: Incoming reply addr = ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: lookup size is 1 entries
[1337210515] unbound[81693:0] debug: received udp reply.
[1337210515] unbound[81693:0] debug: udp message[12:0] 3BDB84030000000000000000
[1337210515] unbound[81693:0] debug: outnet handle udp reply
[1337210515] unbound[81693:0] debug: measured roundtrip at 205 msec
[1337210515] unbound[81693:0] debug: svcd callbacks start
[1337210515] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210515] unbound[81693:0] info: from server ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] info: for packet[12:0] 3BDB84030000000000000000
[1337210515] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210515] unbound[81693:0] debug: mesh_run: start
[1337210515] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210515] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: process_response: new external response event
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210515] unbound[81693:0] debug: query response was timeout
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210515] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 6
[1337210515] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (2 result, 0 avail) cacheNS
[1337210515] unbound[81693:0] info: b1.skype.net. * A
[1337210515] unbound[81693:0] info: b2.skype.net. * A
[1337210515] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: servselect ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=539
[1337210515] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=692
[1337210515] unbound[81693:0] debug: selrtt 539
[1337210515] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: sending to target: <sa.skype.net.> 195.46.253.244#53
[1337210515] unbound[81693:0] debug: dnssec status: not expected
[1337210515] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210515] unbound[81693:0] debug: qname perturbed to auCkNqwgxadSMEKTW21eG1ZFV2xGtAQs312xvAF54J0ex4OLy6umokV510RA.3AugQaNX502FTqBMv0auBnwxvrPd5BbBG3TYaxW2z2pszP2XPxe3kbdCpj35.YT3wsV3cL4soVyQbbaDHg4unKT2KDj5vGxjJcZFEsYll00fFVAKFP5ETpx21.EN1k5C6oTSKovMKtVH63sDKqTOPAG2XRGwt1E2XmMV4PFtFnbO0m.sa.sKypE.NEt.
[1337210515] unbound[81693:0] debug: serviced query UDP timeout=539 msec
[1337210515] unbound[81693:0] debug: inserted new pending reply id=4dd8
[1337210515] unbound[81693:0] debug: opened UDP if=0 port=32731
[1337210515] unbound[81693:0] debug: comm point start listening 8
[1337210515] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210515] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210515] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210515] unbound[81693:0] info: histogram of recursion processing times
[1337210515] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210515] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210515] unbound[81693:0] info: 0.016384 0.032768 2
[1337210515] unbound[81693:0] info: 0.524288 1.000000 2
[1337210515] unbound[81693:0] info: 1.000000 2.000000 1
[1337210515] unbound[81693:0] info: 2.000000 4.000000 1
[1337210515] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210515] unbound[81693:0] debug: svcd callbacks end
[1337210515] unbound[81693:0] debug: close of port 58823
[1337210515] unbound[81693:0] debug: close fd 9
[1337210515] unbound[81693:0] debug: answer cb
[1337210515] unbound[81693:0] debug: Incoming reply id = 4dd8
[1337210515] unbound[81693:0] debug: Incoming reply addr = ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: lookup size is 1 entries
[1337210515] unbound[81693:0] debug: received udp reply.
[1337210515] unbound[81693:0] debug: udp message[12:0] 4DD884030000000000000000
[1337210515] unbound[81693:0] debug: outnet handle udp reply
[1337210515] unbound[81693:0] debug: measured roundtrip at 185 msec
[1337210515] unbound[81693:0] debug: svcd callbacks start
[1337210515] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210515] unbound[81693:0] info: from server ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] info: for packet[12:0] 4DD884030000000000000000
[1337210515] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210515] unbound[81693:0] debug: mesh_run: start
[1337210515] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210515] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: process_response: new external response event
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210515] unbound[81693:0] debug: query response was timeout
[1337210515] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210515] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 7
[1337210515] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (1 result, 0 avail) cacheNS
[1337210515] unbound[81693:0] info: b1.skype.net. * A
[1337210515] unbound[81693:0] info: b2.skype.net. * A
[1337210515] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210515] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210515] unbound[81693:0] debug: rtt=692
[1337210515] unbound[81693:0] debug: selrtt 692
[1337210515] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: sending to target: <sa.skype.net.> 212.187.172.27#53
[1337210515] unbound[81693:0] debug: dnssec status: not expected
[1337210515] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210515] unbound[81693:0] debug: qname perturbed to aUCKNQWgXADsmeKtw21eg1zfV2xGTAqS312xvAf54J0Ex4oly6umOkV510RA.3AuGqANx502FtqbmV0AUBnWxVRPd5bBBG3tyAXw2Z2pSZp2XPxe3kbDcPj35.Yt3wSV3CL4SOVYQbBADhg4uNkT2Kdj5VGXJJCzfEsylL00fFVAKfP5eTPX21.en1K5c6OtSKOVMkTvh63sdkQtOPaG2xrGWt1e2xMMv4PfTFnbo0m.Sa.sKyPe.NET.
[1337210515] unbound[81693:0] debug: serviced query UDP timeout=692 msec
[1337210515] unbound[81693:0] debug: inserted new pending reply id=2486
[1337210515] unbound[81693:0] debug: opened UDP if=0 port=65263
[1337210515] unbound[81693:0] debug: comm point start listening 9
[1337210515] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210515] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210515] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210515] unbound[81693:0] info: histogram of recursion processing times
[1337210515] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210515] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210515] unbound[81693:0] info: 0.016384 0.032768 2
[1337210515] unbound[81693:0] info: 0.524288 1.000000 2
[1337210515] unbound[81693:0] info: 1.000000 2.000000 1
[1337210515] unbound[81693:0] info: 2.000000 4.000000 1
[1337210515] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210515] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210515] unbound[81693:0] debug: svcd callbacks end
[1337210515] unbound[81693:0] debug: close of port 32731
[1337210515] unbound[81693:0] debug: close fd 8
[1337210516] unbound[81693:0] debug: answer cb
[1337210516] unbound[81693:0] debug: Incoming reply id = 2486
[1337210516] unbound[81693:0] debug: Incoming reply addr = ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: lookup size is 1 entries
[1337210516] unbound[81693:0] debug: received udp reply.
[1337210516] unbound[81693:0] debug: udp message[12:0] 248684030000000000000000
[1337210516] unbound[81693:0] debug: outnet handle udp reply
[1337210516] unbound[81693:0] debug: measured roundtrip at 189 msec
[1337210516] unbound[81693:0] debug: svcd callbacks start
[1337210516] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210516] unbound[81693:0] info: from server ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] info: for packet[12:0] 248684030000000000000000
[1337210516] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210516] unbound[81693:0] debug: mesh_run: start
[1337210516] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210516] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: process_response: new external response event
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210516] unbound[81693:0] debug: query response was timeout
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210516] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 8
[1337210516] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (1 result, 0 avail) cacheNS
[1337210516] unbound[81693:0] info: b1.skype.net. * A
[1337210516] unbound[81693:0] info: b2.skype.net. * A
[1337210516] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: rtt=614
[1337210516] unbound[81693:0] debug: selrtt 614
[1337210516] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: sending to target: <sa.skype.net.> 212.187.172.27#53
[1337210516] unbound[81693:0] debug: dnssec status: not expected
[1337210516] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210516] unbound[81693:0] debug: qname perturbed to AUCKnQWGxAdsMEktw21Eg1ZFV2XGTaQS312xVAf54J0ex4olY6umOKv510rA.3auGQaNX502ftqBmv0aUbnWXVrPD5BBBg3TyaXW2z2PszP2xpXe3KBdcPj35.YT3WSv3Cl4sOvYqBBADHG4unkt2KDJ5VGXJjcZFEsYlL00FFvaKfP5etpX21.eN1k5C6otsKovmKtvH63SDKQToPaG2XRGWT1E2xMmv4PftFNbO0m.sa.skypE.net.
[1337210516] unbound[81693:0] debug: serviced query UDP timeout=614 msec
[1337210516] unbound[81693:0] debug: inserted new pending reply id=1e57
[1337210516] unbound[81693:0] debug: opened UDP if=0 port=38349
[1337210516] unbound[81693:0] debug: comm point start listening 8
[1337210516] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210516] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210516] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210516] unbound[81693:0] info: histogram of recursion processing times
[1337210516] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210516] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210516] unbound[81693:0] info: 0.016384 0.032768 2
[1337210516] unbound[81693:0] info: 0.524288 1.000000 2
[1337210516] unbound[81693:0] info: 1.000000 2.000000 1
[1337210516] unbound[81693:0] info: 2.000000 4.000000 1
[1337210516] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210516] unbound[81693:0] debug: svcd callbacks end
[1337210516] unbound[81693:0] debug: close of port 65263
[1337210516] unbound[81693:0] debug: close fd 9
[1337210516] unbound[81693:0] debug: answer cb
[1337210516] unbound[81693:0] debug: Incoming reply id = 1e57
[1337210516] unbound[81693:0] debug: Incoming reply addr = ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: lookup size is 1 entries
[1337210516] unbound[81693:0] debug: received udp reply.
[1337210516] unbound[81693:0] debug: udp message[12:0] 1E5784030000000000000000
[1337210516] unbound[81693:0] debug: outnet handle udp reply
[1337210516] unbound[81693:0] debug: measured roundtrip at 197 msec
[1337210516] unbound[81693:0] debug: svcd callbacks start
[1337210516] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210516] unbound[81693:0] info: from server ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] info: for packet[12:0] 1E5784030000000000000000
[1337210516] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210516] unbound[81693:0] debug: mesh_run: start
[1337210516] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210516] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: process_response: new external response event
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210516] unbound[81693:0] debug: query response was timeout
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210516] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 9
[1337210516] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (1 result, 0 avail) cacheNS
[1337210516] unbound[81693:0] info: b1.skype.net. * A
[1337210516] unbound[81693:0] info: b2.skype.net. * A
[1337210516] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] debug: servselect ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: rtt=560
[1337210516] unbound[81693:0] debug: selrtt 560
[1337210516] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: sending to target: <sa.skype.net.> 212.187.172.27#53
[1337210516] unbound[81693:0] debug: dnssec status: not expected
[1337210516] unbound[81693:0] debug: EDNS lookup known=1 vs=0
[1337210516] unbound[81693:0] debug: qname perturbed to auCknqWgXaDSMektW21eg1zFV2xGtaqS312xVaF54J0eX4oly6umokv510Ra.3AugqAnx502fTqBMV0aUBnwXVRpd5BBBG3tyaXW2z2pszP2XPxE3kbdCPj35.yt3wSV3cl4SovYqBbadhG4unkt2kdj5VGxjJCZfESYLl00fFvAkfp5eTpx21.EN1K5c6OTSKoVmKTvH63sDkQtOPAg2XRgWT1e2XMMV4pFtFnBO0M.sA.SkYpE.NEt.
[1337210516] unbound[81693:0] debug: serviced query UDP timeout=560 msec
[1337210516] unbound[81693:0] debug: inserted new pending reply id=f96a
[1337210516] unbound[81693:0] debug: opened UDP if=0 port=61766
[1337210516] unbound[81693:0] debug: comm point start listening 9
[1337210516] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210516] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210516] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210516] unbound[81693:0] info: histogram of recursion processing times
[1337210516] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210516] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210516] unbound[81693:0] info: 0.016384 0.032768 2
[1337210516] unbound[81693:0] info: 0.524288 1.000000 2
[1337210516] unbound[81693:0] info: 1.000000 2.000000 1
[1337210516] unbound[81693:0] info: 2.000000 4.000000 1
[1337210516] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=26761 val=314677
[1337210516] unbound[81693:0] debug: svcd callbacks end
[1337210516] unbound[81693:0] debug: close of port 38349
[1337210516] unbound[81693:0] debug: close fd 8
[1337210516] unbound[81693:0] debug: answer cb
[1337210516] unbound[81693:0] debug: Incoming reply id = f96a
[1337210516] unbound[81693:0] debug: Incoming reply addr = ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: lookup size is 1 entries
[1337210516] unbound[81693:0] debug: received udp reply.
[1337210516] unbound[81693:0] debug: udp message[12:0] F96A84030000000000000000
[1337210516] unbound[81693:0] debug: outnet handle udp reply
[1337210516] unbound[81693:0] debug: measured roundtrip at 202 msec
[1337210516] unbound[81693:0] debug: svcd callbacks start
[1337210516] unbound[81693:0] info: no qname in reply to check 0x20ID
[1337210516] unbound[81693:0] info: from server ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] info: for packet[12:0] F96A84030000000000000000
[1337210516] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210516] unbound[81693:0] debug: mesh_run: start
[1337210516] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
[1337210516] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: process_response: new external response event
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210516] unbound[81693:0] debug: query response was timeout
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210516] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 10
[1337210516] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (0 result, 0 avail) cacheNS
[1337210516] unbound[81693:0] info: b1.skype.net. * A
[1337210516] unbound[81693:0] info: b2.skype.net. * A
[1337210516] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] debug: No more query targets, attempting last resort
[1337210516] unbound[81693:0] debug: try to grab parent NS
[1337210516] unbound[81693:0] debug: iter_handle processing q with state INIT REQUEST STATE
[1337210516] unbound[81693:0] info: resolving Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: request has dependency depth of 0
[1337210516] unbound[81693:0] debug: cache delegation returns delegpt
[1337210516] unbound[81693:0] info: DelegationPoint<skype.net.>: 4 names (4 missing), 4 addrs (0 result, 4 avail) cacheNS
[1337210516] unbound[81693:0] info: ns1.skype.net. A
[1337210516] unbound[81693:0] info: ns3.skype.net. A
[1337210516] unbound[81693:0] info: ns5.skype.net. A
[1337210516] unbound[81693:0] info: ns2.skype.net. A
[1337210516] unbound[81693:0] debug: ip4 193.95.154.49 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 212.187.172.228 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 80.252.85.81 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 212.8.163.98 port 53 (len 16)
[1337210516] unbound[81693:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2)
[1337210516] unbound[81693:0] info: resolving (init part 2): Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3)
[1337210516] unbound[81693:0] info: resolving (init part 3): Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210516] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
[1337210516] unbound[81693:0] info: DelegationPoint<skype.net.>: 4 names (0 missing), 4 addrs (0 result, 4 avail) cacheNS
[1337210516] unbound[81693:0] info: ns1.skype.net. * A
[1337210516] unbound[81693:0] info: ns3.skype.net. * A
[1337210516] unbound[81693:0] info: ns5.skype.net. * A
[1337210516] unbound[81693:0] info: ns2.skype.net. * A
[1337210516] unbound[81693:0] debug: ip4 193.95.154.49 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 212.187.172.228 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 80.252.85.81 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 212.8.163.98 port 53 (len 16)
[1337210516] unbound[81693:0] debug: attempt to get extra 3 targets
[1337210516] unbound[81693:0] debug: servselect ip4 212.8.163.98 port 53 (len 16)
[1337210516] unbound[81693:0] debug: rtt=473
[1337210516] unbound[81693:0] debug: servselect ip4 80.252.85.81 port 53 (len 16)
[1337210516] unbound[81693:0] debug: rtt=478
[1337210516] unbound[81693:0] debug: servselect ip4 212.187.172.228 port 53 (len 16)
[1337210516] unbound[81693:0] debug: rtt=468
[1337210516] unbound[81693:0] debug: selrtt 376
[1337210516] unbound[81693:0] info: sending query: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: sending to target: <skype.net.> 193.95.154.49#53
[1337210516] unbound[81693:0] debug: dnssec status: not expected
[1337210516] unbound[81693:0] debug: EDNS lookup known=0 vs=0
[1337210516] unbound[81693:0] debug: qname perturbed to aucKnqWgXADSmeKTw21eG1zfv2xgTaqs312XVAf54j0ex4oLY6UMoKV510ra.3auGQANx502fTQbmV0AuBNWxVrpD5bbbG3Tyaxw2Z2PsZp2xPXE3KbdcPj35.yT3wSV3Cl4SOvYqbBADhg4unKt2KDJ5vgxJJCzfEsYlL00FFvAKfP5ETpx21.En1K5C6OtSkovMKTVh63sDKqTOpaG2xrgwT1E2XMMv4PftfNbO0m.sa.SkypE.nET.
[1337210516] unbound[81693:0] debug: serviced query UDP timeout=376 msec
[1337210516] unbound[81693:0] debug: inserted new pending reply id=bbce
[1337210516] unbound[81693:0] debug: opened UDP if=0 port=5938
[1337210516] unbound[81693:0] debug: comm point start listening 8
[1337210516] unbound[81693:0] debug: mesh_run: iterator module exit state is module_wait_reply
[1337210516] unbound[81693:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 6 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210516] unbound[81693:0] info: average recursion processing time 0.992590 sec
[1337210516] unbound[81693:0] info: histogram of recursion processing times
[1337210516] unbound[81693:0] info: [25%]=0.028672 median[50%]=0.762144 [75%]=1.5
[1337210516] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210516] unbound[81693:0] info: 0.016384 0.032768 2
[1337210516] unbound[81693:0] info: 0.524288 1.000000 2
[1337210516] unbound[81693:0] info: 1.000000 2.000000 1
[1337210516] unbound[81693:0] info: 2.000000 4.000000 1
[1337210516] unbound[81693:0] info: 0RDd mod1 rep Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: cache memory msg=307733 rrset=383622 infra=27204 val=314677
[1337210516] unbound[81693:0] debug: svcd callbacks end
[1337210516] unbound[81693:0] debug: close of port 61766
[1337210516] unbound[81693:0] debug: close fd 9
[1337210516] unbound[81693:0] debug: answer cb
[1337210516] unbound[81693:0] debug: Incoming reply id = bbce
[1337210516] unbound[81693:0] debug: Incoming reply addr = ip4 193.95.154.49 port 53 (len 16)
[1337210516] unbound[81693:0] debug: lookup size is 1 entries
[1337210516] unbound[81693:0] debug: received udp reply.
[1337210516] unbound[81693:0] debug: udp message[343:0] BBCE801000010000000200033C6175634B6E715767584144536D654B547732316547317A6676327867546171733331325856416635346A306578346F4C5936554D6F4B5635313072613C3361754751414E78353032665451626D56304175424E5778567270443562626247335479617877325A3250735A703278505845334B626463506A33353C79543377535633436C34534F76597162424144686734756E4B74324B444A357667784A4A437A664573596C4C3030464676414B66503545547078323134456E314B3543364F74536B6F764D4B545668363373444B71544F706147327872677754314532584D4D7634506674664E624F306D02736105536B7970
[1337210516] unbound[81693:0] debug: udp message[343:256] 45036E45540000010001C0F800020001000546000005026232C0FBC0F800020001000546000005026231C0FBC1270001000100000E100004C32EFDF4C1160001000100000E100004D4BBAC1B0000291000000080000000
[1337210516] unbound[81693:0] debug: outnet handle udp reply
[1337210516] unbound[81693:0] debug: serviced query: EDNS works for ip4 193.95.154.49 port 53 (len 16)
[1337210516] unbound[81693:0] debug: measured roundtrip at 173 msec
[1337210516] unbound[81693:0] debug: svcd callbacks start
[1337210516] unbound[81693:0] debug: good 0x20-ID in reply qname
[1337210516] unbound[81693:0] debug: worker svcd callback for qstate 0x7fe99144c080
[1337210516] unbound[81693:0] debug: mesh_run: start
[1337210516] unbound[81693:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
[1337210516] unbound[81693:0] info: iterator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: process_response: new external response event
[1337210516] unbound[81693:0] info: scrub for skype.net. NS IN
[1337210516] unbound[81693:0] info: response for Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] info: reply from <skype.net.> 193.95.154.49#53
[1337210516] unbound[81693:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0
;; flags: qr ; QUERY: 1, ANSWER: 0, AUTHORITY: 2, ADDITIONAL: 2
;; QUESTION SECTION:
;; aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. IN A
;; ANSWER SECTION:
;; AUTHORITY SECTION:
sa.skype.net. 345600 IN NS b2.skype.net.
sa.skype.net. 345600 IN NS b1.skype.net.
;; ADDITIONAL SECTION:
b1.skype.net. 3600 IN A 195.46.253.244
b2.skype.net. 3600 IN A 212.187.172.27
;; Query time: 0 msec
;; WHEN: Wed Dec 31 16:00:00 1969
;; MSG SIZE rcvd: 332
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
[1337210516] unbound[81693:0] info: query response was REFERRAL
[1337210516] unbound[81693:0] info: store parent-side NS sa.skype.net. NS IN
[1337210516] unbound[81693:0] info: found in cache b1.skype.net. A IN
[1337210516] unbound[81693:0] info: found in cache b2.skype.net. A IN
[1337210516] unbound[81693:0] debug: copy attempt count previous dp ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: copy attempt count previous dp ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] debug: remove from usable list dp ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: remove from usable list dp ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (2 missing), 2 addrs (0 result, 0 avail) parentNS
[1337210516] unbound[81693:0] info: b1.skype.net. A
[1337210516] unbound[81693:0] info: b2.skype.net. A
[1337210516] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] debug: cleared outbound list for next round
[1337210516] unbound[81693:0] debug: iter_handle processing q with state QUERY TARGETS STATE
[1337210516] unbound[81693:0] info: processQueryTargets: Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
[1337210516] unbound[81693:0] info: DelegationPoint<sa.skype.net.>: 2 names (0 missing), 2 addrs (0 result, 0 avail) parentNS
[1337210516] unbound[81693:0] info: b1.skype.net. * A
[1337210516] unbound[81693:0] info: b2.skype.net. * A
[1337210516] unbound[81693:0] debug: ip4 212.187.172.27 port 53 (len 16)
[1337210516] unbound[81693:0] debug: ip4 195.46.253.244 port 53 (len 16)
[1337210516] unbound[81693:0] debug: attempt to get extra 3 targets
[1337210516] unbound[81693:0] debug: No more query targets, attempting last resort
[1337210516] unbound[81693:0] info: found in cache b1.skype.net. A IN
[1337210516] unbound[81693:0] info: found in cache b2.skype.net. A IN
[1337210516] unbound[81693:0] info: found parent-side b1.skype.net. A IN
[1337210516] unbound[81693:0] info: found parent-side b2.skype.net. A IN
[1337210516] unbound[81693:0] debug: out of query targets -- returning SERVFAIL
[1337210516] unbound[81693:0] debug: store error response in message cache
[1337210516] unbound[81693:0] debug: return error response SERVFAIL
[1337210516] unbound[81693:0] debug: mesh_run: iterator module exit state is module_finished
[1337210516] unbound[81693:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
[1337210516] unbound[81693:0] info: validator operate: query Aucknqwgxadsmektw21eg1zfv2xgtaqs312xvaf54j0ex4oly6umokv510ra.3augqanx502ftqbmv0aubnwxvrpd5bbbg3tyaxw2z2pszp2xpxe3kbdcpj35.yt3wsv3cl4sovyqbbadhg4unkt2kdj5vgxjjczfesyll00ffvakfp5etpx21.en1k5c6otskovmktvh63sdkqtopag2xrgwt1e2xmmv4pftfnbo0m.sa.skype.net. A IN
[1337210516] unbound[81693:0] debug: validator: nextmodule returned
[1337210516] unbound[81693:0] debug: cannot validate non-answer, rcode SERVFAIL
[1337210516] unbound[81693:0] debug: mesh_run: validator module exit state is module_finished
[1337210516] unbound[81693:0] debug: query took 2.254083 sec
[1337210516] unbound[81693:0] info: mesh_run: end 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 7 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210516] unbound[81693:0] info: average recursion processing time 1.172803 sec
[1337210516] unbound[81693:0] info: histogram of recursion processing times
[1337210516] unbound[81693:0] info: [25%]=0.03072 median[50%]=0.881072 [75%]=2.25
[1337210516] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210516] unbound[81693:0] info: 0.016384 0.032768 2
[1337210516] unbound[81693:0] info: 0.524288 1.000000 2
[1337210516] unbound[81693:0] info: 1.000000 2.000000 1
[1337210516] unbound[81693:0] info: 2.000000 4.000000 2
[1337210516] unbound[81693:0] debug: cache memory msg=307733 rrset=384044 infra=27204 val=314677
[1337210516] unbound[81693:0] debug: svcd callbacks end
[1337210516] unbound[81693:0] debug: close of port 5938
[1337210516] unbound[81693:0] debug: close fd 8
[1337210516] unbound[81693:0] debug: caught signal SIGINT
[1337210518] unbound[81693:0] info: service stopped (unbound 1.4.16).
[1337210518] unbound[81693:0] debug: stop threads
[1337210518] unbound[81693:0] debug: cleanup.
[1337210518] unbound[81693:0] info: server stats for thread 0: 8 queries, 1 answers from cache, 7 recursions, 0 prefetch
[1337210518] unbound[81693:0] info: server stats for thread 0: requestlist max 1 avg 0.428571 exceeded 0 jostled 0
[1337210518] unbound[81693:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 7 recursion replies sent, 0 replies dropped, 0 states jostled out
[1337210518] unbound[81693:0] info: average recursion processing time 1.172803 sec
[1337210518] unbound[81693:0] info: histogram of recursion processing times
[1337210518] unbound[81693:0] info: [25%]=0.03072 median[50%]=0.881072 [75%]=2.25
[1337210518] unbound[81693:0] info: lower(secs) upper(secs) recursions
[1337210518] unbound[81693:0] info: 0.016384 0.032768 2
[1337210518] unbound[81693:0] info: 0.524288 1.000000 2
[1337210518] unbound[81693:0] info: 1.000000 2.000000 1
[1337210518] unbound[81693:0] info: 2.000000 4.000000 2
[1337210518] unbound[81693:0] debug: cache memory msg=295672 rrset=295672 infra=27204 val=314677
[1337210518] unbound[81693:0] debug: Exit cleanup.
[1337210518] unbound[81693:0] debug: switching log to stderr
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment