Date: Fri, 3 Feb 2012 01:22:28 +0400 (MSK) From: Lev Serebryakov <lev@FreeBSD.org> To: FreeBSD-gnats-submit@FreeBSD.org Subject: kern/164721: ath device timeouts Message-ID: <20120202212228.766844AC37@onlyone.friendlyhosting.spb.ru> Resent-Message-ID: <201202022130.q12LUB93072450@freefall.freebsd.org>
next in thread | raw e-mail | index | archive | help
>Number: 164721
>Category: kern
>Synopsis: ath device timeouts
>Confidential: no
>Severity: serious
>Priority: medium
>Responsible: freebsd-bugs
>State: open
>Quarter:
>Keywords:
>Date-Required:
>Class: sw-bug
>Submitter-Id: current-users
>Arrival-Date: Thu Feb 02 21:30:11 UTC 2012
>Closed-Date:
>Last-Modified:
>Originator: Lev Serebryakov <lev@freebsd.org>
>Release: FreeBSD 10.0-CURRENT i386
>Organization:
>Environment:
System: FreeBSD gateway.home.serebryakov.spb.ru 10.0-CURRENT FreeBSD 10.0-CURRENT #1: Wed Jan 11 21:07:34 MSK 2012 root@vmware-c-32.home.serebryakov.spb.ru:/usr/obj/nanobsd.gateway-net5501/usr/src/sys/NET5501 i386
ath0: <Atheros 9220> mem 0xa0060000-0xa006ffff irq 15 at device 17.0 on pci0
ath0: [HT] enabling HT modes
ath0: [HT] 2 RX streams; 2 TX streams
ath0: AR9220 mac 128.2 RF5133 phy 13.0
ath0: 2GHz radio: 0x0000; 5GHz radio: 0x00c0
ath0@pci0:0:17:0: class=0x028000 card=0x2091168c chip=0x0029168c rev=0x01 hdr=0x00
vendor = 'Atheros Communications Inc.'
device = 'AR922X Wireless Network Adapter'
class = network
ath0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 2290
ether f4:ec:38:a3:10:6d
nd6 options=29<PERFORMNUD,IFDISABLED,AUTO_LINKLOCAL>
media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng <hostap>
status: running
wlan0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
ether f4:ec:38:a3:10:6d
inet 192.168.135.1 netmask 0xffffff00 broadcast 192.168.135.255 vhid 5
inet6 fe80::f6ec:38ff:fea3:106d%wlan0 prefixlen 64 scopeid 0xc vhid 5
inet6 2001:470:923f:2::1 prefixlen 64 vhid 5
nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
media: IEEE 802.11 Wireless Ethernet autoselect mode 11ng <hostap>
status: running
ssid home.serebryakov.spb.ru channel 9 (2452 MHz 11g ht/20) bssid f4:ec:38:a3:10:6d
regdomain ROW country RU indoor ecm authmode WPA2/802.11i -wps -tsn
privacy MIXED deftxkey 3
AES-CCM 2:128-bit
AES-CCM 3:128-bit powersavemode OFF powersavesleep 100 txpower 30
txpowmax 50.0 -dotd rtsthreshold 2346 fragthreshold 2346 bmiss 7
11a ucast NONE mgmt 6 Mb/s mcast 6 Mb/s maxretry 6
11b ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
11g ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
turboA ucast NONE mgmt 6 Mb/s mcast 6 Mb/s maxretry 6
turboG ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
sturbo ucast NONE mgmt 6 Mb/s mcast 6 Mb/s maxretry 6
11na ucast NONE mgmt 12 MCS mcast 12 MCS maxretry 6
11ng ucast NONE mgmt 2 MCS mcast 2 MCS maxretry 6
half ucast NONE mgmt 3 Mb/s mcast 3 Mb/s maxretry 6
quarter ucast NONE mgmt 1 Mb/s mcast 1 Mb/s maxretry 6
scanvalid 60 -bgscan bgscanintvl 300 bgscanidle 250
roam:11a rssi 7dBm rate 12 Mb/s
roam:11b rssi 7dBm rate 1 Mb/s
roam:11g rssi 7dBm rate 5 Mb/s
roam:turboA rssi 7dBm rate 12 Mb/s
roam:turboG rssi 7dBm rate 12 Mb/s
roam:sturbo rssi 7dBm rate 12 Mb/s
roam:11na rssi 7dBm MCS 1
roam:11ng rssi 7dBm MCS 1
roam:half rssi 7dBm rate 6 Mb/s
roam:quarter rssi 7dBm rate 3 Mb/s
-pureg protmode CTS ht htcompat -ampdutx ampdurx ampdulimit 64k
ampdudensity 8 amsdu shortgi htprotmode RTSCTS -puren -smps -rifs wme
burst -dwds -hidessid apbridge dtimperiod 1 doth -dfs inact
bintval 100
AC_BE cwmin 4 cwmax 6 aifs 3 txopLimit 0 -acm ack
cwmin 4 cwmax 10 aifs 3 txopLimit 0 -acm
AC_BK cwmin 4 cwmax 10 aifs 7 txopLimit 0 -acm ack
cwmin 4 cwmax 10 aifs 7 txopLimit 0 -acm
AC_VI cwmin 3 cwmax 4 aifs 1 txopLimit 94 -acm ack
cwmin 3 cwmax 4 aifs 2 txopLimit 94 -acm
AC_VO cwmin 2 cwmax 3 aifs 1 txopLimit 47 -acm ack
cwmin 2 cwmax 3 aifs 2 txopLimit 47 -acm
groups: wlan
>Description:
Sometimes ath0 gives tiemout when transmitting to 802.11g client.
The higher is speed the higher is tiemouts frequency.
When environment is noisy, speed is low and timeouts is rare. When environment is clean, speed is high (up to 2.5MiB/s) but timeouts are frequent.
Here is output of `dmesg' when reset debug is enabled.
ath0: stuck beacon; resetting (bmiss count 4)
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x212fb40, link 0
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0x20beb40, link 0
ar5212StopDmaReceive: dma failed to stop in 10ms
AR_CR=0x00000024
AR_DIAG_SW=0x42000020
ath_stoprecv: rx queue 0x1b96480, link 0xcdb96420
ath0: stuck beacon; resetting (bmiss count 4)
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20bc780, link 0xd58c6420
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0x20fe3c0, link 0
ath_stoprecv: rx queue 0x1b90660, link 0xcdb90600
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20c63c0, link 0xd58b5060
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b94d20, link 0xcdb94c00
R[ 0] (DS.V:0xcdb94c60 DS.P:0x1b94c60) L:01b94cc0 D:18d33000 !
aa55aa55 00000800 1b800005 0000013aath0: ath_start: sc_inreset_cnt > 0; bailing
008b0a6d 00000100 06808080 80808080 80808080 80808080 00000005
R[ 1] (DS.V:0xcdb94cc0 DS.P:0x1b94cc0) L:01b94d20 D:137c6000 *
aa55aa55 00000800 1b800b10 00000031
008b3700 00000100 11808080 80808080 80808080 80808080 00000003
ath0: ath_raw_xmit: sc_inreset_cnt > 0; bailing
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20b5000, link 0xd58c57e0
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b977e0, link 0xcdb976c0
R[ 0] (DS.V:0xcdb97720 DS.P:0x1b97720) L:01b97780 D:08942000 *
aa55aa55 00000800 1b800a0c 00000112
004cddcf 00000100 0e808080 80808080 80808080 80808080 00000003
R[ 1] (DS.V:0xcdb97780 DS.P:0x1b97780) L:01b977e0 D:136f1000 *
aa55aa55 00000800 1b800304 000000a2
004cf785 00000100 06808080 80808080 80808080 80808080 00000003
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20c5780, link 0xd58b2060
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b8e600, link 0xcdb8e540
R[ 0] (DS.V:0xcdb8e5a0 DS.P:0x1b8e5a0) L:01b8e600 D:13706000 !
aa55aa55 00000800 1b800f0a 0000008e
004d8f1e 00000100 11808080 80808080 80808080 80808080 00000611
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20b2000, link 0xd58cb420
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b922a0, link 0xcdb92180
R[ 0] (DS.V:0xcdb921e0 DS.P:0x1b921e0) L:01b92240 D:18ced000 *
aa55aa55 00000800 1b80060e 00000112
006b3c79 00000100 0f808080 80808080 80808080 80808080 00000003
R[ 1] (DS.V:0xcdb92240 DS.P:0x1b92240) L:01b922a0 D:136e0800 *
aa55aa55 00000800 1b800306 000000a2
006b536b 00000100 08808080 80808080 80808080 80808080 00000003
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20cb3c0, link 0xd58c9b40
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b95420, link 0xcdb95360
R[ 0] (DS.V:0xcdb953c0 DS.P:0x1b953c0) L:01b95420 D:18d1c000 *
aa55aa55 00000800 1b80040a 00000112
007aafb5 00000100 0b808080 80808080 80808080 80808080 00000003
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20c9b40, link 0xd592f420
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0x20ca780, link 0
ath_stoprecv: rx queue 0x1b98e40, link 0xcdb98de0
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x212f3c0, link 0xd592ab40
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0x20bcb40, link 0
ath_stoprecv: rx queue 0x1b95420, link 0xcdb95360
R[ 0] (DS.V:0xcdb953c0 DS.P:0x1b953c0) L:01b95420 D:18c5a000 *
aa55aa55 00000800 1b80090e 00000112
01227240 00000100 0f808080 80808080 80808080 80808080 00000003
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x212ab40, link 0xd58cf780
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b99540, link 0xcdb99480
R[ 0] (DS.V:0xcdb994e0 DS.P:0x1b994e0) L:01b99540 D:1374a000 *
aa55aa55 00000800 1b80070c 00000112
00a885b6 00000100 0d808080 80808080 80808080 80808080 00000003
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20cf780, link 0xd58e2000
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b95180, link 0xcdb95060
R[ 0] (DS.V:0xcdb950c0 DS.P:0x1b950c0) L:01b95120 D:17100000 *
aa55aa55 00000800 1b80110c 00000147
01045026 00000100 12808080 80808080 80808080 80808080 00000003
R[ 1] (DS.V:0xcdb95120 DS.P:0x1b95120) L:01b95180 D:18cbf800 !
aa55aa55 00000800 1b80070a 0000006a
01045e67 00000100 0c808080 80808080 80808080 80808080 00000005
ath0: ath_tx_tid_drain: node 0xc4d8b000: tid 16: txq_depth=28, txq_aggr_depth=0, sched=0, paused=0, hwq_depth=0, incomp=0, baw_head=0, baw_tail=0 txa_start=-1, ni_txseqs=11
FRDS f4:ec:38:a3:10:6d->00:18:de:08:e8:1d(f4:ec:38:a3:10:6d) data WEP [IV 7c 99 00 05 00 00 KID 0] 0M
0842 3a01 0018 de08 e81d f4ec 38a3 106d f4ec 38a3 106d 6000 7c99 0020 0500 0000 aaaa 0300 0000 888e
ath0: device timeout
ath0: ath_reset: called
ath0: ath_stoptxdma: tx queue [9] 0x1b9b000, link 0
ath0: ath_tx_stopdma: tx queue [0] 0, link 0
ath0: ath_tx_stopdma: tx queue [1] 0x20e2000, link 0xd58baba0
ath0: ath_tx_stopdma: tx queue [2] 0, link 0
ath0: ath_tx_stopdma: tx queue [3] 0, link 0
ath0: ath_tx_stopdma: tx queue [8] 0, link 0
ath_stoprecv: rx queue 0x1b98840, link 0xcdb987e0
>How-To-Repeat:
Use FreeBSD with ath driver in HostAP mode, transfer some files to client.
>Fix:
>Release-Note:
>Audit-Trail:
>Unformatted:
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20120202212228.766844AC37>