Attachment 653686 Details for Bug 871809 – nm.log

nm.log

nm.log (text/plain), 13.36 KB, created by Mads Kiilerich on 2012-11-28 18:58:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Mads Kiilerich

Created: 2012-11-28 18:58:37 UTC

Size: 13.36 KB

patch

obsolete

>NetworkManager[4971]: <info> NetworkManager (version 0.9.7.0-8.git20121004.fc18) is starting...
>NetworkManager[4971]: <info> Read config file /etc/NetworkManager/NetworkManager.conf
>NetworkManager[4971]: <info> WEXT support is enabled
>NetworkManager[4971]:    ifcfg-rh: Acquired D-Bus service com.redhat.ifcfgrh1
>NetworkManager[4971]: <info> Loaded plugin ifcfg-rh: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
>NetworkManager[4971]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
>NetworkManager[4971]:    ifcfg-rh: parsing /etc/sysconfig/network-scripts/ifcfg-lo ... 
>NetworkManager[4971]: <info> modem-manager is now available
>NetworkManager[4971]: <info> monitoring kernel firmware directory '/lib/firmware'.
>NetworkManager[4971]: <info> rfkill1: found WiFi radio killswitch (at /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/ieee80211/phy0/rfkill1) (driver iwl3945)
>NetworkManager[4971]: <info> WiFi disabled by radio killswitch; enabled by state file
>NetworkManager[4971]: <info> WWAN enabled by radio killswitch; enabled by state file
>NetworkManager[4971]: <info> WiMAX enabled by radio killswitch; enabled by state file
>NetworkManager[4971]: <info> Networking is enabled by state file
>NetworkManager[4971]: <warn> failed to allocate link cache: (-10) Operation not supported
>NetworkManager[4971]: <info> (eth0): carrier is ON
>NetworkManager[4971]: <info> (eth0): new Ethernet device (driver: 'e1000e' ifindex: 2)
>NetworkManager[4971]: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/0
>NetworkManager[4971]: <info> (eth0): now managed
>NetworkManager[4971]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>NetworkManager[4971]: <info> (eth0): preparing device.
>NetworkManager[4971]: <info> (eth0): deactivating device (reason 'managed') [2]
>NetworkManager[4971]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0
>NetworkManager[4971]: <info> (wlan0): using nl80211 for WiFi device control
>NetworkManager[4971]: <info> (wlan0): new 802.11 WiFi device (driver: 'iwl3945' ifindex: 3)
>NetworkManager[4971]: <info> (wlan0): exported as /org/freedesktop/NetworkManager/Devices/1
>NetworkManager[4971]: <info> (wlan0): now managed
>NetworkManager[4971]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>NetworkManager[4971]: <info> (wlan0): bringing up device.
>NetworkManager[4971]: <info> (wlan0): deactivating device (reason 'managed') [2]
>NetworkManager[4971]: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
>NetworkManager[4971]: <warn> /sys/devices/virtual/net/lo: couldn't determine device driver; ignoring...
>NetworkManager[4971]: <info> (eth0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>NetworkManager[4971]: <info> Auto-activating connection 'Wired connection 1'.
>NetworkManager[4971]: <info> Activation (eth0) starting connection 'Wired connection 1'
>NetworkManager[4971]: <info> (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>NetworkManager[4971]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
>NetworkManager[4971]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
>NetworkManager[4971]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
>NetworkManager[4971]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
>NetworkManager[4971]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
>NetworkManager[4971]: <info> (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
>NetworkManager[4971]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
>NetworkManager[4971]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
>NetworkManager[4971]: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
>NetworkManager[4971]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
>NetworkManager[4971]: <info> (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
>NetworkManager[4971]: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
>NetworkManager[4971]: <info> dhclient started with pid 4974
>NetworkManager[4971]: <info> Activation (eth0) Beginning IP6 addrconf.
>NetworkManager[4971]: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
>NetworkManager[4971]: <info> (eth0): DHCPv4 state changed nbi -> preinit
>NetworkManager[4971]: <info> (eth0): DHCPv4 state changed preinit -> bound
>NetworkManager[4971]: <info>   address 172.16.15.155
>NetworkManager[4971]: <info>   prefix 24 (255.255.255.0)
>NetworkManager[4971]: <info>   gateway 172.16.15.1
>NetworkManager[4971]: <info>   nameserver '172.16.12.3'
>NetworkManager[4971]: <info>   nameserver '172.16.12.4'
>NetworkManager[4971]: <info>   domain name 'headquarters.excitor.dk'
>NetworkManager[4971]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
>NetworkManager[4971]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) started...
>NetworkManager[4971]: <info> (eth0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
>NetworkManager[4971]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) complete.
>NetworkManager[4971]: <info> (eth0): device state change: secondaries -> activated (reason 'none') [90 100 0]
>NetworkManager[4971]: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
>NetworkManager[4971]: <info> Activation (eth0) successful, device activated.
>NetworkManager[4971]: <info> (eth0): IP6 addrconf timed out or failed.
>NetworkManager[4971]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
>NetworkManager[4971]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) started...
>NetworkManager[4971]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
>NetworkManager[4971]: <warn> (ttyS0): failed to look up interface index
>NetworkManager[4971]: <info> (ttyS0): new GSM/UMTS device (driver: 'serial_cs' ifindex: 0)
>NetworkManager[4971]: <info> (ttyS0): exported as /org/freedesktop/NetworkManager/Devices/2
>NetworkManager[4971]: <info> (ttyS0): now managed
>NetworkManager[4971]: <info> (ttyS0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>NetworkManager[4971]: <info> (ttyS0): deactivating device (reason 'managed') [2]
>NetworkManager[4971]: <info> (ttyS0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>NetworkManager[4971]: <info> Activation (ttyS0) starting connection '3 BredbÃ¥nd (standard)'
>NetworkManager[4971]: <info> (ttyS0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) scheduled...
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) started...
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 1 of 5 (Device Prepare) complete.
>NetworkManager[4971]: <info> WWAN now enabled by management service
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) scheduled...
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) starting...
>NetworkManager[4971]: <info> (ttyS0): device state change: prepare -> config (reason 'none') [40 50 0]
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) successful.
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 3 of 5 (IP Configure Start) scheduled.
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 2 of 5 (Device Configure) complete.
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 3 of 5 (IP Configure Start) started...
>NetworkManager[4971]: <info> (ttyS0): device state change: config -> ip-config (reason 'none') [50 70 0]
>NetworkManager[4971]: <info> starting PPP connection
>NetworkManager[4971]: <info> pppd started with pid 5278
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 4 of 5 (IPv6 Configure Timeout) scheduled...
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 3 of 5 (IP Configure Start) complete.
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 4 of 5 (IPv6 Configure Timeout) started...
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 4 of 5 (IPv6 Configure Timeout) complete.
>Plugin /usr/lib/pppd/2.4.5/nm-pppd-plugin.so loaded.
>** Message: nm-ppp-plugin: (plugin_init): initializing
>** Message: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
>Using interface ppp0
>Connect: ppp0 <--> /dev/ttyS0
>** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
>** Message: nm-ppp-plugin: (nm_phasechange): status 6 / phase 'authenticate'
>** Message: nm-ppp-plugin: (get_credentials): passwd-hook, requesting credentials...
>** Message: nm-ppp-plugin: (get_credentials): got credentials from NetworkManager
>CHAP authentication succeeded
>CHAP authentication succeeded
>** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
>NetworkManager[4971]: <warn> /sys/devices/virtual/net/ppp0: couldn't determine device driver; ignoring...
>Could not determine remote IP address: defaulting to 10.64.64.64
>local  IP address 94.191.212.126
>remote IP address 10.64.64.64
>primary   DNS address 80.251.201.177
>secondary DNS address 80.251.201.178
>** Message: nm-ppp-plugin: (nm_phasechange): status 9 / phase 'running'
>** Message: nm-ppp-plugin: (nm_ip_up): ip-up event
>** Message: nm-ppp-plugin: (nm_ip_up): sending Ip4Config to NetworkManager...
>NetworkManager[4971]: <info> PPP manager(IP Config Get) reply received.
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 5 of 5 (IPv4 Commit) started...
>NetworkManager[4971]: <info> (ttyS0): device state change: ip-config -> secondaries (reason 'none') [70 90 0]
>NetworkManager[4971]: <info> Activation (ttyS0) Stage 5 of 5 (IPv4 Commit) complete.
>NetworkManager[4971]: <info> (ttyS0): device state change: secondaries -> activated (reason 'none') [90 100 0]
>NetworkManager[4971]: <info> Activation (ttyS0) successful, device activated.
>NetworkManager[4971]: <info> (eth0): carrier now OFF (device state 100, deferring action for 4 seconds)
>NetworkManager[4971]: <info> (eth0): device state change: activated -> unavailable (reason 'carrier-changed') [100 20 40]
>NetworkManager[4971]: <info> (eth0): deactivating device (reason 'carrier-changed') [40]
>NetworkManager[4971]: <info> (eth0): canceled DHCP transaction, DHCP client pid 4974
>NetworkManager[4971]: <info> Policy set '3 BredbÃ¥nd (standard)' (ppp0) as default for IPv4 routing and DNS.
>NetworkManager[4971]: <info> (ttyS0): device state change: activated -> failed (reason 'modem-no-carrier') [100 120 25]
>NetworkManager[4971]: <warn> Activation (ttyS0) failed for connection '3 BredbÃ¥nd (standard)'
>NetworkManager[4971]: <info> (ttyS0): device state change: failed -> disconnected (reason 'none') [120 30 0]
>NetworkManager[4971]: <info> (ttyS0): deactivating device (reason 'none') [0]
>Terminating on signal 15
>** Message: nm-ppp-plugin: (nm_phasechange): status 10 / phase 'terminate'
>** Message: nm-ppp-plugin: (nm_phasechange): status 8 / phase 'network'
>Connect time 3.3 minutes.
>Sent 576 bytes, received 0 bytes.
>** Message: nm-ppp-plugin: (nm_phasechange): status 5 / phase 'establish'
>** Message: nm-ppp-plugin: (nm_phasechange): status 11 / phase 'disconnect'
>Connection terminated.
>** Message: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
>** Message: nm-ppp-plugin: (nm_exit_notify): cleaning up
>NetworkManager[4971]: <info> WWAN now disabled by management service
>NetworkManager[4971]: <info> (ttyS0): now unmanaged
>NetworkManager[4971]: <info> (ttyS0): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
>NetworkManager[4971]: <info> (ttyS0): cleaning up...
>NetworkManager[4971]: <info> (ttyS0): taking down device.
>NetworkManager[4971]: <info> the modem manager disappeared
>NetworkManager[4971]: <info> modem-manager is now available
>NetworkManager[4971]: <warn> (ttyS0): failed to look up interface index
>NetworkManager[4971]: <info> (ttyS0): new GSM/UMTS device (driver: 'serial_cs' ifindex: 0)
>NetworkManager[4971]: <info> (ttyS0): exported as /org/freedesktop/NetworkManager/Devices/3
>NetworkManager[4971]: <info> (ttyS0): now managed
>NetworkManager[4971]: <info> (ttyS0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>NetworkManager[4971]: <info> (ttyS0): deactivating device (reason 'managed') [2]
>NetworkManager[4971]: <info> (ttyS0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>NetworkManager[4971]: <info> caught signal 15, shutting down normally.
>NetworkManager[4971]: <info> (eth0): now unmanaged
>NetworkManager[4971]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
>NetworkManager[4971]: <info> (eth0): cleaning up...
>NetworkManager[4971]: <info> (eth0): taking down device.
>NetworkManager[4971]: <info> (wlan0): now unmanaged
>NetworkManager[4971]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'removed') [20 10 36]
>NetworkManager[4971]: <info> (ttyS0): now unmanaged
>NetworkManager[4971]: <info> (ttyS0): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
>NetworkManager[4971]: <info> (ttyS0): cleaning up...
>NetworkManager[4971]: <info> (ttyS0): taking down device.
>NetworkManager[4971]: <info> exiting (success)

Actions: View

Attachments on bug 871809: 636111 | 653686 | 653687 | 654661 | 654662 | 655223 | 655224