From owner-freebsd-wireless@FreeBSD.ORG Sun Jan 27 12:31:38 2013 Return-Path: Delivered-To: freebsd-wireless@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) by hub.freebsd.org (Postfix) with ESMTP id 005212BC for ; Sun, 27 Jan 2013 12:31:37 +0000 (UTC) (envelope-from vince@unsane.co.uk) Received: from unsane.co.uk (unsane-pt.tunnel.tserv5.lon1.ipv6.he.net [IPv6:2001:470:1f08:110::2]) by mx1.freebsd.org (Postfix) with ESMTP id 97347E18 for ; Sun, 27 Jan 2013 12:31:37 +0000 (UTC) Received: from vincemacbook.unsane.co.uk (vincemacbook.unsane.co.uk [10.10.10.20]) (authenticated bits=0) by unsane.co.uk (8.14.6/8.14.6) with ESMTP id r0RCVYrL005022 (version=TLSv1/SSLv3 cipher=DHE-RSA-CAMELLIA256-SHA bits=256 verify=NO) for ; Sun, 27 Jan 2013 12:31:35 GMT (envelope-from vince@unsane.co.uk) Message-ID: <51051E26.1050300@unsane.co.uk> Date: Sun, 27 Jan 2013 12:31:34 +0000 From: Vincent Hoffman User-Agent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.6; rv:17.0) Gecko/20130107 Thunderbird/17.0.2 MIME-Version: 1.0 To: "freebsd-wireless@freebsd.org" Subject: wireless link flaps (ath ar9280) X-Enigmail-Version: 1.5 Content-Type: text/plain; charset=ISO-8859-1 Content-Transfer-Encoding: 7bit X-BeenThere: freebsd-wireless@freebsd.org X-Mailman-Version: 2.1.14 Precedence: list List-Id: "Discussions of 802.11 stack, tools device driver development." List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sun, 27 Jan 2013 12:31:38 -0000 Hi all, I'm running a recent -current (r245741) and seem to be getting interface flaps. Since this machine is mainly used for home mail and a little light file serving I'm not sure when they started occuring as i only have logging going back to the 18th Jan. Sample log messages are: Jan 24 16:26:22 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 24 16:26:22 ostracod kernel: AR_CR=0x00000024 Jan 24 16:26:22 ostracod kernel: AR_DIAG_SW=0x42000020 Jan 24 16:26:23 ostracod wpa_supplicant[568]: CTRL-EVENT-DISCONNECTED bssid=e0:91:f5:48:5b:b9 reason=0 Jan 24 16:26:23 ostracod kernel: wlan0: link state changed to DOWN Jan 24 16:26:26 ostracod wpa_supplicant[568]: Trying to associate with e0:91:f5:48:5b:b9 (SSID='vhoff' freq=2462 MHz) Jan 24 16:26:26 ostracod wpa_supplicant[568]: Associated with e0:91:f5:48:5b:b9 Jan 24 16:26:26 ostracod kernel: wlan0: link state changed to UP Jan 24 16:26:27 ostracod wpa_supplicant[568]: WPA: Key negotiation completed with e0:91:f5:48:5b:b9 [PTK=CCMP GTK=TKIP] I sometime get just Jan 25 00:57:36 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 25 00:57:36 ostracod kernel: AR_CR=0x00000024 Jan 25 00:57:36 ostracod kernel: AR_DIAG_SW=0x42000020 or Jan 25 09:39:24 ostracod wpa_supplicant[568]: WPA: Group rekeying completed with e0:91:f5:48:5b:b9 [GTK=TKIP] Jan 25 09:41:13 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 25 09:41:13 ostracod kernel: AR_CR=0x00000024 Jan 25 09:41:13 ostracod kernel: AR_DIAG_SW=0x42000020 Jan 25 09:46:50 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 25 09:46:50 ostracod kernel: AR_CR=0x00000024 Jan 25 09:46:50 ostracod kernel: AR_DIAG_SW=0x42000020 Jan 25 09:49:24 ostracod wpa_supplicant[568]: WPA: Group rekeying completed with e0:91:f5:48:5b:b9 [GTK=TKIP] Jan 25 09:52:10 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 25 09:52:10 ostracod kernel: AR_CR=0x00000024 Jan 25 09:52:10 ostracod kernel: AR_DIAG_SW=0x42000020 Jan 25 09:57:07 ostracod kernel: ath0: bb hang detected (0x4), resetting or Jan 26 17:24:46 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 26 17:24:46 ostracod kernel: AR_CR=0x00000024 Jan 26 17:24:46 ostracod kernel: AR_DIAG_SW=0x42000020 Jan 26 17:25:31 ostracod kernel: ar5416PerCalibrationN: NF calibration didn't finish; delaying CCA with no interface flaps. And sometimes much more info Jan 25 16:56:27 ostracod kernel: ar5416StopDmaReceive: dma failed to stop in 10ms Jan 25 16:56:27 ostracod kernel: AR_CR=0x00000024 Jan 25 16:56:27 ostracod kernel: AR_DIAG_SW=0x42000020 Jan 25 16:56:28 ostracod wpa_supplicant[568]: CTRL-EVENT-DISCONNECTED bssid=e0:91:f5:48:5b:b9 reason=0 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_print: norm: node 0xffffff8014828000: bf=0xffffff80008b23e8: addbaw=1, dobaw=1, seqno=3447, retry=6 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_print: node 0xffffff8014828000: bf=0xffffff80008b23e8: txq[1] axq_depth=0, axq_aggr_depth=0 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_print: node 0xffffff8014828000: bf=0xffffff80008b23e8: tid txq_depth=10 hwq_depth=0, bar_wait=1, isfiltered=0 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_print: node 0xffffff8014828000: tid 0: sched=0, paused=1, incomp=0, baw_head=114, baw_tail=116 txa_start=3446, ni_txseqs=3456 Jan 25 16:56:28 ostracod kernel: TODS 00:24:23:07:fb:5d->e0:91:f5:48:5b:b9(e0:91:f5:48:5b:b9) data QoS [TID 0] WEP [IV b6 89 00 0c 00 00 KID 0] 0M Jan 25 16:56:28 ostracod kernel: 8849 0000 e091 f548 5bb9 0024 2307 fb5d e091 f548 5bb9 70d7 0000 dec0 b689 0020 0c00 0000 aaaa 0300 0000 0800 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3448 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3449 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3450 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3451 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3452 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3453 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3454 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_tid_drain_pkt: wasn't added: seqno 3455 Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008a9e08: seqno 3448: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008aa468: seqno 3449: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008c8a80: seqno 3450: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008cf080: seqno 3451: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008a8488: seqno 3452: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008a64a8: seqno 3453: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008aa930: seqno 3454: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: ath0: ath_tx_default_comp: bf 0xffffff80008a8ae8: seqno 3455: dobaw should've been cleared! Jan 25 16:56:28 ostracod kernel: wlan0: link state changed to DOWN Jan 25 16:56:39 ostracod wpa_supplicant[568]: Trying to associate with e0:91:f5:48:5b:b9 (SSID='vhoff' freq=2462 MHz) Jan 25 16:56:39 ostracod wpa_supplicant[568]: Associated with e0:91:f5:48:5b:b9 Jan 25 16:56:39 ostracod kernel: wlan0: link state changed to UP Jan 25 16:56:40 ostracod wpa_supplicant[568]: WPA: Key negotiation completed with e0:91:f5:48:5b:b9 [PTK=CCMP GTK=TKIP] Device is ath0: mem 0xfebf0000-0xfebfffff irq 16 at device 0.0 on pci4 ath0: [HT] enabling HT modes ath0: [HT] 2 RX streams; 1 TX streams ath0: AR9280 mac 128.2 RF5133 phy 13.0 ath0: 2GHz radio: 0x0000; 5GHz radio: 0x00c0 Any suggestions? I'm happy to provide more info if needed. Vince