Netgate Discussion Forum
    • Categories
    • Recent
    • Tags
    • Popular
    • Users
    • Search
    • Register
    • Login

    Unbound Issue

    Scheduled Pinned Locked Moved General pfSense Questions
    12 Posts 5 Posters 3.7k Views
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • G Offline
      guardian Rebel Alliance
      last edited by

      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
      
      

      If you find my post useful, please give it a thumbs up!
      pfSense 2.7.2-RELEASE

      1 Reply Last reply Reply Quote 0
      • H Offline
        hda
        last edited by

        1st Start with the defaults:

        Network Interfaces - ALL
        Outgoing Network Interfaces - ALL

        1 Reply Last reply Reply Quote 0
        • G Offline
          guardian Rebel Alliance
          last edited by

          @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?

          If you find my post useful, please give it a thumbs up!
          pfSense 2.7.2-RELEASE

          1 Reply Last reply Reply Quote 0
          • K Offline
            kpa
            last edited by

            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.

            1 Reply Last reply Reply Quote 0
            • RonpfSR Offline
              RonpfS
              last edited by

              @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.

              2.4.5-RELEASE-p1 (amd64)
              Intel Core2 Quad CPU Q8400 @ 2.66GHz 8GB
              Backup 0.5_5, Bandwidthd 0.7.4_4, Cron 0.3.7_5, pfBlockerNG-devel 3.0.0_16, Status_Traffic_Totals 2.3.1_1, System_Patches 1.2_5

              1 Reply Last reply Reply Quote 0
              • G Offline
                guardian Rebel Alliance
                last edited by

                @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?

                If you find my post useful, please give it a thumbs up!
                pfSense 2.7.2-RELEASE

                1 Reply Last reply Reply Quote 0
                • RonpfSR Offline
                  RonpfS
                  last edited by

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

                  2.4.5-RELEASE-p1 (amd64)
                  Intel Core2 Quad CPU Q8400 @ 2.66GHz 8GB
                  Backup 0.5_5, Bandwidthd 0.7.4_4, Cron 0.3.7_5, pfBlockerNG-devel 3.0.0_16, Status_Traffic_Totals 2.3.1_1, System_Patches 1.2_5

                  1 Reply Last reply Reply Quote 0
                  • G Offline
                    guardian Rebel Alliance
                    last edited by

                    @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.> 
                    

                    If you find my post useful, please give it a thumbs up!
                    pfSense 2.7.2-RELEASE

                    1 Reply Last reply Reply Quote 0
                    • O Offline
                      OrbitalFactory
                      last edited by

                      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.

                      1 Reply Last reply Reply Quote 0
                      • RonpfSR Offline
                        RonpfS
                        last edited by

                        Maybe in Services / DNS Resolver / General Settings

                        2.4.5-RELEASE-p1 (amd64)
                        Intel Core2 Quad CPU Q8400 @ 2.66GHz 8GB
                        Backup 0.5_5, Bandwidthd 0.7.4_4, Cron 0.3.7_5, pfBlockerNG-devel 3.0.0_16, Status_Traffic_Totals 2.3.1_1, System_Patches 1.2_5

                        1 Reply Last reply Reply Quote 0
                        • O Offline
                          OrbitalFactory
                          last edited by

                          @RonpfS:

                          Maybe in Services / DNS Resolver / General Settings

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

                          1 Reply Last reply Reply Quote 0
                          • O Offline
                            OrbitalFactory
                            last edited by

                            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.

                            1 Reply Last reply Reply Quote 0
                            • First post
                              Last post
                            Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.