Query results for CAA jp.rq.fo ----- Unbound logs ----- Jan 15 16:16:23 unbound[2868:0] debug: creating udp6 socket ::1 1053 Jan 15 16:16:23 unbound[2868:0] debug: creating tcp6 socket ::1 1053 Jan 15 16:16:23 unbound[2868:0] debug: creating udp4 socket 127.0.0.1 1053 Jan 15 16:16:23 unbound[2868:0] debug: creating tcp4 socket 127.0.0.1 1053 Jan 15 16:16:23 unbound[2868:0] debug: module config: "validator iterator" Jan 15 16:16:23 unbound[2868:0] debug: chdir to . Jan 15 16:16:23 unbound[2868:0] debug: switching log to stderr Jan 15 16:16:23 unbound[2868:0] debug: no config, using builtin root hints. Jan 15 16:16:23 unbound[2868:0] notice: init module 0: validator Jan 15 16:16:23 unbound[2868:0] debug: validator nsec3cfg keysz 1024 mxiter 150 Jan 15 16:16:23 unbound[2868:0] debug: validator nsec3cfg keysz 2048 mxiter 150 Jan 15 16:16:23 unbound[2868:0] debug: validator nsec3cfg keysz 4096 mxiter 150 Jan 15 16:16:23 unbound[2868:0] notice: init module 1: iterator Jan 15 16:16:23 unbound[2868:0] debug: target fetch policy for level 0 is 3 Jan 15 16:16:23 unbound[2868:0] debug: target fetch policy for level 1 is 2 Jan 15 16:16:23 unbound[2868:0] debug: target fetch policy for level 2 is 1 Jan 15 16:16:23 unbound[2868:0] debug: target fetch policy for level 3 is 0 Jan 15 16:16:23 unbound[2868:0] debug: target fetch policy for level 4 is 0 Jan 15 16:16:23 unbound[2868:0] debug: donotq: ff00::/8 Jan 15 16:16:23 unbound[2868:0] debug: donotq: fe80::/10 Jan 15 16:16:23 unbound[2868:0] debug: donotq: fc00::/7 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 2001::/32 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 2001:db8::/32 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 2001:2::/48 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 2001::/23 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 100::/64 Jan 15 16:16:23 unbound[2868:0] debug: donotq: ::ffff:0:0/96 Jan 15 16:16:23 unbound[2868:0] debug: donotq: ::1/128 Jan 15 16:16:23 unbound[2868:0] debug: donotq: ::/128 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 100.64.0.0/10 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 255.255.255.255/32 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 240.0.0.0/4 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 224.0.0.0/4 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 192.18.0.0/15 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 192.88.99.0/24 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 203.0.113.0/24 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 198.51.100.0/24 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 192.0.2.0/24 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 192.0.0.0/24 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 169.254.0.0/16 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 0.0.0.0/8 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 127.0.0.0/8 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 169.254.0.0/16 Jan 15 16:16:23 unbound[2868:0] debug: duplicate donotquery address ignored. Jan 15 16:16:23 unbound[2868:0] debug: donotq: 10.0.0.0/8 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 172.16.0.0/12 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 192.168.0.0/16 Jan 15 16:16:23 unbound[2868:0] debug: donotq: 127.0.0.0/8 Jan 15 16:16:23 unbound[2868:0] debug: duplicate donotquery address ignored. Jan 15 16:16:23 unbound[2868:0] debug: donotq: ::1 Jan 15 16:16:23 unbound[2868:0] debug: duplicate donotquery address ignored. Jan 15 16:16:23 unbound[2868:0] debug: total of 59441 outgoing ports available Jan 15 16:16:23 unbound[2868:0] debug: start threads Jan 15 16:16:23 unbound[2868:0] debug: mini-event internal uses select method. Jan 15 16:16:23 unbound[2868:0] debug: cache memory msg=66104 rrset=66104 infra=7952 val=66384 Jan 15 16:16:23 unbound[2868:0] info: start of service (unbound 1.24.1). Jan 15 16:16:24 unbound[2868:0] query: 127.0.0.1 jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: worker request: max UDP reply size modified (4096 to max-udp-size) Jan 15 16:16:24 unbound[2868:0] debug: from ip4 127.0.0.1 port 41598 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: udp request from ip4 127.0.0.1 port 41598 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:24 unbound[2868:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new Jan 15 16:16:24 unbound[2868:0] info: validator operate: query jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: validator: pass to next module Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: validator module exit state is module_wait_module Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Jan 15 16:16:24 unbound[2868:0] debug: process_request: new external request event Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE Jan 15 16:16:24 unbound[2868:0] info: resolving jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: request has dependency depth of 0 Jan 15 16:16:24 unbound[2868:0] info: priming . IN NS Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_subquery Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query . NS IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: . NS IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: A.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: B.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: C.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: D.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: E.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: F.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: G.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: H.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: I.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: J.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: K.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: L.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: M.ROOT-SERVERS.NET. * A AAAA Jan 15 16:16:24 unbound[2868:0] debug: ip4 198.41.0.4 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 170.247.170.2 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.33.4.12 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 199.7.91.13 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.203.230.10 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.5.5.241 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.112.36.4 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 198.97.190.53 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.36.148.17 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.58.128.30 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 193.0.14.129 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 199.7.83.42 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 202.12.27.33 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:503:ba3e::2:30 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2801:1b8:10::b port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2::c port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2d::d port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:a8::e port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2f::f port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:12::d0d port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:1::53 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:7fe::53 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:503:c27::2:30 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:7fd::1 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:9f::42 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:dc3::35 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 2 targets Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: . NS IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: <.> 192.33.4.12#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] info: mesh_run: end 2 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:24 unbound[2868:0] info: 0pvCD mod1 . NS IN Jan 15 16:16:24 unbound[2868:0] info: 1RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: cache memory msg=66104 rrset=66104 infra=8224 val=66384 Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to . Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=ffc6 Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=42858 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: answer cb Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply id = ffc6 Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply addr = ip4 192.33.4.12 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: lookup size is 1 entries Jan 15 16:16:24 unbound[2868:0] debug: received udp reply. Jan 15 16:16:24 unbound[2868:0] debug: udp message[1109:0] FFC684100001000E0000001B000002000100000200010007E9000014016B0C726F6F742D73657276657273036E657400C011000200010007E90000040162C01EC011000200010007E9000004016CC01EC011000200010007E90000040163C01EC011000200010007E90000040161C01EC011000200010007E90000040166C01EC011000200010007E90000040168C01EC011000200010007E90000040167C01EC011000200010007E90000040164C01EC011000200010007E9000004016AC01EC011000200010007E90000040165C01EC011000200010007E90000040169C01EC011000200010007E9000004016DC01E00002E00010007E90001130002080000 Jan 15 16:16:24 unbound[2868:0] debug: udp message[1109:256] 07E900697A24706968F2E0554700718892BEE382CBBC4E9410C5B6F810009513EA891763B20A156A95586DC3FBB9648A3869A5D1EB2521907D6C188481919F4F3489E50957B5945AE18EDBA93C0E1CDFAB11BB568478A9E19747426AD400874A0853FDD8D824D9525DE1E7F25D44284901D958ACD7897C7A38A4E6810C277263CB976E4626AC91A135D6BC043F4F3F70DD93AE9FCAF58391BF03781406FC637BFAC4E21A612A748FF2AC25F07F8E86551A72B04F3DF83E8CFB09ED29340D2C1BB25FD3C7D28144BAF792D5C710777954623E50E9971FF4B7353A7FC05C2390BB0359F2E0C598A5857A9EDA990BDF684F6BFEF215548B7E4A39ACCF46B586E38C Jan 15 16:16:24 unbound[2868:0] debug: udp message[1109:512] 37E20C2041DBBCA05873F2BEA47CC0EC000100010007E9000004CA0C1B21C04C000100010007E9000004C707532AC01C000100010007E9000004C1000E81C0BC000100010007E9000004C03A801EC0DC000100010007E9000004C0249411C08C000100010007E9000004C661BE35C09C000100010007E9000004C0702404C07C000100010007E9000004C00505F1C0CC000100010007E9000004C0CBE60AC0AC000100010007E9000004C7075B0DC05C000100010007E9000004C021040CC03C000100010007E9000004AAF7AA02C06C000100010007E9000004C6290004C0EC001C00010007E900001020010DC3000000000000000000000035C04C001C0001 Jan 15 16:16:24 unbound[2868:0] debug: udp message[1109:768] 0007E900001020010500009F00000000000000000042C01C001C00010007E9000010200107FD000000000000000000000001C0BC001C00010007E9000010200105030C2700000000000000020030C0DC001C00010007E9000010200107FE000000000000000000000053C08C001C00010007E900001020010500000100000000000000000053C09C001C00010007E900001020010500001200000000000000000D0DC07C001C00010007E900001020010500002F0000000000000000000FC0CC001C00010007E90000102001050000A80000000000000000000EC0AC001C00010007E900001020010500002D0000000000000000000DC05C001C00010007E900 Jan 15 16:16:24 unbound[2868:0] debug: udp message[1109:1024] 00102001050000020000000000000000000CC03C001C00010007E9000010280101B800100000000000000000000BC06C001C00010007E900001020010503BA3E0000000000000002003000002904D0000080000000 Jan 15 16:16:24 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:24 unbound[2868:0] debug: serviced query: EDNS works for ip4 192.33.4.12 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: measured roundtrip at 13 msec Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:24 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:24 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query . NS IN Jan 15 16:16:24 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:24 unbound[2868:0] info: scrub for . NS IN Jan 15 16:16:24 unbound[2868:0] info: response for . NS IN Jan 15 16:16:24 unbound[2868:0] info: reply from <.> 192.33.4.12#53 Jan 15 16:16:24 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 14, AUTHORITY: 0, ADDITIONAL: 26 ;; QUESTION SECTION: . IN NS ;; ANSWER SECTION: . 0 IN NS f.root-servers.net. . 0 IN NS h.root-servers.net. . 0 IN NS g.root-servers.net. . 0 IN NS d.root-servers.net. . 0 IN NS j.root-servers.net. . 0 IN NS e.root-servers.net. . 0 IN NS i.root-servers.net. . 0 IN NS m.root-servers.net. . 0 IN NS k.root-servers.net. . 0 IN NS b.root-servers.net. . 0 IN NS l.root-servers.net. . 0 IN NS c.root-servers.net. . 0 IN NS a.root-servers.net. . 0 IN RRSIG NS 8 0 518400 20260128150000 20260115140000 21831 . cYiSvuOCy7xOlBDFtvgQAJUT6okXY7IKFWqVWG3D+7lkijhppdHrJSGQfWwYhIGRn080ieUJV7WUWuGO26k8DhzfqxG7VoR4qeGXR0Jq1ACHSghT/djYJNlSXeHn8l1EKEkB2Vis14l8ejik5oEMJ3Jjy5duRiaskaE11rwEP08/cN2Trp/K9YORvwN4FAb8Y3v6xOIaYSp0j/KsJfB/joZVGnKwTz34Poz7Ce0pNA0sG7Jf08fSgUS695LVxxB3eVRiPlDplx/0tzU6f8BcI5C7A1ny4MWYpYV6ntqZC99oT2v+8hVUi35KOazPRrWG44w34gwgQdu8oFhz8r6kfA== ;{id = 21831} ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: m.root-servers.net. 0 IN A 202.12.27.33 l.root-servers.net. 0 IN A 199.7.83.42 k.root-servers.net. 0 IN A 193.0.14.129 j.root-servers.net. 0 IN A 192.58.128.30 i.root-servers.net. 0 IN A 192.36.148.17 h.root-servers.net. 0 IN A 198.97.190.53 g.root-servers.net. 0 IN A 192.112.36.4 f.root-servers.net. 0 IN A 192.5.5.241 e.root-servers.net. 0 IN A 192.203.230.10 d.root-servers.net. 0 IN A 199.7.91.13 c.root-servers.net. 0 IN A 192.33.4.12 b.root-servers.net. 0 IN A 170.247.170.2 a.root-servers.net. 0 IN A 198.41.0.4 m.root-servers.net. 0 IN AAAA 2001:dc3::35 l.root-servers.net. 0 IN AAAA 2001:500:9f::42 k.root-servers.net. 0 IN AAAA 2001:7fd::1 j.root-servers.net. 0 IN AAAA 2001:503:c27::2:30 i.root-servers.net. 0 IN AAAA 2001:7fe::53 h.root-servers.net. 0 IN AAAA 2001:500:1::53 g.root-servers.net. 0 IN AAAA 2001:500:12::d0d f.root-servers.net. 0 IN AAAA 2001:500:2f::f e.root-servers.net. 0 IN AAAA 2001:500:a8::e d.root-servers.net. 0 IN AAAA 2001:500:2d::d c.root-servers.net. 0 IN AAAA 2001:500:2::c b.root-servers.net. 0 IN AAAA 2801:1b8:10::b a.root-servers.net. 0 IN AAAA 2001:503:ba3e::2:30 ;; MSG SIZE rcvd: 1086 Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:24 unbound[2868:0] info: query response was ANSWER Jan 15 16:16:24 unbound[2868:0] debug: TTL 0: dropped msg from cache Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state PRIME RESPONSE STATE Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_finished Jan 15 16:16:24 unbound[2868:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone Jan 15 16:16:24 unbound[2868:0] info: validator operate: query . NS IN Jan 15 16:16:24 unbound[2868:0] debug: validator: nextmodule returned Jan 15 16:16:24 unbound[2868:0] debug: not validating response, is valrec(validation recursion lookup) Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: validator module exit state is module_finished Jan 15 16:16:24 unbound[2868:0] info: priming successful for . NS IN Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: m.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: i.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: e.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: j.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: d.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: g.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: h.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: f.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: a.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: c.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: l.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: b.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: k.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:503:ba3e::2:30 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2801:1b8:10::b port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2::c port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2d::d port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:a8::e port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2f::f port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:12::d0d port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:1::53 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:7fe::53 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:503:c27::2:30 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:7fd::1 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:9f::42 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:dc3::35 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip4 198.41.0.4 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 170.247.170.2 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.33.4.12 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 199.7.91.13 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.203.230.10 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.5.5.241 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.112.36.4 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 198.97.190.53 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.36.148.17 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.58.128.30 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 193.0.14.129 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 199.7.83.42 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 202.12.27.33 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 2): jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 3): jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: m.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: i.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: e.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: j.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: d.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: g.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: h.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: f.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: a.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: c.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: l.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: b.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: k.root-servers.net. * A AAAA Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:503:ba3e::2:30 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2801:1b8:10::b port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2::c port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2d::d port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:a8::e port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:2f::f port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:12::d0d port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:1::53 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:7fe::53 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:503:c27::2:30 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:7fd::1 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:500:9f::42 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:dc3::35 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip4 198.41.0.4 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 170.247.170.2 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.33.4.12 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 199.7.91.13 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.203.230.10 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.5.5.241 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.112.36.4 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 198.97.190.53 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.36.148.17 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 192.58.128.30 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 193.0.14.129 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 199.7.83.42 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 202.12.27.33 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 3 targets Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: servselect ip4 192.33.4.12 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: rtt=297 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 297 Jan 15 16:16:24 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: <.> 199.7.83.42#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:24 unbound[2868:0] info: 0RDd mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: cache memory msg=66104 rrset=73860 infra=8521 val=66384 Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:24 unbound[2868:0] debug: serviced_delete Jan 15 16:16:24 unbound[2868:0] debug: close of port 42858 Jan 15 16:16:24 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:24 unbound[2868:0] debug: close fd 9 Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to jp.Rq.fo. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=4f95 Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=36664 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: answer cb Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply id = 4f95 Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply addr = ip4 199.7.83.42 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: lookup size is 1 entries Jan 15 16:16:24 unbound[2868:0] debug: received udp reply. Jan 15 16:16:24 unbound[2868:0] debug: udp message[664:0] 4F9580000001000000070009026A7002527102666F0001010001C012000200010002A30000080161036E6963C012C012000200010002A30000040162C028C012000200010002A30000040163C028C012000200010002A30000040164C028C012002B00010001518000241A230802A752CEAAE4E28B4232244E5486D55889F117A15B81D3128CE7E9359F1D47A445C012002B0001000151800024379708022828B71E13A64B57B7BB68C56519D583597BEC04EF2721D967622223F4CCF36BC012002E0001000151800113002B080100015180697A24706968F2E05547007EEFA7D5C0C359F5648737F424B7BBA823ED505C9C4BAFD2ED8E7F7C46A0BFC8358D30 Jan 15 16:16:24 unbound[2868:0] debug: udp message[664:256] 7CFFD4359C7CE28FF782CFF78FA696E4E19D12810B73F92A14344EDE1A9F3087C5E050CEBB048B633DD2E5FAC371DFF63C0C992E2704EE9EE49C519E641436E5B24B2C0990A8142BD6BECAEC8210F6F440DE925C16EF22C71F3559688A3D2286FBB48A68E61A3123666B95CDC3AAF959E260F329C048174CE6A4F926063D44ABF3D0CFFDCFAF7A0DB81A2C470766C305F707FCBD85F4F67B0ABC7ADD42D5104F619736892A041A36DE9042B2D8CDF5744148AABAFF7423CE4413BA3CF0D3A68E738BE8589FBAE71C4B156C0621287D95108031302C11CF8E8009C212BFC026000100010002A3000004C2A9DA1BC03A000100010002A3000004B918401BC04A00 Jan 15 16:16:24 unbound[2868:0] debug: udp message[664:512] 0100010002A3000004D412F81BC05A000100010002A3000004D412F91BC026001C00010002A30000102001067C13CC00000000000000010027C03A001C00010002A30000102A042B0013CC00000000000000010027C04A001C00010002A30000102A042B0013EE00000000000000000027C05A001C00010002A30000102A042B0013FF000000000000000000270000291000000080000000 Jan 15 16:16:24 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:24 unbound[2868:0] debug: serviced query: EDNS works for ip4 199.7.83.42 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: measured roundtrip at 63 msec Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:24 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:24 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:24 unbound[2868:0] info: scrub for . NS IN Jan 15 16:16:24 unbound[2868:0] info: response for jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] info: reply from <.> 199.7.83.42#53 Jan 15 16:16:24 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ; QUERY: 1, ANSWER: 0, AUTHORITY: 7, ADDITIONAL: 8 ;; QUESTION SECTION: jp.rq.fo. IN CAA ;; ANSWER SECTION: ;; AUTHORITY SECTION: fo. 0 IN NS a.nic.fo. fo. 0 IN NS b.nic.fo. fo. 0 IN NS c.nic.fo. fo. 0 IN NS d.nic.fo. fo. 0 IN DS 6691 8 2 A752CEAAE4E28B4232244E5486D55889F117A15B81D3128CE7E9359F1D47A445 fo. 0 IN DS 14231 8 2 2828B71E13A64B57B7BB68C56519D583597BEC04EF2721D967622223F4CCF36B fo. 0 IN RRSIG DS 8 1 86400 20260128150000 20260115140000 21831 . fu+n1cDDWfVkhzf0JLe7qCPtUFycS6/S7Y5/fEagv8g1jTB8/9Q1nHzij/eCz/ePppbk4Z0SgQtz+SoUNE7eGp8wh8XgUM67BItjPdLl+sNx3/Y8DJkuJwTunuScUZ5kFDblskssCZCoFCvWvsrsghD29EDeklwW7yLHHzVZaIo9Iob7tIpo5hoxI2Zrlc3DqvlZ4mDzKcBIF0zmpPkmBj1Eq/PQz/3Pr3oNuBosRwdmwwX3B/y9hfT2ewq8et1C1RBPYZc2iSoEGjbekEKy2M31dEFIqrr/dCPORBO6PPDTpo5zi+hYn7rnHEsVbAYhKH2VEIAxMCwRz46ACcISvw== ;{id = 21831} ;; ADDITIONAL SECTION: a.nic.fo. 0 IN A 194.169.218.27 b.nic.fo. 0 IN A 185.24.64.27 c.nic.fo. 0 IN A 212.18.248.27 d.nic.fo. 0 IN A 212.18.249.27 a.nic.fo. 0 IN AAAA 2001:67c:13cc::1:27 b.nic.fo. 0 IN AAAA 2a04:2b00:13cc::1:27 c.nic.fo. 0 IN AAAA 2a04:2b00:13ee::27 d.nic.fo. 0 IN AAAA 2a04:2b00:13ff::27 ;; MSG SIZE rcvd: 653 Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:24 unbound[2868:0] info: query response was REFERRAL Jan 15 16:16:24 unbound[2868:0] info: found in cache d.nic.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: found in cache d.nic.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: found in cache c.nic.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: found in cache c.nic.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: found in cache b.nic.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: found in cache b.nic.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: found in cache a.nic.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: found in cache a.nic.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 4 names (0 missing), 8 addrs (0 result, 8 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: d.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: c.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: b.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: a.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] debug: ip6 2a04:2b00:13ff::27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2a04:2b00:13ee::27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2a04:2b00:13cc::1:27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:67c:13cc::1:27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip4 212.18.249.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 212.18.248.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 185.24.64.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 194.169.218.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: cleared outbound list for next round Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 4 names (0 missing), 8 addrs (0 result, 8 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: d.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: c.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: b.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] info: a.nic.fo. * A AAAA Jan 15 16:16:24 unbound[2868:0] debug: ip6 2a04:2b00:13ff::27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2a04:2b00:13ee::27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2a04:2b00:13cc::1:27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip6 2001:67c:13cc::1:27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: ip4 212.18.249.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 212.18.248.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 185.24.64.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 194.169.218.27 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 3 targets Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: 2a04:2b00:13cc::1:27#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:24 unbound[2868:0] info: 0RDd mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: cache memory msg=66104 rrset=76805 infra=8818 val=66384 Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:24 unbound[2868:0] debug: serviced_delete Jan 15 16:16:24 unbound[2868:0] debug: close of port 36664 Jan 15 16:16:24 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:24 unbound[2868:0] debug: close fd 9 Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to jP.RQ.Fo. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=302c Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=25291 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: answer cb Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply id = 302c Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply addr = ip6 2a04:2b00:13cc::1:27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: lookup size is 1 entries Jan 15 16:16:24 unbound[2868:0] debug: received udp reply. Jan 15 16:16:24 unbound[2868:0] debug: udp message[622:0] 302C80000001000000070004026A5002525102466F0001010001C00F0002000100000E100006036E7331C00FC00F0002000100000E100006036E7332C00FC00F0002000100000E100006036E7333C00F2030666862396630716E31687270306F6E32336A376E6E3535686D30736738646BC0120032000100000384002301010000001403E8C43C99009701B1A82B925D1E7F155C162B880007220080000002902032303736743063726B396C6E3074346736626733613169336F7034686A6A3530C012003200010000038400220101000000141038F690A5AD76FC2F6C2A7EEFDA877DDEAAB0BF0006200000000012C050002E00010000038400960032080200 Jan 15 16:16:24 unbound[2868:0] debug: udp message[622:256] 000384696F56DC6947B4C48CCD02666F006641FA296A73AA79545A8143F5A1FB4856A52811468FF145D257926671C0A8D15E8B6111F2D2A49B0BE958B665EE4830DD44A8C19DA6D164F1CB95F7E6CD121C11F8AC3B4BAD14EC4BF1681457E2F04FCE7F046EE7188C533731395FDC05B6E5B9F4BDE3D10FC28DBAD593C0DEEF71989F66F29EB861B1CC284D725C0AFEDFADC0A0002E00010000038400960032080200000384696F6D296947CB118CCD02666F001C3B6CB350FC5A60172411A86864E1E64E93D86B9D3739000C41D33E50946954513E1F2D9840291275EDCD0C20243A7678CCEC65A77CC3932E6640D21EC629452C8177762D60B040688B6B1D9F Jan 15 16:16:24 unbound[2868:0] debug: udp message[622:512] E2AE77EAE4E4DE958A17B29DC26EB2CFC321E5734396AD3DDEB5CAE82231E84AC13FA7E847A881BB66AA8C1A22CF8E4E441290C0260001000100000E1000040FCC61D2C0380001000100000E100004938768BEC04A0001000100000E100004938768B200002904D0000080000000 Jan 15 16:16:24 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:24 unbound[2868:0] debug: serviced query: EDNS works for ip6 2a04:2b00:13cc::1:27 port 53 (len 28) Jan 15 16:16:24 unbound[2868:0] debug: measured roundtrip at 15 msec Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:24 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:24 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:24 unbound[2868:0] info: scrub for fo. NS IN Jan 15 16:16:24 unbound[2868:0] info: response for jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] info: reply from 2a04:2b00:13cc::1:27#53 Jan 15 16:16:24 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr ; QUERY: 1, ANSWER: 0, AUTHORITY: 7, ADDITIONAL: 3 ;; QUESTION SECTION: jp.rq.fo. IN CAA ;; ANSWER SECTION: ;; AUTHORITY SECTION: rq.fo. 0 IN NS ns1.rq.fo. rq.fo. 0 IN NS ns2.rq.fo. rq.fo. 0 IN NS ns3.rq.fo. 0fhb9f0qn1hrp0on23j7nn55hm0sg8dk.fo. 0 IN NSEC3 1 1 0 - 0fkc8f4p02bg3cd85e95q7jv2le1cas8 NS SOA TXT RRSIG DNSKEY NSEC3PARAM ;{flags: optout} 0fhb9f0qn1hrp0on23j7nn55hm0sg8dk.fo. 0 IN RRSIG NSEC3 8 2 900 20260120102012 20251221085012 36045 fo. ZkH6KWpzqnlUWoFD9aH7SFalKBFGj/FF0leSZnHAqNFei2ER8tKkmwvpWLZl7kgw3USowZ2m0WTxy5X35s0SHBH4rDtLrRTsS/FoFFfi8E/OfwRu5xiMUzcxOV/cBbblufS949EPwo261ZPA3u9xmJ9m8p64YbHMKE1yXAr+360= ;{id = 36045} 2076t0crk9ln0t4g6bg3a1i3op4hjj50.fo. 0 IN NSEC3 1 1 0 - 20sfd455llrfobrc59vevmk7fnfalc5v NS DS RRSIG ;{flags: optout} 2076t0crk9ln0t4g6bg3a1i3op4hjj50.fo. 0 IN RRSIG NSEC3 8 2 900 20260120115521 20251221102521 36045 fo. HDtss1D8WmAXJBGoaGTh5k6T2GudNzkADEHTPlCUaVRRPh8tmEApEnXtzQwgJDp2eMzsZad8w5MuZkDSHsYpRSyBd3YtYLBAaItrHZ/irnfq5OTelYoXsp3CbrLPwyHlc0OWrT3etcroIjHoSsE/p+hHqIG7ZqqMGiLPjk5EEpA= ;{id = 36045} ;; ADDITIONAL SECTION: ns1.rq.fo. 0 IN A 15.204.97.210 ns2.rq.fo. 0 IN A 147.135.104.190 ns3.rq.fo. 0 IN A 147.135.104.178 ;; MSG SIZE rcvd: 611 Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:24 unbound[2868:0] info: query response was REFERRAL Jan 15 16:16:24 unbound[2868:0] info: negcache insert referral fo. NS IN Jan 15 16:16:24 unbound[2868:0] info: negcache rr 0fhb9f0qn1hrp0on23j7nn55hm0sg8dk.fo. NSEC3 IN Jan 15 16:16:24 unbound[2868:0] info: negcache rr 2076t0crk9ln0t4g6bg3a1i3op4hjj50.fo. NSEC3 IN Jan 15 16:16:24 unbound[2868:0] info: found in cache ns3.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: found in cache ns2.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: found in cache ns1.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: cleared outbound list for next round Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) parentNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 3 targets Jan 15 16:16:24 unbound[2868:0] info: new target ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: new target ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: new target ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE Jan 15 16:16:24 unbound[2868:0] info: resolving ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: request has dependency depth of 1 Jan 15 16:16:24 unbound[2868:0] debug: cache delegation returns delegpt Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 2): ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 3): ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 2 targets Jan 15 16:16:24 unbound[2868:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns2.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: new target ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: new target ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE Jan 15 16:16:24 unbound[2868:0] info: resolving ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: request has dependency depth of 1 Jan 15 16:16:24 unbound[2868:0] debug: cache delegation returns delegpt Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 2): ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 3): ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 2 targets Jan 15 16:16:24 unbound[2868:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns3.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns2.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: new target ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE Jan 15 16:16:24 unbound[2868:0] info: resolving ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: request has dependency depth of 1 Jan 15 16:16:24 unbound[2868:0] debug: cache delegation returns delegpt Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 2): ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3) Jan 15 16:16:24 unbound[2868:0] info: resolving (init part 3): ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (3 missing), 3 addrs (0 result, 3 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. A Jan 15 16:16:24 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 2 targets Jan 15 16:16:24 unbound[2868:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns3.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns2.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] info: skipping target due to dependency cycle (harden-glue: no may fix some of the cycles) ns1.rq.fo. A IN Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: 15.204.97.210#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:24 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=9118 val=67188 Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:24 unbound[2868:0] debug: serviced_delete Jan 15 16:16:24 unbound[2868:0] debug: close of port 25291 Jan 15 16:16:24 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:24 unbound[2868:0] debug: close fd 9 Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to jP.RQ.fO. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=a5a1 Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=53586 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to Ns2.rQ.Fo. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=8418 Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=48760 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to NS3.rq.fo. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=3e6b Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=61954 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 11 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to Ns1.rq.Fo. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=843f Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=40034 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 12 (-1 msec) Jan 15 16:16:24 unbound[2868:0] debug: answer cb Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply id = 843f Jan 15 16:16:24 unbound[2868:0] debug: Incoming reply addr = ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: lookup size is 4 entries Jan 15 16:16:24 unbound[2868:0] debug: received udp reply. Jan 15 16:16:24 unbound[2868:0] debug: udp message[27:0] 843F84000001000000000000034E733102727102466F00001C0001 Jan 15 16:16:24 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:24 unbound[2868:0] debug: serviced query: EDNS works for ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: measured roundtrip at 21 msec Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:24 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:24 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:24 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:24 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:24 unbound[2868:0] info: scrub for rq.fo. NS IN Jan 15 16:16:24 unbound[2868:0] info: response for ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: reply from 15.204.97.210#53 Jan 15 16:16:24 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: ns1.rq.fo. IN AAAA ;; ANSWER SECTION: ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 27 Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:24 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:24 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1 Jan 15 16:16:24 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:24 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:24 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:24 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:24 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: attempt to get extra 2 targets Jan 15 16:16:24 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:24 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: rtt=306 ADDR_LAME Jan 15 16:16:24 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: rtt=376 Jan 15 16:16:24 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:24 unbound[2868:0] debug: rtt=376 Jan 15 16:16:24 unbound[2868:0] debug: selrtt 376 Jan 15 16:16:24 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:24 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:24 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:24 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:24 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:24 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:24 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:24 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:24 unbound[2868:0] debug: serviced_delete Jan 15 16:16:24 unbound[2868:0] debug: close of port 40034 Jan 15 16:16:24 unbound[2868:0] debug: comm_point_close of 12: event_del Jan 15 16:16:24 unbound[2868:0] debug: close fd 12 Jan 15 16:16:24 unbound[2868:0] debug: serviced send timer Jan 15 16:16:24 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:24 unbound[2868:0] debug: qname perturbed to NS1.Rq.fO. Jan 15 16:16:24 unbound[2868:0] debug: serviced query UDP timeout=376 msec Jan 15 16:16:24 unbound[2868:0] debug: inserted new pending reply id=2348 Jan 15 16:16:24 unbound[2868:0] debug: opened UDP if=0 port=29146 Jan 15 16:16:24 unbound[2868:0] debug: comm point start listening 12 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 1 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=376 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=306 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 306 Jan 15 16:16:25 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 53586 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 9 Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c698b30 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 2, currentqueries 0 sentcount 1 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=306 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 306 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 15.204.97.210#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 48760 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 10 Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 1, currentqueries 0 sentcount 1 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=306 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 306 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 15.204.97.210#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 61954 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 11: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 11 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=1 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to nS3.rq.fO. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=306 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=d3ab Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=56110 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to Jp.rQ.FO. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=752 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=40c5 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=5335 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=1 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to NS2.rq.Fo. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=306 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=dcf0 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=11537 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 11 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: answer cb Jan 15 16:16:25 unbound[2868:0] debug: Incoming reply id = d3ab Jan 15 16:16:25 unbound[2868:0] debug: Incoming reply addr = ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: lookup size is 4 entries Jan 15 16:16:25 unbound[2868:0] debug: received udp reply. Jan 15 16:16:25 unbound[2868:0] debug: udp message[27:0] D3AB84000001000000000000036E533302727102664F00001C0001 Jan 15 16:16:25 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:25 unbound[2868:0] debug: measured roundtrip at 17 msec Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] info: scrub for rq.fo. NS IN Jan 15 16:16:25 unbound[2868:0] info: response for ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: reply from 15.204.97.210#53 Jan 15 16:16:25 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: ns3.rq.fo. IN AAAA ;; ANSWER SECTION: ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 27 Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 1, currentqueries 0 sentcount 2 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=247 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 752 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 56110 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 9 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to ns3.rQ.FO. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=752 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=d7d0 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=43996 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: answer cb Jan 15 16:16:25 unbound[2868:0] debug: Incoming reply id = dcf0 Jan 15 16:16:25 unbound[2868:0] debug: Incoming reply addr = ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: lookup size is 4 entries Jan 15 16:16:25 unbound[2868:0] debug: received udp reply. Jan 15 16:16:25 unbound[2868:0] debug: udp message[27:0] DCF084000001000000000000034E533202727102466F00001C0001 Jan 15 16:16:25 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:25 unbound[2868:0] debug: measured roundtrip at 18 msec Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c698b30 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] info: scrub for rq.fo. NS IN Jan 15 16:16:25 unbound[2868:0] info: response for ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: reply from 15.204.97.210#53 Jan 15 16:16:25 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: ns2.rq.fo. IN AAAA ;; ANSWER SECTION: ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 27 Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 2, currentqueries 0 sentcount 2 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=204 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 752 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 11537 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 11: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 11 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to ns2.rQ.fO. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=752 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=b1dc Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=23838 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 11 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 2 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: attempt to get extra 2 targets Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=204 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: selrtt 752 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 29146 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 12: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 12 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to ns1.RQ.FO. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=752 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=50cf Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=27780 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 12 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 2 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=204 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 204 Jan 15 16:16:25 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 15.204.97.210#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 5335 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 10 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=1 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to jP.RQ.fo. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=204 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=a362 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=41486 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: answer cb Jan 15 16:16:25 unbound[2868:0] debug: Incoming reply id = a362 Jan 15 16:16:25 unbound[2868:0] debug: Incoming reply addr = ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: lookup size is 4 entries Jan 15 16:16:25 unbound[2868:0] debug: received udp reply. Jan 15 16:16:25 unbound[2868:0] debug: udp message[26:0] A36284000001000000000000026A5002525102666F0001010001 Jan 15 16:16:25 unbound[2868:0] debug: outnet handle udp reply Jan 15 16:16:25 unbound[2868:0] debug: measured roundtrip at 16 msec Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: good 0x20-ID in reply qname Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] info: scrub for rq.fo. NS IN Jan 15 16:16:25 unbound[2868:0] info: response for jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] info: reply from 15.204.97.210#53 Jan 15 16:16:25 unbound[2868:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr aa ; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: jp.rq.fo. IN CAA ;; ANSWER SECTION: ;; AUTHORITY SECTION: ;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 26 Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 3 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=752 Jan 15 16:16:25 unbound[2868:0] debug: selrtt 752 Jan 15 16:16:25 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 41486 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 10 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to JP.Rq.fo. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=752 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=fc07 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=23530 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 1, currentqueries 0 sentcount 3 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 43996 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 9 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to NS3.Rq.fo. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=9461 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=53437 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c698b30 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 2, currentqueries 0 sentcount 3 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 23838 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 11: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 11 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to Ns2.RQ.Fo. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=bb9e Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=17448 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 11 (-1 msec) Jan 15 16:16:25 unbound[2868:0] debug: timeout udp Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:25 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:25 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:25 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:25 unbound[2868:0] debug: query response was timeout Jan 15 16:16:25 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:25 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 3 Jan 15 16:16:25 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:25 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:25 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:25 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:25 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:25 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:25 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:25 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:25 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:25 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:25 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:25 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:25 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:25 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:25 unbound[2868:0] debug: serviced_delete Jan 15 16:16:25 unbound[2868:0] debug: close of port 27780 Jan 15 16:16:25 unbound[2868:0] debug: comm_point_close of 12: event_del Jan 15 16:16:25 unbound[2868:0] debug: close fd 12 Jan 15 16:16:25 unbound[2868:0] debug: serviced send timer Jan 15 16:16:25 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:25 unbound[2868:0] debug: qname perturbed to Ns1.RQ.fo. Jan 15 16:16:25 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:25 unbound[2868:0] debug: inserted new pending reply id=8440 Jan 15 16:16:25 unbound[2868:0] debug: opened UDP if=0 port=41127 Jan 15 16:16:25 unbound[2868:0] debug: comm point start listening 12 (-1 msec) Jan 15 16:16:26 unbound[2868:0] debug: timeout udp Jan 15 16:16:26 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:26 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:26 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:26 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:26 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:26 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:26 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:26 unbound[2868:0] debug: query response was timeout Jan 15 16:16:26 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:26 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:26 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 4 Jan 15 16:16:26 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:26 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:26 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:26 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:26 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:26 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:26 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:26 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:26 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:26 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:26 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:26 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:26 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:26 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:26 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:26 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:26 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:26 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:26 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:26 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:26 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:26 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:26 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:26 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:26 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:26 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:26 unbound[2868:0] debug: serviced_delete Jan 15 16:16:26 unbound[2868:0] debug: close of port 23530 Jan 15 16:16:26 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:26 unbound[2868:0] debug: close fd 10 Jan 15 16:16:26 unbound[2868:0] debug: serviced send timer Jan 15 16:16:26 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:26 unbound[2868:0] debug: qname perturbed to JP.rq.Fo. Jan 15 16:16:26 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:26 unbound[2868:0] debug: inserted new pending reply id=601f Jan 15 16:16:26 unbound[2868:0] debug: opened UDP if=0 port=45227 Jan 15 16:16:26 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:27 unbound[2868:0] debug: timeout udp Jan 15 16:16:27 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:27 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:27 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:27 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:27 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:27 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:27 unbound[2868:0] debug: query response was timeout Jan 15 16:16:27 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:27 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: processQueryTargets: targetqueries 1, currentqueries 0 sentcount 4 Jan 15 16:16:27 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:27 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:27 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:27 unbound[2868:0] info: sending query: ns3.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:27 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:27 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:27 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:27 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:27 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:27 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:27 unbound[2868:0] debug: serviced_delete Jan 15 16:16:27 unbound[2868:0] debug: close of port 53437 Jan 15 16:16:27 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:27 unbound[2868:0] debug: close fd 9 Jan 15 16:16:27 unbound[2868:0] debug: timeout udp Jan 15 16:16:27 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:27 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c698b30 Jan 15 16:16:27 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:27 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:27 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:27 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:27 unbound[2868:0] debug: query response was timeout Jan 15 16:16:27 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:27 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: processQueryTargets: targetqueries 2, currentqueries 0 sentcount 4 Jan 15 16:16:27 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:27 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:27 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:27 unbound[2868:0] info: sending query: ns2.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:27 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:27 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:27 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:27 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:27 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:27 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:27 unbound[2868:0] debug: serviced_delete Jan 15 16:16:27 unbound[2868:0] debug: close of port 17448 Jan 15 16:16:27 unbound[2868:0] debug: comm_point_close of 11: event_del Jan 15 16:16:27 unbound[2868:0] debug: close fd 11 Jan 15 16:16:27 unbound[2868:0] debug: serviced send timer Jan 15 16:16:27 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:27 unbound[2868:0] debug: qname perturbed to nS3.Rq.fo. Jan 15 16:16:27 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:27 unbound[2868:0] debug: inserted new pending reply id=3d89 Jan 15 16:16:27 unbound[2868:0] debug: opened UDP if=0 port=24967 Jan 15 16:16:27 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:27 unbound[2868:0] debug: serviced send timer Jan 15 16:16:27 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:27 unbound[2868:0] debug: qname perturbed to nS2.Rq.Fo. Jan 15 16:16:27 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:27 unbound[2868:0] debug: inserted new pending reply id=2419 Jan 15 16:16:27 unbound[2868:0] debug: opened UDP if=0 port=64639 Jan 15 16:16:27 unbound[2868:0] debug: comm point start listening 11 (-1 msec) Jan 15 16:16:27 unbound[2868:0] debug: timeout udp Jan 15 16:16:27 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:27 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:27 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:27 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:27 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:27 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:27 unbound[2868:0] debug: query response was timeout Jan 15 16:16:27 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:27 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 4 Jan 15 16:16:27 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:27 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:27 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=1504 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:27 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:27 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:27 unbound[2868:0] debug: selrtt 1504 Jan 15 16:16:27 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:27 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:27 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:27 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:27 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:27 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:27 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:27 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:27 unbound[2868:0] debug: serviced_delete Jan 15 16:16:27 unbound[2868:0] debug: close of port 41127 Jan 15 16:16:27 unbound[2868:0] debug: comm_point_close of 12: event_del Jan 15 16:16:27 unbound[2868:0] debug: close fd 12 Jan 15 16:16:27 unbound[2868:0] debug: serviced send timer Jan 15 16:16:27 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:27 unbound[2868:0] debug: qname perturbed to NS1.rq.fo. Jan 15 16:16:27 unbound[2868:0] debug: serviced query UDP timeout=1504 msec Jan 15 16:16:27 unbound[2868:0] debug: inserted new pending reply id=0fa2 Jan 15 16:16:27 unbound[2868:0] debug: opened UDP if=0 port=20497 Jan 15 16:16:27 unbound[2868:0] debug: comm point start listening 12 (-1 msec) Jan 15 16:16:28 unbound[2868:0] debug: timeout udp Jan 15 16:16:28 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:28 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:28 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:28 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:28 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:28 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:28 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:28 unbound[2868:0] debug: query response was timeout Jan 15 16:16:28 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:28 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:28 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 5 Jan 15 16:16:28 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:28 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:28 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:28 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:28 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:28 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:28 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:28 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:28 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:28 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:28 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:28 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:28 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:28 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:28 unbound[2868:0] debug: selrtt 3008 Jan 15 16:16:28 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:28 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:28 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:28 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:28 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:28 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:28 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:28 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:28 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:28 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:28 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:28 unbound[2868:0] debug: serviced_delete Jan 15 16:16:28 unbound[2868:0] debug: close of port 45227 Jan 15 16:16:28 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:28 unbound[2868:0] debug: close fd 10 Jan 15 16:16:28 unbound[2868:0] debug: serviced send timer Jan 15 16:16:28 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:28 unbound[2868:0] debug: qname perturbed to Jp.rQ.Fo. Jan 15 16:16:28 unbound[2868:0] debug: serviced query UDP timeout=3008 msec Jan 15 16:16:28 unbound[2868:0] debug: inserted new pending reply id=2deb Jan 15 16:16:28 unbound[2868:0] debug: opened UDP if=0 port=48207 Jan 15 16:16:28 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:29 unbound[2868:0] debug: timeout udp Jan 15 16:16:29 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:29 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:29 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:29 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:29 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:29 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:29 unbound[2868:0] debug: query response was timeout Jan 15 16:16:29 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:29 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: processQueryTargets: targetqueries 1, currentqueries 0 sentcount 5 Jan 15 16:16:29 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:29 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:29 unbound[2868:0] debug: selrtt 3008 Jan 15 16:16:29 unbound[2868:0] info: sending query: ns3.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:29 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:29 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:29 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:29 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:29 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:29 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:29 unbound[2868:0] debug: serviced_delete Jan 15 16:16:29 unbound[2868:0] debug: close of port 24967 Jan 15 16:16:29 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:29 unbound[2868:0] debug: close fd 9 Jan 15 16:16:29 unbound[2868:0] debug: timeout udp Jan 15 16:16:29 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:29 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c698b30 Jan 15 16:16:29 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:29 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:29 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:29 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:29 unbound[2868:0] debug: query response was timeout Jan 15 16:16:29 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:29 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: processQueryTargets: targetqueries 2, currentqueries 0 sentcount 5 Jan 15 16:16:29 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:29 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:29 unbound[2868:0] debug: selrtt 3008 Jan 15 16:16:29 unbound[2868:0] info: sending query: ns2.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:29 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:29 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:29 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:29 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:29 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:29 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:29 unbound[2868:0] debug: serviced_delete Jan 15 16:16:29 unbound[2868:0] debug: close of port 64639 Jan 15 16:16:29 unbound[2868:0] debug: comm_point_close of 11: event_del Jan 15 16:16:29 unbound[2868:0] debug: close fd 11 Jan 15 16:16:29 unbound[2868:0] debug: serviced send timer Jan 15 16:16:29 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:29 unbound[2868:0] debug: qname perturbed to nS3.rQ.Fo. Jan 15 16:16:29 unbound[2868:0] debug: serviced query UDP timeout=3008 msec Jan 15 16:16:29 unbound[2868:0] debug: inserted new pending reply id=3bba Jan 15 16:16:29 unbound[2868:0] debug: opened UDP if=0 port=17355 Jan 15 16:16:29 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:29 unbound[2868:0] debug: serviced send timer Jan 15 16:16:29 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:29 unbound[2868:0] debug: qname perturbed to Ns2.RQ.Fo. Jan 15 16:16:29 unbound[2868:0] debug: serviced query UDP timeout=3008 msec Jan 15 16:16:29 unbound[2868:0] debug: inserted new pending reply id=2447 Jan 15 16:16:29 unbound[2868:0] debug: opened UDP if=0 port=32855 Jan 15 16:16:29 unbound[2868:0] debug: comm point start listening 11 (-1 msec) Jan 15 16:16:29 unbound[2868:0] debug: timeout udp Jan 15 16:16:29 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:29 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:29 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:29 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:29 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:29 unbound[2868:0] info: Capsforid: timeouts, starting fallback Jan 15 16:16:29 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:29 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 5 Jan 15 16:16:29 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:29 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:29 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: 0x20 fallback number 0 Jan 15 16:16:29 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:29 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:29 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:29 unbound[2868:0] debug: selrtt 3008 Jan 15 16:16:29 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:29 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:29 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:29 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:29 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:29 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:29 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:29 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:29 unbound[2868:0] debug: serviced_delete Jan 15 16:16:29 unbound[2868:0] debug: close of port 20497 Jan 15 16:16:29 unbound[2868:0] debug: comm_point_close of 12: event_del Jan 15 16:16:29 unbound[2868:0] debug: close fd 12 Jan 15 16:16:29 unbound[2868:0] debug: serviced send timer Jan 15 16:16:29 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:29 unbound[2868:0] debug: serviced query UDP timeout=3008 msec Jan 15 16:16:29 unbound[2868:0] debug: inserted new pending reply id=41a9 Jan 15 16:16:29 unbound[2868:0] debug: opened UDP if=0 port=35765 Jan 15 16:16:29 unbound[2868:0] debug: comm point start listening 12 (-1 msec) Jan 15 16:16:31 unbound[2868:0] debug: timeout udp Jan 15 16:16:31 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:31 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:31 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:31 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:31 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:31 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:31 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:31 unbound[2868:0] debug: query response was timeout Jan 15 16:16:31 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:31 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:31 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 6 Jan 15 16:16:31 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:31 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:31 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:31 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:31 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:31 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:31 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:31 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:31 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:31 unbound[2868:0] debug: rtt=6016 Jan 15 16:16:31 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:31 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:31 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:31 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:31 unbound[2868:0] debug: selrtt 3008 Jan 15 16:16:31 unbound[2868:0] info: sending query: jp.rq.fo. CAA IN Jan 15 16:16:31 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:31 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:31 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:31 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:31 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:31 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:31 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:31 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:31 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:31 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:31 unbound[2868:0] debug: serviced_delete Jan 15 16:16:31 unbound[2868:0] debug: close of port 48207 Jan 15 16:16:31 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:31 unbound[2868:0] debug: close fd 10 Jan 15 16:16:31 unbound[2868:0] debug: serviced send timer Jan 15 16:16:31 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:31 unbound[2868:0] debug: qname perturbed to JP.rQ.Fo. Jan 15 16:16:31 unbound[2868:0] debug: serviced query UDP timeout=3008 msec Jan 15 16:16:31 unbound[2868:0] debug: inserted new pending reply id=e1df Jan 15 16:16:31 unbound[2868:0] debug: opened UDP if=0 port=14445 Jan 15 16:16:31 unbound[2868:0] debug: comm point start listening 10 (-1 msec) Jan 15 16:16:32 unbound[2868:0] debug: timeout udp Jan 15 16:16:32 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:32 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a1d50 Jan 15 16:16:32 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:32 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:32 unbound[2868:0] info: iterator operate: query ns3.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:32 unbound[2868:0] info: Capsforid: timeouts, starting fallback Jan 15 16:16:32 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:32 unbound[2868:0] info: processQueryTargets: ns3.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: processQueryTargets: targetqueries 1, currentqueries 0 sentcount 6 Jan 15 16:16:32 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:32 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: wait for all targets for fallback Jan 15 16:16:32 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:32 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:32 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:32 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:32 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:32 unbound[2868:0] debug: serviced_delete Jan 15 16:16:32 unbound[2868:0] debug: close of port 17355 Jan 15 16:16:32 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:32 unbound[2868:0] debug: close fd 9 Jan 15 16:16:32 unbound[2868:0] debug: timeout udp Jan 15 16:16:32 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:32 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c698b30 Jan 15 16:16:32 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:32 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:32 unbound[2868:0] info: iterator operate: query ns2.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:32 unbound[2868:0] info: Capsforid: timeouts, starting fallback Jan 15 16:16:32 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:32 unbound[2868:0] info: processQueryTargets: ns2.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: processQueryTargets: targetqueries 2, currentqueries 0 sentcount 6 Jan 15 16:16:32 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:32 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: wait for all targets for fallback Jan 15 16:16:32 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:32 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:32 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:32 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:32 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:32 unbound[2868:0] debug: serviced_delete Jan 15 16:16:32 unbound[2868:0] debug: close of port 32855 Jan 15 16:16:32 unbound[2868:0] debug: comm_point_close of 11: event_del Jan 15 16:16:32 unbound[2868:0] debug: close fd 11 Jan 15 16:16:32 unbound[2868:0] debug: timeout udp Jan 15 16:16:32 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:32 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:32 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:32 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:32 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:32 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:32 unbound[2868:0] debug: query response was timeout Jan 15 16:16:32 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:32 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 6 Jan 15 16:16:32 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:32 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:32 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: 0x20 fallback number 0 Jan 15 16:16:32 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:32 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: rtt=3008 Jan 15 16:16:32 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: rtt=6016 Jan 15 16:16:32 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:32 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:32 unbound[2868:0] debug: selrtt 3008 Jan 15 16:16:32 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:32 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:32 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:32 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:32 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:32 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:32 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:32 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:32 unbound[2868:0] debug: serviced_delete Jan 15 16:16:32 unbound[2868:0] debug: close of port 35765 Jan 15 16:16:32 unbound[2868:0] debug: comm_point_close of 12: event_del Jan 15 16:16:32 unbound[2868:0] debug: close fd 12 Jan 15 16:16:32 unbound[2868:0] debug: serviced send timer Jan 15 16:16:32 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:32 unbound[2868:0] debug: serviced query UDP timeout=3008 msec Jan 15 16:16:32 unbound[2868:0] debug: inserted new pending reply id=5747 Jan 15 16:16:32 unbound[2868:0] debug: opened UDP if=0 port=33007 Jan 15 16:16:32 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:34 unbound[2868:0] debug: timeout udp Jan 15 16:16:34 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:34 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c6a6660 Jan 15 16:16:34 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:34 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:34 unbound[2868:0] info: iterator operate: query jp.rq.fo. CAA IN Jan 15 16:16:34 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:34 unbound[2868:0] info: Capsforid: timeouts, starting fallback Jan 15 16:16:34 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:34 unbound[2868:0] info: processQueryTargets: jp.rq.fo. CAA IN Jan 15 16:16:34 unbound[2868:0] debug: processQueryTargets: targetqueries 3, currentqueries 0 sentcount 7 Jan 15 16:16:34 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) parentNS Jan 15 16:16:34 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:34 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:34 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:34 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:34 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:34 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:34 unbound[2868:0] debug: wait for all targets for fallback Jan 15 16:16:34 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:34 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:34 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:34 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:34 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:34 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:34 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:34 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:34 unbound[2868:0] debug: serviced_delete Jan 15 16:16:34 unbound[2868:0] debug: close of port 14445 Jan 15 16:16:34 unbound[2868:0] debug: comm_point_close of 10: event_del Jan 15 16:16:34 unbound[2868:0] debug: close fd 10 Jan 15 16:16:35 unbound[2868:0] debug: timeout udp Jan 15 16:16:35 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:35 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:35 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:35 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:35 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:35 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:35 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:35 unbound[2868:0] debug: query response was timeout Jan 15 16:16:35 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:35 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:35 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 7 Jan 15 16:16:35 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:35 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:35 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:35 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:35 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:35 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:35 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:35 unbound[2868:0] debug: 0x20 fallback number 0 Jan 15 16:16:35 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:35 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:35 unbound[2868:0] debug: rtt=6016 Jan 15 16:16:35 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:35 unbound[2868:0] debug: rtt=6016 Jan 15 16:16:35 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:35 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:35 unbound[2868:0] debug: selrtt 6016 Jan 15 16:16:35 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:35 unbound[2868:0] debug: sending to target: 147.135.104.178#53 Jan 15 16:16:35 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:35 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:35 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:35 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:35 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:35 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:35 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:35 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:35 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:35 unbound[2868:0] debug: serviced_delete Jan 15 16:16:35 unbound[2868:0] debug: close of port 33007 Jan 15 16:16:35 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:35 unbound[2868:0] debug: close fd 9 Jan 15 16:16:35 unbound[2868:0] debug: serviced send timer Jan 15 16:16:35 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:35 unbound[2868:0] debug: serviced query UDP timeout=6016 msec Jan 15 16:16:35 unbound[2868:0] debug: inserted new pending reply id=1107 Jan 15 16:16:35 unbound[2868:0] debug: opened UDP if=0 port=44552 Jan 15 16:16:35 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:41 unbound[2868:0] debug: timeout udp Jan 15 16:16:41 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:41 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:41 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:41 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:41 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:41 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:41 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:41 unbound[2868:0] debug: query response was timeout Jan 15 16:16:41 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:41 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:41 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 8 Jan 15 16:16:41 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:41 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:41 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:41 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:41 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:41 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:41 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:41 unbound[2868:0] debug: 0x20 fallback number 0 Jan 15 16:16:41 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:41 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:41 unbound[2868:0] debug: rtt=12032 Jan 15 16:16:41 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:41 unbound[2868:0] debug: rtt=6016 Jan 15 16:16:41 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:41 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:41 unbound[2868:0] debug: selrtt 6016 Jan 15 16:16:41 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:41 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:41 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:41 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:41 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:41 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:41 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:41 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:41 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:41 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:41 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:41 unbound[2868:0] debug: serviced_delete Jan 15 16:16:41 unbound[2868:0] debug: close of port 44552 Jan 15 16:16:41 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:41 unbound[2868:0] debug: close fd 9 Jan 15 16:16:41 unbound[2868:0] debug: serviced send timer Jan 15 16:16:41 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:41 unbound[2868:0] debug: serviced query UDP timeout=6016 msec Jan 15 16:16:41 unbound[2868:0] debug: inserted new pending reply id=9734 Jan 15 16:16:41 unbound[2868:0] debug: opened UDP if=0 port=40463 Jan 15 16:16:41 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Jan 15 16:16:47 unbound[2868:0] debug: timeout udp Jan 15 16:16:47 unbound[2868:0] debug: svcd callbacks start Jan 15 16:16:47 unbound[2868:0] debug: worker svcd callback for qstate 0x7f806c694220 Jan 15 16:16:47 unbound[2868:0] debug: mesh_run: start Jan 15 16:16:47 unbound[2868:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply Jan 15 16:16:47 unbound[2868:0] info: iterator operate: query ns1.rq.fo. AAAA IN Jan 15 16:16:47 unbound[2868:0] debug: process_response: new external response event Jan 15 16:16:47 unbound[2868:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Jan 15 16:16:47 unbound[2868:0] debug: query response was timeout Jan 15 16:16:47 unbound[2868:0] debug: iter_handle processing q with state QUERY TARGETS STATE Jan 15 16:16:47 unbound[2868:0] info: processQueryTargets: ns1.rq.fo. AAAA IN Jan 15 16:16:47 unbound[2868:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 9 Jan 15 16:16:47 unbound[2868:0] info: DelegationPoint: 3 names (0 missing), 3 addrs (3 result, 0 avail) cacheNS Jan 15 16:16:47 unbound[2868:0] info: ns3.rq.fo. * A Jan 15 16:16:47 unbound[2868:0] info: ns2.rq.fo. * A Jan 15 16:16:47 unbound[2868:0] info: ns1.rq.fo. * A Jan 15 16:16:47 unbound[2868:0] debug: ADDR_LAME ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:47 unbound[2868:0] debug: ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:47 unbound[2868:0] debug: ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:47 unbound[2868:0] debug: 0x20 fallback number 0 Jan 15 16:16:47 unbound[2868:0] debug: rpz: iterator module callback: have_rpz=0 Jan 15 16:16:47 unbound[2868:0] debug: servselect ip4 147.135.104.190 port 53 (len 16) Jan 15 16:16:47 unbound[2868:0] debug: rtt=12032 Jan 15 16:16:47 unbound[2868:0] debug: servselect ip4 147.135.104.178 port 53 (len 16) Jan 15 16:16:47 unbound[2868:0] debug: rtt=12032 Jan 15 16:16:47 unbound[2868:0] debug: servselect ip4 15.204.97.210 port 53 (len 16) Jan 15 16:16:47 unbound[2868:0] debug: rtt=169 ADDR_LAME Jan 15 16:16:47 unbound[2868:0] debug: selrtt 12032 Jan 15 16:16:47 unbound[2868:0] info: sending query: ns1.rq.fo. AAAA IN Jan 15 16:16:47 unbound[2868:0] debug: sending to target: 147.135.104.190#53 Jan 15 16:16:47 unbound[2868:0] debug: dnssec status: not expected Jan 15 16:16:47 unbound[2868:0] debug: mesh_run: iterator module exit state is module_wait_reply Jan 15 16:16:47 unbound[2868:0] info: mesh_run: end 4 recursion states (1 with reply, 0 detached), 1 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out Jan 15 16:16:47 unbound[2868:0] info: 0vRDCD mod1 ns1.rq.fo. AAAA IN Jan 15 16:16:47 unbound[2868:0] info: 1vRDCDc mod1 ns2.rq.fo. AAAA IN Jan 15 16:16:47 unbound[2868:0] info: 2vRDCDc mod1 ns3.rq.fo. AAAA IN Jan 15 16:16:47 unbound[2868:0] info: 3RDdc mod1 rep jp.rq.fo. CAA IN Jan 15 16:16:47 unbound[2868:0] debug: cache memory msg=66104 rrset=78793 infra=10027 val=67188 Jan 15 16:16:47 unbound[2868:0] debug: svcd callbacks end Jan 15 16:16:47 unbound[2868:0] debug: serviced_delete Jan 15 16:16:47 unbound[2868:0] debug: close of port 40463 Jan 15 16:16:47 unbound[2868:0] debug: comm_point_close of 9: event_del Jan 15 16:16:47 unbound[2868:0] debug: close fd 9 Jan 15 16:16:47 unbound[2868:0] debug: serviced send timer Jan 15 16:16:47 unbound[2868:0] debug: EDNS lookup known=0 vs=0 Jan 15 16:16:47 unbound[2868:0] debug: serviced query UDP timeout=12032 msec Jan 15 16:16:47 unbound[2868:0] debug: inserted new pending reply id=0965 Jan 15 16:16:47 unbound[2868:0] debug: opened UDP if=0 port=58975 Jan 15 16:16:47 unbound[2868:0] debug: comm point start listening 9 (-1 msec) Error running query: read udp 127.0.0.1:41598->127.0.0.1:1053: i/o timeout