What's new

AC87U PPPOE bug impossible reconnect after remote disconnect

  • SNBForums Code of Conduct

    SNBForums is a community for everyone, no matter what their level of experience.

    Please be tolerant and patient of others, especially newcomers. We are all here to share and learn!

    The rules are simple: Be patient, be nice, be helpful or be gone!

ankhazam

Senior Member
Hello there,
my ISP every 24 hours disconnects my PPPOE connection.
For years I had no issues with that fact.

However recently when I moved to the AC87U (stock 3.0.0.4.376_2769 firmware) this became a major problem as every time the ISP disconnects me I get red WAN light and lots of such lines in the log
Code:
...
Oct 17 16:41:40 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 12
...

The usage count is a various number >0 when the bug occurs but supposedly doesn't count to 0 which would unlock the reconnect.
The router refuses to reconnect automatically unless I reboot it.

Is there any fix to this issue known? Does Merlin edition contain a fix?
 
Hi,
today it happened again. Below is the log from today.
WAN connection was recovered yet after router reboot :/
Please help me.

Code:
Oct 23 00:50:14 kernel: br0: received packet on vlan1 with own address as source address
Oct 23 00:50:14 kernel: br0: received packet on vlan1 with own address as source address
Oct 23 00:50:14 kernel: br0: received packet on vlan1 with own address as source address
Oct 23 00:50:14 kernel: br0: received packet on vlan1 with own address as source address
Oct 23 08:57:38 pptpd[14182]: CTRL: EOF or bad error reading ctrl packet length.
Oct 23 08:57:38 pptpd[14182]: CTRL: couldn't read packet header (exit)
Oct 23 08:57:38 pptpd[14182]: CTRL: CTRL read failed
Oct 23 11:50:56 pppd[1085]: Connection terminated.
Oct 23 11:50:57 WAN Connection: Fail to connect with some issues.
Oct 23 11:50:57 stop_nat_rules: apply the redirect_rules!
Oct 23 11:51:06 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 4
Oct 23 11:51:16 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 4
...// few hundred same lines until I got to the place where the router is deployed.
Oct 23 16:10:11 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 4
Oct 23 16:10:21 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 4
Oct 23 16:10:31 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 4

It's highly irritating as I cannot VPN into the network unless I get on-site and reboot it locally :/

As an possible solution I thought of installing RMerlin's firmware and setup a cron task to reset the WAN interface periodically.

Regards
 
same problem, no solution

I have the same problem and I didn't found any solution.
Asus told me to go in service to change the router.
New one is doing the same :(


Dec 9 05:17:04 pppd[1013]: Connection terminated.
Dec 9 05:17:04 pppd[1013]: Modem hangup
Dec 9 05:17:06 WAN Connection: Fail to connect with some issues.
Dec 9 05:17:06 stop_nat_rules: apply the redirect_rules!
Dec 9 05:17:49 pppd[1013]: Timeout waiting for PADO packets
Dec 9 05:19:05 pppd[1013]: Timeout waiting for PADO packets
Dec 9 05:20:20 pppd[1013]: Timeout waiting for PADO packets
 
Nope, your case is irrelevant to my bug.
PADO packet timeout is something totally different.
It is a timeout from DSL provider, not the router fault.
In case of PADO timeouts you just have to wait, whereas my issue lies within the router software.
 
It is staying with "Timeout waiting for PADO packets" until reboot.
Always the same. Never came back. I have to reboot to have the WAN back.
Maybe not exactly the same but similar. I'm using PPPoE.
Disconnect and then fail to connect.

I saw that you have too this:
Oct 23 11:50:56 pppd[1085]: Connection terminated.
Oct 23 11:50:57 WAN Connection: Fail to connect with some issues.
Oct 23 11:50:57 stop_nat_rules: apply the redirect_rules!
 
Hello,
I have the same problem with the ISP.
Every 24 hours disconnects my PPPOE connection of the the AC87 router (current firmware: RT-AC87U_3.0.0.4_376.48_1).

Dec 08 18:43:50 pppd[738]: Connect time 1440.0 minutes.
Dec 08 18:43:50 WAN Connection: Fail to connect with some issues.
Dec 08 18:43:50 stop_nat_rules: apply the redirect_rules!
Dec 08 18:43:53 pppd[738]: Connection terminated.
Dec 08 18:44:03 kernel: unregister_netdevice: waiting for ppp0 to become free. Usage count = 192

Then I power on/off. WAN is back for 1440.0 minutes.
What is the problem? What can I do?.
 
Last edited:
Looks like something binds itself to the ppp0 interface and doesn't free it. That would be a design flaw.

If the issue only happens with the AC87U (as seems the case in this thread) then the first suspect would be the DPI engine. Try either turning off Adaptive QoS, AiProtection and Apps analyzing, or upgrading to the recently released beta firmware from Asus (378_xxxx) to see if it helps.
 
Finally relevant posts!

@Joergi that's the same problem

@RMerlin, this might be the issue as I'm using all DPI features.
Moreover it is true that on the latest beta I did not experience this problem so maybe the fixed it.

Moreover I tracked this issue to be some old linux kernel bug...
 
Last edited:
Finally relevant posts!

@Joergi that's the same problem

@RMerlin, this might be the issue as I'm using all DPI features.
Moreover it is true that on the latest beta I did not experience this problem so maybe the fixed it.

Moreover I tracked this issue to be some old linux kernel bug...

I know that the latest beta does use a newer version of TrendMicro's engine, so it will be interesting to see if the issue is truly resolved after a few days of uptime.
 
I have 3123 version with everything disabled. It have the minim options enabled for internet. No QoS, no aiprotection, no apps analyzing :( .
It is happening usually during the night.
Only the reboot is getting it back online.

Dec 9 21:04:34 kernel: br0: received packet on vlan1 with own address as source address
Dec 9 21:17:54 kernel: br0: received packet on vlan1 with own address as source address
Dec 9 21:59:57 ntp: start NTP update
Dec 9 22:41:44 pppd[1132]: Connection terminated.
Dec 9 22:41:44 pppd[1132]: Modem hangup
Dec 9 22:41:46 WAN Connection: Fail to connect with some issues.
Dec 9 22:41:46 stop_nat_rules: apply the redirect_rules!
Dec 9 22:42:30 pppd[1132]: Timeout waiting for PADO packets
Dec 9 22:43:45 pppd[1132]: Timeout waiting for PADO packets
Dec 9 22:45:00 pppd[1132]: Timeout waiting for PADO packets
 
Have you tried waiting for let say 15 minutes? I remember that I had to wait a bit more when this was happening to me on a different router (also connected via PPPOE and same modem type)
 
Yes, yesterday stopped at 10:41 PM and this morning at 6:00 AM was still in timeout. I restarted and... back online.
 
Router daily reboot

The true nature behind why my internet works despite the 24h ISP forced disconnect is that my router simply reboots itself after such a hangup...
Below is a part of log from the last few days...

Code:
Dec  6 04:42:01 kernel: br0: received packet on vlan1 with own address as source address
Dec  6 04:59:57 ntp: start NTP update
Dec  6 05:59:59 ntp: start NTP update
Dec  6 07:00:01 ntp: start NTP update
Dec  6 07:09:16 kernel: br0: received packet on vlan1 with own address as source address
Dec  6 07:33:03 kernel: htb: htb qdisc 13: is non-work-conserving?
Dec  6 07:59:57 ntp: start NTP update
Dec  6 08:02:31 miniupnpd[878]: recv (state0): Connection reset by peer
Jan  1 01:00:11 syslogd started: BusyBox v1.17.4
Jan  1 01:00:11 kernel: klogd started: BusyBox v1.17.4 (2014-11-26 17:06:57 CST)
Jan  1 01:00:11 kernel: Linux version 2.6.36.4brcmarm (raymonddev@raymonddev-1404) (gcc version 4.5.3 (Buildroot 2012.02) ) #1 SMP PREEMPT Wed Nov 26 17:12:20 CST 2014
Jan  1 01:00:11 kernel: CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c53c7f
Jan  1 01:00:11 kernel: CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:11 kernel: Machine: Northstar Prototype
Jan  1 01:00:11 kernel: Ignoring unrecognised tag 0x00000000
Jan  1 01:00:11 kernel: Memory policy: ECC disabled, Data cache writealloc
Jan  1 01:00:11 kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 60416
Jan  1 01:00:11 kernel: Kernel command line: root=/dev/mtdblock2 console=ttyS0,115200 init=/sbin/preinit earlyprintk debug
Jan  1 01:00:11 kernel: Memory: 255540k/255540k available, 6604k reserved, 0K highmem
Jan  1 01:00:11 kernel: Virtual kernel memory layout:
...
Dec  7 08:32:48 pppd[711]: Connection terminated.
Dec  7 08:32:48 pppd[711]: Modem hangup
Dec  7 08:32:50 WAN Connection: Fail to connect with some issues.
Dec  7 08:32:50 stop_nat_rules: apply the redirect_rules!
Jan  1 01:00:11 syslogd started: BusyBox v1.17.4
Jan  1 01:00:11 kernel: klogd started: BusyBox v1.17.4 (2014-11-26 17:06:57 CST)
Jan  1 01:00:11 kernel: Linux version 2.6.36.4brcmarm (raymonddev@raymonddev-1404) (gcc version 4.5.3 (Buildroot 2012.02) ) #1 SMP PREEMPT Wed Nov 26 17:12:20 CST 2014
Jan  1 01:00:11 kernel: CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c53c7f
Jan  1 01:00:11 kernel: CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:11 kernel: Machine: Northstar Prototype
Jan  1 01:00:11 kernel: Ignoring unrecognised tag 0x00000000
Jan  1 01:00:11 kernel: Memory policy: ECC disabled, Data cache writealloc
...
Dec  8 08:33:02 kernel: br0: received packet on vlan1 with own address as source address
Dec  8 08:35:19 WAN Connection: Fail to connect with some issues.
Dec  8 08:35:19 stop_nat_rules: apply the redirect_rules!
Dec  8 08:35:19 pppd[712]: Connection terminated.
Dec  8 08:35:19 pppd[712]: Modem hangup
Dec  8 08:35:30 pppd[712]: Connected to <MAC> via interface eth0
Dec  8 08:35:30 pppd[712]: Connect: ppp0 <--> eth0
Dec  8 08:35:30 pppd[712]: CHAP authentication succeeded
Dec  8 08:35:30 pppd[712]: peer from calling number <MAC> authorized
Dec  8 08:35:30 pppd[712]: local  IP address <IP>
Dec  8 08:35:30 pppd[712]: remote IP address <IP>
Dec  8 08:35:33 start_nat_rules: apply the nat_rules(/tmp/nat_rules_ppp0_eth0)!
Dec  8 08:35:33 rc_service: ip-up 15917:notify_rc stop_pptpd
Dec  8 08:35:33 rc_service: ip-up 15917:notify_rc start_pptpd
Jan  1 01:00:11 syslogd started: BusyBox v1.17.4
Jan  1 01:00:11 kernel: klogd started: BusyBox v1.17.4 (2014-11-26 17:06:57 CST)
Jan  1 01:00:11 kernel: Linux version 2.6.36.4brcmarm (raymonddev@raymonddev-1404) (gcc version 4.5.3 (Buildroot 2012.02) ) #1 SMP PREEMPT Wed Nov 26 17:12:20 CST 2014
Jan  1 01:00:11 kernel: CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c53c7f
Jan  1 01:00:11 kernel: CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:11 kernel: Machine: Northstar Prototype
Jan  1 01:00:11 kernel: Ignoring unrecognised tag 0x00000000
Jan  1 01:00:11 kernel: Memory policy: ECC disabled, Data cache writealloc
Jan  1 01:00:11 kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 60416
Jan  1 01:00:11 kernel: Kernel command line: root=/dev/mtdblock2 console=ttyS0,115200 init=/sbin/preinit earlyprintk debug
Jan  1 01:00:11 kernel: Memory: 255540k/255540k available, 6604k reserved, 0K highmem
Jan  1 01:00:11 kernel: Virtual kernel memory layout:
...
Dec  9 08:36:24 kernel: br0: received packet on vlan1 with own address as source address
Dec  9 08:36:25 kernel: br0: received packet on vlan1 with own address as source address
Dec  9 08:36:26 kernel: br0: received packet on vlan1 with own address as source address
Jan  1 01:00:11 syslogd started: BusyBox v1.17.4
Jan  1 01:00:11 kernel: klogd started: BusyBox v1.17.4 (2014-11-26 17:06:57 CST)
Jan  1 01:00:11 kernel: Linux version 2.6.36.4brcmarm (raymonddev@raymonddev-1404) (gcc version 4.5.3 (Buildroot 2012.02) ) #1 SMP PREEMPT Wed Nov 26 17:12:20 CST 2014
Jan  1 01:00:11 kernel: CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c53c7f
Jan  1 01:00:11 kernel: CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:11 kernel: Machine: Northstar Prototype
Jan  1 01:00:11 kernel: Ignoring unrecognised tag 0x00000000
Jan  1 01:00:11 kernel: Memory policy: ECC disabled, Data cache writealloc
Jan  1 01:00:11 kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 60416
Jan  1 01:00:11 kernel: Kernel command line: root=/dev/mtdblock2 console=ttyS0,115200 init=/sbin/preinit earlyprintk debug
Jan  1 01:00:11 kernel: Memory: 255540k/255540k available, 6604k reserved, 0K highmem
Jan  1 01:00:11 kernel: Virtual kernel memory layout:
...
Dec 10 05:38:47 kernel: br0: received packet on vlan1 with own address as source address
Dec 10 05:38:48 kernel: br0: received packet on vlan1 with own address as source address
Dec 10 05:59:57 ntp: start NTP update
Dec 10 06:59:59 ntp: start NTP update
Dec 10 08:00:01 ntp: start NTP update
Jan  1 01:00:11 syslogd started: BusyBox v1.17.4
Jan  1 01:00:11 kernel: klogd started: BusyBox v1.17.4 (2014-11-26 17:06:57 CST)
Jan  1 01:00:11 kernel: Linux version 2.6.36.4brcmarm (raymonddev@raymonddev-1404) (gcc version 4.5.3 (Buildroot 2012.02) ) #1 SMP PREEMPT Wed Nov 26 17:12:20 CST 2014
Jan  1 01:00:11 kernel: CPU: ARMv7 Processor [413fc090] revision 0 (ARMv7), cr=10c53c7f
Jan  1 01:00:11 kernel: CPU: VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Jan  1 01:00:11 kernel: Machine: Northstar Prototype
Jan  1 01:00:11 kernel: Ignoring unrecognised tag 0x00000000
Jan  1 01:00:11 kernel: Memory policy: ECC disabled, Data cache writealloc
Jan  1 01:00:11 kernel: Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 60416
Jan  1 01:00:11 kernel: Kernel command line: root=/dev/mtdblock2 console=ttyS0,115200 init=/sbin/preinit earlyprintk debug
Jan  1 01:00:11 kernel: Memory: 255540k/255540k available, 6604k reserved, 0K highmem
Jan  1 01:00:11 kernel: Virtual kernel memory layout:

Something is definitely wrong xD
 
Last edited:
I tried with zero and different with1200 seconds. With 1200 seconds seems to reconnect but I need online connection not with disconnection after 30 minutes of idle. I will tray again with ISP but they are saying to test with computer which is working. They don't offer any support for usage of routers.
THX anyway!
 
I had the same problem. ASUS recommended downgrading to an earlier version of the firmware. I installed the one previous to the latest and so far my nightly disconnect problem has gone away.
 
I tried also with the lowest version available on asus support. The same problem, still doesn't disconnect right.
Merlin firmware showed something like the disconnect script failed otherwise, a very good firmware tuning.
I would like to have this router running well with Merlin's firmware.
 
Hello,
I flashed my router AC87U with the current stock ASUS beta-firmware (378_xxxx).
Looks like something binds itself to the ppp0 interface and doesn't free it. That would be a design flaw.

If the issue only happens with the AC87U (as seems the case in this thread) then the first suspect would be the DPI engine. Try either turning off Adaptive QoS, AiProtection and Apps analyzing, or upgrading to the recently released beta firmware from Asus (378_xxxx) to see if it helps.
I made a 5 sec. reset and installed all settings new (No QoS, only AiProtection).
Now the router make an auto reboot after every 24h (06:00).

Dec 12 00:59:59 ntp: start NTP update
Dec 12 02:00:01 ntp: start NTP update
Dec 12 02:59:57 ntp: start NTP update
Dec 12 03:59:57 ntp: start NTP update
Dec 12 04:59:57 ntp: start NTP update
Jan 1 01:00:11 syslogd started: BusyBox v1.17.4
.
.
.
Dec 12 05:57:27 dfs: start dfs scan
Dec 12 05:57:27 nodfs_scan: complete
Dec 12 05:59:57 ntp: start NTP update
Dec 12 06:59:57 ntp: start NTP update
Dec 12 07:59:57 ntp: start NTP update
Dec 12 08:59:57 ntp: start NTP update

Is this normal?
 

Sign Up For SNBForums Daily Digest

Get an update of what's new every day delivered to your mailbox. Sign up here!
Top