PPPoE Connection Unstable



  • Afternoon folks,
    Im having a weird problem whereby my pppoe connection keeps dropping, it successfully connects but this is happpening randomly and i dont know why. Obviously during this time it causes me loss of internet.
    Anyone have any suggestions as to whats goin on? Below is a copy of my PPP logs;

    Aug 3 19:40:26 ppp: [wan] 81.156.95.xxx -> 172.16.12.107
    Aug 3 19:40:26 ppp: [wan] IPCP: LayerUp
    Aug 3 19:40:26 ppp: [wan] IPCP: state change Ack-Sent --> Opened
    Aug 3 19:40:26 ppp: [wan] IPADDR 81.156.95.214
    Aug 3 19:40:26 ppp: [wan] IPCP: rec'd Configure Ack #3 (Ack-Sent)
    Aug 3 19:40:26 ppp: [wan] IPADDR 81.156.95.214
    Aug 3 19:40:26 ppp: [wan] IPCP: SendConfigReq #3
    Aug 3 19:40:26 ppp: [wan] 81.156.95.214 is OK
    Aug 3 19:40:26 ppp: [wan] IPADDR 81.156.95.214
    Aug 3 19:40:26 ppp: [wan] IPCP: rec'd Configure Nak #2 (Ack-Sent)
    Aug 3 19:40:26 ppp: [wan] IFACE: Rename interface ng0 to pppoe0
    Aug 3 19:40:26 ppp: [wan] IFACE: Up event
    Aug 3 19:40:26 ppp: [wan] 02e0:4cff:fe68:84cb -> 0ea4:02ff:fe2e:bc01
    Aug 3 19:40:26 ppp: [wan] IPV6CP: LayerUp
    Aug 3 19:40:26 ppp: [wan] IPV6CP: state change Ack-Sent --> Opened
    Aug 3 19:40:26 ppp: [wan] IPV6CP: rec'd Configure Ack #1 (Ack-Sent)
    Aug 3 19:40:26 ppp: [wan] IPADDR 0.0.0.0
    Aug 3 19:40:26 ppp: [wan] IPCP: SendConfigReq #2
    Aug 3 19:40:26 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Aug 3 19:40:26 ppp: [wan] IPCP: rec'd Configure Reject #1 (Ack-Sent)
    Aug 3 19:40:26 ppp: [wan] IPCP: state change Req-Sent --> Ack-Sent
    Aug 3 19:40:26 ppp: [wan] IPADDR 172.16.12.107
    Aug 3 19:40:26 ppp: [wan] IPCP: SendConfigAck #57
    Aug 3 19:40:26 ppp: [wan] 172.16.12.107 is OK
    Aug 3 19:40:26 ppp: [wan] IPADDR 172.16.12.107
    Aug 3 19:40:26 ppp: [wan] IPCP: rec'd Configure Request #57 (Req-Sent)
    Aug 3 19:40:26 ppp: [wan] IPV6CP: state change Req-Sent --> Ack-Sent
    Aug 3 19:40:26 ppp: [wan] IPV6CP: SendConfigAck #108
    Aug 3 19:40:26 ppp: [wan] IPV6CP: rec'd Configure Request #108 (Req-Sent)
    Aug 3 19:40:26 ppp: [wan] IPV6CP: SendConfigReq #1
    Aug 3 19:40:26 ppp: [wan] IPV6CP: state change Starting --> Req-Sent
    Aug 3 19:40:26 ppp: [wan] IPV6CP: Up event
    Aug 3 19:40:26 ppp: [wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Aug 3 19:40:26 ppp: [wan] IPADDR 0.0.0.0
    Aug 3 19:40:26 ppp: [wan] IPCP: SendConfigReq #1
    Aug 3 19:40:26 ppp: [wan] IPCP: state change Starting --> Req-Sent
    Aug 3 19:40:26 ppp: [wan] IPCP: Up event
    Aug 3 19:40:26 ppp: [wan] IPV6CP: LayerStart
    Aug 3 19:40:26 ppp: [wan] IPV6CP: state change Initial --> Starting
    Aug 3 19:40:26 ppp: [wan] IPV6CP: Open event
    Aug 3 19:40:26 ppp: [wan] IPCP: LayerStart
    Aug 3 19:40:26 ppp: [wan] IPCP: state change Initial --> Starting
    Aug 3 19:40:26 ppp: [wan] IPCP: Open event
    Aug 3 19:40:26 ppp: [wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
    Aug 3 19:40:26 ppp: [wan_link0] Link: Join bundle "wan"
    Aug 3 19:40:26 ppp: [wan_link0] Link: Matched action 'bundle "wan" ""'
    Aug 3 19:40:26 ppp: [wan_link0] LCP: authorization successful
    Aug 3 19:40:26 ppp: [wan_link0] MESG: CHAP authentication success
    Aug 3 19:40:26 ppp: [wan_link0] CHAP: rec'd SUCCESS #1 len: 31
    Aug 3 19:40:26 ppp: [wan_link0] CHAP: sending RESPONSE #1 len: 46
    Aug 3 19:40:26 ppp: [wan_link0] CHAP: Using authname "bthomehub@btbroadband.com"
    Aug 3 19:40:26 ppp: [wan_link0] Name: "acc-aln2.hac"
    Aug 3 19:40:26 ppp: [wan_link0] CHAP: rec'd CHALLENGE #1 len: 62
    Aug 3 19:40:26 ppp: [wan_link0] LCP: LayerUp
    Aug 3 19:40:26 ppp: [wan_link0] LCP: auth: peer wants CHAP, I want nothing
    Aug 3 19:40:26 ppp: [wan_link0] LCP: state change Ack-Sent --> Opened
    Aug 3 19:40:26 ppp: [wan_link0] MAGICNUM 0x039adbc2
    Aug 3 19:40:26 ppp: [wan_link0] MRU 1492
    Aug 3 19:40:26 ppp: [wan_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
    Aug 3 19:40:26 ppp: [wan_link0] MAGICNUM 0x039adbc2
    Aug 3 19:40:26 ppp: [wan_link0] MRU 1492
    Aug 3 19:40:26 ppp: [wan_link0] LCP: SendConfigReq #2
    Aug 3 19:40:26 ppp: [wan_link0] PROTOCOMP
    Aug 3 19:40:26 ppp: [wan_link0] LCP: rec'd Configure Reject #1 (Ack-Sent)
    Aug 3 19:40:26 ppp: [wan_link0] LCP: state change Req-Sent --> Ack-Sent
    Aug 3 19:40:26 ppp: [wan_link0] MAGICNUM 0x715fca98
    Aug 3 19:40:26 ppp: [wan_link0] AUTHPROTO CHAP MD5
    Aug 3 19:40:26 ppp: [wan_link0] MRU 1492
    Aug 3 19:40:26 ppp: [wan_link0] LCP: SendConfigAck #255
    Aug 3 19:40:26 ppp: [wan_link0] MAGICNUM 0x715fca98
    Aug 3 19:40:26 ppp: [wan_link0] AUTHPROTO CHAP MD5
    Aug 3 19:40:26 ppp: [wan_link0] MRU 1492
    Aug 3 19:40:26 ppp: [wan_link0] LCP: rec'd Configure Request #255 (Req-Sent)
    Aug 3 19:40:26 ppp: [wan_link0] MAGICNUM 0x039adbc2
    Aug 3 19:40:26 ppp: [wan_link0] MRU 1492
    Aug 3 19:40:26 ppp: [wan_link0] PROTOCOMP
    Aug 3 19:40:26 ppp: [wan_link0] LCP: SendConfigReq #1
    Aug 3 19:40:26 ppp: [wan_link0] LCP: state change Starting --> Req-Sent
    Aug 3 19:40:26 ppp: [wan_link0] LCP: Up event
    Aug 3 19:40:26 ppp: [wan_link0] Link: UP event
    Aug 3 19:40:26 ppp: [wan_link0] PPPoE: connection successful
    Aug 3 19:40:26 ppp: PPPoE: rec'd ACNAME "acc-aln2.hac"
    Aug 3 19:40:26 ppp: [wan_link0] PPPoE: Connecting to ''
    Aug 3 19:40:26 ppp: [wan_link0] LCP: LayerStart
    Aug 3 19:40:26 ppp: [wan_link0] LCP: state change Initial --> Starting
    Aug 3 19:40:26 ppp: [wan_link0] LCP: Open event
    Aug 3 19:40:26 ppp: [wan_link0] Link: OPEN event
    Aug 3 19:40:26 ppp: [wan] Bundle: Interface ng0 created
    Aug 3 19:40:26 ppp: web: web is not running
    Aug 3 19:40:26 ppp: process 88961 terminated
    Aug 3 19:40:26 ppp: [wan_link0] Link: Shutdown
    Aug 3 19:40:26 ppp: [wan] Bundle: Shutdown
    Aug 3 19:40:25 ppp: waiting for process 88961 to die...
    Aug 3 19:40:24 ppp: waiting for process 88961 to die...
    Aug 3 19:40:24 ppp: [wan_link0] LCP: state change Closing --> Initial
    Aug 3 19:40:24 ppp: [wan_link0] LCP: LayerFinish
    Aug 3 19:40:24 ppp: [wan_link0] LCP: Down event
    Aug 3 19:40:24 ppp: [wan_link0] LCP: state change Stopping --> Closing
    Aug 3 19:40:24 ppp: [wan_link0] LCP: Close event
    Aug 3 19:40:24 ppp: [wan_link0] Link: giving up after 0 reconnection attempts
    Aug 3 19:40:24 ppp: [wan_link0] Link: DOWN event
    Aug 3 19:40:24 ppp: [wan_link0] PPPoE: connection closed
    Aug 3 19:40:24 ppp: [wan] rec'd unexpected protocol IP
    Aug 3 19:40:24 ppp: [wan] rec'd unexpected protocol IP
    Aug 3 19:40:24 ppp: [wan] rec'd unexpected protocol IP
    Aug 3 19:40:24 ppp: [wan] rec'd unexpected protocol IP
    Aug 3 19:40:24 ppp: [wan_link0] LCP: LayerDown
    Aug 3 19:40:24 ppp: [wan_link0] LCP: SendTerminateAck #3
    Aug 3 19:40:24 ppp: [wan] IPV6CP: state change Closing --> Initial
    Aug 3 19:40:24 ppp: [wan] Bundle: No NCPs left. Closing links...
    Aug 3 19:40:24 ppp: [wan] IPV6CP: LayerFinish
    Aug 3 19:40:24 ppp: [wan] IPV6CP: Down event
    Aug 3 19:40:24 ppp: [wan] IPCP: state change Closed --> Initial
    Aug 3 19:40:24 ppp: [wan] IPCP: Down event
    Aug 3 19:40:24 ppp: [wan] IPV6CP: Close event
    Aug 3 19:40:24 ppp: [wan] IPCP: Close event
    Aug 3 19:40:24 ppp: [wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    Aug 3 19:40:24 ppp: [wan_link0] Link: Leave bundle "wan"
    Aug 3 19:40:24 ppp: [wan_link0] LCP: state change Opened --> Stopping
    Aug 3 19:40:24 ppp: [wan_link0] LCP: rec'd Terminate Request #121 (Opened)
    Aug 3 19:40:24 ppp: [wan] IPCP: LayerFinish
    Aug 3 19:40:24 ppp: [wan] IPCP: state change Closing --> Closed
    Aug 3 19:40:24 ppp: [wan] IPCP: rec'd Terminate Ack #4 (Closing)
    Aug 3 19:40:24 ppp: [wan] IFACE: Rename interface pppoe0 to pppoe0
    Aug 3 19:40:24 ppp: [wan] IFACE: Down event
    Aug 3 19:40:23 ppp: [wan] IPV6CP: LayerDown
    Aug 3 19:40:23 ppp: [wan] IPV6CP: SendTerminateReq #2
    Aug 3 19:40:23 ppp: [wan] IPV6CP: state change Opened --> Closing
    Aug 3 19:40:23 ppp: [wan] IPV6CP: Close event
    Aug 3 19:40:23 ppp: [wan] IFACE: Removing IPv4 address from pppoe0 failed(IGNORING for now. This should be only for PPPoE friendly!): Can't assign requested address
    Aug 3 19:40:23 ppp: [wan] IPCP: LayerDown
    Aug 3 19:40:23 ppp: [wan] IPCP: SendTerminateReq #4
    Aug 3 19:40:23 ppp: [wan] IPCP: state change Opened --> Closing
    Aug 3 19:40:23 ppp: [wan] IPCP: Close event
    Aug 3 19:40:23 ppp: [wan] IFACE: Close event
    Aug 3 19:40:23 ppp: caught fatal signal TERM
    Aug 3 19:40:23 ppp: waiting for process 88961 to die...
    Aug 3 19:40:23 ppp: process 10649 started, version 5.8 (root@pfSense_v2_4_4_amd64-pfSense_v2_4_4-job-09 11:56 16-Nov-2018)
    Aug 3 19:40:23 ppp:
    Aug 3 19:40:23 ppp: Multi-link PPP daemon for FreeBSD

    Thanks guys


  • Netgate Administrator

    There doesn't appear to be anything wrong shown in those logs. If the daemon should not have been restarted at that point I would check the system logs for that point to see what was happening that may have restarted it.

    Steve


Log in to reply