From owner-freebsd-stable@FreeBSD.ORG Sat Nov 22 17:17:38 2014 Return-Path: Delivered-To: stable@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [8.8.178.115]) (using TLSv1.2 with cipher AECDH-AES256-SHA (256/256 bits)) (No client certificate requested) by hub.freebsd.org (Postfix) with ESMTPS id D8D77413 for ; Sat, 22 Nov 2014 17:17:38 +0000 (UTC) Received: from mail-wg0-x232.google.com (mail-wg0-x232.google.com [IPv6:2a00:1450:400c:c00::232]) (using TLSv1 with cipher ECDHE-RSA-RC4-SHA (128/128 bits)) (Client CN "smtp.gmail.com", Issuer "Google Internet Authority G2" (verified OK)) by mx1.freebsd.org (Postfix) with ESMTPS id 88BD0A92 for ; Sat, 22 Nov 2014 17:17:38 +0000 (UTC) Received: by mail-wg0-f50.google.com with SMTP id k14so9253823wgh.37 for ; Sat, 22 Nov 2014 09:17:36 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=googlemail.com; s=20120113; h=sender:date:from:to:subject:message-id:mime-version:content-type :content-disposition; bh=PSB15MkeIbLsrB06Rmu80YTxtQKIw4xe1DyBujEzQB8=; b=UQGiwn9trlnssPry6Wm9E6C/QVNjVp+uStO1Z3kNjTDTnICz7ZuDom5f/gHUOxX+gv 5/eR0cBnHKxLUKfoN9kGmz0MkcvOl2GlxWG+IeECyndc3NSTu+Z/MwgavAF4hgki3Jq4 reKZZwHyNL2oxNwhuYWd3JKh1QLRSOfIOAWOiAXf5yNVGUMXHlgm18NvLTEq+P+YTYvE e2SZ8pv2kKIgUVpORIyxhp4meNuviCRJ4A9AkQgwwifF9mdZqxMCn3pgovS9cVv/G0Tx H7OeeXW2z4iYm+Z+iHsCGBcItEiYiDhHfApxNeBe8FSYjUgIgFc1BmTwoxTdFWkjXGV7 8DLQ== X-Received: by 10.180.211.108 with SMTP id nb12mr7265611wic.76.1416676656805; Sat, 22 Nov 2014 09:17:36 -0800 (PST) Received: from localhost (p5B2FC451.dip0.t-ipconnect.de. [91.47.196.81]) by mx.google.com with ESMTPSA id ge17sm4024163wic.0.2014.11.22.09.17.35 for (version=TLSv1.2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128); Sat, 22 Nov 2014 09:17:36 -0800 (PST) Sender: Thomas Zander Date: Sat, 22 Nov 2014 18:17:34 +0100 From: Thomas Zander To: stable@freebsd.org Subject: ath(4) device timeout -> reboot necessary Message-ID: <20141122171734.GA4600@marvin2011.fritz.box> MIME-Version: 1.0 Content-Type: text/plain; charset=us-ascii Content-Disposition: inline X-PGP-KeyID: 0xC85996CD X-PGP-URL: http://pgp.mit.edu:11371/pks/lookup?op=get&search=0x8DD48929C85996CD X-PGP-Fingerprint: 4F59 75B4 4CE3 3B00 BC61 5400 8DD4 8929 C859 96CD X-BeenThere: freebsd-stable@freebsd.org X-Mailman-Version: 2.1.18-1 Precedence: list List-Id: Production branch of FreeBSD source code List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 22 Nov 2014 17:17:38 -0000 Hi, regularly[1] I get those on my workstation: Nov 22 10:52:15 marvin2011 kernel: ath0: device timeout Nov 22 13:31:43 marvin2011 kernel: ath0: device timeout Nov 22 13:52:54 marvin2011 kernel: ath0: device timeout Nov 22 16:50:05 marvin2011 kernel: ath0: device timeout Weren't there logs, most of the time I would not even notice, but sometimes the machine loses connection to the AP, and when that happens, I first try to disable wpa_supplicant and restart the interface. Rarely[2] this works, but most of the time, this happens: Nov 22 16:53:37 marvin2011 kernel: wlan0: link state changed to DOWN Nov 22 16:53:37 marvin2011 dhclient[339]: connection closed Nov 22 16:53:37 marvin2011 dhclient[339]: exiting. Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Device not configured Nov 22 16:53:37 marvin2011 last message repeated 3 times Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=25, val=0, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=95, val=208, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=17, val=0, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=26, val=0, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=95, val=208, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=17, val=0, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=26, val=0, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: ioctl[SIOCS80211, op=16, val=1, arg_len=0]: Device not configured Nov 22 16:53:37 marvin2011 wpa_supplicant[327]: wlan0: CTRL-EVENT-TERMINATING Nov 22 16:53:37 marvin2011 riggs: /etc/rc.d/netif: WARNING: wlan0 does not exist. Skipped. Nov 22 16:54:27 marvin2011 kernel: wlan0: Ethernet address: Nov 22 16:54:27 marvin2011 devd: Executing '/etc/pccard_ether wlan0 start' Nov 22 16:54:27 marvin2011 wpa_supplicant[92697]: Successfully initialized wpa_supplicant Nov 22 16:54:27 marvin2011 wpa_supplicant[92712]: Successfully initialized wpa_supplicant Nov 22 16:54:27 marvin2011 dhclient[92732]: dhclient already running, pid: 92731. Nov 22 16:54:27 marvin2011 dhclient[92732]: exiting. Nov 22 16:54:27 marvin2011 riggs: /etc/rc.d/dhclient: WARNING: failed to start dhclient Nov 22 16:54:27 marvin2011 wpa_supplicant[92714]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Nov 22 16:54:27 marvin2011 kernel: ath0: ath_reset_grablock: didn't finish after 10 iterations Nov 22 16:54:27 marvin2011 kernel: ath0: ath_reset_grablock: warning, recursive reset path! Nov 22 16:54:27 marvin2011 kernel: ath0: oath_reset: concurrent reset! Danger! Nov 22 16:54:27 marvin2011 wpa_supplicant[92714]: wlan0: Failed to initiate AP scan Nov 22 16:54:28 marvin2011 wpa_supplicant[92713]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Nov 22 16:54:28 marvin2011 wpa_supplicant[92713]: wlan0: Failed to initiate AP scan Nov 22 16:54:29 marvin2011 wpa_supplicant[92714]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Nov 22 16:54:29 marvin2011 wpa_supplicant[92714]: wlan0: Failed to initiate AP scan After this, the console gets flooded with the same error messages from ath(4) (grablog, recursive reset, danger) and wpa_supplicant over and over. Machine is 10.1-STABLE amd64, this is the dmesg output from the wlan adapter: ath0: mem 0xf7c00000-0xf7c0ffff irq 16 at device 0.0 on pci4 ath0: [HT] enabling HT modes ath0: [HT] enabling short-GI in 20MHz mode ath0: [HT] 1 stream STBC receive enabled ath0: [HT] 1 stream STBC transmit enabled ath0: [HT] 2 RX streams; 2 TX streams ath0: AR9287 mac 384.2 RF5133 phy 15.15 ath0: 2GHz radio: 0x0000; 5GHz radio: 0x00c0 So far, I have been unable to find a solution other than rebooting the machine, and I am looking for better options. The favourite option, obviously, is a patch that resolves it, but recommending an adapter that just works[tm] out of the box on 10.1 is acceptable as well, as long as - it is PCIe, and - currently, as of today, available at one of the usual dealers in Germany, e.g. amazon.de, mindfactory, etc. Of course I am happy to do whatever I can to debug the problem, but in lack of expert knowledge on wlan drivers, I'd need some help here. Best regards Riggs [1] statistically about once every three hours [2] roughly in 10% of the cases