Trying to find out why my unbound will not resolve www.startpuntgeldzaken.nl (1.13.1)
Gerben Wierda
gerben.wierda at rna.nl
Wed May 5 18:50:51 UTC 2021
I have tested additionally on a separate test machine with unbound 1.13.1 with logging set to 4
Same problem. I also noticed there are TCP errors, but I have do-tcp set to no.
May 05 18:30:39 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:30:39 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:30:39 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30425 val=296000
May 05 18:30:39 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:30:39 unbound-default[1267:0] debug: close of port 29533
May 05 18:30:39 unbound-default[1267:0] debug: close fd 10
May 05 18:30:47 unbound-default[1267:0] info: 192.168.2.32 trace.svc.ui.com <http://trace.svc.ui.com/>. A IN
May 05 18:30:47 unbound-default[1267:0] debug: using localzone . transparent
May 05 18:30:47 unbound-default[1267:0] info: 192.168.2.32 trace.svc.ui.com <http://trace.svc.ui.com/>. AAAA IN
May 05 18:30:47 unbound-default[1267:0] debug: using localzone . transparent
May 05 18:31:20 unbound-default[1267:0] info: 192.168.2.86 www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:20 unbound-default[1267:0] debug: using localzone . transparent
May 05 18:31:20 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:20 unbound-default[1267:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
May 05 18:31:20 unbound-default[1267:0] info: validator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:20 unbound-default[1267:0] debug: validator: pass to next module
May 05 18:31:20 unbound-default[1267:0] debug: mesh_run: validator module exit state is module_wait_module
May 05 18:31:20 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
May 05 18:31:20 unbound-default[1267:0] debug: process_request: new external request event
May 05 18:31:20 unbound-default[1267:0] debug: iter_handle processing q with state INIT REQUEST STATE
May 05 18:31:20 unbound-default[1267:0] info: resolving www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:20 unbound-default[1267:0] debug: request has dependency depth of 0
May 05 18:31:20 unbound-default[1267:0] debug: forwarding request
May 05 18:31:20 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:20 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:20 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
May 05 18:31:20 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (0 result, 2 avail) parentNS
May 05 18:31:20 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:20 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:20 unbound-default[1267:0] debug: attempt to get extra 3 targets
May 05 18:31:20 unbound-default[1267:0] debug: servselect ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:20 unbound-default[1267:0] debug: rtt=266
May 05 18:31:20 unbound-default[1267:0] debug: selrtt 266
May 05 18:31:20 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:20 unbound-default[1267:0] debug: sending to target: <.> 9.9.9.9#53
May 05 18:31:20 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:20 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:20 unbound-default[1267:0] debug: serviced query UDP timeout=266 msec
May 05 18:31:20 unbound-default[1267:0] debug: inserted new pending reply id=f9a4
May 05 18:31:20 unbound-default[1267:0] debug: opened UDP if=0 port=8345
May 05 18:31:20 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:20 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:20 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:20 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:20 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:20 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:20 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:20 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:20 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:20 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:20 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30425 val=296000
May 05 18:31:20 unbound-default[1267:0] debug: answer cb
May 05 18:31:20 unbound-default[1267:0] debug: Incoming reply id = f9a4
May 05 18:31:20 unbound-default[1267:0] debug: Incoming reply addr = ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:20 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:20 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:20 unbound-default[1267:0] debug: udp message[974:0] F9A483A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C000500010000003C0018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003C00A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:20 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E00010000003C0129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:20 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C037000500010000012C000E0B74702D6E696275642D3031C03DC037002E00010000012C00A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:20 unbound-default[1267:0] debug: udp message[974:768] 73730CC245000100010000012C0004541663B0C245002E00010000012C00A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:20 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:20 unbound-default[1267:0] debug: measured roundtrip at 45 msec
May 05 18:31:20 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:20 unbound-default[1267:0] debug: close of port 8345
May 05 18:31:20 unbound-default[1267:0] debug: close fd 9
May 05 18:31:23 unbound-default[1267:0] debug: tcp error for address ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:23 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:23 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:23 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:23 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:23 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:23 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:23 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:23 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:23 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:23 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:23 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1
May 05 18:31:23 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (2 result, 0 avail) parentNS
May 05 18:31:23 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:23 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:23 unbound-default[1267:0] debug: attempt to get extra 3 targets
May 05 18:31:23 unbound-default[1267:0] debug: servselect ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:23 unbound-default[1267:0] debug: rtt=246
May 05 18:31:23 unbound-default[1267:0] debug: selrtt 246
May 05 18:31:23 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:23 unbound-default[1267:0] debug: sending to target: <.> 9.9.9.9#53
May 05 18:31:23 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:23 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:23 unbound-default[1267:0] debug: serviced query UDP timeout=246 msec
May 05 18:31:23 unbound-default[1267:0] debug: inserted new pending reply id=7830
May 05 18:31:23 unbound-default[1267:0] debug: opened UDP if=0 port=25891
May 05 18:31:23 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:23 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:23 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:23 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:23 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:23 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:23 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:23 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:23 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:23 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:23 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30425 val=296000
May 05 18:31:23 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:23 unbound-default[1267:0] debug: answer cb
May 05 18:31:23 unbound-default[1267:0] debug: Incoming reply id = 7830
May 05 18:31:23 unbound-default[1267:0] debug: Incoming reply addr = ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:23 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:23 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:23 unbound-default[1267:0] debug: udp message[974:0] 783083A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C00050001000000390018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003900A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:23 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E0001000000390129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:23 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C0370005000100000129000E0B74702D6E696275642D3031C03DC037002E00010000012900A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:23 unbound-default[1267:0] debug: udp message[974:768] 73730CC24500010001000001290004541663B0C245002E00010000012900A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:23 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:23 unbound-default[1267:0] debug: measured roundtrip at 25 msec
May 05 18:31:23 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:23 unbound-default[1267:0] debug: close of port 25891
May 05 18:31:23 unbound-default[1267:0] debug: close fd 9
May 05 18:31:25 unbound-default[1267:0] info: 192.168.2.86 www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:25 unbound-default[1267:0] debug: using localzone . transparent
May 05 18:31:25 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30425 val=296000
May 05 18:31:26 unbound-default[1267:0] debug: tcp error for address ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:26 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:26 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:26 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:26 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:26 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:26 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:26 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:26 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:26 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:26 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:26 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 2
May 05 18:31:26 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (2 result, 0 avail) parentNS
May 05 18:31:26 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:26 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:26 unbound-default[1267:0] debug: attempt to get extra 3 targets
May 05 18:31:26 unbound-default[1267:0] debug: servselect ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:26 unbound-default[1267:0] debug: rtt=203
May 05 18:31:26 unbound-default[1267:0] debug: selrtt 203
May 05 18:31:26 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:26 unbound-default[1267:0] debug: sending to target: <.> 9.9.9.9#53
May 05 18:31:26 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:26 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:26 unbound-default[1267:0] debug: serviced query UDP timeout=203 msec
May 05 18:31:26 unbound-default[1267:0] debug: inserted new pending reply id=fe52
May 05 18:31:26 unbound-default[1267:0] debug: opened UDP if=0 port=46237
May 05 18:31:26 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:26 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:26 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 2 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:26 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:26 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:26 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:26 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:26 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:26 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:26 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:26 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30425 val=296000
May 05 18:31:26 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:26 unbound-default[1267:0] debug: answer cb
May 05 18:31:26 unbound-default[1267:0] debug: Incoming reply id = fe52
May 05 18:31:26 unbound-default[1267:0] debug: Incoming reply addr = ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:26 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:26 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:26 unbound-default[1267:0] debug: udp message[974:0] FE5283A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C00050001000000360018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003600A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:26 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E0001000000360129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:26 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C0370005000100000126000E0B74702D6E696275642D3031C03DC037002E00010000012600A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:26 unbound-default[1267:0] debug: udp message[974:768] 73730CC24500010001000001260004541663B0C245002E00010000012600A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:26 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:26 unbound-default[1267:0] debug: measured roundtrip at 21 msec
May 05 18:31:26 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:26 unbound-default[1267:0] debug: close of port 46237
May 05 18:31:26 unbound-default[1267:0] debug: close fd 9
May 05 18:31:29 unbound-default[1267:0] debug: tcp error for address ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:29 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:29 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:29 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:29 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:29 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:29 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:29 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:29 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:29 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:29 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:29 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 3
May 05 18:31:29 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (2 result, 0 avail) parentNS
May 05 18:31:29 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:29 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:29 unbound-default[1267:0] debug: servselect ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:29 unbound-default[1267:0] debug: rtt=168
May 05 18:31:29 unbound-default[1267:0] debug: selrtt 168
May 05 18:31:29 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:29 unbound-default[1267:0] debug: sending to target: <.> 149.112.112.112#53
May 05 18:31:29 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:29 unbound-default[1267:0] debug: EDNS lookup known=0 vs=0
May 05 18:31:29 unbound-default[1267:0] debug: serviced query UDP timeout=376 msec
May 05 18:31:29 unbound-default[1267:0] debug: inserted new pending reply id=cb35
May 05 18:31:29 unbound-default[1267:0] debug: opened UDP if=0 port=8076
May 05 18:31:29 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:29 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:29 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 2 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:29 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:29 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:29 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:29 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:29 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:29 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:29 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:29 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:29 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:29 unbound-default[1267:0] debug: answer cb
May 05 18:31:29 unbound-default[1267:0] debug: Incoming reply id = cb35
May 05 18:31:29 unbound-default[1267:0] debug: Incoming reply addr = ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:29 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:29 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:29 unbound-default[1267:0] debug: udp message[974:0] CB3583A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C000500010000003C0018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003C00A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:29 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E00010000003C0129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:29 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C037000500010000012C000E0B74702D6E696275642D3031C03DC037002E00010000012C00A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:29 unbound-default[1267:0] debug: udp message[974:768] 73730CC245000100010000012C0004541663B0C245002E00010000012C00A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:29 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:29 unbound-default[1267:0] debug: serviced query: EDNS works for ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:29 unbound-default[1267:0] debug: measured roundtrip at 48 msec
May 05 18:31:29 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:29 unbound-default[1267:0] debug: close of port 8076
May 05 18:31:29 unbound-default[1267:0] debug: close fd 9
May 05 18:31:30 unbound-default[1267:0] info: 192.168.2.86 www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:30 unbound-default[1267:0] debug: using localzone . transparent
May 05 18:31:30 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:32 unbound-default[1267:0] debug: tcp error for address ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:32 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:32 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:32 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:32 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:32 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:32 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:32 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:32 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:32 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:32 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:32 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 4
May 05 18:31:32 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (2 result, 0 avail) parentNS
May 05 18:31:32 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:32 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:32 unbound-default[1267:0] debug: servselect ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:32 unbound-default[1267:0] debug: rtt=168
May 05 18:31:32 unbound-default[1267:0] debug: servselect ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:32 unbound-default[1267:0] debug: rtt=338
May 05 18:31:32 unbound-default[1267:0] debug: selrtt 168
May 05 18:31:32 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:32 unbound-default[1267:0] debug: sending to target: <.> 9.9.9.9#53
May 05 18:31:32 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:32 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:32 unbound-default[1267:0] debug: serviced query UDP timeout=168 msec
May 05 18:31:32 unbound-default[1267:0] debug: inserted new pending reply id=979d
May 05 18:31:32 unbound-default[1267:0] debug: opened UDP if=0 port=18861
May 05 18:31:32 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:32 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:32 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 3 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:32 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:32 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:32 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:32 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:32 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:32 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:32 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:32 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:32 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:32 unbound-default[1267:0] debug: answer cb
May 05 18:31:32 unbound-default[1267:0] debug: Incoming reply id = 979d
May 05 18:31:32 unbound-default[1267:0] debug: Incoming reply addr = ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:32 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:32 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:32 unbound-default[1267:0] debug: udp message[54:0] 979D83A000010000000000010377777712737461727470756E7467656C647A616B656E026E6C00000100010000290200000080000000
May 05 18:31:32 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:32 unbound-default[1267:0] debug: measured roundtrip at 61 msec
May 05 18:31:32 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:32 unbound-default[1267:0] debug: close of port 18861
May 05 18:31:32 unbound-default[1267:0] debug: close fd 9
May 05 18:31:35 unbound-default[1267:0] debug: tcp error for address ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:35 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:35 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:35 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:35 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:35 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:35 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:35 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:35 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:35 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:35 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:35 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 5
May 05 18:31:35 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (2 result, 0 avail) parentNS
May 05 18:31:35 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:35 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:35 unbound-default[1267:0] debug: servselect ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:35 unbound-default[1267:0] debug: rtt=338
May 05 18:31:35 unbound-default[1267:0] debug: servselect ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:35 unbound-default[1267:0] debug: rtt=182
May 05 18:31:35 unbound-default[1267:0] debug: selrtt 182
May 05 18:31:35 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:35 unbound-default[1267:0] debug: sending to target: <.> 9.9.9.9#53
May 05 18:31:35 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:35 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:35 unbound-default[1267:0] debug: serviced query UDP timeout=182 msec
May 05 18:31:35 unbound-default[1267:0] debug: inserted new pending reply id=2840
May 05 18:31:35 unbound-default[1267:0] debug: opened UDP if=0 port=20260
May 05 18:31:35 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:35 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:35 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 3 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:35 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:35 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:35 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:35 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:35 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:35 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:35 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:35 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:35 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:35 unbound-default[1267:0] debug: answer cb
May 05 18:31:35 unbound-default[1267:0] debug: Incoming reply id = 2840
May 05 18:31:35 unbound-default[1267:0] debug: Incoming reply addr = ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:35 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:35 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:35 unbound-default[1267:0] debug: udp message[974:0] 284083A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C00050001000000360018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003600A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:35 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E0001000000360129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:35 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C0370005000100000126000E0B74702D6E696275642D3031C03DC037002E00010000012600A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:35 unbound-default[1267:0] debug: udp message[974:768] 73730CC24500010001000001260004541663B0C245002E00010000012600A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:35 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:35 unbound-default[1267:0] debug: measured roundtrip at 24 msec
May 05 18:31:35 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:35 unbound-default[1267:0] debug: close of port 20260
May 05 18:31:35 unbound-default[1267:0] debug: close fd 9
May 05 18:31:39 unbound-default[1267:0] debug: tcp error for address ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:39 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:39 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:39 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:39 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:39 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:39 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:39 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:39 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:39 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:39 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:39 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 6
May 05 18:31:39 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (1 result, 0 avail) parentNS
May 05 18:31:39 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:39 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:39 unbound-default[1267:0] debug: servselect ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:39 unbound-default[1267:0] debug: rtt=338
May 05 18:31:39 unbound-default[1267:0] debug: selrtt 338
May 05 18:31:39 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:39 unbound-default[1267:0] debug: sending to target: <.> 149.112.112.112#53
May 05 18:31:39 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:39 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:39 unbound-default[1267:0] debug: serviced query UDP timeout=338 msec
May 05 18:31:39 unbound-default[1267:0] debug: inserted new pending reply id=a451
May 05 18:31:39 unbound-default[1267:0] debug: opened UDP if=0 port=18897
May 05 18:31:39 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:39 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:39 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 3 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:39 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:39 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:39 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:39 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:39 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:39 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:39 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:39 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:39 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:39 unbound-default[1267:0] debug: answer cb
May 05 18:31:39 unbound-default[1267:0] debug: Incoming reply id = a451
May 05 18:31:39 unbound-default[1267:0] debug: Incoming reply addr = ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:39 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:39 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:39 unbound-default[1267:0] debug: udp message[974:0] A45183A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C000500010000003C0018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003C00A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:39 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E00010000003C0129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:39 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C037000500010000012C000E0B74702D6E696275642D3031C03DC037002E00010000012C00A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:39 unbound-default[1267:0] debug: udp message[974:768] 73730CC245000100010000012C0004541663B0C245002E00010000012C00A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:39 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:39 unbound-default[1267:0] debug: measured roundtrip at 50 msec
May 05 18:31:39 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:39 unbound-default[1267:0] debug: close of port 18897
May 05 18:31:39 unbound-default[1267:0] debug: close fd 9
May 05 18:31:42 unbound-default[1267:0] debug: tcp error for address ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:42 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:42 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:42 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:42 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:42 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:42 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:42 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:42 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:42 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:42 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:42 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 7
May 05 18:31:42 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (1 result, 0 avail) parentNS
May 05 18:31:42 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:42 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:42 unbound-default[1267:0] debug: servselect ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:42 unbound-default[1267:0] debug: rtt=307
May 05 18:31:42 unbound-default[1267:0] debug: selrtt 307
May 05 18:31:42 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:42 unbound-default[1267:0] debug: sending to target: <.> 149.112.112.112#53
May 05 18:31:42 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:42 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:42 unbound-default[1267:0] debug: serviced query UDP timeout=307 msec
May 05 18:31:42 unbound-default[1267:0] debug: inserted new pending reply id=25ad
May 05 18:31:42 unbound-default[1267:0] debug: opened UDP if=0 port=39592
May 05 18:31:42 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:42 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:42 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 3 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:42 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:42 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:42 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:42 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:42 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:42 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:42 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:42 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:42 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:42 unbound-default[1267:0] debug: answer cb
May 05 18:31:42 unbound-default[1267:0] debug: Incoming reply id = 25ad
May 05 18:31:42 unbound-default[1267:0] debug: Incoming reply addr = ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:42 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:42 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:42 unbound-default[1267:0] debug: udp message[974:0] 25AD83A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C000500010000003C0018056E696275640A7472756570656F706C6505737061636500C00C002E00010000003C00A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:42 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E00010000003C0129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:42 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C037000500010000012C000E0B74702D6E696275642D3031C03DC037002E00010000012C00A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:42 unbound-default[1267:0] debug: udp message[974:768] 73730CC245000100010000012C0004541663B0C245002E00010000012C00A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:42 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:42 unbound-default[1267:0] debug: measured roundtrip at 45 msec
May 05 18:31:42 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:42 unbound-default[1267:0] debug: close of port 39592
May 05 18:31:42 unbound-default[1267:0] debug: close fd 9
May 05 18:31:45 unbound-default[1267:0] debug: tcp error for address ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:45 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:45 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:45 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:45 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:45 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:45 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:45 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:45 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:45 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:45 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:45 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 8
May 05 18:31:45 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (1 result, 0 avail) parentNS
May 05 18:31:45 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:45 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:45 unbound-default[1267:0] debug: servselect ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:45 unbound-default[1267:0] debug: rtt=271
May 05 18:31:45 unbound-default[1267:0] debug: selrtt 271
May 05 18:31:45 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:45 unbound-default[1267:0] debug: sending to target: <.> 149.112.112.112#53
May 05 18:31:45 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:45 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:45 unbound-default[1267:0] debug: serviced query UDP timeout=271 msec
May 05 18:31:45 unbound-default[1267:0] debug: inserted new pending reply id=f7a1
May 05 18:31:45 unbound-default[1267:0] debug: opened UDP if=0 port=31844
May 05 18:31:45 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:45 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:45 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 3 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:45 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:45 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:45 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:45 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:45 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:45 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:45 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:45 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:45 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:45 unbound-default[1267:0] debug: answer cb
May 05 18:31:45 unbound-default[1267:0] debug: Incoming reply id = f7a1
May 05 18:31:45 unbound-default[1267:0] debug: Incoming reply addr = ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:45 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:45 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:45 unbound-default[1267:0] debug: udp message[974:0] F7A183A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C000500010000002C0018056E696275640A7472756570656F706C6505737061636500C00C002E00010000002C00A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:45 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E00010000002C0129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:45 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C037000500010000011C000E0B74702D6E696275642D3031C03DC037002E00010000011C00A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:45 unbound-default[1267:0] debug: udp message[974:768] 73730CC245000100010000011C0004541663B0C245002E00010000011C00A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:45 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:45 unbound-default[1267:0] debug: measured roundtrip at 22 msec
May 05 18:31:45 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:45 unbound-default[1267:0] debug: close of port 31844
May 05 18:31:45 unbound-default[1267:0] debug: close fd 9
May 05 18:31:48 unbound-default[1267:0] debug: tcp error for address ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:48 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:48 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:48 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:48 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:48 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:48 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:48 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:48 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:48 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:48 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:48 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 9
May 05 18:31:48 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (1 result, 0 avail) parentNS
May 05 18:31:48 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:48 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:48 unbound-default[1267:0] debug: servselect ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:48 unbound-default[1267:0] debug: rtt=215
May 05 18:31:48 unbound-default[1267:0] debug: selrtt 215
May 05 18:31:48 unbound-default[1267:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:48 unbound-default[1267:0] debug: sending to target: <.> 149.112.112.112#53
May 05 18:31:48 unbound-default[1267:0] debug: dnssec status: not expected
May 05 18:31:48 unbound-default[1267:0] debug: EDNS lookup known=1 vs=0
May 05 18:31:48 unbound-default[1267:0] debug: serviced query UDP timeout=215 msec
May 05 18:31:48 unbound-default[1267:0] debug: inserted new pending reply id=a750
May 05 18:31:48 unbound-default[1267:0] debug: opened UDP if=0 port=51837
May 05 18:31:48 unbound-default[1267:0] debug: comm point start listening 9 (-1 msec)
May 05 18:31:48 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_wait_reply
May 05 18:31:48 unbound-default[1267:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 3 waiting replies, 2 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:48 unbound-default[1267:0] info: average recursion processing time 0.029573 sec
May 05 18:31:48 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:48 unbound-default[1267:0] info: [25%]=0 median[50%]=0 [75%]=0
May 05 18:31:48 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:48 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:48 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:48 unbound-default[1267:0] info: 0RDd mod1 rep www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:48 unbound-default[1267:0] debug: cache memory msg=296476 rrset=296684 infra=30866 val=296000
May 05 18:31:48 unbound-default[1267:0] debug: svcd callbacks end
May 05 18:31:48 unbound-default[1267:0] debug: answer cb
May 05 18:31:48 unbound-default[1267:0] debug: Incoming reply id = a750
May 05 18:31:48 unbound-default[1267:0] debug: Incoming reply addr = ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:48 unbound-default[1267:0] debug: lookup size is 1 entries
May 05 18:31:48 unbound-default[1267:0] debug: received udp reply.
May 05 18:31:48 unbound-default[1267:0] debug: udp message[974:0] A75083A000010007000000010377777712737461727470756E7467656C647A616B656E026E6C0000010001C00C00050001000000200018056E696275640A7472756570656F706C6505737061636500C00C002E00010000002000A9000507020000003C609C6C006080BC80706412737461727470756E7467656C647A616B656E026E6C009220FA364B3D0C7EB0B2DF564EB8F5F9B32334C39EC2CB54EE9EADA58600CEA226EF0AE897B51597065DFA773C8A07346996C91A38A903CF7D5E7D94323E2C4EBEBB66A8E1F6198B38F3373C90CF30D252814B4ABBC6D5603E1518DF9C90F88F293236EDC189EC6BB82B01AF2A95B855D95C2BE84643508F49E46697
May 05 18:31:48 unbound-default[1267:0] debug: udp message[974:256] 77C0A8BDC00C002E0001000000200129000508020000003C609C6C006080BC80F14B12737461727470756E7467656C647A616B656E026E6C0064C40C418346200F0BFFC403EF28F9FC5EE97F0EB3870437410B8D6B4AB1E1EE54AF73DF47751E5CF0F9B0040A095F44AC0B5CCD59F3D988D638C2563E52AC4EC60A50CC65991DF41986536A0FCF3DEBDE725B6061ED70CDEF6CEA694F94889291088FBA600CFBC3DC77DF5E24E0101494DF409DCA35BC05BB17328F2DDDB6363A4CE4F5AB2E1F653C0DFD9092B59EB5489C73691897B7D05B16C124A8F6940662FC66034C012D63ADDA484081CBC1EC27602D2988BF4B8C17628046F3680D4309652132014736
May 05 18:31:48 unbound-default[1267:0] debug: udp message[974:512] 16C01EA7AACA89D31A257172944E98B382416BA3C17C4B1DE3EFA28CCAF71610E4D7A1F98DF9DE1F828097D54BA133EDF748B63B85B198E331C0370005000100000110000E0B74702D6E696275642D3031C03DC037002E00010000011000A4000507030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500A19B70EDA2BFD27C98CD4239E6386617570CDF6CF1EC15E7053F97420A8B0973C22E25025315B504D482ABAFAFA90E6127C680A33AB3ECAD6AE8B51608E7E1B3FDE7BE4D24E6411C21EE99B41C9884FA4AEA88F874D0B18CDF3F77CFF96D50C27000FF23CF905D6CF23A9538F9C0269C6A8635100D0F93120CB8A7BFF2
May 05 18:31:48 unbound-default[1267:0] debug: udp message[974:768] 73730CC24500010001000001100004541663B0C245002E00010000011000A4000107030000012C609C6C006080BC809CA10A7472756570656F706C6505737061636500539B832E5A40E412A9B67DC7CB3E3353210CB5A453B63E22FFAC9AAF4648F90079275886E92BAE6989435140E18FC822F37287654A6C4651DAF720B096C3254E66DC282506001EFC0E7351AE5B3F1F9612FB0ACD564D181641C8162E1A25F66EC686069F670677F90A9F34AC2879CF6D7B800D16B8A9AA245AE9BA9A2BD8526E00002904D0000080000000
May 05 18:31:48 unbound-default[1267:0] debug: outnet handle udp reply
May 05 18:31:48 unbound-default[1267:0] debug: measured roundtrip at 24 msec
May 05 18:31:48 unbound-default[1267:0] debug: initiate TCP query EDNS
May 05 18:31:48 unbound-default[1267:0] debug: close of port 51837
May 05 18:31:48 unbound-default[1267:0] debug: close fd 9
May 05 18:31:51 unbound-default[1267:0] debug: tcp error for address ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:51 unbound-default[1267:0] debug: svcd callbacks start
May 05 18:31:51 unbound-default[1267:0] debug: worker svcd callback for qstate 0x7fde48992690
May 05 18:31:51 unbound-default[1267:0] debug: mesh_run: start
May 05 18:31:51 unbound-default[1267:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
May 05 18:31:51 unbound-default[1267:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:51 unbound-default[1267:0] debug: process_response: new external response event
May 05 18:31:51 unbound-default[1267:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
May 05 18:31:51 unbound-default[1267:0] debug: query response was timeout
May 05 18:31:51 unbound-default[1267:0] debug: iter_handle processing q with state QUERY TARGETS STATE
May 05 18:31:51 unbound-default[1267:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:51 unbound-default[1267:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 10
May 05 18:31:51 unbound-default[1267:0] info: DelegationPoint<.>: 0 names (0 missing), 2 addrs (0 result, 0 avail) parentNS
May 05 18:31:51 unbound-default[1267:0] debug: ip4 149.112.112.112 port 53 (len 16)
May 05 18:31:51 unbound-default[1267:0] debug: ip4 9.9.9.9 port 53 (len 16)
May 05 18:31:51 unbound-default[1267:0] debug: No more query targets, attempting last resort
May 05 18:31:51 unbound-default[1267:0] debug: configured stub or forward servers failed -- returning SERVFAIL
May 05 18:31:51 unbound-default[1267:0] debug: store error response in message cache
May 05 18:31:51 unbound-default[1267:0] debug: return error response SERVFAIL
May 05 18:31:51 unbound-default[1267:0] debug: mesh_run: iterator module exit state is module_finished
May 05 18:31:51 unbound-default[1267:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
May 05 18:31:51 unbound-default[1267:0] info: validator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
May 05 18:31:51 unbound-default[1267:0] debug: validator: nextmodule returned
May 05 18:31:51 unbound-default[1267:0] debug: cannot validate non-answer, rcode SERVFAIL
May 05 18:31:51 unbound-default[1267:0] debug: mesh_run: validator module exit state is module_finished
May 05 18:31:51 unbound-default[1267:0] debug: query took 20.839862 sec
May 05 18:31:51 unbound-default[1267:0] debug: query took 25.845004 sec
May 05 18:31:51 unbound-default[1267:0] debug: query took 30.844610 sec
May 05 18:31:51 unbound-default[1267:0] info: mesh_run: end 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 5 recursion replies sent, 0 replies dropped, 0 states jostled out
May 05 18:31:51 unbound-default[1267:0] info: average recursion processing time 15.517724 sec
May 05 18:31:51 unbound-default[1267:0] info: histogram of recursion processing times
May 05 18:31:51 unbound-default[1267:0] info: [25%]=0.04096 median[50%]=18.6667 [75%]=25.3333
May 05 18:31:51 unbound-default[1267:0] info: lower(secs) upper(secs) recursions
May 05 18:31:51 unbound-default[1267:0] info: 0.016384 0.032768 1
May 05 18:31:51 unbound-default[1267:0] info: 0.032768 0.065536 1
May 05 18:31:51 unbound-default[1267:0] info: 16.000000 32.000000 3
May 05 18:31:51 unbound-default[1267:0] debug: cache memory msg=296863 rrset=296684 infra=30866 val=296000
May 05 18:31:51 unbound-default[1267:0] debug: svcd callbacks end
Gerben Wierda (LinkedIn <https://www.linkedin.com/in/gerbenwierda>)
R&A Enterprise Architecture <https://ea.rna.nl/> (main site)
Book: Chess and the Art of Enterprise Architecture <https://ea.rna.nl/the-book/>
Book: Mastering ArchiMate <https://ea.rna.nl/the-book-edition-iii/>
> On 5 May 2021, at 19:03, Gerben Wierda via Unbound-users <unbound-users at lists.nlnetlabs.nl <mailto:unbound-users at lists.nlnetlabs.nl>> wrote:
>
> My unbound 1.12.0 will not resolve www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/> but it does resolve most other domains without a problem
>
> May 05 16:54:50 unbound-default[40069:0] info: resolving www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:50 unbound-default[40069:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:50 unbound-default[40069:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:50 unbound-default[40069:0] debug: sending to target: <.> 9.9.9.9#53
> May 05 16:54:50 unbound-default[40069:0] debug: cache memory msg=297432 rrset=298522 infra=30866 val=296000
> May 05 16:54:53 unbound-default[40069:0] debug: tcp error for address 9.9.9.9 port 53
> May 05 16:54:53 unbound-default[40069:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
> May 05 16:54:53 unbound-default[40069:0] info: iterator operate: query www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:53 unbound-default[40069:0] info: processQueryTargets: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:53 unbound-default[40069:0] info: sending query: www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:53 unbound-default[40069:0] debug: sending to target: <.> 149.112.112.112#53
> May 05 16:54:53 unbound-default[40069:0] debug: cache memory msg=297432 rrset=298522 infra=30866 val=296000
> May 05 16:54:55 unbound-default[40069:0] info: 192.168.2.66 www.startpuntgeldzaken.nl <http://www.startpuntgeldzaken.nl/>. A IN
> May 05 16:54:55 unbound-default[40069:0] debug: cache memory msg=297432 rrset=298522 infra=30866 val=296000
>
> The problem seems to be tcp error, but I have no clue what causes that error. I could use some help in hunting this down.
>
> Note, asking the forwarder directly resolves without problem. So it is the communication between my unbound and the other resolver.
>
> Gerben Wierda (LinkedIn <https://www.linkedin.com/in/gerbenwierda>)
> R&A Enterprise Architecture <https://ea.rna.nl/> (main site)
> Book: Chess and the Art of Enterprise Architecture <https://ea.rna.nl/the-book/>
> Book: Mastering ArchiMate <https://ea.rna.nl/the-book-edition-iii/>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.nlnetlabs.nl/pipermail/unbound-users/attachments/20210505/f84a66ff/attachment-0001.htm>
More information about the Unbound-users
mailing list