Hostname „vuplus-support.org“ wird nicht aufgelöst

drpsycho · June 15, 2020, 6:12pm

Hallo @all,

„vuplus-support.org“ wird nicht mehr aufgelöst.

Pihole v5, alle Updates, Unbound nach Redirecting... installiert.

Bisher keine Probleme, der Name wurde eigentlich immer aufgelöst. Ich weiß nicht genau seit wann er nicht mehr aufgelöst wird, da ich die Url selten ansurfe. Wenn ich einen lokalen DNS Record anlege, dann geht es natürlich.
Alle Dienste neu gestartet, trotzdem Fehler. DNS sonst ohne Probleme.

Wer hat Ideen oder wo kann ich noch ansetzen?
Ggf. kann jemand mit Pihole und Unbound den Fehler nachvollziehen.
Raspi 4, Pihole nativ

Danke euch schon mal.

Wolle

Marcoblue · June 15, 2020, 6:59pm

Ich kann nur sagen, dass die Seite bei mir aufgelöst wird. Ich habe Pihole v5 ebenfalls mit unbound. https://www.vuplus-support.org/ ist übrigens nur über IPv4 erreichbar.

drpsycho · June 15, 2020, 7:58pm

hmmmm...das hab ich erwartet.

Wo könnte mein Fehler sein...?

IPv6 ist off

yubiuser · June 15, 2020, 8:04pm

Um den Fehler einzugrenzen: Wechsele mal den Upstream DNS in Pihole von Unbound auf einen anderen Server.

drpsycho · June 15, 2020, 8:09pm

Done.

Bin jetzt auf Cloudflare und keine lokalen DNS Einträge mehr.
Unbound und Pihole neu gestartet, löst sofort richtig auf.

Wieder zurück auf 127.0.0.1#5335 und restart services => Name wird
nicht mehr aufgelöst.

Ergänzung;

local DNS vuplus-support.org und www. vuplus-support.org eingetragen,
Upstream 127.0.0.1#5335

dig vuplus-support.org @127.0.0.1 -p 5335

; <<>> DiG 9.11.5-P4-5.1+deb10u1-Raspbian <<>> vuplus-support.org @127.0.0.1 -p 5335
;; global options: +cmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: SERVFAIL, id: 38630
;; flags: qr rd ra; QUERY: 1, ANSWER: 0, AUTHORITY: 0, ADDITIONAL: 1

;; OPT PSEUDOSECTION:
; EDNS: version: 0, flags:; udp: 1472
;; QUESTION SECTION:
;vuplus-support.org. IN A

;; Query time: 0 msec
;; SERVER: 127.0.0.1#5335(127.0.0.1)
;; WHEN: Mo Jun 15 23:06:55 CEST 2020
;; MSG SIZE rcvd: 47

yubiuser · June 16, 2020, 8:38am

Das macht in dieser Kombi keinen Sinn. Wenn du Local DNS Einträge anlegst, werden die von Pihole direkt beantwortet. Mit deiner dig Abfrage umgehst du Pihole ja und landest wieder bei Unbound, das da ja schon Probleme mit hat.
Der Grund ist nur: warum? Wie sieht denn deine Unboud config aus?

Ich habe das gleiche Setup und es funktioniert einwandfrei.


chrko@ThinkPad-X230:~$ dig vuplus-support.org

; <<>> DiG 9.11.3-1ubuntu1.12-Ubuntu <<>> vuplus-support.org
;; global options: +cmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 15228
;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 1

;; OPT PSEUDOSECTION:
; EDNS: version: 0, flags:; udp: 1472
;; QUESTION SECTION:
;vuplus-support.org.		IN	A

;; ANSWER SECTION:
vuplus-support.org.	0	IN	A	5.135.209.15

;; Query time: 4 msec
;; SERVER: 10.0.1.5#53(10.0.1.5)
;; WHEN: Tue Jun 16 10:34:40 CEST 2020
;; MSG SIZE  rcvd: 63

Hier scheint auch alles OK zu sein:
https://dnsviz.net/d/vuplus-support.org/dnssec/

Add

Du kannst man in deiner Unbound config das Verbose level hochdrehen und schauen, was da im Hintergrund abläuft.

drpsycho · June 16, 2020, 8:58am

Ok, das wusste ich nicht, dass das keinen Sinn macht. Sorry...
Bin jetzt nicht so der DNS Profi.

Meine Unbound-Config ist genau so wie in Redirecting... beschrieben. Hab’s noch mal geprüft. Ging ja bis vor einiger Zeit.

Wie drehe ich das verbose-level hoch und wie kann ich Dir die notwendigen Infos zukommen lassen?

yubiuser · June 16, 2020, 9:05am

Du schreibst in /etc/unbound/unbound.conf.d/pi-hole.conf einfach

    logfile: "/var/log/unbound/unbound.log"
    log-time-ascii: yes
    verbosity: 2

rein, erstellst die log Datei und ordnest sie dem Nutzer unbound zu.

touch /var/log/unbound/unbound.log
sudo chown /var/log/unbound/unbound.log

startest unbound neu

sudo service unbound restart

Führst die Abfrage aus und lässt dir dann das Log mal ausgeben.

cat /var/log/unbound/unbound.log

drpsycho · June 16, 2020, 9:23am

Danke Dir für Deine Unterstützung. Im Quote oben fehlt vermutlich der User „unbound“. Richtig? Ich habe das so „chown unbound...“ ergänzt.

Logfile und Verzeichnis erstellt, Service restart, mit „dig“ abgefragt aber Logfile bleibt leer.

Vermutlich habe ich noch iwo eine Fehler...

Add:

Fehler gefunden, Logfile-Pfad war in der conf nicht aktiv.

drpsycho · June 16, 2020, 9:30am

Jun 16 11:29:04 unbound[6934:0] info: query response was ANSWER
Jun 16 11:29:04 unbound[6934:0] info: response for webhook.logentries.com. A IN
Jun 16 11:29:04 unbound[6934:0] info: reply from <logentries.com.> 205.251.192.119#53
Jun 16 11:29:04 unbound[6934:0] info: query response was ANSWER
Jun 16 11:29:04 unbound[6934:0] info: prime trust anchor
Jun 16 11:29:04 unbound[6934:0] info: generate keytag query _ta-4f66. NULL IN
Jun 16 11:29:04 unbound[6934:0] info: resolving . DNSKEY IN
Jun 16 11:29:04 unbound[6934:0] info: resolving _ta-4f66. NULL IN
Jun 16 11:29:04 unbound[6934:0] info: response for . DNSKEY IN
Jun 16 11:29:04 unbound[6934:0] info: reply from <.> 193.0.14.129#53
Jun 16 11:29:04 unbound[6934:0] info: query response was ANSWER
Jun 16 11:29:04 unbound[6934:0] info: validate keys with anchor(DS): sec_status_secure
Jun 16 11:29:04 unbound[6934:0] info: Successfully primed trust anchor . DNSKEY IN
Jun 16 11:29:04 unbound[6934:0] info: validated DS com. DS IN
Jun 16 11:29:04 unbound[6934:0] info: resolving com. DNSKEY IN
Jun 16 11:29:04 unbound[6934:0] info: response for _ta-4f66. NULL IN
Jun 16 11:29:04 unbound[6934:0] info: reply from <.> 192.112.36.4#53
Jun 16 11:29:04 unbound[6934:0] info: query response was NXDOMAIN ANSWER
Jun 16 11:29:04 unbound[6934:0] info: response for com. DNSKEY IN
Jun 16 11:29:04 unbound[6934:0] info: reply from <com.> 192.31.80.30#53
Jun 16 11:29:04 unbound[6934:0] info: query response was ANSWER
Jun 16 11:29:04 unbound[6934:0] info: validated DNSKEY com. DNSKEY IN
Jun 16 11:29:04 unbound[6934:0] info: NSEC3s for the referral proved no DS.
Jun 16 11:29:04 unbound[6934:0] info: Verified that unsigned response is INSECURE
Jun 16 11:29:18 unbound[6934:0] info: resolving vuplus-support.org. A IN
Jun 16 11:29:18 unbound[6934:0] info: response for vuplus-support.org. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <org.> 199.19.53.1#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL

Jun 16 11:29:23 unbound[6934:0] info: resolving vuplus-support.org. A IN
Jun 16 11:29:28 unbound[6934:0] info: resolving vuplus-support.org. A IN
Jun 16 11:29:29 unbound[6934:0] info: resolving gateway.fe.apple-dns.net. A IN
Jun 16 11:29:29 unbound[6934:0] info: response for gateway.fe.apple-dns.net. A IN
Jun 16 11:29:29 unbound[6934:0] info: reply from <.> 192.33.4.12#53
Jun 16 11:29:29 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:29 unbound[6934:0] info: response for gateway.fe.apple-dns.net. A IN
Jun 16 11:29:29 unbound[6934:0] info: reply from <net.> 192.54.112.30#53
Jun 16 11:29:29 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:29 unbound[6934:0] info: resolving b.ns.apple.com. A IN
Jun 16 11:29:29 unbound[6934:0] info: resolving c.ns.apple.com. A IN
Jun 16 11:29:29 unbound[6934:0] info: resolving a.ns.apple.com. A IN
Jun 16 11:29:29 unbound[6934:0] info: response for c.ns.apple.com. A IN
Jun 16 11:29:29 unbound[6934:0] info: reply from <com.> 192.54.112.30#53
Jun 16 11:29:29 unbound[6934:0] info: query response was REFERRAL

yubiuser · June 16, 2020, 9:42am

Wie du siehst, ist es wichtig, am Ende das verbosity level wieder zu reduzieren - sonst spammst du dir das Log zu...

Interessant sind

Jun 16 11:29:18 unbound[6934:0] info: resolving vuplus-support.org. A IN
Jun 16 11:29:18 unbound[6934:0] info: response for vuplus-support.org. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <org.> 199.19.53.1#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: resolving 2-can.njalla.in. A IN
Jun 16 11:29:18 unbound[6934:0] info: resolving 3-get.njalla.fo. A IN
Jun 16 11:29:18 unbound[6934:0] info: resolving 1-you.njalla.no. A IN
Jun 16 11:29:18 unbound[6934:0] info: response for 3-get.njalla.fo. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <.> 192.58.128.30#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: response for 2-can.njalla.in. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <.> 193.0.14.129#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: response for 2-can.njalla.in. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <in.> 156.154.101.20#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: resolving ns3.njal.la. A IN
Jun 16 11:29:18 unbound[6934:0] info: resolving ns2.njal.la. A IN
Jun 16 11:29:18 unbound[6934:0] info: response for ns2.njal.la. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <.> 192.5.5.241#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: response for 1-you.njalla.no. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <.> 199.7.83.42#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: response for ns3.njal.la. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <.> 193.0.14.129#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:18 unbound[6934:0] info: response for 1-you.njalla.no. A IN
Jun 16 11:29:18 unbound[6934:0] info: reply from <no.> 158.38.8.133#53
Jun 16 11:29:18 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:21 unbound[6934:0] info: response for 3-get.njalla.fo. A IN
Jun 16 11:29:21 unbound[6934:0] info: reply from <fo.> 212.18.249.27#53
Jun 16 11:29:21 unbound[6934:0] info: query response was REFERRAL
Jun 16 11:29:21 unbound[6934:0] info: resolving ns1.njal.la. A IN
Jun 16 11:29:23 unbound[6934:0] info: resolving vuplus-support.org. A IN
Jun 16 11:29:28 unbound[6934:0] info: resolving vuplus-support.org. A IN

Er bekommt da nur "REFERRAL" aber keine Antwort vom Upstream server.

Du könntest versuchen verbosity noch auf 5 hochzudrehen und schauen, ob es dann noch mehr Infos gibt (dann aber den Log bitte vor dem posten von anderen Anfragen bereinigen).

drpsycho · June 16, 2020, 9:47am

Danke für Deine Antwort.
Verbosity hab ich schon wieder auf 0 gestellt und Log hier auch gekürzt.

Stelle jetzt nochmal auf 5 und melde mich gleich.

Add:

Jun 16 11:48:46 unbound[10389:0] info: 5RDdc mod2 rep vuplus-support.org. A IN
Jun 16 11:48:46 unbound[10389:0] info: 6RDdc mod2 rep vuplus-support.org. A IN
Jun 16 11:48:46 unbound[10389:0] info: 7RDdc mod2 rep vuplus-support.org. A IN
Jun 16 11:48:46 unbound[10389:0] debug: cache memory msg=42869 rrset=113551 infra=24225 val=42114 subnet=41372
Jun 16 11:48:46 unbound[10389:0] debug: svcd callbacks end
Jun 16 11:48:46 unbound[10389:0] debug: close of port 26622
Jun 16 11:48:46 unbound[10389:0] debug: close fd 12
Jun 16 11:48:48 unbound[10389:0] info: service stopped (unbound 1.9.0).
Jun 16 11:48:48 unbound[10389:0] debug: stop threads
Jun 16 11:48:48 unbound[10389:0] debug: cleanup.
Jun 16 11:48:48 unbound[10389:0] info: server stats for thread 0: 7 queries, 0 answers from cache, 7 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jun 16 11:48:48 unbound[10389:0] info: server stats for thread 0: requestlist max 7 avg 4.71429 exceeded 0 jostled 0
Jun 16 11:48:48 unbound[10389:0] info: mesh has 8 recursion states (3 with reply, 0 detached), 3 waiting replies, 4 recursion replies sent, 0 replies dropped, 0 states jostled out
Jun 16 11:48:48 unbound[10389:0] info: average recursion processing time 0.187477 sec
Jun 16 11:48:48 unbound[10389:0] info: histogram of recursion processing times
Jun 16 11:48:48 unbound[10389:0] info: [25%]=0.131072 median[50%]=0.196608 [75%]=0.262144
Jun 16 11:48:48 unbound[10389:0] info: lower(secs) upper(secs) recursions
Jun 16 11:48:48 unbound[10389:0] info: 0.065536 0.131072 1
Jun 16 11:48:48 unbound[10389:0] info: 0.131072 0.262144 2
Jun 16 11:48:48 unbound[10389:0] info: 0.262144 0.524288 1
Jun 16 11:48:48 unbound[10389:0] debug: cache memory msg=33040 rrset=33040 infra=24225 val=42114 subnet=41372
Jun 16 11:48:48 unbound[10389:0] debug: close of port 54422
Jun 16 11:48:48 unbound[10389:0] debug: close fd 13
Jun 16 11:48:48 unbound[10389:0] debug: close of port 60191
Jun 16 11:48:48 unbound[10389:0] debug: close fd 11
Jun 16 11:48:48 unbound[10389:0] debug: Exit cleanup.
Jun 16 11:48:48 unbound[10389:0] debug: switching log to stderr
Jun 16 11:48:48 unbound[10581:0] info: start of service (unbound 1.9.0).

yubiuser · June 16, 2020, 9:57am

Kannst du das repoduzieren, dass der Server nach dieser Anfrage stopped und dann wieder automatisch startet?

Jun 16 11:48:48 unbound[10389:0] info: service stopped (unbound 1.9.0).

Jun 16 11:48:48 unbound[10581:0] info: start of service (unbound 1.9.0)

drpsycho · June 16, 2020, 10:03am

Ich hab nach dem ändern des Verbosity-Level den Unbound neu gestartet.

yubiuser · June 16, 2020, 10:05am

Ok, dann fehlt aber oben im Log noch die Anfrage. Da stehen ja nur die ersten Zeilen drin, müssten ja viel mehr als bei verbosity=2 sein.

drpsycho · June 16, 2020, 10:14am

OK, dann hoffe ich, ist es so richtig:

Jun 16 11:48:21 unbound[10389:0] debug: subnet[module 0] operate: extstate:module_state_initial event:module_event_new
Jun 16 11:48:21 unbound[10389:0] info: subnet operate: query vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: subnet: not found in cache. pass to next module
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: subnet module exit state is module_wait_module
Jun 16 11:48:21 unbound[10389:0] debug: validator[module 1] operate: extstate:module_state_initial event:module_event_pass
Jun 16 11:48:21 unbound[10389:0] info: validator operate: query vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: validator: pass to next module
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: validator module exit state is module_wait_module
Jun 16 11:48:21 unbound[10389:0] debug: iterator[module 2] operate: extstate:module_state_initial event:module_event_pass
Jun 16 11:48:21 unbound[10389:0] debug: process_request: new external request event
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state INIT REQUEST STATE
Jun 16 11:48:21 unbound[10389:0] info: resolving vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: request has dependency depth of 0
Jun 16 11:48:21 unbound[10389:0] debug: cache delegation returns delegpt
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) cacheNS
Jun 16 11:48:21 unbound[10389:0] info:   c.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   g.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   d.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   i.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   e.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   j.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   f.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   b.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   h.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   k.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   l.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   m.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:dc3::35 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 202.12.27.33 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:9f::42 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.7.83.42 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:7fd::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 193.0.14.129 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:503:ba3e::2:30 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 198.41.0.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:1::53 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 198.97.190.53 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:200::b port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.9.14.201 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2f::f port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.5.5.241 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:503:c27::2:30 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.58.128.30 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:a8::e port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.203.230.10 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:7fe::53 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.36.148.17 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2d::d port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.7.91.13 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:12::d0d port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.112.36.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2::c port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.33.4.12 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2)
Jun 16 11:48:21 unbound[10389:0] info: resolving (init part 2):  vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3)
Jun 16 11:48:21 unbound[10389:0] info: resolving (init part 3):  vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state QUERY TARGETS STATE
Jun 16 11:48:21 unbound[10389:0] info: processQueryTargets: vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) cacheNS
Jun 16 11:48:21 unbound[10389:0] info:   c.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   g.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   d.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   i.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   e.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   j.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   f.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   b.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   h.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   k.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   l.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   m.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:dc3::35 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 202.12.27.33 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:9f::42 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.7.83.42 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:7fd::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 193.0.14.129 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:503:ba3e::2:30 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 198.41.0.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:1::53 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 198.97.190.53 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:200::b port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.9.14.201 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2f::f port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.5.5.241 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:503:c27::2:30 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.58.128.30 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:a8::e port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.203.230.10 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:7fe::53 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.36.148.17 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2d::d port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.7.91.13 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:12::d0d port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.112.36.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2::c port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.33.4.12 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: removing 1 labels
Jun 16 11:48:21 unbound[10389:0] debug: attempt to get extra 3 targets
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 199.7.91.13 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=302
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 192.203.230.10 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=311
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 192.5.5.241 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=234
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 199.9.14.201 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=315
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 198.97.190.53 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=302
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 193.0.14.129 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=315
Jun 16 11:48:21 unbound[10389:0] debug: servselect ip4 202.12.27.33 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    rtt=311
Jun 16 11:48:21 unbound[10389:0] debug: selrtt 234
Jun 16 11:48:21 unbound[10389:0] info: sending query: org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: sending to target: <.> 198.41.0.4#53
Jun 16 11:48:21 unbound[10389:0] debug: dnssec status: expected
Jun 16 11:48:21 unbound[10389:0] debug: EDNS lookup known=0 vs=0
Jun 16 11:48:21 unbound[10389:0] debug: serviced query UDP timeout=376 msec
Jun 16 11:48:21 unbound[10389:0] debug: inserted new pending reply id=630f
Jun 16 11:48:21 unbound[10389:0] debug: opened UDP if=0 port=22466
Jun 16 11:48:21 unbound[10389:0] debug: comm point start listening 11
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: iterator module exit state is module_wait_reply
Jun 16 11:48:21 unbound[10389:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 1 recursion replies sent, 0 replies dropped, 0 states jostled out
Jun 16 11:48:21 unbound[10389:0] info: average recursion processing time 0.210348 sec
Jun 16 11:48:21 unbound[10389:0] info: histogram of recursion processing times
Jun 16 11:48:21 unbound[10389:0] info: [25%]=0 median[50%]=0 [75%]=0
Jun 16 11:48:21 unbound[10389:0] info: lower(secs) upper(secs) recursions
Jun 16 11:48:21 unbound[10389:0] info:    0.131072    0.262144 1
Jun 16 11:48:21 unbound[10389:0] info: 0RDd mod2 rep vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: cache memory msg=36829 rrset=60553 infra=9786 val=36693 subnet=41372
Jun 16 11:48:21 unbound[10389:0] debug: answer cb
Jun 16 11:48:21 unbound[10389:0] debug: Incoming reply id = 630f
Jun 16 11:48:21 unbound[10389:0] debug: Incoming reply addr = ip4 198.41.0.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: lookup size is 1 entries
Jun 16 11:48:21 unbound[10389:0] debug: received udp reply.
Jun 16 11:48:21 unbound[10389:0] debug: udp message[805:0] 630F8010000100000009000D036F72670000010001C00C000200010002A3000015026430036F72670B6166696C6961732D6E7374C00CC00C000200010002A3000019026130036F72670B6166696C6961732D6E737404696E666F00C00C000200010002A3000005026330C045C00C000200010002A3000005026132C045C00C000200010002A3000005026230C024C00C000200010002A3000005026232C024C00C002B000100015180001845DB070138C5CF93B369C7557E0515FAAA57060F1BFB12C1C00C002B000100015180002445DB0702D889CAD790F01979E860D6627B58F85AB554E0E491FE06515F35548D1EB4E6EEC00C002E000100015180011300
Jun 16 11:48:21 unbound[10389:0] debug: udp message[805:256] 2B0801000151805EF975505EE843C0BF07004CBC9E7AA5FB901F81EDE75BBF7F06938A76BA85E33637FCB08D82FD7CD51D77BE15E39D384BDA3C049381393E09DA475E4E9BDEFBA8CB6C3BDB72EF509E6A12161EBC22FAF6AE88E425CED762A3A67275FDA8A428C2A8ECC2A653A4C56B6F40D4F7EF1A12FEB4050DCDE09EBB8262A6798DA592A49055FD6CCDC31765D672317DDF5293709EE1CA7C73272EB2919D95E24CD586D048C9D62CF712895B11A423952752D378CCE2BEC99330088FD911B42F5320A11B89B801D8CB41A18D8F73837989253307512B103572A38DC803B7C52AA71946F52095D6F26B27ED75D767F755D3C099D10135A578327545BD74
Jun 16 11:48:21 unbound[10389:0] debug: udp message[805:512] 1079B5A93E35F8DC80421F29D95C3145CB04C021000100010002A3000004C7133901C021001C00010002A300001020010500000F00000000000000000001C042000100010002A3000004C7133801C042001C00010002A300001020010500000E00000000000000000001C067000100010002A3000004C7133501C067001C00010002A300001020010500000B00000000000000000001C078000100010002A3000004C7F97001C078001C00010002A300001020010500004000000000000000000001C089000100010002A3000004C7133601C089001C00010002A300001020010500000C00000000000000000001C09A000100010002A3000004C7F97801C09A
Jun 16 11:48:21 unbound[10389:0] debug: udp message[805:768] 001C00010002A3000010200105000048000000000000000000010000291000000080000000
Jun 16 11:48:21 unbound[10389:0] debug: outnet handle udp reply
Jun 16 11:48:21 unbound[10389:0] debug: serviced query: EDNS works for ip4 198.41.0.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: measured roundtrip at 12 msec
Jun 16 11:48:21 unbound[10389:0] debug: svcd callbacks start
Jun 16 11:48:21 unbound[10389:0] debug: worker svcd callback for qstate 0x215d770
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: start
Jun 16 11:48:21 unbound[10389:0] debug: iterator[module 2] operate: extstate:module_wait_reply event:module_event_reply
Jun 16 11:48:21 unbound[10389:0] info: iterator operate: query vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: process_response: new external response event
Jun 16 11:48:21 unbound[10389:0] info: scrub for . NS IN
Jun 16 11:48:21 unbound[10389:0] info: response for vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] info: reply from <.> 198.41.0.4#53
Jun 16 11:48:21 unbound[10389:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0
;; flags: qr ; QUERY: 1, ANSWER: 0, AUTHORITY: 9, ADDITIONAL: 12 
;; QUESTION SECTION:
org.	IN	A

;; ANSWER SECTION:

;; AUTHORITY SECTION:
org.	86400	IN	NS	d0.org.afilias-nst.org.
org.	86400	IN	NS	a0.org.afilias-nst.info.
org.	86400	IN	NS	c0.org.afilias-nst.info.
org.	86400	IN	NS	a2.org.afilias-nst.info.
org.	86400	IN	NS	b0.org.afilias-nst.org.
org.	86400	IN	NS	b2.org.afilias-nst.org.
org.	86400	IN	DS	17883 7 1 38C5CF93B369C7557E0515FAAA57060F1BFB12C1
org.	86400	IN	DS	17883 7 2 D889CAD790F01979E860D6627B58F85AB554E0E491FE06515F35548D1EB4E6EE
org.	86400	IN	RRSIG	DS 8 1 86400 20200629050000 20200616040000 48903 . TLyeeqX7kB+B7edbv38Gk4p2uoXjNjf8sI2C/XzVHXe+FeOdOEvaPASTgTk+CdpHXk6b3vuoy2w723LvUJ5qEhYevCL69q6I5CXO12KjpnJ1/aikKMKo7MKmU6TFa29A1PfvGhL+tAUNzeCeu4JipnmNpZKkkFX9bM3DF2XWcjF931KTcJ7hynxzJy6ykZ2V4kzVhtBIydYs9xKJWxGkI5UnUtN4zOK+yZMwCI/ZEbQvUyChG4m4AdjLQaGNj3ODeYklMwdRKxA1cqONyAO3xSqnGUb1IJXW8msn7XXXZ/dV08CZ0QE1pXgydUW9dBB5tak+NfjcgEIfKdlcMUXLBA== ;{id = 48903}

;; ADDITIONAL SECTION:
d0.org.afilias-nst.org.	86400	IN	A	199.19.57.1
d0.org.afilias-nst.org.	86400	IN	AAAA	2001:500:f::1
a0.org.afilias-nst.info.	86400	IN	A	199.19.56.1
a0.org.afilias-nst.info.	86400	IN	AAAA	2001:500:e::1
c0.org.afilias-nst.info.	86400	IN	A	199.19.53.1
c0.org.afilias-nst.info.	86400	IN	AAAA	2001:500:b::1
a2.org.afilias-nst.info.	86400	IN	A	199.249.112.1
a2.org.afilias-nst.info.	86400	IN	AAAA	2001:500:40::1
b0.org.afilias-nst.org.	86400	IN	A	199.19.54.1
b0.org.afilias-nst.org.	86400	IN	AAAA	2001:500:c::1
b2.org.afilias-nst.org.	86400	IN	A	199.249.120.1
b2.org.afilias-nst.org.	86400	IN	AAAA	2001:500:48::1
;; MSG SIZE  rcvd: 794

Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
Jun 16 11:48:21 unbound[10389:0] info: query response was REFERRAL
Jun 16 11:48:21 unbound[10389:0] info: found in cache b2.org.afilias-nst.org. A IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache b2.org.afilias-nst.org. AAAA IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache b0.org.afilias-nst.org. A IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache b0.org.afilias-nst.org. AAAA IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache a2.org.afilias-nst.info. A IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache a2.org.afilias-nst.info. AAAA IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache c0.org.afilias-nst.info. A IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache c0.org.afilias-nst.info. AAAA IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache a0.org.afilias-nst.info. A IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache a0.org.afilias-nst.info. AAAA IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache d0.org.afilias-nst.org. A IN
Jun 16 11:48:21 unbound[10389:0] info: found in cache d0.org.afilias-nst.org. AAAA IN
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<org.>: 6 names (0 missing), 12 addrs (0 result, 12 avail) parentNS
Jun 16 11:48:21 unbound[10389:0] info:   b2.org.afilias-nst.org. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   b0.org.afilias-nst.org. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a2.org.afilias-nst.info. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   c0.org.afilias-nst.info. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a0.org.afilias-nst.info. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   d0.org.afilias-nst.org. * A AAAA
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:48::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.249.120.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:c::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.54.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:40::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.249.112.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:b::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.53.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:e::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.56.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:f::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.57.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: cleared outbound list for next round
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state QUERY TARGETS STATE
Jun 16 11:48:21 unbound[10389:0] info: processQueryTargets: vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<org.>: 6 names (0 missing), 12 addrs (0 result, 12 avail) parentNS
Jun 16 11:48:21 unbound[10389:0] info:   b2.org.afilias-nst.org. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   b0.org.afilias-nst.org. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a2.org.afilias-nst.info. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   c0.org.afilias-nst.info. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a0.org.afilias-nst.info. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   d0.org.afilias-nst.org. * A AAAA
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:48::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.249.120.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:c::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.54.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:40::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.249.112.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:b::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.53.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:e::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.56.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:f::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.19.57.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: attempt to get extra 3 targets
Jun 16 11:48:21 unbound[10389:0] debug: selrtt 376
Jun 16 11:48:21 unbound[10389:0] info: sending query: vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: sending to target: <org.> 199.19.53.1#53
Jun 16 11:48:21 unbound[10389:0] debug: dnssec status: expected
Jun 16 11:48:21 unbound[10389:0] debug: EDNS lookup known=0 vs=0
Jun 16 11:48:21 unbound[10389:0] debug: serviced query UDP timeout=376 msec
Jun 16 11:48:21 unbound[10389:0] debug: inserted new pending reply id=a062
Jun 16 11:48:21 unbound[10389:0] debug: opened UDP if=0 port=58649
Jun 16 11:48:21 unbound[10389:0] debug: comm point start listening 12
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: iterator module exit state is module_wait_reply
Jun 16 11:48:21 unbound[10389:0] info: mesh_run: end 1 recursion states (1 with reply, 0 detached), 1 waiting replies, 1 recursion replies sent, 0 replies dropped, 0 states jostled out
Jun 16 11:48:21 unbound[10389:0] info: average recursion processing time 0.210348 sec
Jun 16 11:48:21 unbound[10389:0] info: histogram of recursion processing times
Jun 16 11:48:21 unbound[10389:0] info: [25%]=0 median[50%]=0 [75%]=0
Jun 16 11:48:21 unbound[10389:0] info: lower(secs) upper(secs) recursions
Jun 16 11:48:21 unbound[10389:0] info:    0.131072    0.262144 1
Jun 16 11:48:21 unbound[10389:0] info: 0RDd mod2 rep vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: cache memory msg=36829 rrset=63321 infra=10019 val=36693 subnet=41372
Jun 16 11:48:21 unbound[10389:0] debug: svcd callbacks end
Jun 16 11:48:21 unbound[10389:0] debug: close of port 22466
Jun 16 11:48:21 unbound[10389:0] debug: close fd 11
Jun 16 11:48:21 unbound[10389:0] debug: answer cb
Jun 16 11:48:21 unbound[10389:0] debug: Incoming reply id = a062
Jun 16 11:48:21 unbound[10389:0] debug: Incoming reply addr = ip4 199.19.53.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: lookup size is 1 entries
Jun 16 11:48:21 unbound[10389:0] debug: received udp reply.
Jun 16 11:48:21 unbound[10389:0] debug: udp message[627:0] A062801000010000000700010E7675706C75732D737570706F7274036F72670000010001C00C0002000100015180001105322D63616E066E6A616C6C6102696E00C00C0002000100015180001105332D676574066E6A616C6C6102666F00C00C0002000100015180001105312D796F75066E6A616C6C61026E6F0020683970377537747232753931643076306C6A73396C316769646E703930753368C01B003200010001518000270101000104D399EAAB148A72ADECC64F4DE46030E412E0D6D2ED08D7581D000722000000000290C07B002E000100015180009700320702000151805F0444AB5EE8871BF2D5036F72670070C215AEEAECD0FE3B38B4AA5C31
Jun 16 11:48:21 unbound[10389:0] debug: udp message[627:256] 677BAAC91B0AC738F061B6866275CCEC1D08C315A214C73D08FC5F12C44EED7261EDDBEE1DCAE955961B2D851A920FEF21A299D106A0068BAEE885FBF64C6FE7375F5606F8432EF7A35E91FFEB09A7754ACF6A36C79454146F315BE55ED6E7F4EBCBB5E60C71C6A99E6DB6DFDCBD07EC23D0206F6965747673386A366B6F70326A74726F6F70336E393076656A657661313468C0ED003200010001518000260101000104D399EAAB14C49E235B7BD2744C6680F07CBBB11ADAED9C60DD0006200000000012C172002E000100015180009700320702000151805EFB5A455EDF9CB5F2D5036F7267008BF95D5B982E4522A7B2712C2E5069715CEF48F55D08386F
Jun 16 11:48:21 unbound[10389:0] debug: udp message[627:512] 090B3D8192ABA373CF6662EE6F804C636735FC63E5FC90FBA2E1EAD2B4A4C0FEF849EC31B898E0E39AC2C789AF5E3E285955BBF34470D09C1FC703A24C58561676C9EDD59766128D787C78BEE628257D555E5D89AA28455608B41C97E60A68A29E3D7C6E07224A980000291000000080000000
Jun 16 11:48:21 unbound[10389:0] debug: outnet handle udp reply
Jun 16 11:48:21 unbound[10389:0] debug: serviced query: EDNS works for ip4 199.19.53.1 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: measured roundtrip at 94 msec
Jun 16 11:48:21 unbound[10389:0] debug: svcd callbacks start
Jun 16 11:48:21 unbound[10389:0] debug: worker svcd callback for qstate 0x215d770
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: start
Jun 16 11:48:21 unbound[10389:0] debug: iterator[module 2] operate: extstate:module_wait_reply event:module_event_reply
Jun 16 11:48:21 unbound[10389:0] info: iterator operate: query vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: process_response: new external response event
Jun 16 11:48:21 unbound[10389:0] info: scrub for org. NS IN
Jun 16 11:48:21 unbound[10389:0] info: response for vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] info: reply from <org.> 199.19.53.1#53
Jun 16 11:48:21 unbound[10389:0] info: incoming scrubbed packet: ;; ->>HEADER<<- opcode: QUERY, rcode: NOERROR, id: 0
;; flags: qr ; QUERY: 1, ANSWER: 0, AUTHORITY: 7, ADDITIONAL: 0 
;; QUESTION SECTION:
vuplus-support.org.	IN	A

;; ANSWER SECTION:

;; AUTHORITY SECTION:
vuplus-support.org.	86400	IN	NS	2-can.njalla.in.
vuplus-support.org.	86400	IN	NS	3-get.njalla.fo.
vuplus-support.org.	86400	IN	NS	1-you.njalla.no.
h9p7u7tr2u91d0v0ljs9l1gidnp90u3h.org.	86400	IN	NSEC3	1 1 1 D399EAAB h9parr669t6u8o1gsg9e1lmitk4dem0t NS SOA RRSIG DNSKEY NSEC3PARAM ;{flags: optout}
h9p7u7tr2u91d0v0ljs9l1gidnp90u3h.org.	86400	IN	RRSIG	NSEC3 7 2 86400 20200707094723 20200616084723 62165 org. cMIVrurs0P47OLSqXDFne6rJGwrHOPBhtoZidczsHQjDFaIUxz0I/F8SxE7tcmHt2+4dyulVlhsthRqSD+8hopnRBqAGi67ohfv2TG/nN19WBvhDLvejXpH/6wmndUrPajbHlFQUbzFb5V7W5/Try7XmDHHGqZ5ttt/cvQfsI9A= ;{id = 62165}
oietvs8j6kop2jtroop3n90vejeva14h.org.	86400	IN	NSEC3	1 1 1 D399EAAB oif26mrrq9q4opk0u1ubnc8qrbmpoo6t NS DS RRSIG ;{flags: optout}
oietvs8j6kop2jtroop3n90vejeva14h.org.	86400	IN	RRSIG	NSEC3 7 2 86400 20200630152909 20200609142909 62165 org. i/ldW5guRSKnsnEsLlBpcVzvSPVdCDhvCQs9gZKro3PPZmLub4BMY2c1/GPl/JD7ouHq0rSkwP74SewxuJjg45rCx4mvXj4oWVW780Rw0JwfxwOiTFhWFnbJ7dWXZhKNeHx4vuYoJX1VXl2JqihFVgi0HJfmCmiinj18bgciSpg= ;{id = 62165}

;; ADDITIONAL SECTION:
;; MSG SIZE  rcvd: 616

Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state QUERY RESPONSE STATE
Jun 16 11:48:21 unbound[10389:0] info: query response was REFERRAL
Jun 16 11:48:21 unbound[10389:0] info: negcache insert referral  org. NS IN
Jun 16 11:48:21 unbound[10389:0] info: negcache rr h9p7u7tr2u91d0v0ljs9l1gidnp90u3h.org. NSEC3 IN
Jun 16 11:48:21 unbound[10389:0] info: negcache rr oietvs8j6kop2jtroop3n90vejeva14h.org. NSEC3 IN
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<vuplus-support.org.>: 3 names (3 missing), 0 addrs (0 result, 0 avail) parentNS
Jun 16 11:48:21 unbound[10389:0] info:   1-you.njalla.no. 
Jun 16 11:48:21 unbound[10389:0] info:   3-get.njalla.fo. 
Jun 16 11:48:21 unbound[10389:0] info:   2-can.njalla.in. 
Jun 16 11:48:21 unbound[10389:0] debug: cleared outbound list for next round
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state QUERY TARGETS STATE
Jun 16 11:48:21 unbound[10389:0] info: processQueryTargets: vuplus-support.org. A IN
Jun 16 11:48:21 unbound[10389:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<vuplus-support.org.>: 3 names (3 missing), 0 addrs (0 result, 0 avail) parentNS
Jun 16 11:48:21 unbound[10389:0] info:   1-you.njalla.no. 
Jun 16 11:48:21 unbound[10389:0] info:   3-get.njalla.fo. 
Jun 16 11:48:21 unbound[10389:0] info:   2-can.njalla.in. 
Jun 16 11:48:21 unbound[10389:0] debug: attempt to get extra 3 targets
Jun 16 11:48:21 unbound[10389:0] info: new target 1-you.njalla.no. A IN
Jun 16 11:48:21 unbound[10389:0] info: new target 3-get.njalla.fo. A IN
Jun 16 11:48:21 unbound[10389:0] info: new target 2-can.njalla.in. A IN
Jun 16 11:48:21 unbound[10389:0] debug: no current targets -- waiting for 3 targets to resolve.
Jun 16 11:48:21 unbound[10389:0] debug: mesh_run: iterator module exit state is module_wait_subquery
Jun 16 11:48:21 unbound[10389:0] debug: iterator[module 2] operate: extstate:module_state_initial event:module_event_pass
Jun 16 11:48:21 unbound[10389:0] info: iterator operate: query 2-can.njalla.in. A IN
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state INIT REQUEST STATE
Jun 16 11:48:21 unbound[10389:0] info: resolving 2-can.njalla.in. A IN
Jun 16 11:48:21 unbound[10389:0] debug: request has dependency depth of 1
Jun 16 11:48:21 unbound[10389:0] debug: cache delegation returns delegpt
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) cacheNS
Jun 16 11:48:21 unbound[10389:0] info:   c.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   g.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   d.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   i.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   e.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   j.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   f.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   b.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   h.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   k.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   l.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   m.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:dc3::35 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 202.12.27.33 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:9f::42 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.7.83.42 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:7fd::1 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 193.0.14.129 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:503:ba3e::2:30 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 198.41.0.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:1::53 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 198.97.190.53 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:200::b port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.9.14.201 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2f::f port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.5.5.241 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:503:c27::2:30 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.58.128.30 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:a8::e port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.203.230.10 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:7fe::53 port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.36.148.17 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2d::d port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 199.7.91.13 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:12::d0d port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.112.36.4 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug:    ip6 2001:500:2::c port 53 (len 28)
Jun 16 11:48:21 unbound[10389:0] debug:    ip4 192.33.4.12 port 53 (len 16)
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 2)
Jun 16 11:48:21 unbound[10389:0] info: resolving (init part 2):  2-can.njalla.in. A IN
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state INIT REQUEST STATE (stage 3)
Jun 16 11:48:21 unbound[10389:0] info: resolving (init part 3):  2-can.njalla.in. A IN
Jun 16 11:48:21 unbound[10389:0] debug: iter_handle processing q with state QUERY TARGETS STATE
Jun 16 11:48:21 unbound[10389:0] info: processQueryTargets: 2-can.njalla.in. A IN
Jun 16 11:48:21 unbound[10389:0] debug: processQueryTargets: targetqueries 0, currentqueries 0 sentcount 0
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<.>: 13 names (0 missing), 26 addrs (0 result, 26 avail) cacheNS
Jun 16 11:48:21 unbound[10389:0] info:   c.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   g.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   d.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   i.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   e.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   j.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   f.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   b.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   h.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   a.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   k.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   l.root-servers.net. * A AAAA
Jun 16 11:48:21 unbound[10389:0] info:   m.root-servers.net. * A AAAA

yubiuser · June 16, 2020, 11:35am

Ja, das sieht nach verbosity=5 Output aus

Ich erkenne leider nicht, worin das Problem besteht, dafür reichen meine DNS Kenntnisse auch nicht aus.

Das einzige was m.M. nach komisch aussieht ist:

Jun 16 11:48:21 unbound[10389:0] info: negcache rr oietvs8j6kop2jtroop3n90vejeva14h.org. NSEC
Jun 16 11:48:21 unbound[10389:0] info: DelegationPoint<vuplus-support.org.>: 3 names (3 missing), 0 addrs (0 result, 0 avail) parentN

Aber wie man das löst?

drpsycho · June 16, 2020, 1:03pm

Danke für Deine Einschätzung.

Wie kann ich jetzt jemanden mit KnowHow dazu animieren,
hier mal draufzuschauen?

yubiuser · June 16, 2020, 3:54pm

Vielleicht können wir @Bucking_Horn animieren.

drpsycho · June 16, 2020, 5:54pm

Das würde mich sehr freuen.