Early timeouts connecting to unencrypted network with ndiswrapper on FC5

["Peter Williams" <peter newton cx>]

Hi,

(Please CC any replies to me as I'm not subscribed to the list.)

I just got a Netgear WG311v3 PCI 802.11g card for my Fedora Core 5
desktop. It apparently runs on a Marvell 88w8335 chipset. After doing some
research, I've installed ndiswrapper from Livna and configured the driver,
and that part seems to be fine.

After making an FDI file to create a info.linux.driver HAL entry, I can
start NetworkManager and see the local networks. (Without that entry, NM
crashes -- a sanity check in nm_get_device_driver_name might be helpful.)
So I can at least scan for networks. However, if I try to connect to an
unencrypted network, NetworkManager times out in phase 2 of the connection
process:

Jun 11 04:32:08 beta NetworkManager: <information>      starting...
Jun 11 04:32:10 beta NetworkManager: <information>      wlan0: Device is
fully-supported using driver 'ndiswrapper'.
Jun 11 04:32:10 beta NetworkManager: <information>      nm_device_init():
waiting for device's worker thread to start
Jun 11 04:32:10 beta NetworkManager: <information>      nm_device_init():
device's worker thread started, continuing.
Jun 11 04:32:10 beta NetworkManager: <information>      Now managing
wireless (802.11) device 'wlan0'.
Jun 11 04:32:10 beta NetworkManager: <information>      Deactivating
device wlan0.
Jun 11 04:32:16 beta NetworkManager: <information>      eth0: Device is
fully-supported using driver '3c59x'.
Jun 11 04:32:16 beta NetworkManager: <information>      nm_device_init():
waiting for device's worker thread to start
Jun 11 04:32:16 beta NetworkManager: <information>      nm_device_init():
device's worker thread started, continuing.
Jun 11 04:32:16 beta NetworkManager: <information>      Now managing wired
Ethernet (802.3) device 'eth0'.
Jun 11 04:32:16 beta NetworkManager: <information>      Deactivating
device eth0.
Jun 11 04:32:16 beta NetworkManager: <information>      Updating allowed
wireless network lists.
Jun 11 04:32:16 beta NetworkManager: <WARNING>   nm_dbus_get_networks_cb
(): nm-dbus-nmi.c:522 (nm_dbus_get_networks_cb): error received:
org.freedesktop.NetworkManagerInfo.NoNetworks - There
are no wireless networks stored..
Jun 11 04:32:25 beta NetworkManager: <information>      User Switch:
/org/freedesktop/NetworkManager/Devices/wlan0 / wiremch
Jun 11 04:32:25 beta NetworkManager: <information>      Deactivating
device wlan0.
Jun 11 04:32:27 beta dhcdbd: message_handler: message handler not found
under /com/redhat/dhcp/wlan0 for sub-path wlan0.dbus.get.reason
Jun 11 04:32:27 beta NetworkManager: <information>      Device wlan0
activation scheduled...
Jun 11 04:32:27 beta NetworkManager: <information>      Activation (wlan0)
started...
Jun 11 04:32:27 beta NetworkManager: <information>      Activation (wlan0)
Stage 1 of 5 (Device Prepare) scheduled...
Jun 11 04:32:27 beta NetworkManager: <information>      Activation (wlan0)
Stage 1 of 5 (Device Prepare) started...
Jun 11 04:32:27 beta NetworkManager: <information>      Activation (wlan0)
Stage 2 of 5 (Device Configure) scheduled...
Jun 11 04:32:27 beta NetworkManager: <information>      Activation (wlan0)
Stage 1 of 5 (Device Prepare) complete.
Jun 11 04:32:27 beta NetworkManager: <information>      Activation (wlan0)
Stage 2 of 5 (Device Configure) starting...
Jun 11 04:32:27 beta NetworkManager: <information>      Activation
(wlan0/wireless): access point 'wiremch' is unencrypted, no key needed.
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: sending
command 'INTERFACE_ADD wlan0               ndiswrapper    
/var/run/wpa_supplicant '
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: response was
'OK'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: sending
command 'AP_SCAN 1'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: response was
'OK'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: sending
command 'ADD_NETWORK'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: response was '0'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: sending
command 'SET_NETWORK 0 ssid 776972656d6368'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: response was
'OK'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: sending
command 'SET_NETWORK 0 key_mgmt NONE'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: response was
'OK'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: sending
command 'ENABLE_NETWORK 0'
Jun 11 04:32:28 beta NetworkManager: <information>      SUP: response was
'OK'
Jun 11 04:32:30 beta NetworkManager: <information>      Activation (wlan0)
Stage 2 of 5 (Device Configure) complete.
Jun 11 04:32:50 beta NetworkManager: <information>      Activation
(wlan0/wireless): association took too long (>20s), failing activation.
Jun 11 04:32:50 beta NetworkManager: <information>      Activation (wlan0)
failure scheduled...
Jun 11 04:32:50 beta NetworkManager: <information>      Activation (wlan0)
failed for access point (wiremch)
Jun 11 04:32:50 beta NetworkManager: <information>      Activation (wlan0)
failed.
Jun 11 04:32:50 beta NetworkManager: <information>      Deactivating
device wlan0.

(You may notice that I've patched my NetworkManager package to send
-Dndiswrapper to wpa_supplicant as part of my attempts to get this to
work.) The output from wpa_supplicant looks like this:

Setting scan request: 5 sec 0 usec
Starting AP scan (broadcast SSID)
Wireless event: cmd=0x8b1a len=8
Scan timeout - try to get results
Received 614 bytes of scan results (3 BSSes)
Scan results: 3
Selecting BSS from priority group 0
0: 00:09:5b:48:27:90 ssid='wiremch' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
1: 00:e0:98:f1:2c:67 ssid='wireless' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
2: 00:09:5b:ea:71:d6 ssid='Govs' wpa_ie_len=0 rsn_ie_len=0 caps=0x1
   skip - no WPA/RSN IE
No suitable AP found.
Setting scan request: 5 sec 0 usec
Wireless event: cmd=0x8b2a len=8
Wireless event: cmd=0x8b06 len=8
Wireless event: cmd=0x8b06 len=8
Starting AP scan (broadcast SSID)
Scan timeout - try to get results
ioctl[SIOCGIWSCAN]: Resource temporarily unavailable
Scan results: -1
Failed to get scan results
Failed to get scan results - try scanning again
Setting scan request: 1 sec 0 usec
Starting AP scan (broadcast SSID)
Scan timeout - try to get results
ioctl[SIOCGIWSCAN]: Resource temporarily unavailable
Scan results: -1
Failed to get scan results
Failed to get scan results - try scanning again
Setting scan request: 1 sec 0 usec
Starting AP scan (broadcast SSID)

If I run NetworkManager --no-daemon I just see three of those SIOCGIWSCAN
warnings before NM gives up and reports a timeout. I've experimented with
connecting to the AP via command-line tools (iwconfig wlan0 essid wiremch)
without success, although I'm not sure exactly how to properly connect
that way.

Any ideas of what the problem is or suggestions on how to figure out
what's going on? Help would be much appreciated.

Thanks,

Peter

-- 
Peter Williams / peter newton cx