greearb / ath10k-ct

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

after few days uptime, wifi becomes very laggy #61

Open fishxz opened 5 years ago

fishxz commented 5 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). hey, sorry that i have to open another bug... but i noticed, that my wifi gets super laggy after few days of uptime. maybe this is related to some other bug?

Software (OS, Firmware version, kernel, driver, etc) OpenWrt SNAPSHOT r8917-ae622c93b3 / LuCI Master (git-18.361.48411-39aa247) firmware ver 10.4b-ct-4019-fW-012-81e1edd5 kernel 4.14.90

Hardware (NIC chipset, platform, etc) Zyxel nbg6617 IPQ4018

Logs (dmesg, maybe supplicant and/or hostap)

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.90 (buildbot@buildslave) (gcc version 7.4.0 (OpenWrt GCC 7.4.0 r8917-ae622c93b3)) #0 SMP Fri Dec 28 22:09:38 2018
[    0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: ZyXEL NBG6617
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] On node 0 totalpages: 65024
[    0.000000] free_area_init_node: node 0, pgdat c0a23e40, node_mem_map cfdf9000
[    0.000000]   Normal zone: 512 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 65024 pages, LIFO batch:15
[    0.000000] random: get_random_bytes called from start_kernel+0x88/0x3c4 with crng_init=0
[    0.000000] percpu: Embedded 15 pages/cpu @cfdaf000 s29388 r8192 d23860 u61440
[    0.000000] pcpu-alloc: s29388 r8192 d23860 u61440 alloc=15*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 64512
[    0.000000] Kernel command line: board=NBG6617 root=mtd:rootfs zld_ver=2.02 mtdparts=spi0.0:0x60000@0x60000(0:QSEE)ro,0x80000@0xE0000(u-boot)ro,0x10000(env)ro,0x10000(0:ART)ro,0x400000(HLOS),0x10000(dualflag),0x10000(header),0x100000(romd),0x100000(rootfs_data),0x1860000(rootfs) mtdparts= root=31:13
[    0.000000] PID hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
[    0.000000] Memory: 249928K/260096K available (4369K kernel code, 145K rwdata, 1200K rodata, 1024K init, 235K bss, 10168K 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 : 0xd0800000 - 0xff800000   ( 752 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xd0000000   ( 256 MB)
[    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
[    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
[    0.000000]       .text : 0xc0208000 - 0xc0744638   (5362 kB)
[    0.000000]       .init : 0xc0900000 - 0xc0a00000   (1024 kB)
[    0.000000]       .data : 0xc0a00000 - 0xc0a24740   ( 146 kB)
[    0.000000]        .bss : 0xc0a26000 - 0xc0a60c98   ( 236 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 48.00MHz (virt).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xb11fd3bfb, max_idle_ns: 440795203732 ns
[    0.000008] sched_clock: 56 bits at 48MHz, resolution 20ns, wraps every 4398046511096ns
[    0.000021] Switching to timer-based delay loop, resolution 20ns
[    0.000263] Calibrating delay loop (skipped), value calculated using timer frequency.. 96.00 BogoMIPS (lpj=480000)
[    0.000282] pid_max: default: 32768 minimum: 301
[    0.000422] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.000440] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.001002] CPU: Testing write buffer coherency: ok
[    0.001690] Setting up static identity map for 0x80300000 - 0x80300060
[    0.001836] Hierarchical SRCU implementation.
[    0.002490] smp: Bringing up secondary CPUs ...
[    0.005215] smp: Brought up 1 node, 4 CPUs
[    0.005236] SMP: Total of 4 processors activated (384.00 BogoMIPS).
[    0.005244] CPU: All CPU(s) started in SVC mode.
[    0.009650] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5
[    0.009800] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.009825] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    0.010104] pinctrl core: initialized pinctrl subsystem
[    0.010976] NET: Registered protocol family 16
[    0.011298] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.012348] cpuidle: using governor ladder
[    0.012393] cpuidle: using governor menu
[    0.029321] usbcore: registered new interface driver usbfs
[    0.029403] usbcore: registered new interface driver hub
[    0.029484] usbcore: registered new device driver usb
[    0.029531] pps_core: LinuxPPS API ver. 1 registered
[    0.029540] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.029566] PTP clock support registered
[    0.030876] clocksource: Switched to clocksource arch_sys_counter
[    0.031696] NET: Registered protocol family 2
[    0.032391] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
[    0.032432] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
[    0.032481] TCP: Hash tables configured (established 2048 bind 2048)
[    0.032589] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.032627] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.032815] NET: Registered protocol family 1
[    0.032864] PCI: CLS 0 bytes, default 64
[    0.033860] No memory allocated for crashlog
[    0.034064] workingset: timestamp_bits=30 max_order=16 bucket_order=0
[    0.037358] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    0.037373] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[    0.042589] io scheduler noop registered
[    0.042610] io scheduler deadline registered (default)
[    0.045236] bam-dma-engine 8e04000.dma: num-channels unspecified in dt
[    0.045257] bam-dma-engine 8e04000.dma: num-ees unspecified in dt
[    0.045959] tcsr 1949000.tcsr: setting wifi_glb_cfg = 41000000
[    0.046037] tcsr 194b000.tcsr: setting usb hs phy mode select = e700e7
[    0.046109] tcsr 1953000.ess_tcsr: setting ess interface select = 0
[    0.046181] tcsr 1957000.tcsr: setting wifi_noc_memtype_m0_m2 = 2222222
[    0.046400] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[    0.048183] msm_serial 78af000.serial: msm_serial: detected port #0
[    0.048234] msm_serial 78af000.serial: uartclk = 1843200
[    0.048288] 78af000.serial: ttyMSM0 at MMIO 0x78af000 (irq = 25, base_baud = 115200) is a MSM
[    0.048316] msm_serial: console setup on port #0
[    0.577244] console [ttyMSM0] enabled
[    0.582167] msm_serial: driver initialized
[    0.589574] loop: module loaded
[    0.590723] spi_qup 78b5000.spi: IN:block:16, fifo:64, OUT:block:16, fifo:64
[    0.593740] m25p80 spi0.0: mx25l25635f (32768 Kbytes)
[    0.599785] mtd: no mtd-id
[    0.604814] 14 fixed-partitions partitions found on MTD device spi0.0
[    0.607335] Creating 14 MTD partitions on "spi0.0":
[    0.613879] 0x000000000000-0x000000040000 : "SBL1"
[    0.619189] 0x000000040000-0x000000060000 : "MIBIB"
[    0.624000] 0x000000060000-0x0000000c0000 : "QSEE"
[    0.628717] 0x0000000c0000-0x0000000d0000 : "CDT"
[    0.633613] 0x0000000d0000-0x0000000e0000 : "DDRPARAMS"
[    0.638338] 0x0000000e0000-0x000000160000 : "APPSBL"
[    0.643415] 0x000000160000-0x000000170000 : "APPSBLENV"
[    0.648586] 0x000000170000-0x000000180000 : "ART"
[    0.653602] 0x000000180000-0x000000580000 : "kernel"
[    0.658368] 0x000000580000-0x000000590000 : "dualflag"
[    0.663491] 0x000000590000-0x0000005a0000 : "header"
[    0.668365] 0x0000005a0000-0x0000006a0000 : "romd"
[    0.673541] 0x0000006a0000-0x0000007a0000 : "not_root_data"
[    0.678104] 0x0000007a0000-0x000002000000 : "rootfs"
[    0.683594] mtd: device 13 (rootfs) set to be root filesystem
[    0.688449] 1 squashfs-split partitions found on MTD device rootfs
[    0.693916] 0x000000a10000-0x000002000000 : "rootfs_data"
[    0.701333] libphy: ipq40xx_mdio: probed
[    0.736181] ESS reset ok!
[    0.769156] ESS reset ok!
[    1.201274] libphy: Fixed MDIO Bus: probed
[    1.202918] EDMA using MAC@ - using
[    1.202932] f6:89:ca:95:da:35
[    1.301009] i2c /dev entries driver
[    1.331937] NET: Registered protocol family 10
[    1.333737] Segment Routing with IPv6
[    1.335380] NET: Registered protocol family 17
[    1.339074] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
[    1.343778] 8021q: 802.1Q VLAN Support v1.8
[    1.356681] Registering SWP/SWPB emulation handler
[    1.364683] hctosys: unable to open rtc device (rtc0)
[    1.371479] VFS: Mounted root (squashfs filesystem) readonly on device 31:13.
[    1.373057] Freeing unused kernel memory: 1024K
[    1.473726] random: fast init done
[    2.054700] init: Console is alive
[    2.054915] init: - watchdog -
[    3.157323] kmodloader: loading kernel modules from /etc/modules-boot.d/*
[    3.584437] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    3.584506] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
[    3.589059] xhci-hcd xhci-hcd.0.auto: hcc params 0x0228f665 hci version 0x100 quirks 0x0000000002010010
[    3.596494] xhci-hcd xhci-hcd.0.auto: irq 95, io mem 0x08a00000
[    3.606469] hub 1-0:1.0: USB hub found
[    3.611722] hub 1-0:1.0: 1 port detected
[    3.615785] xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
[    3.619567] xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
[    3.624894] xhci-hcd xhci-hcd.0.auto: Host supports USB 3.0  SuperSpeed
[    3.632589] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.639728] hub 2-0:1.0: USB hub found
[    3.647420] hub 2-0:1.0: 1 port detected
[    3.651452] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    3.655002] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 3
[    3.660558] xhci-hcd xhci-hcd.1.auto: hcc params 0x0220f665 hci version 0x100 quirks 0x0000000002010010
[    3.667932] xhci-hcd xhci-hcd.1.auto: irq 96, io mem 0x06000000
[    3.678012] hub 3-0:1.0: USB hub found
[    3.683174] hub 3-0:1.0: 1 port detected
[    3.687335] xhci-hcd xhci-hcd.1.auto: xHCI Host Controller
[    3.691029] xhci-hcd xhci-hcd.1.auto: new USB bus registered, assigned bus number 4
[    3.696302] xhci-hcd xhci-hcd.1.auto: Host supports USB 3.0  SuperSpeed
[    3.703953] usb usb4: We don't know the algorithms for LPM for this host, disabling LPM.
[    3.710957] hub 4-0:1.0: USB hub found
[    3.718804] hub 4-0:1.0: config failed, hub doesn't have any ports! (err -19)
[    3.724463] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[    3.741150] init: - preinit -
[    4.396322] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[    4.396415] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[    4.413875] random: procd: uninitialized urandom read (4 bytes read)
[    7.964224] jffs2: notice: (157) jffs2_build_xattr_subsystem: complete building xattr subsystem, 10 of xdatum (3 unchecked, 7 orphan) and 64 of xref (7 dead, 0 orphan) found.
[    7.975941] mount_root: switching to jffs2 overlay
[    7.997789] overlayfs: upper fs does not support tmpfile.
[    8.018505] urandom-seed: Seeding with /etc/urandom.seed
[    8.177473] procd: - early -
[    8.177585] procd: - watchdog -
[    8.783494] random: crng init done
[    8.872124] procd: - watchdog -
[    8.875011] procd: - ubus -
[    9.027153] procd: - init -
[    9.590735] kmodloader: loading kernel modules from /etc/modules.d/*
[    9.597542] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    9.625446] u32 classifier
[    9.625478]     input device check on
[    9.627090]     Actions configured
[    9.632729] Mirror/redirect action on
[    9.639889] nf_conntrack version 0.5.0 (4096 buckets, 16384 max)
[    9.658037] Loading modules backported from Linux version v4.19.7-0-g61c68f2a2af0
[    9.658079] Backport generated by backports.git v4.19.7-1-0-g148b072d
[    9.669242] ip_tables: (C) 2000-2006 Netfilter Core Team
[    9.732514] xt_time: kernel timezone is -0000
[    9.770966] PPP generic driver version 2.4.2
[    9.772263] NET: Registered protocol family 24
[    9.973187] ath10k_ahb a000000.wifi: Direct firmware load for ath10k/fwcfg-ahb-a000000.wifi.txt failed with error -2
[    9.973233] ath10k_ahb a000000.wifi: Falling back to user helper
[   10.018291] firmware ath10k!fwcfg-ahb-a000000.wifi.txt: firmware_loading_store: map pages failed
[   10.026098] ath10k_ahb a000000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/ct-firmware-5.bin failed with error -2
[   10.026161] ath10k_ahb a000000.wifi: Falling back to user helper
[   10.085639] firmware ath10k!QCA4019!hw1.0!ct-firmware-5.bin: firmware_loading_store: map pages failed
[   10.086051] ath10k_ahb a000000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/ct-firmware-2.bin failed with error -2
[   10.093928] ath10k_ahb a000000.wifi: Falling back to user helper
[   10.160480] firmware ath10k!QCA4019!hw1.0!ct-firmware-2.bin: firmware_loading_store: map pages failed
[   10.160980] ath10k_ahb a000000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/firmware-6.bin failed with error -2
[   10.168717] ath10k_ahb a000000.wifi: Falling back to user helper
[   10.230381] firmware ath10k!QCA4019!hw1.0!firmware-6.bin: firmware_loading_store: map pages failed
[   11.013109] ath10k_ahb a000000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000
[   11.013156] ath10k_ahb a000000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   11.024710] ath10k_ahb a000000.wifi: firmware ver 10.4b-ct-4019-fW-012-81e1edd5 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 crc32 adeedaac
[   11.113967] ath10k_ahb a000000.wifi: board_file api 2 bmi_id 0:16 crc32 bcebe54c
[   12.426159] ath10k_ahb a000000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   12.426203] ath10k_ahb a000000.wifi: msdu-desc: 2500  skid: 32
[   12.473598] ath10k_ahb a000000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   12.475043] ath10k_ahb a000000.wifi: wmi print 'free: 58060 iram: 22488 sram: 35968'
[   12.694330] ath10k_ahb a000000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 32 raw 0 hwcrypto 1
[   12.860656] ath: EEPROM regdomain: 0x0
[   12.860739] ath: EEPROM indicates default country code should be used
[   12.860787] ath: doing EEPROM country->regdmn map search
[   12.860878] ath: country maps to regdmn code: 0x3a
[   12.861178] ath: Country alpha2 being used: US
[   12.861235] ath: Regpair used: 0x3a
[   13.082989] ath10k_ahb a800000.wifi: Direct firmware load for ath10k/fwcfg-ahb-a800000.wifi.txt failed with error -2
[   13.083037] ath10k_ahb a800000.wifi: Falling back to user helper
[   13.331977] firmware ath10k!fwcfg-ahb-a800000.wifi.txt: firmware_loading_store: map pages failed
[   13.338680] ath10k_ahb a800000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/ct-firmware-5.bin failed with error -2
[   13.339854] ath10k_ahb a800000.wifi: Falling back to user helper
[   13.403953] firmware ath10k!QCA4019!hw1.0!ct-firmware-5.bin: firmware_loading_store: map pages failed
[   13.404431] ath10k_ahb a800000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/ct-firmware-2.bin failed with error -2
[   13.412245] ath10k_ahb a800000.wifi: Falling back to user helper
[   13.482920] firmware ath10k!QCA4019!hw1.0!ct-firmware-2.bin: firmware_loading_store: map pages failed
[   13.483344] ath10k_ahb a800000.wifi: Direct firmware load for ath10k/QCA4019/hw1.0/firmware-6.bin failed with error -2
[   13.491202] ath10k_ahb a800000.wifi: Falling back to user helper
[   13.554328] firmware ath10k!QCA4019!hw1.0!firmware-6.bin: firmware_loading_store: map pages failed
[   13.554661] ath10k_ahb a800000.wifi: qca4019 hw1.0 target 0x01000000 chip_id 0x003b00ff sub 0000:0000
[   13.562261] ath10k_ahb a800000.wifi: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[   13.574934] ath10k_ahb a800000.wifi: firmware ver 10.4b-ct-4019-fW-012-81e1edd5 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 crc32 adeedaac
[   13.626498] ath10k_ahb a800000.wifi: board_file api 2 bmi_id 0:17 crc32 bcebe54c
[   14.940700] ath10k_ahb a800000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   14.940751] ath10k_ahb a800000.wifi: msdu-desc: 2500  skid: 32
[   14.987954] ath10k_ahb a800000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   14.989044] ath10k_ahb a800000.wifi: wmi print 'free: 58060 iram: 22488 sram: 35968'
[   15.154047] ath10k_ahb a800000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 32 raw 0 hwcrypto 1
[   15.217201] ath: EEPROM regdomain: 0x0
[   15.217214] ath: EEPROM indicates default country code should be used
[   15.217219] ath: doing EEPROM country->regdmn map search
[   15.217228] ath: country maps to regdmn code: 0x3a
[   15.217237] ath: Country alpha2 being used: US
[   15.217242] ath: Regpair used: 0x3a
[   15.225749] kmodloader: done loading kernel modules from /etc/modules.d/*
[   19.166641] br-lan: port 1(eth0) entered blocking state
[   19.166686] br-lan: port 1(eth0) entered disabled state
[   19.171195] device eth0 entered promiscuous mode
[   19.177998] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[   19.193007] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[   19.194337] ess_edma c080000.edma: eth1: GMAC Link is up with phy_speed=1000
[   19.197835] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[   20.197159] ath: EEPROM regdomain: 0x8114
[   20.197208] ath: EEPROM indicates we should expect a country code
[   20.200199] ath: doing EEPROM country->regdmn map search
[   20.206390] ath: country maps to regdmn code: 0x37
[   20.211696] ath: Country alpha2 being used: DE
[   20.216235] ath: Regpair used: 0x37
[   20.220649] ath: regdomain 0x8114 dynamically updated by user
[   20.224138] ath: EEPROM regdomain: 0x8114
[   20.229945] ath: EEPROM indicates we should expect a country code
[   20.233986] ath: doing EEPROM country->regdmn map search
[   20.240033] ath: country maps to regdmn code: 0x37
[   20.245485] ath: Country alpha2 being used: DE
[   20.250007] ath: Regpair used: 0x37
[   20.254493] ath: regdomain 0x8114 dynamically updated by user
[   20.258656] br-lan: port 1(eth0) entered blocking state
[   20.263833] br-lan: port 1(eth0) entered forwarding state
[   20.270850] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[   23.318776] ath10k_ahb a800000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   23.318823] ath10k_ahb a800000.wifi: msdu-desc: 2500  skid: 32
[   23.366052] ath10k_ahb a800000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   23.366992] ath10k_ahb a800000.wifi: wmi print 'free: 58060 iram: 22488 sram: 35968'
[   23.664912] ath10k_ahb a800000.wifi: Firmware lacks feature flag indicating a retry limit of > 2 is OK, requested limit: 4
[   23.666522] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[   24.317085] ath10k_ahb a800000.wifi: NOTE:  Firmware DBGLOG output disabled in debug_mask: 0x10000000
[   25.165244] ath10k_ahb a000000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[   25.165298] ath10k_ahb a000000.wifi: msdu-desc: 2500  skid: 32
[   25.212779] ath10k_ahb a000000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[   25.214057] ath10k_ahb a000000.wifi: wmi print 'free: 58060 iram: 22488 sram: 35968'
[   25.615603] ath10k_ahb a000000.wifi: Firmware lacks feature flag indicating a retry limit of > 2 is OK, requested limit: 4
[   25.617241] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   25.629275] br-lan: port 2(wlan0) entered blocking state
[   25.631549] br-lan: port 2(wlan0) entered disabled state
[   25.637470] device wlan0 entered promiscuous mode
[   25.642590] br-lan: port 2(wlan0) entered blocking state
[   25.646944] br-lan: port 2(wlan0) entered forwarding state
[   25.652993] br-lan: port 2(wlan0) entered disabled state
[   25.658205] br-lan: port 3(wlan1) entered blocking state
[   25.663176] br-lan: port 3(wlan1) entered disabled state
[   25.668846] device wlan1 entered promiscuous mode
[   25.675571] pppoe-wan: renamed from ppp0
[   26.204067] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   26.209255] br-lan: port 2(wlan0) entered blocking state
[   26.209794] br-lan: port 2(wlan0) entered forwarding state
[   26.657270] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[   26.657643] br-lan: port 3(wlan1) entered blocking state
[   26.662807] br-lan: port 3(wlan1) entered forwarding state
[   49.684245] ath10k_ahb a000000.wifi: Invalid VHT mcs 15 peer stats
[   55.581135] ath10k_ahb a000000.wifi: Invalid peer id 4 or peer stats buffer, peer:   (null)  sta:   (null)
[508049.385628] device wlan1 left promiscuous mode
[508049.387260] br-lan: port 3(wlan1) entered disabled state
[508049.486367] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 0
[508049.486715] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 0
[508049.492432] ath10k_ahb a800000.wifi: peer-unmap-event: unknown peer id 0
[508051.546699] ath10k_ahb a800000.wifi: 10.4 wmi init: vdevs: 16  peers: 48  tid: 96
[508051.546753] ath10k_ahb a800000.wifi: msdu-desc: 2500  skid: 32
[508051.594091] ath10k_ahb a800000.wifi: wmi print 'P 48/48 V 16 K 144 PH 176 T 186  msdu-desc: 2500  sw-crypt: 0 ct-sta: 0'
[508051.597766] ath10k_ahb a800000.wifi: wmi print 'free: 58060 iram: 22488 sram: 35968'
[508051.897259] ath10k_ahb a800000.wifi: Firmware lacks feature flag indicating a retry limit of > 2 is OK, requested limit: 4
[508051.897530] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[508051.912048] br-lan: port 3(wlan1) entered blocking state
[508051.913459] br-lan: port 3(wlan1) entered disabled state
[508051.919209] device wlan1 entered promiscuous mode
[508052.615001] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[508052.615439] br-lan: port 3(wlan1) entered blocking state
[508052.620570] br-lan: port 3(wlan1) entered forwarding state
greearb commented 5 years ago

If you use stock QCA firmware, do you see the same problem?

If you disconnect and reconnect the station device(s), does the problem remain?

There are no obvious errors in the logs.

fishxz commented 5 years ago

what u mean by stock qca firmware? the firmware from https://github.com/kvalo/ath10k-firmware/tree/master/QCA4019/hw1.0/3.6?

if i restart the interface, the problem is instant solved.

Ping wird ausgeführt für openwrt.lan [192.168.1.1] mit 32 Bytes Daten:
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Zeitüberschreitung der Anforderung.
Antwort von 192.168.1.1: Bytes=32 Zeit=2ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=2ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=5ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=3ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Zeitüberschreitung der Anforderung.
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Zeitüberschreitung der Anforderung.
Zeitüberschreitung der Anforderung.
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64
Antwort von 192.168.1.1: Bytes=32 Zeit=1ms TTL=64`

happened again like 1 hour ago.

greearb commented 5 years ago

Yes, like that firmware from kvalo (or the QCA one packaged in openwrt).

Can you show me ping time for when it is messing up? And, if you do a larger download, will it work OK (ie, is it just ping that is weird?)

fishxz commented 5 years ago

this is the ping when it is messing up. i will check next time a download, but i can say discord lags like hell then, same for counter-strike. it is like im just dropping alot of pakages.

if i install "ath10k-firmware-qca4019" from openwrt, will this one get loaded instead, because it seems like i cant uninstall the -ct version.

fishxz commented 5 years ago

i installed today the non -ct pakage. i will report in a few days

fishxz commented 5 years ago

i tested for a while the non -ct pakage and i couldnt reproduce it, so i switched back to -ct, to see if it will happen again...

i also noticed another problem: its not possible to run 2 1080p streams over wlan in my network. as soon the second stream starts, both will start to buffer frequently. (ct and non -ct)

fishxz commented 5 years ago

just happened again... after wifi restart all is fine again

vamanea commented 5 years ago

Just wanted to add my +1 for this problem.

I have exactly the same router and faced pretty much the same problems with the -ct firmwares. I downloaded the ones from candelatech.com as well but the problems described above persisted. Once I downloaded and started using the non -ct firmware everything started working properly again, no timeouts, no need to restart wifi, and my ESPxxx devices did not randomly disconnect.

For reference this is the firmware I'm currently using:

[   12.566301] ath10k_ahb a000000.wifi: firmware ver 10.4-3.6-00140 api 5 features no-p2p,mfp,peer-flow-ctrl,btcoex-param,allows-mesh-bcast,no-ps crc32 ba79b746
[   12.768511] ath10k_ahb a000000.wifi: board_file api 2 bmi_id 0:16 crc32 bcebe54c
[   14.139131] ath10k_ahb a000000.wifi: 10.4 wmi init: vdevs: 16  peers: 528  tid: 102
[   14.139185] ath10k_ahb a000000.wifi: msdu-desc: 2500  skid: 32
[   14.189408] ath10k_ahb a000000.wifi: htt-ver 2.2 wmi-op 6 htt-op 4 cal pre-cal-file max-sta 512 raw 0 hwcrypto 1
greearb commented 5 years ago

Please describe how your systems are configured. Can you reproduce it if you disable encryption, for instance? Are you using PMF/MFP? Is it only certain devices that have this connection problem, or do all of them exhibit the same behaviour?

Are you able to take an RF capture in the case where the problem is happening?

vamanea commented 5 years ago

@greearb I can't speak for OP but for my cases:

I am able to take a RF capture, is it something specific I'm looking for?

greearb commented 5 years ago

I just uploaded a new beta firmware that fixes a problem that could cause tx-scheduling problems in the firmware. Maybe retest with that most recent firmware to see if it works any better in your case?

vamanea commented 5 years ago

@greearb I suppose I need to update the driver too, the current driver I have is spamming my console with this: ath10k_ahb a800000.wifi: Unknown (10.4) eventid: 40955

vamanea commented 5 years ago

Updated to beta ct firmware: 10.4b-ct-4019-fH-012-c886a6af and also updated to last openwrt and it seems much better.

Only problem seem to be some kernel warnings:

[175100.198548] WARNING: CPU: 2 PID: 1805 at backports-4.19.32-1/net/wireless/util.c:1146 cfg80211_calculate_bitrate+0x1d8/0x24c [cfg80211]
[175100.202120] invalid rate bw=0, mcs=15, nss=4
[175100.214473] 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 wireguard 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 hwmon crc_ccitt compat ledtrig_usbport nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 ip6_udp_tunnel udp_tunnel leds_gpio xhci_plat_hcd xhci_pci xhci_hcd dwc3 dwc3_of_simple gpio_button_hotplug
[175100.268563] CPU: 2 PID: 1805 Comm: hostapd Not tainted 4.14.111 #0
[175100.290040] Hardware name: Generic DT based system
[175100.296055] [<c030e45c>] (unwind_backtrace) from [<c030a968>] (show_stack+0x10/0x14)
[175100.300909] [<c030a968>] (show_stack) from [<c07393c8>] (dump_stack+0x88/0x9c)
[175100.308892] [<c07393c8>] (dump_stack) from [<c0320e20>] (__warn+0xe4/0x100)
[175100.316250] [<c0320e20>] (__warn) from [<c0320e74>] (warn_slowpath_fmt+0x38/0x48)
[175100.325032] [<c0320e74>] (warn_slowpath_fmt) from [<bf20072c>] (cfg80211_calculate_bitrate+0x1d8/0x24c [cfg80211])
[175100.331855] [<bf20072c>] (cfg80211_calculate_bitrate [cfg80211]) from [<bf2287e4>] (nl80211_put_sta_rate+0x48/0x2e4 [cfg80211])
[175100.341359] [<bf2287e4>] (nl80211_put_sta_rate [cfg80211]) from [<bf212328>] (nl80211_update_connect_params+0x794/0x1034 [cfg80211])
[175100.353144] [<bf212328>] (nl80211_update_connect_params [cfg80211]) from [<bf212d68>] (cfg80211_del_sta_sinfo+0x98/0x188 [cfg80211])
[175100.365197] [<bf212d68>] (cfg80211_del_sta_sinfo [cfg80211]) from [<bf25df20>] (sta_set_sinfo+0xc54/0xcb0 [mac80211])
[175100.378521] [<bf25df20>] (sta_set_sinfo [mac80211]) from [<bf25df9c>] (__sta_info_destroy+0x20/0x28 [mac80211])
[175100.388940] [<bf25df9c>] (__sta_info_destroy [mac80211]) from [<bf25e00c>] (sta_info_destroy_addr_bss+0x28/0x40 [mac80211])
[175100.399090] [<bf25e00c>] (sta_info_destroy_addr_bss [mac80211]) from [<bf20a4f8>] (nl80211_del_station+0xe8/0xf8 [cfg80211])
[175100.409534] [<bf20a4f8>] (nl80211_del_station [cfg80211]) from [<c066b620>] (genl_rcv_msg+0x2e8/0x388)
[175100.420352] [<c066b620>] (genl_rcv_msg) from [<c066a800>] (netlink_rcv_skb+0x8c/0xfc)
[175100.429537] [<c066a800>] (netlink_rcv_skb) from [<c066b328>] (genl_rcv+0x24/0x34)
[175100.437514] [<c066b328>] (genl_rcv) from [<c0669fd4>] (netlink_unicast+0x158/0x208)
[175100.445056] [<c0669fd4>] (netlink_unicast) from [<c066a478>] (netlink_sendmsg+0x334/0x37c)
[175100.452984] [<c066a478>] (netlink_sendmsg) from [<c0619b64>] (sock_sendmsg+0x14/0x24)
[175100.461049] [<c0619b64>] (sock_sendmsg) from [<c061a098>] (___sys_sendmsg+0x204/0x22c)
[175100.469041] [<c061a098>] (___sys_sendmsg) from [<c061aea0>] (__sys_sendmsg+0x40/0x64)
[175100.476960] [<c061aea0>] (__sys_sendmsg) from [<c0307680>] (ret_fast_syscall+0x0/0x54)
[175100.486713] ---[ end trace 60ecb48db228e521 ]---