OpenWrt/LEDE Project

  • Status Unconfirmed
  • Percent Complete
    0%
  • Task Type Bug Report
  • Category Base system
  • Assigned To No-one
  • Operating System All
  • Severity High
  • Priority Very Low
  • Reported Version Trunk
  • Due in Version Undecided
  • Due Date Undecided
  • Private
Attached to Project: OpenWrt/LEDE Project
Opened by Paul Traina - 03.04.2019

FS#2220 - ath10k NEW kernel thread crash in CT htt firmware - ath10k unusable after 50k seconds

OpenWrt SNAPSHOT, r9754-fbe2e7d15e
Trunk Pulled April 1st.
Archer C7v2

Unclear how to reproduce, just happens.

Supplying init logs and kernel trace logs:

[ 31.099086] ath10k 4.19 driver, optimized for CT firmware, probing pci device: 0x3c.
[ 31.108127] PCI: Enabling device 0000:00:00.0 (0000 → 0002)
[ 31.116173] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
[ 31.389333] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/fwcfg-pci-0000:00:00.0.txt failed with error -2
[ 31.400044] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 31.491602] firmware ath10k!fwcfg-pci-0000:00:00.0.txt: firmware_loading_store: map pages failed
[ 31.500774] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
[ 31.511656] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 31.687486] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
[ 31.696848] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/cal-pci-0000:00:00.0.bin failed with error -2
[ 31.707378] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 31.949406] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/ct-firmware-5.bin failed with error -2
[ 31.960559] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 32.095183] firmware ath10k!QCA988X!hw2.0!ct-firmware-5.bin: firmware_loading_store: map pages failed
[ 32.104800] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/ct-firmware-2.bin failed with error -2
[ 32.115944] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 32.291783] firmware ath10k!QCA988X!hw2.0!ct-firmware-2.bin: firmware_loading_store: map pages failed
[ 32.301402] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
[ 32.312279] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 32.487841] firmware ath10k!QCA988X!hw2.0!firmware-6.bin: firmware_loading_store: map pages failed
[ 32.497209] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-5.bin failed with error -2
[ 32.508092] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 32.684408] firmware ath10k!QCA988X!hw2.0!firmware-5.bin: firmware_loading_store: map pages failed
[ 32.693770] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-4.bin failed with error -2
[ 32.704646] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 32.879819] firmware ath10k!QCA988X!hw2.0!firmware-4.bin: firmware_loading_store: map pages failed
[ 32.889172] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-3.bin failed with error -2
[ 32.900050] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 33.076143] firmware ath10k!QCA988X!hw2.0!firmware-3.bin: firmware_loading_store: map pages failed
[ 33.089091] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
[ 33.098473] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 0
[ 33.110860] ath10k_pci 0000:00:00.0: firmware ver 10.1-ct-8x-fW-022-883e26a8 api 2 features wmi-10.x,has-wmi-mgmt-tx,mfp,txstatus-noack,wmi-10.x-CT,ratemask-CT,txrate-CT,get-temp-CT,tx-rc-CT,cust-stats-CT,retry-gt2-CT,txrate2-CT,beacon-cb-CT crc32 7a67ce60
[ 33.219987] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
[ 33.230599] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 33.382851] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
[ 33.392104] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
[ 34.332647] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16 peers: 127 tid: 256
[ 34.350100] ath10k_pci 0000:00:00.0: wmi print ‘P 128 V 8 T 410’ [ 34.356422] ath10k_pci 0000:00:00.0: wmi print ‘msdu-desc: 1424 sw-crypt: 0 ct-sta: 0’ [ 34.364543] ath10k_pci 0000:00:00.0: wmi print ‘alloc rem: 24680 iram: 26872’ [ 34.432688] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 2 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
[ 34.445504] ath10k_pci 0000:00:00.0: NOTE: Firmware DBGLOG output disabled in debug_mask: 0×10000000 [ 34.579131] ath: EEPROM regdomain: 0×0 [ 34.579137] ath: EEPROM indicates default country code should be used
[ 34.579140] ath: doing EEPROM country→regdmn map search
[ 34.579152] ath: country maps to regdmn code: 0x3a
[ 34.579158] ath: Country alpha2 being used: US
[ 34.579161] ath: Regpair used: 0x3a
[ 34.652355] ath: EEPROM regdomain: 0×0 [ 34.652362] ath: EEPROM indicates default country code should be used
[ 34.652365] ath: doing EEPROM country→regdmn map search
[ 34.652378] ath: country maps to regdmn code: 0x3a
[ 34.652383] ath: Country alpha2 being used: US
[ 34.652386] ath: Regpair used: 0x3a
[ 34.669837] ieee80211 phy1: Selected rate control algorithm ‘minstrel_ht’ [ 34.671315] ieee80211 phy1: Atheros AR9550 Rev:0 mem=0xb8100000, irq=12 50893 seconds later: [50893.910674] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50893.961879] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.015322] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.064267] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.115474] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.166670] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.217864] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.269072] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.320275] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.371474] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.422686] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.473885] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.525096] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.576362] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.627495] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.678696] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.732123] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.781096] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.834596] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.883493] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[50894.934705] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50894.985904] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
...repeats...
[50896.978515] ath10k_pci 0000:00:00.0: wmi command 36954 timeout, restarting hardware
[50896.986336] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50897.033922] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
...repeats...
[50900.018475] ath10k_pci 0000:00:00.0: wmi command 40859 timeout, restarting hardware
[50900.026455] ath10k_pci 0000:00:00.0: failed to send wmi nop: -11
[50900.032989] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request: -143
[50900.048476] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 87385a00 vdev: 0 addr: 50:f5:da:36:35:2c
[50900.059639] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 85914a00 vdev: 0 addr: ac:bc:32:b4:c4:d7
[50900.070766] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 86c43400 vdev: 1 addr: 32:b5:c2:96:62:fd
[50900.081883] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 86e1f000 vdev: 0 addr: 30:b5:c2:96:62:fd
[50900.093145] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[50900.355684] ieee80211 phy0: Hardware restart was requested
[50900.361749] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -143
[50900.369442] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -143
[50901.941908] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16 peers: 127 tid: 256
[50901.959432] ath10k_pci 0000:00:00.0: wmi print ‘P 128 V 8 T 410’ [50901.965928] ath10k_pci 0000:00:00.0: wmi print ‘msdu-desc: 1424 sw-crypt: 0 ct-sta: 0’ [50901.974101] ath10k_pci 0000:00:00.0: wmi print ‘alloc rem: 24680 iram: 26872’ [50902.043511] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
[50902.058893] ath10k_pci 0000:00:00.0: set-coverage-class, phyclk: 88 value: 0
[50902.079716] ath10k_pci 0000:00:00.0: device successfully recovered
[50902.086147] ath10k_pci 0000:00:00.0: Invalid state: 3 in ath10k_htt_tx_32, warning will not be repeated.
[50902.086152] ————[ cut here ]———— [50902.086232] WARNING: CPU: 0 PID: 4533 at /home/vagrant/openwrt/build_dir/target-mips_24kc_musl/linux-ath79_generic/ath10k-ct-2019-03-25-2e917efb/ath10k-4.19/htt_tx.c:1253 ath10k_convert_hw_rate_to_rate_info+0×190/0xae4 [ath10k_core]
[50902.086235] Modules linked in: ath9k ath9k_common pppoe ppp_async ath9k_hw ath10k_pci ath10k_core ath pppox ppp_generic nf_conntrack_ipv6 mac80211 iptable_nat ipt_REJECT ipt_MASQUERADE cfg80211 xt_time xt_tcpudp xt_tcpmss xt_statistic xt_state xt_recent xt_nat xt_multiport xt_mark xt_mac xt_limit xt_length xt_hl xt_helper xt_ecn xt_dscp xt_conntrack xt_connmark xt_connlimit xt_connbytes xt_comment xt_TRACE xt_TCPMSS xt_REDIRECT xt_LOG xt_HL xt_FLOWOFFLOAD xt_DSCP xt_CT xt_CLASSIFY 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_netlink iptable_raw iptable_mangle iptable_filter ipt_ECN ip6table_raw ip_tables crc_ccitt compat sch_cake nf_conntrack
[50902.086394] sch_tbf sch_ingress sch_htb sch_hfsc em_u32 cls_u32 cls_tcindex cls_route cls_matchall cls_fw cls_flow cls_basic act_skbedit act_mirred ledtrig_usbport xt_set ip_set_list_set ip_set_hash_netportnet ip_set_hash_netport ip_set_hash_netnet ip_set_hash_netiface ip_set_hash_net ip_set_hash_mac ip_set_hash_ipportnet ip_set_hash_ipportip ip_set_hash_ipport ip_set_hash_ipmark ip_set_hash_ip ip_set_bitmap_port ip_set_bitmap_ipmac ip_set_bitmap_ip ip_set nfnetlink nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 ifb tun ehci_platform ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
[50902.086515] CPU: 0 PID: 4533 Comm: kworker/0:1 Not tainted 4.14.109 #0
[50902.086640] Workqueue: events_freezable ieee80211_alloc_hw_nm [mac80211]
[50902.086644] Stack : 00000009 000004e5 87d58468 800b2878 804aeb00 87c07cb4 85da4ddc 80501947
[50902.086665] 804aac3c 87c07c8c 80500000 800b36d4 87d58468 00000000 87c07c68 dabdb416
[50902.086684] 00000000 00000000 00000000 00000000 00000004 8040f32c 00000001 68775f6e
[50902.086702] 735f6672 80500000 000001be 65657a61 00000000 00000000 87154274 87124968
[50902.086721] 00000009 000004e5 87d58468 8723cbf0 00000000 00000000 00000000 80650000
[50902.086739] ...
[50902.086745] Call Trace:
[50902.086767] [<8006a90c>] show_stack+0×58/0×100 [50902.086791] [<80084fa0>]
warn+0xe4/0×118 [50902.086802] [<80085064>] warn_slowpath_null+0x1c/0×28 [50902.086866] [<87124968>] ath10k_convert_hw_rate_to_rate_info+0×190/0xae4 [ath10k_core]
[50902.086896] [<87103d04>] 0x87103d04
[50902.086913] —[ end trace db720eb818c982c2 ]— [50902.086925] ath10k_pci 0000:00:00.0: failed to transmit packet, dropping: -19
[50902.086938] ath10k_pci 0000:00:00.0: failed to submit frame: -19
[50902.086945] ath10k_pci 0000:00:00.0: failed to push frame: -19
[50902.086967] ath10k_pci 0000:00:00.0: failed to transmit packet, dropping: -19
[50902.086977] ath10k_pci 0000:00:00.0: failed to submit frame: -19
[50902.086984] ath10k_pci 0000:00:00.0: failed to push frame: -19
[50902.097084] ath10k_pci 0000:00:00.0: failed to transmit packet, dropping: -19
...repeats...
[55789.947757] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55789.998968] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
55792.860153] ath10k_pci 0000:00:00.0: wmi command 36893 timeout, restarting hardware
[55792.867970] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55792.875741] ath10k_pci 0000:00:00.0: failed to delete peer b0:19:c6:64:f9:69 for vdev 0: -11
[55792.884453] ath10k_pci 0000:00:00.0: found sta peer b0:19:c6:64:f9:69 (ptr 86e1f600 id 33) entry on vdev 0 after it was supposedly removed
[55792.897563] ————[ cut here ]———— [55792.902662] WARNING: CPU: 0 PID: 2420 at backports-4.19.23-1/net/mac80211/sta_info.c:1004 sta_set_sinfo+0xbac/0xc58 [mac80211]
[55792.914415] Modules linked in: ath9k ath9k_common pppoe ppp_async ath9k_hw ath10k_pci ath10k_core ath pppox ppp_generic nf_conntrack_ipv6 mac80211 iptable_nat ipt_REJECT ipt_MASQUERADE cfg80211 xt_time xt_tcpudp xt_tcpmss xt_statistic xt_state xt_recent xt_nat xt_multiport xt_mark xt_mac xt_limit xt_length xt_hl xt_helper xt_ecn xt_dscp xt_conntrack xt_connmark xt_connlimit xt_connbytes xt_comment xt_TRACE xt_TCPMSS xt_REDIRECT xt_LOG xt_HL xt_FLOWOFFLOAD xt_DSCP xt_CT xt_CLASSIFY 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_netlink iptable_raw iptable_mangle iptable_filter ipt_ECN ip6table_raw ip_tables crc_ccitt compat sch_cake nf_conntrack
[55792.986359] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55792.986485] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55793.003634] sch_tbf sch_ingress sch_htb sch_hfsc em_u32 cls_u32 cls_tcindex cls_route cls_matchall cls_fw cls_flow cls_basic act_skbedit act_mirred ledtrig_usbport xt_set ip_set_list_set ip_set_hash_netportnet ip_set_hash_netport ip_set_hash_netnet ip_set_hash_netiface ip_set_hash_net ip_set_hash_mac ip_set_hash_ipportnet ip_set_hash_ipportip ip_set_hash_ipport ip_set_hash_ipmark ip_set_hash_ip ip_set_bitmap_port ip_set_bitmap_ipmac ip_set_bitmap_ip ip_set nfnetlink nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables ip6t_REJECT x_tables nf_reject_ipv6 ifb tun ehci_platform ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
[55793.062374] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55793.070938] CPU: 0 PID: 2420 Comm: hostapd Tainted: G W 4.14.109 #0
[55793.078506] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55793.086883] Stack : 80520000 804d84a4 00000000 00000000 804aeb00 85da1b04 87dd02dc 80501947
[55793.095767] 804aacd0 00000974 80653670 000003ec 85da1cb4 00000001 85da1ab8 b78f71e9
[55793.104653] 00000000 00000000 80650000 00012a38 00000000 00000000 00000008 00000000
[55793.114085] 000002ce 861a1102 000002cc 00000000 80000000 00000000 87064a04 87007c2c
[55793.123006] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55793.131158] 00000009 000003ec 85da1cb4 87ff8800 00000002 802697c4 00000000 80650000
[55793.139658] ...
[55793.142953] Call Trace:
[55793.145468] [<8006a90c>] show_stack+0×58/0×100 [55793.149997] [<80084fa0>] warn+0xe4/0×118 [55793.154512] [<80085064>] warn_slowpath_null+0x1c/0×28 [55793.160292] [<87007c2c>] sta_set_sinfo+0xbac/0xc58 [mac80211]
[55793.166181] [<87007db8>] sta_info_destroy_addr_bss+0×38/0×60 [mac80211]
[55793.173452] [<8778f17c>] cfg80211_check_station_change+0x1be0/0x2c54 [cfg80211]
[55793.180959] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55793.188700] —[ end trace db720eb818c982c3 ]— [55793.224635] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55793.275831] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
...
[55796.220118] ath10k_pci 0000:00:00.0: wmi command 36954 timeout, restarting hardware
[55796.245481] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55796.296663] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55796.317807] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 8561b600 vdev: 0 addr: 50:f5:da:36:35:2c
[55796.328987] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 85322c00 vdev: 0 addr: ac:bc:32:b4:c4:d7
[55796.340121] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 834fb200 vdev: 1 addr: 32:b5:c2:96:62:fd
[55796.351253] ath10k_pci 0000:00:00.0: removing peer, cleanup-all, deleting: peer 834fb000 vdev: 0 addr: 30:b5:c2:96:62:fd
[55796.860635] irq 15: nobody cared (try booting with the “irqpoll” option)
[55796.867447] CPU: 0 PID: 15620 Comm: kworker/u2:0 Tainted: G W 4.14.109 #0
[55796.875540] Workqueue: ath10k_wq ath10k_core_create [ath10k_core]
[55796.881718] Stack : ffffffff 00000003 80653ae0 800b2878 804aeb00 87c07cc4 86e123dc 80501947
[55796.890201] 804aac3c 87c07c9c 80500000 800b36d4 80653ae0 00000000 87c07c78 86c8eb6e
[55796.898683] 00000000 00000000 00000000 000151c0 00000000 00000000 00000008 00000000
[55796.907167] 00000323 80500000 00000322 00000000 00000000 8050b6a4 0000000f 00011000
[55796.915650] ffffffff 00000003 80653ae0 80506ae8 00000000 802697c4 00000000 80650000
[55796.924134] ...
[55796.926615] Call Trace:
[55796.929112] [<8006a90c>] show_stack+0×58/0×100 [55796.933620] [<800b6f6c>]
report_bad_irq.isra.0+0×54/0xf0
[55796.939179] [<800b72f4>] note_interrupt+0×284/0×330 [55796.944142] [<800b488c>] handle_irq_event_percpu+0x4c/0×64 [55796.949706] [<800b48e0>] handle_irq_event+0x3c/0x6c
[55796.954650] [<800b7ca4>] handle_level_irq+0×110/0×154 [55796.959772] [<800b3d90>] generic_handle_irq+0×38/0×50 [55796.964903] [<802d4750>] ar724x_pci_irq_handler+0xa4/0xdc
[55796.970376] [<800b3d90>] generic_handle_irq+0×38/0×50 [55796.975502] [<80219354>] ath79_intc_irq_handler+0×94/0xec
[55796.980981] [<800b3d90>] generic_handle_irq+0×38/0×50 [55796.986117] [<804144d4>] do_IRQ+0x1c/0x2c
[55796.990185] [<80219260>] plat_irq_dispatch+0xc0/0×120 [55796.995306] [<800658d8>] handle_int+0×138/0×144 [55796.999900] [<80219260>] plat_irq_dispatch+0xc0/0×120 [55797.005018] handlers:
[55797.007333] [<877134a0>] ath10k_pci_irq_msi_fw_mask [ath10k_pci]
[55797.013420] Disabling IRQ #15
[55797.285000] ieee80211 phy0: Hardware restart was requested
[55800.300248] ath10k_pci 0000:00:00.0: bss channel survey timed out
[55801.740207] ath10k_pci 0000:00:00.0: 10.1 wmi init: vdevs: 16 peers: 127 tid: 256
[55801.850097] ath10k_pci 0000:00:00.0: wmi print ‘P 128 V 8 T 410’ [55801.856213] ath10k_pci 0000:00:00.0: wmi print ‘msdu-desc: 1424 sw-crypt: 0 ct-sta: 0’ [55801.864353] ath10k_pci 0000:00:00.0: wmi print ‘alloc rem: 24680 iram: 26872’ [55802.950176] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
[55808.120391] ath10k_pci 0000:00:00.0: device successfully recovered
[55808.126726] net_ratelimit: 79 callbacks suppressed
[55808.126736] ath10k_pci 0000:00:00.0: failed to transmit packet, dropping: -19
[55808.126748] ath10k_pci 0000:00:00.0: failed to submit frame: -19
[55808.126756] ath10k_pci 0000:00:00.0: failed to push frame: -19
[55808.126771] ath10k_pci 0000:00:00.0: failed to transmit packet, dropping: -19
[55808.126780] ath10k_pci 0000:00:00.0: failed to submit frame: -19
[55808.126787] ath10k_pci 0000:00:00.0: failed to push frame: -19
[55808.560031] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55808.567607] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55808.575152] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55808.670084] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55808.677616] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55808.790161] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55808.797700] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55808.890070] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55808.897614] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.000079] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.007588] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.110035] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.117559] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.125363] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.220094] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.227789] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.330097] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.337638] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.440112] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.447645] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.550389] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon
[55809.557931] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 0, skipped old beacon
[55809.660388] ath10k_pci 0000:00:00.0: SWBA overrun on vdev 1, skipped old beacon


psyborg commented on 09.09.2019 19:17

same crash here with set_sta_sinfo message and few others. after interface recovered everything worked fine.

Linux version 4.14.118
MIPS: machine is Ubiquiti LiteBeam AC Gen2
ath79 target
STA xx:xx:xx:xx:xx:xx = AR9462 / Win10

Sun Sep  8 11:10:33 2019 daemon.info hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authenticated
Sun Sep  8 11:10:36 2019 daemon.info hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authenticated
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.509074] ath10k_pci 0000:00:00.0: failed to delete peer xx:xx:xx:xx:xx:xx for vdev 0: -145
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.517841] ath10k_pci 0000:00:00.0: found sta peer xx:xx:xx:xx:xx:xx (ptr 82def400 id 194) entry on vdev 0 after it was supposedly removed
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.530780] ------------[ cut here ]------------
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.535745] WARNING: CPU: 0 PID: 1447 at backports-4.19.32-1/net/mac80211/sta_info.c:1004 sta_set_sinfo+0xbac/0xc58 [mac80211]
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.547406] Modules linked in: ath9k ath9k_common pppoe ppp_async ath9k_hw ath10k_pci ath10k_core ath pppox ppp_generic mac80211 iptable_nat iptable_mangle iptable_filter ipt_REJECT ipt_MASQUERADE ip_tables 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 x_tables thermal_sys slhc nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_log_common nf_flow_table_hw nf_flow_table nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack hwmon crc_ccitt compat ehci_platform ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.607388] CPU: 0 PID: 1447 Comm: hostapd Not tainted 4.14.118 #0
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.613786] Stack : 804d0000 80489150 00000000 00000000 80460fc0 82dcfafc 82eab45c 804b1307
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.622387]         8045d190 000005a7 80603670 000003ec 82dcfcac 00000001 82dcfab0 0bb73a6e
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.630988]         00000000 00000000 80600000 00003ff8 00000000 00000000 00000008 00000000
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.639585]         000000cd 6827cfdb 000000cc 6d3a2068 80000000 00000000 83264c84 83207c1c
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.648168]         00000009 000003ec 82dcfcac 83add800 00000010 8026bd44 00000000 80600000
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.656763]         ...
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.659347] Call Trace:
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.661949] [<8006a9ec>] show_stack+0x58/0x100
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.666578] [<80085080>] __warn+0xe4/0x118
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.670853] [<80085144>] warn_slowpath_null+0x1c/0x28
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.676220] [<83207c1c>] sta_set_sinfo+0xbac/0xc58 [mac80211]
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.682273] [<83207da8>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.689265] [<8314f01c>] cfg80211_check_station_change+0x1be0/0x2c54 [cfg80211]
Sun Sep  8 11:10:39 2019 kern.warn kernel: [491454.696795] ---[ end trace 8cc47180b897e71e ]---
Sun Sep  8 11:10:42 2019 kern.warn kernel: [491457.709041] ath10k_pci 0000:00:00.0: failed to wait for created wmi peer xx:xx:xx:xx:xx:xx on vdev 0: -145
Sun Sep  8 11:10:42 2019 kern.warn kernel: [491457.718946] ath10k_pci 0000:00:00.0: failed to add peer xx:xx:xx:xx:xx:xx for vdev 0 when adding a new sta: -145
Sun Sep  8 11:10:42 2019 daemon.notice hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: Could not add STA to kernel driver
Sun Sep  8 11:10:45 2019 kern.warn kernel: [491460.749027] ath10k_pci 0000:00:00.0: wmi command 36977 timeout, restarting hardware
Sun Sep  8 11:10:45 2019 kern.warn kernel: [491460.756905] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
Sun Sep  8 11:10:45 2019 kern.info kernel: [491460.892000] ieee80211 phy0: Hardware restart was requested
Sun Sep  8 11:10:45 2019 kern.warn kernel: [491460.898798] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -143
Sun Sep  8 11:10:45 2019 kern.warn kernel: [491460.906410] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -143
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.117200] ath10k_pci 0000:00:00.0: pdev param 0 not supported by firmware
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.149024] ------------[ cut here ]------------
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.154025] WARNING: CPU: 0 PID: 1408 at backports-4.19.32-1/net/mac80211/driver-ops.h:19 ieee80211_parse_p2p_noa+0x3ac/0xaa4 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.166585] wlan0.sta1:  Failed check-sdata-in-driver check, flags: 0x1
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.173389] Modules linked in: ath9k ath9k_common pppoe ppp_async ath9k_hw ath10k_pci ath10k_core ath pppox ppp_generic mac80211 iptable_nat iptable_mangle iptable_filter ipt_REJECT ipt_MASQUERADE ip_tables 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 x_tables thermal_sys slhc nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_log_common nf_flow_table_hw nf_flow_table nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack hwmon crc_ccitt compat ehci_platform ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.233401] CPU: 0 PID: 1408 Comm: kworker/0:2 Tainted: G        W       4.14.118 #0
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.241555] Workqueue: events_freezable ieee80211_alloc_hw_nm [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.248449] Stack : 00000009 00000013 832792f8 800b2b74 80460fc0 00000000 00000001 82e85894
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.257063]         8045d0fc 82fb5cdc 804b0000 800b39d4 832792f8 00000000 82fb5cb8 5fea3f86
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.265665]         00000000 00000000 00000000 00004c60 00000000 00000000 00000008 00000000
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.274263]         000000e9 804b0000 000000e8 65657a61 00000000 00000000 832662ac 83235c14
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.282856]         00000009 00000013 832792f8 00000000 00000000 8026bd44 00000000 80600000
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.291609]         ...
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.294191] Call Trace:
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.296795] [<8006a9ec>] show_stack+0x58/0x100
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.301491] [<80085080>] __warn+0xe4/0x118
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.305747] [<800850e4>] warn_slowpath_fmt+0x30/0x3c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.311038] [<83235c14>] ieee80211_parse_p2p_noa+0x3ac/0xaa4 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.317906] [<803cc554>] __mutex_lock.isra.0+0x230/0x368
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.323490] [<832007e4>] ieee80211_alloc_hw_nm+0x784/0x7a4 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.330210] [<8009a0a4>] process_one_work+0x23c/0x3e8
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.335429] [<8009a570>] worker_thread+0x320/0x56c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.340414] [<8009f8a4>] kthread+0x128/0x13c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.344850] [<80065558>] ret_from_kernel_thread+0x14/0x1c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.350438] ---[ end trace 8cc47180b897e71f ]---
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.361001] ------------[ cut here ]------------
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.366043] WARNING: CPU: 0 PID: 1408 at backports-4.19.32-1/net/mac80211/key.c:741 ieee80211_enable_keys+0xb4/0x1a8 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.377828] Modules linked in: ath9k ath9k_common pppoe ppp_async ath9k_hw ath10k_pci ath10k_core ath pppox ppp_generic mac80211 iptable_nat iptable_mangle iptable_filter ipt_REJECT ipt_MASQUERADE ip_tables 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 x_tables thermal_sys slhc nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_log_common nf_flow_table_hw nf_flow_table nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack hwmon crc_ccitt compat ehci_platform ehci_hcd gpio_button_hotplug usbcore nls_base usb_common
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.437888] CPU: 0 PID: 1408 Comm: kworker/0:2 Tainted: G        W       4.14.118 #0
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.446028] Workqueue: events_freezable ieee80211_alloc_hw_nm [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.452936] Stack : 00000009 000002e5 00000005 800b2b74 80460fc0 00000000 00000001 82e85894
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.461538]         8045d0fc 82fb5ce4 804b0000 800b39d4 00000005 00000001 82fb5cc0 5fea3f86
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.470133]         00000000 00000000 804bda70 00005530 00000000 00000000 00000008 00000000
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.478716]         00000100 804b0000 000000ff 65657a61 00000000 00000000 83266130 83234a24
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.487311]         00000009 000002e5 00000005 00000000 00000000 8026bd44 00000000 80600000
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.496061]         ...
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.498643] Call Trace:
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.501281] [<8006a9ec>] show_stack+0x58/0x100
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.505930] [<80085080>] __warn+0xe4/0x118
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.510210] [<80085144>] warn_slowpath_null+0x1c/0x28
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.515577] [<83234a24>] ieee80211_enable_keys+0xb4/0x1a8 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.522244] [<83238ad0>] ieee80211_reconfig+0x904/0xb88 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.528684] [<832007e4>] ieee80211_alloc_hw_nm+0x784/0x7a4 [mac80211]
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.535412] [<8009a0a4>] process_one_work+0x23c/0x3e8
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.540657] [<8009a570>] worker_thread+0x320/0x56c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.545617] [<8009f8a4>] kthread+0x128/0x13c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.550070] [<80065558>] ret_from_kernel_thread+0x14/0x1c
Sun Sep  8 11:10:47 2019 kern.warn kernel: [491462.555644] ---[ end trace 8cc47180b897e720 ]---
Sun Sep  8 11:10:47 2019 kern.info kernel: [491462.563426] ath10k_pci 0000:00:00.0: device successfully recovered

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing