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

    Frequent restarts of both dhcpd and unbound

    Scheduled Pinned Locked Moved
    DHCP and DNS
    2
    5
    260
    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.
    • H
      helgew
      last edited by

      Both dhcpd and unbound are restarting about once a minute or so on my pfSense appliance (latest version, unbound manually upgraded to 1.13.1). I do not have the registration of leases enabled and I am at a loss what might be causing this.

      I did notice that /etc/hosts and /var/etc/filterdns.conf are getting updated with a similar frequency (modification time stamp changes, content being the same).

      Any help would be greatly appreciated.

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

        @helgew said in Frequent restarts of both dhcpd and unbound:

        Both dhcpd and unbound are restarting about once a minute or so on my pfSense appliance (latest version, unbound manually upgraded to 1.13.1). I do not have the registration of leases enabled and I am at a loss what might be causing this.

        I couldn't check your logs, so some wild guessing will be involved :

        System > Advanced > Firewall & NAT and check "Aliases Hostnames Resolve Interval" : it should be 'empty' is default is 300 seconds.

        Also : commandline (console or SSH) :
        ps ax | grep 'leases'

        There should NOT be a line like :

        8741  -  Ss      0:00.01 /usr/local/sbin/dhcpleases -l /var/dhcpd/var/db/dhcpd.leases -d your-domain -p /var/run/unbound.pid -u /var/unbound/dhcpleases_entries.conf -h /etc/hosts

        That's the one restarting unbound when "DHCP Registration" is checked.

        dhcpd service restarts are less common.
        Example : It (can) happen when interfaces are going down and up.

        edit :
        What always solves the issue - and gives you the answer :
        Reset pfSense to default.
        Do not restore the config.
        Add your settings one - by - one .
        Test system behaviour after each step.

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

        H 2 Replies Last reply Reply Quote 0
        • H
          helgew @Gertjan
          last edited by

          @gertjan said in Frequent restarts of both dhcpd and unbound:

          I couldn't check your logs, so some wild guessing will be involved :

          Thank you for your input! Much appreciated!

          There is nothing that seems of interest in the logs, but here they are:

          /var/log/dhcpd.log

          May 20 07:06:46 styx dhcpd[2879]: Sending on   Socket/fallback/fallback-net
May 20 07:06:46 styx dhcpd[2879]: Server starting service.
May 20 07:07:37 styx dhcpd[37783]: Internet Systems Consortium DHCP Server 4.4.2
May 20 07:07:37 styx dhcpd[37783]: Copyright 2004-2020 Internet Systems Consortium.
May 20 07:07:37 styx dhcpd[37783]: All rights reserved.
May 20 07:07:37 styx dhcpd[37783]: For info, please visit https://www.isc.org/software/dhcp/
May 20 07:07:37 styx dhcpd[37783]: Config file: /etc/dhcpd.conf
May 20 07:07:37 styx dhcpd[37783]: Database file: /var/db/dhcpd.leases
May 20 07:07:37 styx dhcpd[37783]: Internet Systems Consortium DHCP Server 4.4.2
May 20 07:07:37 styx dhcpd[37783]: PID file: /var/run/dhcpd.pid
May 20 07:07:37 styx dhcpd[37783]: Copyright 2004-2020 Internet Systems Consortium.
May 20 07:07:37 styx dhcpd[37783]: All rights reserved.
May 20 07:07:37 styx dhcpd[37783]: For info, please visit https://www.isc.org/software/dhcp/
May 20 07:07:37 styx dhcpd[37783]: Wrote 0 class decls to leases file.
May 20 07:07:37 styx dhcpd[37783]: Wrote 0 deleted host decls to leases file.
May 20 07:07:37 styx dhcpd[37783]: Wrote 0 new dynamic host decls to leases file.
May 20 07:07:37 styx dhcpd[37783]: Wrote 34 leases to leases file.
May 20 07:07:37 styx dhcpd[37783]: Listening on BPF/em1.55/00:ec:ac:cd:e8:47/10.55.0.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   BPF/em1.55/00:ec:ac:cd:e8:47/10.55.0.0/24
May 20 07:07:37 styx dhcpd[37783]: Listening on BPF/em1.70/00:ec:ac:cd:e8:47/10.0.7.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   BPF/em1.70/00:ec:ac:cd:e8:47/10.0.7.0/24
May 20 07:07:37 styx dhcpd[37783]: Listening on BPF/em3.40/00:ec:ac:cd:e8:49/10.0.4.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   BPF/em3.40/00:ec:ac:cd:e8:49/10.0.4.0/24
May 20 07:07:37 styx dhcpd[37783]: Listening on BPF/em3.50/00:ec:ac:cd:e8:49/10.0.5.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   BPF/em3.50/00:ec:ac:cd:e8:49/10.0.5.0/24
May 20 07:07:37 styx dhcpd[37783]: Listening on BPF/em2/00:ec:ac:cd:e8:48/10.0.3.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   BPF/em2/00:ec:ac:cd:e8:48/10.0.3.0/24
May 20 07:07:37 styx dhcpd[37783]: Listening on BPF/em1/00:ec:ac:cd:e8:47/10.0.2.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   BPF/em1/00:ec:ac:cd:e8:47/10.0.2.0/24
May 20 07:07:37 styx dhcpd[37783]: Sending on   Socket/fallback/fallback-net
May 20 07:07:37 styx dhcpd[37783]: Server starting service.

          /var/log/resolver.log

          May 20 07:06:41 styx unbound[70913]: [70913:0] info: service stopped (unbound 1.13.1).
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 0: 127 queries, 98 answers from cache, 29 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 0: requestlist max 9 avg 3.62069 exceeded 0 jostled 0
May 20 07:06:41 styx unbound[70913]: [70913:0] info: average recursion processing time 0.181018 sec
May 20 07:06:41 styx unbound[70913]: [70913:0] info: histogram of recursion processing times
May 20 07:06:41 styx unbound[70913]: [70913:0] info: [25%]=0.1024 median[50%]=0.173478 [75%]=0.229376
May 20 07:06:41 styx unbound[70913]: [70913:0] info: lower(secs) upper(secs) recursions
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.000000    0.000001 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.008192    0.016384 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.016384    0.032768 2
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.032768    0.065536 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.065536    0.131072 4
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.131072    0.262144 17
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.262144    0.524288 3
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 1: 140 queries, 131 answers from cache, 9 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 1: requestlist max 0 avg 0 exceeded 0 jostled 0
May 20 07:06:41 styx unbound[70913]: [70913:0] info: average recursion processing time 0.073911 sec
May 20 07:06:41 styx unbound[70913]: [70913:0] info: histogram of recursion processing times
May 20 07:06:41 styx unbound[70913]: [70913:0] info: [25%]=0.018432 median[50%]=0.049152 [75%]=0.12288
May 20 07:06:41 styx unbound[70913]: [70913:0] info: lower(secs) upper(secs) recursions
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.000000    0.000001 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.008192    0.016384 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.016384    0.032768 2
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.032768    0.065536 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.065536    0.131072 2
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.131072    0.262144 2
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 2: 299 queries, 281 answers from cache, 18 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 2: requestlist max 1 avg 0.0555556 exceeded 0 jostled 0
May 20 07:06:41 styx unbound[70913]: [70913:0] info: average recursion processing time 0.043012 sec
May 20 07:06:41 styx unbound[70913]: [70913:0] info: histogram of recursion processing times
May 20 07:06:41 styx unbound[70913]: [70913:0] info: [25%]=0.012288 median[50%]=0.0234057 [75%]=0.049152
May 20 07:06:41 styx unbound[70913]: [70913:0] info: lower(secs) upper(secs) recursions
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.000000    0.000001 3
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.008192    0.016384 3
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.016384    0.032768 7
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.032768    0.065536 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.065536    0.131072 3
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.262144    0.524288 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 3: 449 queries, 424 answers from cache, 25 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:06:41 styx unbound[70913]: [70913:0] info: server stats for thread 3: requestlist max 2 avg 0.32 exceeded 0 jostled 0
May 20 07:06:41 styx unbound[70913]: [70913:0] info: average recursion processing time 0.044770 sec
May 20 07:06:41 styx unbound[70913]: [70913:0] info: histogram of recursion processing times
May 20 07:06:41 styx unbound[70913]: [70913:0] info: [25%]=0.013312 median[50%]=0.0314027 [75%]=0.08192
May 20 07:06:41 styx unbound[70913]: [70913:0] info: lower(secs) upper(secs) recursions
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.000000    0.000001 4
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.004096    0.008192 1
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.008192    0.016384 2
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.016384    0.032768 6
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.032768    0.065536 4
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.065536    0.131072 7
May 20 07:06:41 styx unbound[70913]: [70913:0] info:    0.131072    0.262144 1
May 20 07:06:55 styx unbound[85670]: [85670:0] notice: init module 0: validator
May 20 07:06:55 styx unbound[85670]: [85670:0] notice: init module 1: iterator
May 20 07:06:55 styx unbound[85670]: [85670:0] info: start of service (unbound 1.13.1).
May 20 07:06:56 styx unbound[85670]: [85670:0] info: generate keytag query _ta-4f66. NULL IN
May 20 07:06:59 styx filterdns[69045]: merge_config: configuration reload
# reloading of aliases
May 20 07:07:02 styx filterdns[66302]: merge_config: configuration reload
May 20 07:07:02 styx filterdns[66302]: 	Adding Action: cmd cmd: /usr/local/sbin/pfSctl -c "service reload ipsecdns" host: remote.firewall.com
May 20 07:07:05 styx filterdns[69045]: merge_config: configuration reload
# reloading of aliases
May 20 07:07:12 styx filterdns[69045]: merge_config: configuration reload
# reloading of aliases
May 20 07:07:24 styx filterdns[69045]: merge_config: configuration reload
# reloading of aliases
May 20 07:07:30 styx filterdns[69045]: merge_config: configuration reload
# reloading of aliases
May 20 07:07:33 styx unbound[85670]: [85670:0] info: service stopped (unbound 1.13.1).
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 0: 125 queries, 82 answers from cache, 43 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 0: requestlist max 9 avg 3.62791 exceeded 0 jostled 0
May 20 07:07:33 styx unbound[85670]: [85670:0] info: average recursion processing time 0.131476 sec
May 20 07:07:33 styx unbound[85670]: [85670:0] info: histogram of recursion processing times
May 20 07:07:33 styx unbound[85670]: [85670:0] info: [25%]=0.04096 median[50%]=0.144725 [75%]=0.203435
May 20 07:07:33 styx unbound[85670]: [85670:0] info: lower(secs) upper(secs) recursions
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.000000    0.000001 2
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.008192    0.016384 1
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.016384    0.032768 7
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.032768    0.065536 3
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.065536    0.131072 6
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.131072    0.262144 24
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 1: 535 queries, 512 answers from cache, 23 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 1: requestlist max 3 avg 0.304348 exceeded 0 jostled 0
May 20 07:07:33 styx unbound[85670]: [85670:0] info: average recursion processing time 0.049581 sec
May 20 07:07:33 styx unbound[85670]: [85670:0] info: histogram of recursion processing times
May 20 07:07:33 styx unbound[85670]: [85670:0] info: [25%]=0.013312 median[50%]=0.0259413 [75%]=0.0688128
May 20 07:07:33 styx unbound[85670]: [85670:0] info: lower(secs) upper(secs) recursions
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.000000    0.000001 2
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.008192    0.016384 6
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.016384    0.032768 6
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.032768    0.065536 3
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.065536    0.131072 5
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.262144    0.524288 1
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 2: 440 queries, 425 answers from cache, 15 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 2: requestlist max 1 avg 0.0666667 exceeded 0 jostled 0
May 20 07:07:33 styx unbound[85670]: [85670:0] info: average recursion processing time 0.032586 sec
May 20 07:07:33 styx unbound[85670]: [85670:0] info: histogram of recursion processing times
May 20 07:07:33 styx unbound[85670]: [85670:0] info: [25%]=0.018432 median[50%]=0.028672 [75%]=0.0512
May 20 07:07:33 styx unbound[85670]: [85670:0] info: lower(secs) upper(secs) recursions
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.000000    0.000001 1
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.008192    0.016384 2
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.016384    0.032768 6
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.032768    0.065536 4
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.065536    0.131072 2
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 3: 174 queries, 162 answers from cache, 12 recursions, 0 prefetch, 0 rejected by ip ratelimiting
May 20 07:07:33 styx unbound[85670]: [85670:0] info: server stats for thread 3: requestlist max 6 avg 1.75 exceeded 0 jostled 0
May 20 07:07:33 styx unbound[85670]: [85670:0] info: average recursion processing time 0.092648 sec
May 20 07:07:33 styx unbound[85670]: [85670:0] info: histogram of recursion processing times
May 20 07:07:33 styx unbound[85670]: [85670:0] info: [25%]=0.0546133 median[50%]=0.0873813 [75%]=0.120149
May 20 07:07:33 styx unbound[85670]: [85670:0] info: lower(secs) upper(secs) recursions
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.016384    0.032768 1
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.032768    0.065536 3
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.065536    0.131072 6
May 20 07:07:33 styx unbound[85670]: [85670:0] info:    0.131072    0.262144 2
May 20 07:07:47 styx unbound[21966]: [21966:0] notice: init module 0: validator
May 20 07:07:47 styx unbound[21966]: [21966:0] notice: init module 1: iterator
May 20 07:07:47 styx unbound[21966]: [21966:0] info: start of service (unbound 1.13.1).
May 20 07:07:47 styx unbound[21966]: [21966:0] info: generate keytag query _ta-4f66. NULL IN

          System > Advanced > Firewall & NAT and check "Aliases Hostnames Resolve Interval" : it should be 'empty' is default is 300 seconds.

          this is set to its default, however the aliases are being reloaded by unbound more frequently then that:

          [2.5.1-RELEASE][root@styx]/root: grep merge_config /var/log/resolver.log.0
May 20 06:52:01 styx filterdns[69045]: merge_config: configuration reload
May 20 06:52:21 styx filterdns[69045]: merge_config: configuration reload
May 20 06:52:25 styx filterdns[66302]: merge_config: configuration reload
May 20 06:52:27 styx filterdns[69045]: merge_config: configuration reload
May 20 06:52:34 styx filterdns[69045]: merge_config: configuration reload
May 20 06:53:38 styx filterdns[69045]: merge_config: configuration reload
May 20 06:53:44 styx filterdns[69045]: merge_config: configuration reload
May 20 06:54:04 styx filterdns[69045]: merge_config: configuration reload
May 20 06:54:10 styx filterdns[66302]: merge_config: configuration reload
May 20 06:54:11 styx filterdns[69045]: merge_config: configuration reload
May 20 06:54:29 styx filterdns[69045]: merge_config: configuration reload
May 20 06:54:35 styx filterdns[69045]: merge_config: configuration reload
May 20 06:54:55 styx filterdns[69045]: merge_config: configuration reload
May 20 06:54:59 styx filterdns[66302]: merge_config: configuration reload
May 20 06:55:01 styx filterdns[69045]: merge_config: configuration reload
May 20 06:55:08 styx filterdns[69045]: merge_config: configuration reload
May 20 06:55:20 styx filterdns[69045]: merge_config: configuration reload
May 20 06:55:27 styx filterdns[69045]: merge_config: configuration reload
May 20 06:55:47 styx filterdns[69045]: merge_config: configuration reload
May 20 06:55:50 styx filterdns[66302]: merge_config: configuration reload
May 20 06:55:53 styx filterdns[69045]: merge_config: configuration reload
May 20 06:55:59 styx filterdns[69045]: merge_config: configuration reload
May 20 06:57:04 styx filterdns[69045]: merge_config: configuration reload
May 20 06:57:11 styx filterdns[69045]: merge_config: configuration reload
May 20 06:57:31 styx filterdns[69045]: merge_config: configuration reload
May 20 06:57:36 styx filterdns[66302]: merge_config: configuration reload
May 20 06:57:37 styx filterdns[69045]: merge_config: configuration reload
May 20 06:57:56 styx filterdns[69045]: merge_config: configuration reload
May 20 06:58:02 styx filterdns[69045]: merge_config: configuration reload
May 20 06:58:22 styx filterdns[69045]: merge_config: configuration reload
May 20 06:58:26 styx filterdns[66302]: merge_config: configuration reload
May 20 06:58:29 styx filterdns[69045]: merge_config: configuration reload
May 20 06:58:35 styx filterdns[69045]: merge_config: configuration reload
May 20 06:58:47 styx filterdns[69045]: merge_config: configuration reload
May 20 06:58:53 styx filterdns[69045]: merge_config: configuration reload
May 20 06:59:13 styx filterdns[69045]: merge_config: configuration reload
May 20 06:59:16 styx filterdns[66302]: merge_config: configuration reload
May 20 06:59:19 styx filterdns[69045]: merge_config: configuration reload
May 20 06:59:26 styx filterdns[69045]: merge_config: configuration reload
May 20 06:59:38 styx filterdns[69045]: merge_config: configuration reload
May 20 06:59:44 styx filterdns[69045]: merge_config: configuration reload
May 20 07:00:05 styx filterdns[69045]: merge_config: configuration reload
May 20 07:00:08 styx filterdns[66302]: merge_config: configuration reload
May 20 07:00:11 styx filterdns[69045]: merge_config: configuration reload
May 20 07:00:18 styx filterdns[69045]: merge_config: configuration reload
May 20 07:00:30 styx filterdns[69045]: merge_config: configuration reload
May 20 07:00:36 styx filterdns[69045]: merge_config: configuration reload
May 20 07:00:56 styx filterdns[69045]: merge_config: configuration reload
May 20 07:01:00 styx filterdns[66302]: merge_config: configuration reload
May 20 07:01:03 styx filterdns[69045]: merge_config: configuration reload
May 20 07:01:09 styx filterdns[69045]: merge_config: configuration reload
May 20 07:01:22 styx filterdns[69045]: merge_config: configuration reload
May 20 07:01:29 styx filterdns[69045]: merge_config: configuration reload
May 20 07:01:49 styx filterdns[69045]: merge_config: configuration reload
May 20 07:01:52 styx filterdns[66302]: merge_config: configuration reload
May 20 07:01:55 styx filterdns[69045]: merge_config: configuration reload
May 20 07:02:01 styx filterdns[69045]: merge_config: configuration reload
May 20 07:03:06 styx filterdns[69045]: merge_config: configuration reload
May 20 07:03:12 styx filterdns[69045]: merge_config: configuration reload
May 20 07:03:32 styx filterdns[69045]: merge_config: configuration reload
May 20 07:03:38 styx filterdns[66302]: merge_config: configuration reload
May 20 07:03:39 styx filterdns[69045]: merge_config: configuration reload
May 20 07:04:50 styx filterdns[69045]: merge_config: configuration reload
May 20 07:04:56 styx filterdns[69045]: merge_config: configuration reload
May 20 07:05:17 styx filterdns[69045]: merge_config: configuration reload
May 20 07:05:22 styx filterdns[66302]: merge_config: configuration reload
May 20 07:05:23 styx filterdns[69045]: merge_config: configuration reload
May 20 07:05:40 styx filterdns[69045]: merge_config: configuration reload
May 20 07:05:46 styx filterdns[69045]: merge_config: configuration reload
May 20 07:06:06 styx filterdns[69045]: merge_config: configuration reload
May 20 07:06:10 styx filterdns[66302]: merge_config: configuration reload
May 20 07:06:13 styx filterdns[69045]: merge_config: configuration reload
May 20 07:06:19 styx filterdns[69045]: merge_config: configuration reload
May 20 07:06:32 styx filterdns[69045]: merge_config: configuration reload
May 20 07:06:38 styx filterdns[69045]: merge_config: configuration reload
May 20 07:06:59 styx filterdns[69045]: merge_config: configuration reload
May 20 07:07:02 styx filterdns[66302]: merge_config: configuration reload
May 20 07:07:05 styx filterdns[69045]: merge_config: configuration reload
May 20 07:07:12 styx filterdns[69045]: merge_config: configuration reload
May 20 07:07:24 styx filterdns[69045]: merge_config: configuration reload
May 20 07:07:30 styx filterdns[69045]: merge_config: configuration reload
May 20 07:07:50 styx filterdns[69045]: merge_config: configuration reload
May 20 07:07:54 styx filterdns[66302]: merge_config: configuration reload
May 20 07:07:57 styx filterdns[69045]: merge_config: configuration reload
May 20 07:08:03 styx filterdns[69045]: merge_config: configuration reload
May 20 07:08:15 styx filterdns[69045]: merge_config: configuration reload
May 20 07:08:22 styx filterdns[69045]: merge_config: configuration reload
May 20 07:08:42 styx filterdns[69045]: merge_config: configuration reload
May 20 07:08:45 styx filterdns[66302]: merge_config: configuration reload
May 20 07:08:48 styx filterdns[69045]: merge_config: configuration reload
May 20 07:08:54 styx filterdns[69045]: merge_config: configuration reload
[2.5.1-RELEASE][root@styx]/root:

          Also : commandline (console or SSH) :
          ps ax | grep 'leases'

          There should NOT be a line like :

          8741  -  Ss      0:00.01 /usr/local/sbin/dhcpleases -l /var/dhcpd/var/db/dhcpd.leases -d your-domain -p /var/run/unbound.pid -u /var/unbound/dhcpleases_entries.conf -h /etc/hosts

          there is no dhcpleases process running

          dhcpd service restarts are less common.
          Example : It (can) happen when interfaces are going down and up.

          See the logs above. The restart happens just seconds after unbound restarts.

          I did notice this line though:

          May 20 07:07:02 styx filterdns[66302]: 	Adding Action: cmd cmd: /usr/local/sbin/pfSctl -c "service reload ipsecdns" host: remote.firewall.com

          which stems from an IPSEC tunnel to remote.firewall.com. Disabling it does not prevent the restart loops, however.

          edit :
          What always solves the issue - and gives you the answer :
          Reset pfSense to default.
          Do not restore the config.
          Add your settings one - by - one .
          Test system behaviour after each step.

          That'll be a last resort given the extent of my configuration and the disruption it would cause.

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

            @helgew said in Frequent restarts of both dhcpd and unbound:

            filterdns

            How many 'filterdns' tasks are running ?
            I've one, it wakes up every minutes, does 'it's thing', and goes to sleep again.

            I looked up in the manual what 'merge_config' log conditions means.
            First of all, the process (program) 'filterdns' does something completely different as what I though it was doing.
            Can't say I'm sure, but I tend to say : this 'merge_config' message shouldn't happen every 10 seconds of so. This text is syslogged when filterdns starts.
            Looks like filterdns your also restarted every xx seconds.
            Not good at all.

            System routes are added and deleted several times per minute on your pfSense ?

            @helgew said in Frequent restarts of both dhcpd and unbound:

            That'll be a last resort given the extent of my configuration and the disruption it would cause.

            I understand.
            But making it complex regards you with complex 'debug' phases.
            Some one said ones 'keep it simple'. ;)

            Btw : my filterdns doesn't restart often. Has yours always the same PID ?

            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
            • H
              helgew @Gertjan
              last edited by

              @gertjan said in Frequent restarts of both dhcpd and unbound:

              dhcpd service restarts are less common.
              Example : It (can) happen when interfaces are going down and up.

              This really helped me find the underlying cause: an OpenVPN tunnel with a flaky server that would go away quite frequently. Disabling the OpenVPN client configuration solved the frequent resolver and dhcpd restarts.

              Again, thank you for your help. Much appreciated!

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