Hi,
I'm posting to ath5k-devel because I believe ath5k-users has been
closed. I wonder if someone can help me diagnose or repair the
following problem.
I'm using ath5k from stock kernel 2.6.33.1 (+ a couple of patches from
the -stable queue) on an Atheros AR2414 chip (MAC: 0x79, PHY: 0x45),
running hostapd 1:0.6.10-2 from Debian testing, as access point, and
an Android phone (Google G1 with Android 1.6) as client.
Initially the connection works fine, but when hostapd rekeys the WPA
keys, something fails and the phone is no longer associated ("iw dev
wlan0 station dump" shows that it is gone) but it thinks it still is.
How can I tell whether it's the phone's fault or ath5k's? And what
can I do about it? Actually, there are two problems: one is that the
phone does not rekey properly, and another is that when it gets
disconnected after failing to rekey, it does not know about it.
Solving either one of these problems would make me happy. :-)
Here is what hostapd -dd shows when this happens (the problem starts
at "WPA rekeying GTK"; 00:18:41:fe:69:2d is the phone, and I don't
know who 00:25:d3:46:41:96 might be):
STA 00:25:d3:46:41:96 sent probe request for broadcast SSID
MGMT (TX callback) ACK
mgmt::proberesp cb
STA 00:25:d3:46:41:96 sent probe request for broadcast SSID
MGMT (TX callback) ACK
mgmt::proberesp cb
STA 00:25:d3:46:41:96 sent probe request for broadcast SSID
MGMT (TX callback) ACK
mgmt::proberesp cb
STA 00:25:d3:46:41:96 sent probe request for broadcast SSID
MGMT (TX callback) ACK
mgmt::proberesp cb
STA 00:25:d3:46:41:96 sent probe request for broadcast SSID
MGMT (TX callback) ACK
mgmt::proberesp cb
wlan0: WPA rekeying GTK
WPA: group state machine entering state SETKEYS (VLAN-ID 0)
GMK - hexdump(len=32): [REMOVED]
GTK - hexdump(len=32): [REMOVED]
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state REKEYNEGOTIATING
wlan0: STA 00:18:41:fe:69:2d WPA: sending 1/2 msg of Group Key Handshake
WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=0 pairwise=0 kde_len=40
keyidx=2 encr=1)
Plaintext EAPOL-Key Key Data - hexdump(len=48): [REMOVED]
wpa_group_setkeys: GKeyDoneStations=1
wlan0: STA 00:18:41:fe:69:2d WPA: EAPOL-Key timeout
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state REKEYNEGOTIATING
wlan0: STA 00:18:41:fe:69:2d WPA: sending 1/2 msg of Group Key Handshake
WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=0 pairwise=0 kde_len=40
keyidx=2 encr=1)
Plaintext EAPOL-Key Key Data - hexdump(len=48): [REMOVED]
wlan0: STA 00:18:41:fe:69:2d WPA: EAPOL-Key timeout
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state REKEYNEGOTIATING
wlan0: STA 00:18:41:fe:69:2d WPA: sending 1/2 msg of Group Key Handshake
WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=0 pairwise=0 kde_len=40
keyidx=2 encr=1)
Plaintext EAPOL-Key Key Data - hexdump(len=48): [REMOVED]
wlan0: STA 00:18:41:fe:69:2d WPA: EAPOL-Key timeout
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state REKEYNEGOTIATING
wlan0: STA 00:18:41:fe:69:2d WPA: sending 1/2 msg of Group Key Handshake
WPA: Send EAPOL(version=2 secure=1 mic=1 ack=1 install=0 pairwise=0 kde_len=40
keyidx=2 encr=1)
Plaintext EAPOL-Key Key Data - hexdump(len=48): [REMOVED]
wlan0: STA 00:18:41:fe:69:2d WPA: EAPOL-Key timeout
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state REKEYNEGOTIATING
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state KEYERROR
WPA: group state machine entering state SETKEYSDONE (VLAN-ID 0)
WPA: 00:18:41:fe:69:2d WPA_PTK entering state DISCONNECT
hostapd_wpa_auth_disconnect: WPA authenticator requests disconnect: STA
00:18:41:fe:69:2d reason 2
WPA: 00:18:41:fe:69:2d WPA_PTK_GROUP entering state IDLE
WPA: 00:18:41:fe:69:2d WPA_PTK entering state DISCONNECTED
WPA: 00:18:41:fe:69:2d WPA_PTK entering state INITIALIZE
wlan0: STA 00:18:41:fe:69:2d IEEE 802.1X: unauthorizing port
wlan0: STA 00:18:41:fe:69:2d IEEE 802.11: deauthenticated due to local deauth
request
Data/PS-poll frame from not associated STA 00:18:41:fe:69:2d
MGMT (TX callback) fail
STA 00:25:d3:46:41:96 sent probe request for broadcast SSID
MGMT (TX callback) ACK
mgmt::proberesp cb
Data/PS-poll frame from not associated STA 00:18:41:fe:69:2d
MGMT (TX callback) fail
Data/PS-poll frame from not associated STA 00:18:41:fe:69:2d
MGMT (TX callback) fail
Data/PS-poll frame from not associated STA 00:18:41:fe:69:2d
MGMT (TX callback) fail
Data/PS-poll frame from not associated STA 00:18:41:fe:69:2d
MGMT (TX callback) fail
On the Android side, there is nothing whatsoever in the log that seems
to pertain to Wifi: as far as it's concerned, it seems to think that
it's still associated but that no packets are getting through.
And here are the non-comment parts of my hostapd.conf:
interface=wlan0
driver=nl80211
logger_syslog=-1
logger_syslog_level=3
logger_stdout=-1
logger_stdout_level=3
dump_file=/tmp/hostapd.dump
ctrl_interface=/var/run/hostapd
ctrl_interface_group=0
ssid=Davidounet
hw_mode=g
channel=8
beacon_int=250
dtim_period=3
max_num_sta=30
rts_threshold=2347
fragm_threshold=2346
macaddr_acl=0
auth_algs=1
ignore_broadcast_ssid=0
wme_enabled=0
wme_ac_bk_cwmin=4
wme_ac_bk_cwmax=10
wme_ac_bk_aifs=7
wme_ac_bk_txop_limit=0
wme_ac_bk_acm=0
wme_ac_be_aifs=3
wme_ac_be_cwmin=4
wme_ac_be_cwmax=10
wme_ac_be_txop_limit=0
wme_ac_be_acm=0
wme_ac_vi_aifs=2
wme_ac_vi_cwmin=3
wme_ac_vi_cwmax=4
wme_ac_vi_txop_limit=94
wme_ac_vi_acm=0
wme_ac_vo_aifs=2
wme_ac_vo_cwmin=2
wme_ac_vo_cwmax=3
wme_ac_vo_txop_limit=47
wme_ac_vo_acm=0
ap_max_inactivity=600
eapol_key_index_workaround=0
wpa=3
wpa_passphrase=SomePassphraseHere
wpa_key_mgmt=WPA-PSK
wpa_pairwise=TKIP
rsn_pairwise=CCMP
wpa_group_rekey=600
wpa_gmk_rekey=86400
--
David A. Madore
( http://www.madore.org/~david/ )
_______________________________________________
ath5k-devel mailing list
ath5k-devel@lists.ath5k.org
https://lists.ath5k.org/mailman/listinfo/ath5k-devel
