EDiMAX EW-7318USg (rt73usb/2.6.27) and wpasupplicant (0.6.3)

Live forum: http://rt2x00.serialmonkey.com/viewtopic.php?t=5068

ggeorg

03-11-2008 19:59:15

Hi,

i am trying to associate with a WPA enabled accesspoint (wpa_supplicant configuration works fine on same machine with ipw2200 using WEXT driver). Using the same configuration i get a timeout when trying to authenticate

[code2sfed9uq]
1225738445.657292: Initializing interface 'wlan0' conf '/etc/wpa_supplicant/wpa_
supplicant.conf' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
1225738445.657688: Configuration file '/etc/wpa_supplicant/wpa_supplicant.conf'
-> '/etc/wpa_supplicant/wpa_supplicant.conf'
1225738445.657711: Reading configuration file '/etc/wpa_supplicant/wpa_supplican
t.conf'
1225738445.657771: ctrl_interface='/var/run/wpa_supplicant'
1225738445.657790: ctrl_interface_group='adm' (DEPRECATED)
1225738445.657817: ap_scan=2
1225738445.657833: update_config=1
1225738445.657850: Line: 16 - start of a new network block
1225738445.657876: ssid - hexdump_ascii(len=8):
67 67 yy yy yy yy yy yy xxyyyyy
1225738445.657918: PSK (ASCII passphrase) - hexdump_ascii(len=13): [REMOVED]
1225738445.657939: BSSID - hexdump(len=6): 00 13 10 29 aa xx
1225738445.657961: proto: 0x1
1225738445.657977: key_mgmt: 0x2
1225738445.657993: pairwise: 0x8
1225738445.698854: PSK (from passphrase) - hexdump(len=32): [REMOVED]
1225738445.698887: Line 23: removed CCMP from group cipher list since it was not
allowed for pairwise cipher
1225738445.698947: Priority group 0
1225738445.698964: id=0 ssid='xxyyyyy'
1225738445.698979: Initializing interface (2) 'wlan0'
1225738445.699490: SIOCGIWRANGE: WE(compiled)=22 WE(source)=21 enc_capa=0xf
1225738445.699510: capabilities: key_mgmt 0xf enc 0xf flags 0x0
1225738445.755371: WEXT: Operstate: linkmode=1, operstate=5
1225738445.755506: Own MAC address: 00:1f:1f:12:aa:xx
1225738445.755518: wpa_driver_wext_set_wpa
1225738445.755529: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
1225738445.755543: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
1225738445.755553: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
1225738445.755563: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
1225738445.755573: wpa_driver_wext_set_countermeasures
1225738445.755581: wpa_driver_wext_set_drop_unencrypted
1225738445.755588: RSN: flushing PMKID list in the driver
1225738445.755610: Setting scan request: 0 sec 100000 usec
1225738445.758281: EAPOL: SUPP_PAE entering state DISCONNECTED
1225738445.758291: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
1225738445.758299: EAPOL: SUPP_BE entering state INITIALIZE
1225738445.758308: EAP: EAP entering state DISABLED
1225738445.758326: Using existing control interface directory.
1225738445.758812: ctrl_interface_group=4 (from group name 'adm')
1225738445.758862: Added interface wlan0
1225738445.758871: Daemonize..
1225738445.759226: RTM_NEWLINK: operstate=0 ifi_flags=0x1103 ([UP])
1225738445.759252: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1225738445.759267: Wireless event: cmd=0x8b06 len=8
1225738445.857413: State: DISCONNECTED -> SCANNING
1225738445.857490: Trying to associate with SSID 'ggclosed'
1225738445.857502: Cancelling scan request
1225738445.857511: WPA: clearing own WPA/RSN IE
1225738445.857518: Automatic auth_alg selection: 0x1
1225738445.857534: WPA: No WPA/RSN IE available from association info
1225738445.857543: WPA: Set cipher suites based on configuration
1225738445.857550: WPA: Selected cipher suites: group 14 pairwise 8 key_mgmt 2 proto 1
1225738445.857560: WPA: clearing AP WPA IE
1225738445.857566: WPA: clearing AP RSN IE
1225738445.857572: WPA: using GTK TKIP
1225738445.857579: WPA: using PTK TKIP
1225738445.857586: WPA: using KEY_MGMT WPA-PSK
1225738445.857592: WPA: not using MGMT group cipher
1225738445.857603: WPA: Set own WPA IE default - hexdump(len=24): dd 16 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02
1225738445.857621: No keys have been configured - skip key clearing
1225738445.857627: wpa_driver_wext_set_drop_unencrypted
1225738445.857635: State: SCANNING -> ASSOCIATING
1225738445.857642: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1225738445.857650: WEXT: Operstate: linkmode=-1, operstate=5
1225738445.857669: wpa_driver_wext_associate
1225738445.857701: wpa_driver_wext_set_psk
1225738445.857716: Setting authentication timeout: 60 sec 0 usec
1225738445.857760: EAPOL: External notification - EAP success=0
1225738445.857778: EAPOL: External notification - EAP fail=0
1225738445.857785: EAPOL: External notification - portControl=Auto
1225738445.858124: RTM_NEWLINK: operstate=0 ifi_flags=0x1103 ([UP])
1225738445.858136: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1225738445.858146: Wireless event: cmd=0x8b06 len=8
1225738446.418728: RTM_NEWLINK: operstate=0 ifi_flags=0x1103 ([UP])
1225738446.418757: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1225738446.418774: Wireless event: cmd=0x8b19 len=8
1225738446.418846: Received 1115 bytes of scan results (4 BSSes)
1225738446.418905: CTRL-EVENT-SCAN-RESULTS
1225738446.761394: EAPOL: disable timer tick
1225738451.985926: RTM_NEWLINK: operstate=0 ifi_flags=0x1103 ([UP])
1225738451.985958: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1225738451.985976: Wireless event: cmd=0x8b19 len=8
1225738451.986043: Received 1318 bytes of scan results (5 BSSes)
1225738451.986070: CTRL-EVENT-SCAN-RESULTS
1225738505.861408: Authentication with 00:00:00:00:00:00 timed out.
1225738505.861474: Added BSSID 00:00:00:00:00:00 into blacklist
1225738505.861502: No keys have been configured - skip key clearing
1225738505.861516: State: ASSOCIATING -> DISCONNECTED
[/code2sfed9uq]

the BSSID from the last two lines look bogus to me .. but the driver seems to work correct when scanning for results

[code2sfed9uq]
root@localhost:~# wpa_cli scan_results|head -2;wpa_cli scan_results|grep xxyyyyy
Selected interface 'wlan0'
bssid / frequency / signal level / flags / ssid
00:13:10:29:aa:xx 2437 188 [WPA-PSK-TKIP] xxyyyyy
[/code2sfed9uq]

checking channel with iwconfig seems to give me the wrong channel

[code2sfed9uq]
root@localhost:~# iwconfig wlan0
wlan0 IEEE 802.11bg ESSID:"ggclosed"
Mode:Managed Frequency:2.412 GHz Access Point: Not-Associated
Tx-Power=12 dBm
Retry min limit:7 RTS thr:off Fragment thr=2352 B
Encryption key:off
Power Management:off
Link Quality:0 Signal level:0 Noise level:0
Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0
Tx excessive retries:0 Invalid misc:0 Missed beacon:0
[/code2sfed9uq]

trying to force the channel and bssid with [i2sfed9uq]iwconfig wlan0 ap <bssid>[/i2sfed9uq] and [i2sfed9uq]iwconfig wlan0 channel 6[/i2sfed9uq] does not seem to help. However i assume hardware is running ok as IBBS mode and monitor mode works fine. RF communication should not be a problem as its only 10 meters with 9dBi omni and runs on the same spot/machine as ipw2200 (with builtin antenna)

firmware loaded

[code2sfed9uq]
bd733372ae21a010bf8a5511d7711c2d /lib/firmware/rt73.bin
[/code2sfed9uq]

dmesg output

[code2sfed9uq]
[34343.960055] usb 5-6: new high speed USB device using ehci_hcd and address 12
[34344.259632] usb 5-6: configuration #1 chosen from 1 choice
[34344.701561] phy5: Selected rate control algorithm 'pid'
[34344.726725] Registered led device: rt73usb-phy5:radio
[34344.727966] Registered led device: rt73usb-phy5:assoc
[34344.730248] Registered led device: rt73usb-phy5:quality
[34344.744360] usbcore: registered new interface driver rt73usb
[34344.830870] usbcore: registered new interface driver rt2500usb
[34348.900778] firmware: requesting rt73.bin
[34348.981471] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[/code2sfed9uq]

same problem seen on an ixp4xxx platform running 2.6.26 (armv5tel) and wpasupplicant 0.6.4

regards
Georg