Bug 544497 - hostapd does not work in F12 with ath9k driver
Summary: hostapd does not work in F12 with ath9k driver
Keywords:
Status: CLOSED CURRENTRELEASE
Alias: None
Product: Fedora
Classification: Fedora
Component: kernel
Version: 12
Hardware: i686
OS: Linux
low
high
Target Milestone: ---
Assignee: Kernel Maintainer List
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2009-12-05 02:28 UTC by Jaiv
Modified: 2011-05-14 16:08 UTC (History)
12 users (show)

Fixed In Version: kernel-2.6.32.8-58.fc12
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2010-05-04 14:57:34 UTC
Type: ---
Embargoed:


Attachments (Terms of Use)
Fix TX status reporting in ath9k (1.28 KB, patch)
2009-12-07 19:56 UTC, Jouni Malinen
no flags Details | Diff

Description Jaiv 2009-12-05 02:28:12 UTC
Description of problem:

I have network card D-Link DWA-552 which uses ath9k driver. I tried hostapd trunk version as well as 0.7.0 which are working fine with F11 kernel, but with F12 kernel I immediately experienced DHCP problems and wifi clients were disconnected after about 2-5 minutes after the handshake was established (and did not reconnect afterwards). 

Version-Release number of selected component (if applicable):

Here are the kernel versions I tried with hostapd 0.7.0 and with latest git as well:

kernel-PAE-2.6.30.9-99.fc11.i686 - working
kernel-PAE-2.6.31.5-127.fc12.i686 - not working
kernel-PAE-2.6.31.6-145.fc12.i686 - not working
kernel-PAE-2.6.31.6-160.fc12.i686 - not working
kernel-PAE-2.6.32-0.65.rc8.git5.fc13.i686 - not working

How reproducible:
always

Steps to Reproduce:
1. Use F12 kernels (or later)
2.
3.
  
Actual results:
unstable wifi connection

Expected results:
stable wifi connection

Additional info:

Logs when using kernel-PAE-2.6.31.6-160.fc12.i686:
-----------------------------
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT
mgmt::auth
authentication: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=1
status_code=0 wep=0
  New STA
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authentication OK (open system)
wlan0: STA xx:xx:xx:xx:xx:xx MLME:
MLME-AUTHENTICATE.indication(xx:xx:xx:xx:xx:xx, OPEN_SYSTEM)
wlan0: STA xx:xx:xx:xx:xx:xx MLME: MLME-DELETEKEYS.request(xx:xx:xx:xx:xx:xx)
authentication reply: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=2
resp=0 (IE len=0)
MGMT (TX callback) fail
mgmt::auth cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge authentication
response
MGMT
mgmt::assoc_req
association request: STA=xx:xx:xx:xx:xx:xx capab_info=0x411 listen_interval=10
WMM IE - hexdump(len=7): 00 50 f2 02 00 01 00
Validating WMM IE: OUI 00:50:f2  OUI type 2  OUI sub-type 0  version 1  QoS
info 0x0
nl80211: Set beacon (beacon_set=1)
  new AID 1
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: association OK (aid 1)
MGMT (TX callback) fail
mgmt::assoc_resp cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge association
response
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
MGMT (TX callback) fail
mgmt::proberesp cb
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
MGMT (TX callback) fail
mgmt::proberesp cb
-----------------------------
It also fired a bug:

BUG: unable to handle kernel paging request at 080b5f80
IP: [<080b5f80>] 0x80b5f80
*pdpt = 000000002593c001 *pde = 0000000025926067 *pte = 0000000000000000
Oops: 0010 [#1] SMP
last sysfs file: /sys/devices/pci0000:00/0000:00:08.0/net/wlan0/broadcast
Modules linked in: ath9k mac80211 ath cfg80211 rfkill fuse via drm sunrpc
padlock_aes aes_i586 aes_generic nf_nat_ftp nf_conntrack_ftp xt_pkttype
xt_limit ipt_LOG iptable_mangle iptable_nat nf_nat ip6t_REJECT
nf_conntrack_ipv6 ip6table_filter ip6_tables ipv6 cpufreq_ondemand acpi_cpufreq
dm_multipath uinput arc4 ecb snd_via82xx gameport snd_ac97_codec ac97_bus
snd_seq snd_pcm snd_timer snd_page_alloc snd_mpu401_uart snd_rawmidi
snd_seq_device i2c_viapro snd via_rhine soundcore i2c_core firewire_ohci
firewire_core crc_itu_t r8169 mii raid0 raid1 ata_generic pata_acpi pata_via
[last unloaded: rfkill]
Pid: 3573, comm: bluetooth-apple Not tainted (2.6.31.6-160.fc12.i686.PAE #1) 
EIP: 0060:[<080b5f80>] EFLAGS: 00010246 CPU: 0
EIP is at 0x80b5f80
EAX: e59f9680 EBX: e59f9680 ECX: f88fe658 EDX: 00000000
ESI: 00000000 EDI: e5965e74 EBP: e5965f8c ESP: e5965be4
DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
Process bluetooth-apple (pid: 3573, ti=e5964000 task=e58572c0 task.ti=e5964000)
Stack:
c04d4ee9 00001000 00080097 00080097 e5965ec4 09ce9df0 00000000 00c48740
 00000000 00000000 00000000 00000001 e5965ebc c04d4818 00000019 00000000
 e58572c0 00000000 00000000 00000009 e59cf980 00000019 00000000 e5965c18
Call Trace:
[<c04d4ee9>] ? do_sys_poll+0x213/0x3c8
[<c04d4818>] ? __pollwait+0x0/0xaa
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c04d48c2>] ? pollwake+0x0/0x65
[<c056b979>] ? avc_has_perm+0x41/0x4b
[<c0755c09>] ? scm_recv+0x32/0xa1
[<c0756967>] ? unix_stream_recvmsg+0x391/0x3b0
[<c056be05>] ? selinux_socket_recvmsg+0x1a/0x1c
[<c06e37e2>] ? __sock_recvmsg+0x56/0x60
[<c06e5680>] ? sock_aio_read+0xac/0xb8
[<c04c89e2>] ? do_sync_read+0xae/0xe9
[<c056cffd>] ? file_has_perm+0x89/0xa3
[<c0450d39>] ? autoremove_wake_function+0x0/0x34
[<c056d2ed>] ? selinux_file_permission+0x49/0x4d
[<c0566617>] ? security_file_permission+0x14/0x16
[<c04c8aba>] ? rw_verify_area+0x9d/0xc0
[<c04d51df>] ? sys_poll+0x44/0x8d
[<c0477ce0>] ? audit_syscall_exit+0xff/0x114
[<c0408f9b>] ? sysenter_do_call+0x12/0x28
Code:  Bad EIP value.
EIP: [<080b5f80>] 0x80b5f80 SS:ESP 0068:e5965be4
CR2: 00000000080b5f80

--------------------------------
Other details:

#modprobe ath9k
#dmesg -c
cfg80211: Calling CRDA to update world regulatory domain
cfg80211: World regulatory domain updated:
    (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
    (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
    (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
    (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
    (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
    (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
ath9k 0000:00:08.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
ath: EEPROM regdomain: 0x10
ath: EEPROM indicates we should expect a direct regpair map
ath: Country alpha2 being used: CO
ath: Regpair used: 0x10
phy0: Selected rate control algorithm 'ath9k_rate_control'
cfg80211: Calling CRDA for country: CO
Registered led device: ath9k-phy0::radio
Registered led device: ath9k-phy0::assoc
Registered led device: ath9k-phy0::tx
Registered led device: ath9k-phy0::rx
phy0: Atheros AR5416 MAC/BB Rev:2 AR2133 RF Rev:81: mem=0xf8160000, irq=17
cfg80211: Regulatory domain changed to country: CO
    (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
    (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2700 mBm)
    (5170000 KHz - 5250000 KHz @ 20000 KHz), (300 mBi, 1700 mBm)
    (5250000 KHz - 5330000 KHz @ 20000 KHz), (300 mBi, 2300 mBm)
    (5735000 KHz - 5835000 KHz @ 20000 KHz), (300 mBi, 3000 mBm)
cfg80211: Calling CRDA for country: GB
cfg80211: Regulatory domain changed to country: GB
    (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
    (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm)
    (5170000 KHz - 5250000 KHz @ 40000 KHz), (N/A, 2000 mBm)
    (5250000 KHz - 5330000 KHz @ 40000 KHz), (N/A, 2000 mBm)
    (5490000 KHz - 5710000 KHz @ 40000 KHz), (N/A, 2700 mBm)
ADDRCONF(NETDEV_UP): wlan0: link is not ready
#  
----------------------------------------------
logs when using rawhide kernel-PAE-2.6.32-0.65.rc8.git5.fc13.i686:

# uname -a
Linux thsw 2.6.32-0.65.rc8.git5.fc13.i686.PAE #1 SMP Thu Dec 3 01:14:42 EST
2009 i686 i686 i386 GNU/Linux
# /usr/local/bin/hostapd -ddd -K /etc/hostapd.conf
Starting hostapd: Configuration file: /etc/hostapd.conf
ctrl_interface_group=10 (from group name 'wheel')
eapol_version=2
nl80211: Add own interface ifindex 10
nl80211: Add own interface ifindex 13
BSS count 1, BSSID mask 00:00:00:00:00:00 (0 bits)
nl80211: Added 802.11b mode based on 802.11g information
Allowed channel: mode=1 chan=1 freq=2412 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=2 freq=2417 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=3 freq=2422 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=4 freq=2427 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=5 freq=2432 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=6 freq=2437 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=7 freq=2442 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=8 freq=2447 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=9 freq=2452 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=10 freq=2457 MHz max_tx_power=20 dBm
Allowed channel: mode=1 chan=11 freq=2462 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=1 freq=2412 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=2 freq=2417 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=3 freq=2422 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=4 freq=2427 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=5 freq=2432 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=6 freq=2437 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=7 freq=2442 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=8 freq=2447 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=9 freq=2452 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=10 freq=2457 MHz max_tx_power=20 dBm
Allowed channel: mode=0 chan=11 freq=2462 MHz max_tx_power=20 dBm
RATE[0] rate=10 flags=0x2
RATE[1] rate=20 flags=0x6
RATE[2] rate=55 flags=0x6
RATE[3] rate=110 flags=0x6
RATE[4] rate=60 flags=0x0
RATE[5] rate=90 flags=0x0
RATE[6] rate=120 flags=0x0
RATE[7] rate=180 flags=0x0
RATE[8] rate=240 flags=0x0
RATE[9] rate=360 flags=0x0
RATE[10] rate=480 flags=0x0
RATE[11] rate=540 flags=0x0
Completing interface initialization
Mode: IEEE 802.11g  Channel: 3  Frequency: 2422 MHz
Flushing old station entries
Deauthenticate all stations
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=(nil) key_idx=0 set_tx=1
seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=(nil) key_idx=1 set_tx=0
seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=(nil) key_idx=2 set_tx=0
seq_len=0 key_len=0
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=(nil) key_idx=3 set_tx=0
seq_len=0 key_len=0
Using interface wlan0 with hwaddr hh:hh:hh:hh:hh:hh and ssid '....'
WPA: group state machine entering state GTK_INIT (VLAN-ID 0)
GMK - hexdump(len=32): 9c 37 07 d1 4e b6 cf f8 cf d5 7d e5 0c 4d 49 7b c7 09 e5
7a 82 09 03 71 15 08 e1 da 16 ef d7 a8
GTK - hexdump(len=16): dd 5c 3c f5 60 1a f9 17 77 27 52 a6 93 8a c4 92
WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
wpa_driver_nl80211_set_key: ifindex=10 alg=3 addr=(nil) key_idx=1 set_tx=1
seq_len=0 key_len=16
nl80211: Set beacon (beacon_set=0)
wlan0: Setup of interface done.
MGMT (TX callback) ACK

STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT (TX callback) fail
mgmt::proberesp cb
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT
mgmt::auth
authentication: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=1
status_code=0 wep=0
  New STA
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authentication OK (open system)
wlan0: STA xx:xx:xx:xx:xx:xx MLME:
MLME-AUTHENTICATE.indication(xx:xx:xx:xx:xx:xx, OPEN_SYSTEM)
wlan0: STA xx:xx:xx:xx:xx:xx MLME: MLME-DELETEKEYS.request(xx:xx:xx:xx:xx:xx)
authentication reply: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=2
resp=0 (IE len=0)
MGMT (TX callback) fail
mgmt::auth cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge authentication
response
MGMT
mgmt::assoc_req
association request: STA=xx:xx:xx:xx:xx:xx capab_info=0x411 listen_interval=10
WMM IE - hexdump(len=7): 00 50 f2 02 00 01 00
Validating WMM IE: OUI 00:50:f2  OUI type 2  OUI sub-type 0  version 1  QoS
info 0x0
  new AID 1

nl80211: Set beacon (beacon_set=1)
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: association OK (aid 1)
MGMT (TX callback) fail
mgmt::assoc_resp cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge association
response
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT (TX callback) fail
mgmt::proberesp cb
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT
mgmt::deauth
deauthentication: STA=xx:xx:xx:xx:xx:xx reason_code=1
AP-STA-DISCONNECTED xx:xx:xx:xx:xx:xx
wlan0: STA xx:xx:xx:xx:xx:xx WPA: event 3 notification
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=0x9593768 key_idx=0 set_tx=1
seq_len=0 key_len=0
   addr=xx:xx:xx:xx:xx:xx
WPA: xx:xx:xx:xx:xx:xx WPA_PTK entering state DISCONNECTED
WPA: xx:xx:xx:xx:xx:xx WPA_PTK entering state INITIALIZE
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=0x9593768 key_idx=0 set_tx=1
seq_len=0 key_len=0
   addr=xx:xx:xx:xx:xx:xx
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.1X: unauthorizing port
Could not set station xx:xx:xx:xx:xx:xx flags for kernel driver (errno=22).
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: deauthenticated
wlan0: STA xx:xx:xx:xx:xx:xx MLME:
MLME-DEAUTHENTICATE.indication(xx:xx:xx:xx:xx:xx, 1)

wlan0: STA xx:xx:xx:xx:xx:xx MLME: MLME-DELETEKEYS.request(xx:xx:xx:xx:xx:xx)
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=0x9593768 key_idx=0 set_tx=1
seq_len=0 key_len=0
   addr=xx:xx:xx:xx:xx:xx

nl80211: Set beacon (beacon_set=1)
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT (TX callback) fail
mgmt::proberesp cb
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT
mgmt::auth
authentication: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=1
status_code=0 wep=0
  New STA
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authentication OK (open system)
wlan0: STA xx:xx:xx:xx:xx:xx MLME:
MLME-AUTHENTICATE.indication(xx:xx:xx:xx:xx:xx, OPEN_SYSTEM)
wlan0: STA xx:xx:xx:xx:xx:xx MLME: MLME-DELETEKEYS.request(xx:xx:xx:xx:xx:xx)
authentication reply: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=2
resp=0 (IE len=0)
MGMT (TX callback) fail
mgmt::auth cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge authentication
response
MGMT
mgmt::assoc_req
association request: STA=xx:xx:xx:xx:xx:xx capab_info=0x411 listen_interval=10
WMM IE - hexdump(len=7): 00 50 f2 02 00 01 00
Validating WMM IE: OUI 00:50:f2  OUI type 2  OUI sub-type 0  version 1  QoS
info 0x0
  new AID 1

nl80211: Set beacon (beacon_set=1)
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: association OK (aid 1)
MGMT (TX callback) fail
mgmt::assoc_resp cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge association
response
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
STA xx:xx:xx:xx:xx:xx sent probe request for our SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT
mgmt::auth
authentication: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=1
status_code=0 wep=0
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authentication OK (open system)
wlan0: STA xx:xx:xx:xx:xx:xx WPA: event 0 notification
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=0x9593de8 key_idx=0 set_tx=1
seq_len=0 key_len=0
   addr=xx:xx:xx:xx:xx:xx

wlan0: STA xx:xx:xx:xx:xx:xx MLME:
MLME-AUTHENTICATE.indication(xx:xx:xx:xx:xx:xx, OPEN_SYSTEM)
wlan0: STA xx:xx:xx:xx:xx:xx MLME: MLME-DELETEKEYS.request(xx:xx:xx:xx:xx:xx)
wpa_driver_nl80211_set_key: ifindex=10 alg=0 addr=0x9593de8 key_idx=0 set_tx=1
seq_len=0 key_len=0
   addr=xx:xx:xx:xx:xx:xx

authentication reply: STA=xx:xx:xx:xx:xx:xx auth_alg=0 auth_transaction=2
resp=0 (IE len=0)
MGMT (TX callback) fail
mgmt::auth cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge authentication
response
MGMT
mgmt::assoc_req
association request: STA=xx:xx:xx:xx:xx:xx capab_info=0x411 listen_interval=10
WMM IE - hexdump(len=7): 00 50 f2 02 00 01 00
Validating WMM IE: OUI 00:50:f2  OUI type 2  OUI sub-type 0  version 1  QoS
info 0x0
  old AID 1

wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: association OK (aid 1)
MGMT (TX callback) fail
mgmt::assoc_resp cb
wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: did not acknowledge association
response
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
MGMT (TX callback) fail
mgmt::proberesp cb
MGMT (TX callback) fail
mgmt::proberesp cb
STA yy:yy:yy:yy:yy:yy sent probe request for broadcast SSID
MGMT (TX callback) fail
mgmt::proberesp cb

Comment 1 Jouni Malinen 2009-12-07 19:56:34 UTC
Created attachment 376765 [details]
Fix TX status reporting in ath9k

Comment 2 Jouni Malinen 2009-12-07 19:59:56 UTC
The patch that I just attached here is expected to resolve the issue with ath9k not reporting the TX status correctly and as such, making hostapd not allow stations to complete association in some cases. I'm not sure whether this is related to the part of getting disconnected after 2-5 minutes, but this will hopefully handle the not being able reconnect part which is clearly visible in the debug log from hostapd here.

Comment 3 Radoslaw Kujawa 2009-12-07 23:23:50 UTC
Hi, I can confirm that after applying this patch, it is possible to connect. However, connection is still very unstable, and does not last more than 2 minutes in my case.

Comment 4 Juergen Northe 2009-12-09 23:22:54 UTC
I had exact the same problems before. 

But now I have to confirm, that my FC 12 with the recent compat-wireless-2009-12-08 (which includes the patch mentioned above) is working - stable @54MBit !

Hardware: Mainboard Intel Atom N270 (2.6.31.6-162.fc12.i686 -no PAE!!!)
Wireless NIC:  Sparklan WPEA-110N MINI PCI Express (AR9280) ath9k
hostapd: 0.7.0

/etc/hostapd/hostapd.conf
interface=wlan1
bridge=br0
driver=nl80211
logger_syslog=-1
logger_syslog_level=2
logger_stdout=-1
logger_stdout_level=2
ssid=XXXXXXXXXXXX
wpa=2
wpa_psk_file=/etc/hostapd/hostapd.wpa_psk
wpa_key_mgmt=WPA-PSK
wpa_pairwise=CCMP
wpa_group_rekey=600
wpa_gmk_rekey=86400
channel=1
macaddr_acl=0
hw_mode=g
country_code=DE
eapol_key_index_workaround=0
eap_server=0
ieee80211n=1
ht_capab=[HT40+][SHORT-GI-40][DSSS_CCK-40]

cat /etc/sysconfig/network-scripts/ifcfg-br0
DEVICE=br0
TYPE=Bridge
BOOTPROTO=static
IPADDR=172.21.254.254
NETMASK=255.255.0.0
ONBOOT=yes
DELAY=0
STP=no
DNS=172.21.254.254
DOMAIN=intra.acme.com

I suppose that the usage of the PAE kernel plus the missing patch was the reason why the wireless network was unstable. If shorewall is what you're using as firewall script, do not forget to add 'dhcp' as 'options' for the 'br0' interface in /etc/shorewall/interfaces. This will help your dhcpd server to answer the requests of the clients ;-)

Well done!  Thank you very much !

Comment 5 Martin Decky 2009-12-13 12:16:21 UTC
I can confirm all of the previous observations on my own hardware. To sum up:

1) The unmodified kernel-PAE-2.6.31.6-166.fc12.i686 (or any other kernel from Fedora 12 for that matter) breaks hostapd (the error message is "did not acknowledge association response"). All the 2.6.30 PAE kernels from Fedora 11 seem to work fine.

2) Applying the patch from comment #1 to kernel-PAE-2.6.31.6-166.fc12.i686 allows the hostapd to complete station association. However, the communication is very unstable and generally dies after 2 - 3 minutes when the association is no longer possible (no specific error message can be found in the logs).

3) Compiling compat-wireless-2.6 modules (2009-12-11) against kernel-PAE-2.6.31.6-166.fc12.i686 helps even more, but the communication is still very unstable (the data rate generally drops to 1 Mb even for devices four feet away from the ath9k; sometimes the connection dies out completely). Again, nothing much interesting in can be found in the logs.

4) The only combination which gives perfect performance and a rock-solid stability is compat-wireless-2.6 (2009-12-11) compiled against a non-PAE kernel-2.6.31.6-166.fc12.i686.


The fact that the PAE kernel somehow contributes to the problem is really strange. I have just spent several hours analysing the changes between kernel-PAE-2.6.30.9-99.fc11.i686 and kernel-PAE-2.6.31.6-166.fc12.i686, but I haven't found anything which could lead me to the root cause of the bug. Are there any kernel hackers who are willing to look into that? Many thanks.

168c:0029 Network controller: Atheros Communications Inc. AR922X Wireless Network Adapter (rev 01)

Comment 6 Juergen Northe 2009-12-14 23:22:43 UTC
It took me serveral hours to find out that the only working kernel is the one *without* PAE. 
Interessting is that a `tcpdump -n -v -i <wlanX>` on the server side spit out the ARP and the broadcasts requests of the client. Dhcp reqeusts where not answered by the server. I suppose its a problem within the tcp conversation / handshake / ACK.

Hmm... I can not figure out whats the different between a PAE and a non PAE kernel causing such a rude behaviour..

Comment 7 George Shearer 2009-12-28 18:53:56 UTC
I am still experiencing this issue with the latest 2.6.31.9-174 kernel on x86_64.

hostapd: wlan0: STA 00:21:5c:39:a3:99 IEEE 802.11: did not acknowledge authentication response

Nothing but those in my hostapd log.

However if I build & install the latest compat-wireless, it seems to be fine.

seems we just have to wait for the developers/maintainers to get back from their holiday travels and push the latest fixes into the F12 kernel release.

Comment 8 Jaiv 2010-01-25 22:19:33 UTC
I'm still experiencing this issue with latest Fedora 12 kernel-PAE-2.6.31.12-174.2.3.fc12.i686 and Hostapd 0.7.1

Hostapd logs:
Jan 25 07:46:59 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: did not acknowledge authentication response
Jan 25 07:47:38 localhost hostapd: wlan0: STA 00:34:3b:ba:b5:f2 IEEE 802.11: did not acknowledge authentication response
Jan 25 07:47:48 localhost hostapd: wlan0: STA 00:34:3b:ba:b5:f2 IEEE 802.11: disassociated
Jan 25 07:47:49 localhost hostapd: wlan0: STA 00:34:3b:ba:b5:f2 IEEE 802.11: deauthenticated due to inactivity
Jan 25 07:47:51 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: did not acknowledge authentication response
Jan 25 07:48:00 localhost hostapd: wlan0: STA 00:34:3b:ba:b5:f2 IEEE 802.11: did not acknowledge authentication response
Jan 25 07:48:02 localhost hostapd: wlan0: STA 00:34:3b:ba:b5:f2 IEEE 802.11: disassociated
Jan 25 07:48:03 localhost hostapd: wlan0: STA 00:34:3b:ba:b5:f2 IEEE 802.11: deauthenticated due to inactivity
Jan 25 07:48:05 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: did not acknowledge authentication response
Jan 25 07:48:12 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: disassociated
Jan 25 07:48:13 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: deauthenticated due to inactivity
Jan 25 07:48:14 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: did not acknowledge authentication response
Jan 25 07:49:14 localhost hostapd: wlan0: STA 00:14:de:2c:94:84 IEEE 802.11: did not acknowledge authentication response

On the other hand kernel-PAE-2.6.30.10-105.fc11.i686 from Fedora 11 with Hostapd 0.7.1 is working fine.

It seems to be a long holiday...

Comment 9 George Shearer 2010-02-05 20:43:47 UTC
I'm testing 2.6.32.7-37.fc12.x86_64.. So far, my clients are connecting.. If you don't see a further post from me within a few days, assume it's happy. :)

Comment 10 Juergen Northe 2010-02-05 23:34:00 UTC
Hi George,
it sounds great. It is not a PAE kernel, isn't it? Which version of hostapd do you use - I suppose the latest one.
I read about improvements supporting wireless network cards in oncoming kernel versions (>2.6.31). So your result might be the first proof of it ;-)
Well done !

Comment 11 Jaiv 2010-02-09 23:45:22 UTC
Hi guys,

I tried to compile below versions of compat-wireless (from http://linuxwireless.org/en/users/Download/stable/) with below kernels and hostapd 0.7.1:

- kernel-PAE-2.6.30.10-105.fc11.i686 + compat-wireless-2.6.30 - works fine

- kernel-PAE-2.6.31.12-174.2.3.fc12.i686 + compat-wireless-2.6.31-rc7 - I managed to compile with some difficulties (to resolve I copied used driver-select from 2.6.32) so in final compilation of ath9k was successful but I got into the same problem:

"IEEE 802.11: did not acknowledge authentication response"

So does not look good for me. If anybody wants me to try any other kernel and/or wireless or hostapd version please let me know.

Comment 12 George Shearer 2010-02-10 00:39:31 UTC
Just to confirm my findings:

System: Intel Atom 330
Distribution: Fedora 12 "off the shelf" plus all updates.
Kernel: kernel-2.6.32.7-37.fc12.x86_64 (from updates-testing repo)
Application: hostapd-0.6.9-7.fc12.x86_64

19:36:37 up 4 days,  3:55,  1 user,  load average: 0.01, 0.02, 0.03

Over 4 days since I rebooted on that kernel, things are working perfectly.

-G

Comment 13 George Shearer 2010-02-12 22:21:38 UTC
------------[ cut here ]------------
WARNING: at net/mac80211/rx.c:2491 ieee80211_rx+0xe9/0x5c9 [mac80211]()
Hardware name: MS-7418
Modules linked in: vfat fat cls_basic iptable_mangle xt_TCPMSS xt_multiport ipt_MASQUERADE nf_nat_sip nf_nat_irc nf_nat_ftp nf_conntrack_sip nf_conntrack_irc nf_conntrack_h323 nf_conntrack_ftp cls_u32 act_mirred em_cmp sch_ingress sch_tbf sch_prio sch_sfq ifb tun cryptd aes_x86_64 aes_generic p4_clockmod freq_table speedstep_lib bridge 8021q garp stp llc ipt_LOG iptable_nat nf_nat arc4 ecb snd_hda_codec_realtek snd_hda_intel ath9k snd_hda_codec mac80211 snd_hwdep snd_seq snd_seq_device iTCO_wdt ath iTCO_vendor_support snd_pcm serio_raw i2c_i801 cfg80211 snd_timer rfkill snd r8169 soundcore mii snd_page_alloc ext2 usb_storage i915 drm_kms_helper drm i2c_algo_bit i2c_core video output [last unloaded: freq_table]
Pid: 0, comm: swapper Tainted: G        W  2.6.32.7-37.fc12.x86_64 #1
Call Trace:
 <IRQ>  [<ffffffff81056338>] warn_slowpath_common+0x7c/0x94
 [<ffffffff81056364>] warn_slowpath_null+0x14/0x16
 [<ffffffffa01ad998>] ieee80211_rx+0xe9/0x5c9 [mac80211]
 [<ffffffff8105d425>] ? local_bh_enable_ip+0xe/0x10
 [<ffffffff814555fe>] ? _spin_unlock_bh+0x15/0x17
 [<ffffffffa02123a2>] ath_rx_send_to_mac80211+0xc4/0xd3 [ath9k]
 [<ffffffffa0213010>] ath_rx_tasklet+0x755/0x7a7 [ath9k]
 [<ffffffffa0210c21>] ath9k_tasklet+0x58/0xca [ath9k]
 [<ffffffffa01fcbcb>] ? ath9k_hw_set_interrupts+0x213/0x29e [ath9k]
 [<ffffffff8105bf6b>] tasklet_action+0x85/0xe4
 [<ffffffffa0211704>] ? ath_isr+0x137/0x14d [ath9k]
 [<ffffffff8105d954>] __do_softirq+0xe5/0x1a9
 [<ffffffff81027e76>] ? apic_write+0x16/0x18
 [<ffffffff81012e2c>] call_softirq+0x1c/0x30
 [<ffffffff810143aa>] do_softirq+0x46/0x86
 [<ffffffff8105d792>] irq_exit+0x3b/0x7d
 [<ffffffff814594dd>] do_IRQ+0xa5/0xbc
 [<ffffffff81012653>] ret_from_intr+0x0/0x11
 <EOI>  [<ffffffff81019154>] ? mwait_idle+0x7a/0x88
 [<ffffffff81019106>] ? mwait_idle+0x2c/0x88
 [<ffffffff81010ca5>] ? cpu_idle+0xaa/0xe4
 [<ffffffff8143e507>] ? rest_init+0x6b/0x6d
 [<ffffffff81815de2>] ? start_kernel+0x3f4/0x3ff
 [<ffffffff818152c1>] ? x86_64_start_reservations+0xac/0xb0
 [<ffffffff818153bd>] ? x86_64_start_kernel+0xf8/0x107
---[ end trace e631e7a2788d2801 ]---


Happened about 6 days in.... Didn't take down my computer though, its still working.

Comment 14 Jaiv 2010-02-18 00:04:51 UTC
Here is my update:

I tried to run below kernels with hostapd 0.7.1 and my ath9k card:
- kernel-PAE-2.6.31.12-174.2.3.fc12.i686 - not working
- kernel-2.6.31.12-174.2.19.fc12.i686 - not working
- kernel-2.6.31.12-174.2.19.fc12.i686 with ath9k compiled from compat-wireless-2.6.31-rc7 - working for couple of minutes, then I get lot of these:

Feb 17 23:13:09 localhost hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authenticated
Feb 17 23:13:09 localhost hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: associated (aid 2)
Feb 17 23:13:12 localhost hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: deauthenticated due to local deauth request
Feb 17 23:13:12 localhost hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: authenticated
Feb 17 23:13:12 localhost hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: associated (aid 2)
Feb 17 23:13:15 localhost hostapd: wlan0: STA xx:xx:xx:xx:xx:xx IEEE 802.11: deauthenticated due to local deauth request

In final I tried:
- kernel-2.6.32.8-58.fc12.i686 (non-PAE) which I downloaded directly from koji - seems to be working fine (even without extra compilation from linux wireless)

Please note I have to use non-PAE kernel as PAE kernels seem to have a bug affecting VIA padlock modules - I will report it in separate ticket.

So I will try to use the latest kernel and I let you know if there is any additional problem affecting hostapd or ath9k.

Comment 15 Ronald Wells 2010-04-26 15:41:00 UTC
Any update on this?  I appear to be running into this same issue with  2.6.32.11-99.fc12.x86_64

Comment 16 Ronald Wells 2010-05-04 13:49:16 UTC
I'm still getting a crash with hostapd and ath9k.  I've tried a bunch of different combinations of kernel and hostapd.  I've tried both PAE and Non PAE kernels with the same results.  If someone who has this working would please post more specific instructions on how exactly you got this to work I would really appreciate it!  

here are the results of lspci |grep Wire
06:00.0 Network controller: Atheros Communications Inc. AR9285 Wireless Network Adapter (PCI-Express) (rev 01)

Here is the crash log from my attempt using fedora 13 beta ( 2.6.33.2-57.fc13.i686.PAE ) and hostapd 0.7.2:

May  4 08:34:54 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed IEEE 802.11: authenticated
May  4 08:34:54 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed IEEE 802.11: No WPA/RSN IE in association request
May  4 08:34:55 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed IEEE 802.11: authenticated
May  4 08:34:55 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed IEEE 802.11: associated (aid 1)
May  4 08:34:55 Fedora13-Beta kernel: ------------[ cut here ]------------
May  4 08:34:55 Fedora13-Beta kernel: WARNING: at kernel/softirq.c:143 _local_bh_enable_ip+0x3e/0xab()
May  4 08:34:55 Fedora13-Beta kernel: Hardware name: nT-330i
May  4 08:34:55 Fedora13-Beta kernel: Modules linked in: aes_i586 aes_generic fuse sunrpc p4_clockmod ipt_MASQUERADE iptable_nat nf_nat ip6t_REJECT nf_c$
May  4 08:34:55 Fedora13-Beta kernel: Pid: 2300, comm: hostapd Not tainted 2.6.33.2-57.fc13.i686.PAE #1
May  4 08:34:55 Fedora13-Beta kernel: Call Trace:
May  4 08:34:55 Fedora13-Beta kernel: [<c0442276>] warn_slowpath_common+0x6a/0x81
May  4 08:34:55 Fedora13-Beta kernel: [<c0447e65>] ? _local_bh_enable_ip+0x3e/0xab
May  4 08:34:55 Fedora13-Beta kernel: [<fabc7a04>] ? ath_tx_node_cleanup+0xee/0x106 [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<c044229f>] warn_slowpath_null+0x12/0x15
May  4 08:34:55 Fedora13-Beta kernel: [<c0447e65>] _local_bh_enable_ip+0x3e/0xab
May  4 08:34:55 Fedora13-Beta kernel: [<c0447edf>] local_bh_enable_ip+0xd/0xf
May  4 08:34:55 Fedora13-Beta kernel: [<c07c6cc8>] _raw_spin_unlock_bh+0x2a/0x2d
May  4 08:34:55 Fedora13-Beta kernel: [<fabc7a04>] ath_tx_node_cleanup+0xee/0x106 [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<fabc336d>] ath9k_sta_notify+0x79/0x7d [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<fab226a5>] __sta_info_unlink+0x11b/0x171 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<fabc32f4>] ? ath9k_sta_notify+0x0/0x7d [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<fab22723>] sta_info_unlink+0x28/0x36 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<fab2ddaf>] ? ieee80211_del_station+0x0/0x6d [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<fab2ddfb>] ieee80211_del_station+0x4c/0x6d [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<f7f4c7f0>] nl80211_del_station+0x70/0x92 [cfg80211]
May  4 08:34:55 Fedora13-Beta kernel: [<c0750b78>] genl_rcv_msg+0x1ac/0x1c9
May  4 08:34:55 Fedora13-Beta kernel: [<c07509cc>] ? genl_rcv_msg+0x0/0x1c9
May  4 08:34:55 Fedora13-Beta kernel: [<c074fb4e>] netlink_rcv_skb+0x35/0x7b
May  4 08:34:55 Fedora13-Beta kernel: [<c07509c0>] genl_rcv+0x20/0x2c
May  4 08:34:55 Fedora13-Beta kernel: [<c074f97e>] netlink_unicast+0xc3/0x11e
May  4 08:34:55 Fedora13-Beta kernel: [<c075051f>] netlink_sendmsg+0x21c/0x229
May  4 08:34:55 Fedora13-Beta kernel: [<c072aae6>] __sock_sendmsg+0x4a/0x53
May  4 08:34:55 Fedora13-Beta kernel: [<c072ada6>] sock_sendmsg+0x98/0xac
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8430>] ? might_fault+0x4c/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8465>] ? might_fault+0x81/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c0732fd9>] ? copy_from_user+0xd/0xf
May  4 08:34:55 Fedora13-Beta kernel: [<c0733305>] ? verify_iovec+0x43/0x72
May  4 08:34:55 Fedora13-Beta kernel: [<c072afe0>] sys_sendmsg+0x18c/0x1f0
May  4 08:34:55 Fedora13-Beta kernel: [<c045e777>] ? sched_clock_cpu+0x125/0x12d
May  4 08:34:55 Fedora13-Beta kernel: [<c0468516>] ? trace_hardirqs_off+0xb/0xd
May  4 08:34:55 Fedora13-Beta kernel: [<c045e7ba>] ? cpu_clock+0x3b/0x53
May  4 08:34:55 Fedora13-Beta kernel: [<c0468549>] ? lock_release_holdtime+0x31/0xd6
May  4 08:34:55 Fedora13-Beta kernel: [<c046b486>] ? lock_release_non_nested+0xb5/0x1e8
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8430>] ? might_fault+0x4c/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8430>] ? might_fault+0x4c/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8465>] ? might_fault+0x81/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c072c904>] sys_socketcall+0x163/0x1aa
May  4 08:34:55 Fedora13-Beta kernel: [<c0408bdf>] sysenter_do_call+0x12/0x38
May  4 08:34:55 Fedora13-Beta kernel: ---[ end trace 02f340be0b38fef0 ]---
May  4 08:34:55 Fedora13-Beta kernel:
May  4 08:34:55 Fedora13-Beta kernel: =================================
May  4 08:34:55 Fedora13-Beta kernel: [ INFO: inconsistent lock state ]
May  4 08:34:55 Fedora13-Beta kernel: 2.6.33.2-57.fc13.i686.PAE #1
May  4 08:34:55 Fedora13-Beta kernel: ---------------------------------
May  4 08:34:55 Fedora13-Beta kernel: inconsistent {IN-SOFTIRQ-W} -> {SOFTIRQ-ON-W} usage.
May  4 08:34:55 Fedora13-Beta kernel: hostapd/2300 [HC0[0]:SC0[0]:HE1:SE1] takes:
May  4 08:34:55 Fedora13-Beta kernel: (&(&local->sta_lock)->rlock){+.?...}, at: [<fab2271a>] sta_info_unlink+0x1f/0x36 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: {IN-SOFTIRQ-W} state was registered at:
May  4 08:34:55 Fedora13-Beta kernel:  [<c046a3fd>] __lock_acquire+0x285/0xb89
May  4 08:34:55 Fedora13-Beta kernel:  [<c046ad94>] lock_acquire+0x93/0xb1
May  4 08:34:55 Fedora13-Beta kernel:  [<c07c66c9>] _raw_spin_lock_irqsave+0x37/0x6a
May  4 08:34:55 Fedora13-Beta kernel:  [<fab32938>] ieee80211_beacon_get_tim+0xf8/0x2b3 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel:  [<fabc2b9a>] ath_beacon_tasklet+0x1b2/0x3d8 [ath9k]
May  4 08:34:55 Fedora13-Beta kernel:  [<c0447254>] tasklet_action+0x78/0xcb
May  4 08:34:55 Fedora13-Beta kernel:  [<c0447bbf>] __do_softirq+0xbc/0x173
May  4 08:34:55 Fedora13-Beta kernel:  [<c0447cb1>] do_softirq+0x3b/0x5f
May  4 08:34:55 Fedora13-Beta kernel:  [<c0447df4>] irq_exit+0x3a/0x6d
May  4 08:34:55 Fedora13-Beta kernel:  [<c040a090>] do_IRQ+0x8b/0x9f
May  4 08:34:55 Fedora13-Beta kernel:  [<c04091b5>] common_interrupt+0x35/0x3c
May  4 08:34:55 Fedora13-Beta kernel:  [<c0407dfe>] cpu_idle+0x9b/0xb5
May  4 08:34:55 Fedora13-Beta kernel:  [<c07b20a7>] rest_init+0x67/0x69
May  4 08:34:55 Fedora13-Beta kernel:  [<c0a53939>] start_kernel+0x378/0x37d
May  4 08:34:55 Fedora13-Beta kernel:  [<c0a530aa>] i386_start_kernel+0x99/0xa0
May  4 08:34:55 Fedora13-Beta kernel: irq event stamp: 60490
May  4 08:34:55 Fedora13-Beta kernel: hardirqs last  enabled at (60487): [<c07c54ac>] __mutex_unlock_slowpath+0xf7/0x104
May  4 08:34:55 Fedora13-Beta kernel: hardirqs last disabled at (60488): [<c07c66b2>] _raw_spin_lock_irqsave+0x20/0x6a
May  4 08:34:55 Fedora13-Beta kernel: softirqs last  enabled at (60490): [<fabc7a04>] ath_tx_node_cleanup+0xee/0x106 [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: softirqs last disabled at (60489): [<c07c67dc>] _raw_spin_lock_bh+0x11/0x58
May  4 08:34:55 Fedora13-Beta kernel:
May  4 08:34:55 Fedora13-Beta kernel: other info that might help us debug this:
May  4 08:34:55 Fedora13-Beta kernel: 5 locks held by hostapd/2300:
May  4 08:34:55 Fedora13-Beta kernel: #0:  (genl_mutex){+.+.+.}, at: [<c075099e>] genl_lock+0x14/0x16
May  4 08:34:55 Fedora13-Beta kernel: #1:  (rtnl_mutex){+.+.+.}, at: [<c074186f>] rtnl_lock+0x14/0x16
May  4 08:34:55 Fedora13-Beta kernel: #2:  (&rdev->mtx){+.+.+.}, at: [<f7f47d03>] cfg80211_get_dev_from_ifindex+0x54/0x6b [cfg80211]
May  4 08:34:55 Fedora13-Beta kernel: #3:  (rcu_read_lock){.+.+..}, at: [<fab2d49c>] rcu_read_lock+0x0/0x26 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: #4:  (&(&local->sta_lock)->rlock){+.?...}, at: [<fab2271a>] sta_info_unlink+0x1f/0x36 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel:
May  4 08:34:55 Fedora13-Beta kernel: stack backtrace:
May  4 08:34:55 Fedora13-Beta kernel: Pid: 2300, comm: hostapd Tainted: G        W  2.6.33.2-57.fc13.i686.PAE #1
May  4 08:34:55 Fedora13-Beta kernel: Call Trace:
May  4 08:34:55 Fedora13-Beta kernel: [<c07c437d>] ? printk+0x14/0x17
May  4 08:34:55 Fedora13-Beta kernel: [<c0469446>] valid_state+0x131/0x144
May  4 08:34:55 Fedora13-Beta kernel: [<c046954b>] mark_lock+0xf2/0x1e1
May  4 08:34:55 Fedora13-Beta kernel: [<c0469c68>] ? check_usage_backwards+0x0/0x68
May  4 08:34:55 Fedora13-Beta kernel: [<c046967d>] mark_held_locks+0x43/0x5b
May  4 08:34:55 Fedora13-Beta kernel: [<c0447ec9>] ? _local_bh_enable_ip+0xa2/0xab
May  4 08:34:55 Fedora13-Beta kernel: [<c04698d9>] trace_hardirqs_on_caller+0x104/0x125
May  4 08:34:55 Fedora13-Beta kernel: [<fabc7a04>] ? ath_tx_node_cleanup+0xee/0x106 [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<c0469905>] trace_hardirqs_on+0xb/0xd
May  4 08:34:55 Fedora13-Beta kernel: [<c0447ec9>] _local_bh_enable_ip+0xa2/0xab
May  4 08:34:55 Fedora13-Beta kernel: [<c0447edf>] local_bh_enable_ip+0xd/0xf
May  4 08:34:55 Fedora13-Beta kernel: [<c07c6cc8>] _raw_spin_unlock_bh+0x2a/0x2d
May  4 08:34:55 Fedora13-Beta kernel: [<fabc7a04>] ath_tx_node_cleanup+0xee/0x106 [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<fabc336d>] ath9k_sta_notify+0x79/0x7d [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<fab226a5>] __sta_info_unlink+0x11b/0x171 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<fabc32f4>] ? ath9k_sta_notify+0x0/0x7d [ath9k]
May  4 08:34:55 Fedora13-Beta kernel: [<fab22723>] sta_info_unlink+0x28/0x36 [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<fab2ddaf>] ? ieee80211_del_station+0x0/0x6d [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<fab2ddfb>] ieee80211_del_station+0x4c/0x6d [mac80211]
May  4 08:34:55 Fedora13-Beta kernel: [<f7f4c7f0>] nl80211_del_station+0x70/0x92 [cfg80211]
May  4 08:34:55 Fedora13-Beta kernel: [<c0750b78>] genl_rcv_msg+0x1ac/0x1c9
May  4 08:34:55 Fedora13-Beta kernel: [<c07509cc>] ? genl_rcv_msg+0x0/0x1c9
May  4 08:34:55 Fedora13-Beta kernel: [<c074fb4e>] netlink_rcv_skb+0x35/0x7b
May  4 08:34:55 Fedora13-Beta kernel: [<c07509c0>] genl_rcv+0x20/0x2c
May  4 08:34:55 Fedora13-Beta kernel: [<c074f97e>] netlink_unicast+0xc3/0x11e
May  4 08:34:55 Fedora13-Beta kernel: [<c075051f>] netlink_sendmsg+0x21c/0x229
May  4 08:34:55 Fedora13-Beta kernel: [<c072aae6>] __sock_sendmsg+0x4a/0x53
May  4 08:34:55 Fedora13-Beta kernel: [<c072ada6>] sock_sendmsg+0x98/0xac
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8430>] ? might_fault+0x4c/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8465>] ? might_fault+0x81/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c0732fd9>] ? copy_from_user+0xd/0xf
May  4 08:34:55 Fedora13-Beta kernel: [<c0733305>] ? verify_iovec+0x43/0x72
May  4 08:34:55 Fedora13-Beta kernel: [<c072afe0>] sys_sendmsg+0x18c/0x1f0
May  4 08:34:55 Fedora13-Beta kernel: [<c045e777>] ? sched_clock_cpu+0x125/0x12d
May  4 08:34:55 Fedora13-Beta kernel: [<c0468516>] ? trace_hardirqs_off+0xb/0xd
May  4 08:34:55 Fedora13-Beta kernel: [<c045e7ba>] ? cpu_clock+0x3b/0x53
May  4 08:34:55 Fedora13-Beta kernel: [<c0468549>] ? lock_release_holdtime+0x31/0xd6
May  4 08:34:55 Fedora13-Beta kernel: [<c046b486>] ? lock_release_non_nested+0xb5/0x1e8
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8430>] ? might_fault+0x4c/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8430>] ? might_fault+0x4c/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c04c8465>] ? might_fault+0x81/0x86
May  4 08:34:55 Fedora13-Beta kernel: [<c072c904>] sys_socketcall+0x163/0x1aa
May  4 08:34:55 Fedora13-Beta kernel: [<c0408bdf>] sysenter_do_call+0x12/0x38
May  4 08:34:55 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed RADIUS: starting accounting session 4BE020B4-00000001
May  4 08:34:55 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed WPA: pairwise key handshake completed (RSN)
May  4 08:36:52 Fedora13-Beta abrt: Kerneloops: Reported 1 kernel oopses to Abrt
May  4 08:36:52 Fedora13-Beta abrtd: Directory 'kerneloops-1272980212-1' creation detected
May  4 08:36:52 Fedora13-Beta abrtd: Getting local universal unique identification
May  4 08:36:52 Fedora13-Beta abrtd: New crash /var/cache/abrt/kerneloops-1272980212-1, processing
May  4 08:36:52 Fedora13-Beta abrtd: Registered Action plugin 'RunApp'
May  4 08:36:52 Fedora13-Beta abrtd: RunApp('/var/cache/abrt/kerneloops-1272980212-1','test x"`cat component`" = x"xorg-x11-server-Xorg" && cp /var/log/$
May  4 08:37:19 Fedora13-Beta hostapd: wlan0: STA 00:12:f0:d8:cb:ed IEEE 802.11: deauthenticated due to local deauth request

Comment 17 John W. Linville 2010-05-04 14:57:34 UTC
Comment 16 seems to be a duplicate of bug 587727 -- please pursue that issue there (including testing kernel-2.6.32.12-115.fc12)...thanks!

Given that other positive feedback, I'm closing this bug as CURRENTRELEASE.


Note You need to log in before you can comment on or make changes to this bug.