Unbound Issue



  • I'm hoping someone can help me troubleshoot an issue with Unbound.

    When I do a Diagnostics / DNS Lookup I get the following:

    Hostname 8.8.8.8
    
    Results
    Result 	Record type
    google-public-dns-a.google.com	PTR
    Timings
    Name server 	Query time
    127.0.0.1	No response
    127.0.0.1	No response
    8.8.8.8	30 msec
    
    

    Unbound is enabled
    Services / DNS Resolver / General Settings

    Enable: Checked Enable DNS resolver
    Network Interfaces - ALL
    Outgoing Network Interfaces - WAN

    System / General Setup
    DNS Server 1 127.0.0.1
    DNS Server 2 8.8.8.8    (Added as a work around for now)

    Firewall
    It doesn't appear to be a firewall issue

    • The only thing the firewall logs show as blocked is IGMP 224.0.0.1

    pfBlockerNG
    pfBlockerNG Disabled for troubleshooting

    pfBlockerNG Disable
    Enable DNSBL Unchecked

    Services / DNS Resolver / General Settings
    I stopped/started/restarted unbound a couple of times with the buttons at the top of the page.

    Checked to see if unbound is running:
    cat /var/run/unbound.pid
    16578

    ps -A | grep 16578
    16578  -  Is      0:00.74 /usr/local/sbin/unbound -c /var/unbound/unbound.conf

    Set the log level to 5

    Status / System Logs / System / General
    General Log Entries.

    
    Feb 17 17:02:18 	dhcpleases 		kqueue error: unkown
    Feb 17 17:01:59 	dhcpleases 		kqueue error: unkown
    Feb 17 17:01:52 	check_reload_status 	Syncing firewall
    Feb 17 17:01:41 	check_reload_status 	Syncing firewall
    Feb 17 16:58:26 	dhcpleases 		kqueue error: unkown
    Feb 17 16:58:20 	check_reload_status 	Syncing firewall
    Feb 17 16:58:13 	dhcpleases 		Could not deliver signal HUP to process because its pidfile (/var/run/unbound.pid) does not exist, No such process.
    Feb 17 16:58:12 	dhcpleases 		Could not deliver signal HUP to process because its pidfile (/var/run/unbound.pid) does not exist, No such process.
    Feb 17 16:58:12 	dhcpleases 		kqueue error: unkown
    Feb 17 16:58:12 	dhcpleases 		Could not deliver signal HUP to process because its pidfile (/var/run/unbound.pid) does not exist, No such process.
    Feb 17 16:58:12 	dhcpleases 		Could not deliver signal HUP to process because its pidfile (/var/run/unbound.pid) does not exist, No such process.
    Feb 17 16:58:05 	check_reload_status 	Syncing firewall 
    

    Status / System Logs / System / DNS Resolver
    750 Matched DNS Resolver Log Entries. (Maximum 750)

    
    Feb 17 17:22:27 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:22:27 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:22:27 	unbound 	16578:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.524288 1.000000 2
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.262144 0.524288 4
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.131072 0.262144 7
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.065536 0.131072 6
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.032768 0.065536 2
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.016384 0.032768 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.008192 0.016384 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:22:27 	unbound 	16578:0 	info: [25%]=0.0846507 median[50%]=0.159159 [75%]=0.278528
    Feb 17 17:22:27 	unbound 	16578:0 	info: histogram of recursion processing times
    Feb 17 17:22:27 	unbound 	16578:0 	info: average recursion processing time 0.196612 sec
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 3: requestlist max 13 avg 1.29167 exceeded 0 jostled 0
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 3: 51 queries, 28 answers from cache, 23 recursions, 1 prefetch
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.524288 1.000000 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.262144 0.524288 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.131072 0.262144 4
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.065536 0.131072 4
    Feb 17 17:22:27 	unbound 	16578:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:22:27 	unbound 	16578:0 	info: [25%]=0.106496 median[50%]=0.16384 [75%]=0.24576
    Feb 17 17:22:27 	unbound 	16578:0 	info: histogram of recursion processing times
    Feb 17 17:22:27 	unbound 	16578:0 	info: average recursion processing time 0.202723 sec
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 2: 17 queries, 7 answers from cache, 10 recursions, 1 prefetch
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.524288 1.000000 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.262144 0.524288 4
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.131072 0.262144 6
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.065536 0.131072 6
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.032768 0.065536 2
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.016384 0.032768 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:22:27 	unbound 	16578:0 	info: [25%]=0.0873813 median[50%]=0.152917 [75%]=0.262144
    Feb 17 17:22:27 	unbound 	16578:0 	info: histogram of recursion processing times
    Feb 17 17:22:27 	unbound 	16578:0 	info: average recursion processing time 0.173151 sec
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 1: requestlist max 5 avg 0.5 exceeded 0 jostled 0
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 1: 52 queries, 32 answers from cache, 20 recursions, 2 prefetch
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.524288 1.000000 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.262144 0.524288 3
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.131072 0.262144 7
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.065536 0.131072 7
    Feb 17 17:22:27 	unbound 	16578:0 	info: 0.032768 0.065536 1
    Feb 17 17:22:27 	unbound 	16578:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:22:27 	unbound 	16578:0 	info: [25%]=0.100645 median[50%]=0.159159 [75%]=0.248101
    Feb 17 17:22:27 	unbound 	16578:0 	info: histogram of recursion processing times
    Feb 17 17:22:27 	unbound 	16578:0 	info: average recursion processing time 0.184947 sec
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 0: requestlist max 6 avg 0.714286 exceeded 0 jostled 0
    Feb 17 17:22:27 	unbound 	16578:0 	info: server stats for thread 0: 36 queries, 17 answers from cache, 19 recursions, 2 prefetch
    Feb 17 17:22:27 	unbound 	16578:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:18 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:18 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:02:18 	unbound 	16578:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:18 	unbound 	16578:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:18 	unbound 	16578:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:18 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:17 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:02:17 	unbound 	16578:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:17 	unbound 	16578:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:17 	unbound 	16578:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:16 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:16 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:02:16 	unbound 	16578:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:16 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:16 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:02:16 	unbound 	16578:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:16 	unbound 	16578:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:16 	unbound 	16578:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:15 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:15 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:02:15 	unbound 	16578:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:15 	unbound 	16578:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:15 	unbound 	16578:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:15 	unbound 	16578:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:15 	unbound 	16578:0 	notice: init module 0: iterator
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:13 	unbound 	4894:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:13 	unbound 	4894:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:05 	unbound 	4894:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:05 	unbound 	4894:0 	notice: init module 0: iterator
    Feb 17 17:02:05 	unbound 	4894:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:05 	unbound 	4894:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:05 	unbound 	4894:0 	notice: init module 0: iterator
    Feb 17 17:02:05 	unbound 	4894:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:05 	unbound 	4894:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:05 	unbound 	4894:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:04 	unbound 	4894:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:04 	unbound 	4894:0 	notice: init module 0: iterator
    Feb 17 17:02:04 	unbound 	4894:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:04 	unbound 	4894:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:04 	unbound 	4894:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:02:04 	unbound 	4894:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:02:04 	unbound 	4894:0 	notice: init module 0: iterator
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:02:02 	unbound 	85108:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:02:02 	unbound 	85108:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:01:59 	unbound 	85108:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:01:59 	unbound 	85108:0 	notice: init module 0: iterator
    Feb 17 17:01:59 	unbound 	85108:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:01:59 	unbound 	85108:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:01:59 	unbound 	85108:0 	notice: init module 0: iterator
    Feb 17 17:01:59 	unbound 	85108:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:59 	unbound 	85108:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:59 	unbound 	85108:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:01:58 	unbound 	85108:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:01:58 	unbound 	85108:0 	notice: init module 0: iterator
    Feb 17 17:01:58 	unbound 	85108:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:01:58 	unbound 	85108:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:01:58 	unbound 	85108:0 	notice: init module 0: iterator
    Feb 17 17:01:58 	unbound 	85108:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:58 	unbound 	85108:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:58 	unbound 	85108:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:01:57 	unbound 	85108:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:01:57 	unbound 	85108:0 	notice: init module 0: iterator
    Feb 17 17:01:57 	unbound 	85108:0 	notice: Restart of unbound 1.5.9.
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:56 	unbound 	85108:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 17:01:56 	unbound 	85108:0 	info: service stopped (unbound 1.5.9).
    Feb 17 17:01:56 	unbound 	85108:0 	info: start of service (unbound 1.5.9).
    Feb 17 17:01:56 	unbound 	85108:0 	notice: init module 0: iterator
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.065536 0.131072 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.032768 0.065536 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:01:55 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 17:01:55 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 17:01:55 	unbound 	40468:0 	info: average recursion processing time 0.188448 sec
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 3: 5 queries, 2 answers from cache, 3 recursions, 0 prefetch
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.131072 0.262144 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:01:55 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 17:01:55 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 17:01:55 	unbound 	40468:0 	info: average recursion processing time 0.271235 sec
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 2: 6 queries, 4 answers from cache, 2 recursions, 0 prefetch
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.524288 1.000000 2
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.065536 0.131072 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.032768 0.065536 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:01:55 	unbound 	40468:0 	info: [25%]=0.065536 median[50%]=0.131072 [75%]=0.762144
    Feb 17 17:01:55 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 17:01:55 	unbound 	40468:0 	info: average recursion processing time 0.319933 sec
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 1 avg 0.25 exceeded 0 jostled 0
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 1: 5 queries, 1 answers from cache, 4 recursions, 0 prefetch
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.262144 0.524288 3
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.131072 0.262144 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: 0.065536 0.131072 1
    Feb 17 17:01:55 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 17:01:55 	unbound 	40468:0 	info: [25%]=0.16384 median[50%]=0.305835 [75%]=0.415061
    Feb 17 17:01:55 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 17:01:55 	unbound 	40468:0 	info: average recursion processing time 0.286239 sec
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 3 avg 1 exceeded 0 jostled 0
    Feb 17 17:01:55 	unbound 	40468:0 	info: server stats for thread 0: 10 queries, 5 answers from cache, 5 recursions, 0 prefetch
    Feb 17 17:01:55 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:59:22 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:59:22 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:59:22 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:59:22 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:59:22 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:59:22 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:59:22 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:59:22 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:22 	unbound 	40468:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:22 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:59:03 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:59:03 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:59:03 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:59:03 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:59:03 	unbound 	40468:0 	info: 0.524288 1.000000 1
    Feb 17 16:59:03 	unbound 	40468:0 	info: 0.131072 0.262144 1
    Feb 17 16:59:03 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:59:03 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:59:03 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:59:03 	unbound 	40468:0 	info: average recursion processing time 0.354360 sec
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 6 avg 3 exceeded 0 jostled 0
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 2: 2 queries, 0 answers from cache, 2 recursions, 0 prefetch
    Feb 17 16:59:03 	unbound 	40468:0 	info: 0.524288 1.000000 1
    Feb 17 16:59:03 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:59:03 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:59:03 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:59:03 	unbound 	40468:0 	info: average recursion processing time 0.536338 sec
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 1: 1 queries, 0 answers from cache, 1 recursions, 0 prefetch
    Feb 17 16:59:03 	unbound 	40468:0 	info: 0.524288 1.000000 1
    Feb 17 16:59:03 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:59:03 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:59:03 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:59:03 	unbound 	40468:0 	info: average recursion processing time 0.563459 sec
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:59:03 	unbound 	40468:0 	info: server stats for thread 0: 1 queries, 0 answers from cache, 1 recursions, 0 prefetch
    Feb 17 16:59:03 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:45 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:45 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:58:45 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:58:45 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:45 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 16:58:45 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:58:45 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:58:45 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:58:45 	unbound 	40468:0 	info: average recursion processing time 0.313344 sec
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 2: 1 queries, 0 answers from cache, 1 recursions, 0 prefetch
    Feb 17 16:58:45 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 16:58:45 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:58:45 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:58:45 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:58:45 	unbound 	40468:0 	info: average recursion processing time 0.333438 sec
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 1: 1 queries, 0 answers from cache, 1 recursions, 0 prefetch
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:45 	unbound 	40468:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:45 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:35 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:34 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:58:34 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:58:34 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:34 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 16:58:34 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:58:34 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:58:34 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:58:34 	unbound 	40468:0 	info: average recursion processing time 0.393248 sec
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 3: 1 queries, 0 answers from cache, 1 recursions, 0 prefetch
    Feb 17 16:58:34 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 16:58:34 	unbound 	40468:0 	info: 0.032768 0.065536 1
    Feb 17 16:58:34 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:58:34 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:58:34 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:58:34 	unbound 	40468:0 	info: average recursion processing time 0.248824 sec
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 16 avg 8 exceeded 0 jostled 0
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 2: 2 queries, 0 answers from cache, 2 recursions, 0 prefetch
    Feb 17 16:58:34 	unbound 	40468:0 	info: 0.262144 0.524288 1
    Feb 17 16:58:34 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:58:34 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:58:34 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:58:34 	unbound 	40468:0 	info: average recursion processing time 0.322017 sec
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 1: 1 queries, 0 answers from cache, 1 recursions, 0 prefetch
    Feb 17 16:58:34 	unbound 	40468:0 	info: 0.262144 0.524288 2
    Feb 17 16:58:34 	unbound 	40468:0 	info: lower(secs) upper(secs) recursions
    Feb 17 16:58:34 	unbound 	40468:0 	info: [25%]=0 median[50%]=0 [75%]=0
    Feb 17 16:58:34 	unbound 	40468:0 	info: histogram of recursion processing times
    Feb 17 16:58:34 	unbound 	40468:0 	info: average recursion processing time 0.431051 sec
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 2 avg 1 exceeded 0 jostled 0
    Feb 17 16:58:34 	unbound 	40468:0 	info: server stats for thread 0: 2 queries, 0 answers from cache, 2 recursions, 0 prefetch
    Feb 17 16:58:34 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:30 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:30 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:58:30 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:58:30 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:30 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:30 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:58:30 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:58:30 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:30 	unbound 	40468:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:30 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:29 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:29 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:58:29 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:58:29 	unbound 	40468:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:29 	unbound 	40468:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:29 	unbound 	40468:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:29 	unbound 	40468:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:29 	unbound 	40468:0 	notice: init module 1: iterator
    Feb 17 16:58:29 	unbound 	40468:0 	notice: init module 0: validator
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:28 	unbound 	19899:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:28 	unbound 	19899:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:26 	unbound 	19899:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:26 	unbound 	19899:0 	notice: init module 1: iterator
    Feb 17 16:58:26 	unbound 	19899:0 	notice: init module 0: validator
    Feb 17 16:58:26 	unbound 	19899:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:26 	unbound 	19899:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:26 	unbound 	19899:0 	notice: init module 1: iterator
    Feb 17 16:58:26 	unbound 	19899:0 	notice: init module 0: validator
    Feb 17 16:58:26 	unbound 	19899:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:26 	unbound 	19899:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:26 	unbound 	19899:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:24 	unbound 	19899:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:24 	unbound 	19899:0 	notice: init module 1: iterator
    Feb 17 16:58:24 	unbound 	19899:0 	notice: init module 0: validator
    Feb 17 16:58:24 	unbound 	19899:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:24 	unbound 	19899:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:24 	unbound 	19899:0 	notice: init module 1: iterator
    Feb 17 16:58:24 	unbound 	19899:0 	notice: init module 0: validator
    Feb 17 16:58:24 	unbound 	19899:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:24 	unbound 	19899:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:24 	unbound 	19899:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:23 	unbound 	19899:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:23 	unbound 	19899:0 	notice: init module 1: iterator
    Feb 17 16:58:23 	unbound 	19899:0 	notice: init module 0: validator
    Feb 17 16:58:23 	unbound 	19899:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:23 	unbound 	19899:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:23 	unbound 	19899:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:23 	unbound 	19899:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:23 	unbound 	19899:0 	notice: init module 1: iterator
    Feb 17 16:58:23 	unbound 	19899:0 	notice: init module 0: validator
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:22 	unbound 	246:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:22 	unbound 	246:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:20 	unbound 	246:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:20 	unbound 	246:0 	notice: init module 1: iterator
    Feb 17 16:58:20 	unbound 	246:0 	notice: init module 0: validator
    Feb 17 16:58:20 	unbound 	246:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:20 	unbound 	246:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:20 	unbound 	246:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:15 	unbound 	246:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:15 	unbound 	246:0 	notice: init module 1: iterator
    Feb 17 16:58:15 	unbound 	246:0 	notice: init module 0: validator
    Feb 17 16:58:15 	unbound 	246:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:15 	unbound 	246:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:15 	unbound 	246:0 	notice: init module 1: iterator
    Feb 17 16:58:15 	unbound 	246:0 	notice: init module 0: validator
    Feb 17 16:58:15 	unbound 	246:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:15 	unbound 	246:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:15 	unbound 	246:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:13 	unbound 	246:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:13 	unbound 	246:0 	notice: init module 1: iterator
    Feb 17 16:58:13 	unbound 	246:0 	notice: init module 0: validator
    Feb 17 16:58:13 	unbound 	246:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:13 	unbound 	246:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:13 	unbound 	246:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:13 	unbound 	246:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:13 	unbound 	246:0 	notice: init module 1: iterator
    Feb 17 16:58:13 	unbound 	246:0 	notice: init module 0: validator
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:12 	unbound 	44536:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:12 	unbound 	44536:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:11 	unbound 	44536:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:11 	unbound 	44536:0 	notice: init module 1: iterator
    Feb 17 16:58:11 	unbound 	44536:0 	notice: init module 0: validator
    Feb 17 16:58:11 	unbound 	44536:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:11 	unbound 	44536:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:11 	unbound 	44536:0 	notice: init module 1: iterator
    Feb 17 16:58:11 	unbound 	44536:0 	notice: init module 0: validator
    Feb 17 16:58:11 	unbound 	44536:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:11 	unbound 	44536:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:11 	unbound 	44536:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:09 	unbound 	44536:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:09 	unbound 	44536:0 	notice: init module 1: iterator
    Feb 17 16:58:09 	unbound 	44536:0 	notice: init module 0: validator
    Feb 17 16:58:09 	unbound 	44536:0 	notice: Restart of unbound 1.5.9.
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 1: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:09 	unbound 	44536:0 	info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:09 	unbound 	44536:0 	info: service stopped (unbound 1.5.9).
    Feb 17 16:58:09 	unbound 	44536:0 	info: start of service (unbound 1.5.9).
    Feb 17 16:58:09 	unbound 	44536:0 	notice: init module 1: iterator
    Feb 17 16:58:09 	unbound 	44536:0 	notice: init module 0: validator
    Feb 17 16:58:08 	unbound 	7895:0 	info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:08 	unbound 	7895:0 	info: server stats for thread 3: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:08 	unbound 	7895:0 	info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
    Feb 17 16:58:08 	unbound 	7895:0 	info: server stats for thread 2: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch
    Feb 17 16:58:08 	unbound 	7895:0 	info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
    
    


  • 1st Start with the defaults:

    Network Interfaces - ALL
    Outgoing Network Interfaces - ALL



  • @hda:

    1st Start with the defaults:

    Network Interfaces - ALL
    Outgoing Network Interfaces - ALL

    I'm a bit confused as t why  Outgoing Network Interfaces - ALL?  Isn't the normal default WAN?

    I did however make the change you suggested and then restarted Unbound.

    I got the following log entries:

    Feb 17 18:25:08 	dhcpleases 		kqueue error: unkown
    Feb 17 18:25:05 	dhcpleases 		Could not deliver signal HUP to process because its pidfile (/var/run/unbound.pid) does not exist, No such process.
    Feb 17 18:24:50 	dhcpleases 		kqueue error: unkown
    Feb 17 18:24:42 	check_reload_status 		Syncing firewall 
    

    I find this message Could not deliver signal HUP to process because its pidfile (/var/run/unbound.pid) does not exist, No such process. very confusing because I just ran:

    cat /var/run/unbound.pid
    83643

    root: ps 83643
      PID TT  STAT    TIME COMMAND
    83643  -  Ss  0:01.31 /usr/local/sbin/unbound -c /var/unbound/unbound.conf

    so both the file exists and task 83643 is unbound.

    I'm wondering if this gives someone a clue?



  • The outgoing interface selection will not make a difference unless you have multiple WANs and/or some custom static routes, the outgoing connections will follow the default gateway setting and use the WAN interface anyway in your case.



  • @guardian:

    Unbound is enabled
    Services / DNS Resolver / General Settings

    Enable: Checked Enable DNS resolver
    Network Interfaces - ALL
    Outgoing Network Interfaces - WAN

    System / General Setup
    DNS Server 1 127.0.0.1
    DNS Server 2 8.8.8.8    (Added as a work around for now)

    Why don't you leave the DNS Server field empty in General setup? Unless you use Unbound in Forwarding mode.

    As for the Output interface, I only specified WAN on my setup.

    You should use the [ code] [ /code] when you put log.



  • @RonpfS:

    Why don't you leave the DNS Server field empty in General setup? Unless you use Unbound in Forwarding mode.

    As for the Output interface, I only specified WAN on my setup.

    Thanks for the reply….

    I put it back to Outgoing Network Interfaces - WAN

    and I tried with:

    DNS Server 1 8.8.8.8  - WAN

    and got the same result.

    Diagnostics / DNS Lookup
    
    DNS Lookup
    Hostname
    Results
    Result 	Record type
    google-public-dns-a.google.com	PTR
    Timings
    Name server 	Query time
    127.0.0.1	No response
    8.8.8.8	32 msec
    
    

    I also tried with NO servers at all and got nothing.

    It appears that the resolver is doing nothing but forwarding.

    @RonpfS:

    You should use the [ code] [ /code] when you put log.

    Fixed… Thanks for point that out.  Tags got mangled when I pasted in the data.

    Anything else I can do to diagnose?  Maybe at the command line?

    Any way of knowing if the issue is a traffic flow problem, a mangled config file, or if the resolver is hosed?



  • Search "dhcpleases kqueue" ===> https://forum.pfsense.org/index.php?topic=111178.0



  • @RonpfS:

    Search "dhcpleases kqueue" ===> https://forum.pfsense.org/index.php?topic=111178.0

    Thanks for that… I'm not 100% sure what it means, but I have changed the bindings to all.  FYI None of the interfaces on my system have IPv6 bindings.

    The resolver seems to be working based on the log info, but for some reason the results are not available to the webgui or the shell.

    For example dig produces the following error net.c:592: sendmsg() failed: Can't assign requested address, but ultimately gets a result from the alternate source by DNS forwarding.

    At this point I don't know what the next step is.

    [2.3.2-RELEASE][root@pfsense]/root: dig google.com
    net.c:592: sendmsg() failed: Can't assign requested address
    
    ; <<>> DiG 9.10.4-P2 <<>> google.com
    ;; global options: +cmd
    ;; Got answer:
    ;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 34282
    ;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 1
    
    ;; OPT PSEUDOSECTION:
    ; EDNS: version: 0, flags:; udp: 512
    ;; QUESTION SECTION:
    ;google.com.                    IN      A
    
    ;; ANSWER SECTION:
    google.com.             155     IN      A       172.217.1.110
    
    ;; Query time: 54 msec
    ;; SERVER: 8.8.8.8#53(8.8.8.8)
    ;; WHEN: Sat Feb 18 00:47:36 EST 2017
    ;; MSG SIZE  rcvd: 55
    
    

    Log info
    1000 Matched DNS Resolver Log Entries. (Maximum 1000)

    
    Feb 18 00:28:56 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:56 	unbound 	35619:1 	info: validator operate: query l.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:56 	unbound 	35619:1 	info: finishing processing for l.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:56 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:56 	unbound 	35619:1 	info: response for l.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	info: iterator operate: query l.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:56 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:56 	unbound 	35619:1 	info: validator operate: query m.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:56 	unbound 	35619:1 	info: finishing processing for m.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:56 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:56 	unbound 	35619:1 	info: response for m.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	info: iterator operate: query m.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:56 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:56 	unbound 	35619:1 	info: validator operate: query c.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:56 	unbound 	35619:1 	info: finishing processing for c.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:56 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:56 	unbound 	35619:1 	info: response for c.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	info: iterator operate: query c.gtld-servers.net. AAAA IN
    Feb 18 00:28:56 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:0 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:0 	info: reply from <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:0 	info: response for k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request l.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <nstld.com.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <nstld.com.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <nstld.com.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request av3.nstld.com. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was REFERRAL
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <com.>192.52.178.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:0 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:0 	info: processQueryTargets: k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query av1.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for av1.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:0 	info: reply from <nstld.com.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:0 	info: response for av1.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query av1.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request j.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was REFERRAL
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <net.>192.52.178.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av4.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av3.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av3.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av2.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av2.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av1.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sanitize: removing potential poison RRset: av1.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:0 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:0 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:0 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:0 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query www.pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: validated DNSKEY org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validated DNSKEY org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: resolving org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: validated DS org. DS IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: validated DS org. DS IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query www.pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: Successfully primed trust anchor . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validate keys with anchor(DS): sec_status_secure
    Feb 18 00:28:55 	unbound 	35619:0 	info: Successfully primed trust anchor . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validate keys with anchor(DS): sec_status_secure
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:0 	info: reply from <.> 192.228.79.201#53
    Feb 18 00:28:55 	unbound 	35619:0 	info: response for . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:2 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query www.php.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validated DNSKEY net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <net.>192.48.79.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <nstld.com.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request f.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query f.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query av3.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for av3.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <nstld.com.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for av3.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query av3.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:2 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query www.pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validated DNSKEY org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <org.>199.249.120.1#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query geo1.easydns.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for geo1.easydns.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <easydns.com.>162.159.24.53#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for geo1.easydns.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query geo1.easydns.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request e.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request k.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77452 rrset=103819 infra=29985 val=73981
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <gtld-servers.net.>192.82.134.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:2 	debug: request i.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query i.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query av4.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for av4.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <nstld.com.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for av4.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query www.php.net. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query av4.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	info: validated DNSKEY net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <net.>192.41.162.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:3 	debug: cache memory msg=77271 rrset=102917 infra=29985 val=72918
    Feb 18 00:28:55 	unbound 	35619:3 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:3 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:3 	info: validator operate: query www.pfsense.org. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:3 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:3 	info: validated DNSKEY org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77271 rrset=102917 infra=29985 val=72918
    Feb 18 00:28:55 	unbound 	35619:1 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:1 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query pfsense.org. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:3 	info: validator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:3 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:3 	info: finishing processing for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:3 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:3 	info: reply from <org.>199.249.112.1#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: Verified that unsigned response is INSECURE
    Feb 18 00:28:55 	unbound 	35619:3 	info: response for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: NSEC3s for the referral proved no DS.
    Feb 18 00:28:55 	unbound 	35619:3 	info: iterator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:3 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query www.pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: validated DNSKEY org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: validated DNSKEY org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <org.>199.249.120.1#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=77090 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query h.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for h.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for h.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query h.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77090 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77090 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=77090 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query j.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: validator operate: query e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: finishing processing for e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: reply from <gtld-servers.net.>192.42.177.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: response for e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query e.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:0 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:0 	info: reply from <netgate.com.>192.207.126.6#53
    Feb 18 00:28:55 	unbound 	35619:0 	info: response for ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <netgate.com.>192.207.126.6#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query ns1.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query ns2.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for ns2.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <netgate.com.>162.208.119.38#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for ns2.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query ns2.netgate.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.42.178.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query d.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.42.178.30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=76894 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: query response was nodata ANSWER
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: reply from <gtld-servers.net.>192.42.178.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	info: response for g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	debug: cache memory msg=76698 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: finishing processing for c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	info: iterator operate: query c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <net.>192.48.79.30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	info: new target c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: new target g.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: new target m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request c.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving (init part 3): net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query c.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving (init part 2): net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query net. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <org.>199.249.120.1#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: error sending query to auth server 2001:500:b::1 port 53
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: sending to target: <org.>2001:500:b::1#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: sending query: org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: processQueryTargets: org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving (init part 3): org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving (init part 2): org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	info: resolving org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:0 	debug: cache memory msg=76698 rrset=101077 infra=29985 val=70913
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query org. DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: processQueryTargets: k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: validated DS net. DS IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query www.php.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query av2.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: validated DS org. DS IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:2 	info: validator operate: query www.pfsense.org. A IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for av2.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	info: resolving av2.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request m.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:2 	info: Successfully primed trust anchor . DNSKEY IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query av2.nstld.com. A IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:2 	info: validate keys with anchor(DS): sec_status_secure
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	info: iterator operate: query m.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: validator operate: query av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: iterator[module 1] operate: extstate:module_wait_subquery event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:0 	debug: validator[module 0] operate: extstate:module_state_initial event:module_event_moddone
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request l.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:0 	info: finishing processing for av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: resolving av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:0 	info: iterator operate: query av4.nstld.com. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request l.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:0 	debug: sending to target: <gtld-servers.net.>192.82.133.30#53
    Feb 18 00:28:55 	unbound 	35619:0 	info: sending query: k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:0 	info: processQueryTargets: k.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: sending query: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:0 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:1 	debug: request l.gtld-servers.net. has exceeded the maximum number of glue fetches 65
    Feb 18 00:28:55 	unbound 	35619:1 	info: processQueryTargets: l.gtld-servers.net. AAAA IN
    Feb 18 00:28:55 	unbound 	35619:1 	info: error sending query to auth server 2001:500:126::30 port 53
    Feb 18 00:28:55 	unbound 	35619:0 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53
    Feb 18 00:28:55 	unbound 	35619:1 	debug: sending to target: <gtld-servers.net.>2001:500:126::30#53</gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></org.></gtld-servers.net.></org.></gtld-servers.net.></gtld-servers.net.></net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></netgate.com.></netgate.com.></netgate.com.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></org.></org.></net.></nstld.com.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></easydns.com.></org.></nstld.com.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></nstld.com.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></net.></net.></gtld-servers.net.></nstld.com.></com.></nstld.com.></gtld-servers.net.></nstld.com.></gtld-servers.net.></nstld.com.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.></gtld-servers.net.> 
    


  • Hi, I read that thread but I do not know where to go to make the change to ALL.

    "That's an issue with link local IPv6 IPs that are specifically bound. If you change your binding to "all", that won't happen. "

    I'm having a similar experience where  DNSBL looks to be set correctly and list have been downloaded but it is not performing. I've checked the DNS resolver settings and they look good too … read a lot of the forum posts but I am still stuck.

    Thanks.



  • Maybe in Services / DNS Resolver / General Settings



  • @RonpfS:

    Maybe in Services / DNS Resolver / General Settings

    Thanks, I checked that a number of times and did not see any option like that.



  • If this is it then it looks okay I believe.

    /

    Unbound Configuration

    ##########################

    Server configuration

    server:

    chroot: /var/unbound
    username: "unbound"
    directory: "/var/unbound"
    pidfile: "/var/run/unbound.pid"
    use-syslog: yes
    port: 53
    verbosity: 1
    hide-identity: yes
    hide-version: yes
    harden-glue: yes
    do-ip4: yes
    do-ip6: yes
    do-udp: yes
    do-tcp: yes

    /code

    Thanks.


Log in to reply