Upgraded from 22.05 - DHCPv6-PD / IPv6 no longer works

adam.lantos

Hi,

I'm having an issue with DHCPv6-PD on 23.01 build 23.01.b.20221220.1316 on an SG-1100 I just upgraded from pfSense+ 22.05. On 22.05 IPv6-PD was working well enough, but on 23.01 everything is broken right from the start.

DHCPv6-PD grabs a WAN address and prefixes on two of my VLANs, but after a few minutes it releases them and I'm left with no IPv6.

Settings:

DHCPv6 WAN: requesting /60 from Comcast Business (sending prefix hint). "Do not wait for RA" is disabled, "only request an IPv6 prefix" is disabled
LAN: track interface WAN, prefix ID set.
DHCPv6 disabled. RA set to unmanaged.
Advanced option "Do not allow PD/Address release" if off. Toggling this setting does not seem to fix the IPv6 issue, since the IPv6 addresses on my LANs are dropped by pfSense regardless of this setting, and I'm left with no routing.

From the logs, it looks like dhcp6c goes through several rounds of prefix creation and release (same addresses each time). I don't understand why it decides to remove the prefixes at the end however. At the end, dhcp6c just exits. Very strange.

Does anyone have an idea what might be broken here? I'm running mostly default settings, nothing crazy, and 22.05 has been pretty solid recently.

Abbreviated/slightly redacted logs with dhcp6c debugging enabled:

18:51:28.195236-08:00   dhcp6c  36042   Sending Solicit
18:51:28.195987-08:00   dhcp6c  36042   a new XID (b54ab6) is generated
18:51:28.196475-08:00   dhcp6c  36042   set client ID (len 10)
18:51:28.196883-08:00   dhcp6c  36042   set identity association
18:51:28.197336-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:28.197804-08:00   dhcp6c  36042   set option request (len 4)
18:51:28.198195-08:00   dhcp6c  36042   set IA_PD prefix
18:51:28.198625-08:00   dhcp6c  36042   set IA_PD
18:51:28.199712-08:00   dhcp6c  36042   send solicit to ff02::1:2%mvneta0.4090
18:51:28.200253-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=SOLICIT, timeo=0, retrans=1091
18:51:28.266271-08:00   dhcp6c  36042   receive advertise from 2001:558:4000:c7::10 on mvneta0.4090
18:51:28.266846-08:00   dhcp6c  36042   get DHCP option client ID, len 10
18:51:28.267758-08:00   dhcp6c  36042   get DHCP option server ID, len 14
18:51:28.268621-08:00   dhcp6c  36042   get DHCP option identity association, len 40
18:51:28.269093-08:00   dhcp6c  36042   IA_NA: ID=0, T1=1802, T2=2883
18:51:28.269531-08:00   dhcp6c  36042   get DHCP option IA address, len 24
18:51:28.269957-08:00   dhcp6c  36042   IA_NA address: [redacted] pltime=6843 vltime=6843
18:51:28.270332-08:00   dhcp6c  36042   get DHCP option IA_PD, len 41
18:51:28.270712-08:00   dhcp6c  36042   IA_PD: ID=0, T1=1802, T2=2883
18:51:28.271018-08:00   dhcp6c  36042   get DHCP option IA_PD prefix, len 25
18:51:28.271260-08:00   dhcp6c  36042   IA_PD prefix: [redacted]::/60 pltime=3604 vltime=3604
18:51:28.271504-08:00   dhcp6c  36042   get DHCP option DNS, len 32
18:51:28.271743-08:00   dhcp6c  36042   server ID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f, pref=-1
18:51:28.272290-08:00   dhcp6c  36042   reset timer for mvneta0.4090 to 0.927960
18:51:29.203452-08:00   dhcp6c  36042   picked a server (ID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f)
18:51:29.203654-08:00   dhcp6c  36042   Sending Request
18:51:29.203705-08:00   dhcp6c  36042   a new XID (271712) is generated
18:51:29.203741-08:00   dhcp6c  36042   set client ID (len 10)
18:51:29.203764-08:00   dhcp6c  36042   set server ID (len 14)
18:51:29.203790-08:00   dhcp6c  36042   set IA address
18:51:29.203811-08:00   dhcp6c  36042   set identity association
18:51:29.203835-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:29.203860-08:00   dhcp6c  36042   set option request (len 4)
18:51:29.203887-08:00   dhcp6c  36042   set IA_PD prefix
18:51:29.203909-08:00   dhcp6c  36042   set IA_PD
18:51:29.204386-08:00   dhcp6c  36042   send request to ff02::1:2%mvneta0.4090
18:51:29.204476-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=REQUEST, timeo=0, retrans=909
18:51:29.234573-08:00   dhcp6c  36042   receive reply from 2001:558:4000:c7::10 on mvneta0.4090
18:51:29.234672-08:00   dhcp6c  36042   get DHCP option client ID, len 10
18:51:29.234734-08:00   dhcp6c  36042   get DHCP option server ID, len 14
18:51:29.234788-08:00   dhcp6c  36042   get DHCP option identity association, len 40
18:51:29.234811-08:00   dhcp6c  36042   IA_NA: ID=0, T1=1780, T2=2882
18:51:29.234834-08:00   dhcp6c  36042   get DHCP option IA address, len 24
18:51:29.234866-08:00   dhcp6c  36042   IA_NA address: [redacted] pltime=6842 vltime=6842
18:51:29.234897-08:00   dhcp6c  36042   get DHCP option IA_PD, len 41
18:51:29.234919-08:00   dhcp6c  36042   IA_PD: ID=0, T1=348, T2=2163
18:51:29.234941-08:00   dhcp6c  36042   get DHCP option IA_PD prefix, len 25
18:51:29.235003-08:00   dhcp6c  36042   IA_PD prefix: [redacted]::/60 pltime=3603 vltime=3603
18:51:29.235033-08:00   dhcp6c  36042   get DHCP option DNS, len 32
18:51:29.235060-08:00   dhcp6c  36042   dhcp6c Received REQUEST
18:51:29.235093-08:00   dhcp6c  36042   nameserver[0] 2001:558:feed::1
18:51:29.235119-08:00   dhcp6c  36042   nameserver[1] 2001:558:feed::2
18:51:29.235359-08:00   dhcp6c  36042   make an IA: PD-0
18:51:29.235471-08:00   dhcp6c  36042   create a prefix [redacted]::/60 pltime=3603, vltime=3603
18:51:29.236924-08:00   dhcp6c  36042   add an address [redacted]/64 on mvneta0.4
18:51:29.238248-08:00   dhcp6c  36042   add an address [redacted]/64 on mvneta0.91
18:51:29.238385-08:00   dhcp6c  36042   make an IA: NA-0
18:51:29.238434-08:00   dhcp6c  36042   create an address [redacted] pltime=6842, vltime=6842
18:51:29.239082-08:00   dhcp6c  36042   add an address [redacted]/128 on mvneta0.4090
18:51:29.239777-08:00   dhcp6c  36042   executes /var/etc/dhcp6c_wan_script.sh
18:51:29.252267-08:00   dhcp6c  39116   dhcp6c RELEASE, REQUEST or EXIT on mvneta0.4090 running rc.newwanipv6
18:51:34.727592-08:00   dhcp6c  36042   script "/var/etc/dhcp6c_wan_script.sh" terminated
18:51:34.727748-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=REQUEST
18:51:34.727821-08:00   dhcp6c  36042   removing server (ID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f)
18:51:34.727864-08:00   dhcp6c  36042   got an expected reply, sleeping.
18:51:34.727901-08:00   dhcp6c  36042   restarting
18:51:34.727924-08:00   dhcp6c  36042   Start address release
18:51:34.727948-08:00   dhcp6c  36042   release an IA: NA-0
18:51:34.727999-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=0, retrans=911
18:51:34.728031-08:00   dhcp6c  36042   Sending Release
18:51:34.728060-08:00   dhcp6c  36042   a new XID (9565e) is generated
18:51:34.728090-08:00   dhcp6c  36042   set client ID (len 10)
18:51:34.728113-08:00   dhcp6c  36042   set server ID (len 14)
18:51:34.728137-08:00   dhcp6c  36042   set IA address
18:51:34.728159-08:00   dhcp6c  36042   set identity association
18:51:34.728183-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:34.729297-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:34.729380-08:00   dhcp6c  36042   remove an IA: NA-0
18:51:34.729460-08:00   dhcp6c  36042   remove an address [redacted]
18:51:34.730272-08:00   dhcp6c  36042   remove an address [redacted]/128 on mvneta0.4090
18:51:34.730424-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=INIT, timeo=0, retrans=256
18:51:34.730502-08:00   dhcp6c  36042   Start address release
18:51:34.730528-08:00   dhcp6c  36042   release an IA: PD-0
18:51:34.730569-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=0, retrans=1024
18:51:34.730594-08:00   dhcp6c  36042   Sending Release
18:51:34.730620-08:00   dhcp6c  36042   a new XID (4472e3) is generated
18:51:34.730648-08:00   dhcp6c  36042   set client ID (len 10)
18:51:34.730670-08:00   dhcp6c  36042   set server ID (len 14)
18:51:34.730692-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:34.730719-08:00   dhcp6c  36042   set IA_PD prefix
18:51:34.730741-08:00   dhcp6c  36042   set IA_PD
18:51:34.731212-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:34.731282-08:00   dhcp6c  36042   remove an IA: PD-0
18:51:34.731323-08:00   dhcp6c  36042   remove a site prefix [redacted]::/60
18:51:34.732414-08:00   dhcp6c  36042   remove an address [redacted]/64 on mvneta0.4
18:51:34.733134-08:00   dhcp6c  36042   remove an address [redacted]/64 on mvneta0.91
18:51:34.733731-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=INIT, timeo=0, retrans=557
18:51:34.733864-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=INIT
18:51:34.733906-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=INIT
18:51:34.734001-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=INIT, timeo=0, retrans=192
18:51:34.927218-08:00   dhcp6c  36042   Sending Solicit
18:51:34.927316-08:00   dhcp6c  36042   a new XID (94991f) is generated
18:51:34.927358-08:00   dhcp6c  36042   set client ID (len 10)
18:51:34.927386-08:00   dhcp6c  36042   set identity association
18:51:34.927410-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:34.927445-08:00   dhcp6c  36042   set option request (len 4)
18:51:34.927473-08:00   dhcp6c  36042   set IA_PD prefix
18:51:34.927495-08:00   dhcp6c  36042   set IA_PD
18:51:34.927989-08:00   dhcp6c  36042   send solicit to ff02::1:2%mvneta0.4090
18:51:34.928064-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=SOLICIT, timeo=0, retrans=1091
18:51:34.990465-08:00   dhcp6c  36042   receive advertise from 2001:558:4000:c7::10 on mvneta0.4090
18:51:34.990558-08:00   dhcp6c  36042   get DHCP option client ID, len 10
18:51:34.990594-08:00   dhcp6c  36042   DUID: 00:03:00:01:00:51:82:11:22:00
18:51:34.990620-08:00   dhcp6c  36042   get DHCP option server ID, len 14
18:51:34.990652-08:00   dhcp6c  36042   DUID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f
18:51:34.990675-08:00   dhcp6c  36042   get DHCP option identity association, len 40
18:51:34.990698-08:00   dhcp6c  36042   IA_NA: ID=0, T1=1798, T2=2877
18:51:34.990723-08:00   dhcp6c  36042   get DHCP option IA address, len 24
18:51:34.990754-08:00   dhcp6c  36042   IA_NA address: [redacted] pltime=6836 vltime=6836
18:51:34.990784-08:00   dhcp6c  36042   get DHCP option IA_PD, len 41
18:51:34.990807-08:00   dhcp6c  36042   IA_PD: ID=0, T1=1798, T2=2877
18:51:34.990828-08:00   dhcp6c  36042   get DHCP option IA_PD prefix, len 25
18:51:34.990857-08:00   dhcp6c  36042   IA_PD prefix: [redacted]::/60 pltime=3597 vltime=3597
18:51:34.990882-08:00   dhcp6c  36042   get DHCP option DNS, len 32
18:51:34.990919-08:00   dhcp6c  36042   server ID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f, pref=-1
18:51:34.990963-08:00   dhcp6c  36042   reset timer for mvneta0.4090 to 0.937099
18:51:35.647441-08:00   dhcp6c  36042   Sending Release
18:51:35.647558-08:00   dhcp6c  36042   set client ID (len 10)
18:51:35.647589-08:00   dhcp6c  36042   set server ID (len 14)
18:51:35.647617-08:00   dhcp6c  36042   set IA address
18:51:35.647638-08:00   dhcp6c  36042   set identity association
18:51:35.647662-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:35.648127-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:35.648204-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=1, retrans=1765
18:51:35.762365-08:00   dhcp6c  36042   Sending Release
18:51:35.762484-08:00   dhcp6c  36042   set client ID (len 10)
18:51:35.762516-08:00   dhcp6c  36042   set server ID (len 14)
18:51:35.762539-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:35.762569-08:00   dhcp6c  36042   set IA_PD prefix
18:51:35.762592-08:00   dhcp6c  36042   set IA_PD
18:51:35.763063-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:35.763140-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=1, retrans=2076
18:51:35.936759-08:00   dhcp6c  36042   picked a server (ID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f)
18:51:35.936879-08:00   dhcp6c  36042   Sending Request
18:51:35.936913-08:00   dhcp6c  36042   a new XID (b6b730) is generated
18:51:35.936948-08:00   dhcp6c  36042   set client ID (len 10)
18:51:35.936972-08:00   dhcp6c  36042   set server ID (len 14)
18:51:35.936997-08:00   dhcp6c  36042   set IA address
18:51:35.937019-08:00   dhcp6c  36042   set identity association
18:51:35.937043-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:35.937067-08:00   dhcp6c  36042   set option request (len 4)
18:51:35.937095-08:00   dhcp6c  36042   set IA_PD prefix
18:51:35.937117-08:00   dhcp6c  36042   set IA_PD
18:51:35.937616-08:00   dhcp6c  36042   send request to ff02::1:2%mvneta0.4090
18:51:35.937697-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=REQUEST, timeo=0, retrans=925
18:51:35.969693-08:00   dhcp6c  36042   receive reply from 2001:558:4000:c7::10 on mvneta0.4090
18:51:35.969783-08:00   dhcp6c  36042   get DHCP option client ID, len 10
18:51:35.969819-08:00   dhcp6c  36042   DUID: 00:03:00:01:00:51:82:11:22:00
18:51:35.969845-08:00   dhcp6c  36042   get DHCP option server ID, len 14
18:51:35.969877-08:00   dhcp6c  36042   DUID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f
18:51:35.969900-08:00   dhcp6c  36042   get DHCP option identity association, len 40
18:51:35.969923-08:00   dhcp6c  36042   IA_NA: ID=0, T1=1773, T2=2875
18:51:35.969946-08:00   dhcp6c  36042   get DHCP option IA address, len 24
18:51:35.969978-08:00   dhcp6c  36042   IA_NA address: [redacted] pltime=6835 vltime=6835
18:51:35.970009-08:00   dhcp6c  36042   get DHCP option IA_PD, len 41
18:51:35.970032-08:00   dhcp6c  36042   IA_PD: ID=0, T1=341, T2=2156
18:51:35.970053-08:00   dhcp6c  36042   get DHCP option IA_PD prefix, len 25
18:51:35.970081-08:00   dhcp6c  36042   IA_PD prefix: [redacted]::/60 pltime=3596 vltime=3596
18:51:35.970107-08:00   dhcp6c  36042   get DHCP option DNS, len 32
18:51:35.970134-08:00   dhcp6c  36042   dhcp6c Received REQUEST
18:51:35.970166-08:00   dhcp6c  36042   nameserver[0] 2001:558:feed::1
18:51:35.970192-08:00   dhcp6c  36042   nameserver[1] 2001:558:feed::2
18:51:35.970221-08:00   dhcp6c  36042   make an IA: PD-0
18:51:35.970255-08:00   dhcp6c  36042   create a prefix [redacted]::/60 pltime=3596, vltime=3596
18:51:35.971519-08:00   dhcp6c  36042   add an address [redacted]/64 on mvneta0.4
18:51:35.972862-08:00   dhcp6c  36042   add an address [redacted]/64 on mvneta0.91
18:51:35.973253-08:00   dhcp6c  36042   make an IA: NA-0
18:51:35.973367-08:00   dhcp6c  36042   create an address [redacted] pltime=6835, vltime=6835
18:51:35.974077-08:00   dhcp6c  36042   add an address [redacted]/128 on mvneta0.4090
18:51:35.974413-08:00   dhcp6c  36042   executes /var/etc/dhcp6c_wan_script.sh
18:51:35.985539-08:00   dhcp6c  70043   dhcp6c RELEASE, REQUEST or EXIT on mvneta0.4090 running rc.newwanipv6
18:51:41.535786-08:00   dhcp6c  36042   script "/var/etc/dhcp6c_wan_script.sh" terminated
18:51:41.535945-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=REQUEST
18:51:41.536021-08:00   dhcp6c  36042   removing server (ID: 00:01:00:01:15:d6:8a:53:14:fe:b5:cf:db:5f)
18:51:41.536065-08:00   dhcp6c  36042   got an expected reply, sleeping.
18:51:41.536113-08:00   dhcp6c  36042   Sending Release
18:51:41.536153-08:00   dhcp6c  36042   set client ID (len 10)
18:51:41.536176-08:00   dhcp6c  36042   set server ID (len 14)
18:51:41.536199-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:41.536233-08:00   dhcp6c  36042   set IA_PD prefix
18:51:41.536259-08:00   dhcp6c  36042   set IA_PD
18:51:41.537065-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:41.537157-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=2, retrans=4164
18:51:41.537187-08:00   dhcp6c  36042   Sending Release
18:51:41.537221-08:00   dhcp6c  36042   set client ID (len 10)
18:51:41.537244-08:00   dhcp6c  36042   set server ID (len 14)
18:51:41.537269-08:00   dhcp6c  36042   set IA address
18:51:41.537291-08:00   dhcp6c  36042   set identity association
18:51:41.537314-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:41.537848-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:41.537923-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=2, retrans=3360
18:51:44.910292-08:00   dhcp6c  36042   Sending Release
18:51:44.910456-08:00   dhcp6c  36042   set client ID (len 10)
18:51:44.910504-08:00   dhcp6c  36042   set server ID (len 14)
18:51:44.910533-08:00   dhcp6c  36042   set IA address
18:51:44.910555-08:00   dhcp6c  36042   set identity association
18:51:44.910579-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:44.911118-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:44.911198-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=3, retrans=6466
18:51:45.711208-08:00   dhcp6c  36042   Sending Release
18:51:45.711372-08:00   dhcp6c  36042   set client ID (len 10)
18:51:45.711515-08:00   dhcp6c  36042   set server ID (len 14)
18:51:45.711549-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:45.711581-08:00   dhcp6c  36042   set IA_PD prefix
18:51:45.711604-08:00   dhcp6c  36042   set IA_PD
18:51:45.712137-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:45.712217-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=3, retrans=8258
18:51:51.378568-08:00   dhcp6c  36042   Sending Release
18:51:51.378688-08:00   dhcp6c  36042   set client ID (len 10)
18:51:51.378717-08:00   dhcp6c  36042   set server ID (len 14)
18:51:51.378743-08:00   dhcp6c  36042   set IA address
18:51:51.378765-08:00   dhcp6c  36042   set identity association
18:51:51.378789-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:51.380123-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:51.380204-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=4, retrans=12822
18:51:54.014597-08:00   dhcp6c  36042   Sending Release
18:51:54.014767-08:00   dhcp6c  36042   set client ID (len 10)
18:51:54.014808-08:00   dhcp6c  36042   set server ID (len 14)
18:51:54.014832-08:00   dhcp6c  36042   set elapsed time (len 2)
18:51:54.014864-08:00   dhcp6c  36042   set IA_PD prefix
18:51:54.014886-08:00   dhcp6c  36042   set IA_PD
18:51:54.015410-08:00   dhcp6c  36042   send release to ff02::1:2%mvneta0.4090
18:51:54.015567-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=RELEASE, timeo=4, retrans=15742
18:54:22.585510-08:00   dhcp6c  36042   no responses were received
18:54:22.586036-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=RELEASE
18:54:22.586481-08:00   dhcp6c  36042   no responses were received
18:54:22.587059-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=RELEASE
18:55:01.164245-08:00   dhclient        38517   FAIL
18:55:01.170286-08:00   dhclient        32762   connection closed
18:55:01.171007-08:00   dhclient        32762   exiting.
18:55:01.223138-08:00   dhclient        38984   PREINIT
18:55:01.264539-08:00   dhclient        38728   DHCPREQUEST on mvneta0.4090 to 255.255.255.255 port 67
18:55:01.309644-08:00   dhclient        38728   DHCPACK from 96.120.95.109
18:55:01.339294-08:00   dhclient        39756   REBOOT
18:55:01.364836-08:00   dhclient        40023   Starting add_new_address()
18:55:01.462199-08:00   dhclient        40278   ifconfig mvneta0.4090 inet [redacted] netmask 255.255.254.0 broadcast 255.255.255.255
18:55:01.519271-08:00   dhclient        40639   New IP Address (mvneta0.4090): [redacted]
18:55:01.527865-08:00   dhclient        40857   New Subnet Mask (mvneta0.4090): 255.255.254.0
18:55:01.537343-08:00   dhclient        41166   New Broadcast Address (mvneta0.4090): 255.255.255.255
18:55:01.547091-08:00   dhclient        41378   New Routers (mvneta0.4090): 73.158.184.1
18:55:01.563332-08:00   dhclient        41526   Adding new routes to interface: mvneta0.4090
18:55:01.597185-08:00   dhclient        42360   /sbin/route add -host 73.158.184.1 -iface mvneta0.4090
18:55:01.617491-08:00   dhclient        42968   /sbin/route add default 73.158.184.1
18:55:01.640758-08:00   dhclient        43163   Creating resolv.conf
18:55:01.884067-08:00   dhclient        38728   bound to [redacted] -- renewal in 294 seconds.
18:55:01.941985-08:00   dhcp6c  36042   exit without release
18:55:01.942697-08:00   dhcp6c  36042   Bypassing address release because of -n flag
18:55:01.943229-08:00   dhcp6c  36042   remove an IA: NA-0
18:55:01.943902-08:00   dhcp6c  36042   remove an address [redacted]
18:55:01.944506-08:00   dhcp6c  36042   failed to remove an address on mvneta0.4090: Can't assign requested address
18:55:01.945016-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=INIT, timeo=0, retrans=924
18:55:01.945796-08:00   dhcp6c  36042   Bypassing address release because of -n flag
18:55:01.946239-08:00   dhcp6c  36042   remove an IA: PD-0
18:55:01.946628-08:00   dhcp6c  36042   remove a site prefix [redacted]::/60
18:55:01.948612-08:00   dhcp6c  36042   remove an address [redacted]/64 on mvneta0.4
18:55:01.955114-08:00   dhcp6c  36042   remove an address [redacted]/64 on mvneta0.91
18:55:01.955275-08:00   dhcp6c  36042   reset a timer on mvneta0.4090, state=INIT, timeo=0, retrans=905
18:55:01.955311-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=INIT
18:55:01.955337-08:00   dhcp6c  36042   removing an event on mvneta0.4090, state=INIT
18:55:01.955363-08:00   dhcp6c  36042   executes /var/etc/dhcp6c_wan_script.sh
18:55:01.957292-08:00   dhcp6c  36042   lstat failed: No such file or directory
18:55:01.958736-08:00   dhcp6c  36042   script "/var/etc/dhcp6c_wan_script.sh" cannot be executed safely
18:55:01.959822-08:00   dhcp6c  36042   script "/var/etc/dhcp6c_wan_script.sh" terminated
18:55:01.960483-08:00   dhcp6c  36042   exiting

jimp

Looks like that behavior is caused by the fix I put in for https://redmine.pfsense.org/issues/13253. Backing out the change from that issue lets it work.

Which is odd considering it has been working fine all around my lab on other systems (VMs, bare metal hardware, etc). It only seems to be affecting the 1100 in that way so far.

But I'll look into it and see what I can figure out.

jimp

I reverted that commit and now I can't reproduce this problem or the original problem that commit was intended to fix, so it seems some other change must have fixed that in a different yet non-obvious way.

So the problem commit is out, and tomorrow's snapshot should be good again. In the meantime, you can install the System Patches package and then create an entry for 5daac45752d00a97a9e01c5ddc7ed4f5ae0501ba to apply the fix OR you can make an entry for 8e88bd48a22b55d213ac7613be74c651706cfa0d and revert that. Either way will have the same net result.

adam.lantos

Thanks @jimp for the fix. I've re-tested with 23.01.b.20221221.1946 snapshot and the issue seems to be resolved.