Dear NetworkManager folks,
using Debian Sid/unstable with systemd 215-10 and NetworkManager
0.9.10.0-5, I set up systemd-networkd for the only physical interface
`eth0`. I left NetworkManager installed in case someone plugs in some
USB device.
The interface `eth0` is configured over DHCP.
Running `systemd-analyze blame`, it shows that the service
NetworkManager still needs 580 ms to finish. Note that this is smaller
than the actual needed time.
Looking at the journal with `sudo journalctl -a --full` the following is
looged for systemd-networkd.
Jan 23 06:54:17 my-asrock-a780-fullhd systemd-networkd[381]: lo : gained carrier
Jan 23 06:54:17 my-asrock-a780-fullhd systemd-networkd[381]: eth0 : link configured
Jan 23 06:54:19 my-asrock-a780-fullhd systemd-networkd[381]: eth0 : gained carrier
Jan 23 06:54:20 my-asrock-a780-fullhd systemd-networkd[381]: eth0 : DHCPv4 address
192.168.178.38/24 via 192.168.178.1
Jan 23 06:54:20 my-asrock-a780-fullhd systemd-networkd[381]: eth0 : link configured
During the same time, NetworkManager is trying to configure the device
too over DHCP. At least a `dhclient` process for the interface `eth0` is
started by it.
$ systemctl --full status NetworkManager
● NetworkManager.service - Network Manager
Loaded: loaded (/lib/systemd/system/NetworkManager.service; enabled)
Active: active (running) since Fr 2015-01-23 06:54:17 CET; 4min 45s ago
Main PID: 366 (NetworkManager)
CGroup: /system.slice/NetworkManager.service
├─366 /usr/sbin/NetworkManager --no-daemon
└─470 /sbin/dhclient -d -q -sf /usr/lib/NetworkManager/nm-dhcp-helper -pf
/var/run/dhclient-eth0.pid -lf
/var/lib/NetworkManager/dhclient-acfa208e-dbb9-4236-86f3-6e06d6615220-eth0.lease -cf
/var/lib/NetworkManager/dhclient-eth0.conf eth0
Please find the output of `sudo journalctl -a --full -u NetworkManager`
pasted below.
Could you please confirm that this is the expected behavior? If not, has
this been fixed in later versions or do I need to configure something
differently?
Thanks,
Paul
-- Logs begin at Fr 2015-01-23 06:54:16 CET, end at Fr 2015-01-23 07:00:41 CET. --
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> NetworkManager (version 0.9.10.0) is
starting...
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Read config:
/etc/NetworkManager/NetworkManager.conf
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> WEXT support is enabled
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> init!
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> update_system_hostname
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> interface-parser: parsing file
/etc/network/interfaces
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> interface-parser: finished parsing
file /etc/network/interfaces
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> management mode: unmanaged
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> devices added (path:
/sys/devices/pci0000:00/0000:00:0a.0/0000:04:00.0/net/eth0, iface: eth0)
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> device added (path:
/sys/devices/pci0000:00/0000:00:0a.0/0000:04:00.0/net/eth0, iface: eth0): no ifupdown configuration found.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> devices added (path:
/sys/devices/virtual/net/lo, iface: lo)
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> device added (path:
/sys/devices/virtual/net/lo, iface: lo): no ifupdown configuration found.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> end _init.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Loaded plugin ifupdown: (C) 2008 Canonical
Ltd. To report bugs please use the NetworkManager mailing list.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Loaded plugin keyfile: (c) 2007 - 2013 Red
Hat, Inc. To report bugs please use the NetworkManager mailing list.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (21303456) ... get_connections.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (21303456) ... get_connections
(managed=false): return empty list.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> get unmanaged devices count: 0
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> monitoring kernel firmware directory
'/lib/firmware'.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> monitoring ifupdown state file
'/run/network/ifstate'.
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> WiFi hardware radio set enabled
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> WWAN hardware radio set enabled
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-bluetooth.so
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wifi.so
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-adsl.so
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Loaded device plugin:
/usr/lib/x86_64-linux-gnu/NetworkManager/libnm-device-plugin-wwan.so
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> WiFi enabled by radio killswitch; enabled
by state file
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> WWAN enabled by radio killswitch; enabled
by state file
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> WiMAX enabled by radio killswitch; enabled
by state file
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> Networking is enabled by state file
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (lo): link connected
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (lo): carrier is ON
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (lo): new Generic device (driver: 'unknown'
ifindex: 1)
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (lo): exported as
/org/freedesktop/NetworkManager/Devices/0
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): carrier is OFF
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): new Ethernet device (driver:
'r8169' ifindex: 2)
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): exported as
/org/freedesktop/NetworkManager/Devices/1
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: unmanaged ->
unavailable (reason 'managed') [10 20 2]
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): preparing device
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): created default wired connection
'Kabelgebundene Verbindung 1'
Jan 23 06:54:17 my-asrock-a780-fullhd NetworkManager[366]: <warn> Error: failed to open /run/network/ifstate
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): link connected
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: unavailable ->
disconnected (reason 'carrier-changed') [20 30 40]
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Auto-activating connection 'Kabelgebundene
Verbindung 1'.
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) starting connection
'Kabelgebundene Verbindung 1'
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 1 of 5 (Device
Prepare) scheduled...
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 1 of 5 (Device
Prepare) started...
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: disconnected
-> prepare (reason 'none') [30 40 0]
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> NetworkManager state is now CONNECTING
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 2 of 5 (Device
Configure) scheduled...
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 1 of 5 (Device
Prepare) complete.
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 2 of 5 (Device
Configure) starting...
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: prepare ->
config (reason 'none') [40 50 0]
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 2 of 5 (Device
Configure) successful.
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 3 of 5 (IP
Configure Start) scheduled.
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 2 of 5 (Device
Configure) complete.
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 3 of 5 (IP
Configure Start) started...
Jan 23 06:54:19 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: config ->
ip-config (reason 'none') [50 70 0]
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Beginning DHCPv4
transaction (timeout in 45 seconds)
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> dhclient started with pid 470
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 3 of 5 (IP
Configure Start) complete.
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): DHCPv4 state changed nbi -> preinit
Jan 23 06:54:20 my-asrock-a780-fullhd dhclient[470]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval
5
Jan 23 06:54:20 my-asrock-a780-fullhd dhclient[470]: DHCPREQUEST on eth0 to 255.255.255.255 port 67
Jan 23 06:54:20 my-asrock-a780-fullhd dhclient[470]: DHCPOFFER from 192.168.178.1
Jan 23 06:54:20 my-asrock-a780-fullhd dhclient[470]: DHCPACK from 192.168.178.1
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): DHCPv4 state changed preinit ->
bound
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> address 192.168.178.38
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> plen 24 (255.255.255.0)
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> gateway 192.168.178.1
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> server identifier 192.168.178.1
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> lease time 864000
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> nameserver '192.168.178.1'
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> domain name 'fritz.box'
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 5 of 5 (IPv4
Configure Commit) scheduled...
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 5 of 5 (IPv4
Commit) started...
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: _nl_get_vtable: assertion 'vtable.handle' failed
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: ip-config ->
ip-check (reason 'none') [70 80 0]
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) Stage 5 of 5 (IPv4
Commit) complete.
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: ip-check ->
secondaries (reason 'none') [80 90 0]
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> (eth0): device state change: secondaries ->
activated (reason 'none') [90 100 0]
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> NetworkManager state is now CONNECTED_LOCAL
Jan 23 06:54:20 my-asrock-a780-fullhd dhclient[470]: bound to 192.168.178.38 -- renewal in 400980 seconds.
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> NetworkManager state is now CONNECTED_GLOBAL
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Policy set 'Kabelgebundene Verbindung 1'
(eth0) as default for IPv4 routing and DNS.
Jan 23 06:54:20 my-asrock-a780-fullhd NetworkManager[366]: <info> Activation (eth0) successful, device
activated.
Jan 23 06:54:23 my-asrock-a780-fullhd NetworkManager[366]: <info> wpa_supplicant started
Jan 23 06:54:24 my-asrock-a780-fullhd NetworkManager[366]: <info> startup complete
Attachment:
signature.asc
Description: This is a digitally signed message part