PPPoE randomly disconnecting



  • Hi everyone,

    I am running pfsense 2.4.4-RELEASE-p1 on a small Zotac Box with an Intel i3.
    My WAN interface connects via PPPoE on IPv4 to ISP (Telekom Germany) via the Connectbox (some Zyxel box that is on Bridge mode) and receives an IPv6 address via DHCP6.

    This is functional so far, but infrequently we are getting disconnects and I can not figure out why these are happening.
    I assume it has something to do with the DHCP6 assignment.

    These are the logs from the most recent disconnect event.

    Jan 14 11:30:01     ppp         Multi-link PPP daemon for FreeBSD
    Jan 14 11:30:01     ppp         process 89546 started, version 5.8 (root@pfSense_v2_4_4_amd64-pfSense_v2_4_4-job-09 11:56 16-Nov-2018)
    Jan 14 11:30:01     ppp         waiting for process 65368 to die...
    Jan 14 11:30:01     ppp         caught fatal signal TERM
    Jan 14 11:30:01     ppp         [wan] IFACE: Close event
    Jan 14 11:30:01     ppp         [wan] IPCP: Close event
    Jan 14 11:30:01     ppp         [wan] IPCP: state change Opened --> Closing
    Jan 14 11:30:01     ppp         [wan] IPCP: SendTerminateReq #4
    Jan 14 11:30:01     ppp         [wan] IPCP: LayerDown
    Jan 14 11:30:01     ppp         [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
    Jan 14 11:30:01     ppp         [wan] IPV6CP: Close event
    Jan 14 11:30:01     ppp         [wan] IPV6CP: state change Opened --> Closing
    Jan 14 11:30:01     ppp         [wan] IPV6CP: SendTerminateReq #2
    Jan 14 11:30:01     ppp         [wan] IPV6CP: LayerDown
    Jan 14 11:30:02     ppp         waiting for process 65368 to die...
    Jan 14 11:30:03     ppp         [wan] IFACE: Down event
    Jan 14 11:30:03     ppp         [wan] IFACE: Rename interface pppoe0 to pppoe0
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan] IPCP: rec'd Terminate Ack #4 (Closing)
    Jan 14 11:30:03     ppp         [wan] IPCP: state change Closing --> Closed
    Jan 14 11:30:03     ppp         [wan] IPCP: LayerFinish
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] rec'd unexpected protocol IP
    Jan 14 11:30:03     ppp         [wan_link0] PPPoE: connection closed
    Jan 14 11:30:03     ppp         [wan_link0] Link: DOWN event
    Jan 14 11:30:03     ppp         [wan_link0] Link: giving up after 0 reconnection attempts
    Jan 14 11:30:03     ppp         [wan_link0] LCP: Close event
    Jan 14 11:30:03     ppp         [wan_link0] LCP: state change Opened --> Closing
    Jan 14 11:30:03     ppp         [wan_link0] Link: Leave bundle "wan"
    Jan 14 11:30:03     ppp         [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    Jan 14 11:30:03     ppp         [wan] IPCP: Close event
    Jan 14 11:30:03     ppp         [wan] IPV6CP: Close event
    Jan 14 11:30:03     ppp         [wan] IPCP: Down event
    Jan 14 11:30:03     ppp         [wan] IPCP: state change Closed --> Initial
    Jan 14 11:30:03     ppp         [wan] IPV6CP: Down event
    Jan 14 11:30:03     ppp         [wan] IPV6CP: LayerFinish
    Jan 14 11:30:03     ppp         [wan] Bundle: No NCPs left. Closing links...
    Jan 14 11:30:03     ppp         [wan] IPV6CP: state change Closing --> Initial
    Jan 14 11:30:03     ppp         [wan_link0] LCP: SendTerminateReq #2
    Jan 14 11:30:03     ppp         [wan_link0] LCP: LayerDown
    Jan 14 11:30:03     ppp         [wan_link0] LCP: Down event
    Jan 14 11:30:03     ppp         [wan_link0] LCP: LayerFinish
    Jan 14 11:30:03     ppp         [wan_link0] LCP: state change Closing --> Initial
    Jan 14 11:30:03     ppp         [wan] IPV6CP: rec'd Terminate Ack #2 (Initial)
    Jan 14 11:30:03     ppp         waiting for process 65368 to die...
    

    In this case it recovered after a short moment, but this was not always the case in the past.

    If needed I can also post the rest of the log that contains the successful re-connection.

    Any help would be greatly appreciated, because my next move would be to just not have IPv6 anymore, which I would like to avoid.