NetworkConfiguration / dhcpcd

DHCP / IPv4LL / IPv6RA / DHCPv6 client.
https://roy.marples.name/projects/dhcpcd
BSD 2-Clause "Simplified" License
348 stars 111 forks source link

DHCP6 rebinding after restart of ppp0 fails. #359

Open noci2012 opened 2 months ago

noci2012 commented 2 months ago

after restart of dhcpcd the PD is acquired.

Built on gentoo: [I] net-misc/dhcpcd Available versions: 9.5.1 10.0.6-r2 ~10.0.6-r3 10.0.8{gpkg} *9999l {debug +embedded ipv6 privsep +udev} Installed versions: 10.0.8{gpkg}(11:15:57 06/25/24)(embedded ipv6 udev -debug -privsep) Homepage: https://github.com/NetworkConfiguration/dhcpcd/ https://roy.marples.name/projects/dhcpcd/ Description: A fully featured, yet light weight RFC2131 compliant DHCP client

Log excerpt:

Aug 29 19:57:33 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 19:57:49 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 19:58:19 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 19:59:15 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:01:02 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:05 firewall dhcpcd[1375]: sending commands to dhcpcd process
Aug 29 20:04:05 firewall dhcpcd[3512]: control command: dhcpcd -n ppp0
Aug 29 20:04:05 firewall dhcpcd[3512]: ppp0: soliciting a DHCPv6 lease
Aug 29 20:04:05 firewall dhcpcd[3512]: ppp0: IAID 00:00:00:01
Aug 29 20:04:05 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:0c
Aug 29 20:04:05 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:f2
Aug 29 20:04:07 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:08 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:11 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:15 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:24 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:43 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:04:52 firewall dhcpcd[3512]: ppp0: carrier lost
Aug 29 20:04:52 firewall dhcpcd[3512]: ppp0: removing interface
Aug 29 20:04:53 firewall dhcpcd[3512]: ppp0: waiting for carrier
Aug 29 20:04:53 firewall dhcpcd[3512]: ppp0: carrier acquired
Aug 29 20:04:53 firewall dhcpcd[3512]: ppp0: IAID 00:00:00:01
Aug 29 20:04:53 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:0c
Aug 29 20:04:53 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:f2
Aug 29 20:04:59 firewall dhcpcd[3512]: ppp0: carrier lost
Aug 29 20:04:59 firewall dhcpcd[3512]: ppp0: removing interface
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: waiting for carrier
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: carrier acquired
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: IAID 00:00:00:01
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:0c
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:f2
Aug 29 20:05:29 firewall dhcpcd[2105]: sending commands to dhcpcd process
Aug 29 20:05:29 firewall dhcpcd[3512]: control command: dhcpcd -n ppp0
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: soliciting a DHCPv6 lease
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: IAID 00:00:00:01
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:0c
Aug 29 20:05:29 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:f2
Aug 29 20:05:30 firewall dhcpcd[2511]: sending commands to dhcpcd process
Aug 29 20:05:30 firewall dhcpcd[3512]: control command: dhcpcd -n ppp0
Aug 29 20:05:30 firewall dhcpcd[2517]: sending commands to dhcpcd process
Aug 29 20:05:30 firewall dhcpcd[3512]: ppp0: soliciting a DHCPv6 lease
Aug 29 20:05:30 firewall dhcpcd[3512]: ppp0: IAID 00:00:00:01
Aug 29 20:05:30 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:0c
Aug 29 20:05:30 firewall dhcpcd[3512]: ppp0: IA type 25 IAID 00:00:00:f2
Aug 29 20:05:30 firewall dhcpcd[3512]: control command: dhcpcd ppp0
Aug 29 20:05:33 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:05:34 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:05:36 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:05:40 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:05:48 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:06:03 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:06:30 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:07:19 firewall dhcpcd[3512]: ppp0: invalid state for DHCP6 type ADVERTISE6 (2)
Aug 29 20:08:07 firewall dhcpcd[3512]: received SIGTERM, stopping
Aug 29 20:08:07 firewall dhcpcd[3512]: ppp0: removing interface
Aug 29 20:08:07 firewall dhcpcd[3512]: vlan10: removing interface
Aug 29 20:08:07 firewall dhcpcd[3512]: vlan4: removing interface
Aug 29 20:08:07 firewall dhcpcd[3512]: lo: removing interface
Aug 29 20:08:07 firewall dhcpcd[3512]: dhcpcd exited
Aug 29 20:08:07 firewall dhcpcd[2731]: dhcpcd-10.0.8 starting
Aug 29 20:08:07 firewall dhcpcd[2733]: dev: loaded udev
Aug 29 20:08:07 firewall dhcpcd[2733]: DUID 00:01:00:01:22:00:9c:ee:d4:3d:7e:f1:d0:79
Aug 29 20:08:07 firewall dhcpcd[2733]: ppp0: IAID 00:00:00:01
Aug 29 20:08:07 firewall dhcpcd[2733]: ppp0: IA type 25 IAID 00:00:00:0c
Aug 29 20:08:07 firewall dhcpcd[2733]: ppp0: IA type 25 IAID 00:00:00:f2
Aug 29 20:08:07 firewall dhcpcd[2733]: ppp0: (null): DHCPv6 REPLY missing Prefix
Aug 29 20:08:07 firewall dhcpcd[2733]: ppp0: rebinding prior DHCPv6 lease
Aug 29 20:08:07 firewall dhcpcd[2733]: lo: activating for delegation
Aug 29 20:08:07 firewall dhcpcd[2733]: lo: IAID 00:00:00:00
Aug 29 20:08:07 firewall dhcpcd[2733]: lo: ipv6_start: Operation not supported
Aug 29 20:08:07 firewall dhcpcd[2733]: vlan10: activating for delegation
Aug 29 20:08:07 firewall dhcpcd[2733]: vlan10: IAID ff:00:00:0a
noci2012 commented 2 months ago

dhcpcd -n ppp0 was used to try to force a rebind. in the end a restart of the process was done. after which it immediately started. process running is:

2733 ? S 0:00 dhcpcd: [manager]

rsmarples commented 2 months ago

Please upgrade to dhcpcd-10.0.10 where a lot of PPP issues have been resolved. Also, why are you delegating to the loopback interface? That makes no sense to me.