Re: Tentative global IPv6 addresses with 8.1.4
Nico Schottelius
Wed Jan 01 13:50:08 2020
Update:
On 5.4.7-arch1-1 the address also stays tentative for quite some time
(minutes?) but eventually transits into regular state:
2: wlp0s20f3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP group default qlen 1000
link/ether 24:ee:9a:54:c3:bf brd ff:ff:ff:ff:ff:ff
inet6 2a0a:e5c0:10:bee:5fe8:6d2:b19a:3e8f/64 scope global dynamic mngtmpaddr noprefixroute
valid_lft 86357sec preferred_lft 14357sec
inet6 fe80::e00d:528:9af3:e20a/64 scope link
valid_lft forever preferred_lft forever
I wonder if something is broken with the DAD mechanism on these
kernels. I'll try to capture the MLD/icmp6 messages next time I connect
to that network.
Best regards,
Nico
Nico Schottelius <nico.schottelius@xxxxxxxxxxx> writes:
> Good morning,
>
> just a heads up - I'm seeing similar issues here at the 36c3, kernel
> version 5.4.6-arch3-1 and dhcpcd 8.1.4-1.
>
> Also staying in tentative state:
>
> 2: wlp0s20f3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue state UP group default qlen 1000
> link/ether 24:ee:9a:54:c3:bf brd ff:ff:ff:ff:ff:ff
> inet 151.217.198.178/18 brd 151.217.255.255 scope global dynamic noprefixroute wlp0s20f3
> valid_lft 2448sec preferred_lft 2080sec
> inet6 2001:67c:20a1:1192:2771:be04:4d42:d834/64 scope global tentative dynamic mngtmpaddr noprefixroute
> valid_lft 7106sec preferred_lft 3506sec
> inet6 fe80::5d65:bdcf:7387:86cf/64 scope link
> valid_lft forever preferred_lft forever
>
> Let me know if additional logs can help to follow up here or at the
> LKML.
>
> Best regards,
>
> Nico
>
>
> Steve Hirsch <stevehirsch49@xxxxxxx> writes:
>
>> I’ve included logs for both versions 8.1.2 and 8.1.4 running on the same
>> Arch Linux server with kernel 5.4.6. The IPv6 tentative problem is not
>> isolated to only this server, but appears on 2 other servers when running
>> 8.1.4.
>>
>> Note: waitip.sh is a bash script that runs from dhcpcd@br0.service
>> after executing dhcpcd and is a simple loop waiting for IP addresses
>> instead of using waitip in dhdcpd.conf. You'll see a timeout for
>> waitip.sh in the 8.1.4 log as it is waiting for a non-tentative IPv6
>> address.
>>
>> -- Logs begin at Thu 2019-12-26 13:04:33 MST, end at Sat 2019-12-28 08:26:51 MST. --
>> Dec 28 08:03:46 server01 systemd[1]: Starting dhcpcd on br0...
>> Dec 28 08:03:46 server01 dhcpcd[456]: dhcpcd-8.1.2 starting
>> Dec 28 08:03:46 server01 dhcpcd[456]: dhcpcd-8.1.2 starting
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:03:46 server01 dhcpcd[456]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: pltime infinity, vltime infinity
>> Dec 28 08:03:46 server01 dhcpcd[456]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: delaying IPv6 router solicitation for 0.8 seconds
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: delaying IPv4 for 0.8 seconds
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: pltime infinity, vltime infinity
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: delaying IPv6 router solicitation for 0.8 seconds
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: delaying IPv4 for 0.8 seconds
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: carrier lost
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' NOCARRIER
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: carrier lost
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' NOCARRIER
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: deleting address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:03:46 server01 dhcpcd[456]: br0: deleting address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: carrier acquired
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: carrier acquired
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: pltime infinity, vltime infinity
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: delaying IPv6 router solicitation for 0.3 seconds
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: delaying IPv4 for 0.9 seconds
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: pltime infinity, vltime infinity
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: delaying IPv6 router solicitation for 0.3 seconds
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: delaying IPv4 for 0.9 seconds
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: soliciting an IPv6 router
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: delaying Router Solicitation for LL address
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: soliciting an IPv6 router
>> Dec 28 08:03:50 server01 dhcpcd[456]: br0: delaying Router Solicitation for LL address
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: reading lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: reading lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: rebinding lease of 192.168.14.10
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: sending REQUEST (xid 0xc2a190a4), next in 3.8 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: rebinding lease of 192.168.14.10
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: sending REQUEST (xid 0xc2a190a4), next in 3.8 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: acknowledged 192.168.14.10 from 192.168.14.1
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: leased 192.168.14.10 for 86400 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: renew in 43200 seconds, rebind in 75600 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: acknowledged 192.168.14.10 from 192.168.14.1
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: writing lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: leased 192.168.14.10 for 86400 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: renew in 43200 seconds, rebind in 75600 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: adding IP address 192.168.14.10/24 broadcast 192.168.14.255
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: writing lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: adding IP address 192.168.14.10/24 broadcast 192.168.14.255
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: adding route to 192.168.14.0/24
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: adding default route via 192.168.14.1
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: adding route to 192.168.14.0/24
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: adding default route via 192.168.14.1
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: ARP announcing 192.168.14.10 (1 of 2), next in 2.0 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: ARP announcing 192.168.14.10 (1 of 2), next in 2.0 seconds
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' REBOOT
>> Dec 28 08:03:51 server01 dhcpcd[456]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' REBOOT
>> Dec 28 08:03:51 server01 dhcpcd[456]: forking to background
>> Dec 28 08:03:51 server01 dhcpcd[456]: forking to background
>> Dec 28 08:03:51 server01 dhcpcd[456]: forked to background, child pid 502
>> Dec 28 08:03:51 server01 dhcpcd[456]: forked to background, child pid 502
>> Dec 28 08:03:51 server01 waitip.sh[503]: waitip starting on br0
>> Dec 28 08:03:51 server01 waitip.sh[503]: waitip waiting for ipv4 on br0
>> Dec 28 08:03:51 server01 waitip.sh[503]: waitip waiting for ipv6 on br0
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: sending Router Solicitation
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: Router Advertisement from fe80::xxxx:xxxx:xxxx:802d
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: adding route to 26xx:xxx:xxxx:xxxx::/64
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: adding default route via fe80::xxxx:xxxx:xxxx:802d
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' ROUTERADVERT
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: reading lease `/var/lib/dhcpcd/br0.lease6'
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: confirming prior DHCPv6 lease
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: delaying CONFIRM6 (xid 0xe4a674), next in 0.8 seconds
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: fe80::xxxx:xxxx:xxxx:802d is reachable again
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: broadcasting CONFIRM6 (xid 0xe4a674), next in 1.0 seconds
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: REPLY6 received from fe80::xxxx:xxxx:xxxx:802d
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: adding address 26xx:xxx:xxxx:xxxx::10/128
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: pltime 86400 seconds, vltime 86400 seconds
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: renew in 40085, rebind in 72485, expire in 86400 seconds
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: writing lease `/var/lib/dhcpcd/br0.lease6'
>> Dec 28 08:03:52 server01 dhcpcd[502]: br0: waiting for DHCPv6 DAD to complete
>> Dec 28 08:03:53 server01 dhcpcd[502]: br0: ARP announcing 192.168.14.10 (2 of 2)
>> Dec 28 08:03:56 server01 dhcpcd[502]: br0: DHCPv6 DAD completed
>> Dec 28 08:03:56 server01 dhcpcd[502]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' REBOOT6
>> Dec 28 08:03:56 server01 dhcpcd[502]: br0: sending NA for 26xx:xxx:xxxx:xxxx::10/128
>> Dec 28 08:03:57 server01 waitip.sh[503]: waitip finished on br0
>> Dec 28 08:03:57 server01 systemd[1]: Started dhcpcd on br0.
>> Dec 28 08:03:57 server01 dhcpcd[502]: br0: sending NA for 26xx:xxx:xxxx:xxxx::10/128
>> Dec 28 08:03:58 server01 dhcpcd[502]: br0: sending NA for 26xx:xxx:xxxx:xxxx::10/128
>> Dec 28 08:04:02 server01 dhcpcd[502]: br0: fe80::xxxx:xxxx:xxxx:802d is reachable again
>> Dec 28 08:04:02 server01 dhcpcd[502]: br0: fe80::xxxx:xxxx:xxxx:802d is reachable again
>> Dec 28 08:05:15 server01 systemd[1]: Stopping dhcpcd on br0...
>> Dec 28 08:05:15 server01 dhcpcd[894]: sending signal TERM to pid 502
>> Dec 28 08:05:15 server01 dhcpcd[894]: waiting for pid 502 to exit
>> Dec 28 08:05:15 server01 dhcpcd[894]: sending signal TERM to pid 502
>> Dec 28 08:05:15 server01 dhcpcd[894]: waiting for pid 502 to exit
>> Dec 28 08:05:15 server01 dhcpcd[502]: received SIGTERM, stopping
>> Dec 28 08:05:15 server01 dhcpcd[502]: br0: removing interface
>> Dec 28 08:05:15 server01 dhcpcd[502]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' STOPPED
>> Dec 28 08:05:15 server01 dhcpcd[502]: dhcpcd exited
>> Dec 28 08:05:15 server01 systemd[1]: dhcpcd@br0.service: Succeeded.
>> Dec 28 08:05:15 server01 systemd[1]: Stopped dhcpcd on br0.
>>
>> -- Logs begin at Thu 2019-12-26 13:04:33 MST, end at Sat 2019-12-28 08:26:51 MST. --
>> Dec 28 08:05:30 server01 systemd[1]: Starting dhcpcd on br0...
>> Dec 28 08:05:30 server01 dhcpcd[462]: dhcpcd-8.1.4 starting
>> Dec 28 08:05:30 server01 dhcpcd[462]: dhcpcd-8.1.4 starting
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' PREINIT
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:05:30 server01 dhcpcd[462]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: pltime infinity, vltime infinity
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: delaying IPv6 router solicitation for 0.8 seconds
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: delaying IPv4 for 0.2 seconds
>> Dec 28 08:05:30 server01 dhcpcd[462]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: pltime infinity, vltime infinity
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: delaying IPv6 router solicitation for 0.8 seconds
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: delaying IPv4 for 0.2 seconds
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: reading lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: reading lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: rebinding lease of 192.168.14.10
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: sending REQUEST (xid 0xf649b726), next in 4.8 seconds
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: rebinding lease of 192.168.14.10
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: sending REQUEST (xid 0xf649b726), next in 4.8 seconds
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: carrier lost
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' NOCARRIER
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: carrier lost
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' NOCARRIER
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: deleting address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' EXPIRE
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: deleting address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:05:30 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' EXPIRE
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: carrier acquired
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: carrier acquired
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' CARRIER
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: pltime infinity, vltime infinity
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: IAID xx:xx:a1:ca
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: delaying IPv6 router solicitation for 0.3 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: delaying IPv4 for 0.2 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding address fe80::xxxx:xxxx:xxxx:a1ca
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: pltime infinity, vltime infinity
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: delaying IPv6 router solicitation for 0.3 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: delaying IPv4 for 0.2 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: reading lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: rebinding lease of 192.168.14.10
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: reading lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: sending REQUEST (xid 0x744adbd0), next in 3.8 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: rebinding lease of 192.168.14.10
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: sending REQUEST (xid 0x744adbd0), next in 3.8 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: acknowledged 192.168.14.10 from 192.168.14.1
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: leased 192.168.14.10 for 86400 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: renew in 43200 seconds, rebind in 75600 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: writing lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: acknowledged 192.168.14.10 from 192.168.14.1
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding IP address 192.168.14.10/24 broadcast 192.168.14.255
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding route to 192.168.14.0/24
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: leased 192.168.14.10 for 86400 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding default route via 192.168.14.1
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: renew in 43200 seconds, rebind in 75600 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: writing lease `/var/lib/dhcpcd/br0.lease'
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding IP address 192.168.14.10/24 broadcast 192.168.14.255
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding route to 192.168.14.0/24
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: adding default route via 192.168.14.1
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: ARP announcing 192.168.14.10 (1 of 2), next in 2.0 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' REBOOT
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: ARP announcing 192.168.14.10 (1 of 2), next in 2.0 seconds
>> Dec 28 08:05:34 server01 dhcpcd[462]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' REBOOT
>> Dec 28 08:05:34 server01 dhcpcd[462]: forking to background
>> Dec 28 08:05:34 server01 dhcpcd[462]: forking to background
>> Dec 28 08:05:34 server01 dhcpcd[462]: forked to background, child pid 509
>> Dec 28 08:05:34 server01 dhcpcd[462]: forked to background, child pid 509
>> Dec 28 08:05:34 server01 waitip.sh[510]: waitip starting on br0
>> Dec 28 08:05:34 server01 waitip.sh[510]: waitip waiting for ipv4 on br0
>> Dec 28 08:05:34 server01 waitip.sh[510]: waitip waiting for ipv6 on br0
>> Dec 28 08:05:34 server01 dhcpcd[509]: br0: soliciting an IPv6 router
>> Dec 28 08:05:34 server01 dhcpcd[509]: br0: delaying Router Solicitation for LL address
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: sending Router Solicitation
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: Router Advertisement from fe80::xxxx:xxxx:xxxx:802d
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: adding route to 26xx:xxx:xxxx:xxxx::/64
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: adding default route via fe80::xxxx:xxxx:xxxx:802d
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' ROUTERADVERT
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: reading lease `/var/lib/dhcpcd/br0.lease6'
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: confirming prior DHCPv6 lease
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: delaying CONFIRM6 (xid 0xc4cb98), next in 0.2 seconds
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: broadcasting CONFIRM6 (xid 0xc4cb98), next in 0.9 seconds
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: REPLY6 received from fe80::xxxx:xxxx:xxxx:802d
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: adding address 26xx:xxx:xxxx:xxxx::10/128
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: pltime 86400 seconds, vltime 86400 seconds
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: renew in 40085, rebind in 72485, expire in 86400 seconds
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: writing lease `/var/lib/dhcpcd/br0.lease6'
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: waiting for DHCPv6 DAD to complete
>> Dec 28 08:05:36 server01 dhcpcd[509]: br0: ARP announcing 192.168.14.10 (2 of 2)
>> Dec 28 08:06:04 server01 waitip.sh[510]: waitip timeout for ipv6 on br0
>> Dec 28 08:06:04 server01 waitip.sh[510]: waitip finished on br0
>> Dec 28 08:06:04 server01 systemd[1]: Started dhcpcd on br0.
>> Dec 28 08:10:38 server01 systemd[1]: Stopping dhcpcd on br0...
>> Dec 28 08:10:38 server01 dhcpcd[925]: sending signal TERM to pid 509
>> Dec 28 08:10:38 server01 dhcpcd[925]: waiting for pid 509 to exit
>> Dec 28 08:10:38 server01 dhcpcd[925]: sending signal TERM to pid 509
>> Dec 28 08:10:38 server01 dhcpcd[925]: waiting for pid 509 to exit
>> Dec 28 08:10:38 server01 dhcpcd[509]: received SIGTERM, stopping
>> Dec 28 08:10:38 server01 dhcpcd[509]: br0: removing interface
>> Dec 28 08:10:38 server01 dhcpcd[509]: br0: executing `/usr/lib/dhcpcd/dhcpcd-run-hooks' STOPPED
>> Dec 28 08:10:38 server01 dhcpcd[509]: dhcpcd exited
>> Dec 28 08:10:38 server01 systemd[1]: dhcpcd@br0.service: Succeeded.
>> Dec 28 08:10:38 server01 systemd[1]: Stopped dhcpcd on br0.
>>
>> From: Roy Marples<mailto:roy@xxxxxxxxxxxx>
>> Sent: Saturday, December 28, 2019 2:41 AM
>> To: Steve Hirsch<mailto:stevehirsch49@xxxxxxx>; dhcpcd-discuss@xxxxxxxxxxxx<mailto:dhcpcd-discuss@xxxxxxxxxxxx>
>> Subject: Re: Tentative global IPv6 addresses with 8.1.4
>>
>> On 28/12/2019 00:03, Steve Hirsch wrote:
>>> Beginning with version 8.1.4 on Arch Linux, interfaces are receiving tentative
>>> global IPv6 addresses. The tentative addresses do not switch to permanent…even
>>> after several minutes. Reverting back to 8.1.2 and the problem goes away.
>>
>> Can you enable add debug to /etc/dhcpcd.conf please and post log?
>> The kernel handles the transition from tentative to non tentative, so the only
>> thing I can think of is that somehow the address is being added again and again
>> very fast.
>>
>> Roy
--
Modern, affordable, Swiss Virtual Machines. Visit www.datacenterlight.ch
Archive administrator: postmaster@marples.name