PPPoE Drops after a while

Hi. I using the PPPoE connection on DSL Line. In the past few days, when I downloading or streaming on the net, suddenly my connection drops. everytime I login to the OpenWRT to see whats happen, and I see my WAN interface lose the connection and says failed to connect.
Fortunately I enable debug mode for wan interface by 'option pppd_options 'debug'.

Lets see what happens when the connection drops:

Sat Mar 21 21:31:54 2020 daemon.info pppd[2357]: No response to 5 echo-requests
Sat Mar 21 21:31:54 2020 daemon.notice pppd[2357]: Serial link appears to be disconnected.
Sat Mar 21 21:31:54 2020 daemon.info pppd[2357]: Connect time 27.1 minutes.
Sat Mar 21 21:31:54 2020 daemon.info pppd[2357]: Sent 7969278 bytes, received 199019725 bytes.
Sat Mar 21 21:31:54 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sat Mar 21 21:31:54 2020 daemon.debug pppd[2357]: Script /lib/netifd/ppp-down started (pid 2856)
Sat Mar 21 21:31:54 2020 daemon.debug pppd[2357]: sent [LCP TermReq id=0x2 "Peer not responding"]

Sat Mar 21 21:31:57 2020 daemon.debug pppd[2357]: sent [LCP TermReq id=0x3 "Peer not responding"]
Sat Mar 21 21:32:00 2020 daemon.notice pppd[2357]: Connection terminated.
Sat Mar 21 21:32:00 2020 daemon.debug pppd[2357]: Send PPPOE Discovery V1T1 PADT session 0x27e7 length 0
Sat Mar 21 21:32:00 2020 daemon.debug pppd[2357]:  dst **:**:**:**:**:**  src **:**:**:**:**:**
Sat Mar 21 21:32:00 2020 daemon.debug pppd[2357]:
Sat Mar 21 21:32:00 2020 daemon.info pppd[2357]: Sent PADT
Sat Mar 21 21:32:00 2020 daemon.notice pppd[2357]: Modem hangup
Sat Mar 21 21:32:00 2020 daemon.info pppd[2357]: Exit.

and after that, attempting to establishing new connection Fails, here is the message and it retrying until I reboot the Modem.

Sat Mar 21 21:32:00 2020 daemon.info pppd[3052]: Plugin rp-pppoe.so loaded.
Sat Mar 21 21:32:00 2020 daemon.info pppd[3052]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Sat Mar 21 21:32:00 2020 daemon.notice pppd[3052]: pppd 2.4.7 started by root, uid 0
Sat Mar 21 21:32:00 2020 daemon.debug pppd[3052]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Sat Mar 21 21:32:00 2020 daemon.debug pppd[3052]:  dst ff:ff:ff:ff:ff:ff  src **:**:**:**:**:**
Sat Mar 21 21:32:00 2020 daemon.debug pppd[3052]:  [service-name]
Sat Mar 21 21:32:05 2020 daemon.debug pppd[3052]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Sat Mar 21 21:32:05 2020 daemon.debug pppd[3052]:  dst ff:ff:ff:ff:ff:ff  src **:**:**:**:**:**
Sat Mar 21 21:32:05 2020 daemon.debug pppd[3052]:  [service-name]
Sat Mar 21 21:32:10 2020 daemon.debug pppd[3052]: Send PPPOE Discovery V1T1 PADI session 0x0 length 4
Sat Mar 21 21:32:10 2020 daemon.debug pppd[3052]:  dst ff:ff:ff:ff:ff:ff  src **:**:**:**:**:**
Sat Mar 21 21:32:10 2020 daemon.debug pppd[3052]:  [service-name]
Sat Mar 21 21:32:15 2020 daemon.warn pppd[3052]: Timeout waiting for PADO packets
Sat Mar 21 21:32:15 2020 daemon.err pppd[3052]: Unable to complete PPPoE Discovery
Sat Mar 21 21:32:15 2020 daemon.info pppd[3052]: Exit.

to get out of this situation, I have to reboot the device.

I set the manually this code for the wan connection: option keepalive '50 5', and then I see drop message changed:

Sat Mar 21 21:54:02 2020 daemon.info pppd[2365]: Terminating on signal 15
Sat Mar 21 21:54:02 2020 daemon.info pppd[2365]: Connect time 10.2 minutes.
Sat Mar 21 21:54:02 2020 daemon.info pppd[2365]: Sent 25251251 bytes, received 1132512706 bytes.
Sat Mar 21 21:54:02 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Sat Mar 21 21:54:02 2020 daemon.debug pppd[2365]: Script /lib/netifd/ppp-down started (pid 7840)
Sat Mar 21 21:54:02 2020 daemon.debug pppd[2365]: sent [LCP TermReq id=0x2 "User request"]
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]: rcvd [LCP TermAck id=0x2]
Sat Mar 21 21:54:03 2020 daemon.notice pppd[2365]: Connection terminated.
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]: Send PPPOE Discovery V1T1 PADT session 0x8651 length 0
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]:  dst **:**:**:**:**:**  src **:**:**:**:**:**
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]:
Sat Mar 21 21:54:03 2020 daemon.info pppd[2365]: Sent PADT
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]: Waiting for 1 child processes...
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]:   script /lib/netifd/ppp-down, pid 7840
Sat Mar 21 21:54:03 2020 daemon.debug pppd[2365]: Script /lib/netifd/ppp-down finished (pid 7840), status = 0x1
Sat Mar 21 21:54:03 2020 daemon.info pppd[2365]: Exit.

So, I can't fix that by changing the keepalive parameter. what is the problem and what should I do?

1 Like

Which device is this? Are you using the builtin modem or an external?
Which OpenWrt version?

You were not using the keepalive option before, right?
Do you see control packets in tcpdump all the time? (change ethX with the physical interface of wan)
tcpdump -i ethX ether[0x0c:2] == 0x8863 or ether[0x0c:2] == 0x8864

2 Likes