Modify

Opened 4 years ago

Closed 4 years ago

Last modified 21 months ago

#10349 closed defect (fixed)

[AR7x11] wifi took very long time to associate in client mode

Reported by: sam.right@… Owned by: nbd
Priority: normal Milestone: Barrier Breaker 14.07
Component: base system Version: Trunk
Keywords: Cc: ndb

Description

I am running openwrt trunk r28738 with newly purchased TL-WR703N Version 1 (Atheros AR9330 rev 1). I was trying to setup client mode on TL-WR703N. The 11N wifi router comes with stock TP link firmware and I have no problems with it so far. It seems that it took the TL-WR703N more than 1 minute to associate.

Here is the wireless config on TL-WR703N

config wifi-device  radio0
	option type     mac80211
	option channel  auto
	option macaddr  5c:63:bf:9a:ee:12
	option hwmode	11gn

config wifi-iface
	option device   radio0
	option network  wifi
	option mode     sta
	option ssid     SSID
	option encryption psk2
	option key PASSWORD

Here is the output log from TL-WR703N. It looks like there are awful lot of DMA failure.

Jan  1 00:00:10 OpenWrt kern.info kernel: ar933x-uart: ttyATH0 at MMIO 0x18020000 (irq = 11) is a AR933X UART
Jan  1 00:00:10 OpenWrt kern.info kernel: console [ttyATH0] enabled, bootconsole disabled
Jan  1 00:00:10 OpenWrt kern.info kernel: Atheros AR71xx SPI Controller driver version 0.2.4
Jan  1 00:00:10 OpenWrt kern.warn kernel: m25p80 spi0.0: found s25sl032a, expected m25p80
Jan  1 00:00:10 OpenWrt kern.info kernel: m25p80 spi0.0: s25sl032a (4096 Kbytes)
Jan  1 00:00:10 OpenWrt kern.debug kernel: spi0.0: searching for MyLoader partition table at offset 0x10000
Jan  1 00:00:10 OpenWrt kern.debug kernel: spi0.0: searching for MyLoader partition table at offset 0x20000
Jan  1 00:00:10 OpenWrt kern.debug kernel: spi0.0: searching for MyLoader partition table at offset 0x30000
Jan  1 00:00:10 OpenWrt kern.debug kernel: spi0.0: searching for MyLoader partition table at offset 0x40000
Jan  1 00:00:10 OpenWrt kern.debug kernel: spi0.0: no MyLoader partition table found
Jan  1 00:00:10 OpenWrt kern.notice kernel: Searching for RedBoot partition table in spi0.0 at offset 0x3e0000
Jan  1 00:00:10 OpenWrt kern.notice kernel: Searching for RedBoot partition table in spi0.0 at offset 0x3f0000
Jan  1 00:00:10 OpenWrt kern.notice kernel: No RedBoot partition table detected in spi0.0
Jan  1 00:00:10 OpenWrt kern.notice kernel: spi0.0: no WRT160NL signature found
Jan  1 00:00:10 OpenWrt kern.notice kernel: Creating 5 MTD partitions on "spi0.0":
Jan  1 00:00:10 OpenWrt kern.notice kernel: 0x000000000000-0x000000020000 : "u-boot"
Jan  1 00:00:10 OpenWrt kern.notice kernel: 0x000000020000-0x000000120000 : "kernel"
Jan  1 00:00:10 OpenWrt kern.notice kernel: 0x000000120000-0x0000003f0000 : "rootfs"
Jan  1 00:00:10 OpenWrt kern.notice kernel: mtd: partition "rootfs" set to be root filesystem
Jan  1 00:00:10 OpenWrt kern.info kernel: mtd: partition "rootfs_data" created automatically, ofs=370000, len=80000 
Jan  1 00:00:10 OpenWrt kern.notice kernel: 0x000000370000-0x0000003f0000 : "rootfs_data"
Jan  1 00:00:10 OpenWrt kern.notice kernel: 0x0000003f0000-0x000000400000 : "art"
Jan  1 00:00:10 OpenWrt kern.notice kernel: 0x000000020000-0x0000003f0000 : "firmware"
Jan  1 00:00:10 OpenWrt kern.info kernel: ag71xx_mdio: probed
Jan  1 00:00:10 OpenWrt kern.info kernel: eth0: Atheros AG71xx at 0xb9000000, irq 4
Jan  1 00:00:10 OpenWrt kern.debug kernel: eth0: connected to PHY at ag71xx-mdio:04 [uid=004dd041, driver=Generic PHY]
Jan  1 00:00:10 OpenWrt kern.info kernel: Atheros AR71xx hardware watchdog driver version 0.1.0
Jan  1 00:00:10 OpenWrt kern.debug kernel: ar71xx-wdt: timeout=15 secs (max=171)
Jan  1 00:00:10 OpenWrt kern.info kernel: TCP westwood registered
Jan  1 00:00:10 OpenWrt kern.info kernel: NET: Registered protocol family 17
Jan  1 00:00:10 OpenWrt kern.info kernel: 802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
Jan  1 00:00:11 OpenWrt user.notice ifup: Enabling Router Solicitations on loopback (lo)
Jan  1 00:00:11 OpenWrt user.notice ifup: Enabling Router Solicitations on lan (eth0)
Jan  1 00:00:12 OpenWrt kern.info kernel: eth0: link up (100Mbps/Full duplex)
Jan  1 00:00:12 OpenWrt sysinit: sh: auto: bad number
Jan  1 00:00:14 OpenWrt sysinit: udhcpc (v1.19.3) started
Jan  1 00:00:14 OpenWrt crond[745]: crond: crond (busybox 1.19.3) started, log level 5
Jan  1 00:00:14 OpenWrt sysinit: Sending discover...
Jan  1 00:00:14 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:14 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:14 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:14 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:15 OpenWrt dnsmasq: DNS rebinding protection is active, will discard upstream RFC1918 responses!
Jan  1 00:00:15 OpenWrt dnsmasq: Allowing 127.0.0.0/8 responses
Jan  1 00:00:15 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:15 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:15 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:15 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:16 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:16 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:17 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:17 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:17 OpenWrt sysinit: Sending discover...
Jan  1 00:00:17 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:17 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:17 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:00:17 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:00:18 OpenWrt dnsmasq[800]: started, version 2.58 cachesize 150
Jan  1 00:00:18 OpenWrt dnsmasq[800]: compile time options: IPv6 GNU-getopt no-DBus no-i18n DHCP TFTP no-conntrack no-IDN
Jan  1 00:00:18 OpenWrt dnsmasq-dhcp[800]: DHCP, IP range 172.16.0.100 -- 172.16.0.249, lease time 12h
Jan  1 00:00:18 OpenWrt dnsmasq[800]: using local addresses only for domain lan
Jan  1 00:00:18 OpenWrt dnsmasq[800]: no servers found in /tmp/resolv.conf.auto, will retry
Jan  1 00:00:18 OpenWrt dnsmasq[800]: read /etc/hosts - 1 addresses
Jan  1 00:00:18 OpenWrt dnsmasq-dhcp[800]: read /etc/ethers - 0 addresses
Jan  1 00:00:20 OpenWrt sysinit: Sending discover...
Jan  1 00:00:22 OpenWrt kernel: ar71xx-wdt: enabling watchdog timer
Jan  1 00:00:22 OpenWrt kernel: ar71xx-wdt: timeout=60 secs
Jan  1 00:00:22 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:22 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:22 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:23 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:23 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:23 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:23 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:23 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:23 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:00:23 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:00:28 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:28 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:28 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:28 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:29 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:29 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:29 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:29 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:30 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:30 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:30 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:30 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:31 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:31 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:31 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:31 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:32 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:32 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:32 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:33 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:33 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:33 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:33 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:00:33 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:00:38 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:38 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:38 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:38 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:38 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:39 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:39 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:39 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:40 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:40 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:40 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:40 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:40 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:40 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:41 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:41 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:42 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:42 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:42 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:42 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:43 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:43 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:43 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:00:43 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:00:47 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:47 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:48 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:48 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:48 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:48 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:48 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:49 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:49 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:49 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:50 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:50 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:50 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:50 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:51 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:51 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:51 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00026e00
Jan  1 00:00:51 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:00:56 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:56 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:56 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00006400
Jan  1 00:00:56 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:00:56 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:56 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:56 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:56 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:57 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:57 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:57 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:57 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:58 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:58 OpenWrt kernel: wlan0: authenticated
Jan  1 00:00:58 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:00:58 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:00:59 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:00:59 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:00:59 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:00:59 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:00 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:00 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:01:00 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:01:01 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:01:01 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:01 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:01 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:01:01 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:01:06 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:06 OpenWrt kernel: wlan0: authenticated
Jan  1 00:01:06 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:06 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:01:06 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:01:06 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:01:07 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:07 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:08 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:08 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:01:08 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:01:08 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:01:09 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:09 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:09 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:01:09 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:01:14 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:14 OpenWrt kernel: wlan0: authenticated
Jan  1 00:01:14 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:14 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:01:14 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:01:14 OpenWrt kernel: wlan0: association with 00:23:cd:1d:7c:94 timed out
Jan  1 00:01:15 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:15 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:15 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:16 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 2)
Jan  1 00:01:16 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 3)
Jan  1 00:01:16 OpenWrt kernel: wlan0: authentication with 00:23:cd:1d:7c:94 timed out
Jan  1 00:01:17 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:17 OpenWrt kernel: ath: Failed to stop TX DMA, queues=0x001!
Jan  1 00:01:17 OpenWrt kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Jan  1 00:01:17 OpenWrt kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Jan  1 00:01:21 OpenWrt kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:21 OpenWrt kernel: wlan0: authenticated
Jan  1 00:01:21 OpenWrt kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Jan  1 00:01:21 OpenWrt kernel: wlan0: RX AssocResp from 00:23:cd:1d:7c:94 (capab=0x431 status=0 aid=7)
Jan  1 00:01:21 OpenWrt kernel: wlan0: associated

Attachments (1)

999-ath9k-sleep.path (363 bytes) - added by sam.right@… 4 years ago.
patch to revert ATH9K_PM_FULL_SLEEP

Download all attachments as: .zip

Change History (35)

comment:1 Changed 4 years ago by sam.right@…

couple of type:

The title should be changed to [AR71xx] and it should be cc to nbd.

comment:2 Changed 4 years ago by nbd

please try r28772

comment:3 Changed 4 years ago by sam.right@…

r28772 is still the same.

comment:4 Changed 4 years ago by sam.right@…

It looks like to be a regression on MAC80211. I revert it back from r28772 to r28294 and reflash the device. The client mode works instantly with MAC80211 r28294 and there is no more DMA failed stuff in the dmesg.

comment:5 Changed 4 years ago by nbd

Thanks, that makes it much easier to track down this issue. I'll let you know when I find something.

comment:6 Changed 4 years ago by nbd

Please try copying http://nbd.name/910-ath9k_disable_fastcc.patch to package/mac80211/patches and rebuilding.

comment:7 Changed 4 years ago by sam.right@…

@nbd

Tried the patch yesterday but still the same behaviour.

comment:8 Changed 4 years ago by nbd

  • Owner changed from developers to nbd
  • Status changed from new to accepted

please try the latest version to see if it improves things. so far i have been unable to reproduce such issues on any of my hardware, so i have to rely on your test feedback.

comment:9 Changed 4 years ago by john.smith.meiru

Nov 15 09:21:48 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:21:48 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Nov 15 09:21:48 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 09:21:49 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:21:53 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:21:54 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:21:54 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:21:54 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:21:54 OpenWrt kern.debug kernel: wlan0: authentication with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:21:54 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:21:54 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Nov 15 09:21:54 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 09:21:55 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:21:59 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:00 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:00 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:00 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:01 OpenWrt kern.debug kernel: wlan0: authentication with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:01 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:01 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Nov 15 09:22:01 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 09:22:01 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:06 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:06 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:06 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:06 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:06 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:07 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:07 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:07 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:08 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:08 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:08 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:08 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:08 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:09 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:09 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:09 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:09 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:10 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:10 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:10 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:10 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:10 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:11 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:11 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:11 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:12 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:12 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:12 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:12 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:12 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:13 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:13 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:13 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:14 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:14 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:14 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:14 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:14 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:15 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:15 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:15 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:16 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:16 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:16 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:16 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:16 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:16 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:16 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:17 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:18 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:18 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:18 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:18 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:18 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:18 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:18 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:19 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:20 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:20 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:20 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:20 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:20 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:20 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:20 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:21 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:21 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:21 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:21 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:22 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:22 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:22 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:22 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:23 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:23 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:23 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:23 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:24 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:24 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:24 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:24 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:25 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:25 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:25 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:26 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:26 OpenWrt kern.debug kernel: wlan0: authentication with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:26 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:26 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Nov 15 09:22:26 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 09:22:27 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:31 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:31 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:31 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:32 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:32 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:32 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:32 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:32 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:33 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:33 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:33 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:33 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:34 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:34 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:34 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:34 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:35 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:35 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:35 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:35 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:36 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:36 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:36 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:36 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:37 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:37 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:37 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:37 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:37 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:38 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:38 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:38 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:38 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:39 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:39 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:39 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:40 OpenWrt kern.debug kernel: wlan0: authentication with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:40 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:40 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Nov 15 09:22:40 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 09:22:40 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:45 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:45 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:45 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:45 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:46 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:46 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:46 OpenWrt kern.debug kernel: wlan0: association with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:46 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:47 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:47 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:47 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 2)
Nov 15 09:22:48 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 3)
Nov 15 09:22:48 OpenWrt kern.debug kernel: wlan0: authentication with 00:22:b0:d1:ce:d5 timed out
Nov 15 09:22:48 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:48 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00024e00
Nov 15 09:22:48 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 09:22:48 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:53 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 09:22:53 OpenWrt kern.debug kernel: wlan0: authenticate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:53 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 09:22:53 OpenWrt kern.debug kernel: wlan0: associate with 00:22:b0:d1:ce:d5 (try 1)
Nov 15 09:22:53 OpenWrt kern.debug kernel: wlan0: RX AssocResp from 00:22:b0:d1:ce:d5 (capab=0x431 status=0 aid=2)
Nov 15 09:22:53 OpenWrt kern.debug kernel: wlan0: associated
Nov 15 09:22:55 OpenWrt user.notice ifup: Allowing Router Advertisements on wwan (wlan0)
Nov 15 09:22:56 OpenWrt user.info firewall: adding wwan (wlan0) to zone wan
Nov 15 09:22:59 OpenWrt daemon.info dnsmasq[1194]: reading /tmp/resolv.conf.auto
Nov 15 09:22:59 OpenWrt daemon.info dnsmasq[1194]: using nameserver 192.168.0.1#53
Nov 15 09:22:59 OpenWrt daemon.info dnsmasq[1194]: using local addresses only for domain lan

I also have the same problem on my TL-WR703N and it still output these errors with the latest revision (29138).

comment:10 Changed 4 years ago by sam.right@…

@nbd

I ended up writing a script to build every single version of mac80211 from r28261 and test each version on TL-WR703N. I found that r28298 is the last solid working version and difference between and r28298 and r28300 is the full sleep mode ATH9K_PM_FULL_SLEEP introduced in function ath9k_ps_restore of file drivers/net/wireless/ath/ath9k/main.c.

I have also tested the very recent version of mac80211 (r28809) by putting the attached patch file into package/mac80211/patches/. This patch will revert ATH9K_PM_FULL_SLEEP to ATH9K_PM_NETWORK_SLEEP. I loaded this version on my TL-WR703N and it seems to associate the network in first try which is just like any version before r28300.

Looks like TL-WR703N does not support full sleep mode well.

I know it's really hard for you to test it without the actual hardware. AFAIK one WR703N was denoted to juhosg and could you ask juhosg if he/she can lend to you?

Changed 4 years ago by sam.right@…

patch to revert ATH9K_PM_FULL_SLEEP

comment:11 Changed 4 years ago by sam.right@…

@john

Could you try my patch against the version you are using (r29138). Just put the patch into package/mac80211/patches/ and rebuild firmware by
[CODE]
make package/mac80211/clean
make
CODE
Then refresh the firmware as usual

comment:12 Changed 4 years ago by sam.right@…

@nbd

Tried r29154 of mac80211 but did not make a difference. It's best to disable ATH9K_PM_FULL_SLEEP for now.

comment:13 Changed 4 years ago by nbd

Your comments about ATH9K_PM_FULL_SLEEP were very helpful. It seems that the device simply needs a reset to recover from full sleep, however the code only does a fast channel change unless something hangs.
I'll rework the code so that it keeps track of power mode changes and issues a reset where necessary, this should hopefully fix this issue for good.

comment:14 Changed 4 years ago by nbd

please try this patch: http://nbd.name/ath9k-test.patch

comment:15 Changed 4 years ago by anonymous

With the latest patch I still sometimes get this DMA error but the association seems to went through on first try!!!

Nov 15 14:33:42 OpenWrt kern.info kernel: ath9k: Driver unloaded
Nov 15 14:33:42 OpenWrt kern.info kernel: Compat-wireless backport release: compat-wireless-2011-10-14
Nov 15 14:33:42 OpenWrt kern.info kernel: Backport based on wireless-testing.git master-2011-11-04
Nov 15 14:33:42 OpenWrt kern.info kernel: cfg80211: Calling CRDA to update world regulatory domain
Nov 15 14:33:45 OpenWrt kern.debug kernel: ath: EEPROM regdomain: 0x0
Nov 15 14:33:45 OpenWrt kern.debug kernel: ath: EEPROM indicates default country code should be used
Nov 15 14:33:45 OpenWrt kern.debug kernel: ath: doing EEPROM country->regdmn map search
Nov 15 14:33:45 OpenWrt kern.debug kernel: ath: country maps to regdmn code: 0x3a
Nov 15 14:33:45 OpenWrt kern.debug kernel: ath: Country alpha2 being used: US
Nov 15 14:33:45 OpenWrt kern.debug kernel: ath: Regpair used: 0x3a
Nov 15 14:33:45 OpenWrt kern.debug kernel: ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Nov 15 14:33:45 OpenWrt kern.debug kernel: Registered led device: ath9k-phy0
Nov 15 14:33:45 OpenWrt kern.info kernel: ieee80211 phy0: Atheros AR9330 Rev:1 mem=0xb8100000, irq=2
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211: World regulatory domain updated:
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211:     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211:     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:46 OpenWrt kern.info kernel: cfg80211: Calling CRDA for country: US
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211: Regulatory domain changed to country: US
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 1700 mBm)
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (5250000 KHz - 5330000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (5490000 KHz - 5600000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (5650000 KHz - 5710000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
Nov 15 14:33:47 OpenWrt kern.info kernel: cfg80211:     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 3000 mBm)
Nov 15 14:33:49 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 14:33:49 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00006400
Nov 15 14:33:49 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up
Nov 15 14:33:49 OpenWrt kern.debug kernel: wlan0: authenticate with 00:23:cd:1d:7c:94 (try 1)
Nov 15 14:33:49 OpenWrt kern.debug kernel: wlan0: authenticated
Nov 15 14:33:49 OpenWrt kern.debug kernel: wlan0: associate with 00:23:cd:1d:7c:94 (try 1)
Nov 15 14:33:49 OpenWrt kern.debug kernel: wlan0: RX AssocResp from 00:23:cd:1d:7c:94 (capab=0x431 status=0 aid=1)

comment:16 Changed 4 years ago by nbd

  • Resolution set to fixed
  • Status changed from accepted to closed

thanks for testing, committed in r29155

comment:17 Changed 4 years ago by sam.right@…

@nbd

Do I need to worry anything about the DMA error like this?

Nov 15 14:33:49 OpenWrt kern.err kernel: ath: Failed to stop TX DMA, queues=0x001!
Nov 15 14:33:49 OpenWrt kern.err kernel: ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x00006400
Nov 15 14:33:49 OpenWrt kern.err kernel: ath: Could not stop RX, we could be confusing the DMA engine when we start RX up

comment:18 Changed 4 years ago by nbd

is this new?

comment:19 Changed 4 years ago by sam.right@…

@nbd

Yes.

If I revert it back to disable ATH9K_PM_FULL_SLEEP then I won't see the DMA error at all.

comment:20 Changed 4 years ago by nbd

ok, i guess there are still some corner cases left to handle. i'll let you know when i have more patches to test. this issue affects more than just ar9330 and simply disabling full sleep is not acceptable for submission to linux-wireless, so i want to get this fixed properly.

comment:21 Changed 4 years ago by sam.right@…

@nbd

I did a bit digging and noticed that even with my ATH9K_PM_FULL_SLEEP disable patch I am still getting random DMA errors on anything later than r29116, i.e. the DMA errors is likely due to r29132.

comment:23 Changed 4 years ago by sam.right@…

@nbd

Just to let you know that the patch did not make a difference.

comment:25 Changed 4 years ago by sam.right@…

Still the same.

comment:26 Changed 4 years ago by anonymous

Latest revision (29164) has fixed it for me, I see no more DMA errors in log. Thanks.

comment:27 Changed 4 years ago by anonymous

I've got the same problem. Is there going to be a firmware file I can download to get it working without having to compile it myself?

I can't get wifi connected in client mode:

ath: Failed to stop TX DMA, queues=0x001!
ath: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x02000020 DMADBG_7=0x000 24e00
ath: Could not stop RX, we could be confusing the DMA engine when we start RX up

Thanks

comment:28 Changed 4 years ago by sam.right@…

You can try an early version of openwrt firmware posted on this thread. https://forum.openwrt.org/viewtopic.php?id=31729

If you don't need 3G or firewall support you can try mine posted here https://forum.openwrt.org/viewtopic.php?pid=148385#p148385

This uses a quite recent trunk version of openwrt with an early version of mac80211 which does not have problems with client connection. The advantage of this firmware is that wifi is pre-configured.

comment:29 Changed 4 years ago by anonymous

@sam, okay thanks :)

My problem is not that it takes too much to connect in client mode but it won't connect at all. So if revision 29164 fixes it (without affecting power consumption) I will either wait till a new snapshot is published (how long does it normally take?) or I'll compile it myself.

AP mode works fine.

comment:30 Changed 4 years ago by sam.right@…

The time it took to connect to ap before the fix varies. I have experienced more than 10 minutes for it to able to connect to the network. I am sure the fix would fix that issue for your. Just wait couple of days for the snapshot build to be available.

comment:31 Changed 4 years ago by anonymous

@sam. Thanks, I've just flashed the snapshot released today and i set some configuration wrong and now i can't telnet my WR703N anymore. I can get it into failsafe with led blinking very fast but it won't accept connections, actually i can't see any hosts up on 192.168.1.0/24. Too bad.. any suggestions, please?

comment:32 Changed 4 years ago by anonymous

You will need to manually set your pc's ip address between 192.168.1.2 and 192.168.1.254 with netmask 255.255.255.0.

You can follow the wiki page here: http://wiki.openwrt.org/doc/howto/generic.failsafe

comment:33 Changed 4 years ago by anonymous

It won't work since the snapshot from 18th November made the Ethernet not working...

comment:34 Changed 21 months ago by jow

  • Milestone changed from Attitude Adjustment 12.09 to Barrier Breaker 14.07

Milestone Attitude Adjustment 12.09 deleted

Add Comment

Modify Ticket

Action
as closed .
The resolution will be deleted. Next status will be 'reopened'.
Author


E-mail address and user name can be saved in the Preferences.

 
Note: See TracTickets for help on using tickets.