rendered paste body946684816.818952: dbus: Register D-Bus object '/fi/w1/wpa_supplicant1'
946684816.820690: Providing DBus service 'fi.w1.wpa_supplicant1'.
946684816.822280: Providing DBus service 'fi.epitest.hostap.WPASupplicant'.
946684816.850990: Initializing interface 'ra0' conf 'N/A' driver 'wext' ctrl_interface 'N/A' bridge 'N/A'
946684816.851453: SIOCGIWRANGE: WE(compiled)=22 WE(source)=14 enc_capa=0xf
946684816.851494: capabilities: key_mgmt 0xf enc 0xf flags 0x0
946684816.851653: netlink: Operstate: linkmode=1, operstate=5
946684816.851991: Own MAC address: 40:61:86:a8:46:a5
946684816.852021: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
946684816.852705: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
946684816.853452: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
946684816.854078: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
946684816.855463: wpa_driver_wext_set_countermeasures
946684816.855548: RSN: flushing PMKID list in the driver
946684816.855586: State: DISCONNECTED -> INACTIVE
946684816.855717: WPS: UUID based on MAC address - hexdump(len=16): 6b 34 26 3c 2c 64 55 93 b9 f7 b7 07 0f a8 a4 38
946684816.908528: EAPOL: SUPP_PAE entering state DISCONNECTED
946684816.908620: EAPOL: Supplicant port status: Unauthorized
946684816.908647: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
946684816.908664: EAPOL: SUPP_BE entering state INITIALIZE
946684816.908684: EAP: EAP entering state DISABLED
946684816.908701: EAPOL: Supplicant port status: Unauthorized
946684816.908720: EAPOL: Supplicant port status: Unauthorized
946684816.908780: dbus: Register interface object '/fi/w1/wpa_supplicant1/Interfaces/1'
946684816.916688: Added interface ra0
946684816.917418: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684816.917470: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684816.917503: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
946684816.917527: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684816.917546: Wireless event: cmd=0x8b06 len=8
946684816.925276: Setting scan request: 0 sec 0 usec
946684816.925452: State: INACTIVE -> SCANNING
946684816.925610: Starting AP scan for wildcard SSID
946684816.925990: Scan requested (ret=0) - scan timeout 5 seconds
946684816.931365: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684816.933078: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/0'
946684816.936616: scan_ssid=1 (0x1)
946684816.936721: ssid - hexdump_ascii(len=8):
41 4d 49 4e 4f 43 4f 4d AMINOCOM
946684816.936794: BSSID - hexdump(len=6): ff ff ff ff ff ff
946684816.939527: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/1'
946684816.944244: scan_ssid=1 (0x1)
946684816.944362: ssid - hexdump_ascii(len=7):
77 69 62 62 6c 65 32 wibble2
946684816.944428: BSSID - hexdump(len=6): ff ff ff ff ff ff
946684816.945743: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/2'
946684816.948220: scan_ssid=1 (0x1)
946684816.948350: ssid - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684816.948447: BSSID - hexdump(len=6): ff ff ff ff ff ff
946684817.912169: EAPOL: disable timer tick
946684817.912268: EAPOL: Supplicant port status: Unauthorized
946684821.931675: Scan timeout - try to get results
946684821.933208: Received 798 bytes of scan results (7 BSSes)
946684821.933295: BSS: Start scan result update 1
946684821.933321: BSS: Add new id 0 BSSID 00:25:3c:ec:36:0b SSID 'BT Fusion-7980'
946684821.933356: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/0'
946684821.934562: BSS: Add new id 1 BSSID c0:3f:0e:7c:d5:76 SSID 'ICENI'
946684821.934616: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/1'
946684821.935750: BSS: Add new id 2 BSSID 00:0f:b5:dd:28:3d SSID 'wibble2'
946684821.935849: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/2'
946684821.936816: BSS: Add new id 3 BSSID 00:24:56:b1:ee:d1 SSID 'wibble1'
946684821.936862: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/3'
946684821.937789: BSS: Add new id 4 BSSID 00:25:3c:ec:36:09 SSID 'BTBusinessHub-980'
946684821.937830: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/4'
946684821.938791: BSS: Add new id 5 BSSID 00:1f:33:86:12:ca SSID 'NETGEAR'
946684821.938830: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/5'
946684821.939875: BSS: Add new id 6 BSSID 00:1a:70:31:c9:79 SSID 'wibble4'
946684821.939935: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/6'
946684821.940874: New scan results available
946684821.948347: Selecting BSS from priority group 0
946684821.948440: Try to find WPA-enabled AP
946684821.948462: 0: 00:25:3c:ec:36:0b ssid='BT Fusion-7980' wpa_ie_len=22 rsn_ie_len=0 caps=0x11
946684821.948489: skip - disabled
946684821.948506: skip - disabled
946684821.948522: skip - disabled
946684821.948539: 1: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684821.948562: skip - disabled
946684821.948578: skip - disabled
946684821.948593: skip - disabled
946684821.948609: 2: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.948632: skip - no WPA/RSN IE
946684821.948649: 3: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.948672: skip - no WPA/RSN IE
946684821.948688: 4: 00:25:3c:ec:36:09 ssid='BTBusinessHub-980' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.948711: skip - no WPA/RSN IE
946684821.948727: 5: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.948749: skip - no WPA/RSN IE
946684821.948766: 6: 00:1a:70:31:c9:79 ssid='wibble4' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.948788: skip - no WPA/RSN IE
946684821.948804: Try to find non-WPA AP
946684821.948820: 0: 00:25:3c:ec:36:0b ssid='BT Fusion-7980' wpa_ie_len=22 rsn_ie_len=0 caps=0x11
946684821.948843: skip - disabled
946684821.948859: skip - disabled
946684821.948874: skip - disabled
946684821.948891: 1: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684821.948913: skip - disabled
946684821.948929: skip - disabled
946684821.948944: skip - disabled
946684821.948961: 2: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.948983: skip - disabled
946684821.948999: skip - disabled
946684821.949015: skip - disabled
946684821.949031: 3: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.949053: skip - disabled
946684821.949069: skip - disabled
946684821.949085: skip - disabled
946684821.949101: 4: 00:25:3c:ec:36:09 ssid='BTBusinessHub-980' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.949124: skip - disabled
946684821.949140: skip - disabled
946684821.949155: skip - disabled
946684821.949172: 5: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.949194: skip - disabled
946684821.949210: skip - disabled
946684821.949225: skip - disabled
946684821.949242: 6: 00:1a:70:31:c9:79 ssid='wibble4' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684821.949264: skip - disabled
946684821.949280: skip - disabled
946684821.949295: skip - disabled
946684821.949314: No suitable network found
946684821.949333: State: SCANNING -> INACTIVE
946684821.950374: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684822.104685: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/3'
946684822.106951: scan_ssid=1 (0x1)
946684822.107069: ssid - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684822.107165: auth_alg: 0x1
946684822.107197: key_mgmt: 0x4
946684822.107280: wep_key0 - hexdump(len=5): [REMOVED]
946684822.107316: wep_tx_keyidx=0 (0x0)
946684822.109061: Setting scan request: 0 sec 0 usec
946684822.109298: State: INACTIVE -> SCANNING
946684822.109457: Scan SSID - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684822.109546: Starting AP scan for specific SSID(s)
946684822.109933: Scan requested (ret=0) - scan timeout 5 seconds
946684822.115726: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/Networks/3
946684822.116046: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684827.111941: Scan timeout - try to get results
946684827.112365: Failed to get scan results
946684827.112395: Failed to get scan results - try scanning again
946684827.112422: Setting scan request: 1 sec 0 usec
946684827.119985: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684828.026246: dbus: Unregister network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/3'
946684828.027693: No keys have been configured - skip key clearing
946684828.027758: State: SCANNING -> DISCONNECTED
946684828.027782: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684828.027804: netlink: Operstate: linkmode=-1, operstate=5
946684828.028071: EAPOL: External notification - portEnabled=0
946684828.028109: EAPOL: Supplicant port status: Unauthorized
946684828.028134: EAPOL: External notification - portValid=0
946684828.028152: EAPOL: Supplicant port status: Unauthorized
946684828.036190: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684828.116033: State: DISCONNECTED -> DISCONNECTED
946684828.116212: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684828.116235: netlink: Operstate: linkmode=-1, operstate=5
946684828.129885: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/3'
946684828.161323: scan_ssid=1 (0x1)
946684828.161452: ssid - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684828.161548: auth_alg: 0x1
946684828.161579: key_mgmt: 0x4
946684828.161741: wep_key0 - hexdump(len=5): [REMOVED]
946684828.161781: wep_tx_keyidx=0 (0x0)
946684828.171300: Setting scan request: 0 sec 0 usec
946684828.171555: State: DISCONNECTED -> SCANNING
946684828.171833: Starting AP scan for wildcard SSID
946684828.172021: Scan requested (ret=0) - scan timeout 5 seconds
946684828.179947: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/Networks/3
946684828.180339: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684833.180234: Scan timeout - try to get results
946684833.180639: Failed to get scan results
946684833.180670: Failed to get scan results - try scanning again
946684833.180697: Setting scan request: 1 sec 0 usec
946684833.188196: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684834.184253: Scan SSID - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684834.184416: Starting AP scan for specific SSID(s)
946684834.185902: Scan requested (ret=0) - scan timeout 5 seconds
946684834.192250: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684839.188701: Scan timeout - try to get results
946684839.189112: Failed to get scan results
946684839.189143: Failed to get scan results - try scanning again
946684839.189171: Setting scan request: 1 sec 0 usec
946684839.196596: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684840.192673: Starting AP scan for wildcard SSID
946684840.192973: Scan requested (ret=0) - scan timeout 5 seconds
946684840.200627: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684845.200850: Scan timeout - try to get results
946684845.201207: Received 695 bytes of scan results (6 BSSes)
946684845.201251: BSS: Start scan result update 2
946684845.201358: New scan results available
946684845.201505: Selecting BSS from priority group 0
946684845.201530: Try to find WPA-enabled AP
946684845.201548: 0: 00:25:3c:ec:36:0b ssid='BT Fusion-7980' wpa_ie_len=22 rsn_ie_len=0 caps=0x11
946684845.201575: skip - disabled
946684845.201592: skip - disabled
946684845.201607: skip - disabled
946684845.201624: skip - SSID mismatch
946684845.201641: 1: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684845.201664: skip - disabled
946684845.201680: skip - disabled
946684845.201696: skip - disabled
946684845.201711: skip - SSID mismatch
946684845.201728: 2: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.201751: skip - no WPA/RSN IE
946684845.201768: 3: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.201790: skip - no WPA/RSN IE
946684845.201807: 4: 00:25:3c:ec:36:09 ssid='BTBusinessHub-980' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.201830: skip - no WPA/RSN IE
946684845.201847: 5: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.201869: skip - no WPA/RSN IE
946684845.201885: Try to find non-WPA AP
946684845.201902: 0: 00:25:3c:ec:36:0b ssid='BT Fusion-7980' wpa_ie_len=22 rsn_ie_len=0 caps=0x11
946684845.201924: skip - disabled
946684845.201940: skip - disabled
946684845.201956: skip - disabled
946684845.201973: skip - SSID mismatch
946684845.201989: 1: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684845.202012: skip - disabled
946684845.202028: skip - disabled
946684845.202043: skip - disabled
946684845.202059: skip - SSID mismatch
946684845.202076: 2: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.202098: skip - disabled
946684845.202114: skip - disabled
946684845.202130: skip - disabled
946684845.202146: skip - SSID mismatch
946684845.202163: 3: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.202185: skip - disabled
946684845.202201: skip - disabled
946684845.202217: skip - disabled
946684845.202233: skip - SSID mismatch
946684845.202249: 4: 00:25:3c:ec:36:09 ssid='BTBusinessHub-980' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684845.202272: skip - disabled
946684845.202288: skip - disabled
946684845.202304: skip - disabled
946684845.202321: selected non-WPA AP 00:25:3c:ec:36:09 ssid='BTBusinessHub-980'
946684845.202360: Trying to associate with 00:25:3c:ec:36:09 (SSID='BTBusinessHub-980' freq=2412 MHz)
946684845.202383: Cancelling scan request
946684845.202402: WPA: clearing own WPA/RSN IE
946684845.202419: Automatic auth_alg selection: 0x1
946684845.202436: Overriding auth_alg selection: 0x1
946684845.202455: WPA: clearing AP WPA IE
946684845.202472: WPA: clearing AP RSN IE
946684845.202488: WPA: clearing own WPA/RSN IE
946684845.202507: No keys have been configured - skip key clearing
946684845.202526: wpa_driver_wext_set_key: alg=1 key_idx=0 set_tx=1 seq_len=0 key_len=5
946684845.202560: State: SCANNING -> ASSOCIATING
946684845.202582: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684845.202602: netlink: Operstate: linkmode=-1, operstate=5
946684845.202783: wpa_driver_wext_associate
946684845.202807: wpa_driver_wext_set_drop_unencrypted
946684845.208666: wpa_driver_wext_set_psk
946684845.209163: Association request to the driver failed
946684845.209318: Setting authentication timeout: 5 sec 0 usec
946684845.209553: EAPOL: External notification - EAP success=0
946684845.209618: EAPOL: Supplicant port status: Unauthorized
946684845.209644: EAPOL: External notification - EAP fail=0
946684845.209662: EAPOL: Supplicant port status: Unauthorized
946684845.209681: EAPOL: External notification - portControl=ForceAuthorized
946684845.209699: EAPOL: Supplicant port status: Unauthorized
946684845.209770: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684845.211522: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684845.211608: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684845.211634: Wireless event: cmd=0x8b06 len=8
946684845.211659: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684845.211682: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684845.211701: Wireless event: cmd=0x8b04 len=12
946684845.211724: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684845.211745: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684845.211764: Wireless event: cmd=0x8b1a len=25
946684845.211805: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/1
946684845.212282: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/2
946684845.215204: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/3
946684846.134775: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684846.134881: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684846.134907: Wireless event: cmd=0x8c07 len=8
946684846.134928: AssocReq IE wireless event - hexdump(len=0):
946684846.135812: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684846.135851: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684846.135874: Wireless event: cmd=0x8b15 len=20
946684846.135893: Wireless event: new AP: 00:25:3c:ec:36:09
946684846.135914: Association info event
946684846.135931: req_ies - hexdump(len=0):
946684846.135950: WPA: clearing own WPA/RSN IE
946684846.135969: State: ASSOCIATING -> ASSOCIATED
946684846.136259: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684846.136287: netlink: Operstate: linkmode=-1, operstate=5
946684846.136585: Associated to a new BSS: BSSID=00:25:3c:ec:36:09
946684846.136636: Associated with 00:25:3c:ec:36:09
946684846.136658: WPA: Association event - clear replay counter
946684846.136689: WPA: Clear old PTK
946684846.136707: EAPOL: External notification - portEnabled=0
946684846.136727: EAPOL: Supplicant port status: Unauthorized
946684846.136750: EAPOL: External notification - portValid=0
946684846.136767: EAPOL: Supplicant port status: Unauthorized
946684846.136786: EAPOL: External notification - portEnabled=1
946684846.136804: EAPOL: SUPP_PAE entering state S_FORCE_AUTH
946684846.136820: EAPOL: Supplicant port status: Authorized
946684846.136837: EAPOL: SUPP_BE entering state IDLE
946684846.138514: Cancelling authentication timeout
946684846.138542: State: ASSOCIATED -> COMPLETED
946684846.138713: CTRL-EVENT-CONNECTED - Connection to 00:25:3c:ec:36:09 completed (auth) [id=3 id_str=]
946684846.138738: wpa_driver_wext_set_operstate: operstate 0->1 (UP)
946684846.138760: netlink: Operstate: linkmode=-1, operstate=6
946684846.140088: Cancelling scan request
946684846.140187: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
946684846.140213: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684846.144962: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684925.545443: RTM_NEWLINK: operstate=1 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
946684925.546484: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684925.546541: Wireless event: cmd=0x8b15 len=20
946684925.546567: Wireless event: new AP: 00:00:00:00:00:00
946684925.546595: Disassociation notification
946684925.546631: Setting scan request: 0 sec 100000 usec
946684925.546704: Added BSSID 00:25:3c:ec:36:09 into blacklist
946684925.546740: CTRL-EVENT-DISCONNECTED bssid=00:25:3c:ec:36:09 reason=0
946684925.546763: State: COMPLETED -> DISCONNECTED
946684925.546786: wpa_driver_wext_set_operstate: operstate 1->0 (DORMANT)
946684925.546810: netlink: Operstate: linkmode=-1, operstate=5
946684925.548061: EAPOL: External notification - portEnabled=0
946684925.548289: EAPOL: SUPP_PAE entering state DISCONNECTED
946684925.548353: EAPOL: Supplicant port status: Unauthorized
946684925.548647: EAPOL: SUPP_BE entering state INITIALIZE
946684925.548709: EAPOL: Supplicant port status: Unauthorized
946684925.548766: EAPOL: External notification - portValid=0
946684925.550229: EAPOL: Supplicant port status: Unauthorized
946684925.550646: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
946684925.550774: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684925.550895: Wireless event: cmd=0x8b15 len=20
946684925.551003: Wireless event: new AP: 00:00:00:00:00:00
946684925.551111: Disassociation notification
946684925.551222: Added BSSID 00:00:00:00:00:00 into blacklist
946684925.551352: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=0
946684925.551463: State: DISCONNECTED -> DISCONNECTED
946684925.551524: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684925.551632: netlink: Operstate: linkmode=-1, operstate=5
946684925.551768: EAPOL: External notification - portEnabled=0
946684925.551879: EAPOL: Supplicant port status: Unauthorized
946684925.551984: EAPOL: External notification - portValid=0
946684925.552090: EAPOL: Supplicant port status: Unauthorized
946684925.552160: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684925.552274: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684925.553537: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684925.606436: dbus: Unregister network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/3'
946684925.606658: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
946684925.641242: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
946684925.642962: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
946684925.644335: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
946684925.647222: State: DISCONNECTED -> DISCONNECTED
946684925.647307: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684925.647334: netlink: Operstate: linkmode=-1, operstate=5
946684925.647383: EAPOL: External notification - portEnabled=0
946684925.647410: EAPOL: Supplicant port status: Unauthorized
946684925.647435: EAPOL: External notification - portValid=0
946684925.647456: EAPOL: Supplicant port status: Unauthorized
946684925.647664: No enabled networks - do not scan
946684925.647694: State: DISCONNECTED -> INACTIVE
946684925.653018: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684925.654398: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/3'
946684925.666866: scan_ssid=1 (0x1)
946684925.667092: ssid - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684925.667310: auth_alg: 0x1
946684925.667381: key_mgmt: 0x4
946684925.667468: wep_key0 - hexdump(len=5): [REMOVED]
946684925.667543: wep_tx_keyidx=0 (0x0)
946684925.669813: Setting scan request: 0 sec 0 usec
946684925.676520: State: INACTIVE -> SCANNING
946684925.676857: Scan SSID - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684925.681763: Starting AP scan for specific SSID(s)
946684925.682054: Scan requested (ret=0) - scan timeout 5 seconds
946684925.682153: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/Networks/3
946684925.682642: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684930.689458: Scan timeout - try to get results
946684930.690007: Failed to get scan results
946684930.690049: Failed to get scan results - try scanning again
946684930.690172: Setting scan request: 1 sec 0 usec
946684930.697464: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684931.693509: Starting AP scan for wildcard SSID
946684931.693811: Scan requested (ret=0) - scan timeout 5 seconds
946684931.701520: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684936.701774: Scan timeout - try to get results
946684936.702206: Failed to get scan results
946684936.702241: Failed to get scan results - try scanning again
946684936.702272: Setting scan request: 1 sec 0 usec
946684936.709787: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684937.705839: Scan SSID - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684937.706027: Starting AP scan for specific SSID(s)
946684937.706248: Scan requested (ret=0) - scan timeout 5 seconds
946684937.713841: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684942.714153: Scan timeout - try to get results
946684942.714581: Failed to get scan results
946684942.714616: Failed to get scan results - try scanning again
946684942.714647: Setting scan request: 1 sec 0 usec
946684942.722115: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684943.718156: Starting AP scan for wildcard SSID
946684943.718451: Scan requested (ret=0) - scan timeout 5 seconds
946684943.726159: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684948.722497: Scan timeout - try to get results
946684948.722854: Received 543 bytes of scan results (5 BSSes)
946684948.722902: BSS: Start scan result update 3
946684948.723020: New scan results available
946684948.723172: Selecting BSS from priority group 0
946684948.725787: Try to find WPA-enabled AP
946684948.725814: 0: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684948.725846: skip - disabled
946684948.725866: skip - disabled
946684948.725886: skip - disabled
946684948.725905: skip - SSID mismatch
946684948.725925: 1: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.725952: skip - no WPA/RSN IE
946684948.725973: 2: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.725998: skip - no WPA/RSN IE
946684948.726019: 3: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726045: skip - no WPA/RSN IE
946684948.726065: 4: 00:1a:70:31:c9:79 ssid='wibble4' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726090: skip - no WPA/RSN IE
946684948.726109: Try to find non-WPA AP
946684948.726129: 0: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684948.726155: skip - disabled
946684948.726174: skip - disabled
946684948.726193: skip - disabled
946684948.726212: skip - SSID mismatch
946684948.726232: 1: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726258: skip - disabled
946684948.726277: skip - disabled
946684948.726296: skip - disabled
946684948.726314: skip - SSID mismatch
946684948.726334: 2: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726360: skip - disabled
946684948.726431: skip - disabled
946684948.726458: skip - disabled
946684948.726477: skip - SSID mismatch
946684948.726497: 3: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726559: skip - disabled
946684948.726580: skip - disabled
946684948.726598: skip - disabled
946684948.726617: skip - SSID mismatch
946684948.726637: 4: 00:1a:70:31:c9:79 ssid='wibble4' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726663: skip - disabled
946684948.726682: skip - disabled
946684948.726700: skip - disabled
946684948.726719: skip - SSID mismatch
946684948.726738: No APs found - clear blacklist and try again
946684948.726757: Removed BSSID 00:00:00:00:00:00 from blacklist (clear)
946684948.726781: Removed BSSID 00:25:3c:ec:36:09 from blacklist (clear)
946684948.726805: Selecting BSS from priority group 0
946684948.726824: Try to find WPA-enabled AP
946684948.726844: 0: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684948.726869: skip - disabled
946684948.726888: skip - disabled
946684948.726906: skip - disabled
946684948.726924: skip - SSID mismatch
946684948.726944: 1: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.726969: skip - no WPA/RSN IE
946684948.726989: 2: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727014: skip - no WPA/RSN IE
946684948.727034: 3: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727059: skip - no WPA/RSN IE
946684948.727079: 4: 00:1a:70:31:c9:79 ssid='wibble4' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727104: skip - no WPA/RSN IE
946684948.727123: Try to find non-WPA AP
946684948.727142: 0: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684948.727167: skip - disabled
946684948.727186: skip - disabled
946684948.727204: skip - disabled
946684948.727222: skip - SSID mismatch
946684948.727241: 1: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727267: skip - disabled
946684948.727285: skip - disabled
946684948.727304: skip - disabled
946684948.727322: skip - SSID mismatch
946684948.727341: 2: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727366: skip - disabled
946684948.727384: skip - disabled
946684948.727403: skip - disabled
946684948.727421: skip - SSID mismatch
946684948.727440: 3: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727465: skip - disabled
946684948.727483: skip - disabled
946684948.727502: skip - disabled
946684948.727520: skip - SSID mismatch
946684948.727539: 4: 00:1a:70:31:c9:79 ssid='wibble4' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684948.727564: skip - disabled
946684948.727583: skip - disabled
946684948.727601: skip - disabled
946684948.727619: skip - SSID mismatch
946684948.727640: No suitable network found
946684948.727674: Setting scan request: 5 sec 0 usec
946684948.730058: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684948.733406: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/2
946684948.734294: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/3
946684948.735462: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/6
946684953.734720: Scan SSID - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684953.734918: Starting AP scan for specific SSID(s)
946684953.735137: Scan requested (ret=0) - scan timeout 5 seconds
946684953.742737: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684958.739123: Scan timeout - try to get results
946684958.739678: Failed to get scan results
946684958.739719: Failed to get scan results - try scanning again
946684958.739750: Setting scan request: 1 sec 0 usec
946684958.747192: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684959.743175: Starting AP scan for wildcard SSID
946684959.743484: Scan requested (ret=0) - scan timeout 5 seconds
946684959.751029: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684964.751337: Scan timeout - try to get results
946684964.751772: Failed to get scan results
946684964.751809: Failed to get scan results - try scanning again
946684964.751842: Setting scan request: 1 sec 0 usec
946684964.759311: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684965.755351: Scan SSID - hexdump_ascii(len=17):
42 54 42 75 73 69 6e 65 73 73 48 75 62 2d 39 38 BTBusinessHub-98
30 0
946684965.755804: Starting AP scan for specific SSID(s)
946684965.756028: Scan requested (ret=0) - scan timeout 5 seconds
946684965.763352: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684970.763619: Scan timeout - try to get results
946684970.764058: Failed to get scan results
946684970.764096: Failed to get scan results - try scanning again
946684970.764129: Setting scan request: 1 sec 0 usec
946684970.771765: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684971.767759: Starting AP scan for wildcard SSID
946684971.768064: Scan requested (ret=0) - scan timeout 5 seconds
946684971.775675: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684976.775938: Scan timeout - try to get results
946684976.776470: Received 440 bytes of scan results (4 BSSes)
946684976.776526: BSS: Start scan result update 4
946684976.776642: BSS: Expire BSS 0 due to no match in scan
946684976.776670: BSS: Remove id 0 BSSID 00:25:3c:ec:36:0b SSID 'BT Fusion-7980'
946684976.776704: dbus: Unregister BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/0'
946684976.776835: BSS: Expire BSS 4 due to no match in scan
946684976.776866: BSS: Remove id 4 BSSID 00:25:3c:ec:36:09 SSID 'BTBusinessHub-980'
946684976.776897: dbus: Unregister BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/4'
946684976.777117: New scan results available
946684976.777264: Selecting BSS from priority group 0
946684976.777294: Try to find WPA-enabled AP
946684976.777317: 0: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684976.777347: skip - disabled
946684976.777369: skip - disabled
946684976.777390: skip - disabled
946684976.777411: skip - SSID mismatch
946684976.777433: 1: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684976.777462: skip - no WPA/RSN IE
946684976.777484: 2: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684976.777513: skip - no WPA/RSN IE
946684976.777535: 3: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684976.777562: skip - no WPA/RSN IE
946684976.777583: Try to find non-WPA AP
946684976.777605: 0: c0:3f:0e:7c:d5:76 ssid='ICENI' wpa_ie_len=0 rsn_ie_len=20 caps=0x11
946684976.777633: skip - disabled
946684976.777654: skip - disabled
946684976.777674: skip - disabled
946684976.777695: skip - SSID mismatch
946684976.777717: 1: 00:0f:b5:dd:28:3d ssid='wibble2' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684976.777745: skip - disabled
946684976.777766: skip - disabled
946684976.777787: skip - disabled
946684976.777807: skip - SSID mismatch
946684976.777829: 2: 00:24:56:b1:ee:d1 ssid='wibble1' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684976.777858: skip - disabled
946684976.777878: skip - disabled
946684976.777899: skip - disabled
946684976.777920: skip - SSID mismatch
946684976.777942: 3: 00:1f:33:86:12:ca ssid='NETGEAR' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
946684976.777970: skip - disabled
946684976.778031: skip - disabled
946684976.778051: skip - disabled
946684976.778070: skip - SSID mismatch
946684976.778091: No suitable network found
946684976.778124: Setting scan request: 5 sec 0 usec
946684976.782776: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684976.783404: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/1
946684976.783625: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/2
946684976.783823: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/3
946684976.786194: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1/BSSs/5
946684977.089205: dbus: Unregister network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/3'
946684981.784213: No enabled networks - do not scan
946684981.784324: State: SCANNING -> INACTIVE
946684981.792214: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1
946684996.992616: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684996.992743: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684996.992776: Wireless event: cmd=0x8c07 len=8
946684996.992803: AssocReq IE wireless event - hexdump(len=0):
946684996.993832: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684996.993893: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684996.993923: Wireless event: cmd=0x8b15 len=20
946684996.993948: Wireless event: new AP: 00:25:3c:ec:36:09
946684996.993975: Association info event
946684996.993997: req_ies - hexdump(len=0):
946684996.994022: WPA: clearing own WPA/RSN IE
946684996.994046: State: INACTIVE -> ASSOCIATED
946684996.994071: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684996.994096: netlink: Operstate: linkmode=-1, operstate=5
946684996.994390: Associated to a new BSS: BSSID=00:25:3c:ec:36:09
946684996.994438: Select network based on association information
946684996.994476: No network configuration found for the current AP
946684996.994502: wpa_driver_wext_disassociate
946684996.996661: No keys have been configured - skip key clearing
946684996.996818: State: ASSOCIATED -> DISCONNECTED
946684996.996882: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684996.996945: netlink: Operstate: linkmode=-1, operstate=5
946684996.997800: EAPOL: External notification - portEnabled=0
946684996.998869: EAPOL: Supplicant port status: Unauthorized
946684996.998975: EAPOL: External notification - portValid=0
946684996.999035: EAPOL: Supplicant port status: Unauthorized
946684996.999119: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
946684996.999187: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
946684996.999251: Wireless event: cmd=0x8b15 len=20
946684996.999311: Wireless event: new AP: 00:00:00:00:00:00
946684996.999374: Disassociation notification
946684996.999437: Added BSSID 00:00:00:00:00:00 into blacklist
946684996.999517: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=0
946684996.999578: State: DISCONNECTED -> DISCONNECTED
946684996.999741: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
946684996.999804: netlink: Operstate: linkmode=-1, operstate=5
946684996.999887: EAPOL: External notification - portEnabled=0
946684996.999948: EAPOL: Supplicant port status: Unauthorized
946684997.000009: EAPOL: External notification - portValid=0
946684997.000067: EAPOL: Supplicant port status: Unauthorized
946684997.000151: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object /fi/w1/wpa_supplicant1/Interfaces/1