OpenWrt/LEDE Project

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

FS#1074 - NETDEV WATCHDOG: wlan2 (mwifiex_sdio): transmit queue 1 timed out - WRT3200ACM

- Device problem occurs on: WRT3200ACM
- Software versions of LEDE release, packages, etc.: LEDE Reboot SNAPSHOT r5096-a29848c / LuCI Master (git-17.260.43176-678c15c)
- Steps to reproduce: I’ve been consistently having problems with wlan2 going offline after the router has been up for a day or so, but just stumbled across this in dmesg. I’ll see if I can figure out the circumstances leading to it.

 

[85338.316517] WARNING: CPU: 1 PID: 0 at net/sched/sch_generic.c:316 dev_watchdog+0x1b4/0×234 [85338.324820] NETDEV WATCHDOG: wlan2 (mwifiex_sdio): transmit queue 1 timed out
[85338.331987] Modules linked in: pppoe ppp_async pppox ppp_generic nf_conntrack_ipv6 iptable_nat ipt_REJECT ipt_MASQUERADE 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_TCPMSS xt_REDIRECT xt_LOG xt_HL xt_DSCP xt_CLASSIFY slhc rfcomm nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack mwifiex_sdio mwifiex iptable_mangle iptable_filter ipt_ECN ip_tables hidp hci_uart crc_ccitt btusb btmrvl_sdio btmrvl btintel bnep bluetooth hid evdev input_core mwlwifi mac80211 cfg80211 compat xt_set ip_set_list_set ip_set_hash_netiface ip_set_hash_netport
[85338.404541] ip_set_hash_netnet ip_set_hash_net ip_set_hash_netportnet 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 ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables x_tables tun regmap_mmio ecb cmac uhci_hcd ohci_platform ohci_hcd gpio_button_hotplug
[85338.442106] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.9.54 #0
[85338.448049] Hardware name: Marvell Armada 380/385 (Device Tree)
[85338.454003] [<c0016010>] (unwind_backtrace) from [<c0012220>] (show_stack+0×10/0×14)
[85338.461782] [<c0012220>] (show_stack) from [<c020c1a0>] (dump_stack+0x7c/0x9c)
[85338.469036] [<c020c1a0>] (dump_stack) from [<c0029218>] (warn+0xbc/0xec)
[85338.475939] [<c0029218>] (
warn) from [<c002927c>] (warn_slowpath_fmt+0×34/0×44)
[85338.483455] [<c002927c>] (warn_slowpath_fmt) from [<c03b6c38>] (dev_watchdog+0x1b4/0×234)
[85338.491661] [<c03b6c38>] (dev_watchdog) from [<c006ffe8>] (call_timer_fn+0×20/0×94)
[85338.499349] [<c006ffe8>] (call_timer_fn) from [<c0070220>] (run_timer_softirq+0x1c4/0×200)
[85338.507650] [<c0070220>] (run_timer_softirq) from [<c002d2b4>] (do_softirq+0xd0/0×204)
[85338.515775] [<c002d2b4>] (
do_softirq) from [<c002d66c>] (irq_exit+0×94/0xb8)
[85338.523029] [<c002d66c>] (irq_exit) from [<c0061e04>] (handle_domain_irq+0×90/0xb4)
[85338.530892] [<c0061e04>] (
handle_domain_irq) from [<c0009428>] (gic_handle_irq+0×50/0×94)
[85338.539277] [<c0009428>] (gic_handle_irq) from [<c0012c8c>] (irq_svc+0x6c/0×90)
[85338.546789] Exception stack(0xdf475f90 to 0xdf475fd8)
[85338.551860] 5f80: 00000001 00000000 00000000 c001b1c0
[85338.560071] 5fa0: 00000000 df474000 c0604fe4 00000002 c05ff168 00000000 df475fe8 00000001
[85338.568282] 5fc0: 00000000 df475fe0 c000f808 c000f80c 60000013 ffffffff
[85338.574925] [<c0012c8c>] (
irq_svc) from [<c000f80c>] (arch_cpu_idle+0x2c/0×38)
[85338.582355] [<c000f80c>] (arch_cpu_idle) from [<c005b154>] (cpu_startup_entry+0xf0/0x19c)
[85338.590567] [<c005b154>] (cpu_startup_entry) from [<000095ac>] (0x95ac)
[85338.597214] —[ end trace 7ee504210e2aead0 ]— [85338.601865] mwifiex_sdio mmc0:0001:1: 8504333 : Tx timeout(#1), bss_type-num = 1-0
[85344.871433] mwifiex_sdio mmc0:0001:1: 8504960 : Tx timeout(#2), bss_type-num = 1-0
[85350.631045] mwifiex_sdio mmc0:0001:1: 8505536 : Tx timeout(#3), bss_type-num = 1-0
[85356.400657] mwifiex_sdio mmc0:0001:1: 8506113 : Tx timeout(#4), bss_type-num = 1-0
[85366.309996] mwifiex_sdio mmc0:0001:1: 8507104 : Tx timeout(#5), bss_type-num = 1-0
[85376.309327] mwifiex_sdio mmc0:0001:1: 8508104 : Tx timeout(#6), bss_type-num = 1-0
[85386.318652] mwifiex_sdio mmc0:0001:1: 8509105 : Tx timeout(#7), bss_type-num = 1-0
[85386.326257] mwifiex_sdio mmc0:0001:1: tx_timeout_cnt exceeds threshold. Triggering card reset!
[85386.356950] mwifiex_sdio mmc0:0001:1: info: shutdown mwifiex...
[85386.362967] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[85386.368924] mwifiex_sdio mmc0:0001:1: Failed to delete mgmt IEs!
[85386.374978] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[85386.380928] mwifiex_sdio mmc0:0001:1: Failed to stop the BSS
[85386.387009] br-lan: port 4(wlan2) entered disabled state
[85386.394495] device wlan2 left promiscuous mode
[85386.398975] br-lan: port 4(wlan2) entered disabled state
[85386.404593] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[85386.410562] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[85386.416507] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[85386.603147] mwifiex_sdio mmc0:0001:1: WLAN FW already running! Skip FW dnld
[85386.610161] mwifiex_sdio mmc0:0001:1: WLAN FW is active
[85396.707953] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout cmd id = 0xa9, act = 0×0 [85396.716787] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
[85396.722652] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
[85396.728423] mwifiex_sdio mmc0:0001:1: is_cmd_timedout = 1
[85396.733842] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
[85396.739178] mwifiex_sdio mmc0:0001:1: last_cmd_index = 3
[85396.744512] mwifiex_sdio mmc0:0001:1: last_cmd_id: b3 00 b3 00 b2 00 a9 00 b3 00
[85396.751940] mwifiex_sdio mmc0:0001:1: last_cmd_act: 00 00 00 00 00 00 00 00 00 00
[85396.759457] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 2
[85396.765225] mwifiex_sdio mmc0:0001:1: last_cmd_resp_id: b3 80 b3 80 b2 80 b3 80 b3 80
[85396.773093] mwifiex_sdio mmc0:0001:1: last_event_index = 2
[85396.778603] mwifiex_sdio mmc0:0001:1: last_event: 37 00 55 00 43 00 55 00 55 00
[85396.785944] mwifiex_sdio mmc0:0001:1: data_sent=1 cmd_sent=1
[85396.791630] mwifiex_sdio mmc0:0001:1: ps_mode=0 ps_state=0
[85396.797372] mwifiex_sdio mmc0:0001:1: info: _mwifiex_fw_dpc: unregister device

ratsputin commented on 20.10.2017 17:03

Second failure today:

[62698.039681] ————[ cut here ]———— [62698.044334] WARNING: CPU: 1 PID: 14 at net/sched/sch_generic.c:316 dev_watchdog+0x1b4/0x234
[62698.052727] NETDEV WATCHDOG: wlan2 (mwifiex_sdio): transmit queue 1 timed out
[62698.059893] Modules linked in: pppoe ppp_async pppox ppp_generic nf_conntrack_ipv6 iptable_nat ipt_REJECT ipt_MASQUERADE 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_TCPMSS xt_REDIRECT xt_LOG xt_HL xt_DSCP xt_CLASSIFY slhc rfcomm nf_reject_ipv4 nf_nat_redirect nf_nat_masquerade_ipv4 nf_conntrack_ipv4 nf_nat_ipv4 nf_nat nf_log_ipv4 nf_defrag_ipv6 nf_defrag_ipv4 nf_conntrack_rtcache nf_conntrack mwifiex_sdio mwifiex iptable_mangle iptable_filter ipt_ECN ip_tables hidp hci_uart crc_ccitt btusb btmrvl_sdio btmrvl btintel bnep bluetooth hid evdev input_core mwlwifi mac80211 cfg80211 compat xt_set ip_set_list_set ip_set_hash_netiface ip_set_hash_netport
[62698.132465] ip_set_hash_netnet ip_set_hash_net ip_set_hash_netportnet 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 ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables x_tables tun regmap_mmio ecb cmac uhci_hcd ohci_platform ohci_hcd gpio_button_hotplug
[62698.170034] CPU: 1 PID: 14 Comm: ksoftirqd/1 Not tainted 4.9.54 #0
[62698.176238] Hardware name: Marvell Armada 380/385 (Device Tree)
[62698.182192] [<c0016010>] (unwind_backtrace) from [<c0012220>] (show_stack+0x10/0x14)
[62698.189973] [<c0012220>] (show_stack) from [<c020c1a0>] (dump_stack+0x7c/0x9c)
[62698.197227] [<c020c1a0>] (dump_stack) from [<c0029218>] (warn+0xbc/0xec)
[62698.204130] [<c0029218>] (
warn) from [<c002927c>] (warn_slowpath_fmt+0x34/0x44)
[62698.211647] [<c002927c>] (warn_slowpath_fmt) from [<c03b6c38>] (dev_watchdog+0x1b4/0x234)
[62698.219861] [<c03b6c38>] (dev_watchdog) from [<c006ffe8>] (call_timer_fn+0x20/0x94)
[62698.227549] [<c006ffe8>] (call_timer_fn) from [<c0070220>] (run_timer_softirq+0x1c4/0x200)
[62698.235848] [<c0070220>] (run_timer_softirq) from [<c002d2b4>] (do_softirq+0xd0/0x204)
[62698.243973] [<c002d2b4>] (
do_softirq) from [<c002d414>] (run_ksoftirqd+0x2c/0x50)
[62698.251663] [<c002d414>] (run_ksoftirqd) from [<c0045140>] (smpboot_thread_fn+0x16c/0x184)
[62698.259963] [<c0045140>] (smpboot_thread_fn) from [<c00422b4>] (kthread+0xd8/0xec)
[62698.267566] [<c00422b4>] (kthread) from [<c000edf8>] (ret_from_fork+0x14/0x3c)
[62698.274822] —[ end trace 13b6a7d4f3c0a950 ]— [62698.279461] mwifiex_sdio mmc0:0001:1: 6240127 : Tx timeout(#1), bss_type-num = 1-0
[62704.039294] mwifiex_sdio mmc0:0001:1: 6240704 : Tx timeout(#2), bss_type-num = 1-0
[62714.038674] mwifiex_sdio mmc0:0001:1: 6241704 : Tx timeout(#3), bss_type-num = 1-0
[62724.038054] mwifiex_sdio mmc0:0001:1: 6242704 : Tx timeout(#4), bss_type-num = 1-0
[62734.047454] mwifiex_sdio mmc0:0001:1: 6243705 : Tx timeout(#5), bss_type-num = 1-0
[62744.036836] mwifiex_sdio mmc0:0001:1: 6244704 : Tx timeout(#6), bss_type-num = 1-0
[62754.036216] mwifiex_sdio mmc0:0001:1: 6245704 : Tx timeout(#7), bss_type-num = 1-0
[62754.043823] mwifiex_sdio mmc0:0001:1: tx_timeout_cnt exceeds threshold. Triggering card reset!
[62754.228690] mwifiex_sdio mmc0:0001:1: info: shutdown mwifiex...
[62754.234775] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[62754.240738] mwifiex_sdio mmc0:0001:1: Failed to delete mgmt IEs!
[62754.246776] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[62754.252747] mwifiex_sdio mmc0:0001:1: Failed to stop the BSS
[62754.258851] br-lan: port 4(wlan2) entered disabled state
[62754.266263] device wlan2 left promiscuous mode
[62754.270765] br-lan: port 4(wlan2) entered disabled state
[62754.276246] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[62754.282222] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[62754.288181] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
[62754.500727] mwifiex_sdio mmc0:0001:1: WLAN FW already running! Skip FW dnld
[62754.507737] mwifiex_sdio mmc0:0001:1: WLAN FW is active
[62764.835522] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout cmd id = 0xa9, act = 0x0
[62764.844350] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
[62764.850213] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
[62764.855988] mwifiex_sdio mmc0:0001:1: is_cmd_timedout = 1
[62764.861408] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
[62764.866745] mwifiex_sdio mmc0:0001:1: last_cmd_index = 2
[62764.872082] mwifiex_sdio mmc0:0001:1: last_cmd_id: 1e 00 b2 00 a9 00 b3 00 b3 00
[62764.879513] mwifiex_sdio mmc0:0001:1: last_cmd_act: 00 00 00 00 00 00 00 00 00 00
[62764.887030] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 1
[62764.892799] mwifiex_sdio mmc0:0001:1: last_cmd_resp_id: 1e 80 b2 80 b3 80 b3 80 b3 80
[62764.900664] mwifiex_sdio mmc0:0001:1: last_event_index = 0
[62764.906176] mwifiex_sdio mmc0:0001:1: last_event: 43 00 37 00 55 00 55 00 55 00
[62764.913515] mwifiex_sdio mmc0:0001:1: data_sent=1 cmd_sent=1
[62764.919202] mwifiex_sdio mmc0:0001:1: ps_mode=0 ps_state=0
[62764.926512] mwifiex_sdio mmc0:0001:1: info: _mwifiex_fw_dpc: unregister device

Ted Carnahan commented on 26.10.2017 02:46

Do you know what version of mwifiex you are using? e.g. in dmesg: "[ 8.289013] «Marvell Mac80211 Wireless PCIE Network Driver version 10.3.4.0-20170606»"

franco commented on 17.08.2018 13:41

Interesting, I am experiencing exaclty the same problem, on the same device (WRT3200ACM), on the same interface (wlan2). So I can indeed confirm this issue.

The interface wlan2 (Marvell 88W8887) causes trouble ever since. On the other hand, wlan0 and wlan1 (Marvell 88W8964) seem to work just fine. I tried with both 2G and 5G, it does not make any difference.

I am facing this problem since LEDE 17.01.0 - currently I have installed LEDE 18.06.0

uname -a
Linux LEDE 4.14.54 #0 SMP Sat Jul 14 11:10:08 2018 armv7l GNU/Linux

Output from LuCI:
Powered by LuCI openwrt-18.06 branch (git-18.219.52706-a49e4e4) / OpenWrt 18.06.0 r7188-b0b5c64c22

I just noticed that LEDE 18.06.1 is out, but however I don't expect this problem to be solved. Well, I will give it a try...

Installed wifi modules:

opkg list-installed | grep wifi
kmod-mwifiex-sdio - 4.14.54+2017-11-01-9
kmod-mwlwifi - 4.14.54+2018-03-30-fcaea79a-1
mwifiex-sdio-firmware - 2017-09-06-a61ac5cf-1
mwlwifi-firmware-88w8964 - 2018-03-30-fcaea79a-1

Output from iwinfo:

wlan0     ESSID: "LEDE-0"
          Access Point: 60:38:E0:BD:7B:BA
          Mode: Master  Channel: 44 (5.220 GHz)
          Tx-Power: 20 dBm  Link Quality: unknown/70
          Signal: unknown  Noise: -97 dBm
          Bit Rate: unknown
          Encryption: WPA2 PSK (CCMP)
          Type: nl80211  HW Mode(s): 802.11nac
          Hardware: 11AB:2B40 11AB:0000 [Marvell 88W8964]
          TX power offset: none
          Frequency offset: none
          Supports VAPs: yes  PHY name: phy0

wlan1     ESSID: "LEDE-1"
          Access Point: 60:38:E0:BD:7B:B9
          Mode: Master  Channel: 11 (2.462 GHz)
          Tx-Power: 20 dBm  Link Quality: unknown/70
          Signal: unknown  Noise: -49 dBm
          Bit Rate: unknown
          Encryption: WPA2 PSK (CCMP)
          Type: nl80211  HW Mode(s): 802.11bgn
          Hardware: 11AB:2B40 11AB:0000 [Marvell 88W8964]
          TX power offset: none
          Frequency offset: none
          Supports VAPs: yes  PHY name: phy1

wlan2     ESSID: "LEDE-2"
          Access Point: 60:38:E0:BD:7B:BB
          Mode: Master  Channel: 1 (2.412 GHz)
          Tx-Power: 0 dBm  Link Quality: unknown/70
          Signal: unknown  Noise: -90 dBm
          Bit Rate: unknown
          Encryption: WPA2 PSK (CCMP)
          Type: nl80211  HW Mode(s): 802.11bgnac
          Hardware: 02DF:9135 0000:0000 [Marvell 88W8887]
          TX power offset: none
          Frequency offset: none
          Supports VAPs: yes  PHY name: phy2

ifconfig shows many TX packet errors. The other interfaces don't have such errors:

wlan2     Link encap:Ethernet  HWaddr 60:38:E0:BD:7B:BB  
          inet6 addr: fe80::6238:e0ff:febd:7bbb/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:11707 errors:0 dropped:0 overruns:0 frame:0
          TX packets:17974 errors:5858 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000 
          RX bytes:1937866 (1.8 MiB)  TX bytes:6569991 (6.2 MiB)

After this bug triggers, wlan2 stops working, the interface disappears.

Here the output from syslog (logread)

Thu Aug 16 22:47:59 2018 daemon.info hostapd: wlan2: STA dc:f0:90:8b:d4:64 WPA: group key handshake completed (RSN)
[...SKIPPED...]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96785.846083] ------------[ cut here ]------------
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96785.850729] WARNING: CPU: 1 PID: 0 at net/sched/sch_generic.c:320 0xc0541144
Fri Aug 17 01:40:23 2018 kern.info kernel: [96785.857812] NETDEV WATCHDOG: wlan2 (mwifiex_sdio): transmit queue 1 timed out
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96785.864977] Modules linked in: pppoe ppp_async pppox ppp_generic nf_conntrack_ipv6 iptable_nat ipt_REJECT ipt_MASQUERADE xt_time xt_tcpudp xt_tcpmss xt_statistic xt_state xt_nat xt_multiport xt_mark xt_mac xt_limit xt_length xt_hl xt_ecn xt_dscp xt_conntrack xt_comment xt_TCPMSS xt_REDIRECT xt_LOG xt_LED xt_HL xt_FLOWOFFLOAD xt_DSCP xt_CT xt_CLASSIFY slhc rfcomm 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 nf_conntrack mwifiex_sdio mwifiex macvlan lib80211_crypt_wep lib80211_crypt_tkip lib80211_crypt_ccmp lib80211 iptable_mangle iptable_filter ipt_ECN ip_tables hidp hci_uart crc_ccitt btusb btmrvl_sdio btmrvl btintel bnep bluetooth sch_fq
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96785.935898]  sch_teql em_nbyte sch_pie sch_gred act_police act_ipt sch_red sch_multiq sch_prio em_cmp em_meta em_text sch_codel sch_sfq cls_basic sch_dsmark act_skbedit act_mirred em_u32 cls_u32 cls_tcindex cls_flow cls_route cls_fw sch_tbf sch_htb sch_hfsc sch_ingress hid evdev input_core mwlwifi mac80211 cfg80211 compat ledtrig_oneshot ledtrig_heartbeat ledtrig_gpio xt_set ip_set_list_set ip_set_hash_netiface ip_set_hash_netport ip_set_hash_netnet ip_set_hash_net ip_set_hash_netportnet 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 ip6t_REJECT nf_reject_ipv6 nf_log_ipv6 nf_log_common ip6table_mangle ip6table_filter ip6_tables x_tables ecdh_generic kpp ecb cmac
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.006906]  ohci_pci uhci_hcd ohci_platform ohci_hcd ledtrig_transient gpio_button_hotplug
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.015303] CPU: 1 PID: 0 Comm: swapper/1 Not tainted 4.14.54 #0
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.021333] Hardware name: Marvell Armada 380/385 (Device Tree)
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.027280] Function entered at [<c010f500>] from [<c010b1d0>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.033138] Function entered at [<c010b1d0>] from [<c0619a08>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.038994] Function entered at [<c0619a08>] from [<c0121d40>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.044851] Function entered at [<c0121d40>] from [<c0121d94>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.050708] Function entered at [<c0121d94>] from [<c0541144>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.056566] Function entered at [<c0541144>] from [<c016f408>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.062423] Function entered at [<c016f408>] from [<c016f5e4>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.068279] Function entered at [<c016f5e4>] from [<c0101628>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.074136] Function entered at [<c0101628>] from [<c01264f0>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.079993] Function entered at [<c01264f0>] from [<c015d4f8>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.085850] Function entered at [<c015d4f8>] from [<c0101464>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.091707] Function entered at [<c0101464>] from [<c010be8c>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.097564] Exception stack(0xdf465f80 to 0xdf465fc8)
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.102638] 5f80: 00000001 00000000 00000000 c0114b40 ffffe000 c0903c70 c0903c24 00000000
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.110852] 5fa0: 00000000 414fc091 00000000 00000000 df465fc8 df465fd0 c01083bc c01083c0
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.119064] 5fc0: 60000013 ffffffff
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.122566] Function entered at [<c010be8c>] from [<c01083c0>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.128422] Function entered at [<c01083c0>] from [<c0154430>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.134279] Function entered at [<c0154430>] from [<c015474c>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.140136] Function entered at [<c015474c>] from [<0010182c>]
Fri Aug 17 01:40:23 2018 kern.warn kernel: [96786.145997] ---[ end trace 93c8c0f9c7a97688 ]---
Fri Aug 17 01:40:23 2018 kern.info kernel: [96786.150638] mwifiex_sdio mmc0:0001:1: 9647734 : Tx timeout(#1), bss_type-num = 1-0
Fri Aug 17 01:40:29 2018 kern.info kernel: [96792.007023] mwifiex_sdio mmc0:0001:1: 9648320 : Tx timeout(#2), bss_type-num = 1-0
Fri Aug 17 01:40:39 2018 kern.info kernel: [96801.858519] mwifiex_sdio mmc0:0001:1: 9649305 : Tx timeout(#3), bss_type-num = 1-0
Fri Aug 17 01:40:49 2018 kern.info kernel: [96811.850035] mwifiex_sdio mmc0:0001:1: 9650304 : Tx timeout(#4), bss_type-num = 1-0
Fri Aug 17 01:40:59 2018 kern.info kernel: [96821.851554] mwifiex_sdio mmc0:0001:1: 9651304 : Tx timeout(#5), bss_type-num = 1-0
Fri Aug 17 01:41:09 2018 kern.info kernel: [96831.853072] mwifiex_sdio mmc0:0001:1: 9652304 : Tx timeout(#6), bss_type-num = 1-0
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.864589] mwifiex_sdio mmc0:0001:1: 9653305 : Tx timeout(#7), bss_type-num = 1-0
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.872197] mwifiex_sdio mmc0:0001:1: tx_timeout_cnt exceeds threshold.	Triggering card reset!
Fri Aug 17 01:41:19 2018 kern.err kernel: [96841.961195] mwifiex_sdio mmc0:0001:1: EVENT: BT coex wlan param update
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.967941] mwifiex_sdio mmc0:0001:1: info: shutdown mwifiex...
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.973992] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.979949] mwifiex_sdio mmc0:0001:1: Failed to delete mgmt IEs!
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.985986] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.991931] mwifiex_sdio mmc0:0001:1: Failed to stop the BSS
Fri Aug 17 01:41:19 2018 daemon.notice netifd: Network device 'wlan2' link is down
Fri Aug 17 01:41:19 2018 kern.info kernel: [96841.998251] br-lan: port 2(wlan2) entered disabled state
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.005518] device wlan2 left promiscuous mode
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.009985] br-lan: port 2(wlan2) entered disabled state
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.015510] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.021475] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.027458] mwifiex_sdio mmc0:0001:1: PREP_CMD: card is removed
Fri Aug 17 01:41:19 2018 daemon.notice hostapd: wlan2: AP-STA-DISCONNECTED dc:f0:90:8b:d4:64
Fri Aug 17 01:41:19 2018 daemon.notice hostapd: wlan2: INTERFACE-DISABLED
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.199223] mwifiex_sdio mmc0:0001:1: WLAN FW already running! Skip FW dnld
Fri Aug 17 01:41:19 2018 kern.info kernel: [96842.206236] mwifiex_sdio mmc0:0001:1: WLAN FW is active
Fri Aug 17 01:41:23 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Aug 17 01:41:29 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.816254] mwifiex_sdio mmc0:0001:1: mwifiex_cmd_timeout_func: Timeout cmd id = 0xa9, act = 0x0
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.825088] mwifiex_sdio mmc0:0001:1: num_data_h2c_failure = 0
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.830952] mwifiex_sdio mmc0:0001:1: num_cmd_h2c_failure = 0
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.836726] mwifiex_sdio mmc0:0001:1: is_cmd_timedout = 1
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.842147] mwifiex_sdio mmc0:0001:1: num_tx_timeout = 0
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.847488] mwifiex_sdio mmc0:0001:1: last_cmd_index = 1
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.852824] mwifiex_sdio mmc0:0001:1: last_cmd_id: b2 00 a9 00 b0 00 b0 00 b0 00
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.860259] mwifiex_sdio mmc0:0001:1: last_cmd_act: 00 00 00 00 01 00 01 00 01 00
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.867779] mwifiex_sdio mmc0:0001:1: last_cmd_resp_index = 0
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.873550] mwifiex_sdio mmc0:0001:1: last_cmd_resp_id: b2 80 20 80 b0 80 b0 80 b0 80
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.881417] mwifiex_sdio mmc0:0001:1: last_event_index = 2
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.886930] mwifiex_sdio mmc0:0001:1: last_event: 55 00 43 00 76 00 55 00 55 00
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.894272] mwifiex_sdio mmc0:0001:1: data_sent=1 cmd_sent=1
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.899959] mwifiex_sdio mmc0:0001:1: ps_mode=0 ps_state=0
Fri Aug 17 01:41:30 2018 kern.info kernel: [96852.905547] mwifiex_sdio mmc0:0001:1: info: _mwifiex_fw_dpc: unregister device
Fri Aug 17 01:41:35 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Aug 17 01:41:41 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Aug 17 01:41:47 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Aug 17 01:41:53 2018 daemon.err hostapd: Failed to set beacon parameters
Fri Aug 17 01:41:59 2018 daemon.err hostapd: Failed to set beacon parameters

Any help would much be appreciated.

marc-o94 commented on 19.08.2018 15:51

Hello i am experiencing exactly the same error on the same device and also on wlan 2 network adapter. I am using it with the 2G frequency and using wlan1 instead is not an option because i have an ESP8266 based wifi plug which is not connecting to wlan1 for some unknown reason. Does anybody have some help?

calder65 commented on 29.08.2018 16:12

+1 to franco's detailed report.

root@francis:~# uname -a
Linux francis 4.14.54 #0 SMP Sat Jul 14 11:10:08 2018 armv7l GNU/Linux
root@francis:~# opkg list-installed|grep wifi
kmod-mwifiex-sdio - 4.14.54+2017-11-01-9
kmod-mwlwifi - 4.14.54+2018-03-30-fcaea79a-1
mwifiex-sdio-firmware - 2017-09-06-a61ac5cf-1
mwlwifi-firmware-88w8964 - 2018-03-30-fcaea79a-1

wrtac3200acm using 5ghz 20Mhz on wlan2 radio. drops off rather quickly. logread is same as franco's above.

Stephane Peter commented on 04.12.2018 09:18

I am also experiencing this problem on the WRTAC3200ACM with OpenWrt 18.06.1 r7258-5eb055306f.

The wlan2 interface drops down and can't be brought back without rebooting. From the kernel log I would guess that there's some kind of bug in the device driver that gets triggered on this radio.

I also sometimes experience similar issues on wlan1 (which I have set up as my 2.4Ghz radio) which tends to drop off at random sometimes. Not sure if it is really the same issue but it does seem fishy since the drivers should be very closely related.

remy meneda commented on 21.03.2019 15:04

Hello,
i can confirm that i have the same bug and it very frustate.
I have upgraded to OpenWrt 18.06.2, hoping that the problem will be solved with a new kmod mwifiex_sdio, but i'm experiencing the same problem.

So my two ESP devices lost every day connection because the driver mwifiex_sdio crashed, impossible to unload the module mwifiex_sdio so the only solution for the moment is to reboot my wrtac3200acm.
Could someone knows if there is a workaround, i mean detect when the wlan2 is removed and reinstate it ?
I try restart the wifi from luci but unsucessfull.
I try wifi stop & wifi start, impossible.

Before crashing i see some Tx errors increasing with ifconfig on wlan2.

Loading...

Available keyboard shortcuts

Tasklist

Task Details

Task Editing