timeout when connecting



Hi!

This is my 1st post, so please be kind ;)

My laptop has integrated bcm4318 chip which is not well supported with
bcm43xx driver, so I went and buy d-link dwl-g650 card which works
nicely with madwifi-ng (r1475) driver and wpa_supplicant-0.5.2.

The problem is in connecting wpa_supplicant with NetworManager.

I patched driver with enccap patch andf applied 'driver-workaround'
patch to NM-0.6.0 which looks more clean (for patching) than 0.6.1.

Here is the log output:

NetworkManager: <information>   Activation (ath0) started...
NetworkManager: <information>   Activation (ath0) Stage 1 of 5 (Device
Prepare) scheduled...
NetworkManager: <information>   Activation (ath0) Stage 1 of 5 (Device
Prepare) started...
NetworkManager: <information>   Activation (ath0) Stage 2 of 5 (Device
Configure) scheduled...
NetworkManager: <information>   Activation (ath0) Stage 1 of 5 (Device
Prepare) complete.
NetworkManager: <information>   Activation (ath0) Stage 2 of 5 (Device
Configure) starting...
NetworkManager: <information>   Activation (ath0/wireless): access point
'gaura' is encrypted, and a key exists.  No new key needed.
NetworkManager: <information>   SUP: sending command 'INTERFACE_ADD ath0
madwifi  /var/run/run/wpa_supplicant     '
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'AP_SCAN 1'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ADD_NETWORK'
NetworkManager: <information>   SUP: response was '0'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 ssid
6761757261'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
scan_ssid 1'NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
proto WPA2'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0
key_mgmt WPA-PSK'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'SET_NETWORK 0 psk
<key>'
NetworkManager: <information>   SUP: response was 'OK'
NetworkManager: <information>   SUP: sending command 'ENABLE_NETWORK 0'
NetworkManager: <information>   SUP: response was 'OK'
ioctl[IEEE80211_IOCTL_SETMLME]: Invalid argument
NetworkManager: <information>   Activation (ath0) Stage 2 of 5 (Device
Configure) complete.
NetworkManager: <information>   wpa_supplicant(13840): Global control
interface '/var/run/run/wpa_supplicant-global'
NetworkManager: <information>   wpa_supplicant(13840): RX global
ctrl_iface - hexdump_ascii(len=56):
NetworkManager: <information>   wpa_supplicant(13840):      49 4e 54 45
52 46 41 43 45 5f 41 44 44 20 61 74   INTERFACE_ADD at
NetworkManager: <information>   wpa_supplicant(13840):      68 30 09 09
6d 61 64 77 69 66 69 09 2f 76 61 72   h0__madwifi_/var
NetworkManager: <information>   wpa_supplicant(13840):      2f 72 75 6e
2f 72 75 6e 2f 77 70 61 5f 73 75 70   /run/run/wpa_sup
NetworkManager: <information>   wpa_supplicant(13840):      70 6c 69 63
61 6e 74 09                           plicant_
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE GLOBAL
INTERFACE_ADD 'ath0            madwifi /var/run/run/wpa_supplicant
'
NetworkManager: <information>   wpa_supplicant(13840): Initializing
interface 'ath0' conf 'N/A' driver 'madwifi' ctrl_interface
'/var/run/run/wpa_supplicant' bridge 'N/A'
NetworkManager: <information>   wpa_supplicant(13840): Initializing
interface (2) 'ath0'
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: SUPP_PAE
entering state DISCONNECTED
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: KEY_RX
entering state NO_KEY_RECEIVE
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: SUPP_BE
entering state INITIALIZE
NetworkManager: <information>   wpa_supplicant(13840): EAP: EAP entering
state DISABLED
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: External
notification - portEnabled=0
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: External
notification - portValid=0
NetworkManager: <information>   wpa_supplicant(13840): SIOCGIWRANGE:
WE(compiled)=19 WE(source)=13 enc_capa=0xf
NetworkManager: <information>   wpa_supplicant(13840):   capabilities:
key_mgmt 0xf enc 0xf
NetworkManager: <information>   wpa_supplicant(13840): Own MAC address:
00:11:95:fa:73:0e
NetworkManager: <information>   wpa_supplicant(13840): l_key: keyidx=0
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_del_key: keyidx=1
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_del_key: keyidx=2
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_del_key: keyidx=3
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_set_countermeasures: enabled=0
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_set_drop_unencrypted: enabled=1
NetworkManager: <information>   wpa_supplicant(13840): Setting scan
request: 0 sec 100000 usec
NetworkManager: <information>   wpa_supplicant(13840): Added interface
ath0
NetworkManager: <information>   wpa_supplicant(13840): Wireless event:
cmd=0x8b06 len=12
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=9):
NetworkManager: <information>   wpa_supplicant(13840):      41 50 5f 53
43 41 4e 20 31                        AP_SCAN 1
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=11):
NetworkManager: <information>   wpa_supplicant(13840):      41 44 44 5f
4e 45 54 57 4f 52 4b                  ADD_NETWORK
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
ADD_NETWORK
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=29): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
SET_NETWORK id=0 name='ssid'
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE: value
- hexdump_ascii(len=10): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): ssid -
hexdump_ascii(len=5):
NetworkManager: <information>   wpa_supplicant(13840):      67 61 75 72
61                               gaura
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=25): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
SET_NETWORK id=0 name='scan_ssid'
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE: value
- hexdump_ascii(len=1): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): scan_ssid=1
(0x1)
NetworkManager: <information>   wpa_supplicant(13840): D]
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
SET_NETWORK id=0 name='proto'
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE: value
- hexdump_ascii(len=4): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): proto: 0x2
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=30): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
SET_NETWORK id=0 name='key_mgmt'
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE: value
- hexdump_ascii(len=7): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): key_mgmt: 0x2
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=82): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
SET_NETWORK id=0 name='psk'
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE: value
- hexdump_ascii(len=64): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): PSK -
hexdump(len=32): [REMOVED]
NetworkManager: <information>   wpa_supplicant(13840): RX ctrl_iface -
hexdump_ascii(len=16):
NetworkManager: <information>   wpa_supplicant(13840):      45 4e 41 42
4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0
NetworkManager: <information>   wpa_supplicant(13840): CTRL_IFACE:
ENABLE_NETWORK id=0
NetworkManager: <information>   wpa_supplicant(13840): Setting scan
request: 0 sec 0 usec
NetworkManager: <information>   wpa_supplicant(13840): State:
DISCONNECTED -> SCANNING
NetworkManager: <information>   wpa_supplicant(13840): Starting AP scan
(specific SSID)
NetworkManager: <information>   wpa_supplicant(13840): Scan SSID -
hexdump_ascii(len=5):
NetworkManager: <information>   wpa_supplicant(13840):      67 61 75 72
61                               gaura
NetworkManager: <information>   wpa_supplicant(13840): Trying to get
current scan results first without requesting a new scan to speed up
initial association
NetworkManager: <information>   wpa_supplicant(13840): Received 299
bytes of scan results (1 BSSes)
NetworkManager: <information>   wpa_supplicant(13840): Scan results: 1
NetworkManager: <information>   wpa_supplicant(13840): Selecting BSS
from priority group 0
NetworkManager: <information>   wpa_supplicant(13840): a' wpa_ie_len=0
rsn_ie_len=22 caps=0x11
NetworkManager: <information>   wpa_supplicant(13840):    selected based
on RSN IE
NetworkManager: <information>   wpa_supplicant(13840): Trying to
associate with 00:14:bf:a2:71:09 (SSID='gaura' freq=2417 MHz)
NetworkManager: <information>   wpa_supplicant(13840): Cancelling scan
request
NetworkManager: <information>   wpa_supplicant(13840): WPA: clearing own
WPA/RSN IE
NetworkManager: <information>   wpa_supplicant(13840): Automatic
auth_alg selection: 0x1
NetworkManager: <information>   wpa_supplicant(13840): RSN: using IEEE
802.11i/D9.0
NetworkManager: <information>   wpa_supplicant(13840): WPA: Selected
cipher suites: group 8 pairwise 8 key_mgmt 2
NetworkManager: <information>   wpa_supplicant(13840): WPA: clearing AP
WPA IE
NetworkManager: <information>   wpa_supplicant(13840): WPA: set AP RSN
IE - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 02 01 00 00
0f ac 02 00 00
NetworkManager: <information>   wpa_supplicant(13840): WPA: using GTK
TKIP
NetworkManager: <information>   wpa_supplicant(13840): WPA: using PTK
TKIP
NetworkManager: <information>   wpa_supplicant(13840): WPA: using
KEY_MGMT WPA-PSK
NetworkManager: <information>   wpa_supplicant(13840): WPA: Set own WPA
IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 02
01 00 00 0f ac 02 00 00
NetworkManager: <information>   wpa_supplicant(13840): No keys have been
configured - skip key clearing
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_set_drop_unencrypted: enabled=1
NetworkManager: <information>   wpa_supplicant(13840): State: SCANNING
-> ASSOCIATING
NetworkManager: <information>   wpa_supplicant(13840):
wpa_driver_madwifi_associate
NetworkManager: <information>   wpa_supplicant(13840): Association
request to the driver failed
NetworkManager: <information>   wpa_supplicant(13840): Setting
authentication timeout: 5 sec 0 usec
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: External
notification - EAP success=0
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: External
notification - EAP fail=0
NetworkManager: <information>   wpa_supplicant(13840): EAPOL: External
notification - portControl=Auto
ioctl[SIOCSIWSCAN]: Invalid argument
ioctl[SIOCSIWSCAN]: Invalid argument
NetworkManager: <information>   Activation (ath0/wireless): association
took too long (>20s), failing activation.
NetworkManager: <information>   Activation (ath0) failure scheduled...
NetworkManager: <information>   Activation (ath0) failed for access
point (gaura)
NetworkManager: <information>   Activation (ath0) failed.
NetworkManager: <information>   Deactivating device ath0.
sendmsg(CTRL_IFACE monitor): No such file or directory
NetworkManager: <information>   SWITCH: no current connection, found
better connection 'eth0'.
NetworkManager: <information>   Will activate connection 'eth0'.



On #madwifi I got info that, depending on the setup, madwifi-ng can take
up to 30s to associate which is more then the present limit.

Any idea how to solve it?

(I saw a timeout-patch for 0.5.1) but it is too complicated to apply it
on 0.6.0 for myself considering other patches already applied.)

NM handles wired eth0 without any problem.

I'd really like to have NM working for my next travelling. 

Please, help...

Sincerely,
Gour

ps. Any IRC #channel where one can get more help (besides
#networkmanager which is not very populated?



Attachment: signature.asc
Description: This is a digitally signed message part



[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]