search

openwrt / mt76

mac80211 driver for MediaTek MT76x0e, MT76x2e, MT7603, MT7615, MT7628 and MT7688
747 stars 341 forks source link

Wifi unstable and wan connection drops on Xiaomi MI router 4c on SoC MediaTek MT7628AN running on OpenWrt 21.02.0-rc2 stable release #547

Open ghost opened 3 years ago

ghost commented 3 years ago

system log attached below:

wifi disconnect Thu Jun 3 19:55:06 2021 kern.info kernel: [21337.734846] rt3050-esw 10110000.esw: link changed 0x06

after restarting wifi radio Thu Jun 3 20:00:24 2021 daemon.notice hostapd: Remove interface 'wlan0' Thu Jun 3 20:00:24 2021 daemon.notice hostapd: wlan0: interface state ENABLED->DISABLED Thu Jun 3 20:00:24 2021 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED 26:0f:41:a2:14:99 Thu Jun 3 20:00:24 2021 daemon.notice hostapd: wlan0: AP-DISABLED Thu Jun 3 20:00:24 2021 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING Thu Jun 3 20:00:24 2021 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Thu Jun 3 20:00:24 2021 kern.info kernel: [21655.465888] device wlan0 left promiscuous mode Thu Jun 3 20:00:24 2021 kern.info kernel: [21655.470650] br-lan: port 2(wlan0) entered disabled state Thu Jun 3 20:00:27 2021 kern.err kernel: [21658.906057] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Thu Jun 3 20:00:29 2021 daemon.notice netifd: Network device 'wlan0' link is down Thu Jun 3 20:00:31 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy0.conf (phy wlan0) --> new PHY Thu Jun 3 20:00:34 2021 kern.err kernel: [21665.817985] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Thu Jun 3 20:00:36 2021 kern.info kernel: [21667.884073] br-lan: port 2(wlan0) entered blocking state Thu Jun 3 20:00:36 2021 kern.info kernel: [21667.889549] br-lan: port 2(wlan0) entered disabled state Thu Jun 3 20:00:36 2021 kern.info kernel: [21667.895378] device wlan0 entered promiscuous mode Thu Jun 3 20:00:36 2021 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE Thu Jun 3 20:00:36 2021 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->HT_SCAN Thu Jun 3 20:00:39 2021 kern.err kernel: [21670.937919] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Thu Jun 3 20:00:44 2021 kern.err kernel: [21676.057867] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Thu Jun 3 20:00:49 2021 kern.err kernel: [21681.177788] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Thu Jun 3 20:00:55 2021 kern.err kernel: [21686.297726] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Thu Jun 3 20:01:00 2021 kern.err kernel: [21691.417664] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Thu Jun 3 20:01:05 2021 kern.err kernel: [21696.537602] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Thu Jun 3 20:01:10 2021 kern.err kernel: [21701.657538] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Thu Jun 3 20:01:15 2021 kern.err kernel: [21706.777479] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Thu Jun 3 20:01:20 2021 kern.err kernel: [21711.897424] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Thu Jun 3 20:01:25 2021 kern.err kernel: [21717.017355] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Thu Jun 3 20:01:30 2021 kern.err kernel: [21722.137291] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Thu Jun 3 20:01:36 2021 kern.err kernel: [21727.257229] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Thu Jun 3 20:01:41 2021 kern.err kernel: [21732.377165] mt76_wmac 10300000.wmac: MCU message 08 (seq 6) timed out Thu Jun 3 20:01:46 2021 kern.err kernel: [21737.497107] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Thu Jun 3 20:01:51 2021 kern.err kernel: [21742.873039] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Thu Jun 3 20:01:53 2021 kern.info kernel: [21744.922760] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Thu Jun 3 20:01:53 2021 kern.info kernel: [21744.929532] br-lan: port 2(wlan0) entered blocking state Thu Jun 3 20:01:53 2021 kern.info kernel: [21744.934979] br-lan: port 2(wlan0) entered forwarding state Thu Jun 3 20:01:53 2021 daemon.notice netifd: Network device 'wlan0' link is up Thu Jun 3 20:01:53 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED Thu Jun 3 20:01:53 2021 daemon.notice hostapd: wlan0: AP-ENABLED

wan connection drop Thu Jun 3 20:08:38 2021 kern.err kernel: [22150.164143] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Thu Jun 3 20:08:43 2021 kern.err kernel: [22154.516085] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Thu Jun 3 20:08:49 2021 kern.err kernel: [22160.660009] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Thu Jun 3 20:08:54 2021 kern.err kernel: [22165.779948] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Thu Jun 3 20:08:59 2021 kern.err kernel: [22170.899888] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Thu Jun 3 20:09:04 2021 kern.err kernel: [22176.019826] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Thu Jun 3 20:09:09 2021 kern.err kernel: [22181.139765] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Thu Jun 3 20:09:15 2021 kern.err kernel: [22186.259707] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Thu Jun 3 20:09:20 2021 kern.err kernel: [22191.379642] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Thu Jun 3 20:09:25 2021 kern.err kernel: [22196.499585] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Thu Jun 3 20:09:30 2021 kern.err kernel: [22201.619520] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Thu Jun 3 20:09:35 2021 kern.err kernel: [22206.739463] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Thu Jun 3 20:09:40 2021 kern.err kernel: [22211.859397] mt76_wmac 10300000.wmac: MCU message 08 (seq 6) timed out Thu Jun 3 20:09:45 2021 kern.err kernel: [22216.979335] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Thu Jun 3 20:09:50 2021 kern.err kernel: [22222.099276] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Thu Jun 3 20:09:55 2021 kern.err kernel: [22227.219215] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Thu Jun 3 20:10:01 2021 kern.err kernel: [22232.339153] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Thu Jun 3 20:10:06 2021 kern.err kernel: [22237.459096] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Thu Jun 3 20:10:11 2021 kern.err kernel: [22242.579030] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Thu Jun 3 20:10:16 2021 kern.err kernel: [22247.698970] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Thu Jun 3 20:10:21 2021 kern.err kernel: [22252.818917] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Thu Jun 3 20:10:26 2021 kern.err kernel: [22257.938869] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Thu Jun 3 20:10:31 2021 kern.err kernel: [22263.058788] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Thu Jun 3 20:10:36 2021 kern.err kernel: [22268.178725] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Thu Jun 3 20:10:42 2021 kern.err kernel: [22273.298665] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Thu Jun 3 20:10:47 2021 kern.err kernel: [22278.418620] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Thu Jun 3 20:10:52 2021 kern.err kernel: [22283.538544] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Thu Jun 3 20:10:57 2021 kern.err kernel: [22288.658776] mt76_wmac 10300000.wmac: MCU message 08 (seq 6) timed out Thu Jun 3 20:28:51 2021 kern.info kernel: [23362.924721] rt3050-esw 10110000.esw: link changed 0x04

Wifi disconnects randomly and gets restored after restarting the router.

I think there might be bug in kernel driver or implementation of the driver not done properly with openwrt stable release. thank you

ghost commented 3 years ago

After getting below system logs, I disabled 'Disassociate On Low Acknowledgement' as suggested in Wireless FAQ in openwrt page: Fri Jun 11 11:34:39 2021 daemon.info hostapd: wlan0: STA 26:0f:41:a2:14:99 IEEE 802.11: disconnected due to excessive missing ACKs Fri Jun 11 11:34:39 2021 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED 26:0f:41:a2:14:99 Fri Jun 11 11:35:09 2021 daemon.info hostapd: wlan0: STA 26:0f:41:a2:14:99 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)

But still the problem exists, only logs slightly changed: Fri Jun 11 12:24:19 2021 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED 26:0f:41:a2:14:99 Fri Jun 11 12:24:19 2021 daemon.info hostapd: wlan0: STA 26:0f:41:a2:14:99 IEEE 802.11: disassociated due to inactivity Fri Jun 11 12:24:20 2021 daemon.info hostapd: wlan0: STA f26:0f:41:a2:14:99 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)

Then I tried resetting and reflashing the OpenWrt 21.02.0-rc2 firmware, I get no problem for 24 hours after that same thing starts happening again, system log attached: Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.000000] Linux version 5.4.119 (builder@buildhost) (gcc version 8.4.0 (OpenWrt GCC 8.4.0 r16122-c2139eef27)) #0 Fri May 28 14:04:04 2021 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Board has DDR2 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Analog PMU set to hw control Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Digital PMU set to hw control Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] SoC Type: MediaTek MT7628AN ver:1 eco:2 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] printk: bootconsole [early0] enabled Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] CPU0 revision is: 00019655 (MIPS 24KEc) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] MIPS: machine is Xiaomi Mi Router 4C Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Initrd not found or empty - disabling initrd Fri Jun 11 17:35:19 2021 kern.warn kernel: [ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes. Fri Jun 11 17:35:19 2021 kern.warn kernel: [ 0.000000] Primary data cache 32kB, 4-way, PIPT, no aliases, linesize 32 bytes Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Zone ranges: Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Normal [mem 0x0000000000000000-0x0000000003ffffff] Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Movable zone start for each node Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Early memory node ranges Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] node 0: [mem 0x0000000000000000-0x0000000003ffffff] Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff] Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 0.000000] On node 0 totalpages: 16384 Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 0.000000] Normal zone: 144 pages used for memmap Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 0.000000] Normal zone: 0 pages reserved Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 0.000000] Normal zone: 16384 pages, LIFO batch:3 Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 0.000000] pcpu-alloc: [0] 0 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 16240 Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.000000] Kernel command line: console=ttyS0,115200 rootfstype=squashfs,jffs2 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Writing ErrCtl register=0007e009 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Readback ErrCtl register=0007e009 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] Memory: 57508K/65536K available (5058K kernel code, 205K rwdata, 632K rodata, 1200K init, 205K bss, 8028K reserved, 0K cma-reserved) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] NR_IRQS: 256 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] intc: using register map from devicetree Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.000000] random: get_random_bytes called from 0x805c4a28 with crng_init=0 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] CPU Clock: 575MHz Fri Jun 11 17:35:19 2021 kern.crit kernel: [ 0.000000] timer_probe: no matching timers found Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6647862422 ns Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.000010] sched_clock: 32 bits at 287MHz, resolution 3ns, wraps every 7469508094ns Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.007561] Calibrating delay loop... 382.46 BogoMIPS (lpj=764928) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.041415] pid_max: default: 32768 minimum: 301 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.046058] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.053076] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.066338] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.075765] futex hash table entries: 256 (order: -1, 3072 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.082419] pinctrl core: initialized pinctrl subsystem Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.090430] NET: Registered protocol family 16 Fri Jun 11 17:35:19 2021 kern.warn kernel: [ 0.121190] workqueue: max_active 576 requested for napi_workq is out of range, clamping between 1 and 512 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.134654] clocksource: Switched to clocksource MIPS Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.140786] NET: Registered protocol family 2 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.145201] IP idents hash table entries: 2048 (order: 2, 16384 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.153033] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.161154] TCP established hash table entries: 1024 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.168525] TCP bind hash table entries: 1024 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.175321] TCP: Hash tables configured (established 1024 bind 1024) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.181604] UDP hash table entries: 256 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.187933] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes, linear) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.194974] NET: Registered protocol family 1 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.199204] PCI: CLS 0 bytes, default 32 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.207740] workingset: timestamp_bits=14 max_order=14 bucket_order=0 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.222065] squashfs: version 4.0 (2009/01/31) Phillip Lougher Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.227709] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc. Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.253429] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.263842] mt7621_gpio 10000600.gpio: registering 32 gpios Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.269548] mt7621_gpio 10000600.gpio: registering 32 gpios Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.275247] mt7621_gpio 10000600.gpio: registering 32 gpios Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.280935] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.289703] printk: console [ttyS0] disabled Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.293907] 10000c00.uartlite: ttyS0 at MMIO 0x10000c00 (irq = 28, base_baud = 2500000) is a 16550A Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.302652] printk: console [ttyS0] enabled Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.311050] printk: bootconsole [early0] disabled Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.321913] spi-mt7621 10000b00.spi: sys_freq: 191666666 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.338913] spi-nor spi0.0: gd25q128 (16384 Kbytes) Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.343959] 7 fixed-partitions partitions found on MTD device spi0.0 Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.350416] Creating 7 MTD partitions on "spi0.0": Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.355304] 0x000000000000-0x000000020000 : "bootloader" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.361814] 0x000000020000-0x000000030000 : "config" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.368003] 0x000000030000-0x000000040000 : "factory" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.374268] 0x000000040000-0x000000050000 : "crash" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.380326] 0x000000050000-0x000000060000 : "cfg_bak" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.386572] 0x000000060000-0x000000160000 : "overlay" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.392862] 0x000000160000-0x000001000000 : "firmware" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.406013] 2 uimage-fw partitions found on MTD device firmware Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.412091] Creating 2 MTD partitions on "firmware": Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.417155] 0x000000000000-0x0000001e525f : "kernel" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.423398] 0x0000001e525f-0x000000ea0000 : "rootfs" Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.429507] mtd: device 8 (rootfs) set to be root filesystem Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.437455] 1 squashfs-split partitions found on MTD device rootfs Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 0.443828] 0x0000004e0000-0x000000ea0000 : "rootfs_data" Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.451272] libphy: Fixed MDIO Bus: probed Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.467366] rt3050-esw 10110000.esw: link changed 0x00 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.474603] mtk_soc_eth 10100000.ethernet eth0: mediatek frame engine at 0xb0100000, irq 5 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.485061] NET: Registered protocol family 10 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.494228] Segment Routing with IPv6 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.498157] NET: Registered protocol family 17 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.502769] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.515936] 8021q: 802.1Q VLAN Support v1.8 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.527198] VFS: Mounted root (squashfs filesystem) readonly on device 31:8. Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.541209] Freeing unused kernel memory: 1200K Fri Jun 11 17:35:19 2021 kern.warn kernel: [ 0.545836] This architecture does not have kernel memory protection. Fri Jun 11 17:35:19 2021 kern.info kernel: [ 0.552373] Run /sbin/init as init process Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 1.150670] random: fast init done Fri Jun 11 17:35:19 2021 user.info kernel: [ 1.227867] init: Console is alive Fri Jun 11 17:35:19 2021 user.info kernel: [ 1.231729] init: - watchdog - Fri Jun 11 17:35:19 2021 user.info kernel: [ 2.005850] kmodloader: loading kernel modules from /etc/modules-boot.d/ Fri Jun 11 17:35:19 2021 user.info kernel: [ 2.117647] kmodloader: done loading kernel modules from /etc/modules-boot.d/ Fri Jun 11 17:35:19 2021 user.info kernel: [ 2.135575] init: - preinit - Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 3.276485] random: jshn: uninitialized urandom read (4 bytes read) Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 3.368060] random: jshn: uninitialized urandom read (4 bytes read) Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 3.548417] random: jshn: uninitialized urandom read (4 bytes read) Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 6.962660] jffs2: notice: (440) jffs2_build_xattr_subsystem: complete building xattr subsystem, 49 of xdatum (3 unchecked, 39 orphan) and 52 of xref (27 dead, 13 orphan) found. Fri Jun 11 17:35:19 2021 user.info kernel: [ 6.980229] mount_root: switching to jffs2 overlay Fri Jun 11 17:35:19 2021 kern.warn kernel: [ 6.988165] overlayfs: upper fs does not support tmpfile. Fri Jun 11 17:35:19 2021 user.warn kernel: [ 7.000285] urandom-seed: Seeding with /etc/urandom.seed Fri Jun 11 17:35:19 2021 user.info kernel: [ 7.110876] procd: - early - Fri Jun 11 17:35:19 2021 user.info kernel: [ 7.113918] procd: - watchdog - Fri Jun 11 17:35:19 2021 user.info kernel: [ 7.789893] procd: - watchdog - Fri Jun 11 17:35:19 2021 user.info kernel: [ 7.794291] procd: - ubus - Fri Jun 11 17:35:19 2021 kern.warn kernel: [ 7.944622] urandom_read: 3 callbacks suppressed Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 7.944631] random: ubusd: uninitialized urandom read (4 bytes read) Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 7.974508] random: ubusd: uninitialized urandom read (4 bytes read) Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 7.981632] random: ubusd: uninitialized urandom read (4 bytes read) Fri Jun 11 17:35:19 2021 user.info kernel: [ 7.991583] procd: - init - Fri Jun 11 17:35:19 2021 user.info kernel: [ 9.169125] kmodloader: loading kernel modules from /etc/modules.d/ Fri Jun 11 17:35:19 2021 kern.info kernel: [ 9.212129] Loading modules backported from Linux version v5.10.34-0-g0aa66717f684 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 9.219913] Backport generated by backports.git v5.10.34-1-0-g7b5533e1 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 9.286635] xt_time: kernel timezone is -0000 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 9.410558] mt76_wmac 10300000.wmac: ASIC revision: 76280001 Fri Jun 11 17:35:19 2021 user.info kernel: [ 9.519443] urngd: v1.0.2 started. Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 9.723761] random: crng init done Fri Jun 11 17:35:19 2021 kern.notice kernel: [ 9.727261] random: 1 urandom warning(s) missed due to ratelimiting Fri Jun 11 17:35:19 2021 kern.info kernel: [ 10.444928] mt76_wmac 10300000.wmac: Firmware Version: 20151201 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 10.451023] mt76_wmac 10300000.wmac: Build Time: 20151201183641 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 10.490673] mt76_wmac 10300000.wmac: firmware init done Fri Jun 11 17:35:19 2021 kern.debug kernel: [ 10.670489] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht' Fri Jun 11 17:35:19 2021 kern.info kernel: [ 10.782050] PPP generic driver version 2.4.2 Fri Jun 11 17:35:19 2021 kern.info kernel: [ 10.795549] NET: Registered protocol family 24 Fri Jun 11 17:35:19 2021 user.info kernel: [ 10.835055] kmodloader: done loading kernel modules from /etc/modules.d/ Fri Jun 11 17:35:21 2021 user.notice dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses! Fri Jun 11 17:35:21 2021 user.notice dnsmasq: Allowing 127.0.0.0/8 responses Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: Connected to system UBus Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: started, version 2.85 cachesize 150 Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: DNS service limited to local subnets Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: 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 Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: UBus support enabled: connected to system bus Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain test Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain onion Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain localhost Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain local Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain invalid Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain bind Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain lan Fri Jun 11 17:35:22 2021 daemon.warn dnsmasq[982]: no servers found in /tmp/resolv.conf.d/resolv.conf.auto, will retry Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: read /etc/hosts - 4 addresses Fri Jun 11 17:35:22 2021 daemon.info dnsmasq[982]: read /tmp/hosts/dhcp.cfg01411c - 0 addresses Fri Jun 11 17:35:24 2021 daemon.notice wpa_supplicant[1080]: Successfully initialized wpa_supplicant Fri Jun 11 17:35:24 2021 kern.info kernel: [ 17.753063] rt3050-esw 10110000.esw: link changed 0x00 Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: 8021ad Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: 8021q Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: macvlan Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: veth Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: bridge Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: Network device Fri Jun 11 17:35:24 2021 user.notice : Added device handler type: tunnel Fri Jun 11 17:35:27 2021 kern.info kernel: [ 20.831073] rt3050-esw 10110000.esw: link changed 0x02 Fri Jun 11 17:35:27 2021 user.notice ucitrack: Setting up /etc/config/network reload dependency on /etc/config/dhcp Fri Jun 11 17:35:27 2021 user.notice ucitrack: Setting up /etc/config/wireless reload dependency on /etc/config/network Fri Jun 11 17:35:28 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/luci-splash Fri Jun 11 17:35:28 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/qos Fri Jun 11 17:35:28 2021 user.notice ucitrack: Setting up /etc/config/firewall reload dependency on /etc/config/miniupnpd Fri Jun 11 17:35:28 2021 user.notice ucitrack: Setting up /etc/config/dhcp reload dependency on /etc/config/odhcpd Fri Jun 11 17:35:29 2021 user.notice ucitrack: Setting up non-init /etc/config/fstab reload handler: /sbin/block mount Fri Jun 11 17:35:29 2021 user.notice ucitrack: Setting up /etc/config/system reload trigger for non-procd /etc/init.d/led Fri Jun 11 17:35:29 2021 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/luci_statistics Fri Jun 11 17:35:29 2021 user.notice ucitrack: Setting up /etc/config/system reload dependency on /etc/config/dhcp Fri Jun 11 17:35:31 2021 kern.info kernel: [ 24.843943] br-lan: port 1(eth0.1) entered blocking state Fri Jun 11 17:35:31 2021 kern.info kernel: [ 24.849523] br-lan: port 1(eth0.1) entered disabled state Fri Jun 11 17:35:31 2021 kern.info kernel: [ 24.855427] device eth0.1 entered promiscuous mode Fri Jun 11 17:35:31 2021 kern.info kernel: [ 24.860350] device eth0 entered promiscuous mode Fri Jun 11 17:35:31 2021 kern.info kernel: [ 24.930377] br-lan: port 1(eth0.1) entered blocking state Fri Jun 11 17:35:31 2021 kern.info kernel: [ 24.935935] br-lan: port 1(eth0.1) entered forwarding state Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'lan' is enabled Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'lan' is setting up now Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'lan' is now up Fri Jun 11 17:35:31 2021 daemon.notice netifd: bridge 'br-lan' link is up Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'lan' has link connectivity Fri Jun 11 17:35:31 2021 daemon.notice netifd: VLAN 'eth0.1' link is up Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'loopback' is enabled Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'loopback' is setting up now Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'loopback' is now up Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan' is enabled Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan' is setting up now Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan' is now up Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: reading /tmp/resolv.conf.d/resolv.conf.auto Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain test Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain onion Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain localhost Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain local Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain invalid Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan6' is enabled Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain bind Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain lan Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using nameserver 8.8.8.8#53 Fri Jun 11 17:35:31 2021 daemon.info dnsmasq[982]: using nameserver 8.8.4.4#53 Fri Jun 11 17:35:31 2021 daemon.notice netifd: Network device 'eth0' link is up Fri Jun 11 17:35:31 2021 daemon.notice netifd: Network device 'lo' link is up Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'loopback' has link connectivity Fri Jun 11 17:35:31 2021 daemon.notice netifd: VLAN 'eth0.2' link is up Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan' has link connectivity Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan6' has link connectivity Fri Jun 11 17:35:31 2021 daemon.notice netifd: Interface 'wan6' is setting up now Fri Jun 11 17:35:32 2021 kern.info kernel: [ 25.887576] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready Fri Jun 11 17:35:32 2021 user.notice firewall: Reloading firewall due to ifup of lan (br-lan) Fri Jun 11 17:35:32 2021 daemon.err odhcpd[1198]: Failed to send to ff02::1%lan@br-lan (Address not available) Fri Jun 11 17:35:32 2021 daemon.err odhcp6c[1718]: Failed to send RS (Address not available) Fri Jun 11 17:35:34 2021 daemon.info procd: - init complete - Fri Jun 11 17:35:35 2021 user.notice firewall: Reloading firewall due to ifup of wan (eth0.2) Fri Jun 11 17:35:36 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy0.conf (phy wlan0) --> new PHY Fri Jun 11 17:35:36 2021 kern.info kernel: [ 29.548228] br-lan: port 2(wlan0) entered blocking state Fri Jun 11 17:35:36 2021 kern.info kernel: [ 29.553705] br-lan: port 2(wlan0) entered disabled state Fri Jun 11 17:35:36 2021 kern.info kernel: [ 29.559550] device wlan0 entered promiscuous mode Fri Jun 11 17:35:36 2021 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE Fri Jun 11 17:35:36 2021 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->HT_SCAN Fri Jun 11 17:35:36 2021 daemon.notice netifd: Interface 'wan6' is now up Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: reading /tmp/resolv.conf.d/resolv.conf.auto Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain test Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain onion Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain localhost Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain local Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain invalid Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain bind Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using only locally-known addresses for domain lan Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using nameserver 8.8.8.8#53 Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using nameserver 8.8.4.4#53 Fri Jun 11 17:35:36 2021 daemon.info dnsmasq[982]: using nameserver fe80::1%eth0.2#53 Fri Jun 11 17:35:37 2021 user.notice firewall: Reloading firewall due to ifup of wan6 (eth0.2) Fri Jun 11 17:35:37 2021 daemon.notice hostapd: 20/40 MHz operation not permitted on channel pri=11 sec=7 based on overlapping BSSes Fri Jun 11 17:35:37 2021 kern.info kernel: [ 31.208807] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Fri Jun 11 17:35:37 2021 kern.info kernel: [ 31.215570] br-lan: port 2(wlan0) entered blocking state Fri Jun 11 17:35:37 2021 kern.info kernel: [ 31.221018] br-lan: port 2(wlan0) entered forwarding state Fri Jun 11 17:35:37 2021 daemon.notice netifd: Network device 'wlan0' link is up Fri Jun 11 17:35:37 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED Fri Jun 11 17:35:37 2021 daemon.notice hostapd: wlan0: AP-ENABLED Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[982]: exiting on receipt of SIGTERM Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: Connected to system UBus Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: started, version 2.85 cachesize 150 Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: DNS service limited to local subnets Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: 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 Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: UBus support enabled: connected to system bus Sat Jun 12 00:15:48 2021 daemon.info dnsmasq-dhcp[2182]: DHCP, IP range 192.168.1.100 -- 192.168.1.249, lease time 12h Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain test Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain onion Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain localhost Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain local Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain invalid Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain bind Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain lan Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: reading /tmp/resolv.conf.d/resolv.conf.auto Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain test Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain onion Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain localhost Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain local Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain invalid Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain bind Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using only locally-known addresses for domain lan Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using nameserver 8.8.8.8#53 Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using nameserver 8.8.4.4#53 Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: using nameserver fe80::1%eth0.2#53 Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: read /etc/hosts - 4 addresses Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses Sat Jun 12 00:15:48 2021 daemon.info dnsmasq-dhcp[2182]: read /etc/ethers - 0 addresses Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: read /etc/hosts - 4 addresses Sat Jun 12 00:15:48 2021 daemon.info dnsmasq[2182]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses Sat Jun 12 00:15:48 2021 daemon.info dnsmasq-dhcp[2182]: read /etc/ethers - 0 addresses Sat Jun 12 00:17:04 2021 daemon.info hostapd: wlan0: STA 26:0f:41:a2:14:99 IEEE 802.11: associated (aid 1) Sat Jun 12 00:17:04 2021 daemon.notice hostapd: wlan0: AP-STA-CONNECTED 26:0f:41:a2:14:99 Sat Jun 12 00:17:04 2021 daemon.info hostapd: wlan0: STA 26:0f:41:a2:14:99 WPA: pairwise key handshake completed (RSN) Sat Jun 12 00:17:04 2021 daemon.info dnsmasq-dhcp[2182]: DHCPREQUEST(br-lan) 192.168.1.186 26:0f:41:a2:14:99 Sat Jun 12 00:17:04 2021 daemon.info dnsmasq-dhcp[2182]: DHCPACK(br-lan) 192.168.1.186 26:0f:41:a2:14:99 Sat Jun 12 01:32:10 2021 daemon.notice hostapd: wlan0: AP-STA-DISCONNECTED 26:0f:41:a2:14:99 Sat Jun 12 01:32:10 2021 daemon.info hostapd: wlan0: STA 26:0f:41:a2:14:99 IEEE 802.11: disassociated due to inactivity Sat Jun 12 01:32:11 2021 daemon.info hostapd: wlan0: STA f26:0f:41:a2:14:99 IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE) Sat Jun 12 01:33:49 2021 kern.info kernel: [ 4713.766470] rt3050-esw 10110000.esw: link changed 0x12 Sat Jun 12 01:34:17 2021 kern.info kernel: [ 4741.205955] rt3050-esw 10110000.esw: link changed 0x02 Sat Jun 12 01:34:20 2021 kern.info kernel: [ 4743.829828] rt3050-esw 10110000.esw: link changed 0x12 Sat Jun 12 01:34:20 2021 daemon.info dnsmasq[2182]: read /etc/hosts - 4 addresses Sat Jun 12 01:34:20 2021 daemon.info dnsmasq[2182]: read /tmp/hosts/odhcpd - 0 addresses Sat Jun 12 01:34:20 2021 daemon.info dnsmasq[2182]: read /tmp/hosts/dhcp.cfg01411c - 2 addresses Sat Jun 12 01:34:20 2021 daemon.info dnsmasq-dhcp[2182]: read /etc/ethers - 0 addresses Sat Jun 12 01:34:24 2021 daemon.info dnsmasq-dhcp[2182]: DHCPDISCOVER(br-lan) 192.168.1.135 47:3c:21:b3:19:77 Sat Jun 12 01:34:24 2021 daemon.info dnsmasq-dhcp[2182]: DHCPOFFER(br-lan) 192.168.1.135 47:3c:21:b3:19:77 Sat Jun 12 01:34:24 2021 daemon.info dnsmasq-dhcp[2182]: DHCPREQUEST(br-lan) 192.168.1.135 47:3c:21:b3:19:77 Sat Jun 12 01:34:24 2021 daemon.info dnsmasq-dhcp[2182]: DHCPACK(br-lan) 192.168.1.135 47:3c:21:b3:19:77 Sat Jun 12 01:47:27 2021 daemon.err uhttpd[1316]: luci: failed login on / for root from 192.168.1.135 Sat Jun 12 01:47:40 2021 daemon.err uhttpd[1316]: luci: accepted login on / for root from 192.168.1.135 Sat Jun 12 01:48:49 2021 kern.err kernel: [ 5613.006963] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Sat Jun 12 01:48:54 2021 kern.err kernel: [ 5618.126932] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Sat Jun 12 01:48:59 2021 kern.err kernel: [ 5623.246866] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Sat Jun 12 01:49:04 2021 kern.err kernel: [ 5628.366824] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Sat Jun 12 01:49:09 2021 kern.err kernel: [ 5633.486776] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Sat Jun 12 01:49:14 2021 kern.err kernel: [ 5638.606716] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Sat Jun 12 01:49:19 2021 kern.err kernel: [ 5643.726655] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Sat Jun 12 01:49:25 2021 kern.err kernel: [ 5648.846595] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Sat Jun 12 01:49:30 2021 kern.err kernel: [ 5653.966986] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Sat Jun 12 01:49:35 2021 kern.err kernel: [ 5659.086472] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Sat Jun 12 01:49:40 2021 kern.err kernel: [ 5664.206411] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Sat Jun 12 01:49:45 2021 kern.err kernel: [ 5669.326352] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Sat Jun 12 01:49:50 2021 kern.err kernel: [ 5674.446293] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Sat Jun 12 01:49:55 2021 kern.err kernel: [ 5679.566234] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Sat Jun 12 01:50:14 2021 kern.err kernel: [ 5698.766022] mt76_wmac 10300000.wmac: MCU message 08 (seq 6) timed out Sat Jun 12 01:50:20 2021 kern.err kernel: [ 5703.885953] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Sat Jun 12 01:50:25 2021 kern.err kernel: [ 5709.005904] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Sat Jun 12 01:50:30 2021 kern.err kernel: [ 5714.125854] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Sat Jun 12 01:50:35 2021 kern.err kernel: [ 5719.245776] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Sat Jun 12 01:50:40 2021 kern.err kernel: [ 5724.365718] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Sat Jun 12 01:50:45 2021 kern.err kernel: [ 5729.485859] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Sat Jun 12 01:50:50 2021 kern.err kernel: [ 5734.605602] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Sat Jun 12 01:50:55 2021 kern.err kernel: [ 5739.725544] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Sat Jun 12 01:51:01 2021 kern.err kernel: [ 5744.845490] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Sat Jun 12 01:51:06 2021 kern.err kernel: [ 5749.965428] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Sat Jun 12 01:51:11 2021 kern.err kernel: [ 5755.085382] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Sat Jun 12 01:51:16 2021 kern.err kernel: [ 5760.205315] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Sat Jun 12 01:51:21 2021 kern.err kernel: [ 5765.325256] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Sat Jun 12 01:51:26 2021 kern.err kernel: [ 5770.445196] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Sat Jun 12 01:51:31 2021 kern.err kernel: [ 5775.565146] mt76_wmac 10300000.wmac: MCU message 08 (seq 6) timed out Sat Jun 12 01:51:36 2021 kern.err kernel: [ 5780.685087] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Sat Jun 12 01:51:42 2021 kern.err kernel: [ 5785.805033] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Sat Jun 12 01:51:47 2021 kern.err kernel: [ 5790.924976] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Sat Jun 12 01:51:52 2021 kern.err kernel: [ 5796.044958] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Sat Jun 12 01:51:57 2021 kern.err kernel: [ 5801.164864] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Sat Jun 12 01:52:02 2021 kern.err kernel: [ 5806.284807] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Sat Jun 12 01:52:07 2021 kern.err kernel: [ 5811.404753] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Sat Jun 12 01:52:12 2021 kern.err kernel: [ 5816.524699] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Sat Jun 12 01:52:17 2021 kern.err kernel: [ 5821.644643] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Sat Jun 12 01:52:22 2021 kern.err kernel: [ 5826.764588] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Sat Jun 12 01:52:28 2021 kern.err kernel: [ 5831.884532] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Sat Jun 12 01:52:33 2021 kern.err kernel: [ 5837.004484] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Sat Jun 12 01:53:04 2021 daemon.err uhttpd[1316]: luci: accepted login on / for root from 192.168.1.135 Sat Jun 12 01:54:31 2021 daemon.notice hostapd: Remove interface 'wlan0' Sat Jun 12 01:54:31 2021 daemon.notice hostapd: wlan0: interface state ENABLED->DISABLED Sat Jun 12 01:54:32 2021 daemon.notice hostapd: wlan0: AP-DISABLED Sat Jun 12 01:54:32 2021 daemon.notice hostapd: wlan0: CTRL-EVENT-TERMINATING Sat Jun 12 01:54:32 2021 daemon.notice hostapd: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sat Jun 12 01:54:32 2021 kern.info kernel: [ 5956.429457] device wlan0 left promiscuous mode Sat Jun 12 01:54:32 2021 kern.info kernel: [ 5956.434221] br-lan: port 2(wlan0) entered disabled state Sat Jun 12 01:54:36 2021 kern.err kernel: [ 5959.883210] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Sat Jun 12 01:54:37 2021 daemon.notice netifd: Network device 'wlan0' link is down Sat Jun 12 01:54:38 2021 daemon.notice hostapd: Configuration file: /var/run/hostapd-phy0.conf (phy wlan0) --> new PHY Sat Jun 12 01:54:44 2021 kern.err kernel: [ 5967.819133] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Sat Jun 12 01:54:46 2021 kern.info kernel: [ 5969.882118] br-lan: port 2(wlan0) entered blocking state Sat Jun 12 01:54:46 2021 kern.info kernel: [ 5969.887599] br-lan: port 2(wlan0) entered disabled state Sat Jun 12 01:54:46 2021 kern.info kernel: [ 5969.893434] device wlan0 entered promiscuous mode Sat Jun 12 01:54:46 2021 daemon.notice hostapd: wlan0: interface state UNINITIALIZED->COUNTRY_UPDATE Sat Jun 12 01:54:46 2021 daemon.notice hostapd: wlan0: interface state COUNTRY_UPDATE->HT_SCAN Sat Jun 12 01:54:49 2021 kern.err kernel: [ 5972.939080] mt76_wmac 10300000.wmac: MCU message 08 (seq 6) timed out Sat Jun 12 01:54:54 2021 kern.err kernel: [ 5978.059062] mt76_wmac 10300000.wmac: MCU message 08 (seq 7) timed out Sat Jun 12 01:54:59 2021 kern.err kernel: [ 5983.178979] mt76_wmac 10300000.wmac: MCU message 08 (seq 8) timed out Sat Jun 12 01:55:04 2021 kern.err kernel: [ 5988.298930] mt76_wmac 10300000.wmac: MCU message 08 (seq 9) timed out Sat Jun 12 01:55:09 2021 kern.err kernel: [ 5993.418881] mt76_wmac 10300000.wmac: MCU message 08 (seq 10) timed out Sat Jun 12 01:55:14 2021 kern.err kernel: [ 5998.538836] mt76_wmac 10300000.wmac: MCU message 08 (seq 11) timed out Sat Jun 12 01:55:19 2021 kern.err kernel: [ 6003.658779] mt76_wmac 10300000.wmac: MCU message 08 (seq 12) timed out Sat Jun 12 01:55:24 2021 kern.err kernel: [ 6008.778729] mt76_wmac 10300000.wmac: MCU message 08 (seq 13) timed out Sat Jun 12 01:55:30 2021 kern.err kernel: [ 6013.898683] mt76_wmac 10300000.wmac: MCU message 08 (seq 14) timed out Sat Jun 12 01:55:35 2021 kern.err kernel: [ 6019.018632] mt76_wmac 10300000.wmac: MCU message 08 (seq 15) timed out Sat Jun 12 01:55:40 2021 kern.err kernel: [ 6024.138578] mt76_wmac 10300000.wmac: MCU message 08 (seq 1) timed out Sat Jun 12 01:55:45 2021 kern.err kernel: [ 6029.258530] mt76_wmac 10300000.wmac: MCU message 08 (seq 2) timed out Sat Jun 12 01:55:50 2021 kern.err kernel: [ 6034.378490] mt76_wmac 10300000.wmac: MCU message 08 (seq 3) timed out Sat Jun 12 01:55:55 2021 kern.err kernel: [ 6039.498429] mt76_wmac 10300000.wmac: MCU message 08 (seq 4) timed out Sat Jun 12 01:56:01 2021 kern.err kernel: [ 6044.874516] mt76_wmac 10300000.wmac: MCU message 08 (seq 5) timed out Sat Jun 12 01:56:03 2021 kern.info kernel: [ 6046.924084] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Sat Jun 12 01:56:03 2021 kern.info kernel: [ 6046.930855] br-lan: port 2(wlan0) entered blocking state Sat Jun 12 01:56:03 2021 kern.info kernel: [ 6046.936302] br-lan: port 2(wlan0) entered forwarding state Sat Jun 12 01:56:03 2021 daemon.notice netifd: Network device 'wlan0' link is up Sat Jun 12 01:56:03 2021 daemon.notice hostapd: wlan0: interface state HT_SCAN->ENABLED Sat Jun 12 01:56:03 2021 daemon.notice hostapd: wlan0: AP-ENABLED Sat Jun 12 01:58:15 2021 daemon.err uhttpd[1316]: luci: accepted login on / for root from 192.168.1.135

thank you

ashwin-r-k commented 2 years ago

I have also faced same problem but with only on one of the client Device which has BROADCAST chip (Macbook air 2015 running linux).

System Log from laptop:

Feb 07 17:44:14 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid= reason=0 Feb 07 17:44:14 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Feb 07 17:44:14 NetworkManager[392]: [1644236054.1569] device (wlp3s0): supplicant interface state: completed -> disconnected Feb 07 17:44:14 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:15 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:16 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:17 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:18 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:19 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:20 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:21 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:22 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:23 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:24 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:25 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:26 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:27 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:28 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:29 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:29 NetworkManager[392]: [1644236069.5744] device (wlp3s0): link timed out. Feb 07 17:44:29 NetworkManager[392]: [1644236069.5747] device (wlp3s0): state change: activated -> failed (reason 'ssid-not-found', sys-iface-state: 'managed') Feb 07 17:44:29 NetworkManager[392]: [1644236069.5758] manager: NetworkManager state is now DISCONNECTED Feb 07 17:44:29 avahi-daemon[389]: Interface wlp3s0.IPv4 no longer relevant for mDNS. Feb 07 17:44:29 avahi-daemon[389]: Leaving mDNS multicast group on interface wlp3s0.IPv4 with address . Feb 07 17:44:29 NetworkManager[392]: [1644236069.5788] device (wlp3s0): set-hw-addr: set MAC address to (scanning) Feb 07 17:44:29 avahi-daemon[389]: Withdrawing address record for on wlp3s0. Feb 07 17:44:29 avahi-daemon[389]: Joining mDNS multicast group on interface wlp3s0.IPv4 with address . Feb 07 17:44:29 avahi-daemon[389]: New relevant interface wlp3s0.IPv4 for mDNS. Feb 07 17:44:29 avahi-daemon[389]: Registering new address record for on wlp3s0.IPv4. Feb 07 17:44:29 wpa_supplicant[883]: wlp3s0: CTRL-EVENT-SCAN-FAILED ret=-22 retry=1 Feb 07 17:44:29 NetworkManager[392]: [1644236069.5840] device (wlp3s0): Activation: failed for connection '' Feb 07 17:44:29 gnome-shell[1281]: An active wireless connection, in infrastructure mode, involves no access point? Feb 07 17:44:29 NetworkManager[392]: [1644236069.5882] device (wlp3s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed') Feb 07 17:44:29 dbus-daemon[391]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.5' (uid=0 pid=392 comm="/usr/bin/NetworkManager --no-daemon ") Feb 07 17:44:29 NetworkManager[392]: [1644236069.6004] dhcp4 (wlp3s0): canceled DHCP transaction Feb 07 17:44:29 NetworkManager[392]: [1644236069.6004] dhcp4 (wlp3s0): state changed bound -> terminated Feb 07 17:44:29 systemd[1]: Starting Network Manager Script Dispatcher Service... Feb 07 17:44:29 avahi-daemon[389]: Withdrawing address record for on wlp3s0. Feb 07 17:44:29 avahi-daemon[389]: Leaving mDNS multicast group on interface wlp3s0.IPv4 with address . Feb 07 17:44:29 avahi-daemon[389]: Interface wlp3s0.IPv4 no longer relevant for mDNS. Feb 07 17:44:29 gnome-shell[1281]: An active wireless connection, in infrastructure mode, involves no access point? Feb 07 17:44:29 gnome-shell[1281]: An active wireless connection, in infrastructure mode, involves no access point? Feb 07 17:44:29 gnome-shell[1281]: An active wireless connection, in infrastructure mode, involves no access point? Feb 07 17:44:29 dbus-daemon[391]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' Feb 07 17:44:29 systemd[1]: Started Network Manager Script Dispatcher Service. Feb 07 17:44:29 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 07 17:44:29 kernel: audit: type=1130 audit(1644236069.626:167): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 07 17:44:34 NetworkManager[392]: [1644236074.9912] policy: auto-activating connection '' (5a827cab-7ad3-470a-acc9-3632a71cb99f) Feb 07 17:44:34 NetworkManager[392]: [1644236074.9919] device (wlp3s0): Activation: starting connection '' (5a827cab-7ad3-470a-acc9-3632a71cb99f) Feb 07 17:44:34 NetworkManager[392]: [1644236074.9920] device (wlp3s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') Feb 07 17:44:34 NetworkManager[392]: [1644236074.9923] manager: NetworkManager state is now CONNECTING Feb 07 17:44:34 NetworkManager[392]: [1644236074.9934] device (wlp3s0): set-hw-addr: reset MAC address to D4:61:9D:05:E1:70 (preserve) Feb 07 17:44:34 NetworkManager[392]: [1644236074.9938] device (wlp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') Feb 07 17:44:34 NetworkManager[392]: [1644236074.9941] device (wlp3s0): Activation: (wifi) access point '' has security, but secrets are required. Feb 07 17:44:34 NetworkManager[392]: [1644236074.9941] device (wlp3s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed') Feb 07 17:44:34 NetworkManager[392]: [1644236074.9957] device (wlp3s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed') Feb 07 17:44:34 NetworkManager[392]: [1644236074.9960] device (wlp3s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') Feb 07 17:44:34 NetworkManager[392]: [1644236074.9964] device (wlp3s0): Activation: (wifi) connection '' has security, and secrets exist. No new secrets needed. Feb 07 17:44:34 NetworkManager[392]: [1644236074.9965] Config: added 'ssid' value '' Feb 07 17:44:34 NetworkManager[392]: [1644236074.9965] Config: added 'scan_ssid' value '1' Feb 07 17:44:34 NetworkManager[392]: [1644236074.9965] Config: added 'bgscan' value 'simple:30:-70:86400' Feb 07 17:44:34 NetworkManager[392]: [1644236074.9965] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256' Feb 07 17:44:34 NetworkManager[392]: [1644236074.9965] Config: added 'psk' value '' Feb 07 17:44:35 NetworkManager[392]: [1644236075.0085] device (wlp3s0): supplicant interface state: disconnected -> inactive