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
signature.asc
Description: This is a digitally signed message part
_______________________________________________ networkmanager-list mailing list [email protected] https://mail.gnome.org/mailman/listinfo/networkmanager-list
