From mboxrd@z Thu Jan 1 00:00:00 1970 From: Harshal Vora Date: Sat, 29 Mar 2014 15:47:33 +0530 Subject: [ath9k-devel] Adhoc mode and chip reset issue with tp link wn721n/ath9k_htc drivers Message-ID: <53369DBD.4060706@amideeptech.com> List-Id: MIME-Version: 1.0 Content-Type: text/plain; charset="us-ascii" Content-Transfer-Encoding: 7bit To: ath9k-devel@lists.ath9k.org Hi, We are running tp link wn721n usb wifi adapter with atheros 9271 chipset on raspberry pi using kernel version 3.6.11 We run the physical interface wlan0 in adhoc mode and also create a virtual interface wlan0-mon0 from wlan0 which runs in monitor mode. The USB chipset suddenly hangs or shuts down after running for sometime. Sometimes it runs for few minutes, sometimes for hours before this happens. Sometimes this happens when a new node enters the adhoc network. We are using babel on top of ad-hoc network to create a mesh like functionality. Below are the logs from one of the device. Also when I try to unload the driver using modprobe -r, it looks like panic mode. We are not using power save mode. Mar 28 15:45:45 localhost kernel: [18268.650000] ath: phy0: Unable to reset channel (2452 Mhz) reset status -22 Mar 28 15:45:45 localhost kernel: [18268.670000] ath: phy0: Unable to set channel Mar 28 15:45:46 localhost kernel: [18269.180000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:45:46 localhost kernel: [18269.180000] ath: phy0: Chip reset failed Mar 28 15:45:46 localhost kernel: [18269.190000] ath: phy0: Unable to reset channel (2457 Mhz) reset status -22 Mar 28 15:45:46 localhost kernel: [18269.210000] ath: phy0: Unable to set channel Mar 28 15:45:46 localhost kernel: [18269.720000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:45:46 localhost kernel: [18269.720000] ath: phy0: Chip reset failed Mar 28 15:45:46 localhost kernel: [18269.730000] ath: phy0: Unable to reset channel (2462 Mhz) reset status -22 Mar 28 15:45:46 localhost kernel: [18269.750000] ath: phy0: Unable to set channel Mar 28 15:45:47 localhost kernel: [18270.250000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:45:47 localhost kernel: [18270.260000] ath: phy0: Chip reset failed Mar 28 15:45:47 localhost kernel: [18270.270000] ath: phy0: Unable to reset channel (2467 Mhz) reset status -22 Mar 28 15:45:47 localhost kernel: [18270.280000] ath: phy0: Unable to set channel Mar 28 15:45:47 localhost kernel: [18270.790000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:45:47 localhost kernel: [18270.800000] ath: phy0: Chip reset failed Mar 28 15:45:48 localhost kernel: [18270.810000] ath: phy0: Unable to reset channel (2472 Mhz) reset status -22 Mar 28 15:45:48 localhost kernel: [18270.820000] ath: phy0: Unable to set channel Mar 28 15:45:48 localhost kernel: [18271.330000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:45:48 localhost kernel: [18271.340000] ath: phy0: Chip reset failed Mar 28 15:45:48 localhost kernel: [18271.350000] ath: phy0: Unable to reset channel (2484 Mhz) reset status -22 Mar 28 15:45:48 localhost kernel: [18271.360000] ath: phy0: Unable to set channel Mar 28 15:45:49 localhost kernel: [18271.870000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:45:49 localhost kernel: [18271.880000] ath: phy0: Chip reset failed Mar 28 15:45:49 localhost kernel: [18271.890000] ath: phy0: Unable to reset channel (2412 Mhz) reset status -22 Mar 28 15:45:49 localhost kernel: [18271.900000] ath: phy0: Unable to set channel Mar 28 15:45:49 localhost kernel: [18272.080000] wlan1: expiring inactive STA c0:4a:00:18:db:79 Mar 28 15:45:49 localhost kernel: [18272.080000] wlan1: moving STA c0:4a:00:18:db:79 to state 3 Mar 28 15:45:49 localhost kernel: [18272.090000] wlan1: moving STA c0:4a:00:18:db:79 to state 2 Mar 28 15:45:49 localhost kernel: [18272.090000] ath: phy0: Unable to remove station entry for: c0:4a:00:18:db:79 Mar 28 15:45:49 localhost kernel: [18272.110000] wlan1: moving STA c0:4a:00:18:db:79 to state 1 Mar 28 15:45:49 localhost kernel: [18272.110000] ieee80211 phy0: Removed STA c0:4a:00:18:db:79 Mar 28 15:45:49 localhost kernel: [18272.120000] ieee80211 phy0: Destroyed STA c0:4a:00:18:db:79 Mar 28 15:45:49 localhost kernel: [18272.120000] wlan1: expiring inactive STA 10:fe:ed:13:f2:10 Mar 28 15:45:49 localhost kernel: [18272.130000] wlan1: moving STA 10:fe:ed:13:f2:10 to state 3 Mar 28 15:45:49 localhost kernel: [18272.130000] wlan1: moving STA 10:fe:ed:13:f2:10 to state 2 Mar 28 15:45:49 localhost kernel: [18272.140000] ath: phy0: Unable to remove station entry for: 10:fe:ed:13:f2:10 Mar 28 15:45:49 localhost kernel: [18272.150000] wlan1: moving STA 10:fe:ed:13:f2:10 to state 1 Mar 28 15:45:49 localhost kernel: [18272.160000] ieee80211 phy0: Removed STA 10:fe:ed:13:f2:10 Mar 28 15:45:49 localhost kernel: [18272.160000] ieee80211 phy0: Destroyed STA 10:fe:ed:13:f2:10 Mar 28 15:45:49 localhost kernel: [18272.170000] wlan1: expiring inactive STA 64:66:b3:18:c9:79 Mar 28 15:45:49 localhost kernel: [18272.170000] wlan1: moving STA 64:66:b3:18:c9:79 to state 3 Mar 28 15:45:49 localhost kernel: [18272.180000] wlan1: moving STA 64:66:b3:18:c9:79 to state 2 Mar 28 15:45:49 localhost kernel: [18272.180000] ath: phy0: Unable to remove station entry for: 64:66:b3:18:c9:79 Mar 28 15:45:49 localhost kernel: [18272.200000] wlan1: moving STA 64:66:b3:18:c9:79 to state 1 Mar 28 15:45:49 localhost kernel: [18272.200000] ieee80211 phy0: Removed STA 64:66:b3:18:c9:79 Mar 28 15:45:49 localhost kernel: [18272.210000] ieee80211 phy0: Destroyed STA 64:66:b3:18:c9:79 Mar 28 15:45:49 localhost kernel: [18272.210000] wlan1: expiring inactive STA 00:1f:3c:0b:f0:5c Mar 28 15:45:49 localhost kernel: [18272.220000] wlan1: moving STA 00:1f:3c:0b:f0:5c to state 3 Mar 28 15:45:49 localhost kernel: [18272.220000] wlan1: moving STA 00:1f:3c:0b:f0:5c to state 2 Mar 28 15:45:49 localhost kernel: [18272.230000] ath: phy0: Unable to remove station entry for: 00:1f:3c:0b:f0:5c Mar 28 15:45:49 localhost kernel: [18272.240000] wlan1: moving STA 00:1f:3c:0b:f0:5c to state 1 Mar 28 15:45:49 localhost kernel: [18272.250000] ieee80211 phy0: Removed STA 00:1f:3c:0b:f0:5c Mar 28 15:45:49 localhost kernel: [18272.250000] ieee80211 phy0: Destroyed STA 00:1f:3c:0b:f0:5c Mar 28 15:45:49 localhost kernel: [18272.260000] wlan1: expiring inactive STA 10:fe:ed:14:d5:c4 Mar 28 15:45:49 localhost kernel: [18272.260000] wlan1: moving STA 10:fe:ed:14:d5:c4 to state 3 Mar 28 15:45:49 localhost kernel: [18272.270000] wlan1: moving STA 10:fe:ed:14:d5:c4 to state 2 Mar 28 15:45:49 localhost kernel: [18272.270000] ath: phy0: Unable to remove station entry for: 10:fe:ed:14:d5:c4 Mar 28 15:45:49 localhost kernel: [18272.280000] wlan1: moving STA 10:fe:ed:14:d5:c4 to state 1 Mar 28 15:45:49 localhost kernel: [18272.290000] ieee80211 phy0: Removed STA 10:fe:ed:14:d5:c4 Mar 28 15:45:49 localhost kernel: [18272.290000] ieee80211 phy0: Destroyed STA 10:fe:ed:14:d5:c4 Mar 28 15:46:19 localhost kernel: [18302.080000] wlan1: No active IBSS STAs - trying to scan for other IBSS networks with same SSID (merge) Mar 28 15:46:19 localhost kernel: [18302.770000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:46:19 localhost kernel: [18302.780000] ath: phy0: Chip reset failed Mar 28 15:46:19 localhost kernel: [18302.780000] ath: phy0: Unable to reset channel (2412 Mhz) reset status -22 Mar 28 15:46:19 localhost kernel: [18302.800000] ath: phy0: Unable to set channel Mar 28 15:46:20 localhost kernel: [18303.310000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:46:20 localhost kernel: [18303.310000] ath: phy0: Chip reset failed Mar 28 15:46:20 localhost kernel: [18303.320000] ath: phy0: Unable to reset channel (2417 Mhz) reset status -22 Mar 28 15:46:20 localhost kernel: [18303.330000] ath: phy0: Unable to set channel Mar 28 15:46:20 localhost ntpdate[12205]: adjust time server 199.102.46.73 offset -0.001353 sec Mar 28 15:46:21 localhost kernel: [18303.850000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:46:21 localhost kernel: [18303.860000] ath: phy0: Chip reset failed Mar 28 15:46:21 localhost kernel: [18303.870000] ath: phy0: Unable to reset channel (2422 Mhz) reset status -22 Mar 28 15:46:21 localhost kernel: [18303.880000] ath: phy0: Unable to set channel Mar 28 15:46:21 localhost kernel: [18304.390000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 28 15:46:21 localhost kernel: [18304.400000] ath: phy0: Chip reset failed Logs after modprobe -r ath9k_htc Mar 29 07:05:21 localhost kernel: [73443.950000] ath: phy0: Failed to wakeup in 500us Mar 29 07:05:21 localhost kernel: [73443.960000] ------------[ cut here ]------------ Mar 29 07:05:21 localhost kernel: [73443.970000] WARNING: at drivers/net/wireless/ath/ath9k/hw.c:2079 ath9k_hw_setpower+0x1fc/0x600 [ath9k_hw]() Mar 29 07:05:21 localhost kernel: [73443.980000] Modules linked in: nfnetlink_log nfnetlink xt_state iptable_filter ipt_MASQUERADE iptable_nat nf_nat nf_conntrack_ipv4 nf_conntrack nf_defrag_ipv4 ip_tables x_tables ath9k_htc(-) mac80211 led_class ath9k_common ath9k_hw ath 8192cu Mar 29 07:05:21 localhost kernel: [73444.000000] Backtrace: Mar 29 07:05:21 localhost kernel: [73444.000000] [] (dump_backtrace+0x0/0x10c) from [] (dump_stack+0x18/0x1c) Mar 29 07:05:21 localhost kernel: [73444.010000] r6:bf0bad38 r5:00000009 r4:00000000 r3:c079a4e0 Mar 29 07:05:21 localhost kernel: [73444.010000] [] (dump_stack+0x0/0x1c) from [] (warn_slowpath_common+0x54/0x6c) Mar 29 07:05:21 localhost kernel: [73444.020000] [] (warn_slowpath_common+0x0/0x6c) from [] (warn_slowpath_null+0x24/0x2c) Mar 29 07:05:21 localhost kernel: [73444.030000] r8:0000704c r7:00020044 r6:00000000 r5:00000000 r4:bf10c339 Mar 29 07:05:21 localhost kernel: [73444.030000] r3:00000009 Mar 29 07:05:21 localhost kernel: [73444.040000] [] (warn_slowpath_null+0x0/0x2c) from [] (ath9k_hw_setpower+0x1fc/0x600 [ath9k_hw]) Mar 29 07:05:21 localhost kernel: [73444.050000] [] (ath9k_hw_setpower+0x0/0x600 [ath9k_hw]) from [] (ath9k_htc_ps_wakeup+0x4c/0x50 [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.060000] [] (ath9k_htc_ps_wakeup+0x0/0x50 [ath9k_htc]) from [] (ath9k_htc_configure_filter+0x78/0xd8 [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.070000] r5:ee7a18d8 r4:ee7a10a0 Mar 29 07:05:21 localhost kernel: [73444.070000] [] (ath9k_htc_configure_filter+0x0/0xd8 [ath9k_htc]) from [] (ieee80211_configure_filter+0x158/0x1b8 [mac80211]) Mar 29 07:05:21 localhost kernel: [73444.090000] r6:ee7a0300 r5:00000000 r4:00000000 r3:bf18d484 Mar 29 07:05:21 localhost kernel: [73444.090000] [] (ieee80211_configure_filter+0x0/0x1b8 [mac80211]) from [] (ieee80211_do_stop+0x174/0x658 [mac80211]) Mar 29 07:05:21 localhost kernel: [73444.100000] r8:00000001 r7:ed64a000 r6:ee7a0300 r5:00000004 r4:000001d0 Mar 29 07:05:21 localhost kernel: [73444.110000] [] (ieee80211_do_stop+0x0/0x658 [mac80211]) from [] (ieee80211_stop+0x18/0x20 [mac80211]) Mar 29 07:05:21 localhost kernel: [73444.120000] [] (ieee80211_stop+0x0/0x20 [mac80211]) from [] (__dev_close_many+0x90/0xd4) Mar 29 07:05:21 localhost kernel: [73444.130000] [] (__dev_close_many+0x0/0xd4) from [] (dev_close_many+0x8c/0xf8) Mar 29 07:05:21 localhost kernel: [73444.140000] r5:ed64bdc0 r4:ed64bd24 Mar 29 07:05:21 localhost kernel: [73444.140000] [] (dev_close_many+0x0/0xf8) from [] (rollback_registered_many+0xc4/0x24c) Mar 29 07:05:21 localhost kernel: [73444.150000] r6:ed64bdc0 r5:ee88c000 r4:ed64bd24 Mar 29 07:05:21 localhost kernel: [73444.150000] [] (rollback_registered_many+0x0/0x24c) from [] (unregister_netdevice_many+0x20/0x68) Mar 29 07:05:21 localhost kernel: [73444.160000] [] (unregister_netdevice_many+0x0/0x68) from [] (ieee80211_remove_interfaces+0x98/0xd8 [mac80211]) Mar 29 07:05:21 localhost kernel: [73444.170000] r4:ee7a0918 r3:00000001 Mar 29 07:05:21 localhost kernel: [73444.180000] [] (ieee80211_remove_interfaces+0x0/0xd8 [mac80211]) from [] (ieee80211_unregister_hw+0x48/0xec [mac80211]) Mar 29 07:05:21 localhost kernel: [73444.190000] [] (ieee80211_unregister_hw+0x0/0xec [mac80211]) from [] (ath9k_htc_disconnect_device+0x58/0x98 [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.200000] r5:ee5fcc00 r4:ee7a10a0 Mar 29 07:05:21 localhost kernel: [73444.200000] [] (ath9k_htc_disconnect_device+0x0/0x98 [ath9k_htc]) from [] (ath9k_htc_hw_deinit+0x18/0x1c [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.220000] r6:ef3ee468 r5:ef3e0220 r4:ee917540 r3:ee5c1080 Mar 29 07:05:21 localhost kernel: [73444.220000] [] (ath9k_htc_hw_deinit+0x0/0x1c [ath9k_htc]) from [] (ath9k_hif_usb_disconnect+0x58/0x12c [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.230000] [] (ath9k_hif_usb_disconnect+0x0/0x12c [ath9k_htc]) from [] (usb_unbind_interface+0x44/0x118) Mar 29 07:05:21 localhost kernel: [73444.240000] r8:c000f204 r7:ef3ee400 r6:ef3e0200 r5:bf198d3c r4:ef3e0220 Mar 29 07:05:21 localhost kernel: [73444.250000] [] (usb_unbind_interface+0x0/0x118) from [] (__device_release_driver+0x78/0xc8) Mar 29 07:05:21 localhost kernel: [73444.260000] r7:bf198d3c r6:ef3e0254 r5:bf198d3c r4:ef3e0220 Mar 29 07:05:21 localhost kernel: [73444.260000] [] (__device_release_driver+0x0/0xc8) from [] (driver_detach+0xe4/0xf0) Mar 29 07:05:21 localhost kernel: [73444.270000] r5:ed64a000 r4:ef3e0220 Mar 29 07:05:21 localhost kernel: [73444.270000] [] (driver_detach+0x0/0xf0) from [] (bus_remove_driver+0x94/0xfc) Mar 29 07:05:21 localhost kernel: [73444.280000] r7:00000080 r6:c07b7abc r5:bf198d3c r4:00000000 Mar 29 07:05:21 localhost kernel: [73444.290000] [] (bus_remove_driver+0x0/0xfc) from [] (driver_unregister+0x58/0x78) Mar 29 07:05:21 localhost kernel: [73444.300000] r6:ed64a000 r5:bf198d3c r4:00000000 r3:00000008 Mar 29 07:05:21 localhost kernel: [73444.300000] [] (driver_unregister+0x0/0x78) from [] (usb_deregister+0x64/0xf8) Mar 29 07:05:21 localhost kernel: [73444.310000] r5:bf198d0c r4:bf198d3c Mar 29 07:05:21 localhost kernel: [73444.310000] [] (usb_deregister+0x0/0xf8) from [] (ath9k_hif_usb_exit+0x14/0x1c [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.320000] r8:c000f204 r7:00000080 r6:ed64a000 r5:01ccc788 r4:bf199690 Mar 29 07:05:21 localhost kernel: [73444.330000] r3:bf1961c4 Mar 29 07:05:21 localhost kernel: [73444.330000] [] (ath9k_hif_usb_exit+0x0/0x1c [ath9k_htc]) from [] (ath9k_htc_exit+0x10/0x20 [ath9k_htc]) Mar 29 07:05:21 localhost kernel: [73444.340000] [] (ath9k_htc_exit+0x0/0x20 [ath9k_htc]) from [] (sys_delete_module+0x1e4/0x2a0) Mar 29 07:05:21 localhost kernel: [73444.350000] [] (sys_delete_module+0x0/0x2a0) from [] (ret_fast_syscall+0x0/0x30) Mar 29 07:05:21 localhost kernel: [73444.360000] r7:00000081 r6:00000001 r5:beda2a48 r4:01ccc538 Mar 29 07:05:21 localhost kernel: [73444.360000] ---[ end trace 6bff6510f53a402b ]--- Mar 29 07:05:21 localhost kernel: [73444.400000] ath: phy0: Failed to wakeup in 500us Mar 29 07:05:21 localhost kernel: [73444.410000] ath: phy0: Unable to remove interface at idx: 0 Mar 29 07:05:21 localhost kernel: [73444.460000] ath: phy0: Failed to wakeup in 500us Mar 29 07:05:21 localhost kernel: [73444.500000] ath: phy0: Failed to wakeup in 500us Mar 29 07:05:21 localhost kernel: [73444.540000] ath: phy0: Failed to wakeup in 500us Mar 29 07:05:22 localhost kernel: [73445.060000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 29 07:05:22 localhost kernel: [73445.570000] ath: phy0: timeout (100000 us) on reg 0x7000: 0xfffffffb & 0x00000003 != 0x00000000 Mar 29 07:05:22 localhost kernel: [73445.590000] device wlan1-mon0 left promiscuous mode