unbound issue
i keep getting servfail messages when doing dig for google or pihole.net i just setup unbound i cannot for the life of me get it working
here is my log
Apr 25 17:00:33 unbound[572:0] debug: rtt=125 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.58.128.30 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=265 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: selrtt 360086 Apr 25 17:00:33 unbound[572:0] debug: chase to recursion lame server Apr 25 17:00:33 unbound[572:0] debug: chase to dnssec lame server Apr 25 17:00:33 unbound[572:0] info: sending query: com. A IN Apr 25 17:00:33 unbound[572:0] debug: sending to target: <.> 192.5.5.241#53 Apr 25 17:00:33 unbound[572:0] debug: dnssec status: expected but lame_query anyway Apr 25 17:00:33 unbound[572:0] debug: mesh_run: iterator module exit state is module_wait_reply Apr 25 17:00:33 unbound[572:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recu> Apr 25 17:00:33 unbound[572:0] info: average recursion processing time 0.059638 sec Apr 25 17:00:33 unbound[572:0] info: histogram of recursion processing times Apr 25 17:00:33 unbound[572:0] info: [25%]=0 median[50%]=0 [75%]=0 Apr 25 17:00:33 unbound[572:0] info: lower(secs) upper(secs) recursions Apr 25 17:00:33 unbound[572:0] info: 0.032768 0.065536 1 Apr 25 17:00:33 unbound[572:0] info: 0.065536 0.131072 1 Apr 25 17:00:33 unbound[572:0] info: 0RDd mod2 rep google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: cache memory msg=69534 rrset=71977 infra=11966 val=66529 subnet=74504 Apr 25 17:00:33 unbound[572:0] debug: serviced send timer Apr 25 17:00:33 unbound[572:0] debug: EDNS lookup known=1 vs=0 Apr 25 17:00:33 unbound[572:0] debug: serviced query UDP timeout=191 msec Apr 25 17:00:33 unbound[572:0] debug: inserted new pending reply id=4f30 Apr 25 17:00:33 unbound[572:0] debug: opened UDP if=0 port=27545 Apr 25 17:00:33 unbound[572:0] debug: comm point start listening 12 (-1 msec) Apr 25 17:00:33 unbound[572:0] debug: answer cb Apr 25 17:00:33 unbound[572:0] debug: Incoming reply id = 4f30 Apr 25 17:00:33 unbound[572:0] debug: Incoming reply addr = ip4 192.5.5.241 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: lookup size is 1 entries Apr 25 17:00:33 unbound[572:0] debug: received udp reply. Apr 25 17:00:33 unbound[572:0] debug: udp message[32:0] 4F308180000100000000000103636F6D000001000100002904D0000080000000 Apr 25 17:00:33 unbound[572:0] debug: outnet handle udp reply Apr 25 17:00:33 unbound[572:0] debug: measured roundtrip at 2 msec Apr 25 17:00:33 unbound[572:0] debug: svcd callbacks start Apr 25 17:00:33 unbound[572:0] debug: worker svcd callback for qstate 0x55e8c78ff430 Apr 25 17:00:33 unbound[572:0] debug: mesh_run: start Apr 25 17:00:33 unbound[572:0] debug: iterator[module 2] operate: extstate:module_wait_reply event:module_event_reply Apr 25 17:00:33 unbound[572:0] info: iterator operate: query google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: process_response: new external response event Apr 25 17:00:33 unbound[572:0] info: scrub for . NS IN Apr 25 17:00:33 unbound[572:0] info: response for google.com. A IN Apr 25 17:00:33 unbound[572:0] info: reply from <.> 192.5.5.241#53 Apr 25 17:00:33 unbound[572:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr rd ra ; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: com. IN A
;; ANSWER SECTION:
;; AUTHORITY SECTION:
;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 21
Apr 25 17:00:33 unbound[572:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Apr 25 17:00:33 unbound[572:0] info: query response was nodata ANSWER Apr 25 17:00:33 unbound[572:0] debug: iter_handle processing q with state QUERY TARGETS STATE Apr 25 17:00:33 unbound[572:0] info: processQueryTargets: google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 1 Apr 25 17:00:33 unbound[572:0] info: DelegationPoint<.>: 13 names (0 missing), 15 addrs (15 result, 0 avail) cacheNS Apr 25 17:00:33 unbound[572:0] info: f.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: m.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: a.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: i.root-servers.net. * A AAAA Apr 25 17:00:33 unbound[572:0] info: h.root-servers.net. * A AAAA Apr 25 17:00:33 unbound[572:0] info: c.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: g.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: l.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: k.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: e.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: d.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: b.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] info: j.root-servers.net. * A Apr 25 17:00:33 unbound[572:0] debug: ip4 192.58.128.30 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 170.247.170.2 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 199.7.91.13 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 192.203.230.10 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 193.0.14.129 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 199.7.83.42 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 192.112.36.4 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 192.33.4.12 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip6 2001:500:1::53 port 53 (len 28) Apr 25 17:00:33 unbound[572:0] debug: ip4 198.97.190.53 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip6 2001:7fe::53 port 53 (len 28) Apr 25 17:00:33 unbound[572:0] debug: ip4 192.36.148.17 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 198.41.0.4 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 202.12.27.33 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: ip4 192.5.5.241 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: attempt to get extra 3 targets Apr 25 17:00:33 unbound[572:0] debug: rpz: iterator module callback: have_rpz=0 Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.58.128.30 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=265 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 170.247.170.2 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=125 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 199.7.91.13 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=153 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.203.230.10 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=234 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 193.0.14.129 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=243 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 199.7.83.42 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=184 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.112.36.4 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=186 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.33.4.12 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=94 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 198.97.190.53 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=170 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.36.148.17 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=152 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 198.41.0.4 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=238 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 202.12.27.33 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=86 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: servselect ip4 192.5.5.241 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: rtt=147 REC_LAME Apr 25 17:00:33 unbound[572:0] debug: selrtt 360086 Apr 25 17:00:33 unbound[572:0] debug: chase to recursion lame server Apr 25 17:00:33 unbound[572:0] debug: chase to dnssec lame server Apr 25 17:00:33 unbound[572:0] info: sending query: google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: sending to target: <.> 199.7.83.42#53 Apr 25 17:00:33 unbound[572:0] debug: dnssec status: expected but lame_query anyway Apr 25 17:00:33 unbound[572:0] debug: mesh_run: iterator module exit state is module_wait_reply Apr 25 17:00:33 unbound[572:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 2 recu> Apr 25 17:00:33 unbound[572:0] info: average recursion processing time 0.059638 sec Apr 25 17:00:33 unbound[572:0] info: histogram of recursion processing times Apr 25 17:00:33 unbound[572:0] info: [25%]=0 median[50%]=0 [75%]=0 Apr 25 17:00:33 unbound[572:0] info: lower(secs) upper(secs) recursions Apr 25 17:00:33 unbound[572:0] info: 0.032768 0.065536 1 Apr 25 17:00:33 unbound[572:0] info: 0.065536 0.131072 1 Apr 25 17:00:33 unbound[572:0] info: 0RDd mod2 rep google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: cache memory msg=69755 rrset=71977 infra=11966 val=66529 subnet=74504 Apr 25 17:00:33 unbound[572:0] debug: svcd callbacks end Apr 25 17:00:33 unbound[572:0] debug: close of port 27545 Apr 25 17:00:33 unbound[572:0] debug: close fd 12 Apr 25 17:00:33 unbound[572:0] debug: serviced send timer Apr 25 17:00:33 unbound[572:0] debug: EDNS lookup known=1 vs=0 Apr 25 17:00:33 unbound[572:0] debug: serviced query UDP timeout=184 msec Apr 25 17:00:33 unbound[572:0] debug: inserted new pending reply id=6c89 Apr 25 17:00:33 unbound[572:0] debug: opened UDP if=0 port=57731 Apr 25 17:00:33 unbound[572:0] debug: comm point start listening 12 (-1 msec) Apr 25 17:00:33 unbound[572:0] debug: answer cb Apr 25 17:00:33 unbound[572:0] debug: Incoming reply id = 6c89 Apr 25 17:00:33 unbound[572:0] debug: Incoming reply addr = ip4 199.7.83.42 port 53 (len 16) Apr 25 17:00:33 unbound[572:0] debug: lookup size is 1 entries Apr 25 17:00:33 unbound[572:0] debug: received udp reply. Apr 25 17:00:33 unbound[572:0] debug: udp message[55:0] 6C898180000100010000000106676F6F676C6503636F6D0000010001C00C0001000> Apr 25 17:00:33 unbound[572:0] debug: outnet handle udp reply Apr 25 17:00:33 unbound[572:0] debug: measured roundtrip at 15 msec Apr 25 17:00:33 unbound[572:0] debug: svcd callbacks start Apr 25 17:00:33 unbound[572:0] debug: worker svcd callback for qstate 0x55e8c78ff430 Apr 25 17:00:33 unbound[572:0] debug: mesh_run: start Apr 25 17:00:33 unbound[572:0] debug: iterator[module 2] operate: extstate:module_wait_reply event:module_event_reply Apr 25 17:00:33 unbound[572:0] info: iterator operate: query google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: process_response: new external response event Apr 25 17:00:33 unbound[572:0] info: scrub for . NS IN Apr 25 17:00:33 unbound[572:0] info: response for google.com. A IN Apr 25 17:00:33 unbound[572:0] info: reply from <.> 199.7.83.42#53 Apr 25 17:00:33 unbound[572:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0 ;; flags: qr rd ra ; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 0 ;; QUESTION SECTION: google.com. IN A
;; ANSWER SECTION: google.com. 6 IN A 142.250.80.46
;; AUTHORITY SECTION:
;; ADDITIONAL SECTION: ;; MSG SIZE rcvd: 44
Apr 25 17:00:33 unbound[572:0] debug: iter_handle processing q with state QUERY RESPONSE STATE Apr 25 17:00:33 unbound[572:0] info: query response was ANSWER Apr 25 17:00:33 unbound[572:0] debug: iter_handle processing q with state FINISHED RESPONSE STATE Apr 25 17:00:33 unbound[572:0] info: finishing processing for google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: mesh_run: iterator module exit state is module_finished Apr 25 17:00:33 unbound[572:0] debug: validator[module 1] operate: extstate:module_wait_module event:module_event_moddone Apr 25 17:00:33 unbound[572:0] info: validator operate: query google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: validator: nextmodule returned Apr 25 17:00:33 unbound[572:0] debug: val handle processing q with state VAL_INIT_STATE Apr 25 17:00:33 unbound[572:0] debug: validator classification positive Apr 25 17:00:33 unbound[572:0] info: no signer, using google.com. TYPE0 CLASS0 Apr 25 17:00:33 unbound[572:0] debug: val handle processing q with state VAL_FINISHED_STATE Apr 25 17:00:33 unbound[572:0] debug: mesh_run: validator module exit state is module_finished Apr 25 17:00:33 unbound[572:0] debug: subnetcache[module 0] operate: extstate:module_wait_module event:module_event_moddone Apr 25 17:00:33 unbound[572:0] info: subnetcache operate: query google.com. A IN Apr 25 17:00:33 unbound[572:0] debug: reply has edns subnet (null) Apr 25 17:00:33 unbound[572:0] debug: mesh_run: subnetcache module exit state is module_finished Apr 25 17:00:33 unbound[572:0] debug: query took 0.018690 sec Apr 25 17:00:33 unbound[572:0] info: mesh_run: end 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 3 recu> Apr 25 17:00:33 unbound[572:0] info: average recursion processing time 0.045989 sec Apr 25 17:00:33 unbound[572:0] info: histogram of recursion processing times Apr 25 17:00:33 unbound[572:0] info: [25%]=0 median[50%]=0 [75%]=0 Apr 25 17:00:33 unbound[572:0] info: lower(secs) upper(secs) recursions Apr 25 17:00:33 unbound[572:0] info: 0.016384 0.032768 1 Apr 25 17:00:33 unbound[572:0] info: 0.032768 0.065536 1 Apr 25 172D:00:33 unbound[572:0] info: 0.065536 0.131072 1 Apr 25 17:00:33 unbound[572:0] debug: cache memory msg=70007 rrset=72219 infra=11966 val=66529 subnet=74504 Apr 25 17:00:33 unbound[572:0] debug: svcd callbacks end Apr 25 17:00:33 unbound[572:0] debug: close of port 57731 Apr 25 17:00:33 unbound[572:0] debug: close fd 12
Posted Apr 25, 2024, 9:07 PM UTC