dhcpcd-discuss

Hi,

	[ This ia a curiosity question really, not a complaint ]

I am using dhcpcd on a ipv4-only network, and of course, when dhcpcd starts it
listens on the UDP port 68 (being a bootp client as well). Specifically, it
does so on a wildcard address, i.e. *:68 as shown by ss(8).

However, after the 1st lease renewal, ss(8) also shows that dhcpcd binds to the
very interface it is running on:
------
$ ss -aun
State      Recv-Q Send-Q Local Address:Port     Peer Address:Port 
UNCONN     0      0      167.96.160.66%bond0:68 *:*     
UNCONN     0      0                        *:68 *:*
$ pgrep -af dhcpcd
502 /usr/bin/dhcpcd -4dB -t 0 bond0
------
This happens only on my university network, and does not occur on my simple
home LAN (dhcp server is dnsmasq). 

So, why does dhcpcd not bind to the interface right from the start, but rather
after renewing the lease? I am also attaching the relevant fragment of the log
file...

Thanks,
-- 
Leonid Isaev
GPG fingerprints: DA92 034D B4A8 EC51 7EA6  20DF 9291 EE8A 043C B8C4
                  C0DF 20D0 C075 C3F1 E1BE  775A A7AE F6CB 164B 5A6D

2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: bond0: delaying DHCP for 0.8 seconds
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: delaying DHCP for 0.8 seconds
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: bond0: carrier lost
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' NOCARRIER
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: carrier lost
2014-08-31T14:01:58.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' NOCARRIER
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: bond0: using ClientID 01:f0:1f:af:4d:36:99
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: using ClientID 01:f0:1f:af:4d:36:99
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: bond0: reading lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: reading lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: bond0: discarding expired lease
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: bond0: soliciting a DHCP lease
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: bond0: sending DISCOVER (xid 0x52db0179), next in 3.8 seconds
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: discarding expired lease
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: soliciting a DHCP lease
2014-08-31T14:01:59.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending DISCOVER (xid 0x52db0179), next in 3.8 seconds
2014-08-31T14:02:02.000-04:00 borei dhcpcd[502]: bond0: sending DISCOVER (xid 0x52db0179), next in 7.3 seconds
2014-08-31T14:02:02.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending DISCOVER (xid 0x52db0179), next in 7.3 seconds
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: bond0: carrier acquired
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: carrier acquired
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: bond0: delaying DHCP for 0.8 seconds
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: delaying DHCP for 0.8 seconds
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: bond0: using ClientID 01:f0:1f:af:4d:36:99
2014-08-31T14:02:03.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: using ClientID 01:f0:1f:af:4d:36:99
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: bond0: reading lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: bond0: discarding expired lease
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: bond0: soliciting a DHCP lease
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: bond0: sending DISCOVER (xid 0x9fe17410), next in 3.6 seconds
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: reading lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: discarding expired lease
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: soliciting a DHCP lease
2014-08-31T14:02:04.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending DISCOVER (xid 0x9fe17410), next in 3.6 seconds
2014-08-31T14:02:07.000-04:00 borei dhcpcd[502]: bond0: sending DISCOVER (xid 0x9fe17410), next in 8.4 seconds
2014-08-31T14:02:07.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending DISCOVER (xid 0x9fe17410), next in 8.4 seconds
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: bond0: offered 167.96.160.66 from 1.1.1.1
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: bond0: sending REQUEST (xid 0x9fe17410), next in 4.8 seconds
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: offered 167.96.160.66 from 1.1.1.1
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending REQUEST (xid 0x9fe17410), next in 4.8 seconds
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: bond0: acknowledged 167.96.160.66 from 1.1.1.1
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: acknowledged 167.96.160.66 from 1.1.1.1
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: bond0: checking for 167.96.160.66
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: bond0: sending ARP probe (1 of 3), next in 1.9 seconds
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: checking for 167.96.160.66
2014-08-31T14:02:08.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP probe (1 of 3), next in 1.9 seconds
2014-08-31T14:02:10.000-04:00 borei dhcpcd[502]: bond0: sending ARP probe (2 of 3), next in 1.6 seconds
2014-08-31T14:02:10.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP probe (2 of 3), next in 1.6 seconds
2014-08-31T14:02:12.000-04:00 borei dhcpcd[502]: bond0: sending ARP probe (3 of 3), next in 2.0 seconds
2014-08-31T14:02:12.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP probe (3 of 3), next in 2.0 seconds
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: leased 167.96.160.66 for 600 seconds
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: renew in 300 seconds, rebind in 525 seconds
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: adding IP address 167.96.160.66/19
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: adding route to 167.96.160.0/19
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: adding default route via 167.96.160.1
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: writing lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' BOUND
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: leased 167.96.160.66 for 600 seconds
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: renew in 300 seconds, rebind in 525 seconds
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: adding IP address 167.96.160.66/19
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: adding route to 167.96.160.0/19
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: adding default route via 167.96.160.1
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: writing lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' BOUND
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: bond0: sending ARP announce (1 of 2), next in 2.0 seconds
2014-08-31T14:02:14.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP announce (1 of 2), next in 2.0 seconds
2014-08-31T14:02:16.000-04:00 borei dhcpcd[502]: bond0: sending ARP announce (2 of 2)
2014-08-31T14:02:16.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP announce (2 of 2)
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: renewing lease of 167.96.160.66
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: rebind in 225 seconds, expire in 300 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: sending REQUEST (xid 0x4e832276), next in 4.2 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: renewing lease of 167.96.160.66
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: rebind in 225 seconds, expire in 300 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending REQUEST (xid 0x4e832276), next in 4.2 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: acknowledged 167.96.160.66 from 1.1.1.1
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: leased 167.96.160.66 for 1800 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: renew in 900 seconds, rebind in 1575 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: IP address 167.96.160.66/19 already exists
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: writing lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' RENEW
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: acknowledged 167.96.160.66 from 1.1.1.1
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: leased 167.96.160.66 for 1800 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: renew in 900 seconds, rebind in 1575 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: IP address 167.96.160.66/19 already exists
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: writing lease `/var/lib/dhcpcd/dhcpcd-bond0.lease'
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' RENEW
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: bond0: sending ARP announce (1 of 2), next in 2.0 seconds
2014-08-31T14:07:13.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP announce (1 of 2), next in 2.0 seconds
2014-08-31T14:07:15.000-04:00 borei dhcpcd[502]: bond0: sending ARP announce (2 of 2)
2014-08-31T14:07:15.000-04:00 borei dhcpcd[502]: dhcpcd[502]: bond0: sending ARP announce (2 of 2)

Attachment: pgp559F1lmUKj.pgp
Description: PGP signature