<span style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">Dear all,</span><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
<br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><span style="color:rgb(0,0,0);font-family:arial;font-size:small">>> Please send debug logs with timestamps (-dt on command line) from both</span><br style="color:rgb(0,0,0);font-family:arial;font-size:small">
<span style="color:rgb(0,0,0);font-family:arial;font-size:small">>> 0.6.10 and 0.7.3.</span>
</div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">> <span style="background-color:transparent">See attachment. (in separate e-mails due to file size limit on ML)</span></div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">Since the logs provided by Koen have been bounced due to size limit on the mailing list, the most relevant parts of the logs are given below.</div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">These logs have been obtained with the ap_scan=1 (default).</div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
<br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">For wpa_supplicant.0.6.10, we see that a RTM_NEWLINK is 1st created and then the AP scan is started. The scan results are returned immediately.</div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">For wpa_supplicant.0.7.3, we see that the scanning is 1st started and then a RTM_NEWLINK is created. The scan results are returned about 8 sec. later.</div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">Apparently, these 2 versions of wpa_supplicant do not initiates scanning in the same way for our configuration. </div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
Would any one have an idea about the cause of this issue? </div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">Has someone already seen similar behaviour? </div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
Thanks in advance for any help or information.</div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
Best regards</div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
Adrien</div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><br></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">
wpa_supplicant.0.6.10 log:</div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><div>146.301062: Initializing interface 'wlan0' conf '/etc/wpa_supplicant.config' driver 'default' ctrl_interface 'N/A' bridge 'N/A'</div>
<div>146.301625: Configuration file '/etc/wpa_supplicant.config' -> '/etc/wpa_supplicant.config'</div><div>146.301781: Reading configuration file '/etc/wpa_supplicant.config'</div><div>146.302656: ctrl_interface='/var/run/wpa_supplicant'</div>
<div>146.302843: ctrl_interface_group='root'</div><div>146.302968: Line: 3 - start of a new network block</div><div>146.303500: ssid - hexdump_ascii(len=16):</div><div> 43 6c 69 63 6b 53 68 61 72 65 5f 6f 75 66 74 69 oufti_oufti</div>
<div>146.303968: scan_ssid=1 (0x1)</div><div>146.304125: proto: 0x2</div><div>146.304281: key_mgmt: 0x2</div><div>146.304593: pairwise: 0x10</div><div>146.304781: group: 0x10</div><div>146.304875: PSK - hexdump(len=32): [REMOVED]</div>
<div>146.306281: priority=20 (0x14)</div><div>146.306656: Priority group 20</div><div>146.306781: id=0 ssid='oufti_oufti'</div><div>146.306875: Initializing interface (2) 'wlan0'</div><div>146.308906: Interface wlan0 set UP - waiting a second for the driver to complete initialization</div>
<div>147.309593: SIOCGIWRANGE: WE(compiled)=22 WE(source)=9 enc_capa=0x0</div><div>147.309843: capabilities: key_mgmt 0x0 enc 0x3 flags 0x0</div><div>147.407750: WEXT: Operstate: linkmode=1, operstate=5</div><div>147.408593: Own MAC address: 00:23:a7:3a:12:61</div>
<div>147.408812: wpa_driver_wext_set_wpa</div><div>147.408968: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0</div><div>147.409250: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0</div>
<div>147.409437: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0</div><div>147.409625: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0</div><div>147.409812: wpa_driver_wext_set_countermeasures</div>
<div>147.409968: wpa_driver_wext_set_drop_unencrypted</div><div>147.440343: RSN: flushing PMKID list in the driver</div><div>147.441437: Setting scan request: 0 sec 100000 usec</div><div>147.441656: EAPOL: SUPP_PAE entering state DISCONNECTED</div>
<div>147.441750: EAPOL: KEY_RX entering state NO_KEY_RECEIVE</div><div>147.441875: EAPOL: SUPP_BE entering state INITIALIZE</div><div>147.441968: EAP: EAP entering state DISABLED</div><div>147.443250: ctrl_interface_group=0 (from group name 'root')</div>
<div>147.444500: Added interface wlan0</div><div>147.445187: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])</div><div>147.445406: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>
147.445625: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])</div>
<div>147.445750: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>147.445843: Wireless event: cmd=0x8b06 len=8</div><div>147.446250: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])</div>
<div>147.446437: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>147.446531: Wireless event: cmd=0x8b1a len=40</div><div>147.446656: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])</div><div>
147.446843: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>147.541937: State: DISCONNECTED -> SCANNING</div><div>147.542125: Starting AP scan (specific SSID)</div><div>147.542281: Scan SSID - hexdump_ascii(len=16):</div>
<div> 43 6c 69 63 6b 53 68 61 72 65 5f 6f 75 66 74 69 oufti_oufti</div><div>147.542593: Trying to get current scan results first without requesting a new scan to speed up initial association</div><div>147.542968: Received 768 bytes of scan results (6 BSSes)</div>
<div>147.543125: New scan results available</div><div>147.543281: Selecting BSS from priority group 20</div><div>147.543375: Try to find WPA-enabled AP</div><div>147.543437: 0: 00:0e:8e:3b:12:73 ssid='oufti_oufti' wpa_ie_len=0 rsn_ie_len=20 caps=0x11</div>
<div>147.543656: selected based on RSN IE</div><div>147.543750: selected WPA AP 00:0e:8e:3b:12:73 ssid='oufti_oufti'</div><div>147.544000: Trying to associate with 00:0e:8e:3b:12:73 (SSID='oufti_oufti' freq=2452 MHz)</div>
<div>147.544125: Cancelling scan request</div><div>147.544218: WPA: clearing own WPA/RSN IE</div></div><div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><br></div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)">log of wpa_supplicant.0.7.3 including the patch provided by Timo at <a href="http://lists.shmoo.com/pipermail/hostap/2012-June/026234.html" target="_blank" style="color:rgb(17,85,204)">http://lists.shmoo.com/pipermail/hostap/2012-June/026234.html</a> (as Koen said previously, this solved the "<span style="color:rgb(80,0,80)">ioctl[SIOCGIWSCAN]: Resource temporarily unavailable</span>" issue):</div>
<div style="color:rgb(34,34,34);font-family:arial,sans-serif;font-size:13px;background-color:rgb(255,255,255)"><div>6346.539343: Initializing interface 'wlan0' conf '/etc/wpa_supplicant.config' driver 'default' ctrl_interface 'N/A' bridge 'N/A'</div>
<div>6346.540125: Configuration file '/etc/wpa_supplicant.config' -> '/etc/wpa_supplicant.config'</div><div>6346.540500: Reading configuration file '/etc/wpa_supplicant.config'</div><div>6346.551500: ctrl_interface='/var/run/wpa_supplicant'</div>
<div>6346.551968: ctrl_interface_group='root'</div><div>6346.552156: Line: 3 - start of a new network block</div><div>6346.552437: ssid - hexdump_ascii(len=16):</div><div> 43 6c 69 63 6b 53 68 61 72 65 5f 6f 75 66 74 69 oufti_oufti</div>
<div>6346.552843: scan_ssid=1 (0x1)</div><div>6346.553218: proto: 0x2</div><div>6346.553406: key_mgmt: 0x2</div><div>6346.553500: pairwise: 0x10</div><div>6346.553593: group: 0x10</div><div>6346.553750: PSK - hexdump(len=32): [REMOVED]</div>
<div>6346.554000: priority=20 (0x14)</div><div>6346.554593: Priority group 20</div><div>6346.554750: id=0 ssid='oufti_oufti'</div><div>6346.557406: SIOCGIWRANGE: WE(compiled)=22 WE(source)=9 enc_capa=0x0</div>
<div>
6346.557562: capabilities: key_mgmt 0x0 enc 0x3 flags 0x0</div><div>6346.656250: netlink: Operstate: linkmode=1, operstate=5</div><div>6346.657343: Own MAC address: 00:23:a7:3a:12:61</div><div>6346.657531: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0</div>
<div>6346.657843: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0</div><div>6346.658062: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0</div><div>6346.658250: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0</div>
<div>6346.658468: wpa_driver_wext_set_countermeasures</div><div>6346.658593: RSN: flushing PMKID list in the driver</div><div>6346.658812: Setting scan request: 0 sec 100000 usec</div><div>6346.955250: EAPOL: SUPP_PAE entering state DISCONNECTED</div>
<div>6346.955750: EAPOL: Supplicant port status: Unauthorized</div><div>6346.955937: EAPOL: KEY_RX entering state NO_KEY_RECEIVE</div><div>6346.956000: EAPOL: SUPP_BE entering state INITIALIZE</div><div>6346.956187: EAP: EAP entering state DISABLED</div>
<div>6346.956250: EAPOL: Supplicant port status: Unauthorized</div><div>6346.956343: EAPOL: Supplicant port status: Unauthorized</div><div>6346.957375: ctrl_interface_group=0 (from group name 'root')</div><div>6346.958531: Added interface wlan0</div>
<div>6346.958937: State: DISCONNECTED -> SCANNING</div><div>6346.959062: Scan SSID - hexdump_ascii(len=16):</div><div> 43 6c 69 63 6b 53 68 61 72 65 5f 6f 75 66 74 69 oufti_oufti</div><div>6346.959687: Starting AP scan for specific SSID(s)</div>
<div>6346.960312: Scan requested (ret=0) - scan timeout 10 seconds</div><div>6346.960750: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])</div><div>6346.960968: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div>
<div>6346.961125: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])</div><div>6346.961250: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>6346.961343: Wireless event: cmd=0x8b06 len=8</div>
<div>6346.961468: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])</div><div>6346.961625: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>6346.961718: Wireless event: cmd=0x8b1a len=40</div>
<div>6346.961843: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])</div><div>6346.961968: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>6347.957468: EAPOL: disable timer tick</div><div>6347.957687: EAPOL: Supplicant port status: Unauthorized</div>
<div>6355.381531: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])</div><div>6355.381781: RTM_NEWLINK, IFLA_IFNAME: Interface 'wlan0' added</div><div>6355.381875: Wireless event: cmd=0x8b19 len=8</div>
<div>
6355.382312: Received 652 bytes of scan results (5 BSSes)</div><div>6355.382593: BSS: Start scan result update 1</div><div>6355.382718: BSS: Add new id 0 BSSID 00:0e:8e:3b:12:73 SSID 'oufti_oufti'</div><div>6355.382937: BSS: Add new id 1 BSSID c0:c1:c0:32:44:80 SSID 'CiscoG2436'</div>
<div>6355.383187: BSS: Add new id 2 BSSID 00:0e:8e:3b:10:4c SSID 'oufti-1143ALFA30'</div><div>6355.383406: BSS: Add new id 3 BSSID c0:c1:c0:32:34:84 SSID 'oufti Cisco'</div><div>6355.383593: BSS: Add new id 4 BSSID 00:0e:8e:3a:47:c1 SSID 'oufti_test_beaconint_100'</div>
<div>6355.383812: New scan results available</div><div>6355.383937: Selecting BSS from priority group 20</div><div>6355.384093: Try to find WPA-enabled AP</div><div>6355.384187: 0: 00:0e:8e:3b:12:73 ssid='oufti_oufti' wpa_ie_len=0 rsn_ie_len=20 caps=0x11</div>
<div>6355.384406: selected based on RSN IE</div><div>6355.384531: selected WPA AP 00:0e:8e:3b:12:73 ssid='oufti_oufti'</div><div>6355.384843: Trying to associate with 00:0e:8e:3b:12:73 (SSID='oufti_oufti' freq=2452 MHz)</div>
<div>6355.385000: FT: Stored MDIE and FTIE from (Re)Association Response - hexdump(len=0):</div><div>6355.385156: Cancelling scan request</div></div><br>