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

    PPPoE random disconnections

    Scheduled Pinned Locked Moved General pfSense Questions
    4 Posts 2 Posters 247 Views 2 Watching
    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.
    • J Offline
      jpns
      last edited by jpns

      I have been running a basic installation, the hardware is a mini-PC from Aliexpress with Celeron N3160, 4GB RAM, 32GB SSD, dual Realtek GbE interfaces. This was running for over a year with no problems but recently I have started having problems with random PPPoE disconnections.

      It was on 2.4.5 and the PPPoE would randomly disconnect, sometimes it would reconnect by itself but sometimes it wouldn't and the only way to solve was power cycle the firewall. I then started getting problems with unbound frequently crashing for no reason that I could see (unfortunately I didn't keep any logs). I don't believe this is an ISP or CPE problem as the ISP was changed during this period of problems and the CPE was also swapped and neither solved the issue. It got so bad that I had to remove the firewall and I temporarily replaced it with a spare unit I had, unfortunately this is an old hardware which is only running 2.3.5 (it is 32bit CPU) but it worked perfectly for 3 weeks with not a single disconnection whatsoever.

      In the mean time I wiped the SSD in the faulty firewall, did a very long memtest (which passed 0 errors), and did a completely fresh install of 2.4.5p1 and set up from scratch. I put this hardware back in two days ago and already it has having random disconnections although unbound is not crashing (for now). Here is a snippet of the PPP logs:

      Feb 6 23:57:02	ppp		[wan] 82.71.56.131 -> 51.148.72.21
Feb 6 23:57:02	ppp		[wan] IPCP: LayerUp
Feb 6 23:57:02	ppp		[wan] IPCP: state change Ack-Sent --> Opened
Feb 6 23:57:02	ppp		[wan] IPADDR 82.71.56.131
Feb 6 23:57:02	ppp		[wan] IPCP: rec'd Configure Ack #15 (Ack-Sent)
Feb 6 23:57:02	ppp		[wan] IPADDR 82.71.56.131
Feb 6 23:57:02	ppp		[wan] IPCP: SendConfigReq #15
Feb 6 23:57:02	ppp		[wan] 82.71.56.131 is OK
Feb 6 23:57:02	ppp		[wan] IPADDR 82.71.56.131
Feb 6 23:57:02	ppp		[wan] IPCP: rec'd Configure Nak #14 (Ack-Sent)
Feb 6 23:57:02	ppp		[wan] IFACE: Rename interface ng0 to pppoe0
Feb 6 23:57:02	ppp		[wan] IFACE: Up event
Feb 6 23:57:00	ppp		[wan] 0000:0000:0000:0001 -> 4afd:8eff:feaa:a4c7
Feb 6 23:57:00	ppp		[wan] IPV6CP: LayerUp
Feb 6 23:57:00	ppp		[wan] IPV6CP: state change Ack-Sent --> Opened
Feb 6 23:57:00	ppp		[wan] IPV6CP: rec'd Configure Ack #9 (Ack-Sent)
Feb 6 23:57:00	ppp		[wan] IPADDR 0.0.0.0
Feb 6 23:57:00	ppp		[wan] IPCP: SendConfigReq #14
Feb 6 23:57:00	ppp		[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Feb 6 23:57:00	ppp		[wan] IPCP: rec'd Configure Reject #13 (Ack-Sent)
Feb 6 23:57:00	ppp		[wan] IPV6CP: state change Req-Sent --> Ack-Sent
Feb 6 23:57:00	ppp		[wan] IPV6CP: SendConfigAck #1
Feb 6 23:57:00	ppp		[wan] IPV6CP: rec'd Configure Request #1 (Req-Sent)
Feb 6 23:57:00	ppp		[wan] IPCP: state change Req-Sent --> Ack-Sent
Feb 6 23:57:00	ppp		[wan] IPADDR 51.148.72.21
Feb 6 23:57:00	ppp		[wan] IPCP: SendConfigAck #1
Feb 6 23:57:00	ppp		[wan] 51.148.72.21 is OK
Feb 6 23:57:00	ppp		[wan] IPADDR 51.148.72.21
Feb 6 23:57:00	ppp		[wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Feb 6 23:57:00	ppp		[wan] IPV6CP: SendConfigReq #9
Feb 6 23:57:00	ppp		[wan] IPV6CP: state change Starting --> Req-Sent
Feb 6 23:57:00	ppp		[wan] IPV6CP: Up event
Feb 6 23:57:00	ppp		[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Feb 6 23:57:00	ppp		[wan] IPADDR 0.0.0.0
Feb 6 23:57:00	ppp		[wan] IPCP: SendConfigReq #13
Feb 6 23:57:00	ppp		[wan] IPCP: state change Starting --> Req-Sent
Feb 6 23:57:00	ppp		[wan] IPCP: Up event
Feb 6 23:57:00	ppp		[wan] IPV6CP: LayerStart
Feb 6 23:57:00	ppp		[wan] IPV6CP: state change Initial --> Starting
Feb 6 23:57:00	ppp		[wan] IPV6CP: Open event
Feb 6 23:57:00	ppp		[wan] IPCP: LayerStart
Feb 6 23:57:00	ppp		[wan] IPCP: state change Initial --> Starting
Feb 6 23:57:00	ppp		[wan] IPCP: Open event
Feb 6 23:57:00	ppp		[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Feb 6 23:57:00	ppp		[wan_link0] Link: Join bundle "wan"
Feb 6 23:57:00	ppp		[wan_link0] Link: Matched action 'bundle "wan" ""'
Feb 6 23:57:00	ppp		[wan_link0] LCP: authorization successful
Feb 6 23:57:00	ppp		[wan_link0] MESG: Authentication success,Welcome!
Feb 6 23:57:00	ppp		[wan_link0] CHAP: rec'd SUCCESS #1 len: 35
Feb 6 23:57:00	ppp		[wan_link0] CHAP: sending RESPONSE #1 len: 34
Feb 6 23:57:00	ppp		[wan_link0] CHAP: Using authname "zen404131@zen"
Feb 6 23:57:00	ppp		[wan_link0] Name: "cor1.lond1"
Feb 6 23:57:00	ppp		[wan_link0] CHAP: rec'd CHALLENGE #1 len: 31
Feb 6 23:57:00	ppp		[wan_link0] LCP: LayerUp
Feb 6 23:57:00	ppp		[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Feb 6 23:57:00	ppp		[wan_link0] LCP: state change Ack-Sent --> Opened
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x135e7a67
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] LCP: rec'd Configure Ack #15 (Ack-Sent)
Feb 6 23:57:00	ppp		[wan_link0] LCP: state change Opened --> Ack-Sent
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0xf7448ee3
Feb 6 23:57:00	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:57:00	ppp		[wan_link0] MRU 1500
Feb 6 23:57:00	ppp		[wan_link0] LCP: SendConfigAck #1
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x135e7a67
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] LCP: SendConfigReq #15
Feb 6 23:57:00	ppp		[wan_link0] LCP: LayerDown
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0xf7448ee3
Feb 6 23:57:00	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:57:00	ppp		[wan_link0] MRU 1500
Feb 6 23:57:00	ppp		[wan_link0] LCP: rec'd Configure Request #1 (Opened)
Feb 6 23:57:00	ppp		[wan_link0] CHAP: sending RESPONSE #1 len: 34
Feb 6 23:57:00	ppp		[wan_link0] CHAP: Using authname "zen404131@zen"
Feb 6 23:57:00	ppp		[wan_link0] Name: "acc-aln1.cip"
Feb 6 23:57:00	ppp		[wan_link0] CHAP: rec'd CHALLENGE #1 len: 56
Feb 6 23:57:00	ppp		[wan_link0] LCP: LayerUp
Feb 6 23:57:00	ppp		[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Feb 6 23:57:00	ppp		[wan_link0] LCP: state change Ack-Sent --> Opened
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x135e7a67
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] LCP: rec'd Configure Ack #14 (Ack-Sent)
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x135e7a67
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] LCP: SendConfigReq #14
Feb 6 23:57:00	ppp		[wan_link0] PROTOCOMP
Feb 6 23:57:00	ppp		[wan_link0] LCP: rec'd Configure Reject #13 (Ack-Sent)
Feb 6 23:57:00	ppp		[wan_link0] LCP: state change Req-Sent --> Ack-Sent
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x5afd59f0
Feb 6 23:57:00	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] LCP: SendConfigAck #3
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x5afd59f0
Feb 6 23:57:00	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] LCP: rec'd Configure Request #3 (Req-Sent)
Feb 6 23:57:00	ppp		[wan_link0] MAGICNUM 0x135e7a67
Feb 6 23:57:00	ppp		[wan_link0] MRU 1492
Feb 6 23:57:00	ppp		[wan_link0] PROTOCOMP
Feb 6 23:57:00	ppp		[wan_link0] LCP: SendConfigReq #13
Feb 6 23:57:00	ppp		[wan_link0] LCP: state change Starting --> Req-Sent
Feb 6 23:57:00	ppp		[wan_link0] LCP: Up event
Feb 6 23:57:00	ppp		[wan_link0] Link: UP event
Feb 6 23:57:00	ppp		[wan_link0] PPPoE: connection successful
Feb 6 23:57:00	ppp		PPPoE: rec'd ACNAME "acc-aln1.cip"
Feb 6 23:57:00	ppp		[wan_link0] PPPoE: Connecting to ''
Feb 6 23:57:00	ppp		[wan_link0] Link: reconnection attempt 1
Feb 6 23:56:59	ppp		[wan_link0] Link: reconnection attempt 1 in 1 seconds
Feb 6 23:56:59	ppp		[wan_link0] LCP: state change Stopping --> Starting
Feb 6 23:56:59	ppp		[wan_link0] LCP: Down event
Feb 6 23:56:59	ppp		[wan_link0] Link: DOWN event
Feb 6 23:56:59	ppp		[wan_link0] PPPoE: connection closed
Feb 6 23:56:59	ppp		[wan_link0] LCP: LayerDown
Feb 6 23:56:59	ppp		[wan_link0] LCP: SendTerminateAck #12
Feb 6 23:56:59	ppp		[wan] IPV6CP: state change Closing --> Initial
Feb 6 23:56:59	ppp		[wan] Bundle: No NCPs left. Closing links...
Feb 6 23:56:59	ppp		[wan] IPV6CP: LayerFinish
Feb 6 23:56:59	ppp		[wan] IPV6CP: Down event
Feb 6 23:56:59	ppp		[wan] IPCP: state change Closing --> Initial
Feb 6 23:56:59	ppp		[wan] IPCP: LayerFinish
Feb 6 23:56:59	ppp		[wan] IPCP: Down event
Feb 6 23:56:59	ppp		[wan] IFACE: Rename interface pppoe0 to pppoe0
Feb 6 23:56:59	ppp		[wan] IFACE: Down event
Feb 6 23:56:58	ppp		[wan] IPV6CP: LayerDown
Feb 6 23:56:58	ppp		[wan] IPV6CP: SendTerminateReq #8
Feb 6 23:56:58	ppp		[wan] IPV6CP: state change Opened --> Closing
Feb 6 23:56:58	ppp		[wan] IPV6CP: Close event
Feb 6 23:56:57	ppp		[wan] IPCP: LayerDown
Feb 6 23:56:57	ppp		[wan] IPCP: SendTerminateReq #12
Feb 6 23:56:57	ppp		[wan] IPCP: state change Opened --> Closing
Feb 6 23:56:57	ppp		[wan] IPCP: Close event
Feb 6 23:56:57	ppp		[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Feb 6 23:56:57	ppp		[wan_link0] Link: Leave bundle "wan"
Feb 6 23:56:57	ppp		[wan_link0] LCP: state change Opened --> Stopping
Feb 6 23:56:57	ppp		[wan_link0] LCP: rec'd Terminate Request #2 (Opened)
Feb 6 23:45:50	ppp		[wan] 82.71.56.131 -> 51.148.72.22
Feb 6 23:45:50	ppp		[wan] IPCP: LayerUp
Feb 6 23:45:50	ppp		[wan] IPCP: state change Ack-Sent --> Opened
Feb 6 23:45:50	ppp		[wan] IPADDR 82.71.56.131
Feb 6 23:45:50	ppp		[wan] IPCP: rec'd Configure Ack #11 (Ack-Sent)
Feb 6 23:45:50	ppp		[wan] IPADDR 82.71.56.131
Feb 6 23:45:50	ppp		[wan] IPCP: SendConfigReq #11
Feb 6 23:45:50	ppp		[wan] 82.71.56.131 is OK
Feb 6 23:45:50	ppp		[wan] IPADDR 82.71.56.131
Feb 6 23:45:50	ppp		[wan] IPCP: rec'd Configure Nak #10 (Ack-Sent)
Feb 6 23:45:50	ppp		[wan] IFACE: Rename interface ng0 to pppoe0
Feb 6 23:45:50	ppp		[wan] IFACE: Up event
Feb 6 23:45:47	ppp		[wan] 0000:0000:0000:0001 -> 4afd:8eff:feaa:a4dd
Feb 6 23:45:47	ppp		[wan] IPV6CP: LayerUp
Feb 6 23:45:47	ppp		[wan] IPV6CP: state change Ack-Sent --> Opened
Feb 6 23:45:47	ppp		[wan] IPV6CP: rec'd Configure Ack #7 (Ack-Sent)
Feb 6 23:45:47	ppp		[wan] IPADDR 0.0.0.0
Feb 6 23:45:47	ppp		[wan] IPCP: SendConfigReq #10
Feb 6 23:45:47	ppp		[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Feb 6 23:45:47	ppp		[wan] IPCP: rec'd Configure Reject #9 (Ack-Sent)
Feb 6 23:45:47	ppp		[wan] IPV6CP: state change Req-Sent --> Ack-Sent
Feb 6 23:45:47	ppp		[wan] IPV6CP: SendConfigAck #1
Feb 6 23:45:47	ppp		[wan] IPV6CP: rec'd Configure Request #1 (Req-Sent)
Feb 6 23:45:47	ppp		[wan] IPCP: state change Req-Sent --> Ack-Sent
Feb 6 23:45:47	ppp		[wan] IPADDR 51.148.72.22
Feb 6 23:45:47	ppp		[wan] IPCP: SendConfigAck #1
Feb 6 23:45:47	ppp		[wan] 51.148.72.22 is OK
Feb 6 23:45:47	ppp		[wan] IPADDR 51.148.72.22
Feb 6 23:45:47	ppp		[wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Feb 6 23:45:47	ppp		[wan] IPV6CP: SendConfigReq #7
Feb 6 23:45:47	ppp		[wan] IPV6CP: state change Starting --> Req-Sent
Feb 6 23:45:47	ppp		[wan] IPV6CP: Up event
Feb 6 23:45:47	ppp		[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Feb 6 23:45:47	ppp		[wan] IPADDR 0.0.0.0
Feb 6 23:45:47	ppp		[wan] IPCP: SendConfigReq #9
Feb 6 23:45:47	ppp		[wan] IPCP: state change Starting --> Req-Sent
Feb 6 23:45:47	ppp		[wan] IPCP: Up event
Feb 6 23:45:47	ppp		[wan] IPV6CP: LayerStart
Feb 6 23:45:47	ppp		[wan] IPV6CP: state change Initial --> Starting
Feb 6 23:45:47	ppp		[wan] IPV6CP: Open event
Feb 6 23:45:47	ppp		[wan] IPCP: LayerStart
Feb 6 23:45:47	ppp		[wan] IPCP: state change Initial --> Starting
Feb 6 23:45:47	ppp		[wan] IPCP: Open event
Feb 6 23:45:47	ppp		[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Feb 6 23:45:47	ppp		[wan_link0] Link: Join bundle "wan"
Feb 6 23:45:47	ppp		[wan_link0] Link: Matched action 'bundle "wan" ""'
Feb 6 23:45:47	ppp		[wan_link0] LCP: authorization successful
Feb 6 23:45:47	ppp		[wan_link0] MESG: Authentication success,Welcome!
Feb 6 23:45:47	ppp		[wan_link0] CHAP: rec'd SUCCESS #1 len: 35
Feb 6 23:45:47	ppp		[wan_link0] CHAP: sending RESPONSE #1 len: 34
Feb 6 23:45:47	ppp		[wan_link0] CHAP: Using authname "zen404131@zen"
Feb 6 23:45:47	ppp		[wan_link0] Name: "cor2.lond1"
Feb 6 23:45:47	ppp		[wan_link0] CHAP: rec'd CHALLENGE #1 len: 31
Feb 6 23:45:47	ppp		[wan_link0] LCP: LayerUp
Feb 6 23:45:47	ppp		[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Feb 6 23:45:47	ppp		[wan_link0] LCP: state change Ack-Sent --> Opened
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x0d199323
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] LCP: rec'd Configure Ack #11 (Ack-Sent)
Feb 6 23:45:47	ppp		[wan_link0] LCP: state change Opened --> Ack-Sent
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x9d2cae32
Feb 6 23:45:47	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:45:47	ppp		[wan_link0] MRU 1500
Feb 6 23:45:47	ppp		[wan_link0] LCP: SendConfigAck #1
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x0d199323
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] LCP: SendConfigReq #11
Feb 6 23:45:47	ppp		[wan_link0] LCP: LayerDown
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x9d2cae32
Feb 6 23:45:47	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:45:47	ppp		[wan_link0] MRU 1500
Feb 6 23:45:47	ppp		[wan_link0] LCP: rec'd Configure Request #1 (Opened)
Feb 6 23:45:47	ppp		[wan_link0] CHAP: sending RESPONSE #1 len: 34
Feb 6 23:45:47	ppp		[wan_link0] CHAP: Using authname "zen404131@zen"
Feb 6 23:45:47	ppp		[wan_link0] Name: "acc-aln1.cip"
Feb 6 23:45:47	ppp		[wan_link0] CHAP: rec'd CHALLENGE #1 len: 53
Feb 6 23:45:47	ppp		[wan_link0] LCP: LayerUp
Feb 6 23:45:47	ppp		[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Feb 6 23:45:47	ppp		[wan_link0] LCP: state change Ack-Sent --> Opened
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x0d199323
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] LCP: rec'd Configure Ack #10 (Ack-Sent)
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x0d199323
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] LCP: SendConfigReq #10
Feb 6 23:45:47	ppp		[wan_link0] PROTOCOMP
Feb 6 23:45:47	ppp		[wan_link0] LCP: rec'd Configure Reject #9 (Ack-Sent)
Feb 6 23:45:47	ppp		[wan_link0] LCP: state change Req-Sent --> Ack-Sent
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x58f216bf
Feb 6 23:45:47	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] LCP: SendConfigAck #9
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x58f216bf
Feb 6 23:45:47	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] LCP: rec'd Configure Request #9 (Req-Sent)
Feb 6 23:45:47	ppp		[wan_link0] MAGICNUM 0x0d199323
Feb 6 23:45:47	ppp		[wan_link0] MRU 1492
Feb 6 23:45:47	ppp		[wan_link0] PROTOCOMP
Feb 6 23:45:47	ppp		[wan_link0] LCP: SendConfigReq #9
Feb 6 23:45:47	ppp		[wan_link0] LCP: state change Starting --> Req-Sent
Feb 6 23:45:47	ppp		[wan_link0] LCP: Up event
Feb 6 23:45:47	ppp		[wan_link0] Link: UP event
Feb 6 23:45:47	ppp		[wan_link0] PPPoE: connection successful
Feb 6 23:45:47	ppp		PPPoE: rec'd ACNAME "acc-aln1.cip"
Feb 6 23:45:47	ppp		[wan_link0] PPPoE: Connecting to ''
Feb 6 23:45:47	ppp		[wan_link0] Link: reconnection attempt 1
Feb 6 23:45:44	ppp		[wan_link0] Link: reconnection attempt 1 in 3 seconds
Feb 6 23:45:44	ppp		[wan_link0] LCP: state change Stopping --> Starting
Feb 6 23:45:44	ppp		[wan_link0] LCP: Down event
Feb 6 23:45:44	ppp		[wan_link0] Link: DOWN event
Feb 6 23:45:44	ppp		[wan_link0] PPPoE: connection closed
Feb 6 23:45:44	ppp		[wan_link0] LCP: LayerDown
Feb 6 23:45:44	ppp		[wan_link0] LCP: SendTerminateAck #8
Feb 6 23:45:44	ppp		[wan] IPV6CP: state change Closing --> Initial
Feb 6 23:45:44	ppp		[wan] Bundle: No NCPs left. Closing links...
Feb 6 23:45:44	ppp		[wan] IPV6CP: LayerFinish
Feb 6 23:45:44	ppp		[wan] IPV6CP: Down event
Feb 6 23:45:44	ppp		[wan] IPCP: state change Closing --> Initial
Feb 6 23:45:44	ppp		[wan] IPCP: LayerFinish
Feb 6 23:45:44	ppp		[wan] IPCP: Down event
Feb 6 23:45:44	ppp		[wan] IFACE: Rename interface pppoe0 to pppoe0
Feb 6 23:45:44	ppp		[wan] IFACE: Down event
Feb 6 23:45:43	ppp		[wan] IPV6CP: LayerDown
Feb 6 23:45:43	ppp		[wan] IPV6CP: SendTerminateReq #6
Feb 6 23:45:43	ppp		[wan] IPV6CP: state change Opened --> Closing
Feb 6 23:45:43	ppp		[wan] IPV6CP: Close event
Feb 6 23:45:43	ppp		[wan] IPCP: LayerDown
Feb 6 23:45:43	ppp		[wan] IPCP: SendTerminateReq #8
Feb 6 23:45:43	ppp		[wan] IPCP: state change Opened --> Closing
Feb 6 23:45:43	ppp		[wan] IPCP: Close event
Feb 6 23:45:43	ppp		[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Feb 6 23:45:43	ppp		[wan_link0] Link: Leave bundle "wan"
Feb 6 23:45:43	ppp		[wan_link0] LCP: state change Opened --> Stopping
Feb 6 23:45:43	ppp		[wan_link0] LCP: rec'd Terminate Request #2 (Opened)
Feb 6 22:08:05	ppp		[wan] 0000:0000:0000:0001 -> 4afd:8eff:feaa:a4dd
Feb 6 22:08:05	ppp		[wan] IPV6CP: LayerUp
Feb 6 22:08:05	ppp		[wan] IPV6CP: state change Ack-Rcvd --> Opened
Feb 6 22:08:05	ppp		[wan] IPV6CP: SendConfigAck #2
Feb 6 22:08:05	ppp		[wan] IPV6CP: rec'd Configure Request #2 (Ack-Rcvd)
Feb 6 22:08:04	ppp		[wan] IPV6CP: state change Req-Sent --> Ack-Rcvd
Feb 6 22:08:04	ppp		[wan] IPV6CP: rec'd Configure Ack #5 (Req-Sent)
Feb 6 22:08:04	ppp		[wan] IPV6CP: SendConfigReq #5
Feb 6 22:08:04	ppp		[wan] IPV6CP: state change Ack-Rcvd --> Req-Sent
Feb 6 22:08:02	ppp		[wan] IFACE: Rename interface ng0 to pppoe0
Feb 6 22:08:02	ppp		[wan] IFACE: Up event
Feb 6 22:08:02	ppp		[wan] 82.71.56.131 -> 51.148.72.22
Feb 6 22:08:02	ppp		[wan] IPCP: LayerUp
Feb 6 22:08:02	ppp		[wan] IPCP: state change Ack-Sent --> Opened
Feb 6 22:08:02	ppp		[wan] IPADDR 82.71.56.131
Feb 6 22:08:02	ppp		[wan] IPCP: rec'd Configure Ack #7 (Ack-Sent)
Feb 6 22:08:02	ppp		[wan] IPADDR 82.71.56.131
Feb 6 22:08:02	ppp		[wan] IPCP: SendConfigReq #7
Feb 6 22:08:02	ppp		[wan] 82.71.56.131 is OK
Feb 6 22:08:02	ppp		[wan] IPADDR 82.71.56.131
Feb 6 22:08:02	ppp		[wan] IPCP: rec'd Configure Nak #6 (Ack-Sent)
Feb 6 22:08:02	ppp		[wan] IPV6CP: state change Req-Sent --> Ack-Rcvd
Feb 6 22:08:02	ppp		[wan] IPV6CP: rec'd Configure Ack #4 (Req-Sent)
Feb 6 22:08:02	ppp		[wan] IPADDR 0.0.0.0
Feb 6 22:08:02	ppp		[wan] IPCP: SendConfigReq #6
Feb 6 22:08:02	ppp		[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Feb 6 22:08:02	ppp		[wan] IPCP: rec'd Configure Reject #5 (Ack-Sent)
Feb 6 22:08:02	ppp		[wan] IPCP: state change Req-Sent --> Ack-Sent
Feb 6 22:08:02	ppp		[wan] IPADDR 51.148.72.22
Feb 6 22:08:02	ppp		[wan] IPCP: SendConfigAck #1
Feb 6 22:08:02	ppp		[wan] 51.148.72.22 is OK
Feb 6 22:08:02	ppp		[wan] IPADDR 51.148.72.22
Feb 6 22:08:02	ppp		[wan] IPCP: rec'd Configure Request #1 (Req-Sent)
Feb 6 22:08:02	ppp		[wan] IPV6CP: SendConfigReq #4
Feb 6 22:08:02	ppp		[wan] IPV6CP: state change Starting --> Req-Sent
Feb 6 22:08:02	ppp		[wan] IPV6CP: Up event
Feb 6 22:08:02	ppp		[wan] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Feb 6 22:08:02	ppp		[wan] IPADDR 0.0.0.0
Feb 6 22:08:02	ppp		[wan] IPCP: SendConfigReq #5
Feb 6 22:08:02	ppp		[wan] IPCP: state change Starting --> Req-Sent
Feb 6 22:08:02	ppp		[wan] IPCP: Up event
Feb 6 22:08:02	ppp		[wan] IPV6CP: LayerStart
Feb 6 22:08:02	ppp		[wan] IPV6CP: state change Initial --> Starting
Feb 6 22:08:02	ppp		[wan] IPV6CP: Open event
Feb 6 22:08:02	ppp		[wan] IPCP: LayerStart
Feb 6 22:08:02	ppp		[wan] IPCP: state change Initial --> Starting
Feb 6 22:08:02	ppp		[wan] IPCP: Open event
Feb 6 22:08:02	ppp		[wan] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Feb 6 22:08:02	ppp		[wan_link0] Link: Join bundle "wan"
Feb 6 22:08:02	ppp		[wan_link0] Link: Matched action 'bundle "wan" ""'
Feb 6 22:08:02	ppp		[wan_link0] LCP: authorization successful
Feb 6 22:08:02	ppp		[wan_link0] MESG: Authentication success,Welcome!
Feb 6 22:08:02	ppp		[wan_link0] CHAP: rec'd SUCCESS #1 len: 35
Feb 6 22:08:02	ppp		[wan_link0] CHAP: sending RESPONSE #1 len: 34
Feb 6 22:08:02	ppp		[wan_link0] CHAP: Using authname "zen404131@zen"
Feb 6 22:08:02	ppp		[wan_link0] Name: "cor2.lond1"
Feb 6 22:08:02	ppp		[wan_link0] CHAP: rec'd CHALLENGE #1 len: 31
Feb 6 22:08:02	ppp		[wan_link0] LCP: LayerUp
Feb 6 22:08:02	ppp		[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Feb 6 22:08:02	ppp		[wan_link0] LCP: state change Ack-Sent --> Opened
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x009ac52b
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] LCP: rec'd Configure Ack #7 (Ack-Sent)
Feb 6 22:08:02	ppp		[wan_link0] LCP: state change Opened --> Ack-Sent
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0xf912cd2c
Feb 6 22:08:02	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 22:08:02	ppp		[wan_link0] MRU 1500
Feb 6 22:08:02	ppp		[wan_link0] LCP: SendConfigAck #1
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x009ac52b
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] LCP: SendConfigReq #7
Feb 6 22:08:02	ppp		[wan_link0] LCP: LayerDown
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0xf912cd2c
Feb 6 22:08:02	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 22:08:02	ppp		[wan_link0] MRU 1500
Feb 6 22:08:02	ppp		[wan_link0] LCP: rec'd Configure Request #1 (Opened)
Feb 6 22:08:02	ppp		[wan_link0] CHAP: sending RESPONSE #1 len: 34
Feb 6 22:08:02	ppp		[wan_link0] CHAP: Using authname "zen404131@zen"
Feb 6 22:08:02	ppp		[wan_link0] Name: "acc-aln1.cip"
Feb 6 22:08:02	ppp		[wan_link0] CHAP: rec'd CHALLENGE #1 len: 54
Feb 6 22:08:02	ppp		[wan_link0] LCP: LayerUp
Feb 6 22:08:02	ppp		[wan_link0] LCP: auth: peer wants CHAP, I want nothing
Feb 6 22:08:02	ppp		[wan_link0] LCP: state change Ack-Sent --> Opened
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x009ac52b
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] LCP: rec'd Configure Ack #6 (Ack-Sent)
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x009ac52b
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] LCP: SendConfigReq #6
Feb 6 22:08:02	ppp		[wan_link0] PROTOCOMP
Feb 6 22:08:02	ppp		[wan_link0] LCP: rec'd Configure Reject #5 (Ack-Sent)
Feb 6 22:08:02	ppp		[wan_link0] LCP: state change Req-Sent --> Ack-Sent
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x16d07f02
Feb 6 22:08:02	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] LCP: SendConfigAck #48
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x16d07f02
Feb 6 22:08:02	ppp		[wan_link0] AUTHPROTO CHAP MD5
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] LCP: rec'd Configure Request #48 (Req-Sent)
Feb 6 22:08:02	ppp		[wan_link0] MAGICNUM 0x009ac52b
Feb 6 22:08:02	ppp		[wan_link0] MRU 1492
Feb 6 22:08:02	ppp		[wan_link0] PROTOCOMP
Feb 6 22:08:02	ppp		[wan_link0] LCP: SendConfigReq #5
Feb 6 22:08:02	ppp		[wan_link0] LCP: state change Starting --> Req-Sent
Feb 6 22:08:02	ppp		[wan_link0] LCP: Up event
Feb 6 22:08:02	ppp		[wan_link0] Link: UP event
Feb 6 22:08:02	ppp		[wan_link0] PPPoE: connection successful
Feb 6 22:08:02	ppp		PPPoE: rec'd ACNAME "acc-aln1.cip"
Feb 6 22:08:02	ppp		[wan_link0] PPPoE: Connecting to ''
Feb 6 22:08:02	ppp		[wan_link0] Link: reconnection attempt 1
Feb 6 22:07:58	ppp		[wan_link0] Link: reconnection attempt 1 in 4 seconds
Feb 6 22:07:58	ppp		[wan_link0] LCP: state change Stopping --> Starting
Feb 6 22:07:58	ppp		[wan_link0] LCP: Down event
Feb 6 22:07:58	ppp		[wan_link0] Link: DOWN event
Feb 6 22:07:58	ppp		[wan_link0] PPPoE: connection closed
Feb 6 22:07:58	ppp		[wan_link0] LCP: LayerDown
Feb 6 22:07:58	ppp		[wan_link0] LCP: SendTerminateAck #4
Feb 6 22:07:58	ppp		[wan] IPV6CP: state change Closing --> Initial
Feb 6 22:07:58	ppp		[wan] Bundle: No NCPs left. Closing links...
Feb 6 22:07:58	ppp		[wan] IPV6CP: LayerFinish
Feb 6 22:07:58	ppp		[wan] IPV6CP: Down event
Feb 6 22:07:58	ppp		[wan] IPCP: state change Closing --> Initial
Feb 6 22:07:58	ppp		[wan] IPCP: LayerFinish
Feb 6 22:07:58	ppp		[wan] IPCP: Down event
Feb 6 22:07:58	ppp		[wan] IFACE: Rename interface pppoe0 to pppoe0
Feb 6 22:07:58	ppp		[wan] IFACE: Down event
Feb 6 22:07:57	ppp		[wan] IPV6CP: LayerDown
Feb 6 22:07:57	ppp		[wan] IPV6CP: SendTerminateReq #3
Feb 6 22:07:57	ppp		[wan] IPV6CP: state change Opened --> Closing
Feb 6 22:07:57	ppp		[wan] IPV6CP: Close event
Feb 6 22:07:56	ppp		[wan] IPCP: LayerDown
Feb 6 22:07:56	ppp		[wan] IPCP: SendTerminateReq #4
Feb 6 22:07:56	ppp		[wan] IPCP: state change Opened --> Closing
Feb 6 22:07:56	ppp		[wan] IPCP: Close event
Feb 6 22:07:56	ppp		[wan] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Feb 6 22:07:56	ppp		[wan_link0] Link: Leave bundle "wan"
Feb 6 22:07:56	ppp		[wan_link0] LCP: state change Opened --> Stopping
Feb 6 22:07:56	ppp		[wan_link0] LCP: rec'd Terminate Request #2 (Opened)
      GertjanG 1 Reply Last reply Reply Quote 0
      • GertjanG Offline
        Gertjan @jpns
        last edited by

        You (from upstream) receive these :
        @jpns said in PPPoE random disconnections:

        [wan_link0] LCP: rec'd Terminate Request #2 (Opened)

        so pfSense pppoe reopens ....

        These are not set :

        4c5e3ee0-0883-4752-a7c6-dc4e478ec50f-image.png

        ?

        No "help me" PM's please. Use the forum, the community will thank you.
        Edit : and where are the logs ??

        J 1 Reply Last reply Reply Quote 0
        • J Offline
          jpns @Gertjan
          last edited by

          @gertjan No they are not set. They were not set on the temporary appliance either, and that never experienced a single disconnect.

          GertjanG 1 Reply Last reply Reply Quote 0
          • GertjanG Offline
            Gertjan @jpns
            last edited by Gertjan

            @jpns :

            Throw this one ine Google : LCP: rec'd Terminate Request
            The first one says : https://forum.netgate.com/topic/30028/pppoe-connection-terminated-every-10-minutes

            No "help me" PM's please. Use the forum, the community will thank you.
            Edit : and where are the logs ??

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