Re: Ad-Hoc network creation took too long, failing activation

[poma <pomidorabelisima gmail com>]

- /etc/wpa_supplicant/wpa_supplicant.conf:

ctrl_interface=/var/run/wpa_supplicant
ctrl_interface_group=wheel

network={
    ssid="MT7601U(mt7601Usta)"
    mode=1
    psk="passphrase"
}


- wpa_supplicant -ddtu -i ra0 -c /etc/wpa_supplicant/wpa_supplicant.conf -D wext

1413068399.834805: wpa_supplicant v2.0
1413068399.835376: random: Trying to read entropy from /dev/random
1413068399.837104: dbus: Register D-Bus object '/fi/w1/wpa_supplicant1'
1413068399.838144: Providing DBus service 'fi.w1.wpa_supplicant1'.
1413068399.838572: Providing DBus service 'fi.epitest.hostap.WPASupplicant'.
1413068399.838586: Successfully initialized wpa_supplicant
1413068399.838593: Initializing interface 'ra0' conf '/etc/wpa_supplicant/wpa_supplicant.conf' driver 'wext' 
ctrl_interface 'N/A' bridge 'N/A'
1413068399.838601: Configuration file '/etc/wpa_supplicant/wpa_supplicant.conf' -> 
'/etc/wpa_supplicant/wpa_supplicant.conf'
1413068399.838614: Reading configuration file '/etc/wpa_supplicant/wpa_supplicant.conf'
1413068399.853312: ctrl_interface='/var/run/wpa_supplicant'
1413068399.853374: ctrl_interface_group='wheel'
1413068399.853396: Line: 4 - start of a new network block
1413068399.853421: ssid - hexdump_ascii(len=19):
     4d 54 37 36 30 31 55 28 6d 74 37 36 30 31 55 73   MT7601U(mt7601Us
     74 61 29                                          ta)             
1413068399.853503: mode=1 (0x1)
1413068399.853524: PSK (ASCII passphrase) - hexdump_ascii(len=10): [REMOVED]
1413068399.867726: PSK (from passphrase) - hexdump(len=32): [REMOVED]
1413068399.867756: Priority group 0
1413068399.867762:    id=0 ssid='MT7601U(mt7601Usta)'
1413068399.867860: SIOCGIWRANGE: WE(compiled)=22 WE(source)=14 enc_capa=0xf
1413068399.867883:   capabilities: key_mgmt 0xf enc 0x1f flags 0x0
1413068399.867915: netlink: Operstate: linkmode=1, operstate=5
1413068399.868027: ra0: Own MAC address: 00:11:22:33:44:55
1413068399.868040: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
1413068399.868680: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
1413068399.869304: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
1413068399.869927: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
1413068399.870551: wpa_driver_wext_set_countermeasures
1413068399.870567: ra0: RSN: flushing PMKID list in the driver
1413068399.870575: ra0: Setting scan request: 0 sec 100000 usec
1413068399.870585: WPS: Set UUID for interface ra0
1413068399.870594: WPS: UUID based on MAC address - hexdump(len=16): ...
1413068399.872662: EAPOL: SUPP_PAE entering state DISCONNECTED
1413068399.872683: EAPOL: Supplicant port status: Unauthorized
1413068399.872689: EAPOL: KEY_RX entering state NO_KEY_RECEIVE
1413068399.872696: EAPOL: SUPP_BE entering state INITIALIZE
1413068399.872702: EAP: EAP entering state DISABLED
1413068399.872708: EAPOL: Supplicant port status: Unauthorized
1413068399.872714: EAPOL: Supplicant port status: Unauthorized
1413068399.872922: ctrl_interface_group=10 (from group name 'wheel')
1413068399.872968: dbus: Register interface object '/fi/w1/wpa_supplicant1/Interfaces/1'
1413068399.873406: dbus: Register network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/0'
1413068399.873486: ra0: Added interface ra0
1413068399.873499: ra0: State: DISCONNECTED -> DISCONNECTED
1413068399.873506: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1413068399.873514: netlink: Operstate: linkmode=-1, operstate=5
1413068399.873547: random: Got 20/20 bytes from /dev/random
1413068399.873586: dbus: org.freedesktop.DBus.Properties.GetAll (/fi/w1/wpa_supplicant1)
1413068399.873642: RTM_NEWLINK: operstate=0 ifi_flags=0x11043 ([UP][RUNNING][LOWER_UP])
1413068399.873655: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
1413068399.873672: WEXT: if_removed already cleared - ignore event
1413068399.873679: Wireless event: cmd=0x8b06 len=12
1413068399.873686: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1413068399.873693: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
1413068399.873704: WEXT: if_removed already cleared - ignore event
1413068399.970796: ra0: State: DISCONNECTED -> SCANNING
1413068399.970979: ra0: Starting AP scan for wildcard SSID
1413068399.971100: Scan requested (ret=0) - scan timeout 10 seconds
1413068399.976088: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object 
/fi/w1/wpa_supplicant1/Interfaces/1
1413068400.873683: EAPOL: disable timer tick
1413068400.873715: EAPOL: Supplicant port status: Unauthorized
1413068402.068911: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1413068402.068954: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
1413068402.069000: WEXT: if_removed already cleared - ignore event
1413068402.069009: Wireless event: cmd=0x8b19 len=16
1413068402.069030: ra0: Event SCAN_RESULTS (3) received
1413068402.069251: Received 3632 bytes of scan results (17 BSSes)
1413068402.069295: ra0: BSS: Start scan result update 1
1413068402.069310: ra0: BSS: Add new id 0 BSSID ::::: SSID '...'
1413068402.069320: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/0'
...
1413068402.072171: ra0: BSS: Add new id 16 BSSID ::::: SSID '...'
1413068402.072182: dbus: Register BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/16'
1413068402.072325: BSS: last_scan_res_used=17/32 last_scan_full=0
1413068402.072345: ra0: New scan results available
1413068402.072426: WPS: AP ::::: type 0 added
...
1413068402.072484: WPS: AP ::::: type 0 added
1413068402.072493: WPS: AP[0] ::::: type=0 tries=0 last_attempt=-1 sec ago blacklist=0
...
1413068402.072544: WPS: AP[5] ::::: type=0 tries=0 last_attempt=-1 sec ago blacklist=0
1413068402.072567: ra0: Selecting BSS from priority group 0
1413068402.072582: ra0: 0: ::::: ssid='...' wpa_ie_len=28 rsn_ie_len=0 caps=0x11 level=-85 wps
1413068402.072592: ra0:    skip - SSID mismatch
...
1413068402.072901: ra0: 16: ::::: ssid='...' wpa_ie_len=0 rsn_ie_len=0 caps=0x1 level=-81
1413068402.072909: ra0:    skip - SSID mismatch
1413068402.072916: ra0: No suitable network found
1413068402.072922: ra0: Setup a new network
1413068402.072930: ra0: Trying to associate with SSID 'MT7601U(mt7601Usta)'
1413068402.072938: ra0: Cancelling scan request
1413068402.072946: ra0: WPA: clearing own WPA/RSN IE
1413068402.072953: ra0: Automatic auth_alg selection: 0x1
1413068402.072961: ra0: WPA: No WPA/RSN IE available from association info
1413068402.072968: ra0: WPA: Set cipher suites based on configuration
1413068402.072977: ra0: WPA: Selected cipher suites: group 30 pairwise 24 key_mgmt 3 proto 2
1413068402.072986: ra0: WPA: clearing AP WPA IE
1413068402.072992: ra0: WPA: clearing AP RSN IE
1413068402.072999: ra0: WPA: using GTK CCMP
1413068402.073005: ra0: WPA: using PTK CCMP
1413068402.073012: ra0: WPA: using KEY_MGMT 802.1X
1413068402.073019: WPA: Set own WPA IE default - hexdump(len=22): ...
1413068402.073041: ra0: No keys have been configured - skip key clearing
1413068402.073051: ra0: State: SCANNING -> ASSOCIATING
1413068402.073057: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1413068402.073077: netlink: Operstate: linkmode=-1, operstate=5
1413068402.073155: wpa_driver_wext_associate
1413068402.073163: wpa_driver_wext_set_drop_unencrypted
1413068402.076070: wpa_driver_wext_set_psk
1413068402.076099: ra0: Cancelling authentication timeout
1413068402.076109: EAPOL: External notification - portControl=ForceAuthorized
1413068402.076117: EAPOL: Supplicant port status: Unauthorized
1413068402.076125: EAPOL: External notification - EAP success=1
1413068402.076132: EAPOL: Supplicant port status: Unauthorized
1413068402.076138: EAP: EAP entering state DISABLED
1413068402.076145: EAPOL: Supplicant port status: Unauthorized
1413068402.076152: EAPOL: External notification - EAP fail=0
1413068402.076158: EAPOL: Supplicant port status: Unauthorized
1413068402.076173: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1413068402.076181: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
1413068402.076196: WEXT: if_removed already cleared - ignore event
1413068402.076203: Wireless event: cmd=0x8b06 len=12
1413068402.076211: RTM_NEWLINK: operstate=0 ifi_flags=0x11003 ([UP][LOWER_UP])
1413068402.076219: RTM_NEWLINK, IFLA_IFNAME: Interface 'ra0' added
1413068402.076230: WEXT: if_removed already cleared - ignore event
1413068402.076237: Wireless event: cmd=0x8b1a len=35
1413068402.076249: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object 
/fi/w1/wpa_supplicant1/Interfaces/1
1413068590.030148: ra0: BSS: Remove id 0 BSSID ::::: SSID '...' due to wpa_bss_flush_by_age
1413068590.030184: dbus: Unregister BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/0'
...
1413068590.031367: ra0: BSS: Remove id 16 BSSID ::::: SSID '...' due to wpa_bss_flush_by_age
1413068590.031377: dbus: Unregister BSS object '/fi/w1/wpa_supplicant1/Interfaces/1/BSSs/16'
1413068590.035370: dbus: flush_object_timeout_handler: Timeout - sending changed properties of object 
/fi/w1/wpa_supplicant1/Interfaces/1


- iwconfig ra0 
ra0       Ralink STA  ESSID:"MT7601U(mt7601Usta)"  Nickname:"MT7601STA"
          Mode:Ad-Hoc  Frequency=2.412 GHz  Cell: 0E:93:AA:B2:4F:23   
          Bit Rate=150 Mb/s   
          RTS thr:off   Fragment thr:off
          Encryption key:off
          Link Quality=70/100  Signal level:0 dBm  Noise level:0 dBm
          Rx invalid nwid:0  Rx invalid crypt:0  Rx invalid frag:0
          Tx excessive retries:0  Invalid misc:0   Missed beacon:0

- http://goo.gl/tf2cmL


^C1413068717.318044: ra0: Removing interface ra0
1413068717.318109: ra0: Request to deauthenticate - bssid=00:00:00:00:00:00 pending_bssid=00:00:00:00:00:00 
reason=3 state=ASSOCIATING
1413068717.318121: wpa_driver_wext_deauthenticate
1413068717.318165: ra0: Event DEAUTH (12) received
1413068717.318173: ra0: Deauthentication notification
1413068717.318181: ra0:  * reason 3 (locally generated)
1413068717.318187: Deauthentication frame IE(s) - hexdump(len=0): [NULL]
1413068717.318200: ra0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 locally_generated=1
1413068717.318210: ra0: Auto connect enabled: try to reconnect (wps=0 wpa_state=5)
1413068717.318255: ra0: Setting scan request: 0 sec 100000 usec
1413068717.318264: Added BSSID 00:00:00:00:00:00 into blacklist
1413068717.318275: ra0: Blacklist count 1 --> request scan in 100 ms
1413068717.318284: ra0: Setting scan request: 0 sec 100000 usec
1413068717.318471: ra0: Disconnect event - remove keys
1413068717.318480: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
1413068717.319792: wpa_driver_wext_set_key: alg=0 key_idx=1 set_tx=0 seq_len=0 key_len=0
1413068717.321538: wpa_driver_wext_set_key: alg=0 key_idx=2 set_tx=0 seq_len=0 key_len=0
1413068717.322156: wpa_driver_wext_set_key: alg=0 key_idx=3 set_tx=0 seq_len=0 key_len=0
1413068717.323786: wpa_driver_wext_set_key: alg=0 key_idx=0 set_tx=0 seq_len=0 key_len=0
1413068717.324416: ra0: State: ASSOCIATING -> DISCONNECTED
1413068717.324430: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1413068717.324440: netlink: Operstate: linkmode=-1, operstate=5
1413068717.324582: EAPOL: External notification - portEnabled=0
1413068717.324593: EAPOL: Supplicant port status: Unauthorized
1413068717.324607: EAPOL: External notification - portValid=0
1413068717.324613: EAPOL: Supplicant port status: Unauthorized
1413068717.324625: ra0: No keys have been configured - skip key clearing
1413068717.324634: ra0: State: DISCONNECTED -> DISCONNECTED
1413068717.324642: wpa_driver_wext_set_operstate: operstate 0->0 (DORMANT)
1413068717.324650: netlink: Operstate: linkmode=-1, operstate=5
1413068717.324663: EAPOL: External notification - portEnabled=0
1413068717.324670: EAPOL: Supplicant port status: Unauthorized
1413068717.324676: EAPOL: External notification - portValid=0
1413068717.324682: EAPOL: Supplicant port status: Unauthorized
1413068717.324689: wpa_driver_wext_set_countermeasures
1413068717.324701: ra0: No keys have been configured - skip key clearing
1413068717.330192: dbus: Unregister network object '/fi/w1/wpa_supplicant1/Interfaces/1/Networks/0'
1413068717.330813: Removed BSSID 00:00:00:00:00:00 from blacklist (clear)
1413068717.330999: ra0: Cancelling scan request
1413068717.331010: ra0: Cancelling authentication timeout
1413068717.331036: netlink: Operstate: linkmode=0, operstate=6
1413068717.547562: dbus: Unregister interface object '/fi/w1/wpa_supplicant1/Interfaces/1'
1413068717.547641: ra0: CTRL-EVENT-TERMINATING 
1413068717.547682: dbus: Unregister D-Bus object '/fi/w1/wpa_supplicant1'