RE: networkmanager-0.9.8.8 will not connect to ipv4 wired network



On Sat, 2014-04-05 at 12:54 +0000, John Frankish wrote:
Using networkmanager-0.9.8.8 and dhcpcd-6.3.2, I am unable to connect to
a wired connection eth0.

If networkmanager is stopped, dhcpcd will connect without problems.

The problem appears to be that networkmanager is stuck in a loop trying to
make an ipv6 connection when the connection is ipv4 - ipv6 is disabled on the
router.

The reason is that there is another dhcpcd process running, while
NetworkManager needs a private dhcpcd process, because it needs to
receive the options back from the dhcpcd that it runs.  More info below...

Ah - I tried this many times, I must have copied an example where dhcpcd was running by mistake.

There is another minor issue in that networkworkmanager is looking for
hosts in /usr/local/etc rather than /etc, but adding a symlink does not resolve
the issue.

/usr/local/etc would be due to a configure-time issue, like many projects
when you're running configure, you need to specify "--prefix=/usr --
sysconfdir=/etc --localstatedir=/var" to put things in the right place,
otherwise it will default to /usr/local to avoid overwriting your existing
installation.

Networkmanager was compiled to /usr/local - the logic is that the base system is under /usr and anything 
additional is under /usr/local.

Since /etc/resolv.conf and /etc/hosts are on the base system, I did not move them

Is there some way to disable ipv6 in a networkmanager conf file?

IPv6 is disabled in each connection profile by setting the IPv6 configuration
"method" to "ignore".  So for a keyfile, you would use:

[ipv6]
method=ignore

or just pick that method in the various GUI editors.

dhcpcd[30849]: dhcpcd already running on pid 30823
(/var/run/dhcpcd-eth0.pid)
NetworkManager[30842]: <info> (eth0): DHCPv4 client pid 30849 exited
with status 1

Here, the private dhcpcd that NetworkManager spawns with the options
that NetworkManager requires, has noticed that there is another dhcpcd
process running.  You can't have two DHCP clients running for the same
interface, otherwise they'll fight over which one handles DHCP.  So you don't
want the one you ran manually above to continue running when you're using
NetworkManager.

Thanks for the reply - I pasted another debug log below with the ipv6 errors removed - the full log is 
attached.

From the log, it looks as though things are working, but I cannot ping the router or google.

----------

Apr  7 17:33:30 box authpriv.notice sudo:       tc : TTY=tty1 ; PWD=/home/tc ; USER=root ; 
COMMAND=/usr/local/etc/init.d/dbus start
Apr  7 17:33:42 box authpriv.notice sudo:       tc : TTY=tty1 ; PWD=/home/tc ; USER=root ; 
COMMAND=/usr/local/etc/init.d/networkmanager start

$ ps aux | grep Net
 6236 root     /usr/local/sbin/NetworkManager
 6254 root     /usr/local/sbin/dhcpcd -B -K -L -G -c /usr/local/lib/NetworkManager/nm-dhcp-client.action -4 
eth0


Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> NetworkManager (version 0.9.8.9) is starting...
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> Read config file 
/usr/local/etc/NetworkManager/nm-system-settings.conf
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> WEXT support is enabled
Apr  7 17:33:42 box daemon.notice dbus[6232]: [system] Activating service name='org.freedesktop.PolicyKit1' 
(using servicehelper)
Apr  7 13:33:42 box daemon.info polkitd[6241]: Started polkitd version 0.112
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: Loading rules from directory 
/usr/local/etc/polkit-1/rules.d
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: Loading rules from directory 
/usr/local/share/polkit-1/rules.d
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: Error opening rules directory: Error opening directory 
'/usr/local/share/polkit-1/rules.d': No such file or directory (g-file-error-quark, 4)
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: <no filename>:0: can't open 
/usr/local/etc/polkit-1/rules.d/50-default.rules: No such file or directory
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: Error compiling script 
/usr/local/etc/polkit-1/rules.d/50-default.rules
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: Finished loading, compiling and executing 1 rules
Apr  7 17:33:42 box daemon.notice dbus[6232]: [system] Successfully activated service 
'org.freedesktop.PolicyKit1'
Apr  7 13:33:42 box authpriv.notice polkitd[6241]: Acquired the name org.freedesktop.PolicyKit1 on the system 
bus
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> Loaded plugin keyfile: (c) 2007 - 2010 Red Hat, 
Inc.  To report bugs please use the NetworkManager mailing list.
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.799349] [nm-modem-manager.c:376] 
modem_manager_disappeared(): trying to start the modem manager...
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.799658] [nm-modem-manager.c:280] 
poke_modem_cb(): Requesting to (re)launch modem-manager...
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> monitoring kernel firmware directory 
'/lib/firmware'.
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.801063] [nm-firewall-manager.c:257] 
nm_firewall_manager_init(): firewall is not running
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> WiFi hardware radio set enabled
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.808010] [nm-policy-hosts.c:72] 
nm_policy_hosts_clean_etc_hosts(): couldn't read /usr/local/etc/hosts: (4) Failed to open file 
'/usr/local/etc/hosts': No such file or directory
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> WiFi enabled by radio killswitch; enabled by 
state file
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> WWAN enabled by radio killswitch; enabled by 
state file
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> WiMAX enabled by radio killswitch; enabled by 
state file
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> Networking is enabled by state file
Apr  7 17:33:42 box daemon.warn NetworkManager[6236]: <warn> failed to allocate link cache: (-10) Operation 
not supported
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.808876] [nm-device-ethernet.c:1607] 
supports_ethtool_carrier_detect(): ethtool is supported
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.808924] [NetworkManagerUtils.c:630] 
nm_utils_get_proc_sys_net_value(): (eth0): error reading /proc/sys/net/ipv6/conf/eth0/accept_ra: (4) Failed 
to open file '/proc/sys/net/ipv6/conf/eth0/accept
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.808955] [NetworkManagerUtils.c:630] 
nm_utils_get_proc_sys_net_value(): (eth0): error reading /proc/sys/net/ipv6/conf/eth0/use_tempaddr: (4) 
Failed to open file '/proc/sys/net/ipv6/conf/eth0/use
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.808960] [nm-device-wired.c:313] 
constructor(): (eth0): kernel ifindex 5
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> (eth0): carrier is OFF
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.809067] [nm-device-ethernet.c:260] 
constructor(): (eth0): kernel ifindex 5
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.809228] [nm-device-ethernet.c:484] 
update_initial_hw_address(): (eth0): read initial MAC address 24:B6:FD:FA:E1:60
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> (eth0): new Ethernet device (driver: 'e1000e' 
ifindex: 5)
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> (eth0): exported as 
/org/freedesktop/NetworkManager/Devices/0
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <debug> [1396877622.809376] [nm-device.c:5322] 
nm_device_set_managed(): (eth0): now managed
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: unmanaged -> 
unavailable (reason 'managed') [10 20 2]
Apr  7 17:33:42 box daemon.info NetworkManager[6236]: <info> (eth0): bringing up device.
Apr  7 13:33:42 box user.debug kernel: e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <info> (eth0): preparing device.
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <info> (eth0): deactivating device (reason 'managed') 
[2]
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.61792] [nm-device-ethernet.c:383] 
_set_hw_addr(): (eth0): no MAC address change needed
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.61980] [nm-system.c:1404] 
nm_system_iface_flush_routes(): (eth0): flushing routes ifindex 5 family INET (2)
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.62453] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.1/32
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.62494] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.0/32
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.62513] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.0/8
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.62529] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.1/32
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.62546] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.255.255.255/32
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.62836] [nm-system.c:194] 
sync_addresses(): (eth0): syncing addresses (family 2)
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.63268] [nm-device.c:5154] 
nm_device_state_changed(): (eth0): device not yet available for transition to DISCONNECTED
Apr  7 13:33:43 box user.debug kernel: e1000e 0000:00:19.0: irq 43 for MSI/MSI-X
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <info> Added default wired connection 'Wired connection 
1' for /sys/devices/pci0000:00/0000:00:19.0/net/eth0
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.65581] 
[nm-settings-connection.c:1524] nm_settings_connection_read_and_fill_timestamp(): failed to read connection 
timestamp for '1d365341-b7ea-4c09-994e-da722222e03f': (4) Key file does not ha
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.66038] [nm-manager.c:1397] 
system_create_virtual_devices(): creating virtual devices...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/dummy0: couldn't 
determine device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/ip_vti0: couldn't 
determine device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/lo: couldn't determine 
device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/tunl0: couldn't 
determine device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/dummy0: couldn't 
determine device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/ip_vti0: couldn't 
determine device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/lo: couldn't determine 
device driver; ignoring...
Apr  7 17:33:43 box daemon.warn NetworkManager[6236]: <warn> /sys/devices/virtual/net/tunl0: couldn't 
determine device driver; ignoring...
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.69118] [nm-manager.c:1397] 
system_create_virtual_devices(): creating virtual devices...
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.69183] [main.c:613] main(): setting 
up local loopback
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.69608] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 5 flags 0x1003
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.71117] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 1 flags 0x10049
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.71165] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 2 flags 0x82
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.71203] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 3 flags 0x80
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.71244] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 4 flags 0x80
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <debug> [1396877623.71289] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 5 flags 0x1003
Apr  7 17:33:43 box daemon.notice dbus[6232]: [system] Activating service 
name='org.freedesktop.ModemManager1' (using servicehelper)
Apr  7 13:33:43 box daemon.info ModemManager[6250]: <info>  ModemManager (version 1.2.0) starting...
Apr  7 17:33:43 box daemon.notice dbus[6232]: [system] Successfully activated service 
'org.freedesktop.ModemManager1'
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <info> ModemManager disappeared from bus
Apr  7 17:33:43 box daemon.info NetworkManager[6236]: <info> ModemManager available in the bus
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.604605] [nm-netlink-monitor.c:164] 
link_msg_handler(): netlink link message: iface idx 5 flags 0x11043
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> (eth0): carrier now ON (device state 20)
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.604767] [nm-device.c:5261] 
nm_device_queue_state(): (eth0): queued state change to disconnected (id 39)
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.604825] [nm-device-wired.c:120] 
set_speed(): (eth0): speed is now 100 Mb/s
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.605077] [nm-device.c:5219] 
queued_set_state(): (eth0): running queued state change to disconnected (id 39)
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: unavailable -> 
disconnected (reason 'carrier-changed') [20 30 40]
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Auto-activating connection 'Wired connection 1'.
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) starting connection 'Wired 
connection 1'
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: disconnected -> 
prepare (reason 'none') [30 40 0]
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> NetworkManager state is now CONNECTING
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.607187] [nm-manager.c:4142] 
policy_activating_device_changed(): ActivatingConnection now Wired connection 1
Apr  7 13:33:44 box user.info kernel: e1000e: eth0 NIC Link is Up 100 Mbps Full Duplex, Flow Control: None
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) 
scheduled...
Apr  7 13:33:44 box user.info kernel: e1000e 0000:00:19.0 eth0: 10/100 speed: disabling TSO
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) 
started...
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 2 of 5 (Device 
Configure) scheduled...
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) 
complete.
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 2 of 5 (Device 
Configure) starting...
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: prepare -> config 
(reason 'none') [40 50 0]
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 2 of 5 (Device 
Configure) successful.
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.608629] [nm-device.c:3069] 
nm_device_activate_schedule_stage3_ip_config_start(): Activation (eth0) setting firewall zone 'default'
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.608650] [nm-firewall-manager.c:112] 
nm_firewall_manager_add_or_change_zone(): (eth0) firewall zone add/change skipped (not running)
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 3 of 5 (IP Configure 
Start) scheduled.
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 2 of 5 (Device 
Configure) complete.
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 3 of 5 (IP Configure 
Start) started...
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: config -> ip-config 
(reason 'none') [50 70 0]
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Beginning DHCPv4 transaction 
(timeout in 45 seconds)
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.609801] [nm-dhcp-dhcpcd.c:164] 
ip4_start(): running: /usr/local/sbin/dhcpcd -B -K -L -G -c 
/usr/local/lib/NetworkManager/nm-dhcp-client.action -4 eth0
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> dhcpcd started with pid 6254
...
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 3 of 5 (IP Configure 
Start) complete.
...
Apr  7 17:33:44 box daemon.info dhcpcd[6254]: version 6.3.2 starting
Apr  7 17:33:44 box daemon.info dhcpcd[6254]: DUID 00:01:00:01:1a:d5:65:b8:24:b6:fd:fa:e1:60
Apr  7 17:33:44 box daemon.info dhcpcd[6254]: eth0: IAID fd:fa:e1:60
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Apr  7 17:33:44 box daemon.info NetworkManager[6236]: <debug> [1396877624.631873] [nm-device.c:1945] 
dhcp4_state_changed(): (eth0): new DHCPv4 client state 1
Apr  7 17:33:44 box daemon.info dhcpcd[6254]: eth0: soliciting a DHCP lease
...
Apr  7 17:33:47 box daemon.info dhcpcd[6254]: eth0: offered 10.180.20.137 from 10.180.1.10
...
Apr  7 17:33:53 box daemon.info dhcpcd[6254]: eth0: leased 10.180.20.137 for 259200 seconds
Apr  7 17:33:53 box daemon.info dhcpcd[6254]: eth0: adding route to 10.180.20.0/24
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info> (eth0): DHCPv4 state changed preinit -> bound
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <debug> [1396877633.126926] [nm-device.c:1945] 
dhcp4_state_changed(): (eth0): new DHCPv4 client state 3
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info>   address 10.180.20.137
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info>   prefix 24 (255.255.255.0)
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info>   gateway 10.180.20.1
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info>   nameserver '10.180.1.10'
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info>   domain name 'mena.saxonservices.local'
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Configure 
Commit) scheduled...
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) 
started...
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <debug> [1396877633.131035] [nm-system.c:194] 
sync_addresses(): (eth0): syncing addresses (family 2)
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <debug> [1396877633.131108] [nm-system.c:247] 
sync_addresses(): (eth0): removing address '10.180.20.137/24'
Apr  7 17:33:53 box daemon.info NetworkManager[6236]: <debug> [1396877633.131230] [nm-system.c:280] 
sync_addresses(): (eth0): adding address '10.180.20.137/0'
Apr  7 17:33:53 box daemon.info dhcpcd[6254]: eth0: removing IP address 10.180.20.137/24
Apr  7 17:33:53 box daemon.info dhcpcd[6254]: eth0: deleting route to 10.180.20.0/24
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.131875] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.1/32
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.131940] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.0/32
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.131960] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.0/8
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.131976] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.0.0.1/32
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.131992] [nm-netlink-utils.c:356] 
dump_route():   route idx 1 family INET (2) addr 127.255.255.255/32
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.132188] [nm-dns-manager.c:995] 
nm_dns_manager_begin_updates(): (device_ip4_config_changed): queueing DNS updates (1)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.132232] [nm-dns-manager.c:1013] 
nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration did not change
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.132248] [nm-dns-manager.c:1017] 
nm_dns_manager_end_updates(): (device_ip4_config_changed): no DNS changes to commit (0)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: ip-config -> 
secondaries (reason 'none') [70 90 0]
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.132545] [nm-device.c:5261] 
nm_device_queue_state(): (eth0): queued state change to activated (id 60)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.132647] [nm-manager.c:4142] 
policy_activating_device_changed(): ActivatingConnection now (none)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.132742] [nm-device.c:5191] 
nm_device_state_changed(): (eth0): device entered SECONDARIES state
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) 
complete.
...
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> (eth0): DHCPv4 state changed bound -> expire
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.134386] [nm-device.c:1945] 
dhcp4_state_changed(): (eth0): new DHCPv4 client state 17
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.134794] [nm-device.c:5219] 
queued_set_state(): (eth0): running queued state change to activated (id 60)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> (eth0): device state change: secondaries -> 
activated (reason 'none') [90 100 0]
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.135241] [nm-manager.c:4050] 
connectivity_changed(): connectivity checking indicates FULL
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> NetworkManager state is now CONNECTED_GLOBAL
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.135685] [nm-dns-manager.c:995] 
nm_dns_manager_begin_updates(): (device_state_changed): queueing DNS updates (1)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.135727] [nm-dns-manager.c:995] 
nm_dns_manager_begin_updates(): (update_routing_and_dns): queueing DNS updates (2)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> Policy set 'Wired connection 1' (eth0) as 
default for IPv4 routing and DNS.
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.136267] [nm-manager.c:4109] 
policy_default_device_changed(): PrimaryConnection now Wired connection 1
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.136354] [nm-dns-manager.c:1013] 
nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.136377] [nm-dns-manager.c:1017] 
nm_dns_manager_end_updates(): (update_routing_and_dns): no DNS changes to commit (1)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.136401] [nm-dns-manager.c:1013] 
nm_dns_manager_end_updates(): (nm_dns_manager_end_updates): DNS configuration changed
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.136416] [nm-dns-manager.c:1022] 
nm_dns_manager_end_updates(): (device_state_changed): committing DNS changes (0)
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <debug> [1396877634.136430] [nm-dns-manager.c:591] 
update_dns(): updating resolv.conf
Apr  7 17:33:54 box daemon.info NetworkManager[6236]: <info> Activation (eth0) successful, device activated.
Apr  7 17:33:54 box daemon.notice dbus[6232]: [system] Activating service 
name='org.freedesktop.nm_dispatcher' (using servicehelper)
Apr  7 17:33:54 box daemon.notice dbus[6232]: [system] Successfully activated service 
'org.freedesktop.nm_dispatcher'
...
Apr  7 17:34:04 box daemon.info NetworkManager[6236]: <info> (eth0): IP6 addrconf timed out or failed.
Apr  7 17:34:04 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure 
Timeout) scheduled...
Apr  7 17:34:04 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure 
Timeout) started...
Apr  7 17:34:04 box daemon.info NetworkManager[6236]: <info> Activation (eth0) Stage 4 of 5 (IPv6 Configure 
Timeout) complete.

Attachment: networkmanager_log.tar.gz
Description: networkmanager_log.tar.gz



[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]