FS#59592 - unbound 1.7.3-3 startup failed

Attached to Project: Community Packages
Opened by Cosmin (q4brk) - Thursday, 09 August 2018, 17:50 GMT
Last edited by Doug Newgard (Scimmia) - Thursday, 09 August 2018, 17:54 GMT
Task Type Bug Report
Category Packages
Status Closed
Assigned To No-one
Architecture All
Severity Medium
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 0
Private No

Details

Description:

The systemd service for unbound version 1.7.3-3 contains the following:

[Service]
ExecStart=/usr/bin/unbound
ExecReload=/bin/kill -HUP $MAINPID
Restart=always

The service doesn't start up properly:

myhost:~ [1]# systemctl restart unbound

myhost:~ [1]# journalctl -f | grep unbound .
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] notice: init module 0: validator
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] notice: init module 1: iterator
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] info: start of service (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] info: service stopped (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Aug 09 06:18:31 myhost unbound[2031]: [2031:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out
Aug 09 06:18:31 myhost systemd[1]: unbound.service: Service RestartSec=100ms expired, scheduling restart.
Aug 09 06:18:31 myhost systemd[1]: unbound.service: Scheduled restart job, restart counter is at 1.
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] notice: init module 0: validator
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] notice: init module 1: iterator
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] info: start of service (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] info: service stopped (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Aug 09 06:18:31 myhost unbound[2033]: [2033:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out
Aug 09 06:18:31 myhost systemd[1]: unbound.service: Service RestartSec=100ms expired, scheduling restart.
Aug 09 06:18:31 myhost systemd[1]: unbound.service: Scheduled restart job, restart counter is at 2.
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] notice: init module 0: validator
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] notice: init module 1: iterator
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] info: start of service (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] info: service stopped (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Aug 09 06:18:31 myhost unbound[2035]: [2035:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out
Aug 09 06:18:31 myhost systemd[1]: unbound.service: Service RestartSec=100ms expired, scheduling restart.
Aug 09 06:18:31 myhost systemd[1]: unbound.service: Scheduled restart job, restart counter is at 3.
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] notice: init module 0: validator
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] notice: init module 1: iterator
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] info: start of service (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] info: service stopped (unbound 1.7.3).
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Aug 09 06:18:31 myhost unbound[2037]: [2037:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out
Aug 09 06:18:32 myhost systemd[1]: unbound.service: Service RestartSec=100ms expired, scheduling restart.
Aug 09 06:18:32 myhost systemd[1]: unbound.service: Scheduled restart job, restart counter is at 4.
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] notice: init module 0: validator
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] notice: init module 1: iterator
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] info: start of service (unbound 1.7.3).
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] info: service stopped (unbound 1.7.3).
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] info: server stats for thread 0: 0 queries, 0 answers from cache, 0 recursions, 0 prefetch, 0 rejected by ip ratelimiting
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] info: server stats for thread 0: requestlist max 0 avg 0 exceeded 0 jostled 0
Aug 09 06:18:32 myhost unbound[2039]: [2039:0] info: mesh has 0 recursion states (0 with reply, 0 detached), 0 waiting replies, 0 recursion replies sent, 0 replies dropped, 0 states jostled out
Aug 09 06:18:32 myhost systemd[1]: unbound.service: Service RestartSec=100ms expired, scheduling restart.
Aug 09 06:18:32 myhost systemd[1]: unbound.service: Scheduled restart job, restart counter is at 5.
Aug 09 06:18:32 myhost systemd[1]: unbound.service: Start request repeated too quickly.
Aug 09 06:18:32 myhost systemd[1]: unbound.service: Failed with result 'start-limit-hit'.

# cat /etc/unbound/unbound.conf
## DNS Over TLS, Simple ENCRYPTED recursive caching DNS, TCP port 853
## unbound.conf -- https://calomel.org
#
server:
directory: /etc/unbound
interface: 127.0.0.1
port: 11153
do-ip4: yes
do-ip6: no

access-control: 127.0.0.0/8 allow
hide-identity: yes
hide-version: yes
use-caps-for-id: yes

ssl-upstream: yes

do-tcp: yes
minimal-responses: yes
prefetch: yes
qname-minimisation: yes
rrset-roundrobin: yes

verbosity: 2

forward-zone:
name: "."
forward-addr: 9.9.9.9@853 # quad9.net primary
forward-addr: 1.1.1.1@853 # cloudflare primary
forward-addr: 149.112.112.112@853 # quad9.net secondary

# pacman -Q | grep -E "systemd|unbound"
libsystemd 239.0-2
python2-systemd 234-2
systemd 239.0-2
systemd-sysvcompat 239.0-2
unbound 1.7.3-3


It seems that changing the ExecStart=/usr/bin/unbound line to ExecStart=/usr/bin/unbound -d fixes the issue (unbound doesn't fork in the background anymore)

This task depends upon

Closed by  Doug Newgard (Scimmia)
Thursday, 09 August 2018, 17:54 GMT
Reason for closing:  Duplicate
Additional comments about closing:   FS#59503   FS#59561 

Loading...