hostap: "Driver does not support WPA"?

Johannes Gijsbers johannes
Wed Jun 14 05:46:48 PDT 2006 

Hello everyone,

I am currently using the hostap driver in the standard Ubuntu Dapper Drake
kernel. However, I decided to contact this list after seeing this error message
pop up several times in logs on this list [1].

I am using NetworkManager, which uses wpasupplicant for the WPA support.
Apparently, it can't connect to our private network, which uses WPA-PSK. I'm
mostly concerned by the following message in daemon.log:

Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_wpa: enabled=1
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Driver does not support WPA.

I thought hostap did support WPA?

Following are some bits from log files and commands:

lshw:

  *-network
       description: Wireless interface
       physical id: 1
       logical name: wlan0
       serial: 00:02:6f:35:8c:2e
       capabilities: ethernet physical wireless
       configuration: broadcast=yes driver=hostap driverversion=0.4.4-kernel
firmware=1.4.9 multicast=yes wireless=IEEE 802.11b

hostap_diag:

Host AP driver diagnostics information for 'wlan0'

NICID: id=0x800c v1.0.0 (PRISM II (2.5) PCMCIA (SST parallel flash))
PRIID: id=0x0015 v1.1.0
STAID: id=0x001f v1.4.9 (station firmware)

iwconfig:

wifi0     IEEE 802.11b  ESSID:"test"
          Mode:Managed  Access Point: None   Bit Rate:2 Mb/s
          Sensitivity=1/3
          Retry min limit:8   RTS thr:off   Fragment thr:off
          Power Management:off

wlan0     IEEE 802.11b  ESSID:"test"
          Mode:Managed  Access Point: None   Bit Rate:2 Mb/s
          Sensitivity=1/3
          Retry min limit:8   RTS thr:off   Fragment thr:off
          Power Management:off
          Link Quality=0/70  Signal level=-73 dBm  Noise level=-73 dBm
          Rx invalid nwid:0  Rx invalid crypt:0  Rx invalid frag:0
          Tx excessive retries:0  Invalid misc:1   Missed beacon:0

dmesg:

[4296554.767000] wifi0: LinkStatus=2 (Disconnected)
[4296554.767000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296558.827000] wifi0: LinkStatus=2 (Disconnected)
[4296558.827000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296558.844000] wifi0: LinkStatus=2 (Disconnected)
[4296558.844000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296558.854000] wifi0: CMD=0x0121 => res=0x7f, resp0=0x0004
[4296558.854000] wifi0: hfa384x_set_rid: CMDCODE_ACCESS_WRITE failed (res=127,
rid=fc48, len=26)
[4296558.855000] wifi0: LinkStatus=2 (Disconnected)
[4296558.856000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296558.866000] wlan0: Trying to join BSSID 00:00:00:00:00:00
[4296558.980000] wifi0: LinkStatus=6 (Association failed)
[4296558.980000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296618.882000] wifi0: hfa384x_cmd: interrupted; err=-4
[4296618.882000] wifi0: hfa384x_set_rid: CMDCODE_ACCESS_WRITE failed (res=-4,
rid=fc2a, len=2)
[4296618.882000] wifi0: cnfAuthentication setting to 0x1 failed
[4296618.883000] wifi0: LinkStatus=2 (Disconnected)
[4296618.884000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296618.894000] wifi0: CMD=0x0121 => res=0x7f, resp0=0x0004
[4296618.894000] wifi0: hfa384x_set_rid: CMDCODE_ACCESS_WRITE failed (res=127,
rid=fc48, len=26)
[4296618.895000] wifi0: LinkStatus=2 (Disconnected)
[4296618.895000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296618.906000] wlan0: Trying to join BSSID 00:00:00:00:00:00
[4296618.919000] wifi0: CMD=0x0121 => res=0x7f, resp0=0x0004
[4296618.919000] wifi0: hfa384x_set_rid: CMDCODE_ACCESS_WRITE failed (res=127,
rid=fc48, len=2)
[4296618.928000] wifi0: LinkStatus=2 (Disconnected)
[4296618.928000] wifi0: LinkStatus: BSSID=44:44:44:44:44:44
[4296618.939000] wlan0: Preferred AP (SIOCSIWAP) is used only in Managed mode
when host_roaming is enabled
[4296630.424000] ADDRCONF(NETDEV_UP): wifi0: link is not ready
[4296630.424000] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[4296634.667000] eth0: no IPv6 routers present

daemon.log:

Jun 13 17:12:30 surfboy NetworkManager: <information>^Imatch
Jun 13 17:12:30 surfboy NetworkManager: <debug info>^I[1150211550.546559]
nm_device_802_11_wireless_get_activation_ap (): Forcing AP 'SX551C0BD80'
Jun 13 17:12:30 surfboy NetworkManager: <information>^IUser Switch:
/org/freedesktop/NetworkManager/Devices/wlan0 / SX551C0BD80
Jun 13 17:12:30 surfboy NetworkManager: <information>^IDeactivating device
wlan0.
Jun 13 17:12:32 surfboy NetworkManager: <information>^IDevice wlan0 activation
scheduled...
Jun 13 17:12:32 surfboy NetworkManager: <information>^IDeactivating device eth0.
Jun 13 17:12:32 surfboy dhclient: wifi0: unknown hardware address type 801
Jun 13 17:12:32 surfboy dhclient: wifi0: unknown hardware address type 801
Jun 13 17:12:32 surfboy dhclient: DHCPRELEASE on eth0 to 192.168.2.1 port 67
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation (wlan0)
started...
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation (wlan0) Stage
1 of 5 (Device Prepare) scheduled...
Jun 13 17:12:33 surfboy NetworkManager: <information>^Imatch
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation (wlan0) Stage
1 of 5 (Device Prepare) started...
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation (wlan0) Stage
2 of 5 (Device Configure) scheduled...
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation (wlan0) Stage
1 of 5 (Device Prepare) complete.
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation (wlan0) Stage
2 of 5 (Device Configure) starting...
Jun 13 17:12:33 surfboy NetworkManager: <information>^IActivation
(wlan0/wireless): access point 'SX551C0BD80' is encrypted, and a key exists.  No
new key needed.
Jun 13 17:12:33 surfboy NetworkManager: <information>^Imatch
Jun 13 17:12:33 surfboy last message repeated 7 times
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'INTERFACE_ADD wlan0^I^Ihostap^I/var/run/wpa_supplicant^I'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'AP_SCAN 2'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'ADD_NETWORK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was '0'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 ssid 5358353531433042443830'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 scan_ssid 1'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 proto WPA'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 key_mgmt WPA-PSK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 psk <key>'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 pairwise TKIP'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'SET_NETWORK 0 group TKIP'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: sending command
'ENABLE_NETWORK 0'
Jun 13 17:12:34 surfboy NetworkManager: <information>^ISUP: response was 'OK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^IActivation (wlan0) Stage
2 of 5 (Device Configure) complete.
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Global control interface '/var/run/wpa_supplicant-global'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
global ctrl_iface - hexdump_ascii(len=52):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   49 4e 54 45 52 46 41 43 45 5f 41 44 44 20 77 6c   INTERFACE_ADD wl
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   61 6e 30 09 09 68 6f 73 74 61 70 09 2f 76 61 72   an0__hostap_/var
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   2f 72 75 6e 2f 77 70 61 5f 73 75 70 70 6c 69 63   /run/wpa_supplic
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   61 6e 74 09                                       ant_
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE GLOBAL INTERFACE_ADD 'wlan0^I^Ihostap^I/var/run/wpa_supplicant^I'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Initializing interface 'wlan0' conf 'N/A' driver 'hostap' ctrl_interface
'/var/run/wpa_supplicant'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Initializing interface (2) 'wlan0'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
EAPOL: SUPP_PAE entering state DISCONNECTED
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
EAPOL: SUPP_BE entering state INITIALIZE
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
EAP: EAP entering state DISABLED
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
EAPOL: External notification - portEnabled=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
EAPOL: External notification - portValid=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
SIOCGIWRANGE: WE(compiled)=19 WE(source)=18 enc_capa=0xf
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
capabilities: key_mgmt 0xf enc 0xf
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Added alternative ifindex 3 (wifi0) for wireless events
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): x
3 (wifi0) for wireless events
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Own MAC address: 00:02:6f:35:8c:2e
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_wpa: enabled=1
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Driver does not support WPA.
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_key: alg=none key_idx=0 set_tx=0 seq_len=0 key_len=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_key: alg=none key_idx=1 set_tx=0 seq_len=0 key_len=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_key: alg=none key_idx=2 set_tx=0 seq_len=0 key_len=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_key: alg=none key_idx=3 set_tx=0 seq_len=0 key_len=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_countermeasures: enabled=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
wpa_driver_hostap_set_drop_unencrypted: enabled=1
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Setting scan request: 0 sec 100000 usec
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Added interface wlan0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Wireless event: cmd=0x8b06 len=8
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=9):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   41 50 5f 53 43 41 4e 20 32                        AP_SCAN 2
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=11):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   41 44 44 5f 4e 45 54 57 4f 52 4b                  ADD_NETWORK
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: ADD_NETWORK
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=41):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 73 73   SET_NETWORK 0 ss
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
31 34 33 33   id 5358353531433
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   30 34 32 34 34 33 38 33 30                        042443830
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: SET_NETWORK id=0 name='ssid' value='5358353531433042443830'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
ssid - hexdump_ascii(len=11):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 58 35 35 31 43 30 42 44 38 30                  SX551C0BD80
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=25):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 73 63   SET_NETWORK 0 sc
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   61 6e 5f 73 73 69 64 20 31                        an_ssid 1
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: SET_NETWORK id=0 name='scan_ssid' value='1'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
scan_ssid=1 (0x1)
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=23):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 70 72   SET_NETWORK 0 pr
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   6f 74 6f 20 57 50 41                              oto WPA
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: SET_NETWORK id=0 name='proto' value='WPA'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
proto: 0x1
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=30):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 6b 65   SET_NETWORK 0 ke
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   79 5f 6d 67 6d 74 20 57 50 41 2d 50 53 4b         y_mgmt WPA-PSK
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
SK'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
key_mgmt: 0x2
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=82): [REMOVED]
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: SET_NETWORK id=0 name='psk'
value='4968b928c362db6a1bdb78ad72a3dca6614cc751263a83cd67d73bbd66f666aa'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
PSK - hexdump(len=32): [REMOVED]
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=27):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 70 61   SET_NETWORK 0 pa
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   69 72 77 69 73 65 20 54 4b 49 50                  irwise TKIP
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: SET_NETWORK id=0 name='pairwise' value='TKIP'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
pairwise: 0x8
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=24):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 67 72   SET_NETWORK 0 gr
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   6f 75 70 20 54 4b 49 50                           oup TKIP
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: SET_NETWORK id=0 name='group' value='TKIP'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
group: 0x8
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311): RX
ctrl_iface - hexdump_ascii(len=16):
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
   45 4e 41 42 4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
CTRL_IFACE: ENABLE_NETWORK id=0
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Setting scan request: 0 sec 0 usec
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
State: DISCONNECTED -> SCANNING
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Trying to associate with SSID 'SX551C0BD80'
Jun 13 17:12:34 surfboy NetworkManager: <information>^Iwpa_supplicant(10311):
Cancelling scan request
Jun 13 17:13:34 surfboy NetworkManager: <information>^IActivation
(wlan0/wireless): association took too long (>60s), failing activation.
Jun 13 17:13:34 surfboy NetworkManager: <information>^IActivation (wlan0)
failure scheduled...
Jun 13 17:13:34 surfboy NetworkManager: <information>^IActivation (wlan0) failed
for access point (SX551C0BD80)
Jun 13 17:13:34 surfboy NetworkManager: <information>^IActivation (wlan0)
failed.
Jun 13 17:13:34 surfboy NetworkManager: <information>^IDeactivating device
wlan0.

I'm really lost here, so I'm not sure I've included the right information. If
you need anything else, please ask.

Thanks in advance.

Johannes

[1] This man, for example, has the same problem and the exact same card I own:
http://lists.shmoo.com/pipermail/hostap/2006-March/012888.html, but his problem
is not resolved.

----- Einde doorgestuurd bericht -----

More information about the Hostap mailing list