[Pkg-dns-devel] Bug#765831: still fails to start

[Corey Hickey]

I've been having this problem a while, and I ran across this bug report.

Ondřej Surý's suggestion of 'ip-transparent: yes' did work for me. This
is a sufficient workaround for me, but I investigated further in the hope
that it is useful.


Peter Palfrader's suggestion of
/etc/systemd/system/nsd.service.d/wait-for-eth0.conf did not work for me.

I had to modify it (I think I did it right) to:

[Unit]
After=ifup at eth0.service


Still, it appears that systemd does try to start nsd after eth0 is up.
...at least 0.3 seconds after dhclient gets an address. Is there
something else that happens in the background before a socket can be
bound? I'm not aware of anything, but not very familiar with it. Here's
the relevant parts of the log:


Jan 17 22:28:56.074040 juniper dhclient[2798]: Internet Systems Consortium DHCP Client 4.3.1
Jan 17 22:28:56.074058 juniper dhclient[2798]: Copyright 2004-2014 Internet Systems Consortium.
Jan 17 22:28:56.074062 juniper dhclient[2798]: All rights reserved.
Jan 17 22:28:56.074066 juniper dhclient[2798]: For info, please visit https://www.isc.org/software/dhcp/
Jan 17 22:28:56.074071 juniper dhclient[2798]: 
Jan 17 22:28:56.075015 juniper networking[2764]: Configuring network interfaces...Internet Systems Consortium DHCP Client 4.3
Jan 17 22:28:56.075176 juniper networking[2764]: Copyright 2004-2014 Internet Systems Consortium.
Jan 17 22:28:56.075294 juniper networking[2764]: All rights reserved.
Jan 17 22:28:56.075655 juniper networking[2764]: For info, please visit https://www.isc.org/software/dhcp/
Jan 17 22:28:56.088676 juniper kernel: 8021q: adding VLAN 0 to HW filter on device eth0
Jan 17 22:28:56.108710 juniper dhclient[2798]: Listening on LPF/eth0/f2:3c:91:93:70:77
Jan 17 22:28:56.108726 juniper dhclient[2798]: Sending on   LPF/eth0/f2:3c:91:93:70:77
Jan 17 22:28:56.108749 juniper dhclient[2798]: Sending on   Socket/fallback
Jan 17 22:28:56.108782 juniper dhclient[2798]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
Jan 17 22:28:56.109427 juniper networking[2764]: Listening on LPF/eth0/f2:3c:91:93:70:77
Jan 17 22:28:56.109600 juniper networking[2764]: Sending on   LPF/eth0/f2:3c:91:93:70:77
Jan 17 22:28:56.109724 juniper networking[2764]: Sending on   Socket/fallback
Jan 17 22:28:56.109839 juniper networking[2764]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
Jan 17 22:28:56.109915 juniper dhclient[2798]: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Jan 17 22:28:56.109951 juniper dhclient[2798]: DHCPOFFER from <xxx>
Jan 17 22:28:56.110406 juniper networking[2764]: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Jan 17 22:28:56.110527 juniper networking[2764]: DHCPOFFER from <xxx>
Jan 17 22:28:56.110581 juniper dhclient[2798]: DHCPACK from <xxx>
Jan 17 22:28:56.111011 juniper networking[2764]: DHCPACK from <xxx>
Jan 17 22:28:56.129742 juniper dhclient[2798]: bound to <xxx> -- renewal in 33383 seconds.
Jan 17 22:28:56.130320 juniper networking[2764]: bound to <xxx> -- renewal in 33383 seconds.
Jan 17 22:28:56.215353 juniper kernel: random: nonblocking pool is initialized
Jan 17 22:28:56.221657 juniper networking[2764]: done.
Jan 17 22:28:56.222653 juniper systemd[1]: Started LSB: Raise network interfaces..
Jan 17 22:28:56.222869 juniper systemd[1]: Starting Network.
Jan 17 22:28:56.223670 juniper systemd[1]: Reached target Network.
Jan 17 22:28:56.223697 juniper systemd[1]: Starting Network is Online.
Jan 17 22:28:56.224509 juniper systemd[1]: Reached target Network is Online.

Jan 17 22:28:56.239185 juniper systemd[1]: Starting Name Server Daemon...

Jan 17 22:28:56.435619 juniper systemd[1]: Started Name Server Daemon.

Jan 17 22:28:56.498818 juniper nsd[3010]: can't bind udp socket: Cannot assign requested address
Jan 17 22:28:56.498835 juniper nsd[3010]: server initialization failed, nsd could not be started
Jan 17 22:28:56.499471 juniper nsd[3010]: [2016-01-17 22:28:56.498] nsd[3010]: error: can't bind udp socket: Cannot assign requested address
Jan 17 22:28:56.499591 juniper nsd[3010]: [2016-01-17 22:28:56.498] nsd[3010]: error: server initialization failed, nsd could not be started
Jan 17 22:28:56.499738 juniper systemd[1]: nsd.service: main process exited, code=exited, status=1/FAILURE
Jan 17 22:28:56.499973 juniper systemd[1]: Unit nsd.service entered failed state.


Thanks,
Corey