Pppoe keeps disconnecting

Installing and Using OpenWrt Network and Wireless Configuration
1

I have installed OpenWrt 19.07.3 on my TP-Link Archer C7 v2 and after setting up pppoe it keeps disconnecting.

If I have nothing connected to the internet it will stay connected for 45minutes without a problem but If I try to play a online game , Facebook , etc it will keep disconnecting.

Bellow is some info from the system log around the time it happen .
I only removed my mac address from the log and put (info removed) in the place of it .

Does anyone know why it keeps disconnecting and a solution to my problem ?

Tue Jul  7 23:36:00 2020 daemon.info pppd[3572]: No response to 5 echo-requests
Tue Jul  7 23:36:00 2020 daemon.notice pppd[3572]: Serial link appears to be disconnected.
Tue Jul  7 23:36:00 2020 daemon.info pppd[3572]: Connect time 3.7 minutes.
Tue Jul  7 23:36:00 2020 daemon.info pppd[3572]: Sent 617883 bytes, received 2940586 bytes.
Tue Jul  7 23:36:00 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Tue Jul  7 23:36:00 2020 daemon.notice netifd: Interface 'wan' has lost the connection
Tue Jul  7 23:36:00 2020 daemon.warn dnsmasq[2317]: no servers found in /tmp/resolv.conf.auto, will retry
Tue Jul  7 23:36:06 2020 daemon.notice pppd[3572]: Connection terminated.
Tue Jul  7 23:36:06 2020 daemon.info pppd[3572]: Sent PADT
Tue Jul  7 23:36:06 2020 daemon.notice pppd[3572]: Modem hangup
Tue Jul  7 23:36:06 2020 daemon.info pppd[3572]: Exit.
Tue Jul  7 23:36:06 2020 daemon.notice netifd: Interface 'wan' is now down
Tue Jul  7 23:36:06 2020 kern.info kernel: [  727.348816] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
Tue Jul  7 23:36:06 2020 kern.info kernel: [  727.370263] eth0: link down
Tue Jul  7 23:36:06 2020 daemon.notice netifd: Interface 'wan' is disabled
Tue Jul  7 23:36:06 2020 daemon.notice netifd: Interface 'wan6' is disabled
Tue Jul  7 23:36:06 2020 kern.info kernel: [  727.398674] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Tue Jul  7 23:36:06 2020 kern.info kernel: [  727.405956] eth0: link up (1000Mbps/Full duplex)
Tue Jul  7 23:36:06 2020 kern.info kernel: [  727.414936] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Tue Jul  7 23:36:06 2020 daemon.notice netifd: Interface 'wan' is enabled
Tue Jul  7 23:36:06 2020 daemon.notice netifd: Interface 'wan' is setting up now
Tue Jul  7 23:36:06 2020 daemon.notice netifd: Interface 'wan6' is enabled
Tue Jul  7 23:36:06 2020 daemon.err insmod: module is already loaded - slhc
Tue Jul  7 23:36:06 2020 daemon.err insmod: module is already loaded - ppp_generic
Tue Jul  7 23:36:06 2020 daemon.err insmod: module is already loaded - pppox
Tue Jul  7 23:36:06 2020 daemon.err insmod: module is already loaded - pppoe
Tue Jul  7 23:36:06 2020 daemon.info pppd[4075]: Plugin rp-pppoe.so loaded.
Tue Jul  7 23:36:06 2020 daemon.info pppd[4075]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Tue Jul  7 23:36:06 2020 daemon.notice pppd[4075]: pppd 2.4.7 started by root, uid 0
Tue Jul  7 23:36:11 2020 daemon.info pppd[4075]: PPP session is 1212
Tue Jul  7 23:36:11 2020 daemon.warn pppd[4075]: Connected to (info removed) via interface eth0.2
Tue Jul  7 23:36:11 2020 kern.info kernel: [  733.085583] pppoe-wan: renamed from ppp0
Tue Jul  7 23:36:11 2020 daemon.info pppd[4075]: Renamed interface ppp0 to pppoe-wan
Tue Jul  7 23:36:11 2020 daemon.info pppd[4075]: Using interface pppoe-wan
Tue Jul  7 23:36:11 2020 daemon.notice pppd[4075]: Connect: pppoe-wan <--> eth0.2
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq-dhcp[2317]: DHCPINFORM(br-lan) 192.168.1.165 14:cc:20:bb:06:2c
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq-dhcp[2317]: DHCPACK(br-lan) 192.168.1.165 14:cc:20:bb:06:2c user-PC64
Tue Jul  7 23:36:12 2020 daemon.info pppd[4075]: CHAP authentication succeeded: CHAP authentication success, unit 613
Tue Jul  7 23:36:12 2020 daemon.notice pppd[4075]: CHAP authentication succeeded
Tue Jul  7 23:36:12 2020 daemon.notice pppd[4075]: peer from calling number 00:30:88:1B:1B:01 authorized
Tue Jul  7 23:36:12 2020 daemon.notice pppd[4075]: local  IP address 65.7.5.29
Tue Jul  7 23:36:12 2020 daemon.notice pppd[4075]: remote IP address 65.7.5.1
Tue Jul  7 23:36:12 2020 daemon.notice pppd[4075]: primary   DNS address 68.94.156.15
Tue Jul  7 23:36:12 2020 daemon.notice pppd[4075]: secondary DNS address 68.94.157.15
Tue Jul  7 23:36:12 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Tue Jul  7 23:36:12 2020 daemon.notice netifd: Interface 'wan' is now up
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: reading /tmp/resolv.conf.auto
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain test
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain onion
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain localhost
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain local
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain invalid
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain bind
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using local addresses only for domain lan
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using nameserver 9.9.9.9#53
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using nameserver 208.67.222.222#53
Tue Jul  7 23:36:12 2020 daemon.info dnsmasq[2317]: using nameserver 8.8.8.8#53
Tue Jul  7 23:36:13 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Tue Jul  7 23:36:22 2020 daemon.info pppd[4075]: No response to 5 echo-requests
Tue Jul  7 23:36:22 2020 daemon.notice pppd[4075]: Serial link appears to be disconnected.
Tue Jul  7 23:36:22 2020 daemon.info pppd[4075]: Connect time 0.2 minutes.
Tue Jul  7 23:36:22 2020 daemon.info pppd[4075]: Sent 24415 bytes, received 95367 bytes.
Tue Jul  7 23:36:22 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Tue Jul  7 23:36:22 2020 daemon.notice netifd: Interface 'wan' has lost the connection
Tue Jul  7 23:36:22 2020 daemon.warn dnsmasq[2317]: no servers found in /tmp/resolv.conf.auto, will retry
Tue Jul  7 23:36:25 2020 daemon.notice pppd[4075]: Connection terminated.
Tue Jul  7 23:36:25 2020 daemon.info pppd[4075]: Sent PADT
Tue Jul  7 23:36:25 2020 daemon.info pppd[4075]: Exit.
Tue Jul  7 23:36:25 2020 daemon.notice netifd: Interface 'wan' is now down
Tue Jul  7 23:36:25 2020 kern.info kernel: [  746.337540] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
Tue Jul  7 23:36:25 2020 kern.info kernel: [  746.358642] eth0: link down
Tue Jul  7 23:36:25 2020 daemon.notice netifd: Interface 'wan' is disabled
Tue Jul  7 23:36:25 2020 daemon.notice netifd: Interface 'wan6' is disabled
Tue Jul  7 23:36:25 2020 kern.info kernel: [  746.381033] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Tue Jul  7 23:36:25 2020 kern.info kernel: [  746.388379] eth0: link up (1000Mbps/Full duplex)
Tue Jul  7 23:36:25 2020 kern.info kernel: [  746.393110] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Tue Jul  7 23:36:25 2020 daemon.notice netifd: Interface 'wan' is enabled
Tue Jul  7 23:36:25 2020 daemon.notice netifd: Interface 'wan' is setting up now
Tue Jul  7 23:36:25 2020 daemon.notice netifd: Interface 'wan6' is enabled
Tue Jul  7 23:36:25 2020 daemon.err insmod: module is already loaded - slhc
Tue Jul  7 23:36:25 2020 daemon.err insmod: module is already loaded - ppp_generic
Tue Jul  7 23:36:25 2020 daemon.err insmod: module is already loaded - pppox
Tue Jul  7 23:36:25 2020 daemon.err insmod: module is already loaded - pppoe
Tue Jul  7 23:36:25 2020 daemon.info pppd[4306]: Plugin rp-pppoe.so loaded.
Tue Jul  7 23:36:25 2020 daemon.info pppd[4306]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Tue Jul  7 23:36:25 2020 daemon.notice pppd[4306]: pppd 2.4.7 started by root, uid 0
Tue Jul  7 23:36:35 2020 daemon.info pppd[4306]: PPP session is 121
Tue Jul  7 23:36:35 2020 daemon.warn pppd[4306]: Connected to (info removed) via interface eth0.2
Tue Jul  7 23:36:35 2020 kern.info kernel: [  756.859576] pppoe-wan: renamed from ppp0
Tue Jul  7 23:36:35 2020 daemon.info pppd[4306]: Renamed interface ppp0 to pppoe-wan
Tue Jul  7 23:36:35 2020 daemon.info pppd[4306]: Using interface pppoe-wan
Tue Jul  7 23:36:35 2020 daemon.notice pppd[4306]: Connect: pppoe-wan <--> eth0.2
Tue Jul  7 23:36:37 2020 daemon.info pppd[4306]: CHAP authentication succeeded: CHAP authentication success, unit 2066
Tue Jul  7 23:36:37 2020 daemon.notice pppd[4306]: CHAP authentication succeeded
Tue Jul  7 23:36:37 2020 daemon.notice pppd[4306]: peer from calling number 00:30:88:1B:1B:01 authorized
Tue Jul  7 23:36:37 2020 daemon.notice pppd[4306]: local  IP address 65.7.9.199
Tue Jul  7 23:36:37 2020 daemon.notice pppd[4306]: remote IP address 65.7.9.1
Tue Jul  7 23:36:37 2020 daemon.notice pppd[4306]: primary   DNS address 68.94.156.15
Tue Jul  7 23:36:37 2020 daemon.notice pppd[4306]: secondary DNS address 68.94.157.15
Tue Jul  7 23:36:37 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Tue Jul  7 23:36:37 2020 daemon.notice netifd: Interface 'wan' is now up
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: reading /tmp/resolv.conf.auto
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain test
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain onion
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain localhost
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain local
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain invalid
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain bind
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using local addresses only for domain lan
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using nameserver 9.9.9.9#53
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using nameserver 208.67.222.222#53
Tue Jul  7 23:36:37 2020 daemon.info dnsmasq[2317]: using nameserver 8.8.8.8#53
Tue Jul  7 23:36:38 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
2

daemon.info pppd[3572]: No response to 5 echo-requests

This is a known bug/issue since LEDE 17.

Try changing the ‘LCP echo failure threshold’ value from default '0' to say a value of ‘5’ in LuCI.

config interface 'wan'
      option keepalive '5 5'
3

I changed it and it helped some but still disconnected . I then changed it from the 5 to 7 and there was no difference .
New log info bellow .

Wed Jul  8 02:43:24 2020 daemon.info dnsmasq-dhcp[2302]: DHCPINFORM(br-lan) 192.168.1.130 (info removed)
Wed Jul  8 02:43:24 2020 daemon.info dnsmasq-dhcp[2302]: DHCPACK(br-lan) 192.168.1.130 (info removed) 
Wed Jul  8 02:43:24 2020 daemon.info dnsmasq-dhcp[2302]: DHCPINFORM(br-lan) 192.168.1.165 (info removed)
Wed Jul  8 02:43:24 2020 daemon.info dnsmasq-dhcp[2302]: DHCPACK(br-lan) 192.168.1.165 (info removed)
Wed Jul  8 02:44:19 2020 daemon.info pppd[3090]: No response to 7 echo-requests
Wed Jul  8 02:44:19 2020 daemon.notice pppd[3090]: Serial link appears to be disconnected.
Wed Jul  8 02:44:19 2020 daemon.info pppd[3090]: Connect time 1.7 minutes.
Wed Jul  8 02:44:19 2020 daemon.info pppd[3090]: Sent 190319 bytes, received 2296960 bytes.
Wed Jul  8 02:44:19 2020 daemon.notice netifd: Network device 'pppoe-wan' link is down
Wed Jul  8 02:44:19 2020 daemon.notice netifd: Interface 'wan' has lost the connection
Wed Jul  8 02:44:19 2020 daemon.warn dnsmasq[2302]: no servers found in /tmp/resolv.conf.auto, will retry
Wed Jul  8 02:44:22 2020 daemon.notice pppd[3090]: Connection terminated.
Wed Jul  8 02:44:22 2020 daemon.info pppd[3090]: Sent PADT
Wed Jul  8 02:44:22 2020 daemon.info pppd[3090]: Exit.
Wed Jul  8 02:44:22 2020 daemon.notice netifd: Interface 'wan' is now down
Wed Jul  8 02:44:22 2020 kern.info kernel: [  403.306758] IPv6: ADDRCONF(NETDEV_UP): eth0.2: link is not ready
Wed Jul  8 02:44:22 2020 kern.info kernel: [  403.316125] eth0: link down
Wed Jul  8 02:44:22 2020 daemon.notice netifd: Interface 'wan6' is disabled
Wed Jul  8 02:44:22 2020 daemon.notice netifd: Interface 'wan' is disabled
Wed Jul  8 02:44:22 2020 kern.info kernel: [  403.338844] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Wed Jul  8 02:44:22 2020 kern.info kernel: [  403.346090] eth0: link up (1000Mbps/Full duplex)
Wed Jul  8 02:44:22 2020 kern.info kernel: [  403.356329] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Wed Jul  8 02:44:22 2020 daemon.notice netifd: Interface 'wan6' is enabled
Wed Jul  8 02:44:22 2020 daemon.notice netifd: Interface 'wan' is enabled
Wed Jul  8 02:44:22 2020 daemon.notice netifd: Interface 'wan' is setting up now
Wed Jul  8 02:44:22 2020 daemon.err insmod: module is already loaded - slhc
Wed Jul  8 02:44:22 2020 daemon.err insmod: module is already loaded - ppp_generic
Wed Jul  8 02:44:22 2020 daemon.err insmod: module is already loaded - pppox
Wed Jul  8 02:44:22 2020 daemon.err insmod: module is already loaded - pppoe
Wed Jul  8 02:44:22 2020 daemon.info pppd[3607]: Plugin rp-pppoe.so loaded.
Wed Jul  8 02:44:22 2020 daemon.info pppd[3607]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.7
Wed Jul  8 02:44:22 2020 daemon.notice pppd[3607]: pppd 2.4.7 started by root, uid 0
Wed Jul  8 02:44:26 2020 daemon.info dnsmasq-dhcp[2302]: DHCPINFORM(br-lan) 192.168.1.130 d4:be:d9:a5:69:0d
Wed Jul  8 02:44:26 2020 daemon.info dnsmasq-dhcp[2302]: DHCPACK(br-lan) 192.168.1.130 d4:be:d9:a5:69:0d user-PC64
Wed Jul  8 02:44:26 2020 daemon.info dnsmasq-dhcp[2302]: DHCPINFORM(br-lan) 192.168.1.165 14:cc:20:bb:06:2c
Wed Jul  8 02:44:26 2020 daemon.info dnsmasq-dhcp[2302]: DHCPACK(br-lan) 192.168.1.165 14:cc:20:bb:06:2c user-PC64
Wed Jul  8 02:44:32 2020 daemon.info pppd[3607]: PPP session is 1351
Wed Jul  8 02:44:32 2020 daemon.warn pppd[3607]: Connected to (info removed) via interface eth0.2
Wed Jul  8 02:44:32 2020 kern.info kernel: [  413.848484] pppoe-wan: renamed from ppp0
Wed Jul  8 02:44:32 2020 daemon.info pppd[3607]: Renamed interface ppp0 to pppoe-wan
Wed Jul  8 02:44:32 2020 daemon.info pppd[3607]: Using interface pppoe-wan
Wed Jul  8 02:44:32 2020 daemon.notice pppd[3607]: Connect: pppoe-wan <--> eth0.2
Wed Jul  8 02:44:33 2020 daemon.info pppd[3607]: CHAP authentication succeeded: CHAP authentication success, unit 708
Wed Jul  8 02:44:33 2020 daemon.notice pppd[3607]: CHAP authentication succeeded
Wed Jul  8 02:44:33 2020 daemon.notice pppd[3607]: peer from calling number 00:30:88:1B:1B:01 authorized
Wed Jul  8 02:44:33 2020 daemon.notice pppd[3607]: local  IP address 65.7.15.165
Wed Jul  8 02:44:33 2020 daemon.notice pppd[3607]: remote IP address 65.7.15.1
Wed Jul  8 02:44:33 2020 daemon.notice pppd[3607]: primary   DNS address 68.94.156.15
Wed Jul  8 02:44:33 2020 daemon.notice pppd[3607]: secondary DNS address 68.94.157.15
Wed Jul  8 02:44:33 2020 daemon.notice netifd: Network device 'pppoe-wan' link is up
Wed Jul  8 02:44:33 2020 daemon.notice netifd: Interface 'wan' is now up
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: reading /tmp/resolv.conf.auto
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain test
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain onion
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain localhost
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain local
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain invalid
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain bind
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using local addresses only for domain lan
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using nameserver 9.9.9.9#53
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using nameserver 208.67.222.222#53
Wed Jul  8 02:44:33 2020 daemon.info dnsmasq[2302]: using nameserver 8.8.8.8#53
Wed Jul  8 02:44:33 2020 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
4

Have you tried a different router (not openwrt) to confirm you do not have an external broadband fault?

5

It works fine on DD-WRT using a build from late 2019. All the builds of DD-WRT from 2020 have broken WAN for me so I tried Gargoyle firmware out and found the pppoe issue there , I then tried OpenWrt to see if it was a issue with Gargoyle or the OpenWrt firmware they build it from and still had connection issues .

If I could fine out more info about DD-WRT pppoe I might be able to apply that info to OpenWrt settings .

6

I guess that there is a compatibility problem with the BRAS/BNG of your ISP and pppd (2.4.7?). You could try a current snapshot build with pppd 2.4.8 but i doubt that it will fix your problem. Anyway it's worth a try...

7

I install the snapshot from today on my Netgear WNDR3700 v2 and even used force link as well and could not hold the connection . The connection was able to handle a little more then yesterday before going down.
Besides from using pppd is there other software I could try for pppoe ?
Where do I find the info about the speed of the pppoe to make sure it is requesting the right speed ?

system log

Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.000000] Linux version 5.4.50 (builder@buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r13743-9e6257c26c)) #0 Wed Jul 8 19:20:19 2020
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] printk: bootconsole [early0] enabled
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] MIPS: machine is Netgear WNDR3700 v2
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] SoC: Atheros AR7161 rev 2
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Initrd not found or empty - disabling initrd
Thu Jul  9 01:19:57 2020 kern.warn kernel: [    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Thu Jul  9 01:19:57 2020 kern.warn kernel: [    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Zone ranges:
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Movable zone start for each node
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Early memory node ranges
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
Thu Jul  9 01:19:57 2020 kern.debug kernel: [    0.000000] On node 0 totalpages: 16384
Thu Jul  9 01:19:57 2020 kern.debug kernel: [    0.000000]   Normal zone: 144 pages used for memmap
Thu Jul  9 01:19:57 2020 kern.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Thu Jul  9 01:19:57 2020 kern.debug kernel: [    0.000000]   Normal zone: 16384 pages, LIFO batch:3
Thu Jul  9 01:19:57 2020 kern.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Thu Jul  9 01:19:57 2020 kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 16240
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.000000] Kernel command line: console=ttyS0,115200 rootfstype=squashfs,jffs2
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Writing ErrCtl register=00000000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Readback ErrCtl register=00000000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] Memory: 57020K/65536K available (5062K kernel code, 186K rwdata, 1168K rodata, 1192K init, 205K bss, 8516K reserved, 0K cma-reserved)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] NR_IRQS: 51
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x32c/0x520 with crng_init=0
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] CPU clock: 680.000 MHz
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5621354254 ns
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.000007] sched_clock: 32 bits at 340MHz, resolution 2ns, wraps every 6316128254ns
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.007803] Calibrating delay loop... 451.58 BogoMIPS (lpj=903168)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.041961] pid_max: default: 32768 minimum: 301
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.046727] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.054041] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.065734] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.075518] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.082422] pinctrl core: initialized pinctrl subsystem
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.089796] NET: Registered protocol family 16
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.399900] PCI host bridge /ahb/apb/pcie-controller@17010000 ranges:
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.406356]  MEM 0x0000000010000000..0x0000000016ffffff
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.411582]   IO 0x0000000000000000..0x0000000000000000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.434257] PCI host bridge to bus 0000:00
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.438413] pci_bus 0000:00: root bus resource [mem 0x10000000-0x16ffffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.445287] pci_bus 0000:00: root bus resource [io  0x0000]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.450857] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.457632] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.466017] pci 0000:00:11.0: [168c:ff1d] type 00 class 0x020000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.472045] pci 0000:00:11.0: reg 0x10: [mem 0x00000000-0x0000ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.478604] pci 0000:00:12.0: [168c:ff1d] type 00 class 0x020000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.484651] pci 0000:00:12.0: reg 0x10: [mem 0x00000000-0x0000ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.491479] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.498126] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.504909] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.514913] clocksource: Switched to clocksource MIPS
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.520993] NET: Registered protocol family 2
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.526209] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.534619] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.542287] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.549337] TCP: Hash tables configured (established 1024 bind 1024)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.555822] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.562387] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.569621] NET: Registered protocol family 1
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.574056] PCI: CLS 0 bytes, default 32
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.581053] workingset: timestamp_bits=14 max_order=14 bucket_order=0
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.593613] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.599485] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.619709] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Thu Jul  9 01:19:57 2020 kern.err kernel: [    0.627725] ar7100-usb-phy 18030000.usb-phy: phy reset is missing
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.634675] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.643113] printk: console [ttyS0] disabled
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.647447] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 10, base_baud = 10625000) is a 16550A
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.656242] printk: console [ttyS0] enabled
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.664587] printk: bootconsole [early0] disabled
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.682417] spi-nor spi0.0: mx25l12805d (16384 Kbytes)
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.687651] 4 fixed-partitions partitions found on MTD device spi0.0
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.693993] Creating 4 MTD partitions on "spi0.0":
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.698789] 0x000000000000-0x000000050000 : "u-boot"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.704609] 0x000000050000-0x000000070000 : "u-boot-env"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.710755] 0x000000070000-0x000000ff0000 : "firmware"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.719507] 2 netgear-fw partitions found on MTD device firmware
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.725554] Creating 2 MTD partitions on "firmware":
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.730527] 0x000000000000-0x00000020b440 : "kernel"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.736289] 0x00000020b440-0x000000f80000 : "rootfs"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.742046] mtd: device 4 (rootfs) set to be root filesystem
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.749235] 1 squashfs-split partitions found on MTD device rootfs
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.755480] 0x000000450000-0x000000f80000 : "rootfs_data"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.761713] 0x000000ff0000-0x000001000000 : "art"
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    0.767829] Realtek RTL8366S ethernet switch driver version 0.2.2
Thu Jul  9 01:19:57 2020 kern.err kernel: [    0.774028] rtl8366s rtl8366s: cannot find mdio node phandle
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.779700] rtl8366s rtl8366s: using GPIO pins 5 (SDA) and 7 (SCK)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.785968] rtl8366s rtl8366s: RTL8366 ver. 1 chip found
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.827165] libphy: rtl8366s: probed
Thu Jul  9 01:19:57 2020 kern.info kernel: [    0.832863] libphy: Fixed MDIO Bus: probed
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.164681] ag71xx 19000000.eth: connected to PHY at fixed-0:00 [uid=00000000, driver=Generic PHY]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.174260] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode: rgmii
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    1.182945] random: fast init done
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.496508] ag71xx 1a000000.eth: connected to PHY at rtl8366s:04 [uid=001cc960, driver=Generic PHY]
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.506152] eth1: Atheros AG71xx at 0xba000000, irq 5, mode: rgmii



Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.512649] i2c /dev entries driver
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.518464] NET: Registered protocol family 10
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.528023] Segment Routing with IPv6
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.531849] NET: Registered protocol family 17
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.536346] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.549285] 8021q: 802.1Q VLAN Support v1.8
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.555475] hctosys: unable to open rtc device (rtc0)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.570370] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.581047] Freeing unused kernel memory: 1192K
Thu Jul  9 01:19:57 2020 kern.warn kernel: [    1.585585] This architecture does not have kernel memory protection.
Thu Jul  9 01:19:57 2020 kern.info kernel: [    1.592014] Run /sbin/init as init process
Thu Jul  9 01:19:57 2020 user.info kernel: [    2.708403] init: Console is alive
Thu Jul  9 01:19:57 2020 user.info kernel: [    2.712128] init: - watchdog -
Thu Jul  9 01:19:57 2020 user.info kernel: [    4.579611] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Thu Jul  9 01:19:57 2020 kern.info kernel: [    4.648340] usbcore: registered new interface driver usbfs
Thu Jul  9 01:19:57 2020 kern.info kernel: [    4.653947] usbcore: registered new interface driver hub
Thu Jul  9 01:19:57 2020 kern.info kernel: [    4.659356] usbcore: registered new device driver usb
Thu Jul  9 01:19:57 2020 kern.info kernel: [    4.670441] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Thu Jul  9 01:19:57 2020 kern.info kernel: [    4.678491] ehci-fsl: Freescale EHCI Host controller driver
Thu Jul  9 01:19:57 2020 kern.info kernel: [    4.685617] ehci-platform: EHCI generic platform driver
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.289818] ehci-platform 1b000000.usb: EHCI Host Controller
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.295505] ehci-platform 1b000000.usb: new USB bus registered, assigned bus number 1
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.303432] ehci-platform 1b000000.usb: irq 3, io mem 0x1b000000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.322924] ehci-platform 1b000000.usb: USB 2.0 started, EHCI 1.00
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.329907] hub 1-0:1.0: USB hub found
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.334071] hub 1-0:1.0: 2 ports detected
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.342605] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.350247] ohci-platform: OHCI generic platform driver
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.355724] ohci-platform 1c000000.usb: Generic Platform OHCI controller
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.362479] ohci-platform 1c000000.usb: new USB bus registered, assigned bus number 2
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.370396] ohci-platform 1c000000.usb: irq 14, io mem 0x1c000000
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.439745] hub 2-0:1.0: USB hub found
Thu Jul  9 01:19:57 2020 kern.info kernel: [    5.443886] hub 2-0:1.0: 2 ports detected
Thu Jul  9 01:19:57 2020 user.info kernel: [    5.449416] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Thu Jul  9 01:19:57 2020 user.info kernel: [    5.459430] init: - preinit -
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    6.626414] random: jshn: uninitialized urandom read (4 bytes read)
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    6.736697] random: jshn: uninitialized urandom read (4 bytes read)
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    7.015918] random: jshn: uninitialized urandom read (4 bytes read)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    7.556739] eth0: link up (1000Mbps/Full duplex)
Thu Jul  9 01:19:57 2020 kern.info kernel: [    7.567028] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Thu Jul  9 01:19:57 2020 kern.warn kernel: [    7.688009] urandom_read: 4 callbacks suppressed
Thu Jul  9 01:19:57 2020 kern.notice kernel: [    7.688017] random: procd: uninitialized urandom read (4 bytes read)
Thu Jul  9 01:19:57 2020 kern.notice kernel: [   12.184893] jffs2: notice: (580) jffs2_build_xattr_subsystem: complete building xattr subsystem, 35 of xdatum (33 unchecked, 2 orphan) and 54 of xref (2 dead, 0 orphan) found.
Thu Jul  9 01:19:57 2020 user.info kernel: [   12.210519] mount_root: switching to jffs2 overlay
Thu Jul  9 01:19:57 2020 kern.warn kernel: [   12.240616] overlayfs: upper fs does not support tmpfile.
Thu Jul  9 01:19:57 2020 user.warn kernel: [   12.266432] urandom-seed: Seeding with /etc/urandom.seed
Thu Jul  9 01:19:57 2020 kern.info kernel: [   12.384290] eth0: link down
Thu Jul  9 01:19:57 2020 user.info kernel: [   12.412455] procd: - early -
Thu Jul  9 01:19:57 2020 user.info kernel: [   12.416724] procd: - watchdog -
Thu Jul  9 01:19:57 2020 user.info kernel: [   13.049909] procd: - watchdog -
Thu Jul  9 01:19:57 2020 user.info kernel: [   13.053361] procd: - ubus -
Thu Jul  9 01:19:57 2020 kern.notice kernel: [   13.090167] random: ubusd: uninitialized urandom read (4 bytes read)
Thu Jul  9 01:19:57 2020 kern.notice kernel: [   13.109068] random: ubusd: uninitialized urandom read (4 bytes read)
Thu Jul  9 01:19:57 2020 user.info kernel: [   13.116876] procd: - init -
Thu Jul  9 01:19:57 2020 user.info kernel: [   14.260392] urngd: v1.0.2 started.
Thu Jul  9 01:19:57 2020 kern.notice kernel: [   14.471966] random: crng init done
Thu Jul  9 01:19:57 2020 user.info kernel: [   15.256108] kmodloader: loading kernel modules from /etc/modules.d/*
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.415239] Mirror/redirect action on
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.448986] u32 classifier
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.451745]     input device check on
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.455394]     Actions configured
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.556171] Loading modules backported from Linux version v5.7-rc3-0-g6a8b55ed4056
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.563785] Backport generated by backports.git v5.7-rc3-1-0-gc0c7d2bb
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.714511] xt_time: kernel timezone is -0000
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.723955] ath9k_pci_owl_loader 0000:00:11.0: enabling device (0000 -> 0002)
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.731331] ath9k_pci_owl_loader 0000:00:12.0: enabling device (0000 -> 0002)
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.748548] ath9k_pci_owl_loader 0000:00:11.0: fixup device configuration
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.760003] ath9k_pci_owl_loader 0000:00:12.0: fixup device configuration
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.777990] pci 0000:00:11.0: [168c:0029] type 00 class 0x028000
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.784065] pci 0000:00:11.0: reg 0x10: [mem 0x10000000-0x1000ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.790378] pci 0000:00:11.0: PME# supported from D0 D3hot
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.796485] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.804203] pci 0000:00:12.0: [168c:0029] type 00 class 0x028000
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.810285] pci 0000:00:12.0: reg 0x10: [mem 0x10010000-0x1001ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.816598] pci 0000:00:12.0: PME# supported from D0 D3hot
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.822662] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.961669] PPP generic driver version 2.4.2
Thu Jul  9 01:19:57 2020 kern.info kernel: [   15.975925] NET: Registered protocol family 24
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.077249] ath9k 0000:00:11.0: enabling device (0000 -> 0002)
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.104223] ath: phy0: Ignoring endianness difference in EEPROM magic bytes.
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.112804] ath: EEPROM regdomain sanitized
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.112808] ath: EEPROM regdomain: 0x64
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.112812] ath: EEPROM indicates we should expect a direct regpair map
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.112827] ath: Country alpha2 being used: 00
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.112830] ath: Regpair used: 0x64
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.126857] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.128723] GPIO line 508 (fixed antenna group 1) hogged as output/high
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.135399] GPIO line 509 (fixed antenna group 1) hogged as output/high
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.142011] GPIO line 510 (fixed antenna group 1) hogged as output/high
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.148626] GPIO line 511 (fixed antenna group 1) hogged as output/high
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.155487] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xb0000000, irq=18
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.162301] ath9k 0000:00:12.0: enabling device (0000 -> 0002)
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.319824] ath: phy1: Ignoring endianness difference in EEPROM magic bytes.
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.328413] ath: EEPROM regdomain sanitized
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.328417] ath: EEPROM regdomain: 0x64
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.328420] ath: EEPROM indicates we should expect a direct regpair map
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.328435] ath: Country alpha2 being used: 00
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.328438] ath: Regpair used: 0x64
Thu Jul  9 01:19:57 2020 kern.debug kernel: [   16.344750] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Thu Jul  9 01:19:57 2020 kern.info kernel: [   16.346843] ieee80211 phy1: Atheros AR9280 Rev:2 mem=0xb0010000, irq=19
Thu Jul  9 01:19:57 2020 user.info kernel: [   16.455323] kmodloader: done loading kernel modules from /etc/modules.d/*
Thu Jul  9 01:19:58 2020 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Thu Jul  9 01:19:58 2020 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: Connected to system UBus
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: started, version 2.81 cachesize 150
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: DNS service limited to local subnets
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: compile time options: IPv6 GNU-getopt no-DBus UBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC no-ID loop-detect inotify dumpfile
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: UBus support enabled: connected to system bus
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain test
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain onion
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain localhost
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain local
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain invalid
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain bind
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: using only locally-known addresses for domain lan
Thu Jul  9 01:19:59 2020 daemon.warn dnsmasq[1071]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: read /etc/hosts - 4 addresses
Thu Jul  9 01:19:59 2020 daemon.info dnsmasq[1071]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses
Thu Jul  9 01:20:00 2020 authpriv.info dropbear[1113]: Not backgrounding
Thu Jul  9 01:20:02 2020 daemon.notice wpa_supplicant[1181]: Successfully initialized wpa_supplicant
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: 8021ad
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: 8021q
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: macvlan
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: veth
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: bridge
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: Network device
Thu Jul  9 01:20:02 2020 user.notice : Added device handler type: tunnel
Thu Jul  9 01:20:08 2020 kern.info kernel: [   29.496014] eth0: link up (1000Mbps/Full duplex)
Thu Jul  9 01:20:08 2020 kern.info kernel: [   29.501491] br-lan: port 1(eth0) entered blocking state
Thu Jul  9 01:20:08 2020 kern.info kernel: [   29.506773] br-lan: port 1(eth0) entered disabled state
Thu Jul  9 01:20:08 2020 kern.info kernel: [   29.512331] device eth0 entered promiscuous mode
Thu Jul  9 01:20:08 2020 kern.info kernel: [   29.555556] br-lan: port 1(eth0) entered blocking state
Thu Jul  9 01:20:08 2020 kern.info kernel: [   29.560830] br-lan: port 1(eth0) entered forwarding state
Thu Jul  9 01:20:08 2020 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
8 

kernel log

[    0.000000] Linux version 5.4.50 (builder@buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r13743-9e6257c26c)) #0 Wed Jul 8 19:20:19 2020
[    0.000000] printk: bootconsole [early0] enabled
[    0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[    0.000000] MIPS: machine is Netgear WNDR3700 v2
[    0.000000] SoC: Atheros AR7161 rev 2
[    0.000000] Initrd not found or empty - disabling initrd
[    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[    0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[    0.000000] Zone ranges:
[    0.000000]   Normal   [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
[    0.000000] On node 0 totalpages: 16384
[    0.000000]   Normal zone: 144 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 16384 pages, LIFO batch:3
[    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 16240
[    0.000000] Kernel command line: console=ttyS0,115200 rootfstype=squashfs,jffs2
[    0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes, linear)
[    0.000000] Writing ErrCtl register=00000000
[    0.000000] Readback ErrCtl register=00000000
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] Memory: 57020K/65536K available (5062K kernel code, 186K rwdata, 1168K rodata, 1192K init, 205K bss, 8516K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] NR_IRQS: 51
[    0.000000] random: get_random_bytes called from start_kernel+0x32c/0x520 with crng_init=0
[    0.000000] CPU clock: 680.000 MHz
[    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5621354254 ns
[    0.000007] sched_clock: 32 bits at 340MHz, resolution 2ns, wraps every 6316128254ns
[    0.007803] Calibrating delay loop... 451.58 BogoMIPS (lpj=903168)
[    0.041961] pid_max: default: 32768 minimum: 301
[    0.046727] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.054041] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.065734] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.075518] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.082422] pinctrl core: initialized pinctrl subsystem
[    0.089796] NET: Registered protocol family 16
[    0.399900] PCI host bridge /ahb/apb/pcie-controller@17010000 ranges:
[    0.406356]  MEM 0x0000000010000000..0x0000000016ffffff
[    0.411582]   IO 0x0000000000000000..0x0000000000000000
[    0.434257] PCI host bridge to bus 0000:00
[    0.438413] pci_bus 0000:00: root bus resource [mem 0x10000000-0x16ffffff]
[    0.445287] pci_bus 0000:00: root bus resource [io  0x0000]
[    0.450857] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[    0.457632] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[    0.466017] pci 0000:00:11.0: [168c:ff1d] type 00 class 0x020000
[    0.472045] pci 0000:00:11.0: reg 0x10: [mem 0x00000000-0x0000ffff]
[    0.478604] pci 0000:00:12.0: [168c:ff1d] type 00 class 0x020000
[    0.484651] pci 0000:00:12.0: reg 0x10: [mem 0x00000000-0x0000ffff]
[    0.491479] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[    0.498126] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
[    0.504909] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
[    0.514913] clocksource: Switched to clocksource MIPS
[    0.520993] NET: Registered protocol family 2
[    0.526209] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    0.534619] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.542287] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
[    0.549337] TCP: Hash tables configured (established 1024 bind 1024)
[    0.555822] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.562387] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
[    0.569621] NET: Registered protocol family 1
[    0.574056] PCI: CLS 0 bytes, default 32
[    0.581053] workingset: timestamp_bits=14 max_order=14 bucket_order=0
[    0.593613] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.599485] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.619709] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
[    0.627725] ar7100-usb-phy 18030000.usb-phy: phy reset is missing
[    0.634675] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.643113] printk: console [ttyS0] disabled
[    0.647447] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 10, base_baud = 10625000) is a 16550A
[    0.656242] printk: console [ttyS0] enabled
[    0.664587] printk: bootconsole [early0] disabled
[    0.682417] spi-nor spi0.0: mx25l12805d (16384 Kbytes)
[    0.687651] 4 fixed-partitions partitions found on MTD device spi0.0
[    0.693993] Creating 4 MTD partitions on "spi0.0":
[    0.698789] 0x000000000000-0x000000050000 : "u-boot"
[    0.704609] 0x000000050000-0x000000070000 : "u-boot-env"
[    0.710755] 0x000000070000-0x000000ff0000 : "firmware"
[    0.719507] 2 netgear-fw partitions found on MTD device firmware
[    0.725554] Creating 2 MTD partitions on "firmware":
[    0.730527] 0x000000000000-0x00000020b440 : "kernel"
[    0.736289] 0x00000020b440-0x000000f80000 : "rootfs"
[    0.742046] mtd: device 4 (rootfs) set to be root filesystem
[    0.749235] 1 squashfs-split partitions found on MTD device rootfs
[    0.755480] 0x000000450000-0x000000f80000 : "rootfs_data"
[    0.761713] 0x000000ff0000-0x000001000000 : "art"
[    0.767829] Realtek RTL8366S ethernet switch driver version 0.2.2
[    0.774028] rtl8366s rtl8366s: cannot find mdio node phandle
[    0.779700] rtl8366s rtl8366s: using GPIO pins 5 (SDA) and 7 (SCK)
[    0.785968] rtl8366s rtl8366s: RTL8366 ver. 1 chip found
[    0.827165] libphy: rtl8366s: probed
[    0.832863] libphy: Fixed MDIO Bus: probed
[    1.164681] ag71xx 19000000.eth: connected to PHY at fixed-0:00 [uid=00000000, driver=Generic PHY]
[    1.174260] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode: rgmii
[    1.182945] random: fast init done
[    1.496508] ag71xx 1a000000.eth: connected to PHY at rtl8366s:04 [uid=001cc960, driver=Generic PHY]
[    1.506152] eth1: Atheros AG71xx at 0xba000000, irq 5, mode: rgmii
[    1.512649] i2c /dev entries driver
[    1.518464] NET: Registered protocol family 10
[    1.528023] Segment Routing with IPv6
[    1.531849] NET: Registered protocol family 17
[    1.536346] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.549285] 8021q: 802.1Q VLAN Support v1.8
[    1.555475] hctosys: unable to open rtc device (rtc0)
[    1.570370] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
[    1.581047] Freeing unused kernel memory: 1192K
[    1.585585] This architecture does not have kernel memory protection.
[    1.592014] Run /sbin/init as init process
[    2.708403] init: Console is alive
[    2.712128] init: - watchdog -
[    4.579611] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    4.648340] usbcore: registered new interface driver usbfs
[    4.653947] usbcore: registered new interface driver hub
[    4.659356] usbcore: registered new device driver usb
[    4.670441] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    4.678491] ehci-fsl: Freescale EHCI Host controller driver
[    4.685617] ehci-platform: EHCI generic platform driver
[    5.289818] ehci-platform 1b000000.usb: EHCI Host Controller
[    5.295505] ehci-platform 1b000000.usb: new USB bus registered, assigned bus number 1
[    5.303432] ehci-platform 1b000000.usb: irq 3, io mem 0x1b000000
[    5.322924] ehci-platform 1b000000.usb: USB 2.0 started, EHCI 1.00
[    5.329907] hub 1-0:1.0: USB hub found
[    5.334071] hub 1-0:1.0: 2 ports detected
[    5.342605] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    5.350247] ohci-platform: OHCI generic platform driver
[    5.355724] ohci-platform 1c000000.usb: Generic Platform OHCI controller
[    5.362479] ohci-platform 1c000000.usb: new USB bus registered, assigned bus number 2
[    5.370396] ohci-platform 1c000000.usb: irq 14, io mem 0x1c000000
[    5.439745] hub 2-0:1.0: USB hub found
[    5.443886] hub 2-0:1.0: 2 ports detected
[    5.449416] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    5.459430] init: - preinit -
[    6.626414] random: jshn: uninitialized urandom read (4 bytes read)
[    6.736697] random: jshn: uninitialized urandom read (4 bytes read)
[    7.015918] random: jshn: uninitialized urandom read (4 bytes read)
[    7.556739] eth0: link up (1000Mbps/Full duplex)
[    7.567028] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    7.688009] urandom_read: 4 callbacks suppressed
[    7.688017] random: procd: uninitialized urandom read (4 bytes read)
[   12.184893] jffs2: notice: (580) jffs2_build_xattr_subsystem: complete building xattr subsystem, 35 of xdatum (33 unchecked, 2 orphan) and 54 of xref (2 dead, 0 orphan) found.
[   12.210519] mount_root: switching to jffs2 overlay
[   12.240616] overlayfs: upper fs does not support tmpfile.
[   12.266432] urandom-seed: Seeding with /etc/urandom.seed
[   12.384290] eth0: link down
[   12.412455] procd: - early -
[   12.416724] procd: - watchdog -
[   13.049909] procd: - watchdog -
[   13.053361] procd: - ubus -
[   13.090167] random: ubusd: uninitialized urandom read (4 bytes read)
[   13.109068] random: ubusd: uninitialized urandom read (4 bytes read)
[   13.116876] procd: - init -
[   14.260392] urngd: v1.0.2 started.
[   14.471966] random: crng init done
[   15.256108] kmodloader: loading kernel modules from /etc/modules.d/*
[   15.415239] Mirror/redirect action on
[   15.448986] u32 classifier
[   15.451745]     input device check on
[   15.455394]     Actions configured
[   15.556171] Loading modules backported from Linux version v5.7-rc3-0-g6a8b55ed4056
[   15.563785] Backport generated by backports.git v5.7-rc3-1-0-gc0c7d2bb
[   15.714511] xt_time: kernel timezone is -0000
[   15.723955] ath9k_pci_owl_loader 0000:00:11.0: enabling device (0000 -> 0002)
[   15.731331] ath9k_pci_owl_loader 0000:00:12.0: enabling device (0000 -> 0002)
[   15.748548] ath9k_pci_owl_loader 0000:00:11.0: fixup device configuration
[   15.760003] ath9k_pci_owl_loader 0000:00:12.0: fixup device configuration
[   15.777990] pci 0000:00:11.0: [168c:0029] type 00 class 0x028000
[   15.784065] pci 0000:00:11.0: reg 0x10: [mem 0x10000000-0x1000ffff]
[   15.790378] pci 0000:00:11.0: PME# supported from D0 D3hot
[   15.796485] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
[   15.804203] pci 0000:00:12.0: [168c:0029] type 00 class 0x028000
[   15.810285] pci 0000:00:12.0: reg 0x10: [mem 0x10010000-0x1001ffff]
[   15.816598] pci 0000:00:12.0: PME# supported from D0 D3hot
[   15.822662] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
[   15.961669] PPP generic driver version 2.4.2
[   15.975925] NET: Registered protocol family 24
[   16.077249] ath9k 0000:00:11.0: enabling device (0000 -> 0002)
[   16.104223] ath: phy0: Ignoring endianness difference in EEPROM magic bytes.
[   16.112804] ath: EEPROM regdomain sanitized
[   16.112808] ath: EEPROM regdomain: 0x64
[   16.112812] ath: EEPROM indicates we should expect a direct regpair map
[   16.112827] ath: Country alpha2 being used: 00
[   16.112830] ath: Regpair used: 0x64
[   16.126857] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[   16.128723] GPIO line 508 (fixed antenna group 1) hogged as output/high
[   16.135399] GPIO line 509 (fixed antenna group 1) hogged as output/high
[   16.142011] GPIO line 510 (fixed antenna group 1) hogged as output/high
[   16.148626] GPIO line 511 (fixed antenna group 1) hogged as output/high
[   16.155487] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xb0000000, irq=18
[   16.162301] ath9k 0000:00:12.0: enabling device (0000 -> 0002)
[   16.319824] ath: phy1: Ignoring endianness difference in EEPROM magic bytes.
[   16.328413] ath: EEPROM regdomain sanitized
[   16.328417] ath: EEPROM regdomain: 0x64
[   16.328420] ath: EEPROM indicates we should expect a direct regpair map
[   16.328435] ath: Country alpha2 being used: 00
[   16.328438] ath: Regpair used: 0x64
[   16.344750] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[   16.346843] ieee80211 phy1: Atheros AR9280 Rev:2 mem=0xb0010000, irq=19
[   16.455323] kmodloader: done loading kernel modules from /etc/modules.d/*
[   29.496014] eth0: link up (1000Mbps/Full duplex)
[   29.501491] br-lan: port 1(eth0) entered blocking state
[   29.506773] br-lan: port 1(eth0) entered disabled state
[   29.512331] device eth0 entered promiscuous mode
[   29.555556] br-lan: port 1(eth0) entered blocking state
[   29.560830] br-lan: port 1(eth0) entered forwarding state
[   30.544368] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   31.687827] eth1: link up (100Mbps/Full duplex)
[   33.382017] br-lan: port 2(wlan0) entered blocking state
[   33.387391] br-lan: port 2(wlan0) entered disabled state
[   33.393051] device wlan0 entered promiscuous mode
[   34.752157] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   34.758739] br-lan: port 2(wlan0) entered blocking state
[   34.764083] br-lan: port 2(wlan0) entered forwarding state
[   36.744212] pppoe-wan: renamed from ppp0
[  269.689938] pppoe-wan: renamed from ppp0
[  315.447393] pppoe-wan: renamed from ppp0
9

fwiw, try increasing the timeout ?

I recall 3 years ago when another LEDE 17 user was witnessing PPPoE disconnections and solved it with these settings

> option keepalive 100 30
>
> I may be mistaken, but above values would offer a 3,000 second (50 hours)
> timeout before router would forcibly disconnect PPP session.

Have you tried 19.07.2 or something older like 17.01.6 or 18.06.4 ?

10

I've been facing the same problem with an Archer C60 V1.

System log has the same

:36:06 2020 daemon.info pppd[3572]: Sent PADT

I read everything I could. I'm on the latest version of Openwrt.... So I went to tries and mistakes

1- I disabled all ipv6 - it kept disconnecting every now and then

2- I disabled all QSM OoS and the PPPoE connection is up for almost 3 days now.

Next week I'll restore the ipv6 back and keep observing.

For now, I'm concluding that my problem is associated wish QSM.