Author Topic: Wpa_supplicant Association Timeout  (Read 2550 times)

simbaB

  • Newbie
  • *
  • Posts: 47
    • View Profile
    • http://www.oakcourt.dyndns.org/~andrew/
Wpa_supplicant Association Timeout
« on: June 11, 2006, 04:20:12 pm »
I have configured my poodle with OPIE and a SanDisk ConnectPlus to use wpa_supplicant to connect to my WPA-Enterprise network. First, I updated wpa-supplicant,hostap-modules, and hostap-modules-cs. Then, I put the following stanza in /etc/network/interfaces:

Code: [Select]
iface wlan0 inet dhcp
  wpa-conf /etc/wpa_supplicant.conf
  wpa-driver hostap

Note the problem I'm going to describe doesn't seem to be affected by the choice of wpa_supplicant driver--either 'hostap' or 'wext' makes it act the same. Putting the card in the machine makes it blink rapidly (loading firmware) and then blink slowly. It is supposed to go solid when associated but this never happens. Running wpa_supplicant manually, or running 'ifup wlan0', gets the following output:

Code: [Select]
root@poodle:/etc/network# wpa_supplicant -iwlan0 -c/etc/wpa_supplicant.conf -Dwext -dd
Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver 'wext' ctrl_interface 'N/A'
Configuration file '/etc/wpa_supplicant.conf' -> '/etc/wpa_supplicant.conf'
Reading configuration file '/etc/wpa_supplicant.conf'
ctrl_interface='/var/run/wpa_supplicant'
ap_scan=1
eapol_version=1
Line: 5 - start of a new network block
ssid - hexdump_ascii(len=8):
     6f 61 6b 63 6f 75 72 74                           oakcourt
key_mgmt: 0x1
eap methods - hexdump(len=2): 19 00
phase2 - hexdump_ascii(len=13):
     61 75 74 68 3d 4d 53 43 48 41 50 76 32            auth=MSCHAPv2
identity - hexdump_ascii(len=6):
     61 6e 64 72 65 77                                 andrew
password - hexdump_ascii(len=10): [REMOVED]
Priority group 0
   id=0 ssid='oakcourt'
Initializing interface (2) 'wlan0'
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
EAPOL: External notification - portEnabled=0
EAPOL: External notification - portValid=0
SIOCGIWRANGE: WE(compiled)=18 WE(source)=18 enc_capa=0xf
  capabilities: key_mgmt 0xf enc 0xf
Added alternative ifindex 14 (wifi0) for wireless events
Own MAC address: 00:60:b3:6c:65:97
wpa_driver_wext_set_wpa
wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
wpa_driver_wext_set_countermeasures
wpa_driver_wext_set_drop_unencrypted
Setting scan request: 0 sec 100000 usec
Using existing control interface directory.
Added interface wlan0
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK, IFLA_IFNAME: Interface 'wifi0' added
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
State: DISCONNECTED -> SCANNING
Starting AP scan (broadcast SSID)
Wireless event: cmd=0x8b19 len=12
Received 199 bytes of scan results (1 BSSes)
Scan results: 1
Selecting BSS from priority group 0
0: 00:c0:49:ec:6f:82 ssid='oakcourt' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
   selected based on WPA IE
Trying to associate with 00:c0:49:ec:6f:82 (SSID='oakcourt' freq=2412 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
WPA: using IEEE 802.11i/D3.0
WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 1
WPA: set AP WPA IE - 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 01
WPA: clearing AP RSN IE
WPA: using GTK TKIP
WPA: using PTK TKIP
WPA: using KEY_MGMT 802.1X
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 01
No keys have been configured - skip key clearing
wpa_driver_wext_set_drop_unencrypted
State: SCANNING -> ASSOCIATING
wpa_driver_wext_associate
Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - portControl=Auto
Wireless event: cmd=0x8b06 len=8
Wireless event: cmd=0x8b04 len=12
Wireless event: cmd=0x8b1a len=21
Wireless event: cmd=0x8b19 len=12
Received 199 bytes of scan results (1 BSSes)
Scan results: 1
Selecting BSS from priority group 0
0: 00:c0:49:ec:6f:82 ssid='oakcourt' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
   selected based on WPA IE
Trying to associate with 00:c0:49:ec:6f:82 (SSID='oakcourt' freq=2412 MHz)
Cancelling scan request
WPA: clearing own WPA/RSN IE
Automatic auth_alg selection: 0x1
WPA: using IEEE 802.11i/D3.0
WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 1
WPA: set AP WPA IE - 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 01
WPA: clearing AP RSN IE
WPA: using GTK TKIP
WPA: using PTK TKIP
WPA: using KEY_MGMT 802.1X
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 01
No keys have been configured - skip key clearing
wpa_driver_wext_set_drop_unencrypted
State: ASSOCIATING -> ASSOCIATING

The block in between "State: SCANNING->ASSOCIATING" and "State: ASSOCIATING->ASSOCIATING" repeats until you stop it. It seems to never be able to associate with the network. There isn't anything wrong with the network AFAIK, our two ThinkPad Linux laptops (ipw2200), my Nokia 770, and a Windows XP machine all connect fine. The wpa_supplicant.conf on the Z is as follows:

Code: [Select]
ctrl_interface=/var/run/wpa_supplicant
ap_scan=1
eapol_version=1

network={
        ssid="oakcourt"
        key_mgmt=WPA-EAP
        eap=PEAP
        phase2="auth=MSCHAPv2"
        identity="andrew"
        password="[removed]"
}
[span style=\'font-size:8pt;line-height:100%\']Collie (not used) | Poodle (OZ/Opie 3.5.4.2-rc2)
Nokia 770 (ITOS 2006) | IBM Thinkpad R51 (Debian)
Intel Core 2 Duo/Desktop Board DG965RY (Debian)[/span]

Hrw

  • Hero Member
  • *****
  • Posts: 1366
    • View Profile
Wpa_supplicant Association Timeout
« Reply #1 on: June 11, 2006, 05:03:35 pm »
Does your card has firmware atleast 1.7.0?

More info in OZ FAQ in wiki.
OpenZaurus 3.5.4x Release Manager
OpenEmbedded, Ångström, Poky developer
My website

Misc embedded hardware.

simbaB

  • Newbie
  • *
  • Posts: 47
    • View Profile
    • http://www.oakcourt.dyndns.org/~andrew/
Wpa_supplicant Association Timeout
« Reply #2 on: June 11, 2006, 05:45:31 pm »
Quote
Does your card has firmware atleast 1.7.0?

More info in OZ FAQ in wiki.
[div align=\"right\"][a href=\"index.php?act=findpost&pid=130769\"][{POST_SNAPBACK}][/a][/div]

The label on the back says firmware 1.7.4, but it is a Prism3-style card, which means the firmware is downloaded to volatile memory from the Z. That firmware, I believe, is 1.8.4. At least that's what I recall from the output of hostap_fw_load.
[span style=\'font-size:8pt;line-height:100%\']Collie (not used) | Poodle (OZ/Opie 3.5.4.2-rc2)
Nokia 770 (ITOS 2006) | IBM Thinkpad R51 (Debian)
Intel Core 2 Duo/Desktop Board DG965RY (Debian)[/span]

simbaB

  • Newbie
  • *
  • Posts: 47
    • View Profile
    • http://www.oakcourt.dyndns.org/~andrew/
Wpa_supplicant Association Timeout
« Reply #3 on: June 11, 2006, 05:59:26 pm »
Here are the initialization messages from 'dmesg' when I insert the card. Note I haven't run hostap_fw_load at this point, just put the card in the slot.

Code: [Select]
hostap_cs: 0.4.7 - 2005-11-20 (Jouni Malinen )
netproto_recv: not up net_dev->flags: 1002
hostap_cs: setting Vcc=33 (constant)
hostap_cs: CS_EVENT_CARD_INSERTION
hostap_cs: ignoring Vcc=33 (from config)
Checking CFTABLE_ENTRY 0x01 (default 0x01)
IO window settings: cfg->io.nwin=1 dflt.io.nwin=1
io->flags = 0x0047, io.base=0x0000, len=128
hostap_cs: Registered netdevice wifi0
wifi0: Interrupt, but dev not OK
netproto_recv: not up net_dev->flags: 1002
hostap_cs: index 0x01: Vcc 3.3, irq 39, io 0xf6000000-0xf600007f
wifi0: Multi-function SanDisk ConnectPlus detected - using vendor-specific initialization
netproto_recv: not up net_dev->flags: 1002
hostap_cs: assuming no Primary image in flash - card initialization not completed
wifi0: test Genesis mode with HCR 0x1f
prism2_pccard_cor_sreset: original COR 4b
Readback test failed, HCR 0x1f write 00 e1 a1 ff read 00 ce a1 ce
wifi0: test Genesis mode with HCR 0x0f
prism2_pccard_cor_sreset: original COR 4f
Readback test succeeded, HCR 0x0f
wifi0: registered netdevice wlan0
wlan0: could not set interface UP - no PRI f/w
« Last Edit: June 11, 2006, 06:00:18 pm by simbaB »
[span style=\'font-size:8pt;line-height:100%\']Collie (not used) | Poodle (OZ/Opie 3.5.4.2-rc2)
Nokia 770 (ITOS 2006) | IBM Thinkpad R51 (Debian)
Intel Core 2 Duo/Desktop Board DG965RY (Debian)[/span]

simbaB

  • Newbie
  • *
  • Posts: 47
    • View Profile
    • http://www.oakcourt.dyndns.org/~andrew/
Wpa_supplicant Association Timeout
« Reply #4 on: June 12, 2006, 02:34:14 pm »
Additionally, I tried a couple of different firmware versions (all higher than 1.7.0), and even upgraded to the test 2.6 kernel images available from hentges.net. The behavior is still the same...
[span style=\'font-size:8pt;line-height:100%\']Collie (not used) | Poodle (OZ/Opie 3.5.4.2-rc2)
Nokia 770 (ITOS 2006) | IBM Thinkpad R51 (Debian)
Intel Core 2 Duo/Desktop Board DG965RY (Debian)[/span]

simbaB

  • Newbie
  • *
  • Posts: 47
    • View Profile
    • http://www.oakcourt.dyndns.org/~andrew/
Wpa_supplicant Association Timeout
« Reply #5 on: June 12, 2006, 04:29:41 pm »
Hi there,

I've since reproduced this behaviour on regular laptop computer with Debian sid, running kernel 2.6.16 with the in-tree HostAP drivers and wpa_supplicant v0.4.9. So this doesn't appear to be an OpenZaurus-specific problem. I'll be taking my issue to the hostap mailing list. Thanks for the help!

Andrew
[span style=\'font-size:8pt;line-height:100%\']Collie (not used) | Poodle (OZ/Opie 3.5.4.2-rc2)
Nokia 770 (ITOS 2006) | IBM Thinkpad R51 (Debian)
Intel Core 2 Duo/Desktop Board DG965RY (Debian)[/span]