Trying To Understand DNS Resolver Errors (unbound 9050:0 & generate keytag query _ta-4f66 & NULL IN failed to prime trust anchor)

jefstrongman · Aug 31, 2020, 9:18 PM

So 150 Log entries in little less than an hour. I have tried my best to research this before asking this question, but to be honest, I am not understanding any of it . I really hate to post this log of a log, but really don't want to leave something out that you may need to see.

Aug 31 13:23:29 unbound 9050:0 info: 0.000000 0.000001 930
Aug 31 13:23:29 unbound 9050:0 info: 0.000008 0.000016 1
Aug 31 13:23:29 unbound 9050:0 info: 0.000032 0.000064 1
Aug 31 13:23:29 unbound 9050:0 info: 0.000256 0.000512 3
Aug 31 13:23:29 unbound 9050:0 info: 0.000512 0.001024 9
Aug 31 13:23:29 unbound 9050:0 info: 0.001024 0.002048 8
Aug 31 13:23:29 unbound 9050:0 info: 0.002048 0.004096 18
Aug 31 13:23:29 unbound 9050:0 info: 0.004096 0.008192 23
Aug 31 13:23:29 unbound 9050:0 info: 0.008192 0.016384 10
Aug 31 13:23:29 unbound 9050:0 info: 0.016384 0.032768 28
Aug 31 13:23:29 unbound 9050:0 info: 0.032768 0.065536 96
Aug 31 13:23:29 unbound 9050:0 info: 0.065536 0.131072 363
Aug 31 13:23:29 unbound 9050:0 info: 0.131072 0.262144 399
Aug 31 13:23:29 unbound 9050:0 info: 0.262144 0.524288 363
Aug 31 13:23:29 unbound 9050:0 info: 0.524288 1.000000 406
Aug 31 13:23:29 unbound 9050:0 info: 1.000000 2.000000 539
Aug 31 13:23:29 unbound 9050:0 info: 2.000000 4.000000 600
Aug 31 13:23:29 unbound 9050:0 info: 4.000000 8.000000 707
Aug 31 13:23:29 unbound 9050:0 info: 8.000000 16.000000 980
Aug 31 13:23:29 unbound 9050:0 info: 16.000000 32.000000 1760
Aug 31 13:23:29 unbound 9050:0 info: 32.000000 64.000000 1619
Aug 31 13:23:29 unbound 9050:0 info: 64.000000 128.000000 1577
Aug 31 13:23:29 unbound 9050:0 info: 128.000000 256.000000 992
Aug 31 13:23:29 unbound 9050:0 info: 256.000000 512.000000 763
Aug 31 13:23:29 unbound 9050:0 info: 512.000000 1024.000000 446
Aug 31 13:23:29 unbound 9050:0 info: 1024.000000 2048.000000 2
Aug 31 13:23:29 unbound 9050:0 info: server stats for thread 1: 16976 queries, 13928 answers from cache, 3048 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 31 13:23:29 unbound 9050:0 info: server stats for thread 1: requestlist max 80 avg 16.6165 exceeded 0 jostled 0
Aug 31 13:23:29 unbound 9050:0 info: average recursion processing time 80.602074 sec
Aug 31 13:23:29 unbound 9050:0 info: histogram of recursion processing times
Aug 31 13:23:29 unbound 9050:0 info: [25%]=0.311861 median[50%]=17.974 [75%]=83.6364
Aug 31 13:23:29 unbound 9050:0 info: lower(secs) upper(secs) recursions
Aug 31 13:23:29 unbound 9050:0 info: 0.000000 0.000001 429
Aug 31 13:23:29 unbound 9050:0 info: 0.000128 0.000256 1
Aug 31 13:23:29 unbound 9050:0 info: 0.001024 0.002048 1
Aug 31 13:23:29 unbound 9050:0 info: 0.002048 0.004096 7
Aug 31 13:23:29 unbound 9050:0 info: 0.004096 0.008192 4
Aug 31 13:23:29 unbound 9050:0 info: 0.008192 0.016384 4
Aug 31 13:23:29 unbound 9050:0 info: 0.016384 0.032768 1
Aug 31 13:23:29 unbound 9050:0 info: 0.032768 0.065536 25
Aug 31 13:23:29 unbound 9050:0 info: 0.065536 0.131072 129
Aug 31 13:23:29 unbound 9050:0 info: 0.131072 0.262144 144
Aug 31 13:23:29 unbound 9050:0 info: 0.262144 0.524288 87
Aug 31 13:23:29 unbound 9050:0 info: 0.524288 1.000000 94
Aug 31 13:23:29 unbound 9050:0 info: 1.000000 2.000000 121
Aug 31 13:23:29 unbound 9050:0 info: 2.000000 4.000000 116
Aug 31 13:23:29 unbound 9050:0 info: 4.000000 8.000000 140
Aug 31 13:23:29 unbound 9050:0 info: 8.000000 16.000000 182
Aug 31 13:23:29 unbound 9050:0 info: 16.000000 32.000000 308
Aug 31 13:23:29 unbound 9050:0 info: 32.000000 64.000000 370
Aug 31 13:23:29 unbound 9050:0 info: 64.000000 128.000000 396
Aug 31 13:23:29 unbound 9050:0 info: 128.000000 256.000000 206
Aug 31 13:23:29 unbound 9050:0 info: 256.000000 512.000000 154
Aug 31 13:23:29 unbound 9050:0 info: 512.000000 1024.000000 119
Aug 31 13:23:29 unbound 9050:0 info: 1024.000000 2048.000000 8
Aug 31 13:23:29 unbound 9050:0 info: server stats for thread 2: 133461 queries, 115610 answers from cache, 17851 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 31 13:23:29 unbound 9050:0 info: server stats for thread 2: requestlist max 148 avg 32.4231 exceeded 0 jostled 0
Aug 31 13:23:29 unbound 9050:0 info: average recursion processing time 79.079804 sec
Aug 31 13:23:29 unbound 9050:0 info: histogram of recursion processing times
Aug 31 13:23:29 unbound 9050:0 info: [25%]=2.06096 median[50%]=21.8352 [75%]=80.3353
Aug 31 13:23:29 unbound 9050:0 info: lower(secs) upper(secs) recursions
Aug 31 13:23:29 unbound 9050:0 info: 0.000000 0.000001 1053
Aug 31 13:23:29 unbound 9050:0 info: 0.000032 0.000064 3
Aug 31 13:23:29 unbound 9050:0 info: 0.000256 0.000512 5
Aug 31 13:23:29 unbound 9050:0 info: 0.000512 0.001024 8
Aug 31 13:23:29 unbound 9050:0 info: 0.001024 0.002048 7
Aug 31 13:23:29 unbound 9050:0 info: 0.002048 0.004096 30
Aug 31 13:23:29 unbound 9050:0 info: 0.004096 0.008192 34
Aug 31 13:23:29 unbound 9050:0 info: 0.008192 0.016384 22
Aug 31 13:23:29 unbound 9050:0 info: 0.016384 0.032768 26
Aug 31 13:23:29 unbound 9050:0 info: 0.032768 0.065536 156
Aug 31 13:23:29 unbound 9050:0 info: 0.065536 0.131072 504
Aug 31 13:23:29 unbound 9050:0 info: 0.131072 0.262144 586
Aug 31 13:23:29 unbound 9050:0 info: 0.262144 0.524288 542
Aug 31 13:23:29 unbound 9050:0 info: 0.524288 1.000000 621
Aug 31 13:23:29 unbound 9050:0 info: 1.000000 2.000000 832
Aug 31 13:23:29 unbound 9050:0 info: 2.000000 4.000000 935
Aug 31 13:23:29 unbound 9050:0 info: 4.000000 8.000000 1101
Aug 31 13:23:29 unbound 9050:0 info: 8.000000 16.000000 1481
Aug 31 13:23:29 unbound 9050:0 info: 16.000000 32.000000 2657
Aug 31 13:23:29 unbound 9050:0 info: 32.000000 64.000000 2258
Aug 31 13:23:29 unbound 9050:0 info: 64.000000 128.000000 2004
Aug 31 13:23:29 unbound 9050:0 info: 128.000000 256.000000 1327
Aug 31 13:23:29 unbound 9050:0 info: 256.000000 512.000000 1042
Aug 31 13:23:29 unbound 9050:0 info: 512.000000 1024.000000 596
Aug 31 13:23:29 unbound 9050:0 info: server stats for thread 3: 1386 queries, 874 answers from cache, 512 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 31 13:23:29 unbound 9050:0 info: server stats for thread 3: requestlist max 30 avg 5.12305 exceeded 0 jostled 0
Aug 31 13:23:29 unbound 9050:0 info: average recursion processing time 71.467717 sec
Aug 31 13:23:29 unbound 9050:0 info: histogram of recursion processing times
Aug 31 13:23:29 unbound 9050:0 info: [25%]=0.105427 median[50%]=7.69231 [75%]=74.8308
Aug 31 13:23:29 unbound 9050:0 info: lower(secs) upper(secs) recursions
Aug 31 13:23:29 unbound 9050:0 info: 0.000000 0.000001 105
Aug 31 13:23:29 unbound 9050:0 info: 0.002048 0.004096 1
Aug 31 13:23:29 unbound 9050:0 info: 0.032768 0.065536 8
Aug 31 13:23:29 unbound 9050:0 info: 0.065536 0.131072 23
Aug 31 13:23:29 unbound 9050:0 info: 0.131072 0.262144 23
Aug 31 13:23:29 unbound 9050:0 info: 0.262144 0.524288 9
Aug 31 13:23:29 unbound 9050:0 info: 0.524288 1.000000 11
Aug 31 13:23:29 unbound 9050:0 info: 1.000000 2.000000 21
Aug 31 13:23:29 unbound 9050:0 info: 2.000000 4.000000 31
Aug 31 13:23:29 unbound 9050:0 info: 4.000000 8.000000 26
Aug 31 13:23:29 unbound 9050:0 info: 8.000000 16.000000 30
Aug 31 13:23:29 unbound 9050:0 info: 16.000000 32.000000 33
Aug 31 13:23:29 unbound 9050:0 info: 32.000000 64.000000 52
Aug 31 13:23:29 unbound 9050:0 info: 64.000000 128.000000 65
Aug 31 13:23:29 unbound 9050:0 info: 128.000000 256.000000 22
Aug 31 13:23:29 unbound 9050:0 info: 256.000000 512.000000 35
Aug 31 13:23:29 unbound 9050:0 info: 512.000000 1024.000000 17
Aug 31 13:23:32 unbound 25031:0 notice: init module 0: validator
Aug 31 13:23:32 unbound 25031:0 notice: init module 1: iterator
Aug 31 13:23:32 unbound 25031:0 info: start of service (unbound 1.10.1).IN

kiokoman · Sep 1, 2020, 8:44 AM

did you change Log Level from Level 1 to something else? they appear to be only stats and info

Services / DNS Resolver / Advanced Settings

Select the level of detail to be logged. Each level also includes the information from previous levels. The default is basic operational information (level 1)