Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

FS#333 - ath10k_pci: crash after ~10d uptime #6764

Closed
openwrt-bot opened this issue Dec 12, 2016 · 9 comments
Closed

FS#333 - ath10k_pci: crash after ~10d uptime #6764

openwrt-bot opened this issue Dec 12, 2016 · 9 comments
Labels

Comments

@openwrt-bot
Copy link

stintel:

After ~10 days of uptime, ath10k crashed and I could no longer associate.

Device: D-Link DAP-2695 rev A1
Running: LEDE r2370

Wireless config:

config wifi-device radio0 option type mac80211 option channel 100 option country BE option txpower 20 option hwmode 11a option path 'pci0000:00/0000:00:00.0' option htmode VHT80 option disabled 0

config wifi-iface
option device 'radio0'
option ifname 'wlan0'
option encryption 'psk2'
option ieee80211d '1'
option ieee80211w '1'
option peerkey '1'
option rsn_preauth '1'
option network 'lan'
option mode 'ap'
option ssid 'xxxxxxxx'
option key 'XXXXXXXX'

config wifi-iface
option device 'radio0'
option ifname 'wlan2'
option encryption 'psk2'
option ieee80211d '1'
option ieee80211w '1'
option peerkey '1'
option rsn_preauth '0'
option network 'guest'
option mode 'ap'
option ssid 'Guests'
option key 'YYYYYYYY'
#option disassoc_low_ack '0'

config wifi-device radio1
option type mac80211
option channel 1
option country BE
option txpower 20
option hwmode 11g
option path 'platform/qca955x_wmac'
option htmode HT40
#option noscan 1

config wifi-iface
option device 'radio1'
option ifname 'wlan1'
option encryption 'psk2'
option ieee80211d '1'
option ieee80211w '1'
option peerkey '1'
option rsn_preauth '1'
option mode 'ap'
option network 'lan'
option ssid 'xxxxxxxx'
option key 'XXXXXXXX'

config wifi-iface
option device 'radio1'
option ifname 'wlan3'
option encryption 'psk2'
option ieee80211d '1'
option ieee80211w '1'
option peerkey '1'
option rsn_preauth '0'
option network 'guest'
option mode 'ap'
option ssid 'Guests'
option key 'YYYYYYYY'
#option disassoc_low_ack '0'

Kernel log:

[ 54.439387] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
[ 54.652312] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
[ 54.663197] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 54.742675] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
[ 54.752039] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/cal-pci-0000:00:00.0.bin failed with error -2
[ 54.762568] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 61.648688] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
[ 61.658088] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
[ 61.671139] ath10k_pci 0000:00:00.0: firmware ver 10.2.4.70.54 api 5 features no-p2p,raw-mode,mfp crc32 9d340dd9
[ 61.681571] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
[ 61.692178] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 61.771624] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
[ 61.786987] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
[ 62.893736] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
[ 63.001904] ath: EEPROM regdomain: 0x0
[ 63.001920] ath: EEPROM indicates default country code should be used
[ 63.001928] ath: doing EEPROM country->regdmn map search
[ 63.001945] ath: country maps to regdmn code: 0x3a
[ 63.001955] ath: Country alpha2 being used: US
[ 63.001963] ath: Regpair used: 0x3a
[ 63.284566] ath: EEPROM regdomain: 0x0
[ 63.284583] ath: EEPROM indicates default country code should be used
[ 63.284591] ath: doing EEPROM country->regdmn map search
[ 63.284608] ath: country maps to regdmn code: 0x3a
[ 63.284617] ath: Country alpha2 being used: US
[ 63.284625] ath: Regpair used: 0x3a
[ 72.829974] ath: EEPROM regdomain: 0x8038
[ 72.834045] ath: EEPROM indicates we should expect a country code
[ 72.840265] ath: doing EEPROM country->regdmn map search
[ 72.845651] ath: country maps to regdmn code: 0x37
[ 72.850522] ath: Country alpha2 being used: BE
[ 72.855022] ath: Regpair used: 0x37
[ 72.858554] ath: regdomain 0x8038 dynamically updated by user
[ 72.864452] ath: EEPROM regdomain: 0x8038
[ 72.868513] ath: EEPROM indicates we should expect a country code
[ 72.874707] ath: doing EEPROM country->regdmn map search
[ 72.880092] ath: country maps to regdmn code: 0x37
[ 72.884943] ath: Country alpha2 being used: BE
[ 72.889458] ath: Regpair used: 0x37
[ 72.892996] ath: regdomain 0x8038 dynamically updated by user
[920549.560152] ath10k_pci 0000:00:00.0: failed to delete peer c0:ee:fb:4a:18:b7 for vdev 0: -145
[920549.568951] ath10k_pci 0000:00:00.0: found sta peer c0:ee:fb:4a:18:b7 (ptr 8d43f600 id 95) entry on vdev 0 after it was supposedly removed
[920549.581722] ------------[ cut here ]------------
[920549.586618] WARNING: CPU: 0 PID: 1431 at compat-wireless-2016-10-08/net/mac80211/sta_info.c:964 sta_set_sinfo+0x92c/0x9e0 mac80211
[920549.598968] Modules linked in: ath9k ath9k_common crc_ccitt ath9k_hw act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat gpio_button_hotplug
[920549.620582] CPU: 0 PID: 1431 Comm: hostapd Not tainted 4.4.35 #0
[920549.626772] Stack : 803c2d64 00000000 00000001 80420000 8fe26580 8040acc3 803a4424 00000597
[920549.626772] 8047379c 8ff8d000 00000000 77415e94 00000000 800a7198 803a9a90 80400000
[920549.626772] 00000003 8ff8d000 803a7e9c 8e5f3b2c 00000000 800a5114 80400000 00000000
[920549.626772] 00000001 801f3100 00000000 00000000 00000000 00000000 00000000 00000000
[920549.626772] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[920549.626772] ...
[920549.663219] Call Trace:
[920549.665810] [<80071ae0>] show_stack+0x50/0x84
[920549.670323] [<80081860>] warn_slowpath_common+0xa0/0xd0
[920549.675724] [<80081918>] warn_slowpath_null+0x18/0x24
[920549.680994] [<8f5870a4>] sta_set_sinfo+0x92c/0x9e0 [mac80211]
[920549.686990] [<8f587188>] __sta_info_destroy+0x30/0x48 [mac80211]
[920549.693222] [<8f587238>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
[920549.700118] [<8f54c144>] cfg80211_check_station_change+0xed8/0x1390 [cfg80211]
[920549.707564]
[920549.709166] ---[ end trace e15a227055dd36c2 ]---
[920866.693883] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer b0:df:3a:12:79:80: -145
[920866.703088] wlan0: failed to remove key (0, b0:df:3a:12:79:80) from hardware (-145)
[920866.719519] ath10k_pci 0000:00:00.0: cipher 0 is not supported
[920866.725602] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[920866.732670] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[920866.740901] ath10k_pci 0000:00:00.0: failed to disassociate station: b0:df:3a:12:79:80 vdev 0: -122
[920866.750169] ------------[ cut here ]------------
[920866.755066] WARNING: CPU: 0 PID: 1431 at compat-wireless-2016-10-08/net/mac80211/sta_info.c:956 sta_set_sinfo+0x8d8/0x9e0 mac80211
[920866.767421] Modules linked in: ath9k ath9k_common crc_ccitt ath9k_hw act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat gpio_button_hotplug
[920866.789044] CPU: 0 PID: 1431 Comm: hostapd Tainted: G W 4.4.35 #0
[920866.796465] Stack : 803c2d64 00000000 00000001 80420000 8fe26580 8040acc3 803a4424 00000597
[920866.796465] 8047379c 8ff8d000 00000000 77415e94 00000000 800a7198 803a9a90 80400000
[920866.796465] 00000003 8ff8d000 803a7e9c 8e5f3b2c 00000000 800a5114 00000000 00000000
[920866.796465] 80409dd0 801f3100 00000000 00000000 00000000 00000000 00000000 00000000
[920866.796465] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[920866.796465] ...
[920866.832912] Call Trace:
[920866.835502] [<80071ae0>] show_stack+0x50/0x84
[920866.840016] [<80081860>] warn_slowpath_common+0xa0/0xd0
[920866.845417] [<80081918>] warn_slowpath_null+0x18/0x24
[920866.850691] [<8f587050>] sta_set_sinfo+0x8d8/0x9e0 [mac80211]
[920866.856685] [<8f587188>] __sta_info_destroy+0x30/0x48 [mac80211]
[920866.862914] [<8f587238>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
[920866.869817] [<8f54c144>] cfg80211_check_station_change+0xed8/0x1390 [cfg80211]
[920866.877266]
[920866.878867] ---[ end trace e15a227055dd36c3 ]---
[920869.873901] ath10k_pci 0000:00:00.0: failed to delete peer b0:df:3a:12:79:80 for vdev 0: -11
[920869.882572] ath10k_pci 0000:00:00.0: found sta peer b0:df:3a:12:79:80 (ptr 8f3f3000 id 243) entry on vdev 0 after it was supposedly removed
[920872.893898] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[920875.893871] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[920878.913873] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[920881.913852] ath10k_pci 0000:00:00.0: failed to set protection mode 0 on vdev 0: -11
[920884.913828] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[920887.923826] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[920890.923830] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[920893.933787] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[920896.933770] ath10k_pci 0000:00:00.0: failed to set protection mode 0 on vdev 1: -11
[920899.933771] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11

Kernel log after running "wifi" in the crashed state:

[921144.042842] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:29:cc:18: -11 [921144.051948] wlan0: failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-11) [921147.052846] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:29:cc:18: -11 [921147.061984] wlan0: failed to set key (2, ff:ff:ff:ff:ff:ff) to hardware (-11) [921150.082845] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer 9e:d6:43:29:cc:18: -11 [921150.092017] wlan2: failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-11) [921153.092796] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer 9e:d6:43:29:cc:18: -11 [921153.101928] wlan2: failed to set key (2, ff:ff:ff:ff:ff:ff) to hardware (-11) [932082.286927] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request [932390.335807] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request [932607.674665] ath10k_pci 0000:00:00.0: failed to down vdev_id 1: -11 [932610.674753] ath10k_pci 0000:00:00.0: failed to stop WMI vdev 1: -11 [932610.682338] ath10k_pci 0000:00:00.0: failed to stop vdev 1: -11 [932613.684666] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer 9e:d6:43:29:cc:18: -11 [932613.694955] wlan2: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-11) [932616.694882] ath10k_pci 0000:00:00.0: failed to submit AP/IBSS self-peer removal on vdev 1: -11 [932619.694635] ath10k_pci 0000:00:00.0: failed to delete WMI vdev 1: -11 [932622.694758] ath10k_pci 0000:00:00.0: failed to remove AP self-peer on vdev 1: -145 [932622.704039] ath10k_pci 0000:00:00.0: found vif peer 9e:d6:43:29:cc:18 entry on vdev 1 after it was supposedly removed [932622.715667] ath10k_pci 0000:00:00.0: removing stale peer 9e:d6:43:29:cc:18 from vdev_id 1 [932622.724455] br-guest: port 3(wlan2) entered disabled state [932622.796620] device wlan2 left promiscuous mode [932622.802446] br-guest: port 3(wlan2) entered disabled state [932622.856835] br-guest: port 2(wlan3) entered disabled state [932622.885493] device wlan3 left promiscuous mode [932622.891306] br-guest: port 2(wlan3) entered disabled state [932622.935955] device wlan0 left promiscuous mode [932622.940833] br-lan: port 2(wlan0) entered disabled state [932622.976382] device wlan1 left promiscuous mode [932622.981149] br-lan: port 3(wlan1) entered disabled state [932626.004584] ath10k_pci 0000:00:00.0: failed to down vdev_id 0: -11 [932629.004572] ath10k_pci 0000:00:00.0: failed to stop WMI vdev 0: -11 [932629.011031] ath10k_pci 0000:00:00.0: failed to stop vdev 0: -11 [932634.014537] ath10k_pci 0000:00:00.0: failed to flush transmit queue (skip 0 ar-state 1): 0 [932637.034556] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:29:cc:18: -11 [932637.043707] wlan0: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-11) [932640.044532] ath10k_pci 0000:00:00.0: failed to submit AP/IBSS self-peer removal on vdev 0: -11 [932643.044507] ath10k_pci 0000:00:00.0: failed to delete WMI vdev 0: -11 [932646.044501] ath10k_pci 0000:00:00.0: failed to remove AP self-peer on vdev 0: -145 [932646.052277] ath10k_pci 0000:00:00.0: found vif peer 9c:d6:43:29:cc:18 entry on vdev 0 after it was supposedly removed [932646.063205] ath10k_pci 0000:00:00.0: removing stale peer 9c:d6:43:29:cc:18 from vdev_id 0 [932652.064468] ath10k_pci 0000:00:00.0: could not suspend target (-11) [932653.410146] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready [932654.710106] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [932654.716877] device wlan1 entered promiscuous mode [932654.755523] device wlan0 entered promiscuous mode [932655.397455] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [932655.404141] br-lan: port 3(wlan0) entered forwarding state [932655.409904] br-lan: port 3(wlan0) entered forwarding state [932655.426138] device wlan2 entered promiscuous mode [932655.445231] IPv6: ADDRCONF(NETDEV_UP): wlan2: link is not ready [932655.451454] br-guest: port 2(wlan2) entered forwarding state [932655.457370] br-guest: port 2(wlan2) entered forwarding state [932655.714483] br-guest: port 2(wlan2) entered disabled state [932655.842761] IPv6: ADDRCONF(NETDEV_CHANGE): wlan2: link becomes ready [932655.849460] br-guest: port 2(wlan2) entered forwarding state [932655.855380] br-guest: port 2(wlan2) entered forwarding state [932656.428869] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [932656.435563] br-lan: port 2(wlan1) entered forwarding state [932656.441274] br-lan: port 2(wlan1) entered forwarding state [932656.466101] device wlan3 entered promiscuous mode [932656.477745] IPv6: ADDRCONF(NETDEV_UP): wlan3: link is not ready [932656.483865] br-guest: port 3(wlan3) entered forwarding state [932656.489791] br-guest: port 3(wlan3) entered forwarding state [932656.714486] br-guest: port 3(wlan3) entered disabled state [932656.869320] IPv6: ADDRCONF(NETDEV_CHANGE): wlan3: link becomes ready [932656.876022] br-guest: port 3(wlan3) entered forwarding state [932656.881921] br-guest: port 3(wlan3) entered forwarding state [932657.404425] br-lan: port 3(wlan0) entered forwarding state [932657.854498] br-guest: port 2(wlan2) entered forwarding state [932658.434436] br-lan: port 2(wlan1) entered forwarding state [932658.874432] br-guest: port 3(wlan3) entered forwarding state [932680.425787] br-guest: port 3(wlan3) entered disabled state [932680.442596] device wlan3 left promiscuous mode [932680.447279] br-guest: port 3(wlan3) entered disabled state [932680.467863] br-guest: port 2(wlan2) entered disabled state [932680.485729] device wlan2 left promiscuous mode [932680.490436] br-guest: port 2(wlan2) entered disabled state [932680.505002] device wlan1 left promiscuous mode [932680.509700] br-lan: port 2(wlan1) entered disabled state [932680.523775] device wlan0 left promiscuous mode [932680.528530] br-lan: port 3(wlan0) entered disabled state [932683.130864] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready [932683.169412] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready [932683.183967] device wlan0 entered promiscuous mode [932683.197266] device wlan1 entered promiscuous mode [932683.813544] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [932683.820249] br-lan: port 2(wlan0) entered forwarding state [932683.826004] br-lan: port 2(wlan0) entered forwarding state [932683.841229] device wlan2 entered promiscuous mode [932683.857367] IPv6: ADDRCONF(NETDEV_UP): wlan2: link is not ready [932683.863484] br-guest: port 2(wlan2) entered forwarding state [932683.869386] br-guest: port 2(wlan2) entered forwarding state [932684.174365] br-guest: port 2(wlan2) entered disabled state [932684.265577] IPv6: ADDRCONF(NETDEV_CHANGE): wlan2: link becomes ready [932684.272265] br-guest: port 2(wlan2) entered forwarding state [932684.278193] br-guest: port 2(wlan2) entered forwarding state [932684.868399] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready [932684.875079] br-lan: port 3(wlan1) entered forwarding state [932684.880820] br-lan: port 3(wlan1) entered forwarding state [932684.905988] device wlan3 entered promiscuous mode [932684.912111] IPv6: ADDRCONF(NETDEV_UP): wlan3: link is not ready [932684.918275] br-guest: port 3(wlan3) entered forwarding state [932684.924142] br-guest: port 3(wlan3) entered forwarding state [932685.174351] br-guest: port 3(wlan3) entered disabled state [932685.305978] IPv6: ADDRCONF(NETDEV_CHANGE): wlan3: link becomes ready [932685.312646] br-guest: port 3(wlan3) entered forwarding state [932685.318582] br-guest: port 3(wlan3) entered forwarding state [932685.824311] br-lan: port 2(wlan0) entered forwarding state [932686.274310] br-guest: port 2(wlan2) entered forwarding state [932686.874321] br-lan: port 3(wlan1) entered forwarding state [932687.314318] br-guest: port 3(wlan3) entered forwarding state

After running wifi, I am able to connect again.

MAC addresses found in logs:
9c:d6:43:29:cc:18: DAP-2695, wlan0
9e:d6:43:29:cc:18: DAP-2695, wlan2

c0:ee:fb:4a:18:b7: OnePlus 2, Android 6.0.1
b0:df:3a:12:79:80: Samsung SM-T520, Android 5.1.1

@openwrt-bot
Copy link
Author

stintel:

Just happened again after 4d uptime. This time, only wlan0 was active on the ath10k radio (no multi-AP).

MAC that didn't appear when I created this bug:

a4:77:33:8c:8b:20: ChromeCast2 Video

[ 9.945432] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0 [ 10.169744] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2 [ 10.180641] ath10k_pci 0000:00:00.0: Falling back to user helper [ 10.272271] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed [ 10.569381] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000 [ 10.578742] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1 [ 10.591867] ath10k_pci 0000:00:00.0: firmware ver 10.2.4-1.0-00016 api 5 features no-p2p,raw-mode,mfp crc32 0c5668f8 [ 10.602663] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2 [ 10.613271] ath10k_pci 0000:00:00.0: Falling back to user helper [ 10.696442] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed [ 10.712219] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08 [ 11.816627] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1 [ 11.922156] ath: EEPROM regdomain: 0x0 [ 11.922172] ath: EEPROM indicates default country code should be used [ 11.922179] ath: doing EEPROM country->regdmn map search [ 11.922195] ath: country maps to regdmn code: 0x3a [ 11.922204] ath: Country alpha2 being used: US [ 11.922212] ath: Regpair used: 0x3a [ 12.285787] ath: EEPROM regdomain: 0x0 [ 12.285803] ath: EEPROM indicates default country code should be used [ 12.285811] ath: doing EEPROM country->regdmn map search [ 12.285828] ath: country maps to regdmn code: 0x3a [ 12.285837] ath: Country alpha2 being used: US [ 12.285845] ath: Regpair used: 0x3a [ 21.409164] ath: EEPROM regdomain: 0x8038 [ 21.413241] ath: EEPROM indicates we should expect a country code [ 21.419444] ath: doing EEPROM country->regdmn map search [ 21.424830] ath: country maps to regdmn code: 0x37 [ 21.429702] ath: Country alpha2 being used: BE [ 21.434210] ath: Regpair used: 0x37 [ 21.437750] ath: regdomain 0x8038 dynamically updated by user [ 21.443652] ath: EEPROM regdomain: 0x8038 [ 21.447717] ath: EEPROM indicates we should expect a country code [ 21.453913] ath: doing EEPROM country->regdmn map search [ 21.459312] ath: country maps to regdmn code: 0x37 [ 21.464165] ath: Country alpha2 being used: BE [ 21.468671] ath: Regpair used: 0x37 [ 21.472223] ath: regdomain 0x8038 dynamically updated by user [346092.285193] ath10k_pci 0000:00:00.0: failed to delete peer c0:ee:fb:4a:18:b7 for vdev 0: -145 [346092.293986] ath10k_pci 0000:00:00.0: found sta peer c0:ee:fb:4a:18:b7 (ptr 8f3ac000 id 95) entry on vdev 0 after it was supposedly removed [346092.306790] ------------[ cut here ]------------ [346092.311694] WARNING: CPU: 0 PID: 1365 at compat-wireless-2016-10-08/net/mac80211/sta_info.c:964 sta_set_sinfo+0x928/0x9dc [mac80211]() [346092.324069] Modules linked in: iptable_nat ath9k nf_nat_ipv4 nf_conntrack_ipv4 iptable_mangle iptable_filter ipt_REJECT ipt_MASQUERADE ip_tables ath9k_common 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 x_tables nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_nat nf_log_ipv4 nf_log_common nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack crc_ccitt ath9k_hw act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat sha256_generic jitterentropy_rng drbg hmac gpio_button_hotplug cryptomgr aead crypto_null crypto_hash [346092.387902] CPU: 0 PID: 1365 Comm: hostapd Not tainted 4.4.47 #0 [346092.394085] Stack : 00000000 00000000 80496542 00000034 803c1518 00000000 00000001 80430000 [346092.394085] 80420000 80424d83 803bdb40 00000555 8049378c 80420000 8f44cc00 00000000 [346092.394085] 00000000 800a955c 803c3238 80420000 00000003 80420000 803c1644 8e977afc [346092.394085] 8049378c 800a73f8 803c0000 00000000 00000001 80200300 00000000 00000000 [346092.394085] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 [346092.394085] ... [346092.430540] Call Trace: [346092.433124] [<8007264c>] show_stack+0x64/0xb0 [346092.437656] [<8008272c>] warn_slowpath_common+0xa0/0xd0 [346092.443051] [<80082830>] warn_slowpath_null+0x18/0x24 [346092.448373] [<8f5071ac>] sta_set_sinfo+0x928/0x9dc [mac80211] [346092.454381] [<8f507290>] __sta_info_destroy+0x30/0x48 [mac80211] [346092.460686] [<8f507340>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211] [346092.467617] [<8f48c670>] cfg80211_check_station_change+0xfb4/0x1528 [cfg80211] [346092.475053] [346092.476674] ---[ end trace 24a608a72f7c1d41 ]--- [346095.495158] ath10k_pci 0000:00:00.0: failed to wait for created wmi peer c0:ee:fb:4a:18:b7 on vdev 0: -145 [346095.505059] ath10k_pci 0000:00:00.0: failed to add peer c0:ee:fb:4a:18:b7 for vdev 0 when adding a new sta: -145 [346293.634172] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer a4:77:33:8c:8b:20: -11 [346293.643288] wlan0: failed to remove key (0, a4:77:33:8c:8b:20) from hardware (-11) [346296.654141] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:29:cc:18: -11 [346296.663247] wlan0: failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-11) [346299.664166] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:29:cc:18: -11 [346299.673266] wlan0: failed to set key (2, ff:ff:ff:ff:ff:ff) to hardware (-11) [346302.694111] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer b0:df:3a:12:79:80: -11 [346302.703216] wlan0: failed to remove key (0, b0:df:3a:12:79:80) from hardware (-11) [346302.721527] ath10k_pci 0000:00:00.0: cipher 525 is not supported [346302.727783] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122 [346302.734870] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122 [346302.743087] ath10k_pci 0000:00:00.0: failed to disassociate station: a4:77:33:8c:8b:20 vdev 0: -122 [346302.752360] ------------[ cut here ]------------ [346302.757275] WARNING: CPU: 0 PID: 1365 at compat-wireless-2016-10-08/net/mac80211/sta_info.c:956 sta_set_sinfo+0x8d4/0x9dc [mac80211]() [346302.769626] Modules linked in: iptable_nat ath9k nf_nat_ipv4 nf_conntrack_ipv4 iptable_mangle iptable_filter ipt_REJECT ipt_MASQUERADE ip_tables ath9k_common 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 x_tables nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_nat nf_log_ipv4 nf_log_common nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack crc_ccitt ath9k_hw act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat sha256_generic jitterentropy_rng drbg hmac gpio_button_hotplug cryptomgr aead crypto_null crypto_hash [346302.833445] CPU: 0 PID: 1365 Comm: hostapd Tainted: G W 4.4.47 #0 [346302.840866] Stack : 00000000 00000000 80496542 00000042 803c1518 00000000 00000001 80430000 [346302.840866] 80420000 80424d83 803bdb40 00000555 8049378c 80420000 8f44cc00 00000000 [346302.840866] 00000000 800a955c 803c3238 80420000 00000003 80420000 803c1644 8e977afc [346302.840866] 8049378c 800a73f8 8e977af8 00000000 80423e90 80200300 00000000 00000000 [346302.840866] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 [346302.840866] ... [346302.877322] Call Trace: [346302.879902] [<8007264c>] show_stack+0x64/0xb0 [346302.884429] [<8008272c>] warn_slowpath_common+0xa0/0xd0 [346302.889824] [<80082830>] warn_slowpath_null+0x18/0x24 [346302.895127] [<8f507158>] sta_set_sinfo+0x8d4/0x9dc [mac80211] [346302.901127] [<8f507290>] __sta_info_destroy+0x30/0x48 [mac80211] [346302.907423] [<8f507340>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211] [346302.914363] [<8f48c670>] cfg80211_check_station_change+0xfb4/0x1528 [cfg80211] [346302.921790] [346302.923392] ---[ end trace 24a608a72f7c1d42 ]--- [346305.924133] ath10k_pci 0000:00:00.0: failed to delete peer a4:77:33:8c:8b:20 for vdev 0: -11 [346305.932794] ath10k_pci 0000:00:00.0: found sta peer a4:77:33:8c:8b:20 (ptr 8f3ac400 id 87) entry on vdev 0 after it was supposedly removed [346305.956350] ath10k_pci 0000:00:00.0: cipher 525 is not supported [346305.962603] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122 [346305.969718] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122 [346308.974069] ath10k_warn: 1 callbacks suppressed [346308.978790] ath10k_pci 0000:00:00.0: failed to delete peer b0:df:3a:12:79:80 for vdev 0: -11 [346308.987491] ath10k_pci 0000:00:00.0: found sta peer b0:df:3a:12:79:80 (ptr 8f3cb200 id 243) entry on vdev 0 after it was supposedly removed [346312.004059] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11 [346315.004045] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11 [346318.024030] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11 [346321.024014] ath10k_pci 0000:00:00.0: failed to set protection mode 0 on vdev 0: -11 [346324.024003] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11 [409419.602401] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request

@openwrt-bot
Copy link
Author

stintel:

Just ran into this again on a Ubiquiti Unifi AP AC Pro running 17.01 branch r3375-d1e0cc8cd5

@openwrt-bot
Copy link
Author

stintel:

And unfortunately this still happens with backports-4.14-rc1 from Hauke's mac80211 branch :-(

202346.930864] ath10k_pci 0000:00:00.0: failed to delete peer c0:ee:fb:4a:18:b7 for vdev 0: -145
[202346.939666] ath10k_pci 0000:00:00.0: found sta peer c0:ee:fb:4a:18:b7 (ptr 8f66c400 id 95) entry on vdev 0 after it was supposedly removed
[202346.953168] ------------[ cut here ]------------
[202346.958116] WARNING: CPU: 0 PID: 1485 at /home/build/lede/build_dir/target-mips_24kc_musl/linux-ar71xx_generic/backports-4.14-rc1/net/mac80211/sta_info.c:975 sta_set_sinfo+0x930/0x9e4 mac80211
[202346.975959] Modules linked in: ath9k ath9k_common crc_ccitt ath9k_hw act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat sha256_generic jitterentropy_rng drbg hmac cmac gpio_button_hotplug aead cryptomgr crypto_null crypto_hash
[202347.006734] CPU: 0 PID: 1485 Comm: hostapd Tainted: G W 4.4.88 #0
[202347.014153] Stack : 803cb224 00000000 00000001 80420000 8fd78c94 80412ca3 803ac79c 000005cd
[202347.014153] 8048378c 8fe94000 00000000 772bff54 00000000 800a7720 803b1f28 80410000
[202347.014153] 00000003 8fe94000 803b032c 8ef0fb2c 00000000 800a569c 01344d0d 00000000
[202347.014153] 80411dd0 801f8700 00000000 00000000 00000000 00000000 00000000 00000000
[202347.014153] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[202347.014153] ...
[202347.050593] Call Trace:
[202347.053183] [<80071dc0>] show_stack+0x54/0x88
[202347.057696] [<80081bc0>] warn_slowpath_common+0xa0/0xd0
[202347.063099] [<80081c78>] warn_slowpath_null+0x18/0x24
[202347.068381] [<8f607050>] sta_set_sinfo+0x930/0x9e4 [mac80211]
[202347.074381] [<8f607134>] __sta_info_destroy+0x30/0x48 [mac80211]
[202347.080618] [<8f6071e4>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
[202347.087532] [<8f54de1c>] cfg80211_check_station_change+0x1fa8/0x3628 [cfg80211]
[202347.095064]
[202347.096663] ---[ end trace 083e174373866414 ]---
[202663.158973] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer a4:77:33:79:20:64: -145
[202663.168167] wlan0: failed to remove key (0, a4:77:33:79:20:64) from hardware (-145)
[202663.187511] ath10k_pci 0000:00:00.0: cipher 27 is not supported
[202663.193678] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[202663.200761] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[202663.208990] ath10k_pci 0000:00:00.0: failed to disassociate station: a4:77:33:79:20:64 vdev 0: -122
[202663.218250] ------------[ cut here ]------------
[202663.223152] WARNING: CPU: 0 PID: 1485 at /home/build/lede/build_dir/target-mips_24kc_musl/linux-ar71xx_generic/backports-4.14-rc1/net/mac80211/sta_info.c:967 sta_set_sinfo+0x8dc/0x9e4 mac80211
[202663.240960] Modules linked in: ath9k ath9k_common crc_ccitt ath9k_hw act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress ath10k_pci ath10k_core ath mac80211 cfg80211 compat sha256_generic jitterentropy_rng drbg hmac cmac gpio_button_hotplug aead cryptomgr crypto_null crypto_hash
[202663.271744] CPU: 0 PID: 1485 Comm: hostapd Tainted: G W 4.4.88 #0
[202663.279163] Stack : 803cb224 00000000 00000001 80420000 8fd78c94 80412ca3 803ac79c 000005cd
[202663.279163] 8048378c 8fe94000 00000000 772bff54 00000000 800a7720 803b1f28 80410000
[202663.279163] 00000003 8fe94000 803b032c 8ef0fb2c 00000000 800a569c 0000001b 00000000
[202663.279163] 80411dd0 801f8700 00000000 00000000 00000000 00000000 00000000 00000000
[202663.279163] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[202663.279163] ...
[202663.315603] Call Trace:
[202663.318182] [<80071dc0>] show_stack+0x54/0x88
[202663.322708] [<80081bc0>] warn_slowpath_common+0xa0/0xd0
[202663.328097] [<80081c78>] warn_slowpath_null+0x18/0x24
[202663.333382] [<8f606ffc>] sta_set_sinfo+0x8dc/0x9e4 [mac80211]
[202663.339372] [<8f607134>] __sta_info_destroy+0x30/0x48 [mac80211]
[202663.345609] [<8f6071e4>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
[202663.352525] [<8f54de1c>] cfg80211_check_station_change+0x1fa8/0x3628 [cfg80211]
[202663.360060]
[202663.361664] ---[ end trace 083e174373866415 ]---
[202666.358984] ath10k_pci 0000:00:00.0: failed to delete peer a4:77:33:79:20:64 for vdev 0: -11
[202666.367668] ath10k_pci 0000:00:00.0: found sta peer a4:77:33:79:20:64 (ptr 8f66ca00 id 136) entry on vdev 0 after it was supposedly removed
[202669.388938] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[202672.388915] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[202675.408917] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[202678.408884] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[202681.408861] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[202684.428847] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[202687.428827] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[202690.439030] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[202693.438796] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[202696.438779] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[202941.417343] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:2d:b8:18: -11
[202941.426451] wlan0: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-11)
[202944.427334] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:d6:43:2d:b8:18: -11
[202944.436611] wlan0: failed to set key (1, ff:ff:ff:ff:ff:ff) to hardware (-11)
[202947.457323] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer 9e:d6:43:2d:b8:18: -11
[202947.466487] wlan2: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-11)
[202950.467291] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer 9e:d6:43:2d:b8:18: -11
[202950.476423] wlan2: failed to set key (1, ff:ff:ff:ff:ff:ff) to hardware (-11)

@openwrt-bot
Copy link
Author

stintel:

Linked this bug in upstream bug report.

@openwrt-bot
Copy link
Author

stintel:

And saw it again after 42 days of uptime, this time on r6397-7cc9914aae running on my Unifi AP AC Pro.

[3586274.365459] ath10k_pci 0000:00:00.0: failed to delete peer 2c:54:91:80:8c:5d for vdev 0: -145
[3586274.374561] ath10k_pci 0000:00:00.0: found sta peer 2c:54:91:80:8c:5d (ptr 86ac6000 id 163) entry on vdev 0 after it was supposedly removed
[3586274.388319] ------------[ cut here ]------------
[3586274.393445] WARNING: CPU: 0 PID: 1613 at /home/build/lede/build_dir/target-mips_24kc_musl/linux-ar71xx_generic/backports-2017-11-01/net/mac80211/sta_info.c:1001 sta_set_sinfo+0x930/0x9d8 [mac80211]
[3586274.411791] Modules linked in: ath9k ath9k_common ath9k_hw ath10k_pci ath10k_core ath mac80211 cfg80211 crc_ccitt compat act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress sha256_generic jitterentropy_rng drbg hmac cmac gpio_button_hotplug aead cryptomgr crypto_null crypto_hash
[3586274.443222] CPU: 0 PID: 1613 Comm: hostapd Not tainted 4.9.85 #0
[3586274.449606] Stack : 804b765a 00000034 00000000 00000001 87d04284 80449187 803fde78 0000064d
[3586274.458490] 804b37a0 000003e9 00000000 00000000 00000000 800a97d4 804036b0 80440000
[3586274.467369] 00000003 000003e9 80401868 8728fb0c 00000000 800d795c 804b765a 000000b9
[3586274.476248] 00000000 80220b00 00000000 00000000 00000000 00000000 00000000 00000000
[3586274.485120] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[3586274.493999] ...
[3586274.496724] Call Trace:
[3586274.499438] [<8006b2c8>] show_stack+0x54/0x88
[3586274.504130] [<8007ff40>] __warn+0xe4/0x118
[3586274.508553] [<80080008>] warn_slowpath_null+0x1c/0x34
[3586274.514028] [<87606fd8>] sta_set_sinfo+0x930/0x9d8 [mac80211]
[3586274.520220] [<87604940>] sta_info_get_bss+0x18/0x68 [mac80211]
[3586274.526492] [<87607160>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
[3586274.533589] [<8758e3c0>] cfg80211_check_station_change+0x21cc/0x3848 [cfg80211]
[3586274.541353] ---[ end trace 9a3c2fdbf9289a0e ]---
[3586568.284256] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 64:bc:0c:9d:ea:bd: -145
[3586568.293773] wlan0: failed to remove key (0, 64:bc:0c:9d:ea:bd) from hardware (-145)
[3586568.310659] ath10k_pci 0000:00:00.0: cipher 0 is not supported
[3586568.317041] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[3586568.324375] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[3586568.332823] ath10k_pci 0000:00:00.0: failed to disassociate station: 64:bc:0c:9d:ea:bd vdev 0: -122
[3586568.342350] ------------[ cut here ]------------
[3586568.347423] WARNING: CPU: 0 PID: 1613 at /home/build/lede/build_dir/target-mips_24kc_musl/linux-ar71xx_generic/backports-2017-11-01/net/mac80211/sta_info.c:993 sta_set_sinfo+0x8e0/0x9d8 [mac80211]
[3586568.365645] Modules linked in: ath9k ath9k_common ath9k_hw ath10k_pci ath10k_core ath mac80211 cfg80211 crc_ccitt compat act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress sha256_generic jitterentropy_rng drbg hmac cmac gpio_button_hotplug aead cryptomgr crypto_null crypto_hash
[3586568.397079] CPU: 0 PID: 1613 Comm: hostapd Tainted: G W 4.9.85 #0
[3586568.404715] Stack : 804b765a 00000042 00000000 00000001 87d04284 80449187 803fde78 0000064d
[3586568.413582] 804b37a0 000003e1 00000000 00000000 00000000 800a97d4 804036b0 80440000
[3586568.422462] 00000003 000003e1 80401868 8728fb0c 00000000 800d795c 804b765a 000000b8
[3586568.431342] 8045ade0 80220b00 00000000 00000000 00000000 00000000 00000000 00000000
[3586568.440221] 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
[3586568.449100] ...
[3586568.451816] Call Trace:
[3586568.454531] [<8006b2c8>] show_stack+0x54/0x88
[3586568.459224] [<8007ff40>] __warn+0xe4/0x118
[3586568.463633] [<80080008>] warn_slowpath_null+0x1c/0x34
[3586568.469111] [<87606f88>] sta_set_sinfo+0x8e0/0x9d8 [mac80211]
[3586568.475301] [<87604940>] sta_info_get_bss+0x18/0x68 [mac80211]
[3586568.481556] [<87607160>] sta_info_destroy_addr_bss+0x38/0x60 [mac80211]
[3586568.488680] [<8758e3c0>] cfg80211_check_station_change+0x21cc/0x3848 [cfg80211]
[3586568.496445] ---[ end trace 9a3c2fdbf9289a0f ]---
[3586571.564263] ath10k_pci 0000:00:00.0: failed to delete peer 64:bc:0c:9d:ea:bd for vdev 0: -11
[3586571.573259] ath10k_pci 0000:00:00.0: found sta peer 64:bc:0c:9d:ea:bd (ptr 86ac6a00 id 174) entry on vdev 0 after it was supposedly removed
[3586574.604254] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 9c:b7:0d:43:78:51: -11
[3586574.613786] wlan0: failed to remove key (0, 9c:b7:0d:43:78:51) from hardware (-11)
[3586574.630868] ath10k_pci 0000:00:00.0: cipher 0 is not supported
[3586574.637126] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[3586574.644451] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[3586574.652902] ath10k_pci 0000:00:00.0: failed to disassociate station: 9c:b7:0d:43:78:51 vdev 0: -122
[3586577.724223] ath10k_pci 0000:00:00.0: failed to delete peer 9c:b7:0d:43:78:51 for vdev 0: -11
[3586577.733139] ath10k_pci 0000:00:00.0: found sta peer 9c:b7:0d:43:78:51 (ptr 87270000 id 82) entry on vdev 0 after it was supposedly removed
[3586593.884157] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer b0:df:3a:12:79:80: -11
[3586593.893615] wlan0: failed to remove key (0, b0:df:3a:12:79:80) from hardware (-11)
[3586593.910301] ath10k_pci 0000:00:00.0: cipher 0 is not supported
[3586593.916584] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[3586593.923865] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[3586593.932328] ath10k_pci 0000:00:00.0: failed to disassociate station: b0:df:3a:12:79:80 vdev 0: -122
[3586597.004151] ath10k_pci 0000:00:00.0: failed to delete peer b0:df:3a:12:79:80 for vdev 0: -11
[3586597.013080] ath10k_pci 0000:00:00.0: found sta peer b0:df:3a:12:79:80 (ptr 872a7a00 id 243) entry on vdev 0 after it was supposedly removed
[3586600.044691] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer a4:77:33:79:20:64: -11
[3586600.054204] wlan0: failed to remove key (0, a4:77:33:79:20:64) from hardware (-11)
[3586600.071236] ath10k_pci 0000:00:00.0: cipher 0 is not supported
[3586600.077518] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[3586600.084827] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[3586600.093363] ath10k_pci 0000:00:00.0: failed to disassociate station: a4:77:33:79:20:64 vdev 0: -122
[3586603.164123] ath10k_pci 0000:00:00.0: failed to delete peer a4:77:33:79:20:64 for vdev 0: -11
[3586603.173032] ath10k_pci 0000:00:00.0: found sta peer a4:77:33:79:20:64 (ptr 8729c600 id 136) entry on vdev 0 after it was supposedly removed
[3586606.284659] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[3586609.324099] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[3586612.364083] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[3586615.404072] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[3586618.444077] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[3586621.484036] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[3586624.524050] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[3586627.564031] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[3586630.604039] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[3586633.643996] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[3628826.670808] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer f0:9f:c2:22:30:9b: -11
[3628826.680337] wlan0: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-11)
[3628829.710787] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer f0:9f:c2:22:30:9b: -11
[3628829.720230] wlan0: failed to set key (1, ff:ff:ff:ff:ff:ff) to hardware (-11)
[3628832.830783] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer f2:9f:c2:22:30:9b: -11
[3628832.840407] wlan2: failed to remove key (1, ff:ff:ff:ff:ff:ff) from hardware (-11)
[3628835.870766] ath10k_pci 0000:00:00.0: failed to install key for vdev 1 peer f2:9f:c2:22:30:9b: -11
[3628835.880127] wlan2: failed to set key (1, ff:ff:ff:ff:ff:ff) to hardware (-11)

@openwrt-bot
Copy link
Author

stintel:

It gets worse. I am now seeing it more frequently, might be related to my new Gemini PDA that causes it to occur faster. Additional problem: the 5GHz network now keeps broadcasting, meaning clients keep trying to connect to it, which fails, causing connectivity issues for several clients (for some reason they do not connect to the same SSID on 2.4GHz but keep trying the 5GHz network). And the worst part is, since my last flash, I now need to reboot the AP to resolve the issue, simply running "wifi" no longer fixes it.

[ 0.000000] Linux version 4.14.59 (stijn@taz) (gcc version 7.3.0 (OpenWrt GCC 7.3.0 r7516+171-ac10975cd7)) #0 Tue Jul 31 01:08:44 2018
[ 0.000000] bootconsole [early0] enabled
[ 0.000000] CPU0 revision is: 00019750 (MIPS 74Kc)
[ 0.000000] MIPS: machine is Ubiquiti UniFi-AC-PRO
[ 0.000000] SoC: Qualcomm Atheros QCA956X ver 1 rev 0
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 08000000 @ 00000000 (usable)
[ 0.000000] Initrd not found or empty - disabling initrd
[ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] On node 0 totalpages: 32768
[ 0.000000] free_area_init_node: node 0, pgdat 80507420, node_mem_map 81007780
[ 0.000000] Normal zone: 256 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 32768 pages, LIFO batch:7
[ 0.000000] random: get_random_bytes called from start_kernel+0x8c/0x474 with crng_init=0
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=132768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 32512
[ 0.000000] Kernel command line: console=ttyS0,115200n8 rootfstype=squashfs,jffs2
[ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Writing ErrCtl register=00000000
[ 0.000000] Readback ErrCtl register=00000000
[ 0.000000] Memory: 123240K/131072K available (3721K kernel code, 146K rwdata, 900K rodata, 1240K init, 212K bss, 7832K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS: 51
[ 0.000000] CPU clock: 775.000 MHz
[ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 4932285024 ns
[ 0.000008] sched_clock: 32 bits at 387MHz, resolution 2ns, wraps every 5541893118ns
[ 0.008211] Calibrating delay loop... 385.84 BogoMIPS (lpj=1929216)
[ 0.074752] pid_max: default: 32768 minimum: 301
[ 0.079779] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.086757] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.097447] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.107843] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.114337] pinctrl core: initialized pinctrl subsystem
[ 0.120653] NET: Registered protocol family 16
[ 0.131045] PCI host bridge /ahb/apb/pcie-controller@18250000 ranges:
[ 0.137844] MEM 0x0000000012000000..0x0000000013ffffff
[ 0.143377] IO 0x0000000000000000..0x0000000000000000
[ 0.162131] PCI host bridge to bus 0000:00
[ 0.166496] pci_bus 0000:00: root bus resource [mem 0x12000000-0x13ffffff]
[ 0.173734] pci_bus 0000:00: root bus resource [io 0x0000]
[ 0.179609] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[ 0.186756] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[ 0.195135] pci 0000:00:00.0: [168c:003c] type 00 class 0x028000
[ 0.195187] pci 0000:00:00.0: reg 0x10: [mem 0x00000000-0x001fffff 64bit]
[ 0.195239] pci 0000:00:00.0: reg 0x30: [mem 0x00000000-0x0000ffff pref]
[ 0.195309] pci 0000:00:00.0: supports D1
[ 0.195317] pci 0000:00:00.0: PME# supported from D0 D1 D3hot
[ 0.195488] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[ 0.195509] pci 0000:00:00.0: BAR 0: assigned [mem 0x12000000-0x121fffff 64bit]
[ 0.203229] pci 0000:00:00.0: BAR 6: assigned [mem 0x12200000-0x1220ffff pref]
[ 0.211392] clocksource: Switched to clocksource MIPS
[ 0.217569] NET: Registered protocol family 2
[ 0.222864] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.230220] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.236966] TCP: Hash tables configured (established 1024 bind 1024)
[ 0.243790] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.249957] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.256821] NET: Registered protocol family 1
[ 0.261476] PCI: CLS 0 bytes, default 32
[ 0.264879] Crashlog allocated RAM at address 0x3f00000
[ 0.271316] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[ 0.282164] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 0.288305] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 0.305387] io scheduler noop registered
[ 0.309522] io scheduler deadline registered (default)
[ 0.315228] ar7200-usb-phy usb-phy: phy reset is missing
[ 0.322284] pinctrl-single 1804002c.pinmux: 512 pins at pa b804002c size 64
[ 0.330559] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
[ 0.337900] console [ttyS0] disabled
[ 0.341738] 18020000.uart: ttyS0 at MMIO 0x18020000 (irq = 8, base_baud = 1562500) is a 16550A
[ 0.350826] console [ttyS0] enabled
[ 0.358383] bootconsole [early0] disabled
[ 0.372310] m25p80 spi0.0: mx25l12805d (16384 Kbytes)
[ 0.377575] 7 fixed-partitions partitions found on MTD device spi0.0
[ 0.384179] Creating 7 MTD partitions on "spi0.0":
[ 0.389135] 0x000000000000-0x000000060000 : "u-boot"
[ 0.394945] 0x000000060000-0x000000070000 : "u-boot-env"
[ 0.401077] 0x000000070000-0x000000800000 : "firmware"
[ 0.421169] 2 uimage-fw partitions found on MTD device firmware
[ 0.427322] 0x000000070000-0x000000200000 : "kernel"
[ 0.433081] 0x000000200000-0x000000800000 : "rootfs"
[ 0.438769] mtd: device 4 (rootfs) set to be root filesystem
[ 0.444694] 1 squashfs-split partitions found on MTD device rootfs
[ 0.451084] 0x000000720000-0x000000800000 : "rootfs_data"
[ 0.457328] 0x000000800000-0x000000f90000 : "ubnt-airos"
[ 0.463487] 0x000000f90000-0x000000fb0000 : "bs"
[ 0.468871] 0x000000fb0000-0x000000ff0000 : "cfg"
[ 0.474399] 0x000000ff0000-0x000001000000 : "EEPROM"
[ 0.481027] libphy: Fixed MDIO Bus: probed
[ 0.821765] libphy: ag71xx_mdio: probed
[ 0.828683] switch0: Atheros AR8337 rev. 2 switch registered on mdio-bus.0
[ 1.822552] ag71xx 19000000.eth: connected to PHY at mdio-bus.0:00 [uid=004dd036, driver=Atheros AR8216/AR8236/AR8316]
[ 1.834142] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:MII
[ 1.841966] NET: Registered protocol family 10
[ 1.849685] Segment Routing with IPv6
[ 1.853614] NET: Registered protocol family 17
[ 1.858243] 8021q: 802.1Q VLAN Support v1.8
[ 1.869000] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
[ 1.883041] Freeing unused kernel memory: 1240K
[ 1.887722] This architecture does not have kernel memory protection.
[ 2.526075] init: Console is alive
[ 2.529766] init: - watchdog -
[ 2.551406] random: fast init done
[ 3.475155] kmodloader: loading kernel modules from /etc/modules-boot.d/

[ 3.613967] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
[ 3.623297] init: - preinit -
[ 4.661529] random: jshn: uninitialized urandom read (4 bytes read)
[ 4.896463] random: jshn: uninitialized urandom read (4 bytes read)
[ 5.018227] random: jshn: uninitialized urandom read (4 bytes read)
[ 6.195531] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 6.201676] IPv6: ADDRCONF(NETDEV_UP): eth0.1: link is not ready
[ 6.236678] urandom_read: 2 callbacks suppressed
[ 6.236684] random: procd: uninitialized urandom read (4 bytes read)
[ 7.232736] eth0: link up (1000Mbps/Full duplex)
[ 7.237594] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 7.244397] IPv6: ADDRCONF(NETDEV_CHANGE): eth0.1: link becomes ready
[ 9.503495] jffs2: notice: (420) jffs2_build_xattr_subsystem: complete building xattr subsystem, 4 of xdatum (3 unchecked, 1 orphan) and 25 of xref (1 dead, 0 orphan) found.
[ 9.521379] mount_root: switching to jffs2 overlay
[ 9.550549] overlayfs: upper fs does not support tmpfile.
[ 9.562382] urandom-seed: Seeding with /etc/urandom.seed
[ 9.612343] procd: - early -
[ 9.615402] procd: - watchdog -
[ 10.286092] procd: - watchdog -
[ 10.289564] procd: - ubus -
[ 10.360741] eth0: link down
[ 10.380910] random: ubusd: uninitialized urandom read (4 bytes read)
[ 10.461364] random: ubusd: uninitialized urandom read (4 bytes read)
[ 10.468363] random: ubusd: uninitialized urandom read (4 bytes read)
[ 10.476177] procd: - init -
[ 10.805857] kmodloader: loading kernel modules from /etc/modules.d/*
[ 10.831676] u32 classifier
[ 10.834480] input device check on
[ 10.838258] Actions configured
[ 10.845712] Mirror/redirect action on
[ 10.853049] Loading modules backported from Linux version wt-2017-11-01-0-gfe248fc2c180
[ 10.861314] Backport generated by backports.git v4.14-rc2-1-31-g86cf0e5d
[ 10.875707] nf_conntrack version 0.5.0 (2048 buckets, 8192 max)
[ 10.906672] xt_time: kernel timezone is -0000
[ 11.041611] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 11.105669] PCI: Enabling device 0000:00:00.0 (0000 -> 0002)
[ 11.111736] ath10k_pci 0000:00:00.0: pci irq legacy oper_irq_mode 1 irq_mode 0 reset_mode 0
[ 11.385453] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/pre-cal-pci-0000:00:00.0.bin failed with error -2
[ 11.396547] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 11.580752] firmware ath10k!pre-cal-pci-0000:00:00.0.bin: firmware_loading_store: map pages failed
[ 11.672289] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/firmware-6.bin failed with error -2
[ 11.683372] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 11.822278] firmware ath10k!QCA988X!hw2.0!firmware-6.bin: firmware_loading_store: map pages failed
[ 11.836540] ath10k_pci 0000:00:00.0: qca988x hw2.0 target 0x4100016c chip_id 0x043202ff sub 0000:0000
[ 11.846112] ath10k_pci 0000:00:00.0: kconfig debug 0 debugfs 1 tracing 0 dfs 1 testmode 1
[ 11.859098] ath10k_pci 0000:00:00.0: firmware ver 10.2.4-1.0-00037 api 5 features no-p2p,raw-mode,mfp,allows-mesh-bcast crc32 a4a52adb
[ 11.961567] ath10k_pci 0000:00:00.0: Direct firmware load for ath10k/QCA988X/hw2.0/board-2.bin failed with error -2
[ 11.972379] ath10k_pci 0000:00:00.0: Falling back to user helper
[ 12.071886] firmware ath10k!QCA988X!hw2.0!board-2.bin: firmware_loading_store: map pages failed
[ 12.081281] ath10k_pci 0000:00:00.0: board_file api 1 bmi_id N/A crc32 bebc7c08
[ 13.189020] ath10k_pci 0000:00:00.0: htt-ver 2.1 wmi-op 5 htt-op 2 cal file max-sta 128 raw 0 hwcrypto 1
[ 13.332905] ath: EEPROM regdomain: 0x0
[ 13.332913] ath: EEPROM indicates default country code should be used
[ 13.332915] ath: doing EEPROM country->regdmn map search
[ 13.332927] ath: country maps to regdmn code: 0x3a
[ 13.332931] ath: Country alpha2 being used: US
[ 13.332934] ath: Regpair used: 0x3a
[ 13.391259] ath: EEPROM regdomain: 0x0
[ 13.391267] ath: EEPROM indicates default country code should be used
[ 13.391270] ath: doing EEPROM country->regdmn map search
[ 13.391283] ath: country maps to regdmn code: 0x3a
[ 13.391287] ath: Country alpha2 being used: US
[ 13.391290] ath: Regpair used: 0x3a
[ 13.402133] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[ 13.405447] ieee80211 phy1: Atheros AR9561 Rev:0 mem=0xb8100000, irq=2
[ 13.480039] kmodloader: done loading kernel modules from /etc/modules.d/*
[ 21.068302] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
[ 21.092529] br-guest: port 1(eth0.99) entered blocking state
[ 21.098378] br-guest: port 1(eth0.99) entered disabled state
[ 21.104533] device eth0.99 entered promiscuous mode
[ 21.109576] device eth0 entered promiscuous mode
[ 21.193425] IPv6: ADDRCONF(NETDEV_UP): br-guest: link is not ready
[ 21.504119] br-lan: port 1(eth0.54) entered blocking state
[ 21.509794] br-lan: port 1(eth0.54) entered disabled state
[ 21.515776] device eth0.54 entered promiscuous mode
[ 21.565236] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[ 22.113054] eth0: link up (1000Mbps/Full duplex)
[ 22.117901] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 22.171521] br-lan: port 1(eth0.54) entered blocking state
[ 22.177200] br-lan: port 1(eth0.54) entered forwarding state
[ 22.183236] br-guest: port 1(eth0.99) entered blocking state
[ 22.189087] br-guest: port 1(eth0.99) entered forwarding state
[ 22.281478] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 22.288359] IPv6: ADDRCONF(NETDEV_CHANGE): br-guest: link becomes ready
[ 23.129516] ath: EEPROM regdomain: 0x8064
[ 23.129523] ath: EEPROM indicates we should expect a country code
[ 23.129527] ath: doing EEPROM country->regdmn map search
[ 23.129531] ath: country maps to regdmn code: 0x34
[ 23.129536] ath: Country alpha2 being used: BG
[ 23.129539] ath: Regpair used: 0x34
[ 23.129544] ath: regdomain 0x8064 dynamically updated by user
[ 23.129617] ath: EEPROM regdomain: 0x8064
[ 23.129619] ath: EEPROM indicates we should expect a country code
[ 23.129622] ath: doing EEPROM country->regdmn map search
[ 23.129626] ath: country maps to regdmn code: 0x34
[ 23.129629] ath: Country alpha2 being used: BG
[ 23.129631] ath: Regpair used: 0x34
[ 23.129635] ath: regdomain 0x8064 dynamically updated by user
[ 28.174777] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 28.208391] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[ 28.218020] br-lan: port 2(wlan0) entered blocking state
[ 28.223563] br-lan: port 2(wlan0) entered disabled state
[ 28.229392] device wlan0 entered promiscuous mode
[ 28.254432] br-lan: port 3(wlan1) entered blocking state
[ 28.259926] br-lan: port 3(wlan1) entered disabled state
[ 28.265744] device wlan1 entered promiscuous mode
[ 28.270666] br-lan: port 3(wlan1) entered blocking state
[ 28.276204] br-lan: port 3(wlan1) entered forwarding state
[ 28.745204] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 28.791815] br-guest: port 2(wlan3) entered blocking state
[ 28.797491] br-guest: port 2(wlan3) entered disabled state
[ 28.803492] device wlan3 entered promiscuous mode
[ 28.851733] IPv6: ADDRCONF(NETDEV_UP): wlan3: link is not ready
[ 28.857861] br-guest: port 2(wlan3) entered blocking state
[ 28.863568] br-guest: port 2(wlan3) entered forwarding state
[ 29.201443] br-guest: port 2(wlan3) entered disabled state
[ 29.341619] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 29.348486] br-lan: port 2(wlan0) entered blocking state
[ 29.354037] br-lan: port 2(wlan0) entered forwarding state
[ 29.386180] br-guest: port 3(wlan2) entered blocking state
[ 29.391918] br-guest: port 3(wlan2) entered disabled state
[ 29.397860] device wlan2 entered promiscuous mode
[ 29.433173] IPv6: ADDRCONF(NETDEV_UP): wlan2: link is not ready
[ 29.439319] br-guest: port 3(wlan2) entered blocking state
[ 29.445026] br-guest: port 3(wlan2) entered forwarding state
[ 29.623445] IPv6: ADDRCONF(NETDEV_CHANGE): wlan3: link becomes ready
[ 29.630197] br-guest: port 2(wlan3) entered blocking state
[ 29.635912] br-guest: port 2(wlan3) entered forwarding state
[ 30.056348] IPv6: ADDRCONF(NETDEV_CHANGE): wlan2: link becomes ready
[ 50.081453] random: crng init done
[ 50.084979] random: 1 urandom warning(s) missed due to ratelimiting
[59431.450588] ath10k_pci 0000:00:00.0: failed to delete peer 00:09:34:5a:84:3c for vdev 0: -145
[59431.459430] ath10k_pci 0000:00:00.0: found sta peer 00:09:34:5a:84:3c (ptr 860b2a00 id 113) entry on vdev 0 after it was supposedly removed
[59431.472674] ------------[ cut here ]------------
[59431.477580] WARNING: CPU: 0 PID: 1555 at /home/build/lede/build_dir/target-mips_24kc_musl/linux-ath79_generic/backports-2017-11-01/net/mac80211/sta_info.c:1001 sta_set_sinfo+0x990/0xa38 [mac80211]
[59431.495658] Modules linked in: ath9k ath9k_common ath9k_hw ath10k_pci ath10k_core ath nf_conntrack_ipv4 mac80211 iptable_mangle iptable_filter ipt_REJECT ip_tables cfg80211 xt_time xt_tcpudp xt_multiport xt_mark xt_mac xt_limit xt_comment xt_TCPMSS xt_LOG xt_FLOWOFFLOAD x_tables thermal_sys nf_reject_ipv4 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 act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress sha256_generic jitterentropy_rng drbg hmac cmac gpio_button_hotplug aead cryptomgr crypto_null crypto_hash
[59431.554976] CPU: 0 PID: 1555 Comm: hostapd Not tainted 4.14.59 #0
[59431.561268] Stack : 00000000 00000000 80647522 00000035 873258d4 804eb907 80494d44 00000613
[59431.569906] 80643670 000003e9 8730dcb4 87464000 8031da24 00000001 8730dab8 48f8969d
[59431.578545] 00000000 00000000 80640000 00004bb8 00000000 00000000 00000007 00000000
[59431.587190] 000000fc 4778edb2 000000fb 00000000 80000000 00000000 87661bec 876076b0
[59431.595836] 00000009 000003e9 8730dcb4 87464000 00000000 8025d584 00000000 80640000
[59431.604483] ...
[59431.607011] Call Trace:
[59431.609551] [<8006a90c>] show_stack+0x58/0x100
[59431.614170] [<80080900>] __warn+0xe4/0x118
[59431.618406] [<800809c4>] warn_slowpath_null+0x1c/0x28
[59431.623734] [<876076b0>] sta_set_sinfo+0x990/0xa38 [mac80211]
[59431.629717] ---[ end trace 91470c3845c73966 ]---
[59440.730577] ath10k_pci 0000:00:00.0: failed to create wmi peer 00:09:34:5a:84:3c on vdev 0: -11
[59440.739647] ath10k_pci 0000:00:00.0: failed to add peer 00:09:34:5a:84:3c for vdev 0 when adding a new sta: -11
[59449.850549] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59458.970480] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59462.010465] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59471.130421] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59474.170406] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59477.210398] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59486.330346] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59495.450319] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59498.490309] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[59507.610273] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[59510.650255] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[59513.690245] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[59522.810219] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59531.930163] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59534.970141] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59544.090112] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59547.130083] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59550.170073] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59559.290032] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59568.410027] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59571.449990] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[59580.569947] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[59583.609931] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[59586.649917] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[59595.769870] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59604.889835] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59607.929845] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59617.049784] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59620.089781] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59623.129744] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59632.249704] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59641.369676] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59644.409810] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[59653.529626] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[59656.569611] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[59659.609589] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[59668.729572] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59677.849516] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59680.889624] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59690.009463] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59693.049589] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59696.089422] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59705.209388] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59714.329350] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59717.369357] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[59726.489295] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[59729.529284] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[59732.569278] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[59741.689240] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59750.809216] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59753.849188] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59762.969129] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59766.009129] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59769.049126] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59778.169063] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59787.289031] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59790.329027] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[59799.449006] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[59802.488982] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[59805.528949] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[59814.648911] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer 34:13:e8:5f:78:a0: -11
[59814.658133] wlan0: failed to remove key (0, 34:13:e8:5f:78:a0) from hardware (-11)
[59823.768886] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59832.888850] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59835.928822] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59845.048774] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59848.088772] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59851.128751] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59857.208757] ath10k_pci 0000:00:00.0: cipher -2043684608 is not supported
[59857.215702] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[59857.222831] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[59857.231122] ath10k_pci 0000:00:00.0: failed to disassociate station: 34:13:e8:5f:78:a0 vdev 0: -122
[59857.240473] ------------[ cut here ]------------
[59857.245382] WARNING: CPU: 0 PID: 1555 at /home/build/lede/build_dir/target-mips_24kc_musl/linux-ath79_generic/backports-2017-11-01/net/mac80211/sta_info.c:993 sta_set_sinfo+0x924/0xa38 [mac80211]
[59857.263340] Modules linked in: ath9k ath9k_common ath9k_hw ath10k_pci ath10k_core ath nf_conntrack_ipv4 mac80211 iptable_mangle iptable_filter ipt_REJECT ip_tables cfg80211 xt_time xt_tcpudp xt_multiport xt_mark xt_mac xt_limit xt_comment xt_TCPMSS xt_LOG xt_FLOWOFFLOAD x_tables thermal_sys nf_reject_ipv4 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 act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress sha256_generic jitterentropy_rng drbg hmac cmac gpio_button_hotplug aead cryptomgr crypto_null crypto_hash
[59857.322656] CPU: 0 PID: 1555 Comm: hostapd Tainted: G W 4.14.59 #0
[59857.330207] Stack : 00000000 00000000 80647522 00000043 873258d4 804eb907 80494d44 00000613
[59857.338852] 80643670 000003e1 8730dcb4 87464000 8031da24 00000001 8730dab8 48f8969d
[59857.347489] 00000000 00000000 80640000 00007708 00000000 00000000 00000007 00000000
[59857.356131] 00000156 535b7413 00000155 00000000 80000000 00000000 87661bec 87607644
[59857.364772] 00000009 000003e1 8730dcb4 87464000 00000002 8025d584 00000000 80640000
[59857.373419] ...
[59857.375948] Call Trace:
[59857.378489] [<8006a90c>] show_stack+0x58/0x100
[59857.383106] [<80080900>] __warn+0xe4/0x118
[59857.387335] [<800809c4>] warn_slowpath_null+0x1c/0x28
[59857.392653] [<87607644>] sta_set_sinfo+0x924/0xa38 [mac80211]
[59857.398637] ---[ end trace 91470c3845c73967 ]---
[59860.408782] ath10k_pci 0000:00:00.0: failed to delete peer 34:13:e8:5f:78:a0 for vdev 0: -11
[59860.417862] ath10k_pci 0000:00:00.0: found sta peer 34:13:e8:5f:78:a0 (ptr 860b2400 id 177) entry on vdev 0 after it was supposedly removed
[59869.528668] ath10k_pci 0000:00:00.0: failed to install key for vdev 0 peer a4:77:33:79:20:64: -11
[59869.537884] wlan0: failed to remove key (0, a4:77:33:79:20:64) from hardware (-11)
[59878.648632] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59887.768585] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59890.808594] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[59899.928544] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[59902.968530] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[59906.008507] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[59915.128461] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[59924.248439] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59927.288415] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59936.408405] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59939.448367] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59942.488364] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59948.568349] ath10k_pci 0000:00:00.0: cipher -2043685888 is not supported
[59948.575278] ath10k_pci 0000:00:00.0: failed to remove peer wep key 0: -122
[59948.582412] ath10k_pci 0000:00:00.0: failed to clear all peer wep keys for vdev 0: -122
[59948.590700] ath10k_pci 0000:00:00.0: failed to disassociate station: a4:77:33:79:20:64 vdev 0: -122
[59951.615437] ath10k_pci 0000:00:00.0: failed to delete peer a4:77:33:79:20:64 for vdev 0: -11
[59951.624268] ath10k_pci 0000:00:00.0: found sta peer a4:77:33:79:20:64 (ptr 870e8600 id 136) entry on vdev 0 after it was supposedly removed
[59960.728278] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[59963.768266] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[59972.888214] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[59975.928208] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[59978.968187] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[59988.088172] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[59991.128141] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[60000.248091] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[60003.288100] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[60006.328072] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[60015.448031] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60024.567986] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[60027.607989] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[60036.727934] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[60039.767920] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[60042.807939] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[60051.927871] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60061.047854] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[60064.087819] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[60073.207783] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[60076.247769] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[60079.287750] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[60088.407715] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60097.527665] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[60100.567681] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[60109.687634] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[60112.727602] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[60115.767597] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[60124.887553] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60134.007518] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[60137.047497] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[60146.167464] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[60149.207460] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[60152.247426] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[60161.367398] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60170.487356] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[60173.527352] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[60182.647300] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[60185.687281] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[60188.727289] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[60197.847246] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60206.967200] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[60210.007176] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[60219.127173] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[60222.167132] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[60225.207109] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[60234.327065] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60243.447037] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[60246.487033] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[60255.606988] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[60258.646964] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[60261.686949] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[60270.806924] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60279.926878] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[60282.966861] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[60292.086822] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[60295.126811] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[60298.166791] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11
[60307.286749] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60316.406732] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 1: -11
[60319.446701] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 1: -11
[60328.566660] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 1: -11
[60331.606655] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 1: -11
[60334.646647] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 1: -11
[60343.766608] ath10k_pci 0000:00:00.0: failed to send pdev bss chan info request
[60352.886561] ath10k_pci 0000:00:00.0: failed to set beacon mode for vdev 0: -11
[60355.926536] ath10k_pci 0000:00:00.0: failed to set dtim period for vdev 0: -11
[60365.046493] ath10k_pci 0000:00:00.0: failed to recalculate rts/cts prot for vdev 0: -11
[60368.086492] ath10k_pci 0000:00:00.0: failed to set cts protection for vdev 0: -11
[60371.126482] ath10k_pci 0000:00:00.0: failed to set preamble for vdev 0: -11

@openwrt-bot
Copy link
Author

nopnopnop:

I have the same problem with OpenWrt SNAPSHOT r8661-32bc733 on Archer C7 v2.

These ath10k_pci errors show up in the logs after a few days and the 5 GHz wifi stops working.

@openwrt-bot
Copy link
Author

stintel:

As of r8841-8c6662a1ac, ar71xx and ath79 have been switched to use ath10k-ct by default. This problem hasn't occurred for me with ath10k-ct. Please upgrade to a more recent snapshot.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

2 participants