Netgate Discussion Forum
    • Categories
    • Recent
    • Tags
    • Popular
    • Users
    • Search
    • Register
    • Login

    Loss of IPV4 address on PPPoE interface after reboot

    Routing and Multi WAN
    3
    6
    255
    Loading More Posts
    • Oldest to Newest
    • Newest to Oldest
    • Most Votes
    Reply
    • Reply as topic
    Log in to reply
    This topic has been deleted. Only users with topic management privileges can see it.
    • M
      MrHedgehog
      last edited by

      I have been able to set-up a working IPV4/IPV6 configuration with pfsense 2.7.2 on both my WAN and LAN interfaces. All good, I thought. I should also add that I've only been using pfsense for a few weeks so an still very much learning.

      Now, when I reboot the router I witness some odd and annoying behaviour. The IPV4 interface will come and be assigned its (static) WAN IP address, then the IPV6 address is assigned. About 4 seconds later the IPV4 address will disappear off the pppoe interface and I will be left with just the IPV6 address on the WAN interface. An ifconfig at this non-working stage looks like this:

      vtnet0: flags=1008843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST,LOWER_UP> metric 0 mtu 1500
	options=800b8<VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,LINKSTATE>
	ether bc:24:11:54:4e:82
	inet6 fe80::xxxx:yyyy:fe54:4e82%vtnet0 prefixlen 64 scopeid 0x1
	media: Ethernet autoselect (10Gbase-T <full-duplex>)
	status: active
	nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
vtnet1: flags=1008843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST,LOWER_UP> metric 0 mtu 1500
	description: LAN
	options=800b8<VLAN_MTU,VLAN_HWTAGGING,JUMBO_MTU,VLAN_HWCSUM,LINKSTATE>
	ether bc:24:11:ab:31:4e
	inet 192.168.1.1 netmask 0xffffff00 broadcast 192.168.1.255
	inet6 fe80::xxxx:yyyy:feab:314e%vtnet1 prefixlen 64 scopeid 0x2
	inet6 2001:xxx:yyy:24c::1 prefixlen 64
	media: Ethernet autoselect (10Gbase-T <full-duplex>)
	status: active
	nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
enc0: flags=0 metric 0 mtu 1536
	options=0
	groups: enc
	nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
lo0: flags=1008049<UP,LOOPBACK,RUNNING,MULTICAST,LOWER_UP> metric 0 mtu 16384
	options=680003<RXCSUM,TXCSUM,LINKSTATE,RXCSUM_IPV6,TXCSUM_IPV6>
	inet 127.0.0.1 netmask 0x0
	inet6 ::1 prefixlen 128
	inet6 fe80::1%lo0 prefixlen 64 scopeid 0x4
	groups: lo
	nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
pflog0: flags=100<PROMISC> metric 0 mtu 33152
	options=0
	groups: pflog
pfsync0: flags=0 metric 0 mtu 1500
	options=0
	maxupd: 128 defer: off version: 1400
	syncok: 1
	groups: pfsync
pppoe0: flags=10088d1<UP,POINTOPOINT,RUNNING,NOARP,SIMPLEX,MULTICAST,LOWER_UP> metric 0 mtu 1492
	description: WAN
	options=0
	inet6 fe80::xxxx:yyyy:fe54:4e82%pppoe0 prefixlen 64 scopeid 0x7
	inet6 fe80::xxxx:yyyy:feab:314e%pppoe0 prefixlen 64 scopeid 0x7
	inet6 2001:8b0:1111:1111:0:ffff:d9a9:110b prefixlen 128 pltime 3600 vltime 7200
	nd6 options=23<PERFORMNUD,ACCEPT_RTADV,AUTO_LINKLOCAL>

      The two screen shots below illustrate this.

      no_ipv4_wan - Copy.JPG
      no_ipv4_gateway - Copy.JPG

      If I look at the pppoe interface with ifconfig, it just has the inet6 addresses.

      My setup is below:

      wan_interface_config.JPG

      lan_interface_config.JPG
      dhcpv6_config.JPG

      I am unsure how to best investigate this further and which logs might be relevant. I am happy to provide any furthers logs (though this can be a little difficult as I can only reproduce this when no-one else is at home due to the loss of connectivity that is brings about).

      Thanks in advance for any assistance.

      V 1 Reply Last reply Reply Quote 0
      • V
        viragomann @MrHedgehog
        last edited by

        @MrHedgehog
        Why do you have "dial on demand" enabled?

        However, I'd expect that the connection is established as soon as you try to make an IPv4 connection to the internet.

        Relevant logs should be PPP and system.

        M 1 Reply Last reply Reply Quote 0
        • M
          MrHedgehog @viragomann
          last edited by

          @viragomann

          Short answer to your question regarding the 'Dial on Demand' option was that it was suggested to enable this in some (farily old) pfsense documentation from my ISP. I've removed that and tried rebooting the router a few times, but it does not seem to make any difference.

          I've included the ppp and system logs (redacted).

          This feels like some sort of timing issue. When the router is up, but with only IPv6 connectivity, if I edit the WAN interface such that it causes a reconnection, the IPv4 address seems to come up.

          ipv4_interface_working.JPG

          Yet it I now reboot the router I suspect the IPv4 connection will fail

          These are the ppp logs from editing the WAN interface and a successful IPv4 connection. I've had to remove the system log as the post becomes too long.

          Sep 14 10:23:13	ppp	5866	[wan] be24:11ff:fe54:4e82 -> 9e89:1eff:fe2c:0000
Sep 14 10:23:13	ppp	5866	[wan] IPV6CP: LayerUp
Sep 14 10:23:13	ppp	5866	[wan] IPV6CP: state change Ack-Rcvd --> Opened
Sep 14 10:23:13	ppp	5866	[wan] IPV6CP: SendConfigAck #0
Sep 14 10:23:13	ppp	5866	[wan] IPV6CP: rec'd Configure Request #0 (Ack-Rcvd)
Sep 14 10:23:13	ppp	5866	[wan] IFACE: Add description "WAN"
Sep 14 10:23:13	ppp	5866	[wan] IFACE: Rename interface ng0 to pppoe0
Sep 14 10:23:13	ppp	5866	[wan] IFACE: Up event
Sep 14 10:23:12	ppp	5866	[wan] 217.xxx.yyy.11 -> xxx.yyy.xxx.yyy
Sep 14 10:23:12	ppp	5866	[wan] IPCP: LayerUp
Sep 14 10:23:12	ppp	5866	[wan] IPCP: state change Ack-Sent --> Opened
Sep 14 10:23:12	ppp	5866	[wan] SECDNS 217.169.20.21
Sep 14 10:23:12	ppp	5866	[wan] PRIDNS 217.169.20.20
Sep 14 10:23:12	ppp	5866	[wan] IPADDR 217.xxx.yyy.11
Sep 14 10:23:12	ppp	5866	[wan] IPCP: rec'd Configure Ack #4 (Ack-Sent)
Sep 14 10:23:12	ppp	5866	[wan] SECDNS 217.169.20.21
Sep 14 10:23:12	ppp	5866	[wan] PRIDNS 217.169.20.20
Sep 14 10:23:12	ppp	5866	[wan] IPADDR 217.xxx.yyy.11
Sep 14 10:23:12	ppp	5866	[wan] IPCP: SendConfigReq #4
Sep 14 10:23:12	ppp	5866	[wan] SECDNS 217.169.20.21
Sep 14 10:23:12	ppp	5866	[wan] PRIDNS 217.169.20.20
Sep 14 10:23:12	ppp	5866	[wan] 217.xxx.yyy.11 is OK
Sep 14 10:23:12	ppp	5866	[wan] IPADDR 217.xxx.yyy.11
Sep 14 10:23:12	ppp	5866	[wan] IPCP: rec'd Configure Nak #3 (Ack-Sent)
Sep 14 10:23:12	ppp	5866	[wan] SECDNS 0.0.0.0
Sep 14 10:23:12	ppp	5866	[wan] PRIDNS 0.0.0.0
Sep 14 10:23:12	ppp	5866	[wan] IPADDR 0.0.0.0
Sep 14 10:23:12	ppp	5866	[wan] IPCP: SendConfigReq #3
Sep 14 10:23:12	ppp	5866	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 14 10:23:12	ppp	5866	[wan] IPCP: rec'd Configure Reject #2 (Ack-Sent)
Sep 14 10:23:12	ppp	5866	[wan] IPV6CP: state change Req-Sent --> Ack-Rcvd
Sep 14 10:23:12	ppp	5866	[wan] IPV6CP: rec'd Configure Ack #2 (Req-Sent)
Sep 14 10:23:12	ppp	5866	[wan] SECDNS 0.0.0.0
Sep 14 10:23:12	ppp	5866	[wan] PRIDNS 0.0.0.0
Sep 14 10:23:12	ppp	5866	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 14 10:23:12	ppp	5866	[wan] IPADDR 0.0.0.0
Sep 14 10:23:12	ppp	5866	[wan] IPCP: SendConfigReq #2
Sep 14 10:23:12	ppp	5866	[wan] IPV6CP: SendConfigReq #2
Sep 14 10:23:11	ppp	5866	[wan] IPCP: state change Req-Sent --> Ack-Sent
Sep 14 10:23:11	ppp	5866	[wan] IPADDR xxx.yyy.xxx.yyy
Sep 14 10:23:11	ppp	5866	[wan] IPCP: SendConfigAck #0
Sep 14 10:23:11	ppp	5866	[wan] xxx.yyy.xxx.yyy is OK
Sep 14 10:23:11	ppp	5866	[wan] IPADDR xxx.yyy.xxx.yyy
Sep 14 10:23:11	ppp	5866	[wan] IPCP: rec'd Configure Request #0 (Req-Sent)
Sep 14 10:23:10	ppp	5866	[wan] IPV6CP: SendConfigReq #1
Sep 14 10:23:10	ppp	5866	[wan] IPV6CP: state change Starting --> Req-Sent
Sep 14 10:23:10	ppp	5866	[wan] IPV6CP: Up event
Sep 14 10:23:10	ppp	5866	[wan] SECDNS 0.0.0.0
Sep 14 10:23:10	ppp	5866	[wan] PRIDNS 0.0.0.0
Sep 14 10:23:10	ppp	5866	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 14 10:23:10	ppp	5866	[wan] IPADDR 0.0.0.0
Sep 14 10:23:10	ppp	5866	[wan] IPCP: SendConfigReq #1
Sep 14 10:23:10	ppp	5866	[wan] IPCP: state change Starting --> Req-Sent
Sep 14 10:23:10	ppp	5866	[wan] IPCP: Up event
Sep 14 10:23:10	ppp	5866	[wan] IPV6CP: LayerStart
Sep 14 10:23:10	ppp	5866	[wan] IPV6CP: state change Initial --> Starting
Sep 14 10:23:10	ppp	5866	[wan] IPV6CP: Open event
Sep 14 10:23:10	ppp	5866	[wan] IPCP: LayerStart
Sep 14 10:23:10	ppp	5866	[wan] IPCP: state change Initial --> Starting
Sep 14 10:23:10	ppp	5866	[wan] IPCP: Open event
Sep 14 10:23:10	ppp	5866	[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 14 10:23:10	ppp	5866	[wan_link0] Link: Join bundle "wan"
Sep 14 10:23:10	ppp	5866	[wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: authorization successful
Sep 14 10:23:10	ppp	5866	[wan_link0] MESG: REDACTED 63421000/19978000
Sep 14 10:23:10	ppp	5866	[wan_link0] CHAP: rec'd SUCCESS #71 len: 33
Sep 14 10:23:10	ppp	5866	[wan_link0] CHAP: sending RESPONSE #71 len: 29
Sep 14 10:23:10	ppp	5866	[wan_link0] CHAP: Using authname "REDACTED"
Sep 14 10:23:10	ppp	5866	[wan_link0] Name: "nge001.mai"
Sep 14 10:23:10	ppp	5866	[wan_link0] CHAP: rec'd CHALLENGE #71 len: 37
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: LayerUp
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: state change Ack-Sent --> Opened
Sep 14 10:23:10	ppp	5866	[wan_link0] MAGICNUM 0x18e05b1e
Sep 14 10:23:10	ppp	5866	[wan_link0] MRU 1492
Sep 14 10:23:10	ppp	5866	[wan_link0] PROTOCOMP
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: state change Req-Sent --> Ack-Sent
Sep 14 10:23:10	ppp	5866	[wan_link0] MAGICNUM 0x00854315
Sep 14 10:23:10	ppp	5866	[wan_link0] AUTHPROTO CHAP MD5
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: SendConfigAck #152
Sep 14 10:23:10	ppp	5866	[wan_link0] MAGICNUM 0x00854315
Sep 14 10:23:10	ppp	5866	[wan_link0] AUTHPROTO CHAP MD5
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: rec'd Configure Request #152 (Req-Sent)
Sep 14 10:23:10	ppp	5866	[wan_link0] MAGICNUM 0x18e05b1e
Sep 14 10:23:10	ppp	5866	[wan_link0] MRU 1492
Sep 14 10:23:10	ppp	5866	[wan_link0] PROTOCOMP
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: SendConfigReq #1
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: state change Starting --> Req-Sent
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: Up event
Sep 14 10:23:10	ppp	5866	[wan_link0] Link: UP event
Sep 14 10:23:10	ppp	5866	[wan_link0] PPPoE: connection successful
Sep 14 10:23:10	ppp	5866	PPPoE: rec'd ACNAME "nge001.mai-re0"
Sep 14 10:23:10	ppp	5866	[wan_link0] PPPoE: Connecting to ''
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: LayerStart
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: state change Initial --> Starting
Sep 14 10:23:10	ppp	5866	[wan_link0] LCP: Open event
Sep 14 10:23:10	ppp	5866	[wan_link0] Link: OPEN event
Sep 14 10:23:10	ppp	5866	[wan] Bundle: Interface ng0 created
Sep 14 10:23:10	ppp	5866	web: web is not running
Sep 14 10:23:09	ppp	13324	process 13324 terminated
Sep 14 10:23:09	ppp	13324	[wan_link0] Link: Shutdown
Sep 14 10:23:09	ppp	13324	[wan] Bundle: Shutdown
Sep 14 10:23:09	ppp	5866	waiting for process 13324 to die...
Sep 14 10:23:08	ppp	5866	waiting for process 13324 to die...
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: state change Closed --> Initial
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: Down event
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: Close event
Sep 14 10:23:07	ppp	13324	[wan_link0] Link: giving up after 0 reconnection attempts
Sep 14 10:23:07	ppp	13324	[wan_link0] Link: DOWN event
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: LayerFinish
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: state change Closing --> Closed
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: rec'd Terminate Ack #2 (Closing)
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: LayerDown
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: SendTerminateReq #2
Sep 14 10:23:07	ppp	13324	[wan] IPV6CP: state change Closed --> Initial
Sep 14 10:23:07	ppp	13324	[wan] IPV6CP: Down event
Sep 14 10:23:07	ppp	13324	[wan] IPCP: state change Closed --> Initial
Sep 14 10:23:07	ppp	13324	[wan] IPCP: Down event
Sep 14 10:23:07	ppp	13324	[wan] IPV6CP: Close event
Sep 14 10:23:07	ppp	13324	[wan] IPCP: Close event
Sep 14 10:23:07	ppp	13324	[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Sep 14 10:23:07	ppp	13324	[wan_link0] Link: Leave bundle "wan"
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: state change Opened --> Closing
Sep 14 10:23:07	ppp	13324	[wan_link0] LCP: Close event
Sep 14 10:23:07	ppp	13324	[wan_link0] Link: CLOSE event
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan_link0] rec'd unexpected protocol IP
Sep 14 10:23:07	ppp	13324	[wan] Bundle: closing link "wan_link0"...
Sep 14 10:23:07	ppp	13324	[wan] Bundle: No NCPs left. Closing links...
Sep 14 10:23:07	ppp	13324	[wan] IPV6CP: LayerFinish
Sep 14 10:23:07	ppp	13324	[wan] IPV6CP: state change Closing --> Closed
Sep 14 10:23:07	ppp	13324	[wan] IPV6CP: rec'd Terminate Ack #4 (Closing)
Sep 14 10:23:07	ppp	13324	[wan] IPCP: LayerFinish
Sep 14 10:23:07	ppp	13324	[wan] IPCP: state change Closing --> Closed
Sep 14 10:23:07	ppp	13324	[wan] IPCP: rec'd Terminate Ack #5 (Closing)
Sep 14 10:23:07	ppp	13324	[wan] IFACE: Set description "WAN"
Sep 14 10:23:07	ppp	13324	[wan] IFACE: Rename interface pppoe0 to pppoe0
Sep 14 10:23:07	ppp	13324	[wan] IFACE: Down event
Sep 14 10:23:07	ppp	5866	waiting for process 13324 to die...
Sep 14 10:23:06	ppp	13324	[wan] IPV6CP: LayerDown
Sep 14 10:23:06	ppp	13324	[wan] IPV6CP: SendTerminateReq #4
Sep 14 10:23:06	ppp	13324	[wan] IPV6CP: state change Opened --> Closing
Sep 14 10:23:06	ppp	13324	[wan] IPV6CP: Close event
Sep 14 10:23:06	ppp	13324	[wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
Sep 14 10:23:06	ppp	5866	waiting for process 13324 to die...
Sep 14 10:23:06	ppp	13324	[wan] IPCP: LayerDown
Sep 14 10:23:06	ppp	13324	[wan] IPCP: SendTerminateReq #5
Sep 14 10:23:06	ppp	13324	[wan] IPCP: state change Opened --> Closing
Sep 14 10:23:06	ppp	13324	[wan] IPCP: Close event
Sep 14 10:23:06	ppp	13324	[wan] IFACE: Close event
Sep 14 10:23:06	ppp	13324	caught fatal signal TERM
Sep 14 10:23:06	ppp	5866	process 5866 started, version 5.9
Sep 14 10:23:06	ppp	5866	Multi-link PPP daemon for FreeBSD

          Now when I reboot the router, There is no IPv4 connection, only IPv6. Logs are below from that session:

          Sep 14 10:43:48	ppp	11363	[wan] be24:11ff:fe54:4e82 -> 9e89:1eff:fe2c:0000
Sep 14 10:43:48	ppp	11363	[wan] IPV6CP: LayerUp
Sep 14 10:43:48	ppp	11363	[wan] IPV6CP: state change Ack-Sent --> Opened
Sep 14 10:43:48	ppp	11363	[wan] IPV6CP: rec'd Configure Ack #3 (Ack-Sent)
Sep 14 10:43:48	ppp	11363	[wan] IPV6CP: state change Opened --> Ack-Sent
Sep 14 10:43:48	ppp	11363	[wan] IPV6CP: SendConfigAck #0
Sep 14 10:43:48	ppp	11363	[wan] IPV6CP: SendConfigReq #3
Sep 14 10:43:45	ppp	11363	[wan] IPV6CP: LayerDown
Sep 14 10:43:45	ppp	11363	[wan] IPV6CP: rec'd Configure Request #0 (Opened)
Sep 14 10:43:42	ppp	11363	[wan] be24:11ff:fe54:4e82 -> 9e89:1eff:fe2c:0000
Sep 14 10:43:42	ppp	11363	[wan] IPV6CP: LayerUp
Sep 14 10:43:42	ppp	11363	[wan] IPV6CP: state change Ack-Rcvd --> Opened
Sep 14 10:43:42	ppp	11363	[wan] IPV6CP: SendConfigAck #0
Sep 14 10:43:42	ppp	11363	[wan] IPV6CP: rec'd Configure Request #0 (Ack-Rcvd)
Sep 14 10:43:42	ppp	11363	[wan] IFACE: Add description "WAN"
Sep 14 10:43:42	ppp	11363	[wan] IFACE: Rename interface ng0 to pppoe0
Sep 14 10:43:42	ppp	11363	[wan] IFACE: Up event
Sep 14 10:43:41	ppp	11363	[wan] 217.169.xxx.yyy -> xxx.yyy.xxx.yyy
Sep 14 10:43:41	ppp	11363	[wan] IPCP: LayerUp
Sep 14 10:43:41	ppp	11363	[wan] IPCP: state change Ack-Sent --> Opened
Sep 14 10:43:41	ppp	11363	[wan] SECDNS 217.169.20.21
Sep 14 10:43:41	ppp	11363	[wan] PRIDNS 217.169.20.20
Sep 14 10:43:41	ppp	11363	[wan] IPADDR 217.169.xxx.yyy
Sep 14 10:43:41	ppp	11363	[wan] IPCP: rec'd Configure Ack #4 (Ack-Sent)
Sep 14 10:43:41	ppp	11363	[wan] SECDNS 217.169.20.21
Sep 14 10:43:41	ppp	11363	[wan] PRIDNS 217.169.20.20
Sep 14 10:43:41	ppp	11363	[wan] IPADDR 217.169.xxx.yyy
Sep 14 10:43:41	ppp	11363	[wan] IPCP: SendConfigReq #4
Sep 14 10:43:41	ppp	11363	[wan] SECDNS 217.169.20.21
Sep 14 10:43:41	ppp	11363	[wan] PRIDNS 217.169.20.20
Sep 14 10:43:41	ppp	11363	[wan] 217.169.xxx.yyy is OK
Sep 14 10:43:41	ppp	11363	[wan] IPADDR 217.169.xxx.yyy
Sep 14 10:43:41	ppp	11363	[wan] IPCP: rec'd Configure Nak #3 (Ack-Sent)
Sep 14 10:43:41	ppp	11363	[wan] IPV6CP: state change Req-Sent --> Ack-Rcvd
Sep 14 10:43:41	ppp	11363	[wan] IPV6CP: rec'd Configure Ack #2 (Req-Sent)
Sep 14 10:43:41	ppp	11363	[wan] SECDNS 0.0.0.0
Sep 14 10:43:41	ppp	11363	[wan] PRIDNS 0.0.0.0
Sep 14 10:43:41	ppp	11363	[wan] IPADDR 0.0.0.0
Sep 14 10:43:41	ppp	11363	[wan] IPCP: SendConfigReq #3
Sep 14 10:43:41	ppp	11363	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 14 10:43:41	ppp	11363	[wan] IPCP: rec'd Configure Reject #2 (Ack-Sent)
Sep 14 10:43:41	ppp	11363	[wan] SECDNS 0.0.0.0
Sep 14 10:43:41	ppp	11363	[wan] PRIDNS 0.0.0.0
Sep 14 10:43:41	ppp	11363	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 14 10:43:41	ppp	11363	[wan] IPADDR 0.0.0.0
Sep 14 10:43:41	ppp	11363	[wan] IPCP: SendConfigReq #2
Sep 14 10:43:41	ppp	11363	[wan] IPV6CP: SendConfigReq #2
Sep 14 10:43:40	ppp	11363	[wan] IPADDR xxx.yyy.xxx.yyy
Sep 14 10:43:40	ppp	11363	[wan] IPCP: SendConfigAck #1
Sep 14 10:43:40	ppp	11363	[wan] xxx.yyy.xxx.yyy is OK
Sep 14 10:43:40	ppp	11363	[wan] IPADDR xxx.yyy.xxx.yyy
Sep 14 10:43:40	ppp	11363	[wan] IPCP: rec'd Configure Request #1 (Ack-Sent)
Sep 14 10:43:39	ppp	11363	[wan] IPCP: state change Req-Sent --> Ack-Sent
Sep 14 10:43:39	ppp	11363	[wan] IPADDR xxx.yyy.xxx.yyy
Sep 14 10:43:39	ppp	11363	[wan] IPCP: SendConfigAck #0
Sep 14 10:43:39	ppp	11363	[wan] xxx.yyy.xxx.yyy is OK
Sep 14 10:43:39	ppp	11363	[wan] IPADDR xxx.yyy.xxx.yyy
Sep 14 10:43:39	ppp	11363	[wan] IPCP: rec'd Configure Request #0 (Req-Sent)
Sep 14 10:43:39	ppp	11363	[wan] IPV6CP: SendConfigReq #1
Sep 14 10:43:39	ppp	11363	[wan] IPV6CP: state change Starting --> Req-Sent
Sep 14 10:43:39	ppp	11363	[wan] IPV6CP: Up event
Sep 14 10:43:39	ppp	11363	[wan] SECDNS 0.0.0.0
Sep 14 10:43:39	ppp	11363	[wan] PRIDNS 0.0.0.0
Sep 14 10:43:39	ppp	11363	[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 14 10:43:39	ppp	11363	[wan] IPADDR 0.0.0.0
Sep 14 10:43:39	ppp	11363	[wan] IPCP: SendConfigReq #1
Sep 14 10:43:39	ppp	11363	[wan] IPCP: state change Starting --> Req-Sent
Sep 14 10:43:39	ppp	11363	[wan] IPCP: Up event
Sep 14 10:43:39	ppp	11363	[wan] IPV6CP: LayerStart
Sep 14 10:43:39	ppp	11363	[wan] IPV6CP: state change Initial --> Starting
Sep 14 10:43:39	ppp	11363	[wan] IPV6CP: Open event
Sep 14 10:43:39	ppp	11363	[wan] IPCP: LayerStart
Sep 14 10:43:39	ppp	11363	[wan] IPCP: state change Initial --> Starting
Sep 14 10:43:39	ppp	11363	[wan] IPCP: Open event
Sep 14 10:43:39	ppp	11363	[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 14 10:43:39	ppp	11363	[wan_link0] Link: Join bundle "wan"
Sep 14 10:43:39	ppp	11363	[wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 14 10:43:39	ppp	11363	[wan_link0] LCP: authorization successful
Sep 14 10:43:39	ppp	11363	[wan_link0] MESG: REDACTED 63421000/19978000
Sep 14 10:43:39	ppp	11363	[wan_link0] CHAP: rec'd SUCCESS #81 len: 33
Sep 14 10:43:38	ppp	11363	[wan_link0] CHAP: sending RESPONSE #81 len: 29
Sep 14 10:43:38	ppp	11363	[wan_link0] CHAP: Using authname "REDACTED"
Sep 14 10:43:38	ppp	11363	[wan_link0] Name: "nge001.mai"
Sep 14 10:43:38	ppp	11363	[wan_link0] CHAP: rec'd CHALLENGE #81 len: 33
Sep 14 10:43:38	ppp	11363	[wan_link0] LCP: LayerUp
Sep 14 10:43:38	ppp	11363	[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Sep 14 10:43:38	ppp	11363	[wan_link0] LCP: state change Ack-Rcvd --> Opened
Sep 14 10:43:38	ppp	11363	[wan_link0] MAGICNUM 0x1a9c4ba1
Sep 14 10:43:38	ppp	11363	[wan_link0] AUTHPROTO CHAP MD5
Sep 14 10:43:38	ppp	11363	[wan_link0] LCP: SendConfigAck #56
Sep 14 10:43:38	ppp	11363	[wan_link0] MAGICNUM 0x1a9c4ba1
Sep 14 10:43:38	ppp	11363	[wan_link0] AUTHPROTO CHAP MD5
Sep 14 10:43:38	ppp	11363	[wan_link0] LCP: rec'd Configure Request #56 (Ack-Rcvd)
Sep 14 10:43:37	ppp	11363	[wan_link0] LCP: state change Req-Sent --> Ack-Rcvd
Sep 14 10:43:37	ppp	11363	[wan_link0] MAGICNUM 0x01fd45f8
Sep 14 10:43:37	ppp	11363	[wan_link0] MRU 1492
Sep 14 10:43:37	ppp	11363	[wan_link0] PROTOCOMP
Sep 14 10:43:37	ppp	11363	[wan_link0] LCP: rec'd Configure Ack #2 (Req-Sent)
Sep 14 10:43:37	ppp	11363	[wan_link0] MAGICNUM 0x01fd45f8
Sep 14 10:43:37	ppp	11363	[wan_link0] MRU 1492
Sep 14 10:43:37	ppp	11363	[wan_link0] PROTOCOMP
Sep 14 10:43:37	ppp	11363	[wan_link0] LCP: SendConfigReq #2
Sep 14 10:43:37	ppp	11363	[wan_link0] LCP: state change Ack-Rcvd --> Req-Sent
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: state change Req-Sent --> Ack-Rcvd
Sep 14 10:43:35	ppp	11363	[wan_link0] MAGICNUM 0x01fd45f8
Sep 14 10:43:35	ppp	11363	[wan_link0] MRU 1492
Sep 14 10:43:35	ppp	11363	[wan_link0] PROTOCOMP
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: rec'd Configure Ack #1 (Req-Sent)
Sep 14 10:43:35	ppp	11363	[wan_link0] MAGICNUM 0x01fd45f8
Sep 14 10:43:35	ppp	11363	[wan_link0] MRU 1492
Sep 14 10:43:35	ppp	11363	[wan_link0] PROTOCOMP
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: SendConfigReq #1
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: state change Starting --> Req-Sent
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: Up event
Sep 14 10:43:35	ppp	11363	[wan_link0] Link: UP event
Sep 14 10:43:35	ppp	11363	[wan_link0] PPPoE: connection successful
Sep 14 10:43:35	ppp	11363	PPPoE: rec'd ACNAME "nge001.mai-re0"
Sep 14 10:43:35	ppp	11363	[wan_link0] PPPoE: Connecting to ''
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: LayerStart
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: state change Initial --> Starting
Sep 14 10:43:35	ppp	11363	[wan_link0] LCP: Open event
Sep 14 10:43:35	ppp	11363	[wan_link0] Link: OPEN event
Sep 14 10:43:35	ppp	11363	[wan] Bundle: Interface ng0 created
Sep 14 10:43:35	ppp	11363	web: web is not running
Sep 14 10:43:35	ppp	11363	process 11363 started, version 5.9
Sep 14 10:43:35	ppp	11363	Multi-link PPP daemon for FreeBSD
          V 1 Reply Last reply Reply Quote 0
          • V
            viragomann @MrHedgehog
            last edited by

            @MrHedgehog
            In the PPP log I cannot find any reason for removing the WAN IPv4. All looks well so far.

            The system general log should show an entry to dropping the IP though.
            Just post the section from connecting to dropping the IP again.
            Or maybe you can find the regarding lines yourself.

            M 1 Reply Last reply Reply Quote 0
            • M
              MrHedgehog @viragomann
              last edited by

              @viragomann - I've compared logs of successful/unsuccessful connection attempt. Below have produced a site by side diff of the logs.

              I might set a static ipv6 address, rather than have it obtained from DHCPv6 (if possible). LHS working, RHS not-working

              Sep 22 21:51:35 pfsense ppp[51357]: Multi-link PPP daemon for FreeBSD                                             |     Sep 22 21:50:35 pfsense ppp[18770]: Multi-link PPP daemon for FreeBSD
Sep 22 21:51:35 pfsense ppp[51357]:                                                                               |     Sep 22 21:50:35 pfsense ppp[18770]:
Sep 22 21:51:35 pfsense ppp[51357]: process 51357 started, version 5.9                                            |     Sep 22 21:50:35 pfsense ppp[18770]: process 18770 started, version 5.9
Sep 22 21:51:35 pfsense ppp[51357]: web: web is not running                                                       |     Sep 22 21:50:35 pfsense ppp[18770]: web: web is not running
Sep 22 21:51:35 pfsense ppp[51357]: [wan] Bundle: Interface ng0 created                                           |     Sep 22 21:50:35 pfsense ppp[18770]: [wan] Bundle: Interface ng0 created
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] Link: OPEN event                                                  |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] Link: OPEN event
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: Open event                                                   |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: Open event
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: state change Initial --> Starting                            |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: state change Initial --> Starting
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: LayerStart                                                   |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: LayerStart
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] PPPoE: Connecting to ''                                           |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] PPPoE: Connecting to ''
Sep 22 21:51:35 pfsense ppp[51357]: PPPoE: rec'd ACNAME "nge001.mai-re0"                                          |     Sep 22 21:50:35 pfsense ppp[18770]: PPPoE: rec'd ACNAME "nge001.mai-re0"
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] PPPoE: connection successful                                      |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] PPPoE: connection successful
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] Link: UP event                                                    |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] Link: UP event
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: Up event                                                     |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: Up event
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: state change Starting --> Req-Sent                           |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: state change Starting --> Req-Sent
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: SendConfigReq #1                                             |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: SendConfigReq #1
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   PROTOCOMP                                                       |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   PROTOCOMP
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MRU 1492                                                        |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   MRU 1492
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MAGICNUM 0xfae8dcaa                                             |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   MAGICNUM 0x25060e68
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: rec'd Configure Request #63 (Req-Sent)                       |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: rec'd Configure Request #56 (Req-Sent)
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   AUTHPROTO CHAP MD5                                              |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   AUTHPROTO CHAP MD5
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MAGICNUM 0x6949deef                                             |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   MAGICNUM 0x3bb3a08a
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: SendConfigAck #63                                            |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: SendConfigAck #56
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   AUTHPROTO CHAP MD5                                              |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   AUTHPROTO CHAP MD5
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MAGICNUM 0x6949deef                                             |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   MAGICNUM 0x3bb3a08a
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: state change Req-Sent --> Ack-Sent                           |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)                            |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   PROTOCOMP                                                       |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   PROTOCOMP
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MRU 1492                                                        |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   MRU 1492
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MAGICNUM 0xfae8dcaa                                             |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   MAGICNUM 0x25060e68
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: state change Ack-Sent --> Opened                             |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: state change Ack-Sent --> Opened
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: auth: peer wants CHAP, I want nothing                        |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: LayerUp                                                      |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] LCP: LayerUp
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] CHAP: rec'd CHALLENGE #208 len: 38                                |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] CHAP: rec'd CHALLENGE #138 len: 47
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   Name: "nge001.mai"                                              |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0]   Name: "nge001.mai"
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] CHAP: Using authname "XXXXX.1"                                    |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] CHAP: Using authname "mwh1@a.1"
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] CHAP: sending RESPONSE #208 len: 29                               |     Sep 22 21:50:35 pfsense ppp[18770]: [wan_link0] CHAP: sending RESPONSE #138 len: 29
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] CHAP: rec'd SUCCESS #208 len: 33                                  |     Sep 22 21:50:36 pfsense ppp[18770]: [wan_link0] CHAP: rec'd SUCCESS #138 len: 33
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0]   MESG: FTTC7520750 63309000/19978000                             |     Sep 22 21:50:36 pfsense ppp[18770]: [wan_link0]   MESG: FTTC7520750 63309000/19978000
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] LCP: authorization successful                                     |     Sep 22 21:50:36 pfsense ppp[18770]: [wan_link0] LCP: authorization successful
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] Link: Matched action 'bundle "wan" ""'                            |     Sep 22 21:50:36 pfsense ppp[18770]: [wan_link0] Link: Matched action 'bundle "wan" ""'
Sep 22 21:51:35 pfsense ppp[51357]: [wan_link0] Link: Join bundle "wan"                                           |     Sep 22 21:50:36 pfsense ppp[18770]: [wan_link0] Link: Join bundle "wan"
Sep 22 21:51:35 pfsense ppp[51357]: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps             |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPCP: Open event                                                        |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPCP: Open event
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPCP: state change Initial --> Starting                                 |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPCP: state change Initial --> Starting
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPCP: LayerStart                                                        |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPCP: LayerStart
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPV6CP: Open event                                                      |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPV6CP: Open event
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPV6CP: state change Initial --> Starting                               |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPV6CP: state change Initial --> Starting
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPV6CP: LayerStart                                                      |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPV6CP: LayerStart
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPCP: Up event                                                          |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPCP: Up event
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPCP: state change Starting --> Req-Sent                                |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPCP: state change Starting --> Req-Sent
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPCP: SendConfigReq #1                                                  |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPCP: SendConfigReq #1
Sep 22 21:51:35 pfsense ppp[51357]: [wan]   IPADDR 0.0.0.0                                                        |     Sep 22 21:50:36 pfsense ppp[18770]: [wan]   IPADDR 0.0.0.0
Sep 22 21:51:35 pfsense ppp[51357]: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid                      |     Sep 22 21:50:36 pfsense ppp[18770]: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 22 21:51:35 pfsense ppp[51357]: [wan]   PRIDNS 0.0.0.0                                                        |     Sep 22 21:50:36 pfsense ppp[18770]: [wan]   PRIDNS 0.0.0.0
Sep 22 21:51:35 pfsense ppp[51357]: [wan]   SECDNS 0.0.0.0                                                        |     Sep 22 21:50:36 pfsense ppp[18770]: [wan]   SECDNS 0.0.0.0
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPV6CP: Up event                                                        |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPV6CP: Up event
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPV6CP: state change Starting --> Req-Sent                              |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPV6CP: state change Starting --> Req-Sent
Sep 22 21:51:35 pfsense ppp[51357]: [wan] IPV6CP: SendConfigReq #1                                                |     Sep 22 21:50:36 pfsense ppp[18770]: [wan] IPV6CP: SendConfigReq #1
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: rec'd Configure Request #0 (Req-Sent)                             |     Sep 22 21:50:37 pfsense ppp[18770]: [wan] IPCP: rec'd Configure Request #0 (Req-Sent)
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR aaa.bbb.81.187                                                 |     Sep 22 21:50:37 pfsense ppp[18770]: [wan]   IPADDR 81.187.81.187
Sep 22 21:51:37 pfsense ppp[51357]: [wan]     aaa.bbb.81.187 is OK                                                |     Sep 22 21:50:37 pfsense ppp[18770]: [wan]     81.187.81.187 is OK
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: SendConfigAck #0                                                  |     Sep 22 21:50:37 pfsense ppp[18770]: [wan] IPCP: SendConfigAck #0
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR aaa.bbb.81.187                                                 |     Sep 22 21:50:37 pfsense ppp[18770]: [wan]   IPADDR 81.187.81.187
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: state change Req-Sent --> Ack-Sent                                |     Sep 22 21:50:37 pfsense ppp[18770]: [wan] IPCP: state change Req-Sent --> Ack-Sent
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPV6CP: SendConfigReq #2                                                |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: rec'd Configure Request #1 (Ack-Sent)
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: SendConfigReq #2                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR aaa.bbb.81.187
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR 0.0.0.0                                                        |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]     aaa.bbb.81.187 is OK
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid                      |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: SendConfigAck #1
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   PRIDNS 0.0.0.0                                                        |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR aaa.bbb.81.187
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   SECDNS 0.0.0.0                                                        |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPV6CP: SendConfigReq #2
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: rec'd Configure Reject #2 (Ack-Sent)                              |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: SendConfigReq #2
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid                      |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR 0.0.0.0
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: SendConfigReq #3                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR 0.0.0.0                                                        |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   PRIDNS 0.0.0.0
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   PRIDNS 0.0.0.0                                                        |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   SECDNS 0.0.0.0
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   SECDNS 0.0.0.0                                                        |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: rec'd Configure Reject #2 (Ack-Sent)
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPV6CP: rec'd Configure Ack #2 (Req-Sent)                               |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd                              |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: SendConfigReq #3
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: rec'd Configure Nak #3 (Ack-Sent)                                 |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR 0.0.0.0
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR aaa.bbb.17.11                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   PRIDNS 0.0.0.0
Sep 22 21:51:37 pfsense ppp[51357]: [wan]     aaa.bbb.17.11 is OK                                                 |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   SECDNS 0.0.0.0
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   PRIDNS aaa.bbb.20.20                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPV6CP: rec'd Configure Ack #2 (Req-Sent)
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   SECDNS aaa.bbb.20.21                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPV6CP: state change Req-Sent --> Ack-Rcvd
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: SendConfigReq #4                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: rec'd Configure Nak #3 (Ack-Sent)
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR aaa.bbb.17.11                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR 217.169.17.11
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   PRIDNS aaa.bbb.20.20                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]     217.169.17.11 is OK
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   SECDNS aaa.bbb.20.21                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   PRIDNS 217.169.20.20
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: rec'd Configure Ack #4 (Ack-Sent)                                 |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   SECDNS aaa.bbb.20.21
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   IPADDR aaa.bbb.17.11                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: SendConfigReq #4
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   PRIDNS aaa.bbb.20.20                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR 217.169.17.11
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   SECDNS aaa.bbb.20.21                                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   PRIDNS 217.169.20.20
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: state change Ack-Sent --> Opened                                  |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   SECDNS aaa.bbb.20.21
Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPCP: LayerUp                                                           |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: rec'd Configure Ack #4 (Ack-Sent)
Sep 22 21:51:37 pfsense ppp[51357]: [wan]   aaa.bbb.17.11 -> aaa.bbb.81.187                                       |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   IPADDR 217.169.17.11
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IFACE: Up event                                                         |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   PRIDNS aaa.bbb.20.20
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IFACE: Rename interface ng0 to pppoe0                                   |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   SECDNS aaa.bbb.20.21
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IFACE: Add description "WAN"                                            |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: state change Ack-Sent --> Opened
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IPV6CP: rec'd Configure Request #0 (Ack-Rcvd)                           |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: LayerUp
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IPV6CP: SendConfigAck #0                                                |     Sep 22 21:50:38 pfsense ppp[18770]: [wan]   aaa.bbb.17.11 -> aaa.bbb.81.187
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IPV6CP: state change Ack-Rcvd --> Opened                                |     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IFACE: Up event
Sep 22 21:51:38 pfsense ppp[51357]: [wan] IPV6CP: LayerUp                                                         |     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IFACE: Rename interface ng0 to pppoe0
Sep 22 21:51:38 pfsense ppp[51357]: [wan]   be24:11ff:feab:314e -> 9e89:1eff:fe2c:0000                            |     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IFACE: Add description "WAN"
                                                                                                                  >     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IPV6CP: rec'd Configure Request #0 (Ack-Rcvd)
                                                                                                                  >     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IPV6CP: SendConfigAck #0
                                                                                                                  >     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IPV6CP: state change Ack-Rcvd --> Opened
                                                                                                                  >     Sep 22 21:50:39 pfsense ppp[18770]: [wan] IPV6CP: LayerUp
                                                                                                                  >     Sep 22 21:50:39 pfsense ppp[18770]: [wan]   be24:11ff:feab:314e -> 9e89:1eff:fe2c:0000
                                                                                                                  >     Sep 22 21:50:42 pfsense ppp[18770]: [wan] IPV6CP: rec'd Configure Request #0 (Opened)
                                                                                                                  >     Sep 22 21:50:42 pfsense ppp[18770]: [wan] IPV6CP: LayerDown
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan] IPV6CP: SendConfigReq #3
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan] IPV6CP: SendConfigAck #0
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan] IPV6CP: state change Opened --> Ack-Sent
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan_link0] rec'd unexpected protocol IPv6
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan] IPV6CP: rec'd Configure Ack #3 (Ack-Sent)
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan] IPV6CP: state change Ack-Sent --> Opened
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan] IPV6CP: LayerUp
                                                                                                                  >     Sep 22 21:50:45 pfsense ppp[18770]: [wan]   be24:11ff:feab:314e -> 9e89:1eff:fe2c:0000
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: caught fatal signal TERM
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IFACE: Close event
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPCP: Close event
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPCP: state change Opened --> Closing
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPCP: SendTerminateReq #5
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPCP: LayerDown
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. Th
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPV6CP: Close event
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPV6CP: state change Opened --> Closing
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPV6CP: SendTerminateReq #4
                                                                                                                  >     Sep 22 21:51:16 pfsense ppp[18770]: [wan] IPV6CP: LayerDown
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IFACE: Down event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IFACE: Rename interface pppoe0 to pppoe0
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IFACE: Set description "WAN"
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPCP: rec'd Terminate Ack #5 (Closing)
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPCP: state change Closing --> Closed
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPCP: LayerFinish
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPV6CP: rec'd Terminate Ack #4 (Closing)
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPV6CP: state change Closing --> Closed
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPV6CP: LayerFinish
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] Bundle: No NCPs left. Closing links...
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] Bundle: closing link "wan_link0"...
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] rec'd unexpected protocol IP
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] rec'd unexpected protocol IPv6
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] Link: CLOSE event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: Close event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: state change Opened --> Closing
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] Link: Leave bundle "wan"
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPCP: Close event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPV6CP: Close event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPCP: Down event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPCP: state change Closed --> Initial
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPV6CP: Down event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan] IPV6CP: state change Closed --> Initial
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: SendTerminateReq #2
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: LayerDown
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] rec'd proto IPv6 during terminate phase
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] rec'd proto IPv6 during terminate phase
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: rec'd Terminate Ack #2 (Closing)
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: state change Closing --> Closed
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: LayerFinish
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] Link: DOWN event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] Link: giving up after 0 reconnection attempts
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: Close event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: Down event
                                                                                                                  >     Sep 22 21:51:17 pfsense ppp[18770]: [wan_link0] LCP: state change Closed --> Initial
                                                                                                                  >     Sep 22 21:51:19 pfsense ppp[18770]: [wan] Bundle: Shutdown
                                                                                                                  >     Sep 22 21:51:19 pfsense ppp[18770]: [wan_link0] Link: Shutdown
                                                                                                                  >     Sep 22 21:51:19 pfsense ppp[18770]: process 18770 terminated

              Logs diverge here:

              Sep 22 21:51:37 pfsense ppp[51357]: [wan] IPV6CP: SendConfigReq #2                                                |     Sep 22 21:50:38 pfsense ppp[18770]: [wan] IPCP: rec'd Configure Request #1 (Ack-Sent)
              1 Reply Last reply Reply Quote 0
              • S StrixTechnica referenced this topic on
              • S
                StrixTechnica
                last edited by

                @MrHedgehog With any luck, this will be fixed in the next release of Plus and CE:

                Redmine: https://redmine.pfsense.org/issues/16103

                Meanwhile, anyone who continues to experience this problem can manually patch /usr/local/bin/ppp-linkdown.

                1 Reply Last reply Reply Quote 0
                • First post
                  Last post
                Copyright 2025 Rubicon Communications LLC (Netgate). All rights reserved.