dhcpcd-discuss

Hi Roy,

On Tue, 7 Jan 2020 at 23:48, James Gross <jg7113@xxxxxxxxx> wrote:

> Hi Roy,
>
> On Tue, 7 Jan 2020 at 14:32, Roy Marples <roy@xxxxxxxxxxxx> wrote:
>
>>
>> I have reworked the timing code yet again so that it should be more
>> accurate.
>> If you could re-test the git master again I would appreciate it!
>
>
I have set up my home network to use DHCPv6

The version of dhcpcd from git master here doesn't pick up an IPv6 address

Dhcpcd version 8.1.5 does.

Master:

cat /var/log/dhcpcd.log
Jan 16 12:52:56 [945]: sending signal TERM to pid 363
Jan 16 12:52:56 [945]: waiting for pid 363 to exit
Jan 16 12:53:09 [280]: dhcpcd-8.99.0 starting
Jan 16 12:53:09 [301]: udev: starting
Jan 16 12:53:09 [301]: dev: loaded udev
Jan 16 12:53:09 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
PREINIT
Jan 16 12:53:09 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
NOCARRIER
Jan 16 12:53:09 [301]: eth0: waiting for carrier
Jan 16 12:53:09 [301]: eth0: carrier acquired
Jan 16 12:53:09 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
CARRIER
Jan 16 12:53:09 [301]: DUID 00:01:00:01:25:1e:c1:c4:b8:27:eb:fe:50:f2
Jan 16 12:53:09 [301]: eth0: IAID eb:ab:05:a7
Jan 16 12:53:09 [301]: eth0: adding address fe80::ba27:ebff:feab:5a7
Jan 16 12:53:09 [301]: eth0: pltime infinity, vltime infinity
Jan 16 12:53:09 [301]: eth0: delaying IPv6 router solicitation for 0.6
seconds
Jan 16 12:53:09 [301]: eth0: delaying IPv4 for 0.7 seconds
Jan 16 12:53:09 [301]: eth0: soliciting an IPv6 router
Jan 16 12:53:09 [301]: eth0: delaying Router Solicitation for LL address
Jan 16 12:53:09 [301]: eth0: using ClientID 01:b8:27:eb:ab:05:a7
Jan 16 12:53:09 [301]: eth0: reading lease `/var/lib/dhcpcd5/eth0.lease'
Jan 16 12:53:09 [301]: eth0: rebinding lease of 192.168.55.22
Jan 16 12:53:09 [301]: eth0: sending REQUEST (xid 0x4656ec04), next in 4.9
seconds
Jan 16 12:53:11 [301]: eth0: carrier lost
Jan 16 12:53:11 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
NOCARRIER
Jan 16 12:53:11 [301]: eth0: deleting address fe80::ba27:ebff:feab:5a7
Jan 16 12:53:11 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks' EXPIRE
Jan 16 12:53:11 [301]: eth0: carrier acquired
Jan 16 12:53:11 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
CARRIER
Jan 16 12:53:11 [301]: eth0: IAID eb:ab:05:a7
Jan 16 12:53:11 [301]: eth0: adding address fe80::ba27:ebff:feab:5a7
Jan 16 12:53:11 [301]: eth0: pltime infinity, vltime infinity
Jan 16 12:53:11 [301]: eth0: delaying IPv6 router solicitation for 0.9
seconds
Jan 16 12:53:11 [301]: eth0: delaying IPv4 for 0.7 seconds
Jan 16 12:53:11 [301]: eth0: soliciting an IPv6 router
Jan 16 12:53:11 [301]: eth0: delaying Router Solicitation for LL address
Jan 16 12:53:11 [301]: eth0: using ClientID 01:b8:27:eb:ab:05:a7
Jan 16 12:53:11 [301]: eth0: reading lease `/var/lib/dhcpcd5/eth0.lease'
Jan 16 12:53:11 [301]: eth0: rebinding lease of 192.168.55.22
Jan 16 12:53:11 [301]: eth0: sending REQUEST (xid 0xcba21024), next in 4.6
seconds
Jan 16 12:53:13 [301]: eth0: sending Router Solicitation
Jan 16 12:53:13 [301]: eth0: Router Advertisement from
fe80::be99:11ff:fe81:6408
Jan 16 12:53:13 [301]: eth0: no global addresses for default route
Jan 16 12:53:13 [301]: eth0: adding route to 2001:8b0:de0a:bb0e::/64
Jan 16 12:53:13 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:53:13 [301]: eth0: reading lease `/var/lib/dhcpcd5/eth0.lease6'
Jan 16 12:53:13 [301]: eth0: confirming prior DHCPv6 lease
Jan 16 12:53:13 [301]: eth0: delaying CONFIRM6 (xid 0x227bb7), next in 0.4
seconds
Jan 16 12:53:13 [301]: eth0: broadcasting CONFIRM6 (xid 0x227bb7), next in
1.0 seconds
Jan 16 12:53:13 [301]: eth0: REPLY6 received from fe80::be99:11ff:fe81:6408
Jan 16 12:53:13 [301]: eth0: adding address 2001:8b0:de0a:bb0e::2/128
Jan 16 12:53:13 [301]: eth0: pltime 86400 seconds, vltime 86400 seconds
Jan 16 12:53:13 [301]: eth0: renew in 43200, rebind in 69120, expire in
86400 seconds
Jan 16 12:53:13 [301]: eth0: writing lease `/var/lib/dhcpcd5/eth0.lease6'
Jan 16 12:53:13 [301]: eth0: waiting for DHCPv6 DAD to complete
Jan 16 12:53:13 [301]: eth0: adding default route via
fe80::be99:11ff:fe81:6408
Jan 16 12:53:13 [301]: eth0: pid 0 deleted address 2001:8b0:de0a:bb0e::2/128
Jan 16 12:53:13 [301]: eth0: deleting default route via
fe80::be99:11ff:fe81:6408
Jan 16 12:53:15 [301]: eth0: sending REQUEST (xid 0xcba21024), next in 8.6
seconds
Jan 16 12:53:15 [301]: eth0: acknowledged 192.168.55.22 from 192.168.55.1
Jan 16 12:53:15 [301]: eth0: probing address 192.168.55.22/24
Jan 16 12:53:15 [301]: eth0: probing for 192.168.55.22
Jan 16 12:53:15 [301]: eth0: ARP probing 192.168.55.22 (1 of 3), next in
1.4 seconds
Jan 16 12:53:16 [301]: eth0: ARP probing 192.168.55.22 (2 of 3), next in
1.3 seconds
Jan 16 12:53:17 [301]: eth0: ARP probing 192.168.55.22 (3 of 3), next in
2.0 seconds
Jan 16 12:53:19 [301]: eth0: DAD completed for 192.168.55.22
Jan 16 12:53:19 [301]: eth0: leased 192.168.55.22 for 300 seconds
Jan 16 12:53:19 [301]: eth0: renew in 150 seconds, rebind in 262 seconds
Jan 16 12:53:19 [301]: eth0: writing lease `/var/lib/dhcpcd5/eth0.lease'
Jan 16 12:53:19 [301]: eth0: adding IP address 192.168.55.22/24 broadcast
192.168.55.255
Jan 16 12:53:19 [301]: eth0: adding route to 192.168.55.0/24
Jan 16 12:53:19 [301]: eth0: adding default route via 192.168.55.1
Jan 16 12:53:19 [301]: eth0: ARP announcing 192.168.55.22 (1 of 2), next in
2.0 seconds
Jan 16 12:53:19 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks' BOUND
Jan 16 12:53:20 [301]: forked to background, child pid 301
Jan 16 12:53:21 [301]: eth0: ARP announcing 192.168.55.22 (2 of 2)
Jan 16 12:53:23 [301]: eth0: Router Advertisement from
fe80::be99:11ff:fe81:6408
Jan 16 12:53:23 [301]: eth0: no global addresses for default route
Jan 16 12:53:23 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:53:33 [301]: eth0: Router Advertisement from
fe80::be99:11ff:fe81:6408
Jan 16 12:53:33 [301]: eth0: no global addresses for default route
Jan 16 12:53:33 [301]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT

8.1.5:

cat /var/log/dhcpcd.log
Jan 16 12:49:06 [3179]: sending signal TERM to pid 2195
Jan 16 12:49:06 [3179]: waiting for pid 2195 to exit
Jan 16 12:49:09 [297]: dhcpcd-8.1.5 starting
Jan 16 12:49:09 [297]: udev: starting
Jan 16 12:49:09 [297]: dev: loaded udev
Jan 16 12:49:09 [297]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
PREINIT
Jan 16 12:49:09 [297]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
NOCARRIER
Jan 16 12:49:09 [297]: eth0: waiting for carrier
Jan 16 12:49:09 [297]: eth0: carrier acquired
Jan 16 12:49:09 [297]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
CARRIER
Jan 16 12:49:09 [297]: DUID 00:01:00:01:25:1e:c1:c0:b8:27:eb:5f:0a:3f
Jan 16 12:49:09 [297]: eth0: IAID eb:0a:5f:6a
Jan 16 12:49:09 [297]: eth0: adding address fe80::ba27:ebff:fe0a:5f6a
Jan 16 12:49:09 [297]: eth0: pltime infinity, vltime infinity
Jan 16 12:49:09 [297]: eth0: delaying IPv6 router solicitation for 1.0
seconds
Jan 16 12:49:09 [297]: eth0: delaying IPv4 for 0.6 seconds
Jan 16 12:49:10 [297]: eth0: using ClientID 01:b8:27:eb:0a:5f:6a
Jan 16 12:49:10 [297]: eth0: reading lease `/var/lib/dhcpcd5/eth0.lease'
Jan 16 12:49:10 [297]: eth0: rebinding lease of 192.168.55.26
Jan 16 12:49:10 [297]: eth0: sending REQUEST (xid 0x277e09a6), next in 4.6
seconds
Jan 16 12:49:10 [297]: eth0: acknowledged 192.168.55.26 from 192.168.55.1
Jan 16 12:49:10 [297]: eth0: probing address 192.168.55.26/24
Jan 16 12:49:10 [297]: eth0: probing for 192.168.55.26
Jan 16 12:49:10 [297]: eth0: ARP probing 192.168.55.26 (1 of 3), next in
1.9 seconds
Jan 16 12:49:10 [297]: eth0: soliciting an IPv6 router
Jan 16 12:49:10 [297]: eth0: delaying Router Solicitation for LL address
Jan 16 12:49:11 [297]: eth0: sending Router Solicitation
Jan 16 12:49:12 [297]: eth0: ARP probing 192.168.55.26 (2 of 3), next in
1.0 seconds
Jan 16 12:49:13 [297]: eth0: Router Advertisement from
fe80::be99:11ff:fe81:6408
Jan 16 12:49:13 [297]: eth0: no global addresses for default route
Jan 16 12:49:13 [297]: eth0: adding route to 2001:8b0:de0a:bb0e::/64
Jan 16 12:49:13 [297]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:49:13 [297]: eth0: reading lease `/var/lib/dhcpcd5/eth0.lease6'
Jan 16 12:49:13 [297]: eth0: confirming prior DHCPv6 lease
Jan 16 12:49:13 [297]: eth0: delaying CONFIRM6 (xid 0xfe65c5), next in 0.9
seconds
Jan 16 12:49:13 [297]: eth0: part of Router Advertisement expired
Jan 16 12:49:13 [297]: eth0: deleting route to 2001:8b0:de0a:bb0e::/64
Jan 16 12:49:13 [297]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:49:13 [297]: eth0: ARP probing 192.168.55.26 (3 of 3), next in
2.0 seconds
Jan 16 12:49:14 [297]: eth0: broadcasting CONFIRM6 (xid 0xfe65c5), next in
1.1 seconds
Jan 16 12:49:14 [297]: eth0: REPLY6 received from fe80::be99:11ff:fe81:6408
Jan 16 12:49:14 [297]: eth0: adding address 2001:8b0:de0a:bb0e::5/128
Jan 16 12:49:14 [297]: eth0: pltime 86400 seconds, vltime 86400 seconds
Jan 16 12:49:14 [297]: eth0: renew in 43200, rebind in 69120, expire in
86400 seconds
Jan 16 12:49:14 [297]: eth0: writing lease `/var/lib/dhcpcd5/eth0.lease6'
Jan 16 12:49:14 [297]: eth0: waiting for DHCPv6 DAD to complete
Jan 16 12:49:14 [297]: eth0: adding default route via
fe80::be99:11ff:fe81:6408
Jan 16 12:49:15 [297]: eth0: DHCPv6 DAD completed
Jan 16 12:49:15 [297]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
REBOOT6
Jan 16 12:49:15 [297]: forking to background
Jan 16 12:49:15 [297]: forked to background, child pid 430
Jan 16 12:49:15 [297]: eth0: sending NA for 2001:8b0:de0a:bb0e::5/128
Jan 16 12:49:15 [430]: eth0: sending NA for 2001:8b0:de0a:bb0e::5/128
Jan 16 12:49:15 [430]: eth0: DAD completed for 192.168.55.26
Jan 16 12:49:15 [430]: eth0: leased 192.168.55.26 for 300 seconds
Jan 16 12:49:15 [430]: eth0: renew in 150 seconds, rebind in 262 seconds
Jan 16 12:49:15 [430]: eth0: writing lease `/var/lib/dhcpcd5/eth0.lease'
Jan 16 12:49:15 [430]: eth0: adding IP address 192.168.55.26/24 broadcast
192.168.55.255
Jan 16 12:49:15 [430]: eth0: adding route to 192.168.55.0/24
Jan 16 12:49:15 [430]: eth0: adding default route via 192.168.55.1
Jan 16 12:49:15 [430]: eth0: ARP announcing 192.168.55.26 (1 of 2), next in
2.0 seconds
Jan 16 12:49:15 [430]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks' BOUND
Jan 16 12:49:16 [430]: eth0: sending NA for 2001:8b0:de0a:bb0e::5/128
Jan 16 12:49:17 [430]: eth0: sending NA for 2001:8b0:de0a:bb0e::5/128
Jan 16 12:49:17 [430]: eth0: ARP announcing 192.168.55.26 (2 of 2)
Jan 16 12:49:23 [430]: eth0: Router Advertisement from
fe80::be99:11ff:fe81:6408
Jan 16 12:49:23 [430]: eth0: adding route to 2001:8b0:de0a:bb0e::/64
Jan 16 12:49:23 [430]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:49:23 [430]: eth0: part of Router Advertisement expired
Jan 16 12:49:23 [430]: eth0: deleting route to 2001:8b0:de0a:bb0e::/64
Jan 16 12:49:23 [430]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:49:33 [430]: eth0: Router Advertisement from
fe80::be99:11ff:fe81:6408
Jan 16 12:49:33 [430]: eth0: adding route to 2001:8b0:de0a:bb0e::/64
Jan 16 12:49:33 [430]: eth0: executing `/lib/dhcpcd/dhcpcd-run-hooks'
ROUTERADVERT
Jan 16 12:49:33 [430]: eth0: part of Router Advertisement expired

Can you take a look?

Thanks,

James