Source: bind9
Version: 1:9.9.5.dfsg-5
Severity: important
Hi.
Since a while I have the problem that bind9 crashes at start.
It may be since I've swiched to systemd, but it might have also
happened before already (I'm not sure about that anymore.
This is systemd's status:
# systemctl status bind9.service -l
● bind9.service - BIND Domain Name Server
Loaded: loaded (/lib/systemd/system/bind9.service; enabled)
Drop-In: /run/systemd/generator/bind9.service.d
└─50-insserv.conf-$named.conf
Active: failed (Result: exit-code) since Sat 2014-11-08 01:32:49 CET; 50s ago
Docs: man:named(8)
Process: 1975 ExecStop=/usr/sbin/rndc stop (code=exited, status=1/FAILURE)
Process: 1895 ExecStart=/usr/sbin/named -f -u bind (code=exited,
status=1/FAILURE)
Main PID: 1895 (code=exited, status=1/FAILURE)
Nov 08 01:32:49 kronecker systemd[1]: Forked /usr/sbin/rndc as 1975
Nov 08 01:32:49 kronecker systemd[1]: bind9.service changed running -> stop
Nov 08 01:32:49 kronecker systemd[1975]: Executing: /usr/sbin/rndc stop
Nov 08 01:32:49 kronecker systemd[1]: Child 1975 belongs to bind9.service
Nov 08 01:32:49 kronecker systemd[1]: bind9.service: control process exited,
code=exited status=1
Nov 08 01:32:49 kronecker systemd[1]: bind9.service got final SIGCHLD for state
stop
Nov 08 01:32:49 kronecker systemd[1]: bind9.service changed stop -> failed
Nov 08 01:32:49 kronecker systemd[1]: Unit bind9.service entered failed state.
Nov 08 01:32:49 kronecker systemd[1]: bind9.service: cgroup is empty
Nov 08 01:32:49 kronecker rndc[1975]: rndc: couldn't get address for
'localhost.': not found
The "localhost." doesn't really matter, I use absolute domain
names in all of my bind configuration, but when I change it to
"localhost" it happens as well.
This is more from the logs:
# journalctl | grep "rndc\|bind9\|named"
Nov 08 01:32:02 kronecker systemd[1]: Installed new job bind9.service/start as
103
Nov 08 01:32:48 kronecker systemd[1]: About to execute: /usr/sbin/named -f -u
bind
Nov 08 01:32:48 kronecker systemd[1]: Forked /usr/sbin/named as 1895
Nov 08 01:32:48 kronecker systemd[1]: bind9.service changed dead -> running
Nov 08 01:32:48 kronecker systemd[1]: Job bind9.service/start finished,
result=done
Nov 08 01:32:48 kronecker systemd[1895]: Executing: /usr/sbin/named -f -u bind
Nov 08 01:32:48 kronecker named[1895]: starting BIND 9.9.5-5-Debian -f -u bind
Nov 08 01:32:48 kronecker named[1895]: built with '--prefix=/usr'
'--mandir=/usr/share/man' '--infodir=/usr/share/info' '--sysconfdir=/etc/bind'
'--localstatedir=/var' '--enable-threads' '--enable-largefile' '--with-libtool'
'--enable-shared' '--enable-static' '--with-openssl=/usr' '--with-gssapi=/usr'
'--with-gnu-ld' '--with-geoip=/usr' '--with-atf=no' '--enable-ipv6'
'--enable-rrl' '--enable-filter-aaaa' 'CFLAGS=-fno-strict-aliasing
-fno-delete-null-pointer-checks -DDIG_SIGCHASE -O2'
Nov 08 01:32:48 kronecker named[1895]:
----------------------------------------------------
Nov 08 01:32:48 kronecker named[1895]: BIND 9 is maintained by Internet Systems
Consortium,
Nov 08 01:32:48 kronecker named[1895]: Inc. (ISC), a non-profit 501(c)(3)
public-benefit
Nov 08 01:32:48 kronecker named[1895]: corporation. Support and training for
BIND 9 are
Nov 08 01:32:48 kronecker named[1895]: available at https://www.isc.org/support
Nov 08 01:32:48 kronecker named[1895]:
----------------------------------------------------
Nov 08 01:32:48 kronecker named[1895]: adjusted limit on open files from 4096
to 1048576
Nov 08 01:32:48 kronecker named[1895]: found 2 CPUs, using 2 worker threads
Nov 08 01:32:48 kronecker named[1895]: using 2 UDP listeners per interface
Nov 08 01:32:48 kronecker named[1895]: using up to 4096 sockets
Nov 08 01:32:49 kronecker named[1895]: loading configuration from
'/etc/bind/named.conf'
Nov 08 01:32:49 kronecker named[1895]: reading built-in trusted keys from file
'/etc/bind/bind.keys'
Nov 08 01:32:49 kronecker named[1895]: using default UDP/IPv4 port range:
[1024, 65535]
Nov 08 01:32:49 kronecker named[1895]: using default UDP/IPv6 port range:
[1024, 65535]
Nov 08 01:32:49 kronecker named[1895]: listening on IPv4 interface lo,
127.0.0.1#53
Nov 08 01:32:49 kronecker named[1895]: listening on IPv4 interface eth0,
85.10.205.199#53
Nov 08 01:32:49 kronecker named[1895]: listening on IPv6 interface lo, ::1#53
Nov 08 01:32:49 kronecker named[1895]: generating session key for dynamic DNS
Nov 08 01:32:49 kronecker named[1895]: sizing zone task pool based on 47 zones
Nov 08 01:32:49 kronecker named[1895]: acache 0x7fa06817e660 cleaning interval
set to 3600.
Nov 08 01:32:49 kronecker named[1895]: could not get query source dispatcher
(2a01:4f8:a0:4024::a:0#0)
Nov 08 01:32:49 kronecker named[1895]: loading configuration: address not
available
Nov 08 01:32:49 kronecker named[1895]: exiting (due to fatal error)
Nov 08 01:32:49 kronecker systemd[1]: Child 1895 (named) died (code=exited,
status=1/FAILURE)
Nov 08 01:32:49 kronecker systemd[1]: Child 1895 belongs to bind9.service
Nov 08 01:32:49 kronecker systemd[1]: bind9.service: main process exited,
code=exited, status=1/FAILURE
Nov 08 01:32:49 kronecker systemd[1]: About to execute: /usr/sbin/rndc stop
Nov 08 01:32:49 kronecker systemd[1]: Forked /usr/sbin/rndc as 1975
Nov 08 01:32:49 kronecker systemd[1]: bind9.service changed running -> stop
Nov 08 01:32:49 kronecker systemd[1975]: Executing: /usr/sbin/rndc stop
Nov 08 01:32:49 kronecker systemd[1]: Child 1975 (rndc) died (code=exited,
status=1/FAILURE)
Nov 08 01:32:49 kronecker systemd[1]: Child 1975 belongs to bind9.service
Nov 08 01:32:49 kronecker systemd[1]: bind9.service: control process exited,
code=exited status=1
Nov 08 01:32:49 kronecker systemd[1]: bind9.service got final SIGCHLD for state
stop
Nov 08 01:32:49 kronecker systemd[1]: bind9.service changed stop -> failed
Nov 08 01:32:49 kronecker systemd[1]: Unit bind9.service entered failed state.
Nov 08 01:32:49 kronecker systemd[1]: bind9.service: cgroup is empty
Nov 08 01:32:49 kronecker rndc[1975]: rndc: couldn't get address for
'localhost.': not found
Nov 08 01:33:19 kronecker systemd[1]: Got message type=method_call sender=n/a
destination=org.freedesktop.systemd1
object=/org/freedesktop/systemd1/unit/bind9_2eservice
interface=org.freedesktop.DBus.Properties member=GetAll cookie=1 reply_cookie=0
error=n/a
Nov 08 01:33:39 kronecker systemd[1]: Got message type=method_call sender=n/a
destination=org.freedesktop.systemd1
object=/org/freedesktop/systemd1/unit/bind9_2eservice
interface=org.freedesktop.DBus.Properties member=GetAll cookie=1 reply_cookie=0
error=n/a
The problem seems to be this line
Nov 08 01:32:49 kronecker named[1895]: could not get query source dispatcher
(2a01:4f8:a0:4024::a:0#0)
Now this smells like a mix of #766943, #727073 and #766291,
but OTOH, I have enabled the 30 seconds sleep mentioned as a
ugly workaround in these other bugs... and all my other daemons
seem to be able to bind just fine.
So maybe it's something bind specific? I.e. that it starts
too early?
Once booting is finished and I log in, manually starting
bind via systemd works just fine.
Any ideas?
Chris.
-- System Information:
Debian Release: jessie/sid
APT prefers unstable
APT policy: (500, 'unstable')
Architecture: amd64 (x86_64)
Kernel: Linux 3.16.0-4-amd64 (SMP w/8 CPU cores)
Locale: LANG=en_DE.utf8, LC_CTYPE=en_DE.utf8 (charmap=UTF-8)
Shell: /bin/sh linked to /bin/dash
--
To UNSUBSCRIBE, email to debian-bugs-dist-requ...@lists.debian.org
with a subject of "unsubscribe". Trouble? Contact listmas...@lists.debian.org
