Random reboots - 2 MediaTek MT7620A devices - 21.02.1

I have 2 devices. TPLink Archer C50 v1 and Xiaomi Mini. Both MediaTek MT7620A and were working fine. They were at 21.02 and yesterday i noticed some random reboots. So i decided to upgrade to 21.02.1. Fresh install. Config from scratch. Same behaviour. (OpenWrt 21.02.1 r16325-88151b8303 / LuCI openwrt-21.02 branch git-21.295.67054-13df80d)

After it reboots, i got no log. I took a look on /var/log but no file is there. Did the path changed? How can i track the reboot problem? I forced to write on /var/log but on each reboot all log is gone.

I also have 2 more deviced. Very old, running custom 18.06.8. All of them are DUMB AP. I just use LAN ports. WAN is diconnected.

I tried many resets, changing almost nothing. Only SSID and Wifi Security. Also stopped odhcpd, dnsmasq and firewall and changed Lan IP. Tried to use year 2020 in date, tried to turn off radios (2 and 5 GHz). No luck. Both reboots ramdonly. Sometimes in 2 hours, other, in 2 minutes.

Since both hardwares got the problem, should it be a bug? Log only show messages after reboot, so i cannot find what happens when it reboots.

Looks like sometimes CPU usage is high. But until i realize and try to use TOP, it's normal again.

The most i got today was:

Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.134036] ------------[ cut here ]------------
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.138868] WARNING: CPU: 0 PID: 713 at backports-5.10.68-1/net/mac80211/airtime.c:457 0x875d6180 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.150434] Modules linked in: rt2800soc rt2800mmio rt2800lib pppoe ppp_async iptable_nat xt_state xt_nat xt_conntrack xt_REDIRECT ASQUERADE xt_FLOWOFFLOAD xt_CT rt2x00soc rt2x00mmio rt2x00lib pppox ppp_generic nf_nat nf_flow_table_hw nf_flow_table nf_conntrack mt76x2e mt76x2_common mt76x02_lib mt76 mac8021t_REJECT cfg80211 xt_time xt_tcpudp xt_multiport xt_mark xt_mac xt_limit xt_comment xt_TCPMSS xt_LOG slhc nf_reject_ipv4 nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 iptable_mangleable_filter ip_tables crc_ccitt compat nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 leds_gpio ohci_platform ohci_hcd mph_dr_of ehci_platform ehci_fsl ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.219288] CPU: 0 PID: 713 Comm: mt76-tx phy0 Not tainted 5.4.154 #0
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.225916] Stack : 00000000 8005ad3c 805c0000 805609d8 00000000 00000000 00000000 00000000
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.234463]         00000000 00000000 00000000 00000000 00000000 00000001 87005c60 21fa48d3
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.243015]         87005cf8 00000000 00000000 00000000 00000038 804ebea4 65746e69 2e352064
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.251561]         00000000 000000cc 00000000 00035898 00000000 87005c40 00000000 875d6180
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.260104]         00000009 00000014 00000004 00000002 00000000 802ab600 00000000 806f0000
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.268649]         ...
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.271142] Call Trace:
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.271154] [<8005ad3c>] 0x8005ad3c
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.277247] [<804ebea4>] 0x804ebea4
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.280840] [<875d6180>] 0x875d6180 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.286889] [<802ab600>] 0x802ab600
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.290469] [<80009790>] 0x80009790
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.294039] [<80009798>] 0x80009798
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.297606] [<80023900>] 0x80023900
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.301188] [<875d6180>] 0x875d6180 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.307241] [<800239a8>] 0x800239a8
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.310845] [<875d6180>] 0x875d6180 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.317043] [<875836a0>] 0x875836a0 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.323124] [<875d6368>] 0x875d6368 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.329196] [<875d6448>] 0x875d6448 [mac80211@2ec79643+0x7c470]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.335261] [<80337f78>] 0x80337f78
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.338860] [<87652a24>] 0x87652a24 [mt76x02_lib@0b41df78+0x9ce0]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.345096] [<8752048c>] 0x8752048c [mt76@5c9bc260+0x9440]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.350710] [<875265ac>] 0x875265ac [mt76@5c9bc260+0x9440]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.356316] [<8004596c>] 0x8004596c
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.359918] [<8752048c>] 0x8752048c [mt76@5c9bc260+0x9440]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.365534] [<87654fe4>] 0x87654fe4 [mt76x02_lib@0b41df78+0x9ce0]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.371771] [<8752048c>] 0x8752048c [mt76@5c9bc260+0x9440]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.377388] [<87520520>] 0x87520520 [mt76@5c9bc260+0x9440]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.383003] [<804ed528>] 0x804ed528
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.386589] [<8003ffac>] 0x8003ffac
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.390158] [<8003fe74>] 0x8003fe74
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.393737] [<8003fe74>] 0x8003fe74
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.397315] [<800050b8>] 0x800050b8
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.400891]
Sat Nov 13 00:21:41 2021 kern.warn kernel: [ 4116.402409] ---[ end trace f63f2363d35c1736 ]---

Since i have 2 devices with the same random problem, i thought it could be a bug. At the other side, before upgrading, the devices worked for months with no problem that i could notice until yesterday.

Any tips? How could i track the problem?

Thank's a lot.

I believe /var/log is symlinked to /tmp/var/log so it is still ram filesystem
you can verify this with
ls -lsa /

something you can try is to remove the firmware for the external radio, of course this would cause you to be stuck with 2.4 Ghz probably, but it would verify the real problem if the reboots stop, then you can make a bug report with better information

opkg remove kmod-mt76x2

Thank you.

Yesterday i turned off the 2.4GHz on one TP-Link and 5GHz on the Xiaomi. The TP-Link reboots. The Xiaomi no (20h uptime). I turned 2 GHz off And 5 GHz on @ TP-Link. Uptime: 10h.

This do not make sense since i turned off both radios and they rebooted. Bit i will keep an eye on it. When it reboots, i will try what you suggest.

After more than a day with no reboot (and 2.4 GHz radios off), i decided to turn it on and both "rebooted" after a while. So i removed the kmod-mt76x2 on the TP-Link.

By the way, i changed the log file path. And now i'm not sure about the reboot. I used to tell it reboos since the uptime start again and there was no log (the devices are not easily visible). But i was looking @ uptime and log and i realize that there is no boot log.

The last logs before uptime start from zero is:

Mon Nov 15 11:18:09 2021 kern.info kernel: [   37.182989] ieee80211 phy1: rt2800_rxiq_calibration: Info - RXIQ G_imb=0, Ph_rx=-1
Mon Nov 15 11:18:09 2021 kern.info kernel: [   37.245215] br-lan: port 2(wlan1) entered blocking state
Mon Nov 15 11:18:09 2021 kern.info kernel: [   37.250748] br-lan: port 2(wlan1) entered disabled state
Mon Nov 15 11:18:09 2021 kern.info kernel: [   37.256750] device wlan1 entered promiscuous mode

And the log just after is:

Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.669404] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.676174] br-lan: port 2(wlan1) entered blocking state
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.681652] br-lan: port 2(wlan1) entered forwarding state
Mon Nov 15 12:09:12 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy0.conf (phy wlan0) --> new PHY
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.861233] br-lan: port 3(wlan0) entered blocking state
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.866765] br-lan: port 3(wlan0) entered disabled state
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.872806] device wlan0 entered promiscuous mode
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.877849] br-lan: port 3(wlan0) entered blocking state
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.883324] br-lan: port 3(wlan0) entered forwarding state
Mon Nov 15 12:09:12 2021 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->HT_SCAN
Mon Nov 15 12:09:12 2021 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->ENABLED
Mon Nov 15 12:09:12 2021 daemon.notice hostapd: wlan1: AP-ENABLED
Mon Nov 15 12:09:12 2021 kern.info kernel: [   37.979336] br-lan: port 3(wlan0) entered disabled state
Mon Nov 15 12:09:13 2021 kern.info kernel: [   38.644247] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mon Nov 15 12:09:13 2021 kern.info kernel: [   38.651021] br-lan: port 3(wlan0) entered blocking state
Mon Nov 15 12:09:13 2021 kern.info kernel: [   38.656512] br-lan: port 3(wlan0) entered forwarding state
Mon Nov 15 12:09:13 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Mon Nov 15 12:09:13 2021 daemon.notice hostapd: wlan0: AP-ENABLED
Mon Nov 15 12:09:14 2021 daemon.notice netifd: Network device 'wlan0' link is up
Mon Nov 15 12:09:14 2021 daemon.notice netifd: Network device 'wlan1' link is up
Mon Nov 15 12:09:14 2021 daemon.notice netifd: wan (1751): udhcpc: sending discover

Is there another stuff that could reset the uptime?

Another uptime reset (@ Xiaomi - both radios on, but with low poer (16db) while i was writting. First, the time situation:

Local Time	2021-11-15 17:18:47
Uptime	1h 0m 12s

It means the start was as 16h:18m. There is no log sugesting a reboot ad this time.

Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.000000] Linux version 5.4.154 (builder@buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r16325-88151b8303)) #0 Sun Oct 24 09:01:35 2021
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Board has DDR2
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Analog PMU set to hw control
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Digital PMU set to hw control
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] SoC Type: MediaTek MT7620A ver:2 eco:6
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] printk: bootconsole [early0] enabled
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] CPU0 revision is: 00019650 (MIPS 24KEc)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] MIPS: machine is Xiaomi MiWiFi Mini
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Initrd not found or empty - disabling initrd
Mon Nov 15 15:46:07 2021 kern.warn kernel: [    0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Mon Nov 15 15:46:07 2021 kern.warn kernel: [    0.000000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Zone ranges:
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000]   Normal   [mem 0x0000000000000000-0x0000000007ffffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Movable zone start for each node
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Early memory node ranges
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x0000000007ffffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
Mon Nov 15 15:46:07 2021 kern.debug kernel: [    0.000000] On node 0 totalpages: 32768
Mon Nov 15 15:46:07 2021 kern.debug kernel: [    0.000000]   Normal zone: 288 pages used for memmap
Mon Nov 15 15:46:07 2021 kern.debug kernel: [    0.000000]   Normal zone: 0 pages reserved
Mon Nov 15 15:46:07 2021 kern.debug kernel: [    0.000000]   Normal zone: 32768 pages, LIFO batch:7
Mon Nov 15 15:46:07 2021 kern.debug kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mon Nov 15 15:46:07 2021 kern.debug kernel: [    0.000000] pcpu-alloc: [0] 0
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 32480
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.000000] Kernel command line: console=ttyS0,115200 rootfstype=squashfs,jffs2
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Writing ErrCtl register=00004000
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Readback ErrCtl register=00004000
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] Memory: 122408K/131072K available (5065K kernel code, 211K rwdata, 632K rodata, 1188K init, 213K bss, 8664K reserved, 0K cma-reserved)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] NR_IRQS: 256
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.000000] random: get_random_bytes called from 0x805c7a28 with crng_init=0
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] CPU Clock: 580MHz
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] clocksource: systick: mask: 0xffff max_cycles: 0xffff, max_idle_ns: 583261500 ns
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] systick: enable autosleep mode
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] systick: running - mult: 214748, shift: 32
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6590553264 ns
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.000011] sched_clock: 32 bits at 290MHz, resolution 3ns, wraps every 7405115902ns
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.007625] Calibrating delay loop... 385.02 BogoMIPS (lpj=770048)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.045499] pid_max: default: 32768 minimum: 301
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.050199] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.057266] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.071827] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.081306] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.087994] pinctrl core: initialized pinctrl subsystem
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.094635] NET: Registered protocol family 16
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.357489] PCI host bridge /pcie@10140000 ranges:
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.362109]  MEM 0x0000000020000000..0x000000002fffffff
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.367163]   IO 0x0000000010160000..0x000000001016ffff
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.395183] rt2880_gpio 10000600.gpio: registering 24 gpios
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.400666] rt2880_gpio 10000600.gpio: registering 24 irq handlers
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.407015] rt2880_gpio 10000638.gpio: registering 16 gpios
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.412445] rt2880_gpio 10000638.gpio: registering 16 irq handlers
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.419416] PCI host bridge to bus 0000:00
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.423400] pci_bus 0000:00: root bus resource [mem 0x20000000-0x2fffffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.430025] pci_bus 0000:00: root bus resource [io  0x10160000-0x1016ffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.436703] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.443293] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.451030] pci 0000:00:00.0: [1814:0801] type 01 class 0x060400
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.456854] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x7fffffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.462915] pci 0000:00:00.0: reg 0x14: [mem 0x20200000-0x2020ffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.469072] pci 0000:00:00.0: supports D1
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.472897] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.480928] pci 0000:01:00.0: [14c3:7662] type 00 class 0x028000
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.486809] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x000fffff 64bit]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.493404] pci 0000:01:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.499980] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.508059] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.514471] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 01
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.520906] pci 0000:00:00.0: BAR 0: no space for [mem size 0x80000000]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.527289] pci 0000:00:00.0: BAR 0: failed to assign [mem size 0x80000000]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.534050] pci 0000:00:00.0: BAR 8: assigned [mem 0x20000000-0x200fffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.540646] pci 0000:00:00.0: BAR 9: assigned [mem 0x20100000-0x201fffff pref]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.547659] pci 0000:00:00.0: BAR 1: assigned [mem 0x20200000-0x2020ffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.554264] pci 0000:01:00.0: BAR 0: assigned [mem 0x20000000-0x200fffff 64bit]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.561370] pci 0000:01:00.0: BAR 6: assigned [mem 0x20100000-0x2010ffff pref]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.568365] pci 0000:00:00.0: PCI bridge to [bus 01]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.573182] pci 0000:00:00.0:   bridge window [mem 0x20000000-0x200fffff]
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.579776] pci 0000:00:00.0:   bridge window [mem 0x20100000-0x201fffff pref]
Mon Nov 15 15:46:07 2021 kern.warn kernel: [    0.586905] workqueue: max_active 576 requested for napi_workq is out of range, clamping between 1 and 512
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.601985] clocksource: Switched to clocksource systick
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.608306] thermal_sys: Registered thermal governor 'step_wise'
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.608740] NET: Registered protocol family 2
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.618958] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.626870] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.635039] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.642450] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.649289] TCP: Hash tables configured (established 1024 bind 1024)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.655612] UDP hash table entries: 256 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.661973] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.669040] NET: Registered protocol family 1
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.673330] PCI: CLS 0 bytes, default 32
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.680368] rt-timer 10000100.timer: maximum frequency is 1220Hz
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.688528] workingset: timestamp_bits=14 max_order=15 bucket_order=1
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.703531] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.709199] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.734399] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.744679] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.753837] printk: console [ttyS0] disabled
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.758070] 10000c00.uartlite: ttyS0 at MMIO 0x10000c00 (irq = 20, base_baud = 2500000) is a Palmchip BK-3103
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.767692] printk: console [ttyS0] enabled
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.776155] printk: bootconsole [early0] disabled
Mon Nov 15 15:46:07 2021 kern.warn kernel: [    0.795235] spi spi0.0: force spi mode3
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.810626] spi-nor spi0.0: w25q128 (16384 Kbytes)
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.815610] 7 fixed-partitions partitions found on MTD device spi0.0
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.822108] Creating 7 MTD partitions on "spi0.0":
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.827027] 0x000000000000-0x000000030000 : "u-boot"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.833251] 0x000000030000-0x000000040000 : "u-boot-env"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.839877] 0x000000040000-0x000000050000 : "factory"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.846307] 0x000000050000-0x000000fd0000 : "firmware"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.856702] 2 uimage-fw partitions found on MTD device firmware
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.862833] Creating 2 MTD partitions on "firmware":
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.867945] 0x000000000000-0x0000001e642d : "kernel"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.874297] 0x0000001e642d-0x000000f80000 : "rootfs"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.880440] mtd: device 5 (rootfs) set to be root filesystem
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.888453] 1 squashfs-split partitions found on MTD device rootfs
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.894864] 0x00000050c000-0x000000f80000 : "rootfs_data"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.901544] 0x000000fd0000-0x000000fe0000 : "crash"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.907717] 0x000000fe0000-0x000000ff0000 : "reserved"
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    0.914246] 0x000000ff0000-0x000001000000 : "Bdata"
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.921258] libphy: Fixed MDIO Bus: probed
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.938336] gsw: setting port4 to ephy mode
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.942693] mtk_soc_eth 10100000.ethernet eth0 (uninitialized): port 1 link up (100Mbps/Full duplex)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.952579] mtk_soc_eth 10100000.ethernet: loaded mt7620 driver
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.959427] mtk_soc_eth 10100000.ethernet eth0: mediatek frame engine at 0xb0100000, irq 5
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.968455] rt2880_wdt 10000120.watchdog: Initialized
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.975405] NET: Registered protocol family 10
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.984633] Segment Routing with IPv6
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.988617] NET: Registered protocol family 17
Mon Nov 15 15:46:07 2021 kern.info kernel: [    0.993260] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Mon Nov 15 15:46:07 2021 kern.info kernel: [    1.006506] 8021q: 802.1Q VLAN Support v1.8
Mon Nov 15 15:46:07 2021 kern.info kernel: [    1.028681] VFS: Mounted root (squashfs filesystem) readonly on device 31:5.
Mon Nov 15 15:46:07 2021 kern.info kernel: [    1.042754] Freeing unused kernel memory: 1188K
Mon Nov 15 15:46:07 2021 kern.warn kernel: [    1.047412] This architecture does not have kernel memory protection.
Mon Nov 15 15:46:07 2021 kern.info kernel: [    1.053992] Run /sbin/init as init process
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    1.233941] random: fast init done
Mon Nov 15 15:46:07 2021 user.info kernel: [    2.893173] init: Console is alive
Mon Nov 15 15:46:07 2021 user.info kernel: [    2.897083] init: - watchdog -
Mon Nov 15 15:46:07 2021 user.info kernel: [    5.278243] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.648692] usbcore: registered new interface driver usbfs
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.654485] usbcore: registered new interface driver hub
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.660057] usbcore: registered new device driver usb
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.672111] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.680689] ehci-fsl: Freescale EHCI Host controller driver
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.687987] ehci-platform: EHCI generic platform driver
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.703748] phy phy-usbphy.0: remote usb device wakeup disabled
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.709812] phy phy-usbphy.0: UTMI 16bit 30MHz
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.714367] ehci-platform 101c0000.ehci: EHCI Host Controller
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.720280] ehci-platform 101c0000.ehci: new USB bus registered, assigned bus number 1
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.728522] ehci-platform 101c0000.ehci: irq 26, io mem 0x101c0000
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.740245] ehci-platform 101c0000.ehci: USB 2.0 started, EHCI 1.00
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.747783] hub 1-0:1.0: USB hub found
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.752151] hub 1-0:1.0: 1 port detected
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.762072] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.769732] ohci-platform: OHCI generic platform driver
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.775460] ohci-platform 101c1000.ohci: Generic Platform OHCI controller
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.782486] ohci-platform 101c1000.ohci: new USB bus registered, assigned bus number 2
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.790728] ohci-platform 101c1000.ohci: irq 26, io mem 0x101c1000
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.822349] hub 2-0:1.0: USB hub found
Mon Nov 15 15:46:07 2021 kern.info kernel: [    5.826721] hub 2-0:1.0: 1 port detected
Mon Nov 15 15:46:07 2021 user.info kernel: [    5.835767] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Mon Nov 15 15:46:07 2021 user.info kernel: [    5.847679] init: - preinit -
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    7.602326] random: jshn: uninitialized urandom read (4 bytes read)
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    7.699066] random: jshn: uninitialized urandom read (4 bytes read)
Mon Nov 15 15:46:07 2021 kern.notice kernel: [    7.850633] random: jshn: uninitialized urandom read (4 bytes read)
Mon Nov 15 15:46:07 2021 kern.info kernel: [    8.490647] 8021q: adding VLAN 0 to HW filter on device eth0
Mon Nov 15 15:46:07 2021 kern.notice kernel: [   11.703116] jffs2: notice: (505) jffs2_build_xattr_subsystem: complete building xattr subsystem, 51 of xdatum (12 unchecked, 39 orphan) and 56 of xref (17 dead, 25 orphan) found.
Mon Nov 15 15:46:07 2021 user.info kernel: [   11.722596] mount_root: switching to jffs2 overlay
Mon Nov 15 15:46:07 2021 kern.warn kernel: [   11.735490] overlayfs: upper fs does not support tmpfile.
Mon Nov 15 15:46:07 2021 user.warn kernel: [   11.752432] urandom-seed: Seeding with /etc/urandom.seed
Mon Nov 15 15:46:07 2021 user.info kernel: [   11.917098] procd: - early -
Mon Nov 15 15:46:07 2021 user.info kernel: [   11.921315] procd: - watchdog -
Mon Nov 15 15:46:07 2021 user.info kernel: [   12.559646] procd: - watchdog -
Mon Nov 15 15:46:07 2021 user.info kernel: [   12.672096] procd: - ubus -
Mon Nov 15 15:46:07 2021 kern.warn kernel: [   12.826537] urandom_read: 5 callbacks suppressed
Mon Nov 15 15:46:07 2021 kern.notice kernel: [   12.826546] random: ubusd: uninitialized urandom read (4 bytes read)
Mon Nov 15 15:46:07 2021 kern.notice kernel: [   12.847780] random: ubusd: uninitialized urandom read (4 bytes read)
Mon Nov 15 15:46:07 2021 user.info kernel: [   12.865614] procd: - init -
Mon Nov 15 15:46:07 2021 user.info kernel: [   14.276950] kmodloader: loading kernel modules from /etc/modules.d/*
Mon Nov 15 15:46:07 2021 kern.info kernel: [   14.537681] Loading modules backported from Linux version v5.10.68-0-g4d8524048a35
Mon Nov 15 15:46:07 2021 kern.info kernel: [   14.545522] Backport generated by backports.git v5.10.68-1-0-ga4f9ba32
Mon Nov 15 15:46:07 2021 kern.info kernel: [   14.616037] xt_time: kernel timezone is -0000
Mon Nov 15 15:46:07 2021 kern.err kernel: [   14.844557] mt76x2e 0000:01:00.0: card - bus=0x1, slot = 0x0 irq=4
Mon Nov 15 15:46:07 2021 kern.info kernel: [   14.851216] mt76x2e 0000:01:00.0: ASIC revision: 76120044
Mon Nov 15 15:46:07 2021 user.err kernel: [   14.886795] urngd: jent-rng init failed, err: 2
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.106296] mt76x2e 0000:01:00.0: ROM patch build: 20141115060606a
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.118272] mt76x2e 0000:01:00.0: Firmware Version: 0.0.00
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.123948] mt76x2e 0000:01:00.0: Build: 1
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.128146] mt76x2e 0000:01:00.0: Build Time: 201507311614____
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.143763] mt76x2e 0000:01:00.0: Firmware running!
Mon Nov 15 15:46:07 2021 kern.debug kernel: [   15.151021] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.206789] PPP generic driver version 2.4.2
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.293546] NET: Registered protocol family 24
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.344313] rt2800_wmac 10180000.wmac: loaded eeprom from mtd device "factory"
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.351758] ieee80211 phy1: rt2x00_set_rt: Info - RT chipset 6352, rev 0500 detected
Mon Nov 15 15:46:07 2021 kern.info kernel: [   15.359717] ieee80211 phy1: rt2x00_set_rf: Info - RF chipset 7620 detected
Mon Nov 15 15:46:07 2021 kern.debug kernel: [   15.366890] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
Mon Nov 15 15:46:07 2021 user.info kernel: [   15.375499] kmodloader: done loading kernel modules from /etc/modules.d/*
Mon Nov 15 15:46:10 2021 kern.notice kernel: [   20.393991] random: crng init done
Mon Nov 15 15:46:11 2021 authpriv.info dropbear[1053]: Not backgrounding
Mon Nov 15 15:46:13 2021 daemon.notice wpa_supplicant[1103]: Successfully initialized wpa_supplicant
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: veth
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: bridge
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: Network device
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 15:46:16 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Mon Nov 15 15:46:16 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Mon Nov 15 15:46:17 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Mon Nov 15 15:46:17 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Mon Nov 15 15:46:17 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Mon Nov 15 15:46:17 2021 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd
Mon Nov 15 15:46:18 2021 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount
Mon Nov 15 15:46:18 2021 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led
Mon Nov 15 15:46:18 2021 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics
Mon Nov 15 15:46:18 2021 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp
Mon Nov 15 15:46:21 2021 kern.info kernel: [   30.904697] 8021q: adding VLAN 0 to HW filter on device eth0
Mon Nov 15 15:46:21 2021 kern.info kernel: [   30.958780] br-lan: port 1(eth0.1) entered blocking state
Mon Nov 15 15:46:21 2021 kern.info kernel: [   30.964399] br-lan: port 1(eth0.1) entered disabled state
Mon Nov 15 15:46:21 2021 kern.info kernel: [   30.970337] device eth0.1 entered promiscuous mode
Mon Nov 15 15:46:21 2021 kern.info kernel: [   30.975291] device eth0 entered promiscuous mode
Mon Nov 15 15:46:21 2021 kern.info kernel: [   31.080622] br-lan: port 1(eth0.1) entered blocking state
Mon Nov 15 15:46:21 2021 kern.info kernel: [   31.086232] br-lan: port 1(eth0.1) entered forwarding state
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'lan' is enabled
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'lan' is setting up now
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'lan' is now up
Mon Nov 15 15:46:21 2021 daemon.notice netifd: bridge 'br-lan' link is up
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'lan' has link connectivity
Mon Nov 15 15:46:21 2021 daemon.notice netifd: VLAN 'eth0.1' link is up
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'loopback' is enabled
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'loopback' is setting up now
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'loopback' is now up
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Network device 'eth0' link is up
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Network device 'lo' link is up
Mon Nov 15 15:46:21 2021 daemon.notice netifd: Interface 'loopback' has link connectivity
Mon Nov 15 15:46:22 2021 daemon.info procd: - init complete -
Mon Nov 15 15:46:22 2021 kern.info kernel: [   31.906409] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
Mon Nov 15 15:46:24 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy1.conf (phy wlan1) --> new PHY
Mon Nov 15 15:46:26 2021 kern.info kernel: [   34.324387] ieee80211 phy1: rt2800_rf_self_txdc_cal: Info - RF Tx self calibration start
Mon Nov 15 15:46:26 2021 kern.info kernel: [   34.333207] ieee80211 phy1: rt2800_rf_self_txdc_cal: Info - RF Tx self calibration end
Mon Nov 15 15:46:26 2021 kern.info kernel: [   36.260422] ieee80211 phy1: rt2800_loft_iq_calibration: Info - LOFT Calibration Done!
Mon Nov 15 15:46:26 2021 kern.info kernel: [   36.272430] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Start!
Mon Nov 15 15:46:26 2021 kern.info kernel: [   36.293758] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Done! CH = 0, (gain= 0, phase=3e)
Mon Nov 15 15:46:26 2021 kern.info kernel: [   36.303997] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Start!
Mon Nov 15 15:46:26 2021 kern.info kernel: [   36.325314] ieee80211 phy1: rt2800_iq_search: Info - IQCalibration Done! CH = 1, (gain= 0, phase=3e)
Mon Nov 15 15:46:26 2021 kern.info kernel: [   36.334674] ieee80211 phy1: rt2800_loft_iq_calibration: Info - TX IQ Calibration Done!
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.417727] ieee80211 phy1: rt2800_rxiq_calibration: Info - RXIQ G_imb=0, Ph_rx=-2
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.435738] ieee80211 phy1: rt2800_rxiq_calibration: Info - RXIQ G_imb=0, Ph_rx=-1
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.498635] br-lan: port 2(wlan1) entered blocking state
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.504166] br-lan: port 2(wlan1) entered disabled state
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.510108] device wlan1 entered promiscuous mode
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.766584] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.773443] br-lan: port 2(wlan1) entered blocking state
Mon Nov 15 15:46:27 2021 kern.info kernel: [   36.778946] br-lan: port 2(wlan1) entered forwarding state
Mon Nov 15 15:46:27 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy0.conf (phy wlan0) --> new PHY
Mon Nov 15 16:19:17 2021 kern.info kernel: [   37.064791] br-lan: port 3(wlan0) entered blocking state
Mon Nov 15 16:19:17 2021 kern.info kernel: [   37.070321] br-lan: port 3(wlan0) entered disabled state
Mon Nov 15 16:19:17 2021 kern.info kernel: [   37.076477] device wlan0 entered promiscuous mode
Mon Nov 15 16:19:17 2021 kern.info kernel: [   37.081560] br-lan: port 3(wlan0) entered blocking state
Mon Nov 15 16:19:17 2021 kern.info kernel: [   37.087042] br-lan: port 3(wlan0) entered forwarding state
Mon Nov 15 16:19:17 2021 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->HT_SCAN
Mon Nov 15 16:19:17 2021 daemon.notice hostapd: wlan1: interface state UNINITIALIZED->ENABLED
Mon Nov 15 16:19:17 2021 daemon.notice hostapd: wlan1: AP-ENABLED
Mon Nov 15 16:19:17 2021 kern.info kernel: [   37.326424] br-lan: port 3(wlan0) entered disabled state
Mon Nov 15 16:19:18 2021 kern.info kernel: [   37.872780] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mon Nov 15 16:19:18 2021 kern.info kernel: [   37.879749] br-lan: port 3(wlan0) entered blocking state
Mon Nov 15 16:19:18 2021 kern.info kernel: [   37.885260] br-lan: port 3(wlan0) entered forwarding state
Mon Nov 15 16:19:18 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED
Mon Nov 15 16:19:18 2021 daemon.notice hostapd: wlan0: AP-ENABLED
Mon Nov 15 16:19:18 2021 daemon.notice netifd: Network device 'wlan0' link is up
Mon Nov 15 16:19:18 2021 daemon.notice netifd: Network device 'wlan1' link is up

I removed the final part of the log (WIFI client connecting - post oversized the limit). But there is these associations only on the end. Maybe it reboot with wring time?

I really don't know what to do. Will look for new devices, but got curious about this problem.

Well. I was looking some logs (and log.old). Something interesting. Looks like it always go back do a specific point (time).

11:17:52

Mon Nov 15 11:36:22 2021 daemon.info hostapd: wlan1: STA 54:a8:84a:43:e2:4c IEEE 802.11: authenticated
Mon Nov 15 11:36:22 2021 daemon.info hostapd: wlan1: STA 54:a8:84a:43:e2:4c IEEE 802.11: associated (aid 2)
Mon Nov 15 11:36:23 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 54:a8:84a:43:e2:4c
Mon Nov 15 11:36:23 2021 daemon.info hostapd: wlan1: STA 54:a8:84a:43:e2:4c WPA: pairwise key handshake completed (RSN)
Mon Nov 15 11:17:52 2021 kern.notice kernel: [   20.263674] random: crng init done
Mon Nov 15 11:17:53 2021 authpriv.info dropbear[1053]: Not backgrounding
Mon Nov 15 11:17:55 2021 daemon.notice wpa_supplicant[1103]: Successfully initialized wpa_supplicant
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: veth
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: bridge
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: Network device
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Mon Nov 15 11:17:59 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash

11:17:52 again

Mon Nov 15 11:45:41 2021 daemon.info hostapd: wlan0: STA 58:9a:08:4b:cd:24 IEEE 802.11: associated (aid 3)
Mon Nov 15 11:45:41 2021 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 58:9a:08:4b:cd:24
Mon Nov 15 11:45:41 2021 daemon.info hostapd: wlan0: STA 58:9a:08:4b:cd:24 WPA: pairwise key handshake completed (RSN)
Mon Nov 15 11:45:59 2021 daemon.info hostapd: wlan1: STA b2:de:30:12:84:12 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Nov 15 11:17:52 2021 kern.notice kernel: [   20.279053] random: crng init done
Mon Nov 15 11:17:53 2021 authpriv.info dropbear[1053]: Not backgrounding
Mon Nov 15 11:17:55 2021 daemon.notice wpa_supplicant[1103]: Successfully initialized wpa_supplicant
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: veth
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: bridge
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: Network device
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network

and 11:17:52 again

Mon Nov 15 12:00:17 2021 daemon.info hostapd: wlan1: STA ac:23:45:67:65:32 IEEE 802.11: authenticated
Mon Nov 15 12:00:17 2021 daemon.info hostapd: wlan1: STA ac:23:45:67:65:32 IEEE 802.11: associated (aid 1)
Mon Nov 15 12:00:17 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED ac:23:45:67:65:32
Mon Nov 15 12:00:17 2021 daemon.info hostapd: wlan1: STA ac:23:45:67:65:32 WPA: pairwise key handshake completed (RSN)
Mon Nov 15 11:17:52 2021 kern.notice kernel: [   20.311330] random: crng init done
Mon Nov 15 11:17:53 2021 authpriv.info dropbear[1053]: Not backgrounding
Mon Nov 15 11:17:55 2021 daemon.notice wpa_supplicant[1103]: Successfully initialized wpa_supplicant
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: veth
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: bridge
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: Network device
Mon Nov 15 11:17:55 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Mon Nov 15 11:17:59 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash

11:17:52 one more time

Mon Nov 15 12:06:03 2021 daemon.info hostapd: wlan0: STA 34:65:89:b2:32:11 WPA: pairwise key handshake completed (RSN)
Mon Nov 15 12:07:23 2021 daemon.info hostapd: wlan1: STA 58:9a:08:4b:cd:24 IEEE 802.11: authenticated
Mon Nov 15 12:07:23 2021 daemon.info hostapd: wlan1: STA 58:9a:08:4b:cd:24 IEEE 802.11: associated (aid 2)
Mon Nov 15 12:07:23 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED 58:9a:08:4b:cd:24
Mon Nov 15 12:07:23 2021 daemon.info hostapd: wlan1: STA 58:9a:08:4b:cd:24 WPA: pairwise key handshake completed (RSN)
Mon Nov 15 11:17:52 2021 kern.notice kernel: [   20.454872] random: crng init done
Mon Nov 15 11:17:53 2021 authpriv.info dropbear[1053]: Not backgrounding
Mon Nov 15 11:17:55 2021 daemon.notice wpa_supplicant[1103]: Successfully initialized wpa_supplicant
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: veth
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: bridge
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: Network device
Mon Nov 15 11:17:56 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp
Mon Nov 15 11:17:58 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network
Mon Nov 15 11:17:59 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash
Mon Nov 15 11:17:59 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos
Mon Nov 15 11:17:59 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd
Mon Nov 15 11:17:59 2021 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd

now it changed:

Mon Nov 15 16:15:01 2021 daemon.info hostapd: wlan1: STA ac:23:45:67:65:32 IEEE 802.11: associated (aid 2)
Mon Nov 15 16:15:01 2021 daemon.notice hostapd: wlan1: AP-STA-CONNECTED ac:23:45:67:65:32
Mon Nov 15 16:15:01 2021 daemon.info hostapd: wlan1: STA ac:23:45:67:65:32 WPA: pairwise key handshake completed (RSN)
Mon Nov 15 16:17:33 2021 daemon.info hostapd: wlan1: STA 11:c7:a4:45:31:63 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Nov 15 16:18:02 2021 daemon.info hostapd: wlan1: STA 54:a8:84a:43:e2:4c IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Nov 15 15:46:10 2021 kern.notice kernel: [   20.393991] random: crng init done
Mon Nov 15 15:46:11 2021 authpriv.info dropbear[1053]: Not backgrounding
Mon Nov 15 15:46:13 2021 daemon.notice wpa_supplicant[1103]: Successfully initialized wpa_supplicant
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: veth
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: bridge
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: Network device
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 15:46:16 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp

Anyway, this doesn't help a lot. Only know that when it reboots, the clock got wrong.

The TP-Link, with the kmod-mt76x2 removed, reseted the uptime

Local Time 2021-11-15 20:35:01
Uptime 1h 20m 2s

This means the problem was around 19:15
The log confirm the problem, with no help at all:


Mon Nov 15 17:16:06 2021 daemon.err uhttpd[1253]: luci: accepted login on /admin/status/overview for root from 192.168.1.84
Mon Nov 15 19:09:01 2021 authpriv.info dropbear[5061]: Child connection from 192.168.1.84:50512
Mon Nov 15 19:09:04 2021 authpriv.notice dropbear[5061]: Auth succeeded with blank password for 'root' from 192.168.1.84:50512
Mon Nov 15 19:13:01 2021 authpriv.info dropbear[5061]: Exit (root) from <192.168.1.84:50512>: Error reading: Connection reset by peer
Mon Nov 15 19:14:43 2021 daemon.info hostapd: wlan0: STA 11:c7:a4:45:31:63 IEEE 802.11: authenticated
Mon Nov 15 19:14:43 2021 daemon.info hostapd: wlan0: STA 11:c7:a4:45:31:63 IEEE 802.11: authenticated
Mon Nov 15 19:14:43 2021 daemon.info hostapd: wlan0: STA 11:c7:a4:45:31:63 IEEE 802.11: associated (aid 1)
Mon Nov 15 19:14:43 2021 daemon.notice hostapd: wlan0: AP-STA-CONNECTED b2:de:30:12:84:12
Mon Nov 15 19:14:43 2021 daemon.info hostapd: wlan0: STA 11:c7:a4:45:31:63 WPA: pairwise key handshake completed (RSN)
Mon Nov 15 15:46:10 2021 kern.notice kernel: [   16.089269] random: crng init done
Mon Nov 15 15:46:10 2021 authpriv.info dropbear[1022]: Not backgrounding
Mon Nov 15 15:46:12 2021 daemon.notice wpa_supplicant[1072]: Successfully initialized wpa_supplicant
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: 8021ad
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: 8021q
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: macvlan
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: veth
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: bridge
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: Network device
Mon Nov 15 15:46:13 2021 user.notice : Added device handler type: tunnel
Mon Nov 15 15:46:15 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp

The only suggest is that when a client roams from one AP to another, the AP crashes.
I will config each AP with a different SSID and see what happens. If it does not solve the problem, I will downgrade and see what happens.

I would monitor free memory throughout the day...

I wouldn't expect a lack of memory. One is 64MB and the other 128MB. And both are dumb. No NAT, no WAN port in use. I checked and they are rebooting when nobody is at home. How could the device have no free memory?

But already started monitoring with "top -b > file.txt" Is there a better way to monitor this?

That was fast. Already got a reboot.

cat top.txt | grep free
Mem: 28504K used, 30176K free, 216K shrd, 3092K buff, 8600K cached
Mem: 28516K used, 30164K free, 216K shrd, 3092K buff, 8612K cached
Mem: 28528K used, 30152K free, 216K shrd, 3092K buff, 8620K cached
Mem: 28528K used, 30152K free, 216K shrd, 3092K buff, 8620K cached
Mem: 28560K used, 30120K free, 216K shrd, 3092K buff, 8624K cached
Mem: 28540K used, 30140K free, 216K shrd, 3092K buff, 8628K cached
Mem: 28540K used, 30140K free, 216K shrd, 3092K buff, 8632K cached
Mem: 28584K used, 30096K free, 216K shrd, 3092K buff, 8640K cached
Mem: 28552K used, 30128K free, 216K shrd, 3092K buff, 8644K cached
Mem: 28552K used, 30128K free, 216K shrd, 3092K buff, 8644K cached
Mem: 28552K used, 30128K free, 216K shrd, 3092K buff, 8648K cached
Mem: 28564K used, 30116K free, 216K shrd, 3092K buff, 8652K cached
Mem: 28564K used, 30116K free, 216K shrd, 3092K buff, 8656K cached
Mem: 28596K used, 30084K free, 216K shrd, 3092K buff, 8660K cached
Mem: 28576K used, 30104K free, 216K shrd, 3092K buff, 8660K cached
Mem: 28588K used, 30092K free, 216K shrd, 3092K buff, 8664K cached
Mem: 28612K used, 30068K free, 216K shrd, 3092K buff, 8668K cached
Mem: 28644K used, 30036K free, 216K shrd, 3092K buff, 8672K cached
Mem: 28612K used, 30068K free, 216K shrd, 3092K buff, 8676K cached
Mem: 28612K used, 30068K free, 216K shrd, 3092K buff, 8680K cached
Mem: 28612K used, 30068K free, 216K shrd, 3092K buff, 8680K cached
Mem: 28644K used, 30036K free, 216K shrd, 3092K buff, 8684K cached
Mem: 28612K used, 30068K free, 216K shrd, 3092K buff, 8688K cached
Mem: 28624K used, 30056K free, 216K shrd, 3092K buff, 8692K cached
Mem: 28624K used, 30056K free, 216K shrd, 3092K buff, 8696K cached
Mem: 28624K used, 30056K free, 216K shrd, 3092K buff, 8700K cached
Mem: 28624K used, 30056K free, 216K shrd, 3092K buff, 8700K cached
Mem: 28656K used, 30024K free, 216K shrd, 3092K buff, 8708K cached
Mem: 28656K used, 30024K free, 216K shrd, 3092K buff, 8712K cached
Mem: 28656K used, 30024K free, 216K shrd, 3092K buff, 8716K cached
Mem: 28656K used, 30024K free, 216K shrd, 3092K buff, 8720K cached
Mem: 28624K used, 30056K free, 216K shrd, 3092K buff, 8720K cached
Mem: 28624K used, 30056K free, 216K shrd, 3092K buff, 8724K cached
Mem: 28636K used, 30044K free, 216K shrd, 3092K buff, 8728K cached
Mem: 28668K used, 30012K free, 216K shrd, 3092K buff, 8732K cached
Mem: 28636K used, 30044K free, 216K shrd, 3092K buff, 8736K cached
Mem: 28648K used, 30032K free, 216K shrd, 3092K buff, 8740K cached
Mem: 28680K used, 30000K free, 216K shrd, 3092K buff, 8744K cached
Mem: 28648K used, 30032K free, 216K shrd, 3092K buff, 8748K cached
Mem: 28688K used, 29992K free, 216K shrd, 3092K buff, 8752K cached
Mem: 28688K used, 29992K free, 216K shrd, 3092K buff, 8756K cached
Mem: 28700K used, 29980K free, 216K shrd, 3092K buff, 8764K cached
Mem: 28700K used, 29980K free, 216K shrd, 3092K buff, 8768K cached
Mem: 28732K used, 29948K free, 216K shrd, 3092K buff, 8772K cached
Mem: 28800K used, 29880K free, 216K shrd, 3092K buff, 8776K cached
Mem: 28768K used, 29912K free, 216K shrd, 3092K buff, 8780K cached
Mem: 28788K used, 29892K free, 216K shrd, 3092K buff, 8784K cached
Mem: 28800K used, 29880K free, 216K shrd, 3092K buff, 8788K cached
Mem: 29328K used, 29352K free, 216K shrd, 3092K buff, 8792K cached
Mem: 29128K used, 29552K free, 216K shrd, 3092K buff, 8800K cached
Mem: 29184K used, 29496K free, 216K shrd, 3092K buff, 8800K cached
Mem: 29192K used, 29488K free, 216K shrd, 3092K buff, 8804K cached
Mem: 29220K used, 29460K free, 216K shrd, 3092K buff, 8808K cached
Mem: 29872K used, 28808K free, 220K shrd, 3092K buff, 8824K cached
Mem: 29504K used, 29176K free, 220K shrd, 3092K buff, 8828K cached
Mem: 29524K used, 29156K free, 220K shrd, 3092K buff, 8832K cached
Mem: 29504K used, 29176K free, 220K shrd, 3092K buff, 8836K cached
Mem: 29440K used, 29240K free, 220K shrd, 3092K buff, 8836K cached
Mem: 29516K used, 29164K free, 220K shrd, 3092K buff, 8840K cached
Mem: 29548K used, 29132K free, 220K shrd, 3092K buff, 8844K cached
Mem: 29528K used, 29152K free, 224K shrd, 3092K buff, 8852K cached
Mem: 29560K used, 29120K free, 224K shrd, 3092K buff, 8856K cached
Mem: 29528K used, 29152K free, 224K shrd, 3092K buff, 8860K cached
Mem: 29496K used, 29184K free, 224K shrd, 3092K buff, 8864K cached
Mem: 29560K used, 29120K free, 224K shrd, 3092K buff, 8868K cached
Mem: 29540K used, 29140K free, 224K shrd, 3092K buff, 8868K cached
Mem: 29552K used, 29128K free, 216K shrd, 3092K buff, 8868K cached
Mem: 29976K used, 28704K free, 220K shrd, 3092K buff, 8876K cached
Mem: 29788K used, 28892K free, 220K shrd, 3092K buff, 8884K cached
Mem: 30136K used, 28544K free, 220K shrd, 3092K buff, 8880K cached
Mem: 30008K used, 28672K free, 220K shrd, 3092K buff, 8884K cached

I downgraded the TP-Link. One day and no reboot yet.
Some more days with no reboot, i will downgrade the Xiaomi (that rebooted today).

just for the hell of it, try a master snapshot

(note that its possible something doesn't work right, but I think the state of master branch is good right now)

https://firmware-selector.openwrt.org/

I will wait some more days to be sure the downgrade fixed it.
I can try the snapshot. The goal is to help diagnostic so it could be fixed. But i don't know how to track it. I did what i could and ended up with no ideas...

Ps. it's important to mention that 21.02 also had the same reboot problems. That was why i updated to 20.01.1.

OpenWRT works unstable on some devices. You can try Padavan instead.

Hi,
The problem is exactly the same on the router Newifi D2 (Newifi3) MT7621.
On 21.02.0 / 21.02.1 - random reboots. I had to go back to version 19.08.

1 Like

Hello @uniq . Welcome. Good to know your first post is to enforce the problem. 3 days with no problem with 19.07.8. I also used 19 for a long time. I hope 21 got fixed soon.

@ulmwind never tried padavan. The previous open wrt worked fine, and it's fine yet after downgrade. Maybe a give a try for padavan in the future. Now I first needed to fast fix the problem.

After downgrade onde device, the reboots stopped. The other device itr still rebooting, but less frequently, wich made me "tolerate" the reboots and keep the new version until today.

I have a TP-Link Archer C2 v1 and experience random reboots with 21.02.1.
So far it seems to stop reboots.

Rolled back to 19.07.8 and noticed a speed bump as well. No 21.02.1 for me, wonder if this should be mentioned on the device wiki as well.

@Grzesiek11 Please consider adding a note to the device page. (https://openwrt.org/toh/tp-link/archer_c2_ac750) Thanks!
Something like:

2021-12-25 Currently some users reported issues with TP-Link Archer C2 v1 with the 21.02.1 firmware. These involve infrequent random reboots. It seems that 19.07.8 is stable, if you experience issues with 21.02.1, consider using 19.07.8 as an alternative.

Having the same problem, randomly reboots and no log, my one could work for few days, then randomly you'll see the reboots from the uptime.

Netgear V7500 V1