Attachment 332107 Details for Bug 551862

Another extract of the NetworkManager log file

network tests 3 (text/plain), 15.90 KB, created by Bruno Canning on 2009-12-11 02:39:44 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Bruno Canning

Created: 2009-12-11 02:39:44 UTC

Size: 15.90 KB

patch

obsolete

>Hardware:
>
>PC: HP Compaq NX6110
>CPU: Intel Celeron M 1.4 GHz
>RAM: 512 MiB
>Graphics: Intel 915 GM
>WiFi: Intel PRO 2200BG
>Lan: Broadcom BCM4401
>
>Software:
>
>OS: Linux 2.6.31.5-0.1-default i686
>System: openSUSE 11.2 RC2 (i586) 32 bit
>KDE: 4.3.1 "release 6"
>Installation Medium: openSUSE-KDE4-LiveCD-Build0339-i686.iso
>
>Tests Performed:
>
>02:18 [sic] onwards
>knetworkmanager previously killed in "top" by root, NetworkManager left running
>started knetworkmanager from konsole by user, its icon appears in the system tray
>(nothing shows up in the log)
>
>02:19 onwards
>activated connection to the internet by physical connection of ethernet cable
>connected to the internet via ethernet port to my modem router (automatic, no further input required after activation)
>pinged an ip address of a computer at college (I am at home), got 4 ping reflections confirming a connection to the internet on first attempt
>deactivated connection by physical disconnection
>
>02:20 onwards
>activated wireless connection via button on keyboard
>connected to the internet via wireless connection to my modem router (automatic, no further input required after activation)
>pinged an ip address of a computer at college (I am at home), got 4 ping reflections confirming a connection to the internet on first attempt
>deactivated wireless connection via button on keyboard
>
>SSID censored (sorry, I'm sure you're all trustworthy but privacy pays)
>
>Comments:
>
>Sorry, I think I misread your requests, you wanted knetworkmanager restarted, not NetworkManager? These tests were done on my original installation of RC2.
>
>Log follows:
>
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  (eth1): carrier now ON (device state 2)
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  (eth1): device state change: 2 -> 3 (reason 40)
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) starting connection 'System eth1'
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  (eth1): device state change: 3 -> 4 (reason 0)
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) scheduled...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) started...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) scheduled...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 1 of 5 (Device Prepare) complete.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) starting...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  (eth1): device state change: 4 -> 5 (reason 0)
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) successful.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) scheduled.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 2 of 5 (Device Configure) complete.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) started...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  (eth1): device state change: 5 -> 7 (reason 0)
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Beginning DHCP transaction.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  dhclient started with pid 8826
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 3 of 5 (IP Configure Start) complete.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  DHCP: device eth1 state changed normal exit -> preinit
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  DHCP: device eth1 state changed preinit -> reboot
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) scheduled...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) started...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>    address 192.168.2.4
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>    prefix 24 (255.255.255.0)
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>    gateway 192.168.2.1
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>    nameserver '192.168.2.1'
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>    domain name 'myhomenetwork'
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) scheduled...
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 4 of 5 (IP Configure Get) complete.
>Dec 11 02:19:11 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) started...
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <info>  (eth1): device state change: 7 -> 8 (reason 0)
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <debug> [1260497952.201203] run_netconfig(): Spawning '/sbin/netconfig modify --service NetworkManager'
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <debug> [1260497952.221503] write_to_netconfig(): Writing to netconfig: DNSSEARCH='myhomenetwork myhomenetwork'#012
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <debug> [1260497952.221853] write_to_netconfig(): Writing to netconfig: DNSSERVERS='192.168.2.1'#012
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <info>  Clearing nscd hosts cache.
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <info>  Policy set 'System eth1' (eth1) as default for routing and DNS.
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <info>  Activation (eth1) successful, device activated.
>Dec 11 02:19:12 linux-vqc4 NetworkManager: <info>  Activation (eth1) Stage 5 of 5 (IP Configure Commit) complete.
>Dec 11 02:19:12 linux-vqc4 nm-dispatcher.action: Script '/etc/NetworkManager/dispatcher.d/autofs' exited with error status 1.
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <info>  (eth1): carrier now OFF (device state 8)
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <info>  (eth1): device state change: 8 -> 2 (reason 40)
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <info>  (eth1): deactivating device (reason: 40).
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <info>  eth1: canceled DHCP transaction, dhcp client pid 8826
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <debug> [1260497985.202074] run_netconfig(): Spawning '/sbin/netconfig modify --service NetworkManager'
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <info>  Clearing nscd hosts cache.
>Dec 11 02:19:45 linux-vqc4 NetworkManager: <WARN>  check_one_route(): (eth1) error -34 returned from rtnl_route_del(): Sucess#012
>Dec 11 02:19:45 linux-vqc4 nm-dispatcher.action: Script '/etc/NetworkManager/dispatcher.d/autofs' exited with error status 1.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Wireless now enabled by radio killswitch
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): bringing up device.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 2 -> 3 (reason 0)
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): supplicant interface state:  starting -> ready
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) starting connection 'myhomenetwork'
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 3 -> 4 (reason 0)
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 4 -> 5 (reason 0)
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0/wireless): access point 'myhomenetwork' has security, but secrets are required.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 5 -> 6 (reason 0)
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <WARN>  update_one_setting(): Failed to update connection secrets: 1 802-1x
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <WARN>  real_connection_secrets_updated(): Ignoring updated secrets for setting 'ipv4'.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <WARN>  real_connection_secrets_updated(): Ignoring updated secrets for setting '802-11-wireless'.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) started...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 6 -> 4 (reason 0)
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) starting...
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 4 -> 5 (reason 0)
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0/wireless): connection 'myhomenetwork' has security, and secrets exist.  No new secrets needed.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Config: added 'ssid' value 'myhomenetwork'
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Config: added 'scan_ssid' value '1'
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Config: added 'key_mgmt' value 'WPA-PSK'
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Config: added 'psk' value '<omitted>'
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 2 of 5 (Device Configure) complete.
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  Config: set interface ap_scan to 1
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  scanning -> disconnected
>Dec 11 02:20:10 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  disconnected -> associated
>Dec 11 02:20:14 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  associated -> disconnected
>Dec 11 02:20:14 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  disconnected -> scanning
>Dec 11 02:20:14 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  scanning -> associating
>Dec 11 02:20:15 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  associating -> 4-way handshake
>Dec 11 02:20:15 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  4-way handshake -> associated
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  associated -> disconnected
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  disconnected -> scanning
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  scanning -> associating
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  associating -> associated
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  associated -> 4-way handshake
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  4-way handshake -> group handshake
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  group handshake -> completed
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  Activation (eth0/wireless) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'myhomenetwork'.
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 5 -> 7 (reason 0)
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  Activation (eth0) Beginning DHCP transaction.
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  dhclient started with pid 9187
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
>Dec 11 02:20:19 linux-vqc4 NetworkManager: <info>  DHCP: device eth0 state changed normal exit -> preinit
>Dec 11 02:20:20 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  completed -> group handshake
>Dec 11 02:20:20 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  group handshake -> completed
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>  DHCP: device eth0 state changed preinit -> reboot
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) scheduled...
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) started...
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>    address 192.168.2.2
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>    prefix 24 (255.255.255.0)
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>    gateway 192.168.2.1
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>    nameserver '192.168.2.1'
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>    domain name 'myhomenetwork'
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) scheduled...
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 4 of 5 (IP Configure Get) complete.
>Dec 11 02:20:23 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) started...
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 7 -> 8 (reason 0)
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <debug> [1260498024.028245] run_netconfig(): Spawning '/sbin/netconfig modify --service NetworkManager'
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <debug> [1260498024.063353] write_to_netconfig(): Writing to netconfig: DNSSEARCH='myhomenetwork myhomenetwork'#012
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <debug> [1260498024.063718] write_to_netconfig(): Writing to netconfig: DNSSERVERS='192.168.2.1'#012
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <info>  Clearing nscd hosts cache.
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <info>  Policy set 'myhomenetwork' (eth0) as default for routing and DNS.
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <info>  Activation (eth0) successful, device activated.
>Dec 11 02:20:24 linux-vqc4 NetworkManager: <info>  Activation (eth0) Stage 5 of 5 (IP Configure Commit) complete.
>Dec 11 02:20:24 linux-vqc4 nm-dispatcher.action: Script '/etc/NetworkManager/dispatcher.d/autofs' exited with error status 1.
>Dec 11 02:20:49 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  completed -> group handshake
>Dec 11 02:20:49 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  group handshake -> completed
>Dec 11 02:20:50 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  completed -> disconnected
>Dec 11 02:20:50 linux-vqc4 NetworkManager: <info>  (eth0): supplicant connection state:  disconnected -> scanning
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <info>  Wireless now disabled by radio killswitch
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <info>  (eth0): device state change: 8 -> 2 (reason 0)
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <info>  (eth0): deactivating device (reason: 0).
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <info>  eth0: canceled DHCP transaction, dhcp client pid 9187
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <debug> [1260498052.205064] run_netconfig(): Spawning '/sbin/netconfig modify --service NetworkManager'
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <info>  Clearing nscd hosts cache.
>Dec 11 02:20:52 linux-vqc4 NetworkManager: <WARN>  check_one_route(): (eth0) error -34 returned from rtnl_route_del(): Sucess#012
>Dec 11 02:20:52 linux-vqc4 nm-dispatcher.action: Script '/etc/NetworkManager/dispatcher.d/autofs' exited with error status 1.

Actions: View

Attachments on bug 551862: 332105 | 332106 | 332107