Bug#564568: marked as done (many retries before connection succeeds)

Debian Bug Tracking System Sun, 01 Jul 2012 08:12:27 -0700

Your message dated Sun, 1 Jul 2012 17:09:46 +0200
with message-id <[email protected]>
and subject line Re: many retries before connection succeeds
has caused the Debian Bug report #564568,
regarding many retries before connection succeeds
to be marked as done.


This means that you claim that the problem has been dealt with.
If this is not the case it is now your responsibility to reopen the
Bug report if necessary, and/or fix the problem forthwith.

(NB: If you are a system administrator and have no idea what this
message is talking about, this may indicate a serious mail system
misconfiguration somewhere. Please contact [email protected]
immediately.)


-- 
564568: http://bugs.debian.org/cgi-bin/bugreport.cgi?bug=564568
Debian Bug Tracking System
Contact [email protected] with problems

--- Begin Message ---
Package: wpasupplicant
I notice that wpa_supplicant sometimes tries many times before the connection eventually succeeds for a particular AP.
When running under network-manager, it sometimes gives up, and I have to go to the menu and select the AP to make it try again. I am repeatedly prompted for the WPA password, even though it has the key already.
When running it from the command line with a simple config file, it keeps retrying and eventually succeeds.
The system is a Thinkpad X61, lenny amd64. I am currently trying it with firmware-iwlwifi 0.22, I have also tried 0.18 (from testing) and the 0.14+lenny2 package from lenny.
network={
   ssid="SpeedTouch1"
   key_mgmt=WPA-PSK
   proto=WPA
   pairwise=TKIP
   group=TKIP
   psk=<hidden>
}


lspci -v:
03:00.0 Network controller: Intel Corporation PRO/Wireless 4965 AG or AGN [Kedron] Network Connection (rev 61)
   Subsystem: Intel Corporation Lenovo ThinkPad T51
   Flags: fast devsel, IRQ 17
   Memory at f7f00000 (64-bit, non-prefetchable) [size=8K]
   Capabilities: [c8] Power Management version 3
Capabilities: [d0] Message Signalled Interrupts: Mask- 64bit+ Queue=0/0 Enable-
   Capabilities: [e0] Express Endpoint, MSI 00
   Capabilities: [100] Advanced Error Reporting <?>
   Capabilities: [140] Device Serial Number a9-56-af-ff-ff-e8-13-00
   Kernel driver in use: iwl4965
   Kernel modules: iwl4965

# uname -a
Linux lt2 2.6.26-2-amd64 #1 SMP Fri Aug 14 07:12:04 UTC 2009 x86_64 GNU/Linux
From dmesg:


[ 4727.877159] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 4730.152521] wlan0: Initial auth_alg=0
[ 4730.152521] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4730.154099] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4730.154109] wlan0: authenticated
[ 4730.154115] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4730.156859] wlan0: RX AssocResp from 00:90:d0:db:ad:19 (capab=0x411 status=0 aid=1)
[ 4730.156859] wlan0: associated
[ 4730.156859] wlan0: switched to short barker preamble (BSSID=00:90:d0:db:ad:19)
[ 4730.176019] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 4741.144458] wlan0: no IPv6 routers present
[ 4741.192917] wlan0: disassociate(reason=3)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.806882] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4743.806882] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4743.815095] wlan0: Initial auth_alg=0
[ 4743.815095] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4743.815095] wlan0: association frame received from 00:90:d0:db:ad:19, but not in associate state - ignored
[ 4743.821222] wlan0: Initial auth_alg=0
[ 4743.821222] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4743.821222] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4743.821222] wlan0: authenticated
[ 4743.821222] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4743.825142] wlan0: authentication frame received from 00:90:d0:db:ad:19, but not in authenticate state - ignored [ 4743.827965] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 status=0 aid=1)
[ 4743.827977] wlan0: associated
[ 4743.828000] wlan0: switched to short barker preamble (BSSID=00:90:d0:db:ad:19)
[ 4754.841820] wlan0: disassociate(reason=3)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4757.448441] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4757.456924] wlan0: Initial auth_alg=0
[ 4757.456924] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4757.456924] wlan0: association frame received from 00:90:d0:db:ad:19, but not in associate state - ignored
[ 4757.462968] wlan0: Initial auth_alg=0
[ 4757.462968] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4757.462968] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4757.462968] wlan0: authenticated
[ 4757.462968] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4757.466115] wlan0: authentication frame received from 00:90:d0:db:ad:19, but not in authenticate state - ignored [ 4757.470892] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 status=0 aid=1)
[ 4757.470892] wlan0: associated
[ 4757.470892] wlan0: switched to short barker preamble (BSSID=00:90:d0:db:ad:19)
[ 4768.487853] wlan0: disassociate(reason=3)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.169810] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4771.169810] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4771.177560] wlan0: Initial auth_alg=0
[ 4771.177560] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4771.177560] wlan0: association frame received from 00:90:d0:db:ad:19, but not in associate state - ignored [ 4771.180076] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4771.180076] wlan0: authenticated
[ 4771.180076] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4771.181961] wlan0: Initial auth_alg=0
[ 4771.181961] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4771.185788] wlan0: association frame received from 00:90:d0:db:ad:19, but not in associate state - ignored [ 4771.188749] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4771.188749] wlan0: authenticated
[ 4771.188749] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4771.201855] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 status=0 aid=1)
[ 4771.201855] wlan0: associated
[ 4771.201855] wlan0: switched to short barker preamble (BSSID=00:90:d0:db:ad:19)
[ 4782.232515] wlan0: disassociate(reason=3)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.848600] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4784.848600] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4784.856414] wlan0: Initial auth_alg=0
[ 4784.856414] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4784.856414] wlan0: association frame received from 00:90:d0:db:ad:19, but not in associate state - ignored
[ 4784.862561] wlan0: Initial auth_alg=0
[ 4784.862561] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4784.862561] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4784.862561] wlan0: authenticated
[ 4784.862561] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4784.865141] wlan0: authentication frame received from 00:90:d0:db:ad:19, but not in authenticate state - ignored [ 4784.870828] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 status=0 aid=1)
[ 4784.870828] wlan0: associated
[ 4784.870828] wlan0: switched to short barker preamble (BSSID=00:90:d0:db:ad:19)
[ 4795.933142] wlan0: disassociate(reason=3)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.653012] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4798.653012] wlan0: RX disassociation from 00:90:d0:db:ad:19 (reason=7)
[ 4798.656857] wlan0: Initial auth_alg=0
[ 4798.656857] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4798.656857] wlan0: association frame received from 00:90:d0:db:ad:19, but not in associate state - ignored
[ 4798.663745] wlan0: Initial auth_alg=0
[ 4798.663745] wlan0: authenticate with AP 00:90:d0:db:ad:19
[ 4798.663745] wlan0: RX authentication from 00:90:d0:db:ad:19 (alg=0 transaction=2 status=0)
[ 4798.663745] wlan0: authenticated
[ 4798.663745] wlan0: associate with AP 00:90:d0:db:ad:19
[ 4798.668536] wlan0: authentication frame received from 00:90:d0:db:ad:19, but not in authenticate state - ignored [ 4798.668548] wlan0: RX ReassocResp from 00:90:d0:db:ad:19 (capab=0x411 status=0 aid=1)
[ 4798.668551] wlan0: associated
[ 4798.668566] wlan0: switched to short barker preamble (BSSID=00:90:d0:db:ad:19)
# wpa_supplicant -c /root/wpa-speedtouch1.conf -i wlan0 -d -t

.......
1263119480.783866: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119480.783871: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119480.783881: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119480.783889: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119480.783897: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119480.783905: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119480.783934: Driver did not support SIOCSIWENCODEEXT
1263119480.783941: State: DISCONNECTED -> DISCONNECTED
1263119480.783946: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119480.783952: WEXT: Operstate: linkmode=-1, operstate=5
1263119480.783961: EAPOL: External notification - portEnabled=0
1263119480.783967: EAPOL: External notification - portValid=0
1263119480.783972: EAPOL: External notification - EAP success=0
1263119480.783980: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.783986: Wireless event: cmd=0x8b06 len=12
1263119480.783993: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.783999: Wireless event: cmd=0x8b04 len=16
1263119480.784005: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.784011: Wireless event: cmd=0x8b1a len=27
1263119480.784018: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119480.784023: Wireless event: cmd=0x8c02 len=128
1263119480.791248: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1263119480.791261: Wireless event: cmd=0x8b15 len=24
1263119480.791266: Wireless event: new AP: 00:90:d0:db:ad:19
1263119480.791273: State: DISCONNECTED -> ASSOCIATED
1263119480.791279: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119480.791285: WEXT: Operstate: linkmode=-1, operstate=5
1263119480.791301: Associated to a new BSS: BSSID=00:90:d0:db:ad:19
1263119480.791308: No keys have been configured - skip key clearing
1263119480.791315: Associated with 00:90:d0:db:ad:19
1263119480.791320: WPA: Association event - clear replay counter
1263119480.791325: WPA: Clear old PTK
1263119480.791330: EAPOL: External notification - portEnabled=0
1263119480.798511: EAPOL: External notification - portValid=0
1263119480.798518: EAPOL: External notification - EAP success=0
1263119480.798523: EAPOL: External notification - portEnabled=1
1263119480.798528: EAPOL: SUPP_PAE entering state CONNECTING
1263119480.798533: EAPOL: SUPP_BE entering state IDLE
1263119480.798541: Setting authentication timeout: 10 sec 0 usec
1263119480.798549: Cancelling scan request
1263119480.798557: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1263119480.798564: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119481.782445: RX EAPOL from 00:90:d0:db:ad:19
1263119481.782491: Setting authentication timeout: 10 sec 0 usec
1263119481.782509: IEEE 802.1X RX: version=1 type=3 length=95
1263119481.782520:   EAPOL-Key type=254
1263119481.782529:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
1263119481.782541:   key_length=32 key_data_length=0
1263119481.782549: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01 1263119481.782560: key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e6 1263119481.782582: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119481.782597:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119481.782608: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1263119481.782619: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119481.782656: State: ASSOCIATED -> 4WAY_HANDSHAKE
1263119481.782668: WPA: RX message 1 of 4-Way Handshake from 00:90:d0:db:ad:19 (ver=1) 1263119481.784817: WPA: Renewed SNonce - hexdump(len=32): 0f 9b 0f ee 6e e8 25 c5 9c 6a b5 ce 78 f8 8a 89 a6 92 9d 6e 1c 93 11 74 8c 14 dc 5e c4 85 36 bd 1263119481.784875: WPA: PTK derivation - A1=00:13:e8:af:56:a9 A2=00:90:d0:db:ad:19
1263119481.784888: WPA: PMK - hexdump(len=32): [REMOVED]
1263119481.784899: WPA: PTK - hexdump(len=64): [REMOVED]
1263119481.784908: WPA: WPA IE for msg 2/4 - 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 02
1263119481.784931: WPA: Sending EAPOL-Key 2/4
1263119483.330463: EAPOL: startWhen --> 0
1263119483.330496: EAPOL: disable timer tick
1263119483.330508: EAPOL: SUPP_PAE entering state CONNECTING
1263119483.330516: EAPOL: enable timer tick
1263119483.330528: EAPOL: txStart
1263119483.330536: WPA: drop TX EAPOL in non-IEEE 802.1X mode (type=1 len=0)
1263119491.786482: Authentication with 00:90:d0:db:ad:19 timed out.
1263119491.786514: BSSID 00:90:d0:db:ad:19 blacklist count incremented to 2
1263119491.786529: wpa_driver_wext_disassociate
1263119491.786641: No keys have been configured - skip key clearing
1263119491.786654: State: 4WAY_HANDSHAKE -> DISCONNECTED
1263119491.786664: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119491.786674: WEXT: Operstate: linkmode=-1, operstate=5
1263119491.786697: EAPOL: External notification - portEnabled=0
1263119491.786706: EAPOL: SUPP_PAE entering state DISCONNECTED
1263119491.786713: EAPOL: SUPP_BE entering state INITIALIZE
1263119491.786724: EAPOL: External notification - portValid=0
1263119491.786734: EAPOL: External notification - EAP success=0
1263119491.786748: Setting scan request: 0 sec 0 usec
1263119491.787106: State: DISCONNECTED -> SCANNING
1263119491.787123: Starting AP scan (broadcast SSID)
1263119491.799096: Scan requested (ret=0) - scan timeout 30 seconds
1263119491.799134: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119491.799148: Wireless event: cmd=0x8b15 len=24
1263119491.799157: Wireless event: new AP: 00:00:00:00:00:00
1263119491.799169: BSSID 00:00:00:00:00:00 blacklist count incremented to 10
1263119491.799186: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119491.799195: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119491.799212: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119491.799224: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119491.799236: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119491.799248: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119491.799339: Driver did not support SIOCSIWENCODEEXT
1263119491.799348: State: SCANNING -> DISCONNECTED
1263119491.799357: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119491.799366: WEXT: Operstate: linkmode=-1, operstate=5
1263119491.799382: EAPOL: External notification - portEnabled=0
1263119491.799391: EAPOL: External notification - portValid=0
1263119491.799399: EAPOL: External notification - EAP success=0
1263119491.799409: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119491.799419: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119494.475672: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.475839: Wireless event: cmd=0x8b19 len=16
1263119494.475934: Received 1485 bytes of scan results (5 BSSes)
1263119494.475950: CTRL-EVENT-SCAN-RESULTS
1263119494.475955: Selecting BSS from priority group 0
1263119494.475960: Try to find WPA-enabled AP
1263119494.475964: 0: 00:90:d0:db:ad:19 ssid='SpeedTouch1' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
1263119494.475971:    skip - blacklisted
1263119494.475975: 1: 00:22:3f:93:e8:f5 ssid='Vermilion' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
1263119494.475981:    skip - SSID mismatch
1263119494.475984: 2: 00:26:f2:0f:04:2c ssid='NETGEARSA' wpa_ie_len=26 rsn_ie_len=24 caps=0x11
1263119494.475991:    skip - SSID mismatch
1263119494.475995: 3: 00:1f:9f:ce:fa:6f ssid='O2wirelessD2D249' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476001:    skip - no WPA/RSN IE
1263119494.476004: 4: 00:1d:68:4f:19:5b ssid='BTHomeHub-4B93' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476010:    skip - no WPA/RSN IE
1263119494.476015: Try to find non-WPA AP
1263119494.476018: 0: 00:90:d0:db:ad:19 ssid='SpeedTouch1' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
1263119494.476024:    skip - blacklisted
1263119494.476028: 1: 00:22:3f:93:e8:f5 ssid='Vermilion' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
1263119494.476034:    skip - SSID mismatch
1263119494.476038: 2: 00:26:f2:0f:04:2c ssid='NETGEARSA' wpa_ie_len=26 rsn_ie_len=24 caps=0x11
1263119494.476043:    skip - SSID mismatch
1263119494.476047: 3: 00:1f:9f:ce:fa:6f ssid='O2wirelessD2D249' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476053:    skip - SSID mismatch
1263119494.476056: 4: 00:1d:68:4f:19:5b ssid='BTHomeHub-4B93' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
1263119494.476062:    skip - SSID mismatch
1263119494.476065: No APs found - clear blacklist and try again
1263119494.476070: Removed BSSID 00:00:00:00:00:00 from blacklist (clear)
1263119494.476075: Removed BSSID 00:90:d0:db:ad:19 from blacklist (clear)
1263119494.476079: Selecting BSS from priority group 0
1263119494.476084: Try to find WPA-enabled AP
1263119494.476088: 0: 00:90:d0:db:ad:19 ssid='SpeedTouch1' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
1263119494.476095:    selected based on WPA IE
1263119494.476098:    selected WPA AP 00:90:d0:db:ad:19 ssid='SpeedTouch1'
1263119494.476103: Try to find non-WPA AP
1263119494.476110: Trying to associate with 00:90:d0:db:ad:19 (SSID='SpeedTouch1' freq=2462 MHz)
1263119494.476115: Cancelling scan request
1263119494.476120: WPA: clearing own WPA/RSN IE
1263119494.476124: Automatic auth_alg selection: 0x1
1263119494.476133: WPA: using IEEE 802.11i/D3.0
1263119494.476137: WPA: Selected cipher suites: group 8 pairwise 8 key_mgmt 2 proto 1 1263119494.476143: WPA: set AP WPA IE - hexdump(len=26): dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 0c 00
1263119494.476154: WPA: clearing AP RSN IE
1263119494.476158: WPA: using GTK TKIP
1263119494.476162: WPA: using PTK TKIP
1263119494.476167: WPA: using KEY_MGMT WPA-PSK
1263119494.476171: WPA: not using MGMT group cipher
1263119494.476176: 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 02
1263119494.476186: No keys have been configured - skip key clearing
1263119494.476190: wpa_driver_wext_set_drop_unencrypted
1263119494.476196: State: DISCONNECTED -> ASSOCIATING
1263119494.476201: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.476205: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.476217: wpa_driver_wext_associate
1263119494.476234: wpa_driver_wext_set_psk
1263119494.476659: Setting authentication timeout: 10 sec 0 usec
1263119494.476670: EAPOL: External notification - EAP success=0
1263119494.476678: EAPOL: External notification - EAP fail=0
1263119494.476683: EAPOL: External notification - portControl=Auto
1263119494.476691: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.476697: Wireless event: cmd=0x8b15 len=24
1263119494.476701: Wireless event: new AP: 00:00:00:00:00:00
1263119494.476707: Added BSSID 00:90:d0:db:ad:19 into blacklist
1263119494.476713: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.476717: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.476726: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.476732: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.476738: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.476744: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.476796: Driver did not support SIOCSIWENCODEEXT
1263119494.476801: State: ASSOCIATING -> DISCONNECTED
1263119494.476805: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.476809: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.476816: EAPOL: External notification - portEnabled=0
1263119494.476821: EAPOL: External notification - portValid=0
1263119494.476825: EAPOL: External notification - EAP success=0
1263119494.476830: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.476834: Wireless event: cmd=0x8b15 len=24
1263119494.476838: Wireless event: new AP: 00:00:00:00:00:00
1263119494.476843: Added BSSID 00:00:00:00:00:00 into blacklist
1263119494.476855: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.476858: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.476865: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.476870: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.476875: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.476880: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.476899: Driver did not support SIOCSIWENCODEEXT
1263119494.476904: State: DISCONNECTED -> DISCONNECTED
1263119494.476907: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.476911: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.476917: EAPOL: External notification - portEnabled=0
1263119494.476921: EAPOL: External notification - portValid=0
1263119494.476925: EAPOL: External notification - EAP success=0
1263119494.476929: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.476934: Wireless event: cmd=0x8b15 len=24
1263119494.476937: Wireless event: new AP: 00:00:00:00:00:00
1263119494.476941: BSSID 00:00:00:00:00:00 blacklist count incremented to 2
1263119494.476947: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.476950: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.476956: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.476962: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.476967: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.476972: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.476990: Driver did not support SIOCSIWENCODEEXT
1263119494.476994: State: DISCONNECTED -> DISCONNECTED
1263119494.476998: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.477002: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.477008: EAPOL: External notification - portEnabled=0
1263119494.477011: EAPOL: External notification - portValid=0
1263119494.477015: EAPOL: External notification - EAP success=0
1263119494.477020: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.477024: Wireless event: cmd=0x8b15 len=24
1263119494.477027: Wireless event: new AP: 00:00:00:00:00:00
1263119494.477032: BSSID 00:00:00:00:00:00 blacklist count incremented to 3
1263119494.477037: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.477041: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.477047: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.490786: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.490802: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.490808: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.490859: Driver did not support SIOCSIWENCODEEXT
1263119494.490865: State: DISCONNECTED -> DISCONNECTED
1263119494.490869: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.490874: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.490887: EAPOL: External notification - portEnabled=0
1263119494.490894: EAPOL: External notification - portValid=0
1263119494.490898: EAPOL: External notification - EAP success=0
1263119494.490906: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.490912: Wireless event: cmd=0x8b15 len=24
1263119494.490916: Wireless event: new AP: 00:00:00:00:00:00
1263119494.490922: BSSID 00:00:00:00:00:00 blacklist count incremented to 4
1263119494.490930: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.490934: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.490941: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.490947: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.490953: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.490958: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.490979: Driver did not support SIOCSIWENCODEEXT
1263119494.490984: State: DISCONNECTED -> DISCONNECTED
1263119494.490988: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.490993: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.490999: EAPOL: External notification - portEnabled=0
1263119494.491003: EAPOL: External notification - portValid=0
1263119494.491007: EAPOL: External notification - EAP success=0
1263119494.491013: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.491018: Wireless event: cmd=0x8b15 len=24
1263119494.491022: Wireless event: new AP: 00:00:00:00:00:00
1263119494.491026: BSSID 00:00:00:00:00:00 blacklist count incremented to 5
1263119494.491032: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.491036: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.491043: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.491049: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.491054: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.491060: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.491080: Driver did not support SIOCSIWENCODEEXT
1263119494.491085: State: DISCONNECTED -> DISCONNECTED
1263119494.491089: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.491093: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.491099: EAPOL: External notification - portEnabled=0
1263119494.491103: EAPOL: External notification - portValid=0
1263119494.491107: EAPOL: External notification - EAP success=0
1263119494.491113: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.491117: Wireless event: cmd=0x8b15 len=24
1263119494.491121: Wireless event: new AP: 00:00:00:00:00:00
1263119494.491126: BSSID 00:00:00:00:00:00 blacklist count incremented to 6
1263119494.491131: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.491135: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.491141: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.491147: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.491153: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.526639: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.526694: Driver did not support SIOCSIWENCODEEXT
1263119494.526699: State: DISCONNECTED -> DISCONNECTED
1263119494.526704: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.526708: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.526721: EAPOL: External notification - portEnabled=0
1263119494.526727: EAPOL: External notification - portValid=0
1263119494.526731: EAPOL: External notification - EAP success=0
1263119494.526739: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.526744: Wireless event: cmd=0x8b15 len=24
1263119494.526748: Wireless event: new AP: 00:00:00:00:00:00
1263119494.526825: BSSID 00:00:00:00:00:00 blacklist count incremented to 7
1263119494.526833: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.526837: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.526843: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.526849: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.526854: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.526860: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.526879: Driver did not support SIOCSIWENCODEEXT
1263119494.526883: State: DISCONNECTED -> DISCONNECTED
1263119494.526887: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.526891: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.526897: EAPOL: External notification - portEnabled=0
1263119494.526901: EAPOL: External notification - portValid=0
1263119494.526905: EAPOL: External notification - EAP success=0
1263119494.526910: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.526914: Wireless event: cmd=0x8b15 len=24
1263119494.526918: Wireless event: new AP: 00:00:00:00:00:00
1263119494.526922: BSSID 00:00:00:00:00:00 blacklist count incremented to 8
1263119494.526927: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.526931: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.526943: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.526949: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.526955: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.526961: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.526981: Driver did not support SIOCSIWENCODEEXT
1263119494.526987: State: DISCONNECTED -> DISCONNECTED
1263119494.526991: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.526995: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.527002: EAPOL: External notification - portEnabled=0
1263119494.527006: EAPOL: External notification - portValid=0
1263119494.527010: EAPOL: External notification - EAP success=0
1263119494.527024: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.527030: Wireless event: cmd=0x8b15 len=24
1263119494.527035: Wireless event: new AP: 00:00:00:00:00:00
1263119494.527039: BSSID 00:00:00:00:00:00 blacklist count incremented to 9
1263119494.527045: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.527049: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.527056: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.527062: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.527067: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.527073: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.527093: Driver did not support SIOCSIWENCODEEXT
1263119494.532915: State: DISCONNECTED -> DISCONNECTED
1263119494.532928: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.532934: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.532947: EAPOL: External notification - portEnabled=0
1263119494.532954: EAPOL: External notification - portValid=0
1263119494.532959: EAPOL: External notification - EAP success=0
1263119494.532968: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.532974: Wireless event: cmd=0x8b06 len=12
1263119494.532980: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.532985: Wireless event: cmd=0x8b15 len=24
1263119494.532989: Wireless event: new AP: 00:00:00:00:00:00
1263119494.532995: BSSID 00:00:00:00:00:00 blacklist count incremented to 10
1263119494.533010: CTRL-EVENT-DISCONNECTED - Disconnect event - remove keys
1263119494.533014: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0 1263119494.533026: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0 1263119494.533033: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0 1263119494.533038: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0 1263119494.533044: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
ioctl[SIOCSIWENCODEEXT]: No such file or directory
1263119494.533087: Driver did not support SIOCSIWENCODEEXT
1263119494.533092: State: DISCONNECTED -> DISCONNECTED
1263119494.533096: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.533101: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.533107: EAPOL: External notification - portEnabled=0
1263119494.533112: EAPOL: External notification - portValid=0
1263119494.533116: EAPOL: External notification - EAP success=0
1263119494.533121: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.533126: Wireless event: cmd=0x8b04 len=16
1263119494.533131: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.533135: Wireless event: cmd=0x8b1a len=27
1263119494.533141: RTM_NEWLINK: operstate=0 ifi_flags=0x1003 ([UP])
1263119494.533145: Wireless event: cmd=0x8c02 len=128
1263119494.533151: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1263119494.533156: Wireless event: cmd=0x8b15 len=24
1263119494.533160: Wireless event: new AP: 00:90:d0:db:ad:19
1263119494.533165: State: DISCONNECTED -> ASSOCIATED
1263119494.533169: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1263119494.533173: WEXT: Operstate: linkmode=-1, operstate=5
1263119494.533182: Associated to a new BSS: BSSID=00:90:d0:db:ad:19
1263119494.533187: No keys have been configured - skip key clearing
1263119494.533192: Associated with 00:90:d0:db:ad:19
1263119494.533196: WPA: Association event - clear replay counter
1263119494.533200: WPA: Clear old PTK
1263119494.533204: EAPOL: External notification - portEnabled=0
1263119494.533208: EAPOL: External notification - portValid=0
1263119494.533212: EAPOL: External notification - EAP success=0
1263119494.533215: EAPOL: External notification - portEnabled=1
1263119494.533219: EAPOL: SUPP_PAE entering state CONNECTING
1263119494.533223: EAPOL: SUPP_BE entering state IDLE
1263119494.533229: Setting authentication timeout: 10 sec 0 usec
1263119494.533235: Cancelling scan request
1263119494.533240: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1263119494.533245: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119494.533255: RX EAPOL from 00:90:d0:db:ad:19
1263119494.533261: Setting authentication timeout: 10 sec 0 usec
1263119494.533267: IEEE 802.1X RX: version=1 type=3 length=95
1263119494.533272:   EAPOL-Key type=254
1263119494.533276:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
1263119494.533281:   key_length=32 key_data_length=0
1263119494.533285: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 00 1263119494.533291: key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e8 1263119494.533300: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119494.533417:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119494.533423: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1263119494.533428: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119494.533440: State: ASSOCIATED -> 4WAY_HANDSHAKE
1263119494.533444: WPA: RX message 1 of 4-Way Handshake from 00:90:d0:db:ad:19 (ver=1) 1263119494.534290: WPA: Renewed SNonce - hexdump(len=32): bd ea 07 bd 57 06 ce 5d 92 92 c4 93 92 4d 07 2b ec 05 a3 37 40 d5 89 b7 6d f9 83 51 d2 7f e8 a9 1263119494.534314: WPA: PTK derivation - A1=00:13:e8:af:56:a9 A2=00:90:d0:db:ad:19
1263119494.534319: WPA: PMK - hexdump(len=32): [REMOVED]
1263119494.534323: WPA: PTK - hexdump(len=64): [REMOVED]
1263119494.534326: WPA: WPA IE for msg 2/4 - 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 02
1263119494.534336: WPA: Sending EAPOL-Key 2/4
1263119495.502077: RX EAPOL from 00:90:d0:db:ad:19
1263119495.502120: IEEE 802.1X RX: version=1 type=3 length=95
1263119495.502132:   EAPOL-Key type=254
1263119495.502140:   key_info 0x89 (ver=1 keyidx=0 rsvd=0 Pairwise Ack)
1263119495.502154:   key_length=32 key_data_length=0
1263119495.502163: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 01 1263119495.502176: key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e8 1263119495.502198: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119495.502213:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119495.502225: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1263119495.502237: key_mic - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119495.502273: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
1263119495.502283: WPA: RX message 1 of 4-Way Handshake from 00:90:d0:db:ad:19 (ver=1) 1263119495.502326: WPA: PTK derivation - A1=00:13:e8:af:56:a9 A2=00:90:d0:db:ad:19
1263119495.502338: WPA: PMK - hexdump(len=32): [REMOVED]
1263119495.502372: WPA: PTK - hexdump(len=64): [REMOVED]
1263119495.502383: WPA: WPA IE for msg 2/4 - 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 02
1263119495.502409: WPA: Sending EAPOL-Key 2/4
1263119495.505083: RX EAPOL from 00:90:d0:db:ad:19
1263119495.505107: IEEE 802.1X RX: version=1 type=3 length=121
1263119495.505118:   EAPOL-Key type=254
1263119495.505126: key_info 0x1c9 (ver=1 keyidx=0 rsvd=0 Pairwise Install Ack MIC)
1263119495.505139:   key_length=32 key_data_length=26
1263119495.505148: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 02 1263119495.505160: key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e8 1263119495.505183: key_iv - hexdump(len=16): 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
1263119495.505198:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119495.505210: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1263119495.505222: key_mic - hexdump(len=16): 1d c1 f4 8d d3 85 3e 3d 9e d4 e0 82 16 53 3c 45
1263119495.505246: State: 4WAY_HANDSHAKE -> 4WAY_HANDSHAKE
1263119495.505256: WPA: RX message 3 of 4-Way Handshake from 00:90:d0:db:ad:19 (ver=1) 1263119495.505267: WPA: IE KeyData - hexdump(len=26): dd 18 00 50 f2 01 01 00 00 50 f2 02 01 00 00 50 f2 02 01 00 00 50 f2 02 0c 00
1263119495.505291: WPA: Sending EAPOL-Key 4/4
1263119495.505323: WPA: Installing PTK to the driver.
1263119495.505333: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
1263119495.505345: wpa_driver_wext_set_key: alg=2 key_idx=0 set_tx=1 seq_len=6 key_len=32
1263119495.505375: State: 4WAY_HANDSHAKE -> GROUP_HANDSHAKE
1263119495.508271: RX EAPOL from 00:90:d0:db:ad:19
1263119495.508292: IEEE 802.1X RX: version=1 type=3 length=127
1263119495.508302:   EAPOL-Key type=254
1263119495.508310: key_info 0x391 (ver=1 keyidx=1 rsvd=0 Group Ack MIC Secure)
1263119495.508322:   key_length=32 key_data_length=32
1263119495.508330: replay_counter - hexdump(len=8): 00 00 00 00 00 00 00 03 1263119495.508343: key_nonce - hexdump(len=32): ac 70 54 85 d0 40 31 76 49 96 72 1f 47 a9 2a 8e ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f 82 1263119495.508364: key_iv - hexdump(len=16): ae ff 62 e1 9d 56 b8 53 d4 2e ff e6 18 4a 0f e9
1263119495.508379:   key_rsc - hexdump(len=8): 00 00 00 00 00 00 00 00
1263119495.508391: key_id (reserved) - hexdump(len=8): 00 00 00 00 00 00 00 00 1263119495.508403: key_mic - hexdump(len=16): ab dd ce a5 86 37 71 ce 8e fa d1 d3 9e 5f 0f a4 1263119495.508426: WPA: RX message 1 of Group Key Handshake from 00:90:d0:db:ad:19 (ver=1)
1263119495.508446: State: GROUP_HANDSHAKE -> GROUP_HANDSHAKE
1263119495.508456: WPA: Group Key - hexdump(len=32): [REMOVED]
1263119495.508465: WPA: Installing GTK to the driver (keyidx=1 tx=0 len=32).
1263119495.508474: WPA: RSC - hexdump(len=6): 00 00 00 00 00 00
1263119495.508485: wpa_driver_wext_set_key: alg=2 key_idx=1 set_tx=0 seq_len=6 key_len=32
1263119495.508505: WPA: Sending EAPOL-Key 2/2
1263119495.508561: WPA: Key negotiation completed with 00:90:d0:db:ad:19 [PTK=TKIP GTK=TKIP]
1263119495.508574: Cancelling authentication timeout
1263119495.508583: Removed BSSID 00:90:d0:db:ad:19 from blacklist
1263119495.508594: State: GROUP_HANDSHAKE -> COMPLETED
1263119495.508608: CTRL-EVENT-CONNECTED - Connection to 00:90:d0:db:ad:19 completed (auth) [id=0 id_str=]
1263119495.508617: wpa_driver_wext_set_operstate: operstate 0->1 (UP)
1263119495.508628: WEXT: Operstate: linkmode=-1, operstate=6
1263119495.509391: EAPOL: External notification - portValid=1
1263119495.509405: EAPOL: External notification - EAP success=1
1263119495.509416: EAPOL: SUPP_PAE entering state AUTHENTICATING
1263119495.509424: EAPOL: SUPP_BE entering state SUCCESS
1263119495.509431: EAP: EAP entering state DISABLED
1263119495.509438: EAPOL: SUPP_PAE entering state AUTHENTICATED
1263119495.509445: EAPOL: SUPP_BE entering state IDLE
1263119495.509453: EAPOL authentication completed successfully
1263119495.509478: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1263119495.509489: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added
1263119497.363490: EAPOL: startWhen --> 0
1263119497.363523: EAPOL: disable timer tick
--- End Message ---

--- Begin Message ---

Hi

On Wednesday 23 May 2012, Stefan Lippers-Hollmann wrote:
> On Wednesday 23 May 2012, Daniel Pocock wrote:
> > Package: wpasupplicant
> > 
> > I notice that wpa_supplicant sometimes tries many times before the 
> > connection eventually succeeds for a particular AP.
> > 
> > When running under network-manager, it sometimes gives up, and I have to 
> > go to the menu and select the AP to make it try again.  I am repeatedly 
> > prompted for the WPA password, even though it has the key already.
> > 
> > When running it from the command line with a simple config file, it 
> > keeps retrying and eventually succeeds.
> > 
> > The system is a Thinkpad X61, lenny amd64.  I am currently trying it 
> > with firmware-iwlwifi 0.22, I have also tried 0.18 (from testing) and 
> > the 0.14+lenny2 package from lenny.
> > 
> > network={
> >     ssid="SpeedTouch1"
> >     key_mgmt=WPA-PSK
> >     proto=WPA
> >     pairwise=TKIP
> >     group=TKIP
> >     psk=<hidden>
> > }
> > 
> > 
> > lspci -v:
> > 
> > 03:00.0 Network controller: Intel Corporation PRO/Wireless 4965 AG or 
> […]
> >     Kernel modules: iwl4965
> […]
> 
> There have been significant changes in iwl4965 and wpasupplicant 
> meanwhile, even though especially pre-iwlagn still appear to be 
> troubled under certain situations, it would be nice to know if this is 
> still an open problem for you in >= wheezy (kernel 3.2 and 
> wpasupplicant 1.0 ideally).
> 
> Please also make sure not to use driver=wext, as nl80211 is the 
> strongly preferred method these days - and the default, if left 
> unconfigured.

No submitter response and given the explanation above it's very likely
that this issue has been fixed meanwhile.

Regards
        Stefan Lippers-Hollmann

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

--- End Message ---

Bug#564568: marked as done (many retries before connection succeeds)

Reply via email to