Problem reconnecting through PPPoE

This thread has been locked for further replies. You can start a new thread to share your ideas or ask questions.

Problem reconnecting through PPPoE

This thread has been locked for further replies. You can start a new thread to share your ideas or ask questions.
Problem reconnecting through PPPoE
Problem reconnecting through PPPoE
2023-08-09 17:56:09
Model: ER605 (TL-R605)  
Hardware Version: V2
Firmware Version: 2.1.2 Build 20230210 Rel.62992

Hello, I have the following problem, from time to time the router needs to reconnect because the ISP give us a dynamic IP, problem is that the router does not reconnect, however if I log in to the router and manually click on the "connect" button there is no problem, the conection is successful, any ideas what can be the problem?

 

 

here is the log:

 

2023-08-09 01:18:19 pppoe_client[16905]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xada5)
2023-08-09 01:18:17 pppoe_client[16330]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:18:16 pppoe_client[14705]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:59 pppoe_client[14341]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:17:57 pppoe_client[12355]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:41 pppoe_client[12355]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:25 pppoe_client[12355]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:08 pppoe_client[12004]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:17:07 pppoe_client[10575]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:16:51 pppoe_client[10575]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:16:51 pppoe_client[10575]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf192)
2023-08-09 01:16:51 ppp[10575]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:16:50 ppp[10575]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:16:50 pppoe_client[10575]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf192)
2023-08-09 01:16:49 pppoe_client[10575]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:16:49 pppoe_client[10575]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf1e9)
2023-08-09 01:16:49 ppp[10575]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:16:49 ppp[10575]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:16:49 pppoe_client[10575]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf1e9)
2023-08-09 01:16:47 pppoe_client[10225]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:16:35 pppoe_client[7836]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:16:35 pppoe_client[7836]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xae91)
2023-08-09 01:16:35 ppp[7836]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:16:35 ppp[7836]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:16:35 pppoe_client[7836]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xae91)
2023-08-09 01:16:34 pppoe_client[7836]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:16:18 pppoe_client[7836]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:16:16 remote_mngt[8776]: <5> 02413242 IP address 192.168.0.10 succeeded to access the <null> server.
2023-08-09 01:16:00 pppoe_client[7184]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:15:48 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:48 pppoe_client[15467]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x2733)
2023-08-09 01:15:48 ppp[15467]: <4> 02313104 [OMADA][osg:9C-53-22-9D-27-A6]: PPPOE g60355 failed to connect to the server because authentication failed with the wrong username or password.
2023-08-09 01:15:48 ppp[15467]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:15:48 pppoe_client[15467]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x2733)
2023-08-09 01:15:47 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:46 pppoe_client[15467]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x99ca)
2023-08-09 01:15:46 ppp[15467]: <4> 02313104 [OMADA][osg:9C-53-22-9D-27-A6]: PPPOE g60355 failed to connect to the server because authentication failed with the wrong username or password.
2023-08-09 01:15:46 ppp[15467]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:15:46 pppoe_client[15467]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x99ca)
2023-08-09 01:15:45 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:45 pppoe_client[15467]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x5a29)
2023-08-09 01:15:45 ppp[15467]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:15:45 ppp[15467]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:15:45 pppoe_client[15467]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x5a29)
2023-08-09 01:15:44 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:44 ppp[15467]: <4> 02313123 WAN1: LCP sending TERMINAL-REQUEST timeout.
2023-08-09 01:15:41 logger[4603]: <5> 02012656 [OMADA][WAN1] of [osg:9C-53-22-9D-27-A6] is down.
2023-08-09 01:15:37 ppp[15467]: <4> 02313109 WAN1: LCP sending ECHO-REQUEST timeout.

  0      
  0      
#1
Options
7 Reply
Re:Problem reconnecting through PPPoE
2023-08-10 06:30:10

Hi @Andres123 

Thanks for posting in our business forum.

This seems to be an issue with your ISP. The log reflexes the interaction between its WAN port to the ISP server. And it reports there were several reconnects to the ISP server to authenticate the username and password.

 

"PADI timeout" should be fine if your PPPoE can reconnect to the ISP and get the Internet.

Best Regards! If you are new to the forum, please read: Howto - A Guide to Use Forum Effectively. Read Before You Post. Look for a model? Search your model NOW Official and Beta firmware. NEW features! Subscribe for the latest update!Download Beta Here☚ ☛ ★ Configuration Guide ★ ☚ ☛ ★ Knowledge Base ★ ☚ ☛ ★ Troubleshooting ★ ☚ ● Be kind and nice. ● Stay on the topic. ● Post details. ● Search first. ● Please don't take it for granted. ● No email confidentiality should be violated. ● S/N, MAC, and your true public IP should be mosaiced.
  0  
  0  
#2
Options
Re:Problem reconnecting through PPPoE
2023-08-10 16:35:20

  @Clive_A Thanks for your reply!

 

I wonder why the problem is fixed when I click manually on "connect", if I do that there is no "PADI timeout" error.

 

However if I let the router to automatically reconnect it can't because of that error.

 

Maybe this can be a bug in the firmware?

  0  
  0  
#3
Options
Re:Problem reconnecting through PPPoE
2023-08-11 02:36:53 - last edited 2023-08-11 02:37:02

Hi @Andres123 

Thanks for posting in our business forum.

Since this error occurs in the middle of the night, in the morning, when you notice your network is down, do you see this error, "PADI timeout" and "disconnected by peer" still logged in the morning?

So to iterate this, in the morning, by the time you click the "Connect", the network is still down and errors pop up in the log with a fresh timestamp. Is that correct?

 

After you click the "Connect", the issue immediately goes away?

Best Regards! If you are new to the forum, please read: Howto - A Guide to Use Forum Effectively. Read Before You Post. Look for a model? Search your model NOW Official and Beta firmware. NEW features! Subscribe for the latest update!Download Beta Here☚ ☛ ★ Configuration Guide ★ ☚ ☛ ★ Knowledge Base ★ ☚ ☛ ★ Troubleshooting ★ ☚ ● Be kind and nice. ● Stay on the topic. ● Post details. ● Search first. ● Please don't take it for granted. ● No email confidentiality should be violated. ● S/N, MAC, and your true public IP should be mosaiced.
  0  
  0  
#4
Options
Re:Problem reconnecting through PPPoE
2023-08-11 03:39:18 - last edited 2023-08-11 03:53:24

  @Clive_A 

 

Correct.

 

In fact I was at the office this time (at 1 am lol) when the wan went offline, I started to check the logs and the errors were the one I pasted, after 3 minutes I clicked "connect" and problem disappeared. Magic.

 

01:15 WAN was down.

01:18 I clicked the "connect" button

 

Here is the full log (notice the lines at 01:18):

 

2023-08-09 01:24:36 dhcp_server[8217]: <5> 01712251 DHCP Server allocated IP address 192.168.0.102 for the client[MAC: 9c:53:22:94:94:76].
2023-08-09 01:18:28 logger[17967]: <5> 02012655 [OMADA][WAN1] of [osg:9C-53-22-9D-27-A6] is up.
2023-08-09 01:18:22 ppp[16905]: <5> 02313126 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 connection on [WAN1] is established successfully. (Local IP:xxxxxx, peer:xxxxx, DNS1:xxxxx, DNS2:xxxxxx.)
2023-08-09 01:18:21 ppp[16905]: <5> 02313125 WAN1: IPv6 PPPOE IPV6C succeeded. (local=xxxxxx, peer=xxxxxx)
2023-08-09 01:18:21 ppp[16905]: <5> 02313103 WAN1: g60355 authenticated.
2023-08-09 01:18:20 ppp[16905]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:18:19 pppoe_client[16905]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xada5)
2023-08-09 01:18:17 pppoe_client[16330]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:18:16 pppoe_client[14705]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:59 pppoe_client[14341]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:17:57 pppoe_client[12355]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:41 pppoe_client[12355]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:25 pppoe_client[12355]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:17:08 pppoe_client[12004]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:17:07 pppoe_client[10575]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:16:51 pppoe_client[10575]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:16:51 pppoe_client[10575]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf192)
2023-08-09 01:16:51 ppp[10575]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:16:50 ppp[10575]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:16:50 pppoe_client[10575]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf192)
2023-08-09 01:16:49 pppoe_client[10575]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:16:49 pppoe_client[10575]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf1e9)
2023-08-09 01:16:49 ppp[10575]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:16:49 ppp[10575]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:16:49 pppoe_client[10575]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xf1e9)
2023-08-09 01:16:47 pppoe_client[10225]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:16:35 pppoe_client[7836]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:16:35 pppoe_client[7836]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xae91)
2023-08-09 01:16:35 ppp[7836]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:16:35 ppp[7836]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:16:35 pppoe_client[7836]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0xae91)
2023-08-09 01:16:34 pppoe_client[7836]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:16:18 pppoe_client[7836]: <4> 08214207 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 failed to connect to the server because sending PADI times out.
2023-08-09 01:16:16 remote_mngt[8776]: <5> 02413242 IP address 192.168.0.10 succeeded to access the <null> server.
2023-08-09 01:16:00 pppoe_client[7184]: <5> 08214202 WAN1: PPPoE began connecting automatically.
2023-08-09 01:15:48 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:48 pppoe_client[15467]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x2733)
2023-08-09 01:15:48 ppp[15467]: <4> 02313104 [OMADA][osg:9C-53-22-9D-27-A6]: PPPOE g60355 failed to connect to the server because authentication failed with the wrong username or password.
2023-08-09 01:15:48 ppp[15467]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:15:48 pppoe_client[15467]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x2733)
2023-08-09 01:15:47 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:46 pppoe_client[15467]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x99ca)
2023-08-09 01:15:46 ppp[15467]: <4> 02313104 [OMADA][osg:9C-53-22-9D-27-A6]: PPPOE g60355 failed to connect to the server because authentication failed with the wrong username or password.
2023-08-09 01:15:46 ppp[15467]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:15:46 pppoe_client[15467]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x99ca)
2023-08-09 01:15:45 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:45 pppoe_client[15467]: <5> 08214212 WAN1: PPPoE connection was disconnected by peer. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x5a29)
2023-08-09 01:15:45 ppp[15467]: <5> 02313111 WAN1: the peer request to disconnect PPP
2023-08-09 01:15:45 ppp[15467]: <5> 02313101 WAN1: LCP negotiation succeeded. (MTU=1492, AUTH=chap)
2023-08-09 01:15:45 pppoe_client[15467]: <5> 08214211 WAN1: PPPoE Discovery phase negotiation succeeded. (AC-MAC=2c-97-b1-7d-0e-dd, Session-ID=0x5a29)
2023-08-09 01:15:44 pppoe_client[15467]: <5> 08214206 WAN1: PPPoE session is over
2023-08-09 01:15:44 ppp[15467]: <4> 02313123 WAN1: LCP sending TERMINAL-REQUEST timeout.
2023-08-09 01:15:41 logger[4603]: <5> 02012656 [OMADA][WAN1] of [osg:9C-53-22-9D-27-A6] is down.
2023-08-09 01:15:37 ppp[15467]: <4> 02313109 WAN1: LCP sending ECHO-REQUEST timeout.

  0  
  0  
#5
Options
Re:Problem reconnecting through PPPoE
2023-08-11 03:51:09

Hi @Andres123 

Take care of yourself. Staying up is bad for you. LOL.

Please delete this line: 2023-08-09 01:18:22 ppp[16905]: <5> 02313126 [OMADA][osg:9C-53-22-9D-27-A6]: PPPoE g60355 connection on [WAN1] is established successfully. 

As it leaks your IP address. For the sake of your network security.

 

About the issue, kinda strange. So if you don't manually connect it, does it recover? If this happens again, can you monitor that? Because this time it only takes 3 minutes. But usually, this PPPoE authentication would not take a very long time. There may be a lag between the ISP server to your end, but should not be very long.

 

Best Regards! If you are new to the forum, please read: Howto - A Guide to Use Forum Effectively. Read Before You Post. Look for a model? Search your model NOW Official and Beta firmware. NEW features! Subscribe for the latest update!Download Beta Here☚ ☛ ★ Configuration Guide ★ ☚ ☛ ★ Knowledge Base ★ ☚ ☛ ★ Troubleshooting ★ ☚ ● Be kind and nice. ● Stay on the topic. ● Post details. ● Search first. ● Please don't take it for granted. ● No email confidentiality should be violated. ● S/N, MAC, and your true public IP should be mosaiced.
  0  
  0  
#6
Options
Re:Problem reconnecting through PPPoE
2023-08-11 04:07:54

  @Clive_A Thanks!

 

I edited that line.

 

It recovers but can take up to 15 minutes with no intervention, usually the PPPoE authentication takes only 5 seconds or so.

 

This is weird, it didn't happen with the router we had before (an archer c80), that makes me think it could be a firmware bug.

 

I will wait for this to happen a few times more and save the logs, then will share them here.

 

Thanks a lot!

  0  
  0  
#7
Options
Re:Problem reconnecting through PPPoE
2023-08-11 07:12:43

Hi @Andres123 

Andres123 wrote

  @Clive_A Thanks!

 

I edited that line.

 

It recovers but can take up to 15 minutes with no intervention, usually the PPPoE authentication takes only 5 seconds or so.

 

This is weird, it didn't happen with the router we had before (an archer c80), that makes me think it could be a firmware bug.

 

I will wait for this to happen a few times more and save the logs, then will share them here.

 

Thanks a lot!

Sure, of course. So, if this happened again, 15 minutes without intervention, it stuck there, you can reach out to me again.

If the time is consistent around 1 AM, you can probably swap the Archer for a comparison. If this happens on the Archer as well, taking 15 minutes to recover, then it could be the ISP problem.

If you recently upgrade the firmware and the issue pops up, you can let me know. I'll check with my colleagues.

Best Regards! If you are new to the forum, please read: Howto - A Guide to Use Forum Effectively. Read Before You Post. Look for a model? Search your model NOW Official and Beta firmware. NEW features! Subscribe for the latest update!Download Beta Here☚ ☛ ★ Configuration Guide ★ ☚ ☛ ★ Knowledge Base ★ ☚ ☛ ★ Troubleshooting ★ ☚ ● Be kind and nice. ● Stay on the topic. ● Post details. ● Search first. ● Please don't take it for granted. ● No email confidentiality should be violated. ● S/N, MAC, and your true public IP should be mosaiced.
  0  
  0  
#8
Options