PPPoE random disconnections
-
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)
-
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 :
?
-
@gertjan No they are not set. They were not set on the temporary appliance either, and that never experienced a single disconnect.
-
@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