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

    After updating to 24.11 EXTREMLEY SLOW "apply changes"

    Scheduled Pinned Locked Moved
    Official Netgate® Hardware
    3
    10
    546
    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.
    • D
      d83
      last edited by

      I recently updated to 24.11 and I am experiencing extremely slow "apply changes". My Netgate 8200 it is taking 1 minute and 20 seconds just to save a simple "host overrides". I have a
      Netgate 7100 that is experiencing 56 seconds "apply changes".

      Is there anything you guys can suggest I check to see that's going on? This is absolutely painful to work with.

      The 8200 is at 9% CPU and 12% Memory

      The 7100 39 CPU and 8% Memory

      1 Reply Last reply Reply Quote 0
      • stephenw10S
        stephenw10 Netgate Administrator
        last edited by

        Hmm, what does the system or resolver log show during that time?

        D 1 Reply Last reply Reply Quote 0
        • D
          d83 @stephenw10
          last edited by

          @stephenw10

          Please let me know if there anything else you need. Thank you.

          My system Logs

          • Language-java
          • Language-kotlin
          Jan 4 03:15:09	php-fpm	93522	/services_unbound_host_edit.php: Configuration Change: d83@192.168.40.88 (Local Database): Host override configured for DNS Resolver.
Jan 4 03:15:09	check_reload_status	676	Syncing firewall
Jan 4 03:16:00	ACME	99557	Checking if renewal is needed for: wildcard_cert_intks
Jan 4 03:16:00	ACME	99557	Renewal number of days not yet reached.

          <

          My DNS Resolver

          • Language-java
          • Language-kotlin
          Jan 4 03:15:27	unbound	29849	[29849:0] info: service stopped (unbound 1.22.0).
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 0: 43 queries, 39 answers from cache, 4 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.130402 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.032768 median[50%]=0.131072 [75%]=0.196608
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 1: 393 queries, 368 answers from cache, 25 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 1: requestlist max 2 avg 0.24 exceeded 1 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.085051 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.0382293 median[50%]=0.073728 [75%]=0.12288
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.000000 0.000001 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 4
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.032768 0.065536 6
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 8
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 5
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 2: 381 queries, 359 answers from cache, 22 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.186195 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.057344 median[50%]=0.102985 [75%]=0.185685
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.032768 0.065536 6
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 7
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 6
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.262144 0.524288 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 1.000000 2.000000 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 3: 99 queries, 92 answers from cache, 7 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.199103 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.03072 median[50%]=0.114688 [75%]=0.212992
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.524288 1.000000 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 4: 263 queries, 243 answers from cache, 20 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 4: requestlist max 3 avg 0.3 exceeded 0 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.200632 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.0436907 median[50%]=0.0786432 [75%]=0.174763
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.000000 0.000001 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.032768 0.065536 6
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 5
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 3
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.262144 0.524288 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 1.000000 2.000000 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 5: 730 queries, 692 answers from cache, 38 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 5: requestlist max 2 avg 0.184211 exceeded 0 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.126458 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.0395947 median[50%]=0.065536 [75%]=0.140434
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.000000 0.000001 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 6
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.032768 0.065536 12
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 9
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 7
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.262144 0.524288 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 1.000000 2.000000 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 6: 190 queries, 180 answers from cache, 10 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 6: requestlist max 1 avg 0.1 exceeded 0 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.119469 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.0720896 median[50%]=0.104858 [75%]=0.16384
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.032768 0.065536 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 5
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 2
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.262144 0.524288 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 7: 59 queries, 33 answers from cache, 26 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 03:15:28	unbound	29849	[29849:0] info: server stats for thread 7: requestlist max 6 avg 3.34615 exceeded 1 jostled 0
Jan 4 03:15:28	unbound	29849	[29849:0] info: average recursion processing time 0.208119 sec
Jan 4 03:15:28	unbound	29849	[29849:0] info: histogram of recursion processing times
Jan 4 03:15:28	unbound	29849	[29849:0] info: [25%]=0.133593 median[50%]=0.196608 [75%]=0.259623
Jan 4 03:15:28	unbound	29849	[29849:0] info: lower(secs) upper(secs) recursions
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.016384 0.032768 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.032768 0.065536 1
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.065536 0.131072 4
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.131072 0.262144 13
Jan 4 03:15:28	unbound	29849	[29849:0] info: 0.262144 0.524288 5
Jan 4 03:15:28	unbound	29849	[29849:0] info: 1.000000 2.000000 1
Jan 4 03:15:42	unbound	39015	[39015:0] notice: init module 0: validator
Jan 4 03:15:42	unbound	39015	[39015:0] notice: init module 1: iterator
Jan 4 03:15:42	unbound	39015	[39015:0] info: start of service (unbound 1.22.0).
Jan 4 03:15:42	unbound	39015	[39015:5] info: generate keytag query _ta-4f66. NULL IN
Jan 4 03:15:42	unbound	39015	[39015:7] info: generate keytag query _ta-4f66. NULL IN
Jan 4 03:15:42	unbound	39015	[39015:3] info: generate keytag query _ta-4f66. NULL IN

          <
          GertjanG 1 Reply Last reply Reply Quote 0
          • stephenw10S
            stephenw10 Netgate Administrator
            last edited by

            Hmm, not much there. Slightly odd that it doesn't appear to do anything almost 20s. Then 14s to restart Unbound.

            Do you have a lot of config options there?

            For comparison an 1100 here, which is far less powerful looks like:

            Jan 4 17:13:04 	php-fpm 	89999 	/services_unbound_host_edit.php: Configuration Change: admin@172.21.16.8 (Local Database): Host override configured for DNS Resolver.
Jan 4 17:13:04 	check_reload_status 	840 	Syncing firewall 

            Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: service stopped (unbound 1.22.0).
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: server stats for thread 0: 1894 queries, 594 answers from cache, 1300 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: server stats for thread 0: requestlist max 4 avg 0.222308 exceeded 133 jostled 0
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: average recursion processing time 0.326451 sec
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: histogram of recursion processing times
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: [25%]=0.0883558 median[50%]=0.114801 [75%]=0.256823
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: lower(secs) upper(secs) recursions
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.000000 0.000001 31
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.000512 0.001024 1
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.008192 0.016384 1
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.016384 0.032768 6
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.032768 0.065536 1
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.065536 0.131072 723
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.131072 0.262144 117
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.262144 0.524288 98
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.524288 1.000000 55
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 1.000000 2.000000 134
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: server stats for thread 1: 3192 queries, 1295 answers from cache, 1897 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: server stats for thread 1: requestlist max 6 avg 0.238271 exceeded 211 jostled 0
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: average recursion processing time 0.334419 sec
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: histogram of recursion processing times
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: [25%]=0.0894903 median[50%]=0.116026 [75%]=0.288876
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: lower(secs) upper(secs) recursions
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.000000 0.000001 23
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.001024 0.002048 2
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.002048 0.004096 2
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.004096 0.008192 3
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.008192 0.016384 4
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.016384 0.032768 5
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.032768 0.065536 2
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.065536 0.131072 1041
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.131072 0.262144 167
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.262144 0.524288 152
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 0.524288 1.000000 85
Jan 4 17:13:08 	unbound 	3707 	[3707:0] info: 1.000000 2.000000 200
Jan 4 17:13:11 	unbound 	82665 	[82665:0] notice: init module 0: validator
Jan 4 17:13:11 	unbound 	82665 	[82665:0] notice: init module 1: iterator
Jan 4 17:13:11 	unbound 	82665 	[82665:0] info: start of service (unbound 1.22.0).

            So 4s to create the new Unbound config. Then 3s to load it.

            D 1 Reply Last reply Reply Quote 0
            • GertjanG
              Gertjan @d83
              last edited by Gertjan

              What you've shown :
              Here : you edit the unbound config :

              @d83 said in After updating to 24.11 EXTREMLEY SLOW "apply changes":

              Jan 4 03:15:09 php-fpm 93522 /services_unbound_host_edit.php: Configuration Change: d83@192.168.40.88 (Local Database): Host override configured for DNS Resolver.

              then unbound had to restart, this, from start :

              Jan 4 03:15:27	unbound	29849	[29849:0] info: service stopped (unbound 1.22.0).

              here :

              Jan 4 03:15:42	unbound	39015	[39015:0] notice: init module 0: validator
Jan 4 03:15:42	unbound	39015	[39015:0] notice: init module 1: iterator
Jan 4 03:15:42	unbound	39015	[39015:0] info: start of service (unbound 1.22.0)

              Ok, you've a 8200 (= loads of memory), so unbound created 6 working threads, instead of my 4 (on a 4100) or 2 with the device stephanw10 is suing.
              Still, a 8200 should ripp through it.

              Btw : during the unbound restart, DNS is out of order. "Internet", and that moment will work just fine, but if you were waiting for 'something' to get resolved at that moment, then, yeah, you wait.
              Your goal as an admin : don't restart unbound ^^ (== don't edit it settings anymore ^^)

              If "apply changes" stays slow, install and fire up uur favorite SSH client.
              You've activated the SSH, access, right , (its for the admin ^^)
              Or, by default, use the console access.
              Run screen size wide

              top

              and have it sorted on "'processor usage".
              Now, back in the GUI, do some "apply changes".
              What comes on top of the list in top ?

              No "help me" PM's please. Use the forum, the community will thank you.
              Edit : and where are the logs ??

              1 Reply Last reply Reply Quote 0
              • D
                d83 @stephenw10
                last edited by

                @stephenw10

                I have just have this PFB in the custom options

                server:include: /var/unbound/pfb_dnsbl.*conf

                I have 25 Host overrides on the 8200

                That's about all that going on

                I just tested it again just changing a host override description took 1 minute 20 seconds to apply those changes .
                Just seems weird because before 24.11 this issue wasn't there.

                Jan 6 06:08:18	unbound	37224	[37224:0] info: service stopped (unbound 1.22.0).
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 0: 7 queries, 7 answers from cache, 0 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 1: 30 queries, 29 answers from cache, 1 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.209310 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0 median[50%]=0 [75%]=0
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.131072 0.262144 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 2: 88 queries, 82 answers from cache, 6 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 2: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.070332 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0.0382293 median[50%]=0.0546133 [75%]=0.098304
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.016384 0.032768 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.032768 0.065536 3
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.065536 0.131072 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.131072 0.262144 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 3: 65 queries, 58 answers from cache, 7 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 3: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.108161 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0.057344 median[50%]=0.114688 [75%]=0.185685
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.016384 0.032768 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.032768 0.065536 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.065536 0.131072 2
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.131072 0.262144 3
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 4: 36 queries, 9 answers from cache, 27 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 4: requestlist max 11 avg 5.7037 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.185972 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0.152442 median[50%]=0.190909 [75%]=0.229376
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.065536 0.131072 3
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.131072 0.262144 23
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.262144 0.524288 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 5: 26 queries, 24 answers from cache, 2 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 5: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.169263 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0 median[50%]=0 [75%]=0
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.065536 0.131072 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.131072 0.262144 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 6: 58 queries, 54 answers from cache, 4 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 6: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.066572 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0.032768 median[50%]=0.065536 [75%]=0.098304
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.016384 0.032768 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.032768 0.065536 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.065536 0.131072 2
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 7: 121 queries, 117 answers from cache, 4 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Jan 6 06:08:19	unbound	37224	[37224:0] info: server stats for thread 7: requestlist max 0 avg 0 exceeded 0 jostled 0
Jan 6 06:08:19	unbound	37224	[37224:0] info: average recursion processing time 0.084869 sec
Jan 6 06:08:19	unbound	37224	[37224:0] info: histogram of recursion processing times
Jan 6 06:08:19	unbound	37224	[37224:0] info: [25%]=0.032768 median[50%]=0.098304 [75%]=0.131072
Jan 6 06:08:19	unbound	37224	[37224:0] info: lower(secs) upper(secs) recursions
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.016384 0.032768 1
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.065536 0.131072 2
Jan 6 06:08:19	unbound	37224	[37224:0] info: 0.131072 0.262144 1
Jan 6 06:08:34	unbound	4263	[4263:0] notice: init module 0: validator
Jan 6 06:08:34	unbound	4263	[4263:0] notice: init module 1: iterator
Jan 6 06:08:34	unbound	4263	[4263:0] info: start of service (unbound 1.22.0).
Jan 6 06:08:34	unbound	4263	[4263:2] info: generate keytag query _ta-4f66. NULL IN
Jan 6 06:08:34	unbound	4263	[4263:4] info: generate keytag query _ta-4f66. NULL IN
                GertjanG 1 Reply Last reply Reply Quote 1
                • stephenw10S
                  stephenw10 Netgate Administrator
                  last edited by

                  How big is the pfBlocker config? If you have every list loaded for example that can slow things down a lot.

                  D 1 Reply Last reply Reply Quote 0
                  • GertjanG
                    Gertjan @d83
                    last edited by

                    @d83 said in After updating to 24.11 EXTREMLEY SLOW "apply changes":

                    server:include: /var/unbound/pfb_dnsbl.*conf

                    That is, if your are using a recent pfBlockerng, part of the odl "unbound mode".
                    Don't you have selected :

                    943fb338-d996-46e3-a652-8e0ba6485faa-image.png

                    but the older, way slower "unbound mode" ?
                    We all went for the "Python mode" as we like speed. Like you, I guess ^^

                    If you have Python mode selected you can delete this "server:include: /var/unbound/pfb_dnsbl.*conf"

                    No "help me" PM's please. Use the forum, the community will thank you.
                    Edit : and where are the logs ??

                    1 Reply Last reply Reply Quote 0
                    • D
                      d83 @stephenw10
                      last edited by

                      @stephenw10

                      I really don' t have too much, well I think...

                      DNSL Group.PNG

                      You are right I turned it off pfBlockerng and my "apply changes" times drop to almost no wait at all.

                      @Gertjan

                      This helps A LOT. From 1 minute and 20 seconds to about 9 seconds!

                      alt text

                      Thank you both for your help, this helps A LOT.

                      1 Reply Last reply Reply Quote 2
                      • stephenw10S
                        stephenw10 Netgate Administrator
                        last edited by

                        Aha, nice result!

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