wpa_supplicant timeout

dumas · 2013-10-07 15:05:49

Hi all,

After system upgrade, wireless stopped working for my desktop (while still working for my android phone).

cat /etc/wpa_supplicant.conf

ctrl_interface=/var/run/wpa_supplicant
ctrl_interface_group=network
update_config=1
network={
	ssid="WD-71"
	psk=*
}

wpa_supplicant -Dwext -i wlan0 -c /etc/wpa_supplicant.conf -d

wpa_supplicant v2.0
Successfully initialized wpa_supplicant
Initializing interface 'wlan0' conf '/etc/wpa_supplicant.conf' driver 'wext' ctrl_interface 'N/A' bridge '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'
ctrl_interface_group='network'
update_config=1
Priority group 0
   id=0 ssid='WD-71'
rfkill: Cannot open RFKILL control device
WEXT: RFKILL status not available
SIOCGIWRANGE: WE(compiled)=22 WE(source)=19 enc_capa=0xf
  capabilities: key_mgmt 0xf enc 0x1f flags 0x0
netlink: Operstate: linkmode=1, operstate=5
wlan0: Own MAC address: 00:1b:2f:2d:90:42
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
wlan0: RSN: flushing PMKID list in the driver
wlan0: Setting scan request: 0 sec 100000 usec
WPS: Set UUID for interface wlan0
WPS: UUID based on MAC address - hexdump(len=16): b5 c0 71 20 84 1a 54 a2 a7 33 1e c0 7f 5a 46 cd
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
EAPOL: KEY_RX entering state NO_KEY_RECEIVE
EAPOL: SUPP_BE entering state INITIALIZE
EAP: EAP entering state DISABLED
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
Using existing control interface directory.
ctrl_interface_group=90 (from group name 'network')
ctrl_iface bind(PF_UNIX) failed: Address already in use
ctrl_iface exists, but does not allow connections - assuming it was leftover from forced program termination
Successfully replaced leftover ctrl_iface socket '/var/run/wpa_supplicant/wlan0'
wlan0: Added interface wlan0
wlan0: State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: linkmode=-1, operstate=5
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b1a len=40
wlan0: State: DISCONNECTED -> SCANNING
wlan0: Starting AP scan for wildcard SSID
Scan requested (ret=0) - scan timeout 10 seconds
EAPOL: disable timer tick
EAPOL: Supplicant port status: Unauthorized
Scan timeout - try to get results
wlan0: Event SCAN_RESULTS (3) received
Received 2772 bytes of scan results (6 BSSes)
wlan0: BSS: Start scan result update 1
wlan0: BSS: Add new id 0 BSSID a0:21:b7:51:eb:cc SSID 'BigPond19FF'
wlan0: BSS: Add new id 1 BSSID 00:90:a9:ab:92:71 SSID 'WD-71'
wlan0: BSS: Add new id 2 BSSID 00:60:64:3e:43:0c SSID 'NP121-1224'
wlan0: BSS: Add new id 3 BSSID 9c:d3:6d:b7:b1:8a SSID 'WD-71_EXT'
wlan0: BSS: Add new id 4 BSSID a0:21:b7:f5:2a:69 SSID 'OPTUSV615E18'
wlan0: BSS: Add new id 5 BSSID 00:1b:11:38:f8:b8 SSID 'd2x'
BSS: last_scan_res_used=6/32 last_scan_full=0
wlan0: New scan results available
WPS: AP a0:21:b7:51:eb:cc type 0 added
WPS: AP 00:90:a9:ab:92:71 type 0 added
WPS: AP 00:60:64:3e:43:0c type 0 added
WPS: AP 9c:d3:6d:b7:b1:8a type 0 added
WPS: AP a0:21:b7:f5:2a:69 type 0 added
WPS: AP[0] a0:21:b7:51:eb:cc type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[1] 00:90:a9:ab:92:71 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[2] 00:60:64:3e:43:0c type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[3] 9c:d3:6d:b7:b1:8a type=0 tries=0 last_attempt=-1 sec ago blacklist=0
WPS: AP[4] a0:21:b7:f5:2a:69 type=0 tries=0 last_attempt=-1 sec ago blacklist=0
wlan0: Selecting BSS from priority group 0
wlan0: 0: a0:21:b7:51:eb:cc ssid='BigPond19FF' wpa_ie_len=28 rsn_ie_len=24 caps=0x11 level=-77 wps
wlan0:    skip - SSID mismatch
wlan0: 1: 00:90:a9:ab:92:71 ssid='WD-71' wpa_ie_len=26 rsn_ie_len=24 caps=0x11 level=-77 wps
wlan0:    selected based on RSN IE
wlan0:    selected BSS 00:90:a9:ab:92:71 ssid='WD-71'
wlan0: Request association: reassociate: 0  selected: 00:90:a9:ab:92:71  bssid: 00:00:00:00:00:00  pending: 00:00:00:00:00:00  wpa_state: SCANNING
wlan0: Trying to associate with 00:90:a9:ab:92:71 (SSID='WD-71' freq=2437 MHz)
wlan0: Cancelling scan request
wlan0: WPA: clearing own WPA/RSN IE
wlan0: Automatic auth_alg selection: 0x1
RSN: PMKSA cache search - network_ctx=(nil) try_opportunistic=0
RSN: Search for BSSID 00:90:a9:ab:92:71
RSN: No PMKSA cache entry found
wlan0: RSN: using IEEE 802.11i/D9.0
wlan0: WPA: Selected cipher suites: group 8 pairwise 24 key_mgmt 2 proto 2
WPA: set AP WPA IE - hexdump(len=28): dd 1a 00 50 f2 01 01 00 00 50 f2 02 02 00 00 50 f2 04 00 50 f2 02 01 00 00 50 f2 02
WPA: set AP RSN IE - hexdump(len=26): 30 18 01 00 00 0f ac 02 02 00 00 0f ac 04 00 0f ac 02 01 00 00 0f ac 02 00 00
wlan0: WPA: using GTK TKIP
wlan0: WPA: using PTK CCMP
wlan0: WPA: using KEY_MGMT WPA-PSK
WPA: Set own WPA IE default - hexdump(len=22): 30 14 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00
wlan0: No keys have been configured - skip key clearing
wlan0: State: SCANNING -> ASSOCIATING
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: linkmode=-1, operstate=5
Limit connection to BSSID 00:90:a9:ab:92:71 freq=2437 MHz based on scan results (bssid_set=0)
wlan0: set_disable_max_amsdu: -1
wlan0: set_ampdu_factor: -1
wlan0: set_ampdu_density: -1
wlan0: set_disable_ht40: 0
wlan0: set_disable_sgi: 0
wpa_driver_wext_associate
wpa_driver_wext_set_drop_unencrypted
wpa_driver_wext_set_psk
wlan0: Setting authentication timeout: 10 sec 0 usec
EAPOL: External notification - EAP success=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - EAP fail=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - portControl=Auto
EAPOL: Supplicant port status: Unauthorized
RSN: Ignored PMKID candidate without preauth flag
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b06 len=8
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b04 len=12
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b1a len=13
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8c07 len=71
AssocReq IE wireless event - hexdump(len=63): 00 05 57 44 2d 37 31 01 08 82 84 8b 96 0c 18 30 48 32 04 12 24 60 6c 30 26 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8c08 len=76
AssocResp IE wireless event - hexdump(len=68): 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60 6c dd 18 00 50 f2 04 10 4a 00 01 10 10 3b 00 01 03 10 49 00 06 00 37 2a 00 01 20 30 18 01 00 00 0f ac 02 02 00 00 0f 00 00 00 00 00 00 00 00 00 00 00 00 00 00
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:90:a9:ab:92:71
wlan0: Event ASSOCINFO (4) received
wlan0: Association info event
req_ies - hexdump(len=63): 00 05 57 44 2d 37 31 01 08 82 84 8b 96 0c 18 30 48 32 04 12 24 60 6c 30 26 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
resp_ies - hexdump(len=68): 01 08 82 84 8b 0c 12 96 18 24 32 04 30 48 60 6c dd 18 00 50 f2 04 10 4a 00 01 10 10 3b 00 01 03 10 49 00 06 00 37 2a 00 01 20 30 18 01 00 00 0f ac 02 02 00 00 0f 00 00 00 00 00 00 00 00 00 00 00 00 00 00
WPA: set own WPA/RSN IE - hexdump(len=40): 30 26 01 00 00 0f ac 02 01 00 00 0f ac 04 01 00 00 0f ac 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
wpa_parse_wpa_ie_rsn: ie has 16 trailing bytes - ignored
wlan0: RSN: PMKID from assoc IE not found from PMKSA cache
wlan0: Event ASSOC (0) received
wlan0: State: ASSOCIATING -> ASSOCIATED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: linkmode=-1, operstate=5
wlan0: Associated to a new BSS: BSSID=00:90:a9:ab:92:71
wlan0: No keys have been configured - skip key clearing
wlan0: Associated with 00:90:a9:ab:92:71
wlan0: WPA: Association event - clear replay counter
wlan0: WPA: Clear old PTK
EAPOL: External notification - portEnabled=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - portValid=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - EAP success=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - portEnabled=1
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: SUPP_BE entering state IDLE
wlan0: Setting authentication timeout: 10 sec 0 usec
wlan0: Cancelling scan request
RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
EAPOL: startWhen --> 0
EAPOL: disable timer tick
EAPOL: SUPP_PAE entering state CONNECTING
EAPOL: enable timer tick
EAPOL: txStart
WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
wlan0: Authentication with 00:90:a9:ab:92:71 timed out.
Added BSSID 00:90:a9:ab:92:71 into blacklist
wlan0: Request to deauthenticate - bssid=00:90:a9:ab:92:71 pending_bssid=00:00:00:00:00:00 reason=3 state=ASSOCIATED
wpa_driver_wext_deauthenticate
wlan0: Event DEAUTH (12) received
wlan0: Deauthentication notification
wlan0:  * reason 3 (locally generated)
Deauthentication frame IE(s) - hexdump(len=0): [NULL]
wlan0: CTRL-EVENT-DISCONNECTED bssid=00:90:a9:ab:92:71 reason=3 locally_generated=1
wlan0: Auto connect enabled: try to reconnect (wps=0 wpa_state=6)
wlan0: Setting scan request: 0 sec 100000 usec
BSSID 00:90:a9:ab:92:71 blacklist count incremented to 2
wlan0: Blacklist count 2 --> request scan in 500 ms
wlan0: Setting scan request: 0 sec 500000 usec
wlan0: Disconnect event - remove keys
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_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wlan0: State: ASSOCIATED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: SUPP_PAE entering state DISCONNECTED
EAPOL: Supplicant port status: Unauthorized
EAPOL: SUPP_BE entering state INITIALIZE
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - portValid=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - EAP success=0
EAPOL: Supplicant port status: Unauthorized
wlan0: No keys have been configured - skip key clearing
wlan0: State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - portValid=0
EAPOL: Supplicant port status: Unauthorized
wlan0: Setting scan request: 1 sec 0 usec
EAPOL: Supplicant port status: Unauthorized
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b15 len=20
Wireless event: new AP: 00:00:00:00:00:00
wlan0: Event DISASSOC (1) received
wlan0: Disassociation notification
wlan0: Auto connect enabled: try to reconnect (wps=0 wpa_state=0)
wlan0: Do not request new immediate scan
wlan0: Disconnect event - remove keys
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_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
wlan0: State: DISCONNECTED -> DISCONNECTED
wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
netlink: Operstate: linkmode=-1, operstate=5
EAPOL: External notification - portEnabled=0
EAPOL: Supplicant port status: Unauthorized
EAPOL: External notification - portValid=0
EAPOL: Supplicant port status: Unauthorized
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
WEXT: if_removed already cleared - ignore event
Wireless event: cmd=0x8b1a len=40
wlan0: State: DISCONNECTED -> SCANNING
wlan0: Starting AP scan for wildcard SSID
Scan requested (ret=0) - scan timeout 10 seconds
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
EAPOL: Supplicant port status: Unauthorized
Scan timeout - try to get results
wlan0: Event SCAN_RESULTS (3) received
Received 2687 bytes of scan results (6 BSSes)
wlan0: BSS: Start scan result update 2
dbus: wpas_dbus_bss_signal_prop_changed: Unknown Property value 7
BSS: last_scan_res_used=6/32 last_scan_full=0
wlan0: New scan results available

So my guess is maybe 10 seconds authentication time is too short? Or is it something else?

Thanks in advance.

hasinasi · 2013-10-09 19:48:44

I have had a similar problem on Kubuntu precise (12.04). I am on KDE 4.11.2. (kubuntu-backports enabled).
Since about 2 days, my wireless is acting up. Nothing in my network changed, and I have been using this on a daily basis for years. Suddenly get kicked off the network. In a few cases, I was able to connect, though. Sometimes it worked to do an off/on cycle of my wireless card (physical switch).
I did not even realize any updates pertinent to the Wireless went through during the past days. I wish I knew which one it was, so that I could hold the old version of the package.
Any ideas, dumas?

dumas · 2013-10-12 13:44:55

So you mean you can occasionally connect? I am not able to at all.

I'm sure other computers or phones can connect to the wifi, and I'm confident the computer hardware is alright.

ndiswrapper appears to be fine, giving me wlan0, which can detect and negotiate with my essid. The problem is always authentication time out in 10 s ...

Any ideas? It's so sad to type this post from my phone

dumas · 2013-10-20 08:22:46

Trying, as per https://bbs.archlinux.org/viewtopic.php?id=170862, to add the line

TimeoutDHCP=20

or other higher values to /etc/netctl/Home and doing

netctl restart Home

gives

Dependency failed for Home wireless connection (Home).

Last edited by dumas (2013-10-20 08:23:26)

dumas · 2013-10-20 08:54:26

After disabling the Home profile, and running netctl rsetart Home, now I have for journal -xn

WPA association/authentication failed for interface 'wlan0'

Although I am certain of my essid and passphrase?

dumas · 2013-10-20 10:33:08

journalctl indicated these problems might be related to ipv6, so I disabled that via kernel parameter. Now journalctl gives

cfg80211: calling CRDA to update world regulatory domain

Arch Linux

#1 2013-10-07 15:05:49

wpa_supplicant timeout

#2 2013-10-09 19:48:44

Re: wpa_supplicant timeout

#3 2013-10-12 13:44:55

Re: wpa_supplicant timeout

#4 2013-10-20 08:22:46

Re: wpa_supplicant timeout

#5 2013-10-20 08:54:26

Re: wpa_supplicant timeout

#6 2013-10-20 10:33:08

Re: wpa_supplicant timeout

Board footer