greearb / ath10k-ct

Stand-alone ath10k driver based on Candela Technologies Linux kernel.
111 stars 40 forks source link

ZyXEL NBG6817 kernel trace #130

Closed evilbulgarian closed 4 years ago

evilbulgarian commented 4 years ago

Please provide this info. See this link for more info on how to gather debug info: http://www.candelatech.com/ath10k-bugs.php

Description of the problem (how to configure, how to reproduce, how often it happens). Over time my wifi has some issues or my router reboots, I see the below trace in dmesg.. Software (OS, Firmware version, kernel, driver, etc)

Logs (dmesg, maybe supplicant and/or hostap)

BusyBox v1.30.1 () built-in shell (ash)


| |.-----.-----.-----.| | | |.----.| | | - || | -| || | | || || | |___|| |_||||____||| |__| |__| W I R E L E S S F R E E D O M

OpenWrt 19.07.2, r10947-65030d81f3

root@wifi01:~# cat /var/log/ lastlog wtmp root@wifi01:~# cat /var/ .uci/ dropbear-ef755c3f/ log/ overlay/ state/ TZ etc/ luci-indexcache resolv.conf sysinfo/ board.json hosts/ luci-modulecache/ resolv.conf.auto tmp/ dhcp.leases lib/ opkg-EdoDII/ run/ usr/ dnsmasq.d/ lock/ opkg-lists/ shm/ root@wifi01:~# cat /var/lo lock/ log/ root@wifi01:~# cat /var/log/ lastlog wtmp root@wifi01:~# cat /var/log/lastlog root@wifi01:~# dmesg [ 0.000000] Booting Linux on physical CPU 0x0 [ 0.000000] Linux version 4.14.171 (builder@buildhost) (gcc version 7.5.0 (OpenWrt GCC 7.5.0 r10947-65030d81f3)) #0 SMP Thu Feb 27 21:05:12 2020 [ 0.000000] CPU: ARMv7 Processor [512f04d0] revision 0 (ARMv7), cr=10c5787d [ 0.000000] CPU: div instructions available: patching division code [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache [ 0.000000] OF: fdt: Machine model: ZyXEL NBG6817 [ 0.000000] Memory policy: Data cache writealloc [ 0.000000] On node 0 totalpages: 122880 [ 0.000000] free_area_init_node: node 0, pgdat c0a27880, node_mem_map ddc39000 [ 0.000000] Normal zone: 960 pages used for memmap [ 0.000000] Normal zone: 0 pages reserved [ 0.000000] Normal zone: 122880 pages, LIFO batch:31 [ 0.000000] random: get_random_bytes called from 0xc09008dc with crng_init=0 [ 0.000000] percpu: Embedded 15 pages/cpu s29388 r8192 d23860 u61440 [ 0.000000] pcpu-alloc: s29388 r8192 d23860 u61440 alloc=15*4096 [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 121920 [ 0.000000] Kernel command line: rootfstype=squashfs,ext4 rootwait noinitrd root=/dev/mmcblk0p8 [ 0.000000] Bootloader command line (ignored): board=NBG6817 root=/dev/mmcblk0p8 rootwait zld_ver=2.04 console=ttyHSL1,115200n8 mtdparts=m25p80:0xC0000(SBL)ro,0x40000(TZ)ro,0x40000(RPM)ro,0x80000(u-boot)ro,0x10000(env)ro,0x10000(ART)ro,0x10000(dualflag),0x210000(reserved) [ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Memory: 479432K/491520K available (4975K kernel code, 160K rwdata, 756K rodata, 1024K init, 235K bss, 12088K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] Virtual kernel memory layout: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) [ 0.000000] vmalloc : 0xde800000 - 0xff800000 ( 528 MB) [ 0.000000] lowmem : 0xc0000000 - 0xde000000 ( 480 MB) [ 0.000000] pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) [ 0.000000] modules : 0xbf000000 - 0xbfe00000 ( 14 MB) [ 0.000000] .text : 0xc0208000 - 0xc07dbe98 (5968 kB) [ 0.000000] .init : 0xc0900000 - 0xc0a00000 (1024 kB) [ 0.000000] .data : 0xc0a00000 - 0xc0a28180 ( 161 kB) [ 0.000000] .bss : 0xc0a2a000 - 0xc0a64e18 ( 236 kB) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.000000] clocksource: dg_timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 305801671480 ns [ 0.000008] sched_clock: 32 bits at 6MHz, resolution 160ns, wraps every 343597383600ns [ 0.000022] Switching to timer-based delay loop, resolution 160ns [ 0.000213] Calibrating delay loop (skipped), value calculated using timer frequency.. 12.50 BogoMIPS (lpj=62500) [ 0.000238] pid_max: default: 32768 minimum: 301 [ 0.000364] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000380] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.000889] CPU: Testing write buffer coherency: ok [ 0.001656] Setting up static identity map for 0x42300000 - 0x42300060 [ 0.001811] Hierarchical SRCU implementation. [ 0.002547] smp: Bringing up secondary CPUs ... [ 0.004372] smp: Brought up 1 node, 2 CPUs [ 0.004391] SMP: Total of 2 processors activated (25.00 BogoMIPS). [ 0.004402] CPU: All CPU(s) started in SVC mode. [ 0.014103] VFP support v0.3: implementor 51 architecture 64 part 4d variant 2 rev 0 [ 0.014265] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.014291] futex hash table entries: 512 (order: 3, 32768 bytes) [ 0.014396] pinctrl core: initialized pinctrl subsystem [ 0.015388] NET: Registered protocol family 16 [ 0.015689] DMA: preallocated 256 KiB pool for atomic coherent allocations [ 0.017049] cpuidle: using governor ladder [ 0.017118] cpuidle: using governor menu [ 0.039959] msm_bus_fabric_init_driver [ 0.041455] usbcore: registered new interface driver usbfs [ 0.041540] usbcore: registered new interface driver hub [ 0.041627] usbcore: registered new device driver usb [ 0.041691] pps_core: LinuxPPS API ver. 1 registered [ 0.041703] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti giometti@linux.it [ 0.041735] PTP clock support registered [ 0.043448] clocksource: Switched to clocksource dg_timer [ 0.046675] NET: Registered protocol family 2 [ 0.047273] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 0.047312] TCP bind hash table entries: 4096 (order: 3, 32768 bytes) [ 0.047370] TCP: Hash tables configured (established 4096 bind 4096) [ 0.047461] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.047489] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.047666] NET: Registered protocol family 1 [ 0.047713] PCI: CLS 0 bytes, default 64 [ 0.048900] No memory allocated for crashlog [ 0.049176] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [ 0.054297] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 0.054314] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc. [ 0.060911] io scheduler noop registered [ 0.060929] io scheduler deadline registered (default) [ 0.062316] qcom-pcie 1b500000.pci: 1b500000.pci supply vdda not found, using dummy regulator [ 0.062432] qcom-pcie 1b500000.pci: 1b500000.pci supply vdda_phy not found, using dummy regulator [ 0.062532] qcom-pcie 1b500000.pci: 1b500000.pci supply vdda_refclk not found, using dummy regulator [ 0.063283] OF: PCI: host bridge /soc/pci@1b500000 ranges: [ 0.063328] OF: PCI: IO 0x0fe00000..0x0fefffff -> 0x0fe00000 [ 0.063353] OF: PCI: MEM 0x08000000..0x0fdfffff -> 0x08000000 [ 0.171587] qcom-pcie 1b500000.pci: link up [ 0.171761] qcom-pcie 1b500000.pci: PCI host bridge to bus 0000:00 [ 0.171783] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.171805] pci_bus 0000:00: root bus resource [io 0x0000-0xfffff] (bus address [0xfe00000-0xfefffff]) [ 0.171820] pci_bus 0000:00: root bus resource [mem 0x08000000-0x0fdfffff] [ 0.171837] pci_bus 0000:00: scanning bus [ 0.171883] pci 0000:00:00.0: [17cb:0101] type 01 class 0x060400 [ 0.171968] pci 0000:00:00.0: calling 0xc030d3c0 [ 0.172038] pci 0000:00:00.0: supports D1 [ 0.172049] pci 0000:00:00.0: PME# supported from D0 D1 D3hot [ 0.172065] pci 0000:00:00.0: PME# disabled [ 0.172315] pci_bus 0000:00: fixups for bus [ 0.172339] PCI: bus0: Fast back to back transfers disabled [ 0.172358] pci 0000:00:00.0: scanning [bus 01-ff] behind bridge, pass 0 [ 0.172482] pci_bus 0000:01: scanning bus [ 0.172659] pci 0000:01:00.0: [168c:0046] type 00 class 0x028000 [ 0.173009] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit] [ 0.173475] pci 0000:01:00.0: calling 0xc030d3c0 [ 0.174224] pci 0000:01:00.0: PME# supported from D0 D3hot D3cold [ 0.174265] pci 0000:01:00.0: PME# disabled [ 0.174604] pci_bus 0000:01: fixups for bus [ 0.174675] PCI: bus1: Fast back to back transfers disabled [ 0.174692] pci_bus 0000:01: bus scan returning with max=01 [ 0.174710] pci 0000:00:00.0: scanning [bus 01-ff] behind bridge, pass 1 [ 0.174727] pci_bus 0000:00: bus scan returning with max=ff [ 0.174776] pci 0000:00:00.0: BAR 8: assigned [mem 0x08000000-0x081fffff] [ 0.174803] pci 0000:01:00.0: BAR 0: assigned [mem 0x08000000-0x081fffff 64bit] [ 0.174933] pci 0000:00:00.0: PCI bridge to [bus 01-ff] [ 0.174956] pci 0000:00:00.0: bridge window [mem 0x08000000-0x081fffff] [ 0.174988] pci 0000:00:00.0: calling 0xc055fae4 [ 0.175188] pcieport 0000:00:00.0: assign IRQ: got 67 [ 0.175484] pcieport 0000:00:00.0: AER enabled with IRQ 35 [ 0.175601] pci 0000:01:00.0: calling 0xc055fae4 [ 0.176021] qcom-pcie 1b700000.pci: 1b700000.pci supply vdda not found, using dummy regulator [ 0.176139] qcom-pcie 1b700000.pci: 1b700000.pci supply vdda_phy not found, using dummy regulator [ 0.176235] qcom-pcie 1b700000.pci: 1b700000.pci supply vdda_refclk not found, using dummy regulator [ 0.176949] OF: PCI: host bridge /soc/pci@1b700000 ranges: [ 0.176987] OF: PCI: IO 0x31e00000..0x31efffff -> 0x31e00000 [ 0.177010] OF: PCI: MEM 0x2e000000..0x31dfffff -> 0x2e000000 [ 0.286888] qcom-pcie 1b700000.pci: link up [ 0.287033] qcom-pcie 1b700000.pci: PCI host bridge to bus 0001:00 [ 0.287053] pci_bus 0001:00: root bus resource [bus 00-ff] [ 0.287068] pci_bus 0001:00: root bus resource [mem 0x2e000000-0x31dfffff] [ 0.287082] pci_bus 0001:00: scanning bus [ 0.287124] pci 0001:00:00.0: [17cb:0101] type 01 class 0x060400 [ 0.287194] pci 0001:00:00.0: calling 0xc030d3c0 [ 0.287256] pci 0001:00:00.0: supports D1 [ 0.287266] pci 0001:00:00.0: PME# supported from D0 D1 D3hot [ 0.287279] pci 0001:00:00.0: PME# disabled [ 0.287516] pci_bus 0001:00: fixups for bus [ 0.287534] PCI: bus0: Fast back to back transfers disabled [ 0.287552] pci 0001:00:00.0: scanning [bus 01-ff] behind bridge, pass 0 [ 0.287668] pci_bus 0001:01: scanning bus [ 0.287853] pci 0001:01:00.0: [168c:0046] type 00 class 0x028000 [ 0.288224] pci 0001:01:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit] [ 0.288721] pci 0001:01:00.0: calling 0xc030d3c0 [ 0.289412] pci 0001:01:00.0: PME# supported from D0 D3hot D3cold [ 0.289456] pci 0001:01:00.0: PME# disabled [ 0.289796] pci_bus 0001:01: fixups for bus [ 0.289872] PCI: bus1: Fast back to back transfers disabled [ 0.289887] pci_bus 0001:01: bus scan returning with max=01 [ 0.289903] pci 0001:00:00.0: scanning [bus 01-ff] behind bridge, pass 1 [ 0.289920] pci_bus 0001:00: bus scan returning with max=ff [ 0.289957] pci 0001:00:00.0: BAR 8: assigned [mem 0x2e000000-0x2e1fffff] [ 0.289981] pci 0001:01:00.0: BAR 0: assigned [mem 0x2e000000-0x2e1fffff 64bit] [ 0.290114] pci 0001:00:00.0: PCI bridge to [bus 01-ff] [ 0.290134] pci 0001:00:00.0: bridge window [mem 0x2e000000-0x2e1fffff] [ 0.290162] pci 0001:00:00.0: calling 0xc055fae4 [ 0.290340] pcieport 0001:00:00.0: assign IRQ: got 100 [ 0.290623] pcieport 0001:00:00.0: AER enabled with IRQ 68 [ 0.290727] pci 0001:01:00.0: calling 0xc055fae4 [ 0.292923] L2 @ QSB rate. Forcing new rate. [ 0.293130] L2 @ 384000 KHz [ 0.293301] CPU0 @ 800000 KHz [ 0.293314] CPU1 @ QSB rate. Forcing new rate. [ 0.293440] CPU1 @ 384000 KHz [ 0.297462] gsbi 16300000.gsbi: GSBI port protocol: 6 crci: 0 [ 0.298857] gsbi 1a200000.gsbi: GSBI port protocol: 3 crci: 0 [ 0.300312] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled [ 0.302870] msm_serial 16340000.serial: msm_serial: detected port #0 [ 0.302971] msm_serial 16340000.serial: uartclk = 7372800 [ 0.303054] 16340000.serial: ttyMSM0 at MMIO 0x16340000 (irq = 101, base_baud = 460800) is a MSM [ 0.303092] msm_serial: console setup on port #0 [ 1.050262] console [ttyMSM0] enabled [ 1.055082] msm_serial: driver initialized [ 1.063528] loop: module loaded [ 1.065048] spi_qup 1a280000.spi: IN:block:16, fifo:64, OUT:block:16, fifo:64 [ 1.067047] m25p80 spi0.0: mx25u3235f (4096 Kbytes) [ 1.072845] 13 qcom-smem partitions found on MTD device spi0.0 [ 1.077554] Creating 13 MTD partitions on "spi0.0": [ 1.083369] 0x000000000000-0x000000020000 : "0:SBL1" [ 1.089052] 0x000000020000-0x000000040000 : "0:MIBIB" [ 1.094163] 0x000000040000-0x000000060000 : "0:SBL2" [ 1.099010] 0x000000060000-0x0000000a0000 : "0:SBL3" [ 1.104121] 0x0000000a0000-0x0000000b0000 : "0:DDRCONFIG" [ 1.109031] 0x0000000b0000-0x0000000c0000 : "0:SSD" [ 1.114386] 0x0000000c0000-0x000000100000 : "0:TZ" [ 1.118970] 0x000000100000-0x000000140000 : "0:RPM" [ 1.123897] 0x000000140000-0x0000001c0000 : "0:APPSBL" [ 1.128602] 0x0000001c0000-0x0000001d0000 : "0:APPSBLENV" [ 1.133892] 0x0000001d0000-0x0000001e0000 : "0:ART" [ 1.139301] 0x0000001e0000-0x0000001f0000 : "0:DUAL_FLAG" [ 1.144058] 0x0000001f0000-0x000000400000 : "0:RESERVED" [ 1.150548] libphy: GPIO Bitbanged MDIO: probed [ 1.175681] switch0: Atheros AR8337 rev. 2 switch registered on gpio-0 [ 2.035773] ar8327: qca,phy-rgmii-en is not specified [ 2.036167] libphy: Fixed MDIO Bus: probed [ 2.041341] ipq806x-gmac-dwmac 37200000.ethernet: PTP uses main clock [ 2.044200] stmmac - user ID: 0x10, Synopsys ID: 0x37 [ 2.050313] ipq806x-gmac-dwmac 37200000.ethernet: Ring mode enabled [ 2.055431] ipq806x-gmac-dwmac 37200000.ethernet: DMA HW capability register supported [ 2.061425] ipq806x-gmac-dwmac 37200000.ethernet: Enhanced/Alternate descriptors [ 2.069475] ipq806x-gmac-dwmac 37200000.ethernet: Enabled extended descriptors [ 2.077056] ipq806x-gmac-dwmac 37200000.ethernet: RX Checksum Offload Engine supported [ 2.084066] ipq806x-gmac-dwmac 37200000.ethernet: COE Type 2 [ 2.091888] ipq806x-gmac-dwmac 37200000.ethernet: TX Checksum insertion supported [ 2.097778] ipq806x-gmac-dwmac 37200000.ethernet: Wake-Up On Lan supported [ 2.105163] ipq806x-gmac-dwmac 37200000.ethernet (unnamed net_device) (uninitialized): device MAC address b2:ee:f2:4b:dc:a1 [ 2.111869] ipq806x-gmac-dwmac 37200000.ethernet: Enable RX Mitigation via HW Watchdog Timer [ 2.124596] ipq806x-gmac-dwmac 37400000.ethernet: PTP uses main clock [ 2.131748] stmmac - user ID: 0x10, Synopsys ID: 0x37 [ 2.137998] ipq806x-gmac-dwmac 37400000.ethernet: Ring mode enabled [ 2.142930] ipq806x-gmac-dwmac 37400000.ethernet: DMA HW capability register supported [ 2.149073] ipq806x-gmac-dwmac 37400000.ethernet: Enhanced/Alternate descriptors [ 2.157070] ipq806x-gmac-dwmac 37400000.ethernet: Enabled extended descriptors [ 2.164630] ipq806x-gmac-dwmac 37400000.ethernet: RX Checksum Offload Engine supported [ 2.171581] ipq806x-gmac-dwmac 37400000.ethernet: COE Type 2 [ 2.179563] ipq806x-gmac-dwmac 37400000.ethernet: TX Checksum insertion supported [ 2.185374] ipq806x-gmac-dwmac 37400000.ethernet: Wake-Up On Lan supported [ 2.192680] ipq806x-gmac-dwmac 37400000.ethernet (unnamed net_device) (uninitialized): device MAC address de:6a:30:04:0a:d7 [ 2.199529] ipq806x-gmac-dwmac 37400000.ethernet: Enable RX Mitigation via HW Watchdog Timer [ 2.211236] i2c /dev entries driver [ 2.220070] Calibration not found. [ 2.223500] Speed bin: 0 [ 2.225903] PVS bin: 4 [ 2.229822] cpuidle: enable-method property 'qcom,kpss-acc-v1' found operations [ 2.230753] cpuidle: enable-method property 'qcom,kpss-acc-v1' found operations [ 2.238991] mmci-pl18x 12400000.sdcc: mmc0: PL180 manf 51 rev0 at 0x12400000 irq 34,0 (pio) [ 2.245437] mmci-pl18x 12400000.sdcc: DMA channels RX dma0chan1, TX dma0chan2 [ 2.314488] sdhci: Secure Digital Host Controller Interface driver [ 2.314518] sdhci: Copyright(c) Pierre Ossman [ 2.319558] sdhci-pltfm: SDHCI platform and OF driver helper [ 2.325491] NET: Registered protocol family 10 [ 2.331365] Segment Routing with IPv6 [ 2.334127] NET: Registered protocol family 17 [ 2.337775] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [ 2.342562] 8021q: 802.1Q VLAN Support v1.8 [ 2.355309] Registering SWP/SWPB emulation handler [ 2.374631] qcom_rpm 108000.rpm: RPM firmware 3.0.16777364 [ 2.389426] s1a: supplied by regulator-dummy [ 2.389513] s1a: Bringing 0uV into 1050000-1050000uV [ 2.393026] s1b: supplied by regulator-dummy [ 2.397869] s1b: Bringing 0uV into 1050000-1050000uV [ 2.402213] s2a: supplied by regulator-dummy [ 2.407055] s2a: Bringing 0uV into 775000-775000uV [ 2.411412] s2b: supplied by regulator-dummy [ 2.415918] s2b: Bringing 0uV into 775000-775000uV [ 2.424555] hctosys: unable to open rtc device (rtc0) [ 2.425789] Waiting for root device /dev/mmcblk0p8... [ 2.449626] mmc0: new high speed MMC card at address 0001 [ 2.450261] mmcblk0: mmc0:0001 S10004 3.56 GiB [ 2.454446] mmcblk0boot0: mmc0:0001 S10004 partition 1 4.00 MiB [ 2.458572] mmcblk0boot1: mmc0:0001 S10004 partition 2 4.00 MiB [ 2.464488] mmcblk0rpmb: mmc0:0001 S10004 partition 3 4.00 MiB, chardev (248:0) [ 2.471925] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 [ 2.485497] VFS: Mounted root (squashfs filesystem) readonly on device 179:8. [ 2.486350] Freeing unused kernel memory: 1024K [ 2.892081] init: Console is alive [ 2.892210] init: - watchdog - [ 2.908982] random: fast init done [ 3.232538] kmodloader: loading kernel modules from /etc/modules-boot.d/ [ 3.250270] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 3.251856] SCSI subsystem initialized [ 3.257722] libata version 3.00 loaded. [ 3.259993] ehci-platform: EHCI generic platform driver [ 3.262035] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 3.264930] ohci-platform: OHCI generic platform driver [ 3.401778] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 3.401878] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 [ 3.406672] xhci-hcd xhci-hcd.0.auto: hcc params 0x0228f065 hci version 0x100 quirks 0x0000000002010010 [ 3.413977] xhci-hcd xhci-hcd.0.auto: irq 106, io mem 0x11000000 [ 3.423834] hub 1-0:1.0: USB hub found [ 3.429366] hub 1-0:1.0: 1 port detected [ 3.433162] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller [ 3.437043] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 [ 3.442279] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0 SuperSpeed [ 3.449989] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 3.456814] hub 2-0:1.0: USB hub found [ 3.464859] hub 2-0:1.0: 1 port detected [ 3.468526] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 3.472405] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3 [ 3.477901] xhci-hcd xhci-hcd.1.auto: hcc params 0x0228f065 hci version 0x100 quirks 0x0000000002010010 [ 3.485347] xhci-hcd xhci-hcd.1.auto: irq 107, io mem 0x10000000 [ 3.495016] hub 3-0:1.0: USB hub found [ 3.500885] hub 3-0:1.0: 1 port detected [ 3.504668] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller [ 3.508511] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4 [ 3.513880] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0 SuperSpeed [ 3.521419] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM. [ 3.528313] hub 4-0:1.0: USB hub found [ 3.536397] hub 4-0:1.0: 1 port detected [ 3.540988] kmodloader: done loading kernel modules from /etc/modules-boot.d/ [ 3.550682] init: - preinit - [ 3.777774] random: jshn: uninitialized urandom read (4 bytes read) [ 3.858358] random: jshn: uninitialized urandom read (4 bytes read) [ 4.028940] random: jshn: uninitialized urandom read (4 bytes read) [ 5.130689] Generic PHY fixed-0:01: attached PHY driver [Generic PHY] (mii_bus:phy_addr=fixed-0:01, irq=POLL) [ 5.132326] dwmac1000: Master AXI performs any burst length [ 5.139718] ipq806x-gmac-dwmac 37400000.ethernet eth1: IEEE 1588-2008 Advanced Timestamp supported [ 5.145265] ipq806x-gmac-dwmac 37400000.ethernet eth1: registered PTP clock [ 5.154964] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready [ 5.165857] urandom_read: 2 callbacks suppressed [ 5.165860] random: procd: uninitialized urandom read (4 bytes read) [ 6.233967] ipq806x-gmac-dwmac 37400000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off [ 6.234058] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 8.440766] EXT4-fs (loop0): recovery complete [ 8.441837] EXT4-fs (loop0): mounted filesystem with ordered data mode. Opts: (null) [ 8.446699] mount_root: switching to ext4 overlay [ 8.477190] urandom-seed: Seeding with /etc/urandom.seed [ 8.568429] procd: - early - [ 8.568590] procd: - watchdog - [ 9.207767] procd: - watchdog - [ 9.208285] procd: - ubus - [ 9.225357] random: ubusd: uninitialized urandom read (4 bytes read) [ 9.261328] random: ubusd: uninitialized urandom read (4 bytes read) [ 9.261836] random: ubusd: uninitialized urandom read (4 bytes read) [ 9.268464] procd: - init - [ 9.463900] urngd: v1.0.2 started. [ 9.484839] kmodloader: loading kernel modules from /etc/modules.d/ [ 9.486547] random: crng init done [ 9.487287] ip6_tables: (C) 2000-2006 Netfilter Core Team [ 9.490252] random: 1 urandom warning(s) missed due to ratelimiting [ 9.496354] Loading modules backported from Linux version v4.19.98-0-gd183c8e2647a [ 9.505099] Backport generated by backports.git v4.19.98-1-0-g8204eb99 [ 9.513784] ip_tables: (C) 2000-2006 Netfilter Core Team [ 9.521486] nf_conntrack version 0.5.0 (8192 buckets, 32768 max) [ 9.540485] xt_time: kernel timezone is -0000 [ 9.553483] PPP generic driver version 2.4.2 [ 9.554370] NET: Registered protocol family 24 [ 9.562121] ath10k_pci 0000:01:00.0: assign IRQ: got 67 [ 9.562143] ath10k 4.19 driver, optimized for CT firmware, probing pci device: 0x46. [ 9.562544] ath10k_pci 0000:01:00.0: enabling device (0140 -> 0142) [ 9.569115] ath10k_pci 0000:01:00.0: enabling bus mastering [ 9.569564] ath10k_pci 0000:01:00.0: pci irq msi oper_irq_mode 2 irq_mode 0 reset_mode 0 [ 9.747463] ath10k_pci 0000:01:00.0: Direct firmware load for ath10k/fwcfg-pci-0000:01:00.0.txt failed with error -2 [ 9.747511] ath10k_pci 0000:01:00.0: Falling back to user helper [ 9.840997] firmware ath10k!fwcfg-pci-0000:01:00.0.txt: firmware_loading_store: map pages failed [ 9.843064] ath10k_pci 0000:01:00.0: Direct firmware load for ath10k/QCA9984/hw1.0/ct-firmware-5.bin failed with error -2 [ 9.848922] ath10k_pci 0000:01:00.0: Falling back to user helper [ 9.891953] firmware ath10k!QCA9984!hw1.0!ct-firmware-5.bin: firmware_loading_store: map pages failed [ 9.892175] ath10k_pci 0000:01:00.0: Direct firmware load for ath10k/QCA9984/hw1.0/ct-firmware-2.bin failed with error -2 [ 9.900226] ath10k_pci 0000:01:00.0: Falling back to user helper [ 9.927517] firmware ath10k!QCA9984!hw1.0!ct-firmware-2.bin: firmware_loading_store: map pages failed [ 9.927699] ath10k_pci 0000:01:00.0: Direct firmware load for ath10k/QCA9984/hw1.0/firmware-6.bin failed with error -2 [ 9.935806] ath10k_pci 0000:01:00.0: Falling back to user helper [ 9.961304] firmware ath10k!QCA9984!hw1.0!firmware-6.bin: firmware_loading_store: map pages failed [ 10.104871] ath10k_pci 0000:01:00.0: qca9984/qca9994 hw1.0 target 0x01000000 chip_id 0x00000000 sub 168c:cafe [ 10.104904] ath10k_pci 0000:01:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0 [ 10.115534] ath10k_pci 0000:01:00.0: firmware ver 10.4b-ct-9984-fW-012-17ba98334 api 5 features mfp,peer-flow-ctrl,txstatus-noack,wmi-10.x-CT,ratemask-CT,regdump-CT,txrate-CT,flush-all-CT,pingpong-CT,ch-regs-CT,nop-CT,set-special-CT,tx-rc-CT,cust-stats-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT,wmi-bcn-rc-CT crc32 877928bc [ 12.450400] ath10k_pci 0000:01:00.0: board_file api 2 bmi_id 0:1 crc32 85498734 [ 18.310978] ath10k_pci 0000:01:00.0: 10.4 wmi init: vdevs: 16 peers: 48 tid: 96 [ 18.311005] ath10k_pci 0000:01:00.0: msdu-desc: 2500 skid: 32 [ 18.392838] ath10k_pci 0000:01:00.0: wmi print 'P 48/48 V 16 K 144 PH 176 T 186 msdu-desc: 2500 sw-crypt: 0 ct-sta: 0' [ 18.393692] ath10k_pci 0000:01:00.0: wmi print 'free: 81784 iram: 23220 sram: 14440' [ 18.652032] ath10k_pci 0000:01:00.0: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 32 raw 0 hwcrypto 1 [ 18.756916] ath: EEPROM regdomain: 0x0 [ 18.756932] ath: EEPROM indicates default country code should be used [ 18.756941] ath: doing EEPROM country->regdmn map search [ 18.756960] ath: country maps to regdmn code: 0x3a [ 18.756973] ath: Country alpha2 being used: US [ 18.756983] ath: Regpair used: 0x3a [ 18.762468] ath10k_pci 0001:01:00.0: assign IRQ: got 100 [ 18.762506] ath10k 4.19 driver, optimized for CT firmware, probing pci device: 0x46. [ 18.763617] ath10k_pci 0001:01:00.0: enabling device (0140 -> 0142) [ 18.769584] ath10k_pci 0001:01:00.0: enabling bus mastering [ 18.770270] ath10k_pci 0001:01:00.0: pci irq msi oper_irq_mode 2 irq_mode 0 reset_mode 0 [ 18.945323] ath10k_pci 0001:01:00.0: Direct firmware load for ath10k/fwcfg-pci-0001:01:00.0.txt failed with error -2 [ 18.945356] ath10k_pci 0001:01:00.0: Falling back to user helper [ 19.079412] firmware ath10k!fwcfg-pci-0001:01:00.0.txt: firmware_loading_store: map pages failed [ 19.080013] ath10k_pci 0001:01:00.0: Direct firmware load for ath10k/QCA9984/hw1.0/ct-firmware-5.bin failed with error -2 [ 19.087341] ath10k_pci 0001:01:00.0: Falling back to user helper [ 19.113923] firmware ath10k!QCA9984!hw1.0!ct-firmware-5.bin: firmware_loading_store: map pages failed [ 19.114077] ath10k_pci 0001:01:00.0: Direct firmware load for ath10k/QCA9984/hw1.0/ct-firmware-2.bin failed with error -2 [ 19.122122] ath10k_pci 0001:01:00.0: Falling back to user helper [ 19.175618] firmware ath10k!QCA9984!hw1.0!ct-firmware-2.bin: firmware_loading_store: map pages failed [ 19.175804] ath10k_pci 0001:01:00.0: Direct firmware load for ath10k/QCA9984/hw1.0/firmware-6.bin failed with error -2 [ 19.183928] ath10k_pci 0001:01:00.0: Falling back to user helper [ 19.220199] firmware ath10k!QCA9984!hw1.0!firmware-6.bin: firmware_loading_store: map pages failed [ 19.220340] ath10k_pci 0001:01:00.0: qca9984/qca9994 hw1.0 target 0x01000000 chip_id 0x00000000 sub 168c:cafe [ 19.228171] ath10k_pci 0001:01:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0 [ 19.240386] ath10k_pci 0001:01:00.0: firmware ver 10.4b-ct-9984-fW-012-17ba98334 api 5 features mfp,peer-flow-ctrl,txstatus-noack,wmi-10.x-CT,ratemask-CT,regdump-CT,txrate-CT,flush-all-CT,pingpong-CT,ch-regs-CT,nop-CT,set-special-CT,tx-rc-CT,cust-stats-CT,txrate2-CT,beacon-cb-CT,wmi-block-ack-CT,wmi-bcn-rc-CT crc32 877928bc [ 21.615527] ath10k_pci 0001:01:00.0: board_file api 2 bmi_id 0:2 crc32 85498734 [ 27.561894] ath10k_pci 0001:01:00.0: 10.4 wmi init: vdevs: 16 peers: 48 tid: 96 [ 27.561921] ath10k_pci 0001:01:00.0: msdu-desc: 2500 skid: 32 [ 27.645936] ath10k_pci 0001:01:00.0: wmi print 'P 48/48 V 16 K 144 PH 176 T 186 msdu-desc: 2500 sw-crypt: 0 ct-sta: 0' [ 27.646820] ath10k_pci 0001:01:00.0: wmi print 'free: 81784 iram: 23220 sram: 14440' [ 27.918192] ath10k_pci 0001:01:00.0: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 32 raw 0 hwcrypto 1 [ 28.016969] ath: EEPROM regdomain: 0x0 [ 28.016983] ath: EEPROM indicates default country code should be used [ 28.016994] ath: doing EEPROM country->regdmn map search [ 28.017012] ath: country maps to regdmn code: 0x3a [ 28.017025] ath: Country alpha2 being used: US [ 28.017035] ath: Regpair used: 0x3a [ 28.025993] kmodloader: done loading kernel modules from /etc/modules.d/ [ 30.436435] Generic PHY fixed-0:01: attached PHY driver [Generic PHY] (mii_bus:phy_addr=fixed-0:01, irq=POLL) [ 30.437144] dwmac1000: Master AXI performs any burst length [ 30.445393] ipq806x-gmac-dwmac 37400000.ethernet eth1: IEEE 1588-2008 Advanced Timestamp supported [ 30.450808] ipq806x-gmac-dwmac 37400000.ethernet eth1: registered PTP clock [ 30.459924] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready [ 30.468459] br-DMZ: port 1(eth1.173) entered blocking state [ 30.472497] br-DMZ: port 1(eth1.173) entered disabled state [ 30.478224] device eth1.173 entered promiscuous mode [ 30.483516] device eth1 entered promiscuous mode [ 30.489874] IPv6: ADDRCONF(NETDEV_UP): br-DMZ: link is not ready [ 30.511483] br-GUEST: port 1(eth1.192) entered blocking state [ 30.511507] br-GUEST: port 1(eth1.192) entered disabled state [ 30.516446] device eth1.192 entered promiscuous mode [ 30.523088] IPv6: ADDRCONF(NETDEV_UP): br-GUEST: link is not ready [ 30.550125] br-WIFI: port 1(eth1.172) entered blocking state [ 30.550150] br-WIFI: port 1(eth1.172) entered disabled state [ 30.555098] device eth1.172 entered promiscuous mode [ 30.561496] IPv6: ADDRCONF(NETDEV_UP): br-WIFI: link is not ready [ 30.567524] IPv6: ADDRCONF(NETDEV_UP): eth1.1: link is not ready [ 31.514049] ipq806x-gmac-dwmac 37400000.ethernet eth1: Link is Up - 1Gbps/Full - flow control off [ 37.222747] ath10k_pci 0001:01:00.0: 10.4 wmi init: vdevs: 16 peers: 48 tid: 96 [ 37.222774] ath10k_pci 0001:01:00.0: msdu-desc: 2500 skid: 32 [ 37.306832] ath10k_pci 0001:01:00.0: wmi print 'P 48/48 V 16 K 144 PH 176 T 186 msdu-desc: 2500 sw-crypt: 0 ct-sta: 0' [ 37.307703] ath10k_pci 0001:01:00.0: wmi print 'free: 81784 iram: 23220 sram: 14440' [ 37.744282] ath10k_pci 0001:01:00.0: Firmware lacks feature flag indicating a retry limit of > 2 is OK, requested limit: 4 [ 37.744512] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready [ 37.758800] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 37.761094] IPv6: ADDRCONF(NETDEV_CHANGE): eth1.1: link becomes ready [ 37.766512] br-WIFI: port 1(eth1.172) entered blocking state [ 37.772885] br-WIFI: port 1(eth1.172) entered forwarding state [ 37.778948] br-DMZ: port 1(eth1.173) entered blocking state [ 37.784321] br-DMZ: port 1(eth1.173) entered forwarding state [ 37.790025] br-GUEST: port 1(eth1.192) entered blocking state [ 37.795691] br-GUEST: port 1(eth1.192) entered forwarding state [ 37.802733] IPv6: ADDRCONF(NETDEV_CHANGE): br-WIFI: link becomes ready [ 37.807254] IPv6: ADDRCONF(NETDEV_CHANGE): br-DMZ: link becomes ready [ 37.813841] IPv6: ADDRCONF(NETDEV_CHANGE): br-GUEST: link becomes ready [ 37.820608] br-WIFI: port 2(wlan1) entered blocking state [ 37.826696] br-WIFI: port 2(wlan1) entered disabled state [ 37.832347] device wlan1 entered promiscuous mode [ 38.221088] ath10k_pci 0001:01:00.0: NOTE: Firmware DBGLOG output disabled in debug_mask: 0x10000000 [ 43.751011] ath10k_pci 0000:01:00.0: 10.4 wmi init: vdevs: 16 peers: 48 tid: 96 [ 43.751040] ath10k_pci 0000:01:00.0: msdu-desc: 2500 skid: 32 [ 43.832919] ath10k_pci 0000:01:00.0: wmi print 'P 48/48 V 16 K 144 PH 176 T 186 msdu-desc: 2500 sw-crypt: 0 ct-sta: 0' [ 43.833755] ath10k_pci 0000:01:00.0: wmi print 'free: 81784 iram: 23220 sram: 14440' [ 44.190574] ath10k_pci 0000:01:00.0: Firmware lacks feature flag indicating a retry limit of > 2 is OK, requested limit: 4 [ 44.190839] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 44.204216] br-DMZ: port 2(wlan0) entered blocking state [ 44.206342] br-DMZ: port 2(wlan0) entered disabled state [ 44.212089] device wlan0 entered promiscuous mode [ 44.550520] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [ 44.550860] br-WIFI: port 2(wlan1) entered blocking state [ 44.556081] br-WIFI: port 2(wlan1) entered forwarding state [ 44.571112] br-DMZ: port 3(wlan1-1) entered blocking state [ 44.571164] br-DMZ: port 3(wlan1-1) entered disabled state [ 44.576521] device wlan1-1 entered promiscuous mode [ 44.587410] IPv6: ADDRCONF(NETDEV_UP): wlan1-1: link is not ready [ 44.587449] br-DMZ: port 3(wlan1-1) entered blocking state [ 44.592484] br-DMZ: port 3(wlan1-1) entered forwarding state [ 44.665609] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-1: link becomes ready [ 44.667221] br-GUEST: port 2(wlan1-2) entered blocking state [ 44.671034] br-GUEST: port 2(wlan1-2) entered disabled state [ 44.677212] device wlan1-2 entered promiscuous mode [ 44.691185] IPv6: ADDRCONF(NETDEV_UP): wlan1-2: link is not ready [ 44.691222] br-GUEST: port 2(wlan1-2) entered blocking state [ 44.696321] br-GUEST: port 2(wlan1-2) entered forwarding state [ 44.721278] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1-2: link becomes ready [ 45.115739] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 45.116072] br-DMZ: port 2(wlan0) entered blocking state [ 45.121189] br-DMZ: port 2(wlan0) entered forwarding state [ 45.137269] br-WIFI: port 3(wlan0-1) entered blocking state [ 45.137304] br-WIFI: port 3(wlan0-1) entered disabled state [ 45.141848] device wlan0-1 entered promiscuous mode [ 45.160481] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready [ 45.160519] br-WIFI: port 3(wlan0-1) entered blocking state [ 45.165611] br-WIFI: port 3(wlan0-1) entered forwarding state [ 45.203925] br-WIFI: port 3(wlan0-1) entered disabled state [ 45.264642] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready [ 45.264796] br-WIFI: port 3(wlan0-1) entered blocking state [ 45.270071] br-WIFI: port 3(wlan0-1) entered forwarding state [ 45.278254] br-GUEST: port 3(wlan0-2) entered blocking state [ 45.281434] br-GUEST: port 3(wlan0-2) entered disabled state [ 45.287426] device wlan0-2 entered promiscuous mode [ 45.305540] IPv6: ADDRCONF(NETDEV_UP): wlan0-2: link is not ready [ 45.305577] br-GUEST: port 3(wlan0-2) entered blocking state [ 45.310609] br-GUEST: port 3(wlan0-2) entered forwarding state [ 45.340919] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-2: link becomes ready [ 45.343406] br-DMZ: port 4(wlan0-3) entered blocking state [ 45.346447] br-DMZ: port 4(wlan0-3) entered disabled state [ 45.352054] device wlan0-3 entered promiscuous mode [ 45.367131] IPv6: ADDRCONF(NETDEV_UP): wlan0-3: link is not ready [ 45.367167] br-DMZ: port 4(wlan0-3) entered blocking state [ 45.372209] br-DMZ: port 4(wlan0-3) entered forwarding state [ 45.472672] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-3: link becomes ready [ 53.787746] ath10k_pci 0000:01:00.0: Invalid peer id 1 or peer stats buffer, peer: db384000 sta: (null) [48089.651096] ath10k_pci 0000:01:00.0: Invalid VHT mcs 15 peer stats [571811.526509] ------------[ cut here ]------------ [571811.526570] WARNING: CPU: 1 PID: 2385 at backports-4.19.98-1/net/wireless/util.c:1147 0xbf25fd88 [cfg80211@bf25b000+0x37000] [571811.531097] invalid rate bw=4, mcs=15, nss=4 [571811.541600] Modules linked in: pppoe ppp_async ath10k_pci ath10k_core ath pppox ppp_generic nf_conntrack_ipv6 mac80211 iptable_nat ipt_REJECT ipt_MASQUERADE cfg80211 xt_time xt_tcpudp xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_FLOWOFFLOAD xt_CT slhc nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_flow_table_hw nf_flow_table nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack iptable_mangle iptable_filter ip_tables crc_ccitt compat ledtrig_usbport nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 leds_gpio xhci_plat_hcd xhci_pci xhci_hcd dwc3 dwc3_of_simple ohci_platform ohci_hcd phy_qcom_dwc3 ahci ehci_platform [571811.594357] sd_mod ahci_platform libahci_platform libahci libata scsi_mod ehci_hcd gpio_button_hotplug ext4 mbcache jbd2 crc32c_generic [571811.616602] CPU: 1 PID: 2385 Comm: hostapd Not tainted 4.14.171 #0 [571811.628899] Hardware name: Generic DT based system [571811.634984] Function entered at [] from [] [571811.639838] Function entered at [] from [] [571811.645742] Function entered at [] from [] [571811.651644] Function entered at [] from [] [571811.657544] Function entered at [] from [] [571811.663489] Function entered at [] from [] [571811.669355] Function entered at [] from [] [571811.675257] Function entered at [] from [] [571811.681159] Function entered at [] from [] [571811.687079] Function entered at [] from [] [571811.692964] Function entered at [] from [] [571811.698865] Function entered at [] from [] [571811.704772] Function entered at [] from [] [571811.710672] Function entered at [] from [] [571811.716573] Function entered at [] from [] [571811.722474] Function entered at [] from [] [571811.728377] Function entered at [] from [] [571811.734280] Function entered at [] from [] [571811.740186] Function entered at [] from [] [571811.746086] Function entered at [] from [] [571811.752079] ---[ end trace 930655c73d61c5bc ]---

greearb commented 4 years ago

That warning was fixed by Petr earlier today and should get into OpenWrt shortly.