Internet Dropping PPPoE - Need to reboot router to get back online

[Xeyrruken]

@Icelvlan Did you manage to get this fixed?

The same has happened to me over the past few weeks, I have had a constant ping test from CST on a server machine.
I have an RT-AX86U 388.6 Full hard reset before installation

Feb 12 02:24:40 pppd[18199]: No response to 10 echo-requests
Feb 12 02:24:40 pppd[18199]: Serial link appears to be disconnected.
Feb 12 02:24:40 pppd[18199]: Connect time 2906.6 minutes.
Feb 12 02:24:40 pppd[18199]: Sent 3987305831 bytes, received 2114299745 bytes.
Feb 12 02:24:46 pppd[18199]: Connection terminated.
Feb 12 02:24:46 pppd[18199]: Sent PADT
Feb 12 02:24:46 pppd[18199]: Modem hangup
Feb 12 02:24:56 pppd[18199]: PPP session is 1 (0x1)
Feb 12 02:24:56 pppd[18199]: Connected to 00:00:5e:00:01:7a via interface eth0
Feb 12 02:24:56 pppd[18199]: Using interface ppp0
Feb 12 02:24:56 pppd[18199]: Connect: ppp0 <--> eth0
Feb 12 02:24:56 pppd[18199]: CHAP authentication succeeded: CHAP authentication success
Feb 12 02:24:56 pppd[18199]: CHAP authentication succeeded
Feb 12 02:24:56 pppd[18199]: peer from calling number 00:00:5E:00:01:7A authorized
Feb 12 02:24:56 pppd[18199]: local  IP address ***
Feb 12 02:24:56 pppd[18199]: remote IP address ***

 

[ColinTaylor]

@Icelvlan Did you manage to get this fixed?

The same has happened to me over the past few weeks, I have had a constant ping test from CST on a server machine.
I have an RT-AX86U 388.6 Full hard reset before installation

What seems to be the problem? According to your log everything worked as intended.

 

[Ripshod]

@Icelvlan Did you manage to get this fixed?

The same has happened to me over the past few weeks, I have had a constant ping test from CST on a server machine.
I have an RT-AX86U 388.6 Full hard reset before installation

Feb 12 02:24:40 pppd[18199]: No response to 10 echo-requests
Feb 12 02:24:40 pppd[18199]: Serial link appears to be disconnected.
Feb 12 02:24:40 pppd[18199]: Connect time 2906.6 minutes.
Feb 12 02:24:40 pppd[18199]: Sent 3987305831 bytes, received 2114299745 bytes.
Feb 12 02:24:46 pppd[18199]: Connection terminated.
Feb 12 02:24:46 pppd[18199]: Sent PADT
Feb 12 02:24:46 pppd[18199]: Modem hangup
Feb 12 02:24:56 pppd[18199]: PPP session is 1 (0x1)
Feb 12 02:24:56 pppd[18199]: Connected to 00:00:5e:00:01:7a via interface eth0
Feb 12 02:24:56 pppd[18199]: Using interface ppp0
Feb 12 02:24:56 pppd[18199]: Connect: ppp0 <--> eth0
Feb 12 02:24:56 pppd[18199]: CHAP authentication succeeded: CHAP authentication success
Feb 12 02:24:56 pppd[18199]: CHAP authentication succeeded
Feb 12 02:24:56 pppd[18199]: peer from calling number 00:00:5E:00:01:7A authorized
Feb 12 02:24:56 pppd[18199]: local  IP address ***
Feb 12 02:24:56 pppd[18199]: remote IP address ***

View attachment 56427


View attachment 56428

Looks like your Internet connection dropped out, then your router reconnected it. Nothing to do with your router. You should contact your ISP.

 

[Xeyrruken]

What seems to be the problem? According to your log everything worked as intended.

Looks like your Internet connection dropped out, then your router reconnected it. Nothing to do with your router. You should contact your ISP.

It is taking down a server, multiple times a day for 1 to 3 minutes per occurrence, maybe it is a coincidence that the issue started to happen after upgrading to 388.6. I was just wondering if it may be something with the router config, as I called my ISP and showed them the logs and they have no issues on their end(as usual lol). I will reset the modem and router once again to see if that helps.

 

[ColinTaylor]

It is taking down a server, multiple times a day for 1 to 3 minutes per occurrence, maybe it is a coincidence that the issue started to happen after upgrading to 388.6. I was just wondering if it may be something with the router config, as I called my ISP and showed them the logs and they have no issues on their end(as usual lol). I will reset the modem and router once again to see if that helps.

There's nothing to suggest a problem with the router. It looks like your ISP reset the connection, which is quiet normal for some types of PPPoE connection. Your router log shows that the connection was up for 2906.6 minutes (just over 48 hours) prior to the reset. If you're loosing internet connectivity inside that period without any other errors in the router's log then I'd say it's an ISP issue.

Do you have access to the modem that your router is connected to? What does it's logs show?

 

[sfx2000]

There's nothing to suggest a problem with the router. It looks like your ISP reset the connection, which is quiet normal for some types of PPPoE connection.

This isn't really "normal"... this kinda of also smells of the issue on another thread where the ISP's DHCP responds badly...

Some google-fu tends to suggest that this, like the other, is Asus, as a lot of reports there mention Asus specifically...

Could be related.

 

[Tvbaas]

@Xeyrruken
Does your ISP require this setting to be enabled?

There is a rather old thread on the meaning of this setting. If it is not required by your ISP, did you already try to set it to "No" and see if it has any impact in your situation ?

 

[Xeyrruken]

There's nothing to suggest a problem with the router. It looks like your ISP reset the connection, which is quiet normal for some types of PPPoE connection. Your router log shows that the connection was up for 2906.6 minutes (just over 48 hours) prior to the reset. If you're loosing internet connectivity inside that period without any other errors in the router's log then I'd say it's an ISP issue.

Do you have access to the modem that your router is connected to? What does it's logs

@Xeyrruken
Does your ISP require this setting to be enabled?
View attachment 56445
There is a rather old tread on the meaning of this setting. If it is not required by your ISP, did you already try to set it to "No" and see if it has any impact in your situation ?

Just turned this off as the ISP said it wasn't necessary, I'm currently monitoring the situation, I had two more disconnects today. If this situation keeps happening I will tell the ISP to change my connection back to IPoE, as the only reason I'm Using PPPoE is because based on the ISP to be able to use the MODEM in Bridge Mode, it is only via PPPoE. and if that is the case I don't want to lose control over my network.

 

[Xeyrruken]

There's nothing to suggest a problem with the router. It looks like your ISP reset the connection, which is quiet normal for some types of PPPoE connection. Your router log shows that the connection was up for 2906.6 minutes (just over 48 hours) prior to the reset. If you're loosing internet connectivity inside that period without any other errors in the router's log then I'd say it's an ISP issue.

Do you have access to the modem that your router is connected to? What does it's logs show?

Definitely there something going on with the ISP, I manage to access the logs of the modem by requesting the admin login for the modem and I found this:

[notice] <133>1 1970-01-01T18:02:31.242058-04:00 AONT cfgmgr 1821 - -  [cfgmgr] InternetGatewayDevice.X_ASB_COM_NokiaParentalControl.Profile.1.Device.1.,flag=0x0(),X_ASB_COM_IPAddress(10.0.0.65->0.0.0.0);X_ASB_COM_Status(1->0);
[notice] <133>1 1970-01-01T18:02:32.267911-04:00 AONT cfgmgr 1821 - -  [cfgmgr] InternetGatewayDevice.X_ASB_COM_NokiaParentalControl.Profile.1.Device.1.X_ASB_COM_DeviceIPv6Address.1. deleted
[notice] <133>1 1970-01-01T18:02:32.269380-04:00 AONT cfgmgr 1821 - -  [cfgmgr] InternetGatewayDevice.LANDevice.1.Hosts.Host.1.X_ASB_COM_IPv6Address.1. deleted
[notice] <133>1 1970-01-01T20:01:25.736597-04:00 AONT dhcpc 3481 - -  DHCP-Comm:send_renew() Unicasts a DHCP renew message
[notice] <133>1 1970-01-01T20:01:27.807142-04:00 AONT dhcpc 3481 - -  main:2535 DHCP ACK received from DHCP Server, Resetting bootsec to ZERO
[notice] <133>1 1970-01-02T00:01:25.866667-04:00 AONT dhcpc 3481 - -  DHCP-Comm:send_renew() Unicasts a DHCP renew message
[info] <134>1 1970-01-02T00:01:25.885706-04:00 AONT evtmgr 1990 - -  ip conflict,  sip is 10.1.1.154 , dip is 10.1.1.154 ,smac is e4:da:df:d0:f8:37 dmac is e4:da:df:d0:f8:37
[notice] <133>1 1970-01-02T00:01:27.937009-04:00 AONT dhcpc 3481 - -  main:2535 DHCP ACK received from DHCP Server, Resetting bootsec to ZERO
[notice] <133>1 1970-01-02T04:01:25.997008-04:00 AONT dhcpc 3481 - -  DHCP-Comm:send_renew() Unicasts a DHCP renew message
[info] <134>1 1970-01-02T04:01:26.022985-04:00 AONT evtmgr 1990 - -  ip conflict,  sip is 10.1.1.154 , dip is 10.1.1.154 ,smac is e4:da:df:d0:f8:37 dmac is e4:da:df:d0:f8:37
[notice] <133>1 1970-01-02T04:01:28.068080-04:00 AONT dhcpc 3481 - -  main:2535 DHCP ACK received from DHCP Server, Resetting bootsec to ZERO
[notice] <133>1 1970-01-02T08:01:26.126710-04:00 AONT dhcpc 3481 - -  DHCP-Comm:send_renew() Unicasts a DHCP renew message
[info] <134>1 1970-01-02T08:01:26.145716-04:00 AONT evtmgr 1990 - -  ip conflict,  sip is 10.1.1.154 , dip is 10.1.1.154 ,smac is e4:da:df:d0:f8:37 dmac is e4:da:df:d0:f8:37
[notice] <133>1 1970-01-02T08:01:28.197660-04:00 AONT dhcpc 3481 - -  main:2535 DHCP ACK received from DHCP Server, Resetting bootsec to ZERO
[notice] <133>1 1970-01-02T12:01:26.256690-04:00 AONT dhcpc 3481 - -  DHCP-Comm:send_renew() Unicasts a DHCP renew message
[info] <134>1 1970-01-02T12:01:26.273505-04:00 AONT evtmgr 1990 - -  ip conflict,  sip is 10.1.1.154 , dip is 10.1.1.154 ,smac is e4:da:df:d0:f8:37 dmac is e4:da:df:d0:f8:37
[notice] <133>1 1970-01-02T12:01:28.327649-04:00 AONT dhcpc 3481 - -  main:2535 DHCP ACK received from DHCP Server, Resetting bootsec to ZERO
[warning] <132>1 1970-01-02T15:15:33.098374-04:00 AONT omciMgr 2134 - -  Fiber disconnected, ONU offline.
[notice] <133>1 1970-01-02T15:15:33.470112-04:00 AONT dhcpc 3481 - -  DHCP-Comm:send_release() Sending a DHCP release message
[warning] <132>1 1970-01-02T15:16:40.268290-04:00 AONT omciMgr 2134 - -  Fiber connected, ONU online.
[notice] <133>1 1970-01-02T15:16:40.536802-04:00 AONT syslog 22183 - -  is_bootpsec_enable:1303 file failed to open
[notice] <133>1 1970-01-02T15:16:40.616525-04:00 AONT dhcpc 22183 - -  DHCP-Comm:send_discover() Broadcast a DHCP discover packet to the network
[notice] <133>1 1970-01-02T15:16:46.737648-04:00 AONT dhcpc 22183 - -  DHCP-Comm:send_selecting() Broadcasts a DHCP request message
[notice] <133>1 1970-01-02T15:16:48.926208-04:00 AONT dhcpc 22183 - -  main:2535 DHCP ACK received from DHCP Server, Resetting bootsec to ZERO
[info] <134>1 1970-01-02T15:16:52.180896-04:00 AONT syslog 1562 - -  SipRegRegisteringState::ProcessSuccessResp,voice term 1 register status is changed to SipRegRegistedState
[notice] <133>1 1970-01-02T17:45:00.707709-04:00 AONT cfgmgr 1821 - -  [cfgmgr] InternetGatewayDevice.X_ASB_COM_NokiaParentalControl.Profile.1.Device.1. deleted
[notice] <133>1 1970-01-02T17:45:00.728446-04:00 AONT cfgmgr 1821 - -  [cfgmgr] InternetGatewayDevice.LANDevice.1.Hosts.Host.1. deleted
[notice] <133>1 1970-01-02T19:16:46.936894-04:00 AONT dhcpc 22183 - -  DHCP-Comm:send_renew() Unicasts a DHCP renew message

This matches the picture below, which is the same amount of ocurrences found on the modem log

 

[ColinTaylor]

I suggest you contact your ISP again and show them this log. It's a bit difficult to correlate to your own log because the date and time on the modem is not correct. But the most interesting entries are the ones showing your ONT going offline for over a minute:

[warning] <132>1 1970-01-02 T15:15:33.098374-04:00 AONT omciMgr 2134 - -  Fiber disconnected, ONU offline.
[notice]  <133>1 1970-01-02 T15:15:33.470112-04:00 AONT dhcpc   3481 - -  DHCP-Comm:send_release() Sending a DHCP release message
[warning] <132>1 1970-01-02 T15:16:40.268290-04:00 AONT omciMgr 2134 - -  Fiber connected, ONU online.

So unless that was you rebooting the modem that looks like an ISP problem.

 

[Xeyrruken]

I suggest you contact your ISP again and show them this log. It's a bit difficult to correlate to your own log because the date and time on the modem is not correct. But the most interesting entries are the ones showing your ONT going offline for over a minute:

[warning] <132>1 1970-01-02 T15:15:33.098374-04:00 AONT omciMgr 2134 - -  Fiber disconnected, ONU offline.
[notice]  <133>1 1970-01-02 T15:15:33.470112-04:00 AONT dhcpc   3481 - -  DHCP-Comm:send_release() Sending a DHCP release message
[warning] <132>1 1970-01-02 T15:16:40.268290-04:00 AONT omciMgr 2134 - -  Fiber connected, ONU online.

So unless that was you rebooting the modem that looks like an ISP problem.

Hey there, I contacted my ISP, and they replaced my modem, which was faulty. However, the disconnections that lasted from 1 to 2 minutes persisted, though the ones lasting 30 to 40 seconds were eliminated after changing modems. The fiber line was in flawless condition, so I started to play around with the settings and discovered that something was going on with the PPP Echo pings on my Asus AX86U router. I installed a new, non-Asus router, and used the same PPPoE parameters as with my Asus, and the Echo requests worked as expected. The issue appears to be that the Asus router executes the PPP Echo ping but never receives a response back, triggering the hangup signal, as if the line were actually disconnected or down. I asked my ISP if, by any chance, they were blocking the Echo signals, and they told me they do not block them. Then, I tried a DNS probe with my Asus router and haven't had a disconnect in the past 5 days. Echo works with TP-Link router I have, I wonder what could be going on with my AX86U that never gets the response.

 

[ColinTaylor]

Well done for coming up with a solution. If DNS Probe works then I'd just leave it at that. Not really worth the effort trying to understand what's going on behind the scenes IMHO.