Unbound restarting every 23 mins after upgrade to 21.01 on SG1000
-
Hi all. After upgrading pfSense to:
22.01-RELEASE (arm)
built on Mon Feb 07 16:39:01 UTC 2022
FreeBSD 12.3-STABLEOn my SG1000 HW, I'm seeing this in my DNS Logs, restart of unbound every 23 minutes. I don't have any packages installed due to the limit HW capacity.
Mar 10 09:53:09 unbound 87907 [87907:0] info: start of service (unbound 1.13.2).
Mar 10 09:53:08 unbound 87907 [87907:0] notice: init module 1: iterator
Mar 10 09:53:08 unbound 87907 [87907:0] notice: init module 0: validator
Mar 10 09:30:19 unbound 16586 [16586:0] info: start of service (unbound 1.13.2).
Mar 10 09:30:19 unbound 16586 [16586:0] notice: init module 1: iterator
Mar 10 09:30:19 unbound 16586 [16586:0] notice: init module 0: validator
Mar 10 09:16:02 unbound 12669 [12669:0] info: start of service (unbound 1.13.2).
Mar 10 09:16:01 unbound 12669 [12669:0] notice: init module 1: iterator
Mar 10 09:16:01 unbound 12669 [12669:0] notice: init module 0: validatorI also have been having a persistent problem of LONG DNS resoulution times (seconds to 10's of seconds each), which I have tried to mitigate with automatic refreshing of DNS cache entries. Here is an example of those logs:
Mar 10 09:11:54 unbound 73151 [73151:0] info: 512.000000 1024.000000 102
Mar 10 09:11:54 unbound 73151 [73151:0] info: 256.000000 512.000000 2275
Mar 10 09:11:53 unbound 73151 [73151:0] info: 128.000000 256.000000 1450
Mar 10 09:11:53 unbound 73151 [73151:0] info: 64.000000 128.000000 854
Mar 10 09:11:53 unbound 73151 [73151:0] info: 32.000000 64.000000 404
Mar 10 09:11:53 unbound 73151 [73151:0] info: 16.000000 32.000000 210
Mar 10 09:11:53 unbound 73151 [73151:0] info: 8.000000 16.000000 129
Mar 10 09:11:53 unbound 73151 [73151:0] info: 4.000000 8.000000 242
Mar 10 09:11:53 unbound 73151 [73151:0] info: 2.000000 4.000000 510
Mar 10 09:11:53 unbound 73151 [73151:0] info: 1.000000 2.000000 1596
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.524288 1.000000 6231
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.262144 0.524288 20750
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.131072 0.262144 34430
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.065536 0.131072 40097
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.032768 0.065536 23254
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.016384 0.032768 1225
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.008192 0.016384 511
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.004096 0.008192 277
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.002048 0.004096 137
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.001024 0.002048 74
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.000512 0.001024 33
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.000256 0.000512 33
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.000128 0.000256 13
Mar 10 09:11:53 unbound 73151 [73151:0] info: 0.000000 0.000001 12544
Mar 10 09:11:53 unbound 73151 [73151:0] info: lower(secs) upper(secs) recursions
Mar 10 09:11:53 unbound 73151 [73151:0] info: [25%]=0.0637665 median[50%]=0.123705 [75%]=0.254179
Mar 10 09:11:53 unbound 73151 [73151:0] info: histogram of recursion processing times
Mar 10 09:11:53 unbound 73151 [73151:0] info: average recursion processing time 9.112102 sec
Mar 10 09:11:53 unbound 73151 [73151:0] info: server stats for thread 0: requestlist max 373 avg 13.7183 exceeded 0 jostled 0
Mar 10 09:11:53 unbound 73151 [73151:0] info: server stats for thread 0: 230718 queries, 81989 answers from cache, 148729 recursions, 1665 prefetch, 0 rejected by ip ratelimitingAny ideas why unbound keeps restarting?