Project

General

Profile

Actions

Bug #7330

closed

IPv6 Prefix is deleted on PPPoe reset, but not reapplied.

Added by Michael Zieher over 7 years ago. Updated over 7 years ago.

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 
Actions #1

Updated by Michael Zieher over 7 years ago

Ah... Sorry!
The interesting Logs are all 04:* ... I somehow missed to truncate the 19:* entries....

Actions #2

Updated by Greg M over 7 years ago

Hi!

Very similar issue I think it`s connected: https://forum.pfsense.org/index.php?topic=126828.0

Actions #3

Updated by Martin Wasley over 7 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?

Actions #4

Updated by Michael Zieher over 7 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 :(

Actions #5

Updated by Michael Zieher over 7 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 ;)

Actions #6

Updated by Greg M over 7 years ago

Hi!
I honestly believe that this one deservers a little higher priority than normal...

Actions #7

Updated by Martin Wasley over 7 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

Actions #8

Updated by Greg M over 7 years ago

It's my thread :)
And yeah, PR https://github.com/pfsense/pfsense/pull/3515 will resolve this one...
Thanks again!

Actions #9

Updated by Martin Wasley over 7 years ago

Fixed - Close this one.

Actions #10

Updated by Jim Pingle over 7 years ago

  • Status changed from New to Resolved

Fixed by PR

Actions

Also available in: Atom PDF