Actions
Bug #7330
closed
IPv6 Prefix is deleted on PPPoe reset, but not reapplied.
Status:
Resolved
Priority:
Normal
Assignee:
-
Category:
DHCP (IPv6)
Target version:
-
Start date:
02/28/2017
Due date:
% Done:
0%
Estimated time:
Plus Target Version:
Release Notes:
Affected Version:
2.4.x
Affected Architecture:
amd64
Description
On boot all adresses are correctly assigned.
On PPPoe reconnect (by provider or by clicking disconnect and connect in Interfaces view) IPv6 Prefixes are released but new Leases not assigned.
By Opening 'Interfaces' / 'WAN' -> 'Save' (without changing anything) and 'Apply' Prefixes are updated correctly.
Settings:
WAN Interface:
LAN Interface:
Nightly reconnect dhcp6c log:
Feb 27 04:00:02 dhcp6c 92612 executes /var/etc/dhcp6c_wan_dhcp6withoutra_script.sh
Feb 27 04:00:02 dhcp6c 92612 dhcp6c Received RELEASE
Feb 27 04:00:02 dhcp6c 92612 status code: success
Feb 27 04:00:02 dhcp6c 92612 get DHCP option status code, len 33
Feb 27 04:00:02 dhcp6c 92612 IA_PD: ID=0, T1=0, T2=0
Feb 27 04:00:02 dhcp6c 92612 get DHCP option IA_PD, len 49
Feb 27 04:00:02 dhcp6c 92612 DUID: 00:01:00:01:20:17:ad:48:00:0d:b9:42:ee:6c
Feb 27 04:00:02 dhcp6c 92612 get DHCP option client ID, len 14
Feb 27 04:00:02 dhcp6c 92612 DUID: 00:03:00:01:e4:81:84:66:e8:0f
Feb 27 04:00:02 dhcp6c 92612 get DHCP option server ID, len 10
Feb 27 04:00:02 dhcp6c 92612 receive reply from fe80::e681:84ff:fe66:e80f%pppoe0 on pppoe0
Feb 27 04:00:02 dhcp6c 92612 removing an event on pppoe0, state=INIT
Feb 27 04:00:02 dhcp6c 92612 reset a timer on pppoe0, state=INIT, timeo=0, retrans=191
Feb 27 04:00:02 dhcp6c 92612 remove an address 2a00:79c0:66f:6803:20d:b9ff:fe42:ee6e/64 on igb2
Feb 27 04:00:02 dhcp6c 92612 remove an address 2a00:79c0:66f:6800:20d:b9ff:fe42:ee6d/64 on igb1
Feb 27 04:00:02 dhcp6c 92612 remove a site prefix 2a00:79c0:66f:6800::/56
Feb 27 04:00:02 dhcp6c 92612 remove an IA: PD-0
Feb 27 04:00:02 dhcp6c 92612 send release to ff02::1:2%pppoe0
Feb 27 04:00:02 dhcp6c 92612 set IA_PD
Feb 27 04:00:02 dhcp6c 92612 set IA_PD prefix
Feb 27 04:00:02 dhcp6c 92612 set elapsed time (len 2)
Feb 27 04:00:02 dhcp6c 92612 set server ID (len 10)
Feb 27 04:00:02 dhcp6c 92612 set client ID (len 14)
Feb 27 04:00:02 dhcp6c 92612 a new XID (8920cf) is generated
Feb 27 04:00:02 dhcp6c 92612 Sending Release
Feb 27 04:00:02 dhcp6c 92612 reset a timer on pppoe0, state=RELEASE, timeo=0, retrans=964
Feb 27 04:00:02 dhcp6c 92612 release an IA: PD-0
Feb 27 04:00:02 dhcp6c 92612 Start address release
Feb 27 04:00:03 dhcp6c 92612 exiting
Feb 27 04:00:03 dhcp6c 92612 removing an event on pppoe0, state=RELEASE
Feb 27 04:00:03 dhcp6c 92612 script "/var/etc/dhcp6c_wan_dhcp6withoutra_script.sh" terminated
Feb 28 04:00:02 dhcp6c 95709 exiting
Feb 28 04:00:02 dhcp6c 95709 removing an event on pppoe0, state=RELEASE
Feb 28 04:00:02 dhcp6c 95709 script "/var/etc/dhcp6c_wan_dhcp6withoutra_script.sh" terminated
Feb 28 04:00:02 dhcp6c 95709 executes /var/etc/dhcp6c_wan_dhcp6withoutra_script.sh
Feb 28 04:00:02 dhcp6c 95709 dhcp6c Received RELEASE
Feb 28 04:00:02 dhcp6c 95709 status code: success
Feb 28 04:00:02 dhcp6c 95709 get DHCP option status code, len 33
Feb 28 04:00:02 dhcp6c 95709 IA_PD: ID=0, T1=0, T2=0
Feb 28 04:00:02 dhcp6c 95709 get DHCP option IA_PD, len 49
Feb 28 04:00:02 dhcp6c 95709 DUID: 00:01:00:01:20:17:ad:48:00:0d:b9:42:ee:6c
Feb 28 04:00:02 dhcp6c 95709 get DHCP option client ID, len 14
Feb 28 04:00:02 dhcp6c 95709 DUID: 00:03:00:01:e4:81:84:66:e8:0f
Feb 28 04:00:02 dhcp6c 95709 get DHCP option server ID, len 10
Feb 28 04:00:02 dhcp6c 95709 receive reply from fe80::e681:84ff:fe66:e80f%pppoe0 on pppoe0
Feb 28 04:00:02 dhcp6c 95709 removing an event on pppoe0, state=INIT
Feb 28 04:00:02 dhcp6c 95709 reset a timer on pppoe0, state=INIT, timeo=0, retrans=243
Feb 28 04:00:02 dhcp6c 95709 remove an address 2a00:79c0:669:1903:20d:b9ff:fe42:ee6e/64 on igb2
Feb 28 04:00:02 dhcp6c 95709 remove an address 2a00:79c0:669:1900:20d:b9ff:fe42:ee6d/64 on igb1
Feb 28 04:00:02 dhcp6c 95709 remove a site prefix 2a00:79c0:669:1900::/56
Feb 28 04:00:02 dhcp6c 95709 remove an IA: PD-0
Feb 28 04:00:02 dhcp6c 95709 send release to ff02::1:2%pppoe0
Feb 28 04:00:02 dhcp6c 95709 set IA_PD
Feb 28 04:00:02 dhcp6c 95709 set IA_PD prefix
Feb 28 04:00:02 dhcp6c 95709 set elapsed time (len 2)
Feb 28 04:00:02 dhcp6c 95709 set server ID (len 10)
Feb 28 04:00:02 dhcp6c 95709 set client ID (len 14)
Feb 28 04:00:02 dhcp6c 95709 a new XID (91bb70) is generated
Feb 28 04:00:02 dhcp6c 95709 Sending Release
Feb 28 04:00:02 dhcp6c 95709 reset a timer on pppoe0, state=RELEASE, timeo=0, retrans=1071
Feb 28 04:00:02 dhcp6c 95709 release an IA: PD-0
Feb 28 04:00:02 dhcp6c 95709 Start address release
Feb 28 19:04:07 dhcp6c 53806 reset a timer on pppoe0, state=INIT, timeo=0, retrans=891
Feb 28 19:04:07 dhcp6c 53340 called
Feb 28 19:04:07 dhcp6c 53340 called
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of closure [}] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of closure [}] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[8] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[sla-len] (7)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[3] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[sla-id] (6)
Feb 28 19:04:07 dhcp6c 53340 <3>begin of closure [{] (1)
Feb 28 19:04:07 dhcp6c 53340 <5>[igb2] (4)
Feb 28 19:04:07 dhcp6c 53340 <3>[prefix-interface] (16)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of closure [}] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[8] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[sla-len] (7)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[0] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[sla-id] (6)
Feb 28 19:04:07 dhcp6c 53340 <3>begin of closure [{] (1)
Feb 28 19:04:07 dhcp6c 53340 <5>[igb1] (4)
Feb 28 19:04:07 dhcp6c 53340 <3>[prefix-interface] (16)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[infinity] (8)
Feb 28 19:04:07 dhcp6c 53340 <3>[56] (2)
Feb 28 19:04:07 dhcp6c 53340 <3>[/] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[::] (2)
Feb 28 19:04:07 dhcp6c 53340 <3>[prefix] (6)
Feb 28 19:04:07 dhcp6c 53340 <13>begin of closure [{] (1)
Feb 28 19:04:07 dhcp6c 53340 <13>[0] (1)
Feb 28 19:04:07 dhcp6c 53340 <13>[pd] (2)
Feb 28 19:04:07 dhcp6c 53340 <3>[id-assoc] (8)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>end of closure [}] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>comment [# we'd like nameservers and RTSOLD to do all the work] (53)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>["/var/etc/dhcp6c_wan_dhcp6withoutra_script.sh"] (46)
Feb 28 19:04:07 dhcp6c 53340 <3>[script] (6)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[domain-name] (11)
Feb 28 19:04:07 dhcp6c 53340 <3>[request] (7)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[domain-name-servers] (19)
Feb 28 19:04:07 dhcp6c 53340 <3>[request] (7)
Feb 28 19:04:07 dhcp6c 53340 <3>comment [# request prefix delegation] (27)
Feb 28 19:04:07 dhcp6c 53340 <3>end of sentence [;] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[0] (1)
Feb 28 19:04:07 dhcp6c 53340 <3>[ia-pd] (5)
Feb 28 19:04:07 dhcp6c 53340 <3>[send] (4)
Feb 28 19:04:07 dhcp6c 53340 <3>begin of closure [{] (1)
Feb 28 19:04:07 dhcp6c 53340 <5>[pppoe0] (6)
Feb 28 19:04:07 dhcp6c 53340 <3>[interface] (9)
Feb 28 19:04:07 dhcp6c 53340 skip opening control port
Feb 28 19:04:07 dhcp6c 53340 failed initialize control message authentication
Feb 28 19:04:07 dhcp6c 53340 failed to open /usr/local/etc/dhcp6cctlkey: No such file or directory
Feb 28 19:04:07 dhcp6c 53340 extracted an existing DUID from /var/db/dhcp6c_duid: 00:01:00:01:20:17:ad:48:00:0d:b9:42:ee:6c
Feb 28 19:04:08 dhcp6c 53806 reset a timer on pppoe0, state=SOLICIT, timeo=0, retrans=1091
Feb 28 19:04:08 dhcp6c 53806 send solicit to ff02::1:2%pppoe0
Feb 28 19:04:08 dhcp6c 53806 set IA_PD
Feb 28 19:04:08 dhcp6c 53806 set IA_PD prefix
Feb 28 19:04:08 dhcp6c 53806 set option request (len 4)
Feb 28 19:04:08 dhcp6c 53806 set elapsed time (len 2)
Feb 28 19:04:08 dhcp6c 53806 set client ID (len 14)
Feb 28 19:04:08 dhcp6c 53806 a new XID (cb766f) is generated
Feb 28 19:04:08 dhcp6c 53806 Sending Solicit
Feb 28 19:04:09 dhcp6c 53806 reset a timer on pppoe0, state=SOLICIT, timeo=1, retrans=2083
Feb 28 19:04:09 dhcp6c 53806 send solicit to ff02::1:2%pppoe0
Feb 28 19:04:09 dhcp6c 53806 set IA_PD
Feb 28 19:04:09 dhcp6c 53806 set IA_PD prefix
Feb 28 19:04:09 dhcp6c 53806 set option request (len 4)
Feb 28 19:04:09 dhcp6c 53806 set elapsed time (len 2)
Feb 28 19:04:09 dhcp6c 53806 set client ID (len 14)
Feb 28 19:04:09 dhcp6c 53806 Sending Solicit
Updated by Michael Zieher almost 8 years ago
Ah... Sorry!
The interesting Logs are all 04:* ... I somehow missed to truncate the 19:* entries....
Updated by Greg M almost 8 years ago
Hi!
Very similar issue I think it`s connected: https://forum.pfsense.org/index.php?topic=126828.0
Updated by Martin Wasley almost 8 years ago
Michael Zieher wrote:
Ah... Sorry!
The interesting Logs are all 04:* ... I somehow missed to truncate the 19:* entries....
No entries from dhcp6c after the last "Start address release" at 04:02:00?
Updated by Michael Zieher almost 8 years ago
Martin Wasley wrote:
Michael Zieher wrote:
Ah... Sorry!
The interesting Logs are all 04:* ... I somehow missed to truncate the 19:* entries....No entries from dhcp6c after the last "Start address release" at 04:02:00?
That's it ... disconnect/reconnect is at 04:00.
There is no more logs from dhcp6c :(
Updated by Michael Zieher almost 8 years ago
Greg M wrote:
Hi!
Very similar issue I think it`s connected: https://forum.pfsense.org/index.php?topic=126828.0
I think it's the same ;)
Updated by Greg M almost 8 years ago
Hi!
I honestly believe that this one deservers a little higher priority than normal...
Updated by Martin Wasley almost 8 years ago
Michael Zieher wrote:
Greg M wrote:
Hi!
Very similar issue I think it`s connected: https://forum.pfsense.org/index.php?topic=126828.0
I think it's the same ;)
See this thread and in particular the message posted here:
https://forum.pfsense.org/index.php?topic=126828.msg701380#msg701380
Updated by Greg M almost 8 years ago
It's my thread :)
And yeah, PR https://github.com/pfsense/pfsense/pull/3515 will resolve this one...
Thanks again!
Updated by
Actions