Re: Not work ATH (AR9285) after update

From: Andrey Fesenko <f0andrey_at_gmail.com>
Date: Tue, 23 Jul 2013 18:12:39 +0400
On Tue, Jul 23, 2013 at 5:13 PM, Adrian Chadd <adrian_at_freebsd.org> wrote:
> .. are two copies of wpa_supplicant running?
>
>
>
> -adrian
>
> On 23 July 2013 06:09, Andrey Fesenko <f0andrey_at_gmail.com> wrote:
>> Hello,
>> i'm horrible news, after update my notebook not nave wi-fi :(
>> full update system, build and install world and kernel.
>>
>> # uname -a
>> FreeBSD x220.local 10.0-CURRENT FreeBSD 10.0-CURRENT #0 r253562: Tue
>> Jul 23 14:44:07 MSK 2013
>> root_at_x220.local:/usr/obj/usr/src/sys/W_BOOK  amd64
>>
>> # grep ATH /usr/src/sys/amd64/conf/W_BOOK
>> options         ATH_ENABLE_11N
>> options         ATH_DEBUG
>> options         ATH_DIAGAPI
>>
>> ath0_at_pci0:3:0:0:        class=0x028000 card=0x1a891a3b chip=0x002b168c
>> rev=0x01 hdr=0x00
>>     vendor     = 'Atheros Communications Inc.'
>>     device     = 'AR9285 Wireless Network Adapter (PCI-Express)'
>>     class      = network
>>
>> # ifconfig
>> ath0: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 2290
>>         ether 00:25:d3:7b:94:87
>>         nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
>>         media: IEEE 802.11 Wireless Ethernet autoselect mode 11g
>>         status: associated
>> wlan0: flags=8c43<UP,BROADCAST,RUNNING,OACTIVE,SIMPLEX,MULTICAST>
>> metric 0 mtu 1500
>>         ether 00:25:d3:7b:94:87
>>         inet6 fe80::225:d3ff:fe7b:9487%wlan0 prefixlen 64 scopeid 0x4
>>         nd6 options=23<PERFORMNUD,ACCEPT_RTADV,AUTO_LINKLOCAL>
>>         media: IEEE 802.11 Wireless Ethernet autoselect (autoselect)
>>         status: no carrier
>>         ssid "" channel 8 (2447 MHz 11g)
>>         regdomain 33411 country RU indoor ecm authmode WPA1+WPA2/802.11i
>>         privacy MIXED deftxkey UNDEF txpower 20 bmiss 7 scanvalid 60
>>         protmode CTS wme burst roaming MANUAL
>> # ifconfig wlan0 list scan
>> SSID/MESH ID    BSSID              CHAN RATE   S:N     INT CAPS
>> APT-SPB2        b8:a3:86:4a:75:6a    6   54M -92:-96  100 EP   HTCAP
>> WPA RSN WME WPS
>> THD1   74:ea:3a:d1:37:46   10   54M -85:-96  100 EP   RSN HTCAP WME
>> Balt1   00:90:4c:c1:00:00    1   54M -90:-96  100 EP   RSN
>> TP-LINK_904D2A  64:70:02:90:4d:2a   11   54M -94:-96  100 EPS  RSN
>> HTCAP WME ATH WPS
>>
>> /var/log/messages
>> ...
>> Jul 23 15:34:24 x220 wpa_supplicant[669]: wlan0: CTRL-EVENT-TERMINATING
>> Jul 23 15:34:24 x220 kernel: wlan0: Ethernet address: 00:25:d3:7b:94:87
>> Jul 23 15:34:24 x220 devd: Executing '/etc/pccard_ether wlan0 start'
>> Jul 23 15:34:24 x220 wpa_supplicant[2563]: Successfully initialized
>> wpa_supplicant
>> Jul 23 15:34:24 x220 wpa_supplicant[2571]: Successfully initialized
>> wpa_supplicant
>> Jul 23 15:34:24 x220 wpa_supplicant[2571]: ctrl_iface exists and seems
>> to be in use - cannot override it
>> Jul 23 15:34:24 x220 wpa_supplicant[2571]: Delete
>> '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore
>> Jul 23 15:34:24 x220 wpa_supplicant[2571]: Failed to initialize
>> control interface '/var/run/wpa_supplicant'. You may have another
>> wpa_supplicant process already running or the file was left by an
>> unclean termination of wpa_supplicant in which case you will need to
>> manually remove this file before starting wpa_supplicant again.
>> Jul 23 15:34:24 x220 wpa_supplicant[2571]: ioctl[SIOCS80211, op=26,
>> val=0, arg_len=0]: Operation not supported
>> Jul 23 15:34:24 x220 wpa_supplicant[2571]: ioctl[SIOCS80211, op=26,
>> val=0, arg_len=0]: Operation not supported
>> Jul 23 15:34:24 x220 root: /etc/rc.d/wpa_supplicant: WARNING: failed
>> to start wpa_supplicant
>> Jul 23 15:34:24 x220 wpa_supplicant[2572]: ioctl[SIOCS80211, op=103,
>> val=0, arg_len=128]: Operation now in progress
>> Jul 23 15:34:24 x220 wpa_supplicant[2572]: wlan0: Failed to initiate AP scan
>> Jul 23 15:34:25 x220 wpa_supplicant[2572]: ioctl[SIOCS80211, op=103,
>> val=0, arg_len=128]: Operation now in progress
>> Jul 23 15:34:25 x220 wpa_supplicant[2572]: wlan0: Failed to initiate AP scan
>> Jul 23 15:34:26 x220 wpa_supplicant[2572]: ioctl[SIOCS80211, op=103,
>> val=0, arg_len=128]: Operation now in progress
>> Jul 23 15:34:26 x220 kernel: ath0: ath_reset_grablock: didn't finish
>> after 10 iterations
>> Jul 23 15:34:26 x220 kernel: ath0: ath_reset_grablock: warning,
>> recursive reset path!
>> Jul 23 15:34:26 x220 kernel: ath0: ath_reset: concurrent reset! Danger!
>> Jul 23 15:34:26 x220 kernel: ath0: ath_raw_xmit: sc_inreset_cnt > 0; bailing
>> Jul 23 15:34:26 x220 kernel: ath0: ath_raw_xmit: sc_inreset_cnt > 0; bailing
>> Jul 23 15:34:26 x220 wpa_supplicant[2572]: wlan0: Failed to initiate AP scan
>> Jul 23 15:34:27 x220 wpa_supplicant[2572]: ioctl[SIOCS80211, op=103,
>> val=0, arg_len=128]: Operation now in progress
>> Jul 23 15:34:27 x220 wpa_supplicant[2572]: wlan0: Failed to initiate AP scan
>> Jul 23 15:34:29 x220 wpa_supplicant[2572]: ioctl[SIOCS80211, op=103,
>> val=0, arg_len=128]: Operation now in progress
>> Jul 23 15:34:29 x220 kernel: ath0: ath_reset_grablock: didn't finish
>> after 10 iterations
>> Jul 23 15:34:29 x220 kernel: ath0: ath_reset_grablock: warning,
>> recursive reset path!

Not,
root_at_x220:~ # ps -A | grep wpa
 671  -  Ss   0:00.73 /usr/sbin/wpa_supplicant -s -B -i wlan0 -c /etc/wpa_suppl

Start, or restart work standart
# /etc/rc.d/netif restart
this scheme work fine ~year or half year.
Although of course the chip AR9285 is not very stable, especially in
combination with some AP.

Hm... intresting boot messages
...
Jul 23 17:47:06 x220 kernel: ath0: <Atheros 9285> mem
0xf2400000-0xf240ffff irq 17 at device 0.0 on pci3
Jul 23 17:47:06 x220 kernel: [ath] AR9285 Main LNA config: LNA1
Jul 23 17:47:06 x220 kernel: [ath] AR9285 Alt LNA config: LNA2
Jul 23 17:47:06 x220 kernel: [ath] LNA diversity disabled, Diversity disabled
Jul 23 17:47:06 x220 kernel: ath0: [HT] enabling HT modes
Jul 23 17:47:06 x220 kernel: ath0: [HT] 1 stream STBC receive enabled
Jul 23 17:47:06 x220 kernel: ath0: [HT] 1 RX streams; 1 TX streams
Jul 23 17:47:06 x220 kernel: ath0: AR9285 mac 192.2 RF5133 phy 14.0
Jul 23 17:47:06 x220 kernel: ath0: 2GHz radio: 0x0000; 5GHz radio: 0x00c0

old log
Jul  2 13:30:02 x220 kernel: ath0: <Atheros 9285> mem
0xf2400000-0xf240ffff irq 17 at device 0.0 on pci3
Jul  2 13:30:02 x220 kernel: ath0: [HT] enabling HT modes
Jul  2 13:30:02 x220 kernel: ath0: [HT] 1 stream STBC receive enabled
Jul  2 13:30:02 x220 kernel: ath0: [HT] 1 RX streams; 1 TX streams
Jul  2 13:30:02 x220 kernel: ath0: AR9285 mac 192.2 RF5133 phy 14.0
Jul  2 13:30:02 x220 kernel: ath0: 2GHz radio: 0x0000; 5GHz radio: 0x00c0

and old style reconnect
Jul  2 14:15:33 x220 wpa_supplicant[516]: CTRL-EVENT-DISCONNECTED
bssid=58:6d:8f:fa:d9:50 reason=0
Jul  2 14:15:33 x220 kernel: wlan0: link state changed to DOWN
Jul  2 14:15:35 x220 wpa_supplicant[516]: Trying to associate with
58:6d:8f:fa:d9:50 (SSID='hometest' freq=2472 MHz)
Jul  2 14:15:35 x220 wpa_supplicant[516]: Associated with 58:6d:8f:fa:d9:50
Jul  2 14:15:35 x220 kernel: wlan0: link state changed to UP
Jul  2 14:15:35 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 14:15:35 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 14:15:35 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 14:15:35 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 14:15:35 x220 wpa_supplicant[516]: WPA: Key negotiation
completed with 58:6d:8f:fa:d9:50 [PTK=CCMP GTK=CCMP]
Jul  2 14:15:35 x220 wpa_supplicant[516]: CTRL-EVENT-CONNECTED -
Connection to 58:6d:8f:fa:d9:50 completed (reauth) [id=0 id_str=]
Jul  2 14:15:35 x220 dhclient: New IP Address (wlan0): 192.168.1.22
Jul  2 14:15:35 x220 dhclient: New Subnet Mask (wlan0): 255.255.255.0
Jul  2 14:15:35 x220 dhclient: New Broadcast Address (wlan0): 192.168.1.255
Jul  2 14:15:35 x220 dhclient: New Routers (wlan0): 192.168.1.1
Jul  2 14:19:57 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 14:19:57 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 14:20:00 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 14:20:00 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 14:22:21 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 14:22:21 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 15:10:13 x220 wpa_supplicant[516]: WPA: Group rekeying
completed with 58:6d:8f:fa:d9:50 [GTK=CCMP]
Jul  2 15:10:13 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 15:10:13 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 15:12:00 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 15:12:00 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Jul  2 15:12:13 x220 kernel: ath0: ath_rate_tx_complete: ts_rate=27
ts_finaltsi=0
Jul  2 15:12:13 x220 kernel: ath0: bad series0 hwrate 0x1b, tries 1
ts_status 0x0
Received on Tue Jul 23 2013 - 12:12:41 UTC

This archive was generated by hypermail 2.4.0 : Wed May 19 2021 - 11:40:39 UTC