Attachment 112789 Details for Bug 225604

NetworkManager log of failed connection attempt

WlanFailure.log (text/plain), 11.98 KB, created by Stefan Quandt on 2007-01-12 20:54:21 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Stefan Quandt

Created: 2007-01-12 20:54:21 UTC

Size: 11.98 KB

patch

obsolete

>Jan 12 20:33:26 linux NetworkManager: <info>  Waking up from sleep.
>Jan 12 20:33:27 linux NetworkManager: <info>  Deactivating device eth2.
>Jan 12 20:33:29 linux NetworkManager: <info>  Deactivating device eth0.
>Jan 12 20:33:29 linux NetworkManager: <info>  eth2: Device is fully-supported using driver 'ipw2200'.
>Jan 12 20:33:29 linux NetworkManager: <info>  nm_device_init(): waiting for device's worker thread to start
>Jan 12 20:33:29 linux NetworkManager: <info>  nm_device_init(): device's worker thread started, continuing.
>Jan 12 20:33:29 linux NetworkManager: <info>  Now managing wireless (802.11) device 'eth2'.
>Jan 12 20:33:29 linux NetworkManager: <info>  Deactivating device eth2.
>Jan 12 20:33:29 linux NetworkManager: <debug> [1168630409.764255] nm_system_device_get_system_config(): found config '/etc/sysconfig/network/ifcfg-eth-id-00:0e:7b:8a:7f:2a' for interface 'eth0'
>Jan 12 20:33:29 linux NetworkManager: <debug> [1168630409.764455] nm_system_device_get_system_config(): BOOTPROTO=dhcp
>Jan 12 20:33:29 linux NetworkManager: <debug> [1168630409.764533] nm_system_device_get_system_config(): ------ Config (eth0)
>Jan 12 20:33:30 linux NetworkManager: <debug> [1168630410.298561] nm_system_device_get_system_config(): dhcp=1
>Jan 12 20:33:30 linux NetworkManager: <debug> [1168630410.298777] nm_system_device_get_system_config(): addr=0.0.0.0
>Jan 12 20:33:30 linux NetworkManager: <debug> [1168630410.298898] nm_system_device_get_system_config(): gw=0.0.0.0
>Jan 12 20:33:30 linux NetworkManager: <debug> [1168630410.299010] nm_system_device_get_system_config(): mask=0.0.0.0
>Jan 12 20:33:30 linux NetworkManager: <debug> [1168630410.299115] nm_system_device_get_system_config(): --------------------- 
>Jan 12 20:33:30 linux NetworkManager: <info>  eth0: Device is fully-supported using driver 'e100'.
>Jan 12 20:33:30 linux NetworkManager: <info>  nm_device_init(): waiting for device's worker thread to start
>Jan 12 20:33:30 linux NetworkManager: <info>  nm_device_init(): device's worker thread started, continuing.
>Jan 12 20:33:30 linux NetworkManager: <info>  Now managing wired Ethernet (802.3) device 'eth0'.
>Jan 12 20:33:30 linux NetworkManager: <info>  Deactivating device eth0.
>Jan 12 20:33:30 linux NetworkManager: <info>  SWITCH: no current connection, found better connection 'eth2'.
>Jan 12 20:33:31 linux NetworkManager: <info>  Will activate connection 'eth2/MyWlan'.
>Jan 12 20:33:31 linux NetworkManager: <info>  Device eth2 activation scheduled...
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) started...
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) started...
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) scheduled...
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) complete.
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) starting...
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2/wireless): access point 'MyWlan' is encrypted, but NO valid key exists.  New key needed.
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) New wireless user key requested for network 'MyWlan'.
>Jan 12 20:33:31 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) complete.
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2) New wireless user key for network 'MyWlan' received.
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) started...
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) scheduled...
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) complete.
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) starting...
>Jan 12 20:33:42 linux NetworkManager: <info>  Activation (eth2/wireless): access point 'MyWlan' is encrypted, and a key exists.  No new key needed.
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'INTERFACE_ADD eth2		wext	/var/run/wpa_supplicant	'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'AP_SCAN 1'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'ADD_NETWORK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was '0'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 ssid 526f64656c62657267576c616e'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 scan_ssid 1'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 key_mgmt NONE'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 wep_key0 <key>'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 wep_tx_keyidx 0'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: sending command 'ENABLE_NETWORK 0'
>Jan 12 20:33:43 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:33:43 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) complete.
>Jan 12 20:33:43 linux NetworkManager: <info>  Old device 'eth2' activating, won't change.
>Jan 12 20:33:43 linux NetworkManager: <info>  Old device 'eth2' activating, won't change.
>Jan 12 20:33:43 linux NetworkManager: <info>  Activation (eth2/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to access point 'MyWlan'.
>Jan 12 20:33:43 linux NetworkManager: <info>  Activation (eth2) Stage 3 of 5 (IP Configure Start) scheduled.
>Jan 12 20:33:43 linux NetworkManager: <info>  Activation (eth2) Stage 3 of 5 (IP Configure Start) started...
>Jan 12 20:33:44 linux NetworkManager: <info>  Activation (eth2) Beginning DHCP transaction.
>Jan 12 20:33:44 linux NetworkManager: <info>  Activation (eth2) Stage 3 of 5 (IP Configure Start) complete.
>Jan 12 20:33:44 linux NetworkManager: <info>  DHCP daemon state is now 12 (successfully started) for interface eth2
>Jan 12 20:33:45 linux NetworkManager: <info>  DHCP daemon state is now 1 (starting) for interface eth2
>Jan 12 20:34:29 linux NetworkManager: <info>  Device 'eth2' DHCP transaction took too long (>45s), stopping it.
>Jan 12 20:34:31 linux NetworkManager: <info>  Activation (eth2) Stage 4 of 5 (IP Configure Timeout) scheduled...
>Jan 12 20:34:31 linux NetworkManager: <info>  DHCP daemon state is now 14 (normal exit) for interface eth2
>Jan 12 20:34:31 linux NetworkManager: <info>  DHCP daemon state is now 11 (unknown) for interface eth2
>Jan 12 20:34:31 linux NetworkManager: <info>  DHCP daemon state is now 14 (normal exit) for interface eth2
>Jan 12 20:34:31 linux NetworkManager: <info>  Activation (eth2) Stage 4 of 5 (IP Configure Timeout) started...
>Jan 12 20:34:31 linux NetworkManager: <debug> [1168630471.013036] real_act_stage4_ip_config_timeout(): Activation (eth2/wireless): could not get IP configuration info for 'MyWlan', asking for new key.
>Jan 12 20:34:31 linux NetworkManager: <info>  Activation (eth2) New wireless user key requested for network 'MyWlan'.
>Jan 12 20:34:31 linux NetworkManager: <info>  Activation (eth2) Stage 4 of 5 (IP Configure Timeout) complete.
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) New wireless user key for network 'MyWlan' received.
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) scheduled...
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) started...
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) scheduled...
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 1 of 5 (Device Prepare) complete.
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) starting...
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2/wireless): access point 'MyWlan' is encrypted, and a key exists.  No new key needed.
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'INTERFACE_ADD eth2		wext	/var/run/wpa_supplicant	'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'AP_SCAN 1'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'ADD_NETWORK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was '0'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 ssid 526f64656c62657267576c616e'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 scan_ssid 1'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 key_mgmt NONE'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 wep_key0 <key>'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'SET_NETWORK 0 wep_tx_keyidx 0'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: sending command 'ENABLE_NETWORK 0'
>Jan 12 20:35:04 linux NetworkManager: <info>  SUP: response was 'OK'
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 2 of 5 (Device Configure) complete.
>Jan 12 20:35:04 linux NetworkManager: <info>  Old device 'eth2' activating, won't change.
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to access point 'MyWlan'.
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 3 of 5 (IP Configure Start) scheduled.
>Jan 12 20:35:04 linux NetworkManager: <info>  Activation (eth2) Stage 3 of 5 (IP Configure Start) started...
>Jan 12 20:35:05 linux NetworkManager: <info>  Activation (eth2) Beginning DHCP transaction.
>Jan 12 20:35:06 linux NetworkManager: <info>  Activation (eth2) Stage 3 of 5 (IP Configure Start) complete.
>Jan 12 20:35:06 linux NetworkManager: <info>  DHCP daemon state is now 12 (successfully started) for interface eth2
>Jan 12 20:35:06 linux NetworkManager: <info>  DHCP daemon state is now 1 (starting) for interface eth2
>Jan 12 20:35:51 linux NetworkManager: <info>  Device 'eth2' DHCP transaction took too long (>45s), stopping it.
>Jan 12 20:35:52 linux NetworkManager: <info>  Activation (eth2) Stage 4 of 5 (IP Configure Timeout) scheduled...
>Jan 12 20:35:52 linux NetworkManager: <info>  DHCP daemon state is now 14 (normal exit) for interface eth2
>Jan 12 20:35:52 linux NetworkManager: <info>  DHCP daemon state is now 11 (unknown) for interface eth2
>Jan 12 20:35:52 linux NetworkManager: <info>  DHCP daemon state is now 14 (normal exit) for interface eth2
>Jan 12 20:35:52 linux NetworkManager: <info>  Activation (eth2) Stage 4 of 5 (IP Configure Timeout) started...
>Jan 12 20:35:52 linux NetworkManager: <debug> [1168630552.037090] real_act_stage4_ip_config_timeout(): Activation (eth2/wireless): could not get IP configuration info for 'MyWlan', asking for new key.
>Jan 12 20:35:52 linux NetworkManager: <info>  Activation (eth2) New wireless user key requested for network 'MyWlan'.
>Jan 12 20:35:52 linux NetworkManager: <info>  Activation (eth2) Stage 4 of 5 (IP Configure Timeout) complete.

Actions: View

Attachments on bug 225604: 107985 | 107986 | 107988 | 107989 | 110010 | 110011 | 110012 | 110013 | 112789 | 132500 | 132730