Internet down very often + logging issues. Asus RT-AC51U

Good Day!
I'm on OpenWrt 21.02.1 r16325-88151b8303 / LuCI openwrt-21.02 branch git-21.295.67054-13df80d
IPv6 is off. WAN connection VLAN PPPoE.
Have an issue with internet going down very often.
The worst thing I can't understand what is going on with system.log:

  1. I clean system.log. Size is 0 b.
  2. Everything works well and system.log becomes a little bit bigger and bigger. 1..3..4 KB filled with something like this:
Sat Dec  4 14:24:50 2021 daemon.info dnsmasq[2629]: read /etc/hosts - 4 addresses
Sat Dec  4 14:24:50 2021 daemon.info dnsmasq[2629]: read /tmp/hosts/dhcp.cfg01411c - 13 addresses
Sat Dec  4 14:24:50 2021 daemon.info dnsmasq-dhcp[2629]: read /etc/ethers - 0 addresses
Sat Dec  4 14:29:33 2021 kern.warn kernel: [  373.172196] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010b, type=4
Sat Dec  4 14:31:12 2021 kern.warn kernel: [  417.185672] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010b, type=4
Sat Dec  4 14:31:27 2021 kern.warn kernel: [  423.531566] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:32:17 2021 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: authenticated
Sat Dec  4 14:32:17 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: associated (aid 1)
Sat Dec  4 14:32:17 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae WPA: pairwise key handshake completed (RSN)
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPDISCOVER(br-lan) 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPOFFER(br-lan) 192.168.1.225 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPREQUEST(br-lan) 192.168.1.225 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPACK(br-lan) 192.168.1.225 5c:6b:d7:02:db:ae Viaomi
Sat Dec  4 14:33:56 2021 kern.warn kernel: [  487.210197] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:33:56 2021 kern.warn kernel: [  487.211779] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:52:39 2021 kern.warn kernel: [  951.841829] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:53:10 2021 kern.warn kernel: [  963.458544] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4type or paste code here
  1. After a random period of time internet (wifi) crashes and upon reconnection system.log has 26 KB. It doesn't contain previous data anymore and has missed time frame, like this:
Sat Dec  4 14:24:52 2021 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Sat Dec  4 14:24:52 2021 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Sat Dec  4 14:24:52 2021 user.notice dnsmasq: Allowing RFC1918 responses for domain plex.direct
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: Connected to system UBus
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: started, version 2.85 cachesize 150
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: DNS service limited to local subnets
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: 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-cryptohash no-DNSSEC no-ID loop-detect inotify dumpfile
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: UBus support enabled: connected to system bus
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain test
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain onion
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain localhost
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain local
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain invalid
.
.
.
Sat Dec  4 14:25:15 2021 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sat Dec  4 14:25:15 2021 kern.info kernel: [   37.743427] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sat Dec  4 14:25:15 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Sat Dec  4 14:25:15 2021 daemon.notice hostapd: wlan0: AP-ENABLED
Sat Dec  4 14:25:15 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: authenticated
Sat Dec  4 14:25:15 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: associated (aid 1)
Sat Dec  4 14:25:15 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 5c:6b:d7:02:db:ae
Sat Dec  4 14:25:15 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae WPA: pairwise key handshake completed (RSN)
Sat Dec  4 15:35:42 2021 daemon.notice netifd: Network device 'wlan0' link is up
Sat Dec  4 15:35:42 2021 daemon.notice netifd: Network device 'wlan1' link is up
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[1166]: exiting on receipt of SIGTERM
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: Connected to system UBus
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: started, version 2.85 cachesize 150
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: DNS service limited to local subnets
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: 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-cryptohash no-DNSSEC no-ID loop-detect inotify dumpfile
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: UBus support enabled: connected to system bus
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq-dhcp[2652]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 1d12h
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain test
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain onion
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain localhost
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain local
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain invalid
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain bind
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain lan
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: reading /tmp/resolv.conf.d/resolv.conf.auto
Sat Dec  4 15:35:43 2021 daemon.info dnsmasq[2652]: using only locally-known addresses for domain test

The worst thing I can't understand what happens before disconnections.
I guess the problem is on the IPS side because I have such disconnections (1-2 times per day) during last two years. But now it happens almost once a hour or two.
ISP says everything is well from their sight and can offer to change router only. I did it once and it didn't help at all. Their routers is locked with no access to logs, so I had to find a router with VLAN and access to logs to find the reason and either buy a good router or to prove ISP the problem is on them.
Any idea that happens with the log and how to fix it and maybe there is an utility what can journal WAN link downs ?
Thanks a lot in advance!

Hi @abash78

Let make things clear, Is nothing to do with your ISP so no need to go thought that of changing it again, PPPoE seems fine also however it seems like is just a Wireless issue only. The Asus RT-AC51U is based on a mediatek MT7610 chipset so possible to have a look around on the forum to see if anyone with the chipset is having the same issue?

Another option is buying a ethernet cable between the ASUS to your computer.

Can you be specific about your symptoms. In particular:

  • does the entire network (wired and wireless) experience issues at the same time, or is it only wireless?
  • When the issues arise, do devices remain connected to wifi or do they drop off?
  • Have you tried pinging various hosts to ascertain the extent of the issue? For example:
    -- ping from one wireless host to another wireless host
    -- ping from one wireless host to a wired host
    -- ping from a wired host to another wired host
    -- ping from wired to the router
    -- ping from wireless to the router
    -- ping a public IP such as 8.8.8.8
    -- ping a public domain such as google.com

This can help identify if the issue is on your local network, your router, or upstream.

1 Like

If you see the OP logs, It clearly states that is a Wireless issue only (hostapd, ieee80211 phy1)

Sat Dec  4 14:29:33 2021 kern.warn kernel: [  373.172196] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010b, type=4
Sat Dec  4 14:31:12 2021 kern.warn kernel: [  417.185672] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010b, type=4
Sat Dec  4 14:31:27 2021 kern.warn kernel: [  423.531566] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:32:17 2021 daemon.notice hostapd: wlan1: AP-STA-DISCONNECTED 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: authenticated
Sat Dec  4 14:32:17 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: associated (aid 1)
Sat Dec  4 14:32:17 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae WPA: pairwise key handshake completed (RSN)
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPDISCOVER(br-lan) 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPOFFER(br-lan) 192.168.1.225 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPREQUEST(br-lan) 192.168.1.225 5c:6b:d7:02:db:ae
Sat Dec  4 14:32:17 2021 daemon.info dnsmasq-dhcp[2629]: DHCPACK(br-lan) 192.168.1.225 5c:6b:d7:02:db:ae Viaomi
Sat Dec  4 14:33:56 2021 kern.warn kernel: [  487.210197] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:33:56 2021 kern.warn kernel: [  487.211779] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:52:39 2021 kern.warn kernel: [  951.841829] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4
Sat Dec  4 14:53:10 2021 kern.warn kernel: [  963.458544] ieee80211 phy1: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010c, type=4type or paste code here

There is a wireless disconnect, but this does not necessarily implicate the router. For example, some mobile phones will disconnect from wifi and fail over to cellular if they detect that they cannot get an internet connection. So we need to know if this happens to all wireless clients, if wired clients are also affected, and the status of the WAN.

1 Like

@psherman @francisuk1989 Thanks a lot for the efforts and for advices!

Looks like the entire network is down - I have a TV connected to the router with a cable, Its connection breaks too.

Wifi goes down, devices drop off. But sometimes wifi stays on without internet connection. To avoid any misunderstandings - after 20 -50 second (up to 2 minutes) connection gets up by itself

When the link is up all the pings are well.
Quite often I can see such messages :
Sat Dec 4 19:55:12 2021 daemon.info dnsmasq[1166]: exiting on receipt of SIGTERM
And these ones:
Sat Dec 4 14:25:13 2021 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)

These ones I just saw:
`Sat Dec 4 16:10:02 2021 kern.info kernel: [ 78.550059] mtk_soc_eth 10100000.ethernet eth0: port 1 link down

Sat Dec 4 16:10:04 2021 kern.info kernel: [ 79.314411] mtk_soc_eth 10100000.ethernet eth0: port 1 link up (100Mbps/Full duplex)`

Here is the latest log, that rewrited the log that was before the connection gets down at about 19:54:

Sat Dec  4 14:24:52 2021 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Sat Dec  4 14:24:52 2021 user.notice dnsmasq: Allowing 127.0.0.0/8 responses
Sat Dec  4 14:24:52 2021 user.notice dnsmasq: Allowing RFC1918 responses for domain plex.direct
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: Connected to system UBus
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: started, version 2.85 cachesize 150
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: DNS service limited to local subnets
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: 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-cryptohash no-DNSSEC no-ID loop-detect inotify dumpfile
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: UBus support enabled: connected to system bus
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain test
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain onion
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain localhost
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain local
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain invalid
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain bind
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain lan
Sat Dec  4 14:24:53 2021 daemon.warn dnsmasq[1166]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: read /etc/hosts - 4 addresses
Sat Dec  4 14:24:53 2021 daemon.info dnsmasq[1166]: read /tmp/hosts/dhcp.cfg01411c - 12 addresses
Sat Dec  4 14:24:55 2021 kern.notice kernel: [   18.471944] random: crng init done
Sat Dec  4 14:24:58 2021 daemon.notice wpa_supplicant[1320]: Successfully initialized wpa_supplicant
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: 8021ad
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: 8021q
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: macvlan
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: veth
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: bridge
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: Network device
Sat Dec  4 14:24:58 2021 user.notice : Added device handler type: tunnel
Sat Dec  4 14:25:01 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Sat Dec  4 14:25:01 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Sat Dec  4 14:25:02 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Sat Dec  4 14:25:02 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Sat Dec  4 14:25:02 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Sat Dec  4 14:25:02 2021 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd
Sat Dec  4 14:25:03 2021 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount
Sat Dec  4 14:25:03 2021 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led
Sat Dec  4 14:25:03 2021 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics
Sat Dec  4 14:25:03 2021 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.621644] 8021q: adding VLAN 0 to HW filter on device eth0
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.632467] device eth0 entered promiscuous mode
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.633211] br-lan: port 1(eth0.1) entered blocking state
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.633226] br-lan: port 1(eth0.1) entered disabled state
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.633662] device eth0.1 entered promiscuous mode
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'lan' is enabled
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'lan' is setting up now
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.644120] br-lan: port 1(eth0.1) entered blocking state
Sat Dec  4 14:25:05 2021 kern.info kernel: [   28.644136] br-lan: port 1(eth0.1) entered forwarding state
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'lan' is now up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: bridge 'br-lan' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'lan' has link connectivity
Sat Dec  4 14:25:05 2021 daemon.notice netifd: VLAN 'eth0.1' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'loopback' is enabled
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'loopback' is setting up now
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'loopback' is now up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'wan' is enabled
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Network device 'eth0' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Network device 'lo' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'loopback' has link connectivity
Sat Dec  4 14:25:05 2021 daemon.notice netifd: VLAN 'eth0.42' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'wan' has link connectivity
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'wan' is setting up now
Sat Dec  4 14:25:06 2021 daemon.err insmod: module is already loaded - slhc
Sat Dec  4 14:25:06 2021 daemon.err insmod: module is already loaded - ppp_generic
Sat Dec  4 14:25:06 2021 daemon.err insmod: module is already loaded - pppox
Sat Dec  4 14:25:06 2021 daemon.err insmod: module is already loaded - pppoe
Sat Dec  4 14:25:06 2021 kern.info kernel: [   29.619849] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Sat Dec  4 14:25:07 2021 user.notice firewall: Reloading firewall due to ifup of lan (br-lan)
Sat Dec  4 14:25:08 2021 daemon.info pppd[1992]: Plugin rp-pppoe.so loaded.
Sat Dec  4 14:25:08 2021 daemon.info pppd[1992]: RP-PPPoE plugin version 3.8p compiled against pppd 2.4.8
Sat Dec  4 14:25:08 2021 daemon.notice pppd[1992]: pppd 2.4.8 started by root, uid 0
Sat Dec  4 14:25:08 2021 daemon.info procd: - init complete -
Sat Dec  4 14:25:09 2021 daemon.info pppd[1992]: PPP session is 49758
Sat Dec  4 14:25:09 2021 daemon.warn pppd[1992]: Connected to 64:00:f1:c4:ab:00 via interface eth0.42
Sat Dec  4 14:25:09 2021 daemon.info pppd[1992]: Renamed interface ppp0 to pppoe-wan
Sat Dec  4 14:25:09 2021 daemon.info pppd[1992]: Using interface pppoe-wan
Sat Dec  4 14:25:09 2021 kern.info kernel: [   32.357403] pppoe-wan: renamed from ppp0
Sat Dec  4 14:25:09 2021 daemon.notice pppd[1992]: Connect: pppoe-wan <--> eth0.42
Sat Dec  4 14:25:10 2021 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sat Dec  4 14:25:10 2021 daemon.notice netifd: Interface 'wan' is now up
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: reading /tmp/resolv.conf.d/resolv.conf.auto
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain test
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain onion
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain localhost
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain local
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain invalid
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain bind
Sat Dec  4 14:25:10 2021 daemon.info dnsmasq[1166]: using only locally-known addresses for domain lan
Sat Dec  4 14:25:11 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy1.conf (phy wlan1) --> new PHY
Sat Dec  4 14:25:12 2021 kern.info kernel: [   34.078325] ieee80211 phy1: rt2800_rf_self_txdc_cal: Info - RF Tx self calibration start
Sat Dec  4 14:25:12 2021 kern.info kernel: [   34.078852] ieee80211 phy1: rt2800_rf_self_txdc_cal: Info - RF Tx self calibration end
Sat Dec  4 14:25:13 2021 user.notice firewall: Reloading firewall due to ifup of wan (pppoe-wan)
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.347112] ieee80211 phy1: rt2800_loft_iq_calibration: Info - LOFT Calibration Done!
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.351112] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Start!
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.365538] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Done! CH = 0, (gain= f, phase= 0)
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.366452] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Start!
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.380862] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Done! CH = 1, (gain= d, phase= 1)
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.380897] ieee80211 phy1: rt2800_loft_iq_calibration: Info - TX IQ Calibration Done!
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.400390] ieee80211 phy1: rt2800_rxiq_calibration: Info - RXIQ G_imb=-1, Ph_rx=-2
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.410759] ieee80211 phy1: rt2800_rxiq_calibration: Info - RXIQ G_imb=0, Ph_rx=-2
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.489519] br-lan: port 2(wlan1) entered blocking state
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.489535] br-lan: port 2(wlan1) entered disabled state
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.490076] device wlan1 entered promiscuous mode
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.760717] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.760978] br-lan: port 2(wlan1) entered blocking state
Sat Dec  4 19:55:08 2021 kern.info kernel: [   37.760992] br-lan: port 2(wlan1) entered forwarding state
Sat Dec  4 19:55:08 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy0.conf (phy wlan0) --> new PHY
Sat Dec  4 19:55:09 2021 kern.info kernel: [   37.809709] br-lan: port 3(wlan0) entered blocking state
Sat Dec  4 19:55:09 2021 kern.info kernel: [   37.809725] br-lan: port 3(wlan0) entered disabled state
Sat Dec  4 19:55:09 2021 kern.info kernel: [   37.810241] device wlan0 entered promiscuous mode
Sat Dec  4 19:55:09 2021 kern.info kernel: [   37.810371] br-lan: port 3(wlan0) entered blocking state
Sat Dec  4 19:55:09 2021 kern.info kernel: [   37.810380] br-lan: port 3(wlan0) entered forwarding state
Sat Dec  4 19:55:09 2021 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->HT_SCAN
Sat Dec  4 19:55:09 2021 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->ENABLED
Sat Dec  4 19:55:09 2021 daemon.notice hostapd: wlan1: AP-ENABLED
Sat Dec  4 19:55:09 2021 kern.info kernel: [   38.223299] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Sat Dec  4 19:55:09 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Sat Dec  4 19:55:09 2021 daemon.notice hostapd: wlan0: AP-ENABLED
Sat Dec  4 19:55:10 2021 daemon.notice netifd: Network device 'wlan0' link is up
Sat Dec  4 19:55:10 2021 daemon.notice netifd: Network device 'wlan1' link is up
Sat Dec  4 19:55:10 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: authenticated
Sat Dec  4 19:55:10 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae IEEE 802.11: associated (aid 1)
Sat Dec  4 19:55:10 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 5c:6b:d7:02:db:ae
Sat Dec  4 19:55:10 2021 daemon.info hostapd: wlan1: STA 5c:6b:d7:02:db:ae WPA: pairwise key handshake completed (RSN)
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[1166]: exiting on receipt of SIGTERM
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: Connected to system UBus
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: started, version 2.85 cachesize 150
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: DNS service limited to local subnets
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: 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-cryptohash no-DNSSEC no-ID loop-detect inotify dumpfile
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: UBus support enabled: connected to system bus
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq-dhcp[2629]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 1d12h
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain test
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain onion
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain localhost
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain local
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain invalid
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain bind
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain lan
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: reading /tmp/resolv.conf.d/resolv.conf.auto
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain test
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain onion
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain localhost
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain local
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain invalid
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain bind
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: using only locally-known addresses for domain lan
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: read /etc/hosts - 4 addresses
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: read /tmp/hosts/dhcp.cfg01411c - 13 addresses
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq-dhcp[2629]: read /etc/ethers - 0 addresses
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: read /etc/hosts - 4 addresses
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq[2629]: read /tmp/hosts/dhcp.cfg01411c - 13 addresses
Sat Dec  4 19:55:12 2021 daemon.info dnsmasq-dhcp[2629]: read /etc/ethers - 0 addresses
Sat Dec  4 19:55:14 2021 daemon.info hostapd: wlan1: STA b2:be:76:21:43:9d IEEE 802.11: authenticated
Sat Dec  4 19:55:14 2021 daemon.info hostapd: wlan1: STA b2:be:76:21:43:9d IEEE 802.11: associated (aid 2)
Sat Dec  4 19:55:15 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED b2:be:76:21:43:9d
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA b2:be:76:21:43:9d WPA: pairwise key handshake completed (RSN)
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA 04:e5:98:7d:81:d6 IEEE 802.11: authenticated
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA 04:e5:98:7d:81:d6 IEEE 802.11: associated (aid 3)
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA b2:be:76:21:43:9c IEEE 802.11: authenticated
Sat Dec  4 19:55:15 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 04:e5:98:7d:81:d6
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA 04:e5:98:7d:81:d6 WPA: pairwise key handshake completed (RSN)
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA b2:be:76:21:43:9c IEEE 802.11: associated (aid 4)
Sat Dec  4 19:55:15 2021 daemon.info dnsmasq-dhcp[2629]: DHCPDISCOVER(br-lan) 04:e5:98:7d:81:d6
Sat Dec  4 19:55:15 2021 daemon.info dnsmasq-dhcp[2629]: DHCPOFFER(br-lan) 192.168.1.215 04:e5:98:7d:81:d6
Sat Dec  4 19:55:15 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED b2:be:76:21:43:9c
Sat Dec  4 19:55:15 2021 daemon.info hostapd: wlan1: STA b2:be:76:21:43:9c WPA: pairwise key handshake completed (RSN)
Sat Dec  4 19:55:16 2021 daemon.info hostapd: wlan1: STA 48:5f:99:71:b8:41 IEEE 802.11: authenticated
Sat Dec  4 19:55:16 2021 daemon.info hostapd: wlan1: STA 48:5f:99:71:b8:41 IEEE 802.11: associated (aid 5)

As I understand wan was down:

Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'wan' is enabled
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Network device 'eth0' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Network device 'lo' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'loopback' has link connectivity
Sat Dec  4 14:25:05 2021 daemon.notice netifd: VLAN 'eth0.42' link is up
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'wan' has link connectivity
Sat Dec  4 14:25:05 2021 daemon.notice netifd: Interface 'wan' is setting up now
.
Sat Dec  4 14:25:10 2021 daemon.notice netifd: Network device 'pppoe-wan' link is up
Sat Dec  4 14:25:10 2021 daemon.notice netifd: Interface 'wan' is now up

1 Like

What about when the link goes down? Do pings still work across different LAN devices (including wireless)?

Seems like you're using DSL -- does that modem show any indications of the issue when it occurs (LEDs, for example, but better would be logs on that device)?

1 Like

Did you check the device's uptime?

i obtain very same error for my dir 320, once i run ddos on it.

Apr 20 21:35:15 ice_device kernel: [ 2927.032091] ieee80211 phy0: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x0109, type=4
Apr 20 21:35:15 ice_device kernel: [ 2932.277907] ieee80211 phy0: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010a, type=4
Apr 20 21:35:29 ice_device kernel: [ 2946.177094] ieee80211 phy0: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x0108, type=4
Apr 20 21:35:34 ice_device kernel: [ 2951.127603] ieee80211 phy0: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x010a, type=4
Apr 20 21:35:35 ice_device kernel: [ 2951.457855] ieee80211 phy0: rt2x00lib_rxdone_read_signal: Warning - Frame received with unrecognized signal, mode=0x0001, signal=0x0109, type=4
Apr 20 21:35:58 ice_device hostapd: wlan0: AP-STA-DISCONNECTED 34:29:12:b7:f0:0e
Apr 20 21:35:58 ice_device hostapd: wlan0: STA 34:29:12:b7:f0:0e IEEE 802.11: disassociated

this is a root cause once # of connection reach about 10000. No memory overflow issue. Just a driver one