Skip site navigation (1)Skip section navigation (2)
Date:      Sat, 22 Nov 2014 18:17:34 +0100
From:      Thomas Zander <riggs@freebsd.org>
To:        stable@freebsd.org
Subject:   ath(4) device timeout -> reboot necessary
Message-ID:  <20141122171734.GA4600@marvin2011.fritz.box>

next in thread | raw e-mail | index | archive | help
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: <arp here>
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: <Atheros 9287> 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



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20141122171734.GA4600>