bin/59098: new dhcpcd broken for ipv6

[christos%zoulas.com@localhost]

>Number:         59098
>Category:       bin
>Synopsis:       new dhcpcd broken for ipv6
>Confidential:   no
>Severity:       serious
>Priority:       medium
>Responsible:    bin-bug-people
>State:          open
>Class:          sw-bug
>Submitter-Id:   net
>Arrival-Date:   Mon Feb 24 16:40:00 +0000 2025
>Originator:     Christos Zoulas
>Release:        NetBSD 10.99.12
>Organization:
	no v6 for you buddy, inc.
>Environment:
System: NetBSD quasar.astron.com 10.99.12 NetBSD 10.99.12 (QUASAR) #41: Mon Feb 24 08:53:53 EST 2025 christos%quasar.astron.com@localhost:/usr/src/sys/arch/amd64/compile/QUASAR amd64
Architecture: x86_64
Machine: amd64
>Description:
	new dhcpcd does not get an ipv6 address

Working log:
Feb 24 11:31:38 [3847]: dhcpcd-10.0.8 starting
Feb 24 11:31:38 [3847]: chrooting as _dhcpcd to /var/chroot/dhcpcd
Feb 24 11:31:38 [3847]: sandbox: posix resource limited
Feb 24 11:31:38 [3635]: spawned manager process on PID 3635
Feb 24 11:31:38 [3635]: spawned privileged proxy on PID 3634
Feb 24 11:31:38 [3635]: spawned network proxy on PID 3636
Feb 24 11:31:38 [3635]: spawned controller proxy on PID 3639
Feb 24 11:31:38 [3635]: DUID 00:01:00:01:20:78:43:c4:00:0d:88:6e:5b:1c
Feb 24 11:31:38 [3635]: wm0: executing: /libexec/dhcpcd-run-hooks PREINIT
Feb 24 11:31:38 [3635]: wm0: executing: /libexec/dhcpcd-run-hooks CARRIER
Feb 24 11:31:38 [3635]: wm1: executing: /libexec/dhcpcd-run-hooks PREINIT
Feb 24 11:31:38 [3635]: wm1: executing: /libexec/dhcpcd-run-hooks CARRIER
Feb 24 11:31:38 [3635]: wm0: IAID 64:92:10:c8
Feb 24 11:31:38 [3635]: wm0: IA type 3 IAID 00:00:00:01
Feb 24 11:31:38 [3635]: wm0: IA type 25 IAID 00:00:00:02
Feb 24 11:31:38 [3635]: wm0: delaying IPv6 router solicitation for 0.2 seconds
Feb 24 11:31:38 [3635]: wm0: reading lease: /var/db/dhcpcd/wm0.lease6
Feb 24 11:31:38 [3635]: wm0: rebinding prior DHCPv6 lease
Feb 24 11:31:38 [3635]: wm0: delaying REBIND6 (xid 0x1f8c5f), next in 1.0 seconds
Feb 24 11:31:38 [3635]: wm0: delaying IPv4 for 1.5 seconds
Feb 24 11:31:38 [3635]: wm1: IAID 91:41:dc:87
Feb 24 11:31:38 [3635]: wm1: delaying IPv4 for 0.5 seconds
Feb 24 11:31:38 [3635]: wm0: soliciting an IPv6 router
Feb 24 11:31:38 [3635]: wm0: sending Router Solicitation
Feb 24 11:31:38 [3635]: wm0: Router Advertisement from fe80::217:10ff:fe87:1987
Feb 24 11:31:38 [3635]: wm0: adding route to 2604:2000:c02:5::/64
Feb 24 11:31:38 [3635]: wm0: adding route to 2604:2000:efc0:5::/64
Feb 24 11:31:38 [3635]: wm0: adding route to 2604:2000:402:5::/64
Feb 24 11:31:38 [3635]: wm0: adding route to 2604:2000:702:5::/64
Feb 24 11:31:38 [3635]: wm0: adding route to 2603:90c5:6:27::/64
Feb 24 11:31:38 [3635]: wm0: adding default route via fe80::217:10ff:fe87:1987
Feb 24 11:31:38 [3635]: wm0: executing: /libexec/dhcpcd-run-hooks ROUTERADVERT
Feb 24 11:31:39 [3635]: wm1: using hwaddr b4:96:91:41:dc:87
Feb 24 11:31:39 [3635]: wm1: reading lease: /var/db/dhcpcd/wm1.lease
Feb 24 11:31:39 [3635]: wm1: rebinding lease of 192.168.2.4
Feb 24 11:31:39 [3635]: wm1: ARP announcing 192.168.2.4 (1 of 2), next in 2.0 seconds
Feb 24 11:31:39 [3634]: wm1: spawned BPF ARP 192.168.2.4 on PID 3902
Feb 24 11:31:39 [3635]: wm1: sending REQUEST (xid 0xcf32ba5b), next in 4.9 seconds
Feb 24 11:31:39 [3634]: wm1: spawned BPF BOOTP on PID 3654
Feb 24 11:31:39 [3635]: wm1: acknowledged 192.168.2.4 from 192.168.2.9
Feb 24 11:31:39 [3635]: wm1: leased 192.168.2.4 for 604800 seconds
Feb 24 11:31:39 [3635]: wm1: renew in 302400 seconds, rebind in 529200 seconds
Feb 24 11:31:39 [3635]: wm1: writing lease: /var/db/dhcpcd/wm1.lease
Feb 24 11:31:39 [3635]: wm1: IP address 192.168.2.4/24 already exists
Feb 24 11:31:39 [3635]: wm1: adding route to 192.168.2.0/24
Feb 24 11:31:39 [3635]: wm1: adding default route via 192.168.2.9
Feb 24 11:31:39 [3635]: wm1: ARP announcing 192.168.2.4 (1 of 2), next in 2.0 seconds
Feb 24 11:31:39 [3635]: wm1: executing: /libexec/dhcpcd-run-hooks REBOOT
Feb 24 11:31:39 [3847]: forked to background
Feb 24 11:31:39 [3634]: wm1: BPF BOOTP exited from PID 3654
Feb 24 11:31:39 [3635]: wm0: multicasting REBIND6 (xid 0x1f8c5f), next in 1.0 seconds
Feb 24 11:31:39 [3635]: wm0: REPLY6 received from fe80::217:10ff:fe87:1987
Feb 24 11:31:39 [3635]: wm0: adding address 2604:2000:efc0:5:e4b1:2ad4:f14a:43f9/128
Feb 24 11:31:39 [3635]: wm0: pltime 604800 seconds, vltime 604800 seconds
Feb 24 11:31:39 [3635]: wm0: renew in 302400, rebind in 483840, expire in 604800 seconds
Feb 24 11:31:39 [3635]: lo0: adding reject route to 2603:7000:2600:29ad::/64 via ::1
Feb 24 11:31:39 [3635]: wm0: writing lease: /var/db/dhcpcd/wm0.lease6
Feb 24 11:31:39 [3635]: wm0: delegated prefix 2603:7000:2600:29ad::/64
Feb 24 11:31:39 [3635]: wm1: adding address 2603:7000:2600:29ad::1/64
Feb 24 11:31:39 [3635]: wm1: pltime 604800 seconds, vltime 604800 seconds
Feb 24 11:31:39 [3635]: wm1: waiting for DHCPv6 DAD to complete
Feb 24 11:31:39 [3635]: wm1: changing route to 2603:7000:2600:29ad::/64
Feb 24 11:31:39 [3635]: wm0: waiting for DHCPv6 DAD to complete
Feb 24 11:31:40 [3635]: wm0: using hwaddr 54:bf:64:92:10:c8
Feb 24 11:31:40 [3635]: wm0: reading lease: /var/db/dhcpcd/wm0.lease
Feb 24 11:31:40 [3635]: wm0: rebinding lease of 104.162.232.43
Feb 24 11:31:40 [3634]: wm0: spawned BPF ARP 104.162.232.43 on PID 3957
Feb 24 11:31:40 [3635]: wm0: ARP announcing 104.162.232.43 (1 of 2), next in 2.0 seconds
Feb 24 11:31:40 [3635]: wm0: sending REQUEST (xid 0x6f4b3a12), next in 4.4 seconds
Feb 24 11:31:40 [3634]: wm0: spawned BPF BOOTP on PID 3648
Feb 24 11:31:40 [3635]: wm0: acknowledged 104.162.232.43 from 142.254.161.89
Feb 24 11:31:40 [3635]: wm0: leased 104.162.232.43 for 56091 seconds
Feb 24 11:31:40 [3635]: wm0: renew in 12891 seconds, rebind in 45291 seconds
Feb 24 11:31:40 [3635]: wm0: writing lease: /var/db/dhcpcd/wm0.lease
Feb 24 11:31:40 [3635]: wm0: IP address 104.162.232.43/20 already exists
Feb 24 11:31:40 [3635]: wm0: adding route to 104.162.224.0/20
Feb 24 11:31:40 [3635]: wm0: changing default route via 104.162.224.1
Feb 24 11:31:40 [3635]: wm0: ARP announcing 104.162.232.43 (1 of 2), next in 2.0 seconds
Feb 24 11:31:40 [3635]: wm0: executing: /libexec/dhcpcd-run-hooks REBOOT
Feb 24 11:31:42 [3635]: wm1: ARP announcing 192.168.2.4 (2 of 2)
Feb 24 11:31:42 [3635]: wm0: ARP announcing 104.162.232.43 (2 of 2)
Feb 24 11:31:42 [3635]: wm0: sending NA for 2604:2000:efc0:5:e4b1:2ad4:f14a:43f9/128
Feb 24 11:31:42 [3635]: wm0: DHCPv6 DAD completed
Feb 24 11:31:42 [3635]: wm0: executing: /libexec/dhcpcd-run-hooks REBIND6
Feb 24 11:31:43 [3634]: wm0: BPF BOOTP exited from PID 3648
Feb 24 11:31:43 [3635]: wm1: DHCPv6 DAD completed
Feb 24 11:31:43 [3635]: wm1: executing: /libexec/dhcpcd-run-hooks DELEGATED6
Feb 24 11:31:43 [3635]: wm0: sending NA for 2604:2000:efc0:5:e4b1:2ad4:f14a:43f9/128
Feb 24 11:31:44 [3635]: wm0: sending NA for 2604:2000:efc0:5:e4b1:2ad4:f14a:43f9/128
Feb 24 11:31:44 [3634]: wm1: BPF ARP 192.168.2.4 exited from PID 3902
Feb 24 11:31:44 [3634]: wm0: BPF ARP 104.162.232.43 exited from PID 3957

Broken log:
Feb 24 16:22:41 [951]: dhcpcd-10.2.0 starting
Feb 24 16:22:41 [951]: chrooting as _dhcpcd to /var/chroot/dhcpcd
Feb 24 16:22:41 [951]: sandbox: posix resource limited
Feb 24 16:22:41 [953]: spawned manager process on PID 953
Feb 24 16:22:41 [953]: spawned privileged proxy on PID 954
Feb 24 16:22:41 [953]: spawned network proxy on PID 955
Feb 24 16:22:41 [953]: spawned controller proxy on PID 700
Feb 24 16:22:41 [953]: DUID 00:01:00:01:20:78:43:c4:00:0d:88:6e:5b:1c
Feb 24 16:22:41 [953]: wm0: executing: /libexec/dhcpcd-run-hooks PREINIT
Feb 24 16:22:42 [953]: wm0: executing: /libexec/dhcpcd-run-hooks CARRIER
Feb 24 16:22:42 [953]: wm1: executing: /libexec/dhcpcd-run-hooks PREINIT
Feb 24 16:22:42 [953]: wm0: IAID 64:92:10:c8
Feb 24 16:22:42 [953]: wm0: IA type 3 IAID 00:00:00:01
Feb 24 16:22:42 [953]: wm0: IA type 25 IAID 00:00:00:02
Feb 24 16:22:42 [953]: wm0: delaying IPv6 router solicitation for 0.2 seconds
Feb 24 16:22:42 [953]: wm0: reading lease: /var/db/dhcpcd/wm0.lease6
Feb 24 16:22:42 [953]: wm0: rebinding prior DHCPv6 lease
Feb 24 16:22:42 [953]: wm0: delaying REBIND6 (xid 0x08d0df), next in 1.1 seconds
Feb 24 16:22:42 [953]: wm0: delaying IPv4 for 1.4 seconds
Feb 24 16:22:42 [953]: wm1: waiting for carrier
Feb 24 16:22:42 [953]: wm1: carrier acquired
Feb 24 16:22:42 [953]: wm1: executing: /libexec/dhcpcd-run-hooks CARRIER
Feb 24 16:22:42 [953]: wm1: IAID 91:41:dc:87
Feb 24 16:22:42 [953]: wm1: adding address fe80::b696:91ff:fe41:dc87
Feb 24 16:22:42 [953]: wm1: pltime infinity, vltime infinity
Feb 24 16:22:42 [953]: wm1: delaying IPv4 for 1.7 seconds
Feb 24 16:22:42 [953]: wm1: carrier lost - roaming
Feb 24 16:22:42 [953]: wm1: executing: /libexec/dhcpcd-run-hooks NOCARRIER_ROAMING
Feb 24 16:22:42 [953]: wm0: soliciting an IPv6 router
Feb 24 16:22:42 [953]: wm0: sending Router Solicitation
Feb 24 16:22:42 [953]: wm1: carrier lost - roaming
Feb 24 16:22:42 [953]: wm1: executing: /libexec/dhcpcd-run-hooks NOCARRIER_ROAMING
Feb 24 16:22:42 [953]: wm0: Router Advertisement from fe80::217:10ff:fe87:1987
Feb 24 16:22:42 [953]: wm0: adding route to 2604:2000:c02:5::/64
Feb 24 16:22:42 [953]: wm0: adding route to 2604:2000:efc0:5::/64
Feb 24 16:22:42 [953]: wm0: adding route to 2604:2000:402:5::/64
Feb 24 16:22:42 [953]: wm0: adding route to 2604:2000:702:5::/64
Feb 24 16:22:42 [953]: wm0: adding route to 2603:90c5:6:27::/64
Feb 24 16:22:42 [953]: wm0: adding default route via fe80::217:10ff:fe87:1987
Feb 24 16:22:42 [953]: lo0: adding reject route to 2603:7000:2600:29ad::/64 via ::1
Feb 24 16:22:42 [953]: wm0: executing: /libexec/dhcpcd-run-hooks ROUTERADVERT
Feb 24 16:22:43 [953]: wm0: multicasting REBIND6 (xid 0x08d0df), next in 1.0 seconds
Feb 24 16:22:43 [953]: wm0: using hwaddr 54:bf:64:92:10:c8
Feb 24 16:22:43 [953]: wm0: reading lease: /var/db/dhcpcd/wm0.lease
Feb 24 16:22:43 [953]: wm0: rebinding lease of 104.162.232.43
Feb 24 16:22:43 [953]: wm0: sending REQUEST (xid 0x7286141d), next in 3.4 seconds
Feb 24 16:22:43 [954]: wm0: spawned BPF BOOTP on PID 1072
Feb 24 16:22:43 [953]: wm0: acknowledged 104.162.232.43 from 142.254.161.89
Feb 24 16:22:43 [953]: wm0: adding IP address 104.162.232.43/20 broadcast 255.255.255.255
Feb 24 16:22:44 [953]: wm0: multicasting REBIND6 (xid 0x08d0df), next in 2.2 seconds
Feb 24 16:22:46 [953]: wm1: carrier acquired
Feb 24 16:22:46 [953]: wm1: executing: /libexec/dhcpcd-run-hooks CARRIER
Feb 24 16:22:46 [953]: wm1: IAID 91:41:dc:87
Feb 24 16:22:46 [953]: wm1: delaying IPv4 for 0.2 seconds
Feb 24 16:22:46 [953]: wm0: multicasting REBIND6 (xid 0x08d0df), next in 4.2 seconds
Feb 24 16:22:46 [953]: wm1: using hwaddr b4:96:91:41:dc:87
Feb 24 16:22:46 [953]: wm1: reading lease: /var/db/dhcpcd/wm1.lease
Feb 24 16:22:46 [953]: wm1: rebinding lease of 192.168.2.4
Feb 24 16:22:46 [953]: wm1: sending REQUEST (xid 0x54de98f), next in 4.8 seconds
Feb 24 16:22:46 [954]: wm1: spawned BPF BOOTP on PID 1083
Feb 24 16:22:46 [953]: wm1: acknowledged 192.168.2.4 from 192.168.2.9
Feb 24 16:22:46 [953]: wm1: adding IP address 192.168.2.4/24 broadcast 192.168.2.255
Feb 24 16:22:48 [953]: wm0: DAD completed for 104.162.232.43
Feb 24 16:22:48 [953]: wm0: leased 104.162.232.43 for 56627 seconds
Feb 24 16:22:48 [953]: wm0: renew in 13427 seconds, rebind in 45827 seconds
Feb 24 16:22:48 [953]: wm0: writing lease: /var/db/dhcpcd/wm0.lease
Feb 24 16:22:48 [953]: wm0: IP address 104.162.232.43/20 already exists
Feb 24 16:22:48 [953]: wm0: adding route to 104.162.224.0/20
Feb 24 16:22:51 [953]: wm0: adding default route via 104.162.224.1
Feb 24 16:22:51 [951]: forked to background
Feb 24 16:22:51 [953]: wm0: executing: /libexec/dhcpcd-run-hooks BOUND
Feb 24 16:22:51 [953]: wm0: multicasting REBIND6 (xid 0x08d0df), next in 3.6 seconds
Feb 24 16:22:51 [954]: wm0: BPF BOOTP exited from PID 1072
Feb 24 16:22:52 [953]: wm1: DAD completed for 192.168.2.4
Feb 24 16:22:52 [953]: wm1: leased 192.168.2.4 for 604800 seconds
Feb 24 16:22:52 [953]: wm1: renew in 302400 seconds, rebind in 529200 seconds
Feb 24 16:22:52 [953]: wm1: writing lease: /var/db/dhcpcd/wm1.lease
Feb 24 16:22:52 [953]: wm1: IP address 192.168.2.4/24 already exists
Feb 24 16:22:52 [953]: wm1: adding route to 192.168.2.0/24
Feb 24 16:22:52 [953]: wm1: executing: /libexec/dhcpcd-run-hooks BOUND
Feb 24 16:22:52 [953]: wm0: failed to rebind prior DHCPv6 delegation
Feb 24 16:22:52 [954]: wm1: BPF BOOTP exited from PID 1083
Feb 24 16:22:52 [953]: wm0: executing: /libexec/dhcpcd-run-hooks TIMEOUT6
Feb 24 16:22:52 [953]: wm0: soliciting a DHCPv6 lease
Feb 24 16:22:52 [953]: wm0: delaying SOLICIT6 (xid 0x9be0e1), next in 1.1 seconds
Feb 24 16:22:53 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 1.1 seconds
Feb 24 16:22:54 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 2.2 seconds
Feb 24 16:22:56 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 4.8 seconds
Feb 24 16:23:01 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 9.3 seconds
Feb 24 16:23:10 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 19.4 seconds
Feb 24 16:23:30 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 42.6 seconds
Feb 24 16:24:12 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 91.7 seconds
Feb 24 16:25:36 [953]: wm0: Router Advertisement from fe80::217:10ff:fe87:1987
Feb 24 16:25:36 [953]: wm0: executing: /libexec/dhcpcd-run-hooks ROUTERADVERT
Feb 24 16:25:44 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 173.6 seconds
Feb 24 16:28:37 [953]: wm0: multicasting SOLICIT6 (xid 0x9be0e1), next in 329.1 seconds
>How-To-Repeat:
	install it.
>Fix:
	please