Help me debugging my PPPoE connection

[fawzy46]

Hi, I connect to my isp/internet using PPPoE no VLAN, the problem is if the line/connection is disconnected for more than 3~5 minutes the connection won't go back up again. The log always shows this pattern below over and over again. what I noticed is all the LCP config requests do not have any authentication configuration for some reason I even tried to put false credentials to see if I would get to the auth stage but no, the same error pattern exactly.

currently, I have a TP-Link vdsl2 modem in bridge mode. the only thing to bring the connection up is to connect a Zte modem (isp provided) and then switch back to the tp-link. so I thought maybe the isp does not like my modem so I spoofed its MAC address with the tp-link one but still no luck at all. also tried two different Huawei modems (also isp provided) but they has the same exact issue as the tp-link modem

one last thing all 4 modems above work fine when they are not in bridge mode

Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: Send PPPOE Discovery V1T1 PADI session 0x0 length 12
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  dst ff:ff:ff:ff:ff:ff  src d8:47:32:a3:29:05
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  [service-name] [host-uniq  e1 4c 00 00]
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: Recv PPPOE Discovery V1T1 PADO session 0x0 length 54
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  dst d8:47:32:a3:29:05  src 98:03:9b:85:7b:de
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  [service-name] [AC-name MQATM2_A-R72A-C-EG] [host-uniq  e1 4c 00 00] [AC-cookie  3b e8 1a 5a 25 ac 53 ec ce c7 fb 47 1b 3a e9 ae]
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: Send PPPOE Discovery V1T1 PADR session 0x0 length 32
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  dst 98:03:9b:85:7b:de  src d8:47:32:a3:29:05
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  [service-name] [host-uniq  e1 4c 00 00] [AC-cookie  3b e8 1a 5a 25 ac 53 ec ce c7 fb 47 1b 3a e9 ae]
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: Recv PPPOE Discovery V1T1 PADS session 0x1 length 12
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  dst d8:47:32:a3:29:05  src 98:03:9b:85:7b:de
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]:  [service-name] [host-uniq  e1 4c 00 00]
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: PADS: Service-Name: ''
Fri May 20 09:26:00 2022 daemon.info pppd[19681]: PPP session is 1
Fri May 20 09:26:00 2022 daemon.warn pppd[19681]: Connected to 98:03:9b:85:7b:de via interface eth1
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: using channel 103
Fri May 20 09:26:00 2022 kern.info kernel: [ 3807.919577] pppoe-wan: renamed from ppp0
Fri May 20 09:26:00 2022 daemon.info pppd[19681]: Renamed interface ppp0 to pppoe-wan
Fri May 20 09:26:00 2022 daemon.info pppd[19681]: Using interface pppoe-wan
Fri May 20 09:26:00 2022 daemon.notice pppd[19681]: Connect: pppoe-wan <--> eth1
Fri May 20 09:26:00 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:03 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:03 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:03 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:06 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:06 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:06 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:09 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:09 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:09 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:12 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:12 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:12 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:15 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:15 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:15 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:18 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:18 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:18 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:21 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:21 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:21 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:24 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:24 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:24 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:27 2022 daemon.debug pppd[19681]: rcvd [LCP ConfReq id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:27 2022 daemon.debug pppd[19681]: sent [LCP ConfAck id=0xbe <mru 1492> <auth pap> <magic 0x2a3b4e7b>]
Fri May 20 09:26:27 2022 daemon.debug pppd[19681]: sent [LCP ConfReq id=0x1 <mru 1492> <magic 0x6dd4b6b2>]
Fri May 20 09:26:30 2022 daemon.warn pppd[19681]: LCP: timeout sending Config-Requests
Fri May 20 09:26:30 2022 daemon.notice pppd[19681]: Connection terminated.
Fri May 20 09:26:30 2022 daemon.debug pppd[19681]: Send PPPOE Discovery V1T1 PADT session 0x1 length 28
Fri May 20 09:26:30 2022 daemon.debug pppd[19681]:  dst 98:03:9b:85:7b:de  src d8:47:32:a3:29:05
Fri May 20 09:26:30 2022 daemon.debug pppd[19681]:  [host-uniq  e1 4c 00 00] [AC-cookie  3b e8 1a 5a 25 ac 53 ec ce c7 fb 47 1b 3a e9 ae]
Fri May 20 09:26:30 2022 daemon.info pppd[19681]: Sent PADT
Fri May 20 09:26:30 2022 daemon.notice pppd[19681]: Modem hangup
Fri May 20 09:26:30 2022 daemon.info pppd[19681]: Exit.
Fri May 20 09:26:30 2022 daemon.err insmod: module is already loaded - ppp_generic
Fri May 20 09:26:30 2022 daemon.err insmod: module is already loaded - pppox
Fri May 20 09:26:30 2022 daemon.err insmod: module is already loaded - pppoe
Fri May 20 09:26:30 2022 daemon.info pppd[19994]: Plugin rp-pppoe.so loaded.
Fri May 20 09:26:30 2022 daemon.info pppd[19994]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Fri May 20 09:26:31 2022 daemon.notice pppd[19994]: pppd 2.4.8 started by root, uid 0

[Bill]

Do you have a OpenWrt router connected to the bridged TP-Link Modem or Zte modem when you are doing these swap out?

If so, then clone mac goes onto the OpenWrt router.

WRT32X running 21.02.3 unable to grab WAN DHCP IP

Copy the WAN MAC of the Buffalo, and set it (MAC override) on the WRT32s WAN port. Internet should start working.

[fawzy46]

I do, and already tried it out no luck

[Bill]

@fawzy46

one last thing all 4 modems above work fine when they are not in bridge mode

@fawzy46

I do, and already tried it out no luck

Good clarification.. I wasn't sure. How long have you been suffering from this disconnection loop? Are you in the mood to call the ISP for support?

I'm out of ideas, so I passing this up the food chain. Thanks for your support on the forums.

[fawzy46]

my ISP is trash af. once I say bridge mode they have no idea what I am talking about + they refuse to transfer me to level 2 or technical support

I am suffering from this bloody issue for about a year and a half now maybe even more

[Bill]

Would a make you feel a little bit better?

[fawzy46]

@fawzy46

LCP: timeout sending Config-Requests

sure

[Bill]

By any chance are you seeing any DHCP renewal problems, or tell me what your lease time is on the WAN once you have this clown show working?

[fawzy46]

nope, no DHCP at wan at all, no ip renewal on wan as long as you keep the connection up. the problem happens as mentioned when I have to disconnect the router or the modem for more than 3/5 minutes or if I suffer a power outage. what makes the situation more strange is sometimes I disconnected the modem for more than 30 minutes and when I plug it back in, the connection goes up with no problem.

[bmork]

Doesn't look like you ever receive an ack on your magic. Maybe try with "nomagic" in the pppd options and see if that helps?

[fawzy46]

will try that, thanks