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



  • 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


  • LAYER 8

    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)


Log in to reply