Attachment 83714 Details for Bug 175852

Log trace of NM when trying to attach to my AP

NM_log.log (text/plain), 14.34 KB, created by Fred Blaise on 2006-05-16 17:46:17 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Fred Blaise

Created: 2006-05-16 17:46:17 UTC

Size: 14.34 KB

patch

obsolete

>May 16 19:43:16 lapdance NetworkManager: <information>  Restarting autofs.
>May 16 19:43:18 lapdance NetworkManager: <information>  Device ath0 activation scheduled...
>May 16 19:43:18 lapdance NetworkManager: <information>  Deactivating device eth0.
>May 16 19:43:18 lapdance NetworkManager: <information>  Stopping ypbind.
>May 16 19:43:18 lapdance NetworkManager: <information>  Restarting autofs.
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) started...
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) Stage 1 of 5 (Device Prepare) started...
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) Stage 2 of 5 (Device Configure) starting...
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0/wireless): access point ' ' is encrypted, but NO valid key exists.  New key needed.
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) New wireless user key requested for network ' '.
>May 16 19:43:19 lapdance NetworkManager: <information>  Activation (ath0) Stage 2 of 5 (Device Configure) complete.
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0) New wireless user key for network ' ' received.
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0) Stage 1 of 5 (Device Prepare) scheduled...
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0) Stage 1 of 5 (Device Prepare) started...
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0) Stage 2 of 5 (Device Configure) scheduled...
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0) Stage 1 of 5 (Device Prepare) complete.
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0) Stage 2 of 5 (Device Configure) starting...
>May 16 19:43:29 lapdance NetworkManager: <information>  Activation (ath0/wireless): access point ' ' is encrypted, and a key exists.  No new key needed.
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'INTERFACE_ADD ath0               madwifi /var/run/wpa_supplicant '
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'AP_SCAN 1'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'ADD_NETWORK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was '0'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'SET_NETWORK 0 ssid 20'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'SET_NETWORK 0 key_mgmt NONE'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'SET_NETWORK 0 auth_alg SHARED'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'SET_NETWORK 0 wep_key0 <key>'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'SET_NETWORK 0 wep_tx_keyidx 0'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: sending command 'ENABLE_NETWORK 0'
>May 16 19:43:30 lapdance NetworkManager: <information>  SUP: response was 'OK'
>May 16 19:43:30 lapdance NetworkManager: <information>  Activation (ath0) Stage 2 of 5 (Device Configure) complete.
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Global control interface '/var/run/wpa_supplicant-global'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX global ctrl_iface - hexdump_ascii(len=52):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     49 4e 54 45 52 46 41 43 45 5f 41 44 44 20 61 74   INTERFACE_ADD at
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     68 30 09 09 6d 61 64 77 69 66 69 09 2f 76 61 72   h0__madwifi_/var
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     2f 72 75 6e 2f 77 70 61 5f 73 75 70 70 6c 69 63   /run/wpa_supplic
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     61 6e 74 09                                       ant_
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE GLOBAL INTERFACE_ADD 'ath0            madwifi /var/run/wpa_supplican'May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Initializing interface 'ath0' conf 'N/A' driver 'madwifi' ctrl_interface '/var/run/wpa_supplicant'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Initializing interface (2) 'ath0'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): EAPOL: SUPP_PAE entering state DISCONNECTED
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): EAPOL: KEY_RX entering state NO_KEY_RECEIVE
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): EAPOL: SUPP_BE entering state INITIALIZE
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): EAP: EAP entering state DISABLED
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): EAPOL: External notification - portEnabled=0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): EAPOL: External notification - portValid=0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): SIOCGIWRANGE: WE(compiled)=19 WE(source)=13 enc_capa=0xf
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):  capabilities: key_mgmt 0xf enc 0xf
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Own MAC address: 00:0e:8e:01:a6:d4
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): wpa_driver_madwifi_del_key: keyidx=0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): r_madwifi_del_key: keyidx=1
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): wpa_driver_madwifi_del_key: keyidx=2
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): wpa_driver_madwifi_del_key: keyidx=3
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): wpa_driver_madwifi_set_countermeasures: enabled=0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): wpa_driver_madwifi_set_drop_unencrypted: enabled=1
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Setting scan request: 0 sec 100000 usec
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Added interface ath0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Wireless event: cmd=0x8b06 len=8
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=9):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     41 50 5f 53 43 41 4e 20 31                        AP_SCAN 1
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=11):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     41 44 44 5f 4e 45 54 57 4f 52 4b                  ADD_NETWORK
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE: ADD_NETWORK
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=21):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 73 73   SET_NETWORK 0 ss
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     69 64 20 32 30                                    id 20
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE: SET_NETWORK id=0 name='ssid' value='20'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=27):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 6b 65   SET_NETWORK 0 ke
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     79 5f 6d 67 6d 74 20 4e 4f 4e 45                  y_mgmt NONE
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE: SET_NETWORK id=0 name='key_mgmt' value='NONE'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): rl_iface - hexdump_ascii(len=29):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 61 75   SET_NETWORK 0 au
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     74 68 5f 61 6c 67 20 53 48 41 52 45 44            th_alg SHARED
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE: SET_NETWORK id=0 name='auth_alg' value='SHARED'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=49):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 77 65   SET_NETWORK 0 we
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     70 5f 6b 65 79 30 20 36 30 36 61 32 66 36 64 65   p_key0 606a2f6de
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     34 37 63 35 65 31 34 38 32 36 32 38 30 62 61 32   47c5e14826280ba2
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     33                                                3
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE: SET_NETWORK id=0 name='wep_key0' value='606a2f6de47c5e14826280ba23'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=29):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     53 45 54 5f 4e 45 54 57 4f 52 4b 20 30 20 77 65   SET_NETWORK 0 we
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     70 5f 74 78 5f 6b 65 79 69 64 78 20 30            p_tx_keyidx 0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE: SET_NETWORK id=0 name='wep_tx_keyidx' value='0'
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=16):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     45 4e 41 42 4c 45 5f 4e 45 54 57 4f 52 4b 20 30   ENABLE_NETWORK 0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RK id=0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Setting scan request: 0 sec 0 usec
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): State: DISCONNECTED -> SCANNING
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Starting AP scan (broadcast SSID)
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Wireless event: cmd=0x8b1a len=8
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): RX ctrl_iface - hexdump_ascii(len=6):
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):     41 54 54 41 43 48                                 ATTACH
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): CTRL_IFACE monitor attached - hexdump(len=42): 2f 76 61 72 2f 72 75 6e 2f 4e 65 74 77 6f 72 6b 4d 61 6e 61 67 65 72 2f 77 70 61 5f 63 74 72 6c 5f 32 39 31 36 2d 31 00 00 00
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Scan timeout - try to get results
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Received 600 bytes of scan results (3 BSSes)
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Scan results: 3
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Selecting BSS from priority group 0
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): 0: 00:08:d3:10:10:65 ssid='Casa' wpa_ie_len=24 rsn_ie_len=0 caps=0x11
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):   skip - SSID mismatch
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): 1: 00:09:5b:f5:01:96 ssid=' ' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):   skip - no WPA/RSN IE
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): 2: 00:0f:b5:9e:6b:e8 ssid='' wpa_ie_len=0 rsn_ie_len=0 caps=0x11
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):   skip - no WPA/RSN IE
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864):   selected non-WPA AP 00:09:5b:f5:01:96 ssid=' '
>May 16 19:44:00 lapdance NetworkManager: <information>  wpa_supplicant(4864): Trying to associate with 00:09:5b:f5:01:96 (SSID=' ' freq=2462 MHz)
>May 16 19:44:30 lapdance NetworkManager: <information>  Activation (ath0/wireless): association took too long (>60s), failing activation.
>May 16 19:44:30 lapdance NetworkManager: <information>  Activation (ath0) failure scheduled...
>May 16 19:44:30 lapdance NetworkManager: <information>  Activation (ath0) failed for access point ( )
>May 16 19:44:30 lapdance NetworkManager: <information>  Activation (ath0) failed.
>May 16 19:44:30 lapdance NetworkManager: <information>  Deactivating device ath0.
>May 16 19:44:30 lapdance NetworkManager: <information>  Stopping ypbind.
>May 16 19:44:30 lapdance NetworkManager: <information>  Restarting autofs.
>May 16 19:44:32 lapdance NetworkManager: <information>  SWITCH: no current connection, found better connection 'eth0'.

Actions: View

Attachments on bug 175852: 83714