Discussion:
systemd-networkd not discovering all devices at bootup, and thus no network is configured
(too old to reply)
Keller, Jacob E
2015-02-03 19:00:45 UTC
Permalink
Hey,

I've recently been using systemd-networkd to great success on a few of
my machines here. However I ran into an interesting problem on at least
2 machines so far. I've included the output of journal for
systemd-networkd with Environment=SYSTEMD_LOG_LEVEL=debug as was
suggested on another post. In addition the only network file I have
configured is em0.network which contains the following,

$cat /etc/systemd/network/em0.network
[Match]
Name=em0

[Network]
DHCP=Yes

The journalctl for systemd-networkd after bootup is,

-- Logs begin at Mon 2012-12-31 20:02:09 PST, end at Tue 2015-02-03 10:57:09 PST. --
Feb 03 10:37:44 jekeller-copperpass systemd-networkd[1055]: timestamp of '/etc/systemd/network' changed
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link 7 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link 8 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : MAC address: 7e:5e:7c:31:44:4d
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : MAC address: b6:ec:a9:4b:e5:42
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : getting address failed: Device or resource busy
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.7 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=signal sender=org.freedesktop.DBus destination=:1.7 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link 9 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link 10 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +UP +LOWER_UP +RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : gained carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +UP
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: -UP -LOWER_UP -RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : lost carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : removing non-existent address: fe80::5054:ff:fe84:d2d5/64 (valid for ever)

Notice that only a bunch of my fake devices, hand made bridges, and such
are found during bootup. However, if I manually restart networkd then it
finds all the devices,


Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: timestamp of '/etc/systemd/network' changed
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link 10 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : flags change: +UP +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link 9 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link 8 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link 7 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link 6 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link 5 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link 4 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link 3 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link 2 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link 1 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : saved original MTU: 0
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.71 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=signal sender=org.freedesktop.DBus destination=:1.71 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : found matching network '/etc/systemd/network/em0.network'
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : bringing link up
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: ::1/128 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: 127.0.0.1/8 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +UP
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +LOWER_UP +RUNNING
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : gained carrier
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : acquiring DHCPv4 lease
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): STARTED on ifindex 2
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : discovering IPv6 routers
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Start Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Error sending Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): DISCOVER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): OFFER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): REQUEST (requesting)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): ACK
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): lease expires in 6h 48min 31.142449s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T2 expires in 5h 57min 27.825629s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T1 expires in 3h 24min 17.074622s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : DHCPv4 address 192.168.0.217/24 via 192.168.0.1
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: 192.168.0.217/24 (valid for 6h 48min 34s)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : link configured
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: fe80::21e:67ff:fe9a:c01f/64 (valid for ever)
Feb 03 10:51:57 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:01 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 1.053130s
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 2.169218s
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 4.550442s
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 9.418559s
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 19.178444s
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 36.913006s

Any suggestions as to why networkd doesn't (a) find em0 during bootup,
or (b) configure it when it does appear after boot up?

Thanks for all your work, much appreciated.

Regards,
Jake
Keller, Jacob E
2015-02-03 22:51:06 UTC
Permalink
Post by Keller, Jacob E
Hey,
I've recently been using systemd-networkd to great success on a few of
my machines here. However I ran into an interesting problem on at least
2 machines so far. I've included the output of journal for
systemd-networkd with Environment=SYSTEMD_LOG_LEVEL=debug as was
suggested on another post. In addition the only network file I have
configured is em0.network which contains the following,
Short answer: I removed biosdevname and then it was all happy. This
seems like a problem or delay in how long biosdevname takes to indicate
the name and honestly systemd persistent names are better anyways.

Regards,
Jake
Keller, Jacob E
2015-02-04 21:58:53 UTC
Permalink
Hi again,
Post by Keller, Jacob E
Hey,
I've recently been using systemd-networkd to great success on a few of
my machines here. However I ran into an interesting problem on at least
2 machines so far. I've included the output of journal for
systemd-networkd with Environment=SYSTEMD_LOG_LEVEL=debug as was
suggested on another post. In addition the only network file I have
configured is em0.network which contains the following,
$cat /etc/systemd/network/em0.network
[Match]
Name=em0
[Network]
DHCP=Yes
The journalctl for systemd-networkd after bootup is,
-- Logs begin at Mon 2012-12-31 20:02:09 PST, end at Tue 2015-02-03 10:57:09 PST. --
Feb 03 10:37:44 jekeller-copperpass systemd-networkd[1055]: timestamp of '/etc/systemd/network' changed
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link 7 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link 8 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : MAC address: 7e:5e:7c:31:44:4d
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : MAC address: b6:ec:a9:4b:e5:42
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : getting address failed: Device or resource busy
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.7 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=signal sender=org.freedesktop.DBus destination=:1.7 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link 9 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link 10 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +UP +LOWER_UP +RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : gained carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +UP
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: -UP -LOWER_UP -RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : lost carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : removing non-existent address: fe80::5054:ff:fe84:d2d5/64 (valid for ever)
Notice that only a bunch of my fake devices, hand made bridges, and such
are found during bootup. However, if I manually restart networkd then it
finds all the devices,
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: timestamp of '/etc/systemd/network' changed
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link 10 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : flags change: +UP +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link 9 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link 8 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link 7 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link 6 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link 5 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link 4 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link 3 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link 2 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link 1 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : saved original MTU: 0
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.71 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=signal sender=org.freedesktop.DBus destination=:1.71 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : found matching network '/etc/systemd/network/em0.network'
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : bringing link up
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: ::1/128 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: 127.0.0.1/8 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +UP
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +LOWER_UP +RUNNING
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : gained carrier
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : acquiring DHCPv4 lease
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): STARTED on ifindex 2
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : discovering IPv6 routers
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Start Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Error sending Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): DISCOVER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): OFFER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): REQUEST (requesting)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): ACK
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): lease expires in 6h 48min 31.142449s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T2 expires in 5h 57min 27.825629s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T1 expires in 3h 24min 17.074622s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : DHCPv4 address 192.168.0.217/24 via 192.168.0.1
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: 192.168.0.217/24 (valid for 6h 48min 34s)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : link configured
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: fe80::21e:67ff:fe9a:c01f/64 (valid for ever)
Feb 03 10:51:57 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:01 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 1.053130s
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 2.169218s
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 4.550442s
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 9.418559s
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 19.178444s
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 36.913006s
Any suggestions as to why networkd doesn't (a) find em0 during bootup,
or (b) configure it when it does appear after boot up?
Thanks for all your work, much appreciated.
Regards,
Jake
I actually see the same behavior now again, so the removal of
biosdevname does not solve this problem! :(

Is there any more information I can provide?

Regards,
Jake
Lennart Poettering
2015-02-11 18:39:20 UTC
Permalink
Post by Keller, Jacob E
Hi again,
Post by Keller, Jacob E
Hey,
I've recently been using systemd-networkd to great success on a few of
my machines here. However I ran into an interesting problem on at least
2 machines so far. I've included the output of journal for
systemd-networkd with Environment=SYSTEMD_LOG_LEVEL=debug as was
suggested on another post. In addition the only network file I have
configured is em0.network which contains the following,
$cat /etc/systemd/network/em0.network
[Match]
Name=em0
[Network]
DHCP=Yes
The journalctl for systemd-networkd after bootup is,
-- Logs begin at Mon 2012-12-31 20:02:09 PST, end at Tue 2015-02-03 10:57:09 PST. --
Feb 03 10:37:44 jekeller-copperpass systemd-networkd[1055]: timestamp of '/etc/systemd/network' changed
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link 7 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link 8 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : MAC address: 7e:5e:7c:31:44:4d
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : MAC address: b6:ec:a9:4b:e5:42
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : getting address failed: Device or resource busy
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.7 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=signal sender=org.freedesktop.DBus destination=:1.7 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link 9 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link 10 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +UP +LOWER_UP +RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : gained carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +UP
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: -UP -LOWER_UP -RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : lost carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : removing non-existent address: fe80::5054:ff:fe84:d2d5/64 (valid for ever)
Notice that only a bunch of my fake devices, hand made bridges, and such
are found during bootup. However, if I manually restart networkd then it
finds all the devices,
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: timestamp of '/etc/systemd/network' changed
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link 10 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : flags change: +UP +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link 9 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link 8 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link 7 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link 6 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link 5 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link 4 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link 3 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link 2 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link 1 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : saved original MTU: 0
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.71 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=signal sender=org.freedesktop.DBus destination=:1.71 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : found matching network '/etc/systemd/network/em0.network'
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : bringing link up
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: ::1/128 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: 127.0.0.1/8 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +UP
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +LOWER_UP +RUNNING
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : gained carrier
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : acquiring DHCPv4 lease
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): STARTED on ifindex 2
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : discovering IPv6 routers
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Start Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Error sending Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): DISCOVER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): OFFER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): REQUEST (requesting)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): ACK
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): lease expires in 6h 48min 31.142449s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T2 expires in 5h 57min 27.825629s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T1 expires in 3h 24min 17.074622s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : DHCPv4 address 192.168.0.217/24 via 192.168.0.1
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: 192.168.0.217/24 (valid for 6h 48min 34s)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : link configured
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: fe80::21e:67ff:fe9a:c01f/64 (valid for ever)
Feb 03 10:51:57 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:01 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 1.053130s
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 2.169218s
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 4.550442s
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 9.418559s
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 19.178444s
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 36.913006s
Any suggestions as to why networkd doesn't (a) find em0 during bootup,
or (b) configure it when it does appear after boot up?
Thanks for all your work, much appreciated.
Regards,
Jake
I actually see the same behavior now again, so the removal of
biosdevname does not solve this problem! :(
Is there any more information I can provide?
Hmm, it appears as if networkd completely misses the netlink messages
describing your em0 link.

To debug this it might be worse adding debug log messages to
manager_rtnl_process_link() to see if any rtnl messages announcing the
interface are received by networkd. If no such message arrives there,
then this indicates a kernel issue, otherwise a bug in networkd.

Lennart
--
Lennart Poettering, Red Hat
Keller, Jacob E
2015-02-11 20:22:01 UTC
Permalink
Post by Lennart Poettering
Post by Keller, Jacob E
Hi again,
Post by Keller, Jacob E
Hey,
I've recently been using systemd-networkd to great success on a few of
my machines here. However I ran into an interesting problem on at least
2 machines so far. I've included the output of journal for
systemd-networkd with Environment=SYSTEMD_LOG_LEVEL=debug as was
suggested on another post. In addition the only network file I have
configured is em0.network which contains the following,
$cat /etc/systemd/network/em0.network
[Match]
Name=em0
[Network]
DHCP=Yes
The journalctl for systemd-networkd after bootup is,
-- Logs begin at Mon 2012-12-31 20:02:09 PST, end at Tue 2015-02-03 10:57:09 PST. --
Feb 03 10:37:44 jekeller-copperpass systemd-networkd[1055]: timestamp of '/etc/systemd/network' changed
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link 7 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link 8 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : MAC address: 7e:5e:7c:31:44:4d
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : MAC address: b6:ec:a9:4b:e5:42
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : getting address failed: Device or resource busy
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.7 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: Got message type=signal sender=org.freedesktop.DBus destination=:1.7 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: br-geneve0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link 9 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0 : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link 10 added
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : udev initialized link
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : saved original MTU: 1500
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : link state is up-to-date
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : unmanaged
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:45 jekeller-copperpass systemd-networkd[1055]: rtnl: received address for a nonexistent link (1), ignoring
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : MAC address: 52:54:00:84:d2:d5
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: +UP +LOWER_UP +RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : gained carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0 : flags change: +UP
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : flags change: -UP -LOWER_UP -RUNNING
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : lost carrier
Feb 03 10:37:46 jekeller-copperpass systemd-networkd[1055]: virbr0-nic : removing non-existent address: fe80::5054:ff:fe84:d2d5/64 (valid for ever)
Notice that only a bunch of my fake devices, hand made bridges, and such
are found during bootup. However, if I manually restart networkd then it
finds all the devices,
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: timestamp of '/etc/systemd/network' changed
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link 10 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : flags change: +UP +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link 9 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link 8 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link 7 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link 6 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link 5 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link 4 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link 3 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +MULTICAST +BROADCAST
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link 2 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : saved original MTU: 1500
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link 1 added
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : udev initialized link
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : saved original MTU: 0
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0-nic : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.71 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: Got message type=signal sender=org.freedesktop.DBus destination=:1.71 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br-geneve0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: br0 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: ovs-system : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f3 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f2 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: enp4s0f1 : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : found matching network '/etc/systemd/network/em0.network'
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : bringing link up
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : getting address failed: Device or resource busy
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : link state is up-to-date
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : unmanaged
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: ::1/128 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: virbr0 : added address: 192.168.122.1/24 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: lo : added address: 127.0.0.1/8 (valid for ever)
Feb 03 10:51:49 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +UP
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : flags change: +LOWER_UP +RUNNING
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : gained carrier
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : acquiring DHCPv4 lease
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): STARTED on ifindex 2
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: em0 : discovering IPv6 routers
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Start Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Error sending Router Solicitation
Feb 03 10:51:53 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): DISCOVER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): OFFER
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): REQUEST (requesting)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): ACK
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): lease expires in 6h 48min 31.142449s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T2 expires in 5h 57min 27.825629s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: DHCP CLIENT (0x3d9e3244): T1 expires in 3h 24min 17.074622s
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : DHCPv4 address 192.168.0.217/24 via 192.168.0.1
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: 192.168.0.217/24 (valid for 6h 48min 34s)
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : link configured
Feb 03 10:51:54 jekeller-copperpass systemd-networkd[2537]: em0 : added address: fe80::21e:67ff:fe9a:c01f/64 (valid for ever)
Feb 03 10:51:57 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:01 jekeller-copperpass systemd-networkd[2537]: ICMPv6 CLIENT: Sent Router Solicitation
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:05 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 1.053130s
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:06 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 2.169218s
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:08 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 4.550442s
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:13 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 9.418559s
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:22 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 19.178444s
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Sent SOLICIT
Feb 03 10:52:42 jekeller-copperpass systemd-networkd[2537]: DHCPv6 CLIENT: Next retransmission in 36.913006s
Any suggestions as to why networkd doesn't (a) find em0 during bootup,
or (b) configure it when it does appear after boot up?
Thanks for all your work, much appreciated.
Regards,
Jake
I actually see the same behavior now again, so the removal of
biosdevname does not solve this problem! :(
Is there any more information I can provide?
Hmm, it appears as if networkd completely misses the netlink messages
describing your em0 link.
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Post by Lennart Poettering
To debug this it might be worse adding debug log messages to
manager_rtnl_process_link() to see if any rtnl messages announcing the
interface are received by networkd. If no such message arrives there,
then this indicates a kernel issue, otherwise a bug in networkd.
Lennart
How would I go about doing that? I am guessing that means modifying the
source of networkd?

I'm also somewhat unfamiliar with the best practice for installing a
local copy of systemd from source rather than from the Fedora RPMs..

I would be willing to try that first to see if upstream systemd fixes my
issue.

Regards,
Jake
Lennart Poettering
2015-02-11 20:32:00 UTC
Permalink
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
I actually see the same behavior now again, so the removal of
biosdevname does not solve this problem! :(
Is there any more information I can provide?
Hmm, it appears as if networkd completely misses the netlink messages
describing your em0 link.
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
Post by Keller, Jacob E
Post by Lennart Poettering
To debug this it might be worse adding debug log messages to
manager_rtnl_process_link() to see if any rtnl messages announcing the
interface are received by networkd. If no such message arrives there,
then this indicates a kernel issue, otherwise a bug in networkd.
Lennart
How would I go about doing that? I am guessing that means modifying the
source of networkd?
Correct.
Post by Keller, Jacob E
I'm also somewhat unfamiliar with the best practice for installing a
local copy of systemd from source rather than from the Fedora RPMs..
Most of us tend to run git versions of systemd, and simply build them
with "./autogen.sh c && sudo make install". But of course, you should
know what you do then, and there's no easy path back to the FEdora version...

Lennart
--
Lennart Poettering, Red Hat
Keller, Jacob E
2015-02-11 20:36:19 UTC
Permalink
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
I actually see the same behavior now again, so the removal of
biosdevname does not solve this problem! :(
Is there any more information I can provide?
Hmm, it appears as if networkd completely misses the netlink messages
describing your em0 link.
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
What about network device renames? Devices are renamed after they
appear. Is it also possible that networkd is started too early and thus
the devices haven't actually appeared yet? But.. then it should get the
message once it does appear? (assuming here that it continues listening
to rtnl messages about links)
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
To debug this it might be worse adding debug log messages to
manager_rtnl_process_link() to see if any rtnl messages announcing the
interface are received by networkd. If no such message arrives there,
then this indicates a kernel issue, otherwise a bug in networkd.
Lennart
How would I go about doing that? I am guessing that means modifying the
source of networkd?
Correct.
Post by Keller, Jacob E
I'm also somewhat unfamiliar with the best practice for installing a
local copy of systemd from source rather than from the Fedora RPMs..
Most of us tend to run git versions of systemd, and simply build them
with "./autogen.sh c && sudo make install". But of course, you should
know what you do then, and there's no easy path back to the FEdora version...
Lennart
How difficult would it be to create an RPM? I have a test machine I'm ok
trying this on, so that's not a huge deal if it can't go back. I'll give
this a shot first and see if problem persists.

Regards,
Jake
Lennart Poettering
2015-02-11 20:46:28 UTC
Permalink
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
What about network device renames? Devices are renamed after they
appear. Is it also possible that networkd is started too early and thus
the devices haven't actually appeared yet? But.. then it should get the
message once it does appear? (assuming here that it continues listening
to rtnl messages about links)
networkd should get all netlink msgs about all of this just fine, and
be able to track renames and everything.
Post by Keller, Jacob E
How difficult would it be to create an RPM?
Well, you can get the spec file from fedpkg and then use it on a
tarball from systemd git made with "make dist". But you probably need
to make a number of updates to the .spec file. Unless you are an RPM
guru I wouldn't bother.

Lennart
--
Lennart Poettering, Red Hat
Keller, Jacob E
2015-02-11 22:28:25 UTC
Permalink
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
What about network device renames? Devices are renamed after they
appear. Is it also possible that networkd is started too early and thus
the devices haven't actually appeared yet? But.. then it should get the
message once it does appear? (assuming here that it continues listening
to rtnl messages about links)
networkd should get all netlink msgs about all of this just fine, and
be able to track renames and everything.
Post by Keller, Jacob E
How difficult would it be to create an RPM?
Well, you can get the spec file from fedpkg and then use it on a
tarball from systemd git made with "make dist". But you probably need
to make a number of updates to the .spec file. Unless you are an RPM
guru I wouldn't bother.
Lennart
Ok. I got the upstream systemd installed. Now I get a very different
behavior, which I am unsure of.

I cut off the last message as it repeated some odd ten thousand times or
so... Presumably retry/failing...

At any rate, it appears possible that some configuration is wrong, or
similar? Any suggestions/thoughts now?

Regards,
Jake

-- Logs begin at Mon 2012-12-31 20:02:09 PST, end at Wed 2015-02-11 14:23:01 PST. --
Feb 11 14:21:46 jekeller-copperpass systemd[1]: Starting Network Service...
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.1 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddMatch cookie=2 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=RequestName cookie=3 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: timestamp of '/etc/systemd/network' changed
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: timestamp of '/usr/lib/systemd/network' changed
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f3 : flags change: +MULTICAST +BROADCAST
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f3 : link 7 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2d6d0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.3/net/enp4s0f3'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2d6d0 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f3 : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f3 : saved original MTU: 1500
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f2 : flags change: +MULTICAST +BROADCAST
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f2 : link 6 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2eba0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.2/net/enp4s0f2'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2eba0 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f2 : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f2 : saved original MTU: 1500
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f1 : flags change: +MULTICAST +BROADCAST
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f1 : link 5 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2ffe0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.1/net/enp4s0f1'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2ffe0 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f1 : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f1 : saved original MTU: 1500
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : flags change: +MULTICAST +BROADCAST
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : link 4 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c31440 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.0/net/enp4s0f0'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c31440 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : saved original MTU: 1500
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens9 : flags change: +MULTICAST +BROADCAST
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens9 : link 3 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c32860 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:09.0/0000:09:00.0/net/ens9'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c32860 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens9 : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens9 : saved original MTU: 1500
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens8 : flags change: +MULTICAST +BROADCAST
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens8 : link 2 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c33c30 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:08.0/0000:08:00.0/net/ens8'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c33c30 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens8 : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens8 : saved original MTU: 1500
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=4 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : link 1 added
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c35110 has devpath '/devices/virtual/net/lo'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c35110 filled with db file data
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : udev initialized link
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : saved original MTU: 0
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : Adding address: ::1/128 (valid for ever)
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : Adding address: 127.0.0.1/8 (valid for ever)
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Enumeration completed
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f3 : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c27990 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.3'
Feb 11 14:21:47 jekeller-copperpass systemd[1]: Started Network Service.
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f3 : unmanaged
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_37 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=5 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f2 : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c37810 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.2'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f2 : unmanaged
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_36 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=6 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f1 : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c37ac0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.1'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f1 : unmanaged
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_35 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2ac30 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.0'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : found matching network '/etc/systemd/network/enp4s0f0.network'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=8 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : bringing link up
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=9 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=10 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens9 : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2b990 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:09.0/0000:09:00.0'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens9 : unmanaged
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=11 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens8 : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c35b30 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:08.0/0000:08:00.0'
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: ens8 : unmanaged
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=12 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : link state is up-to-date
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: lo : unmanaged
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=13 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : flags change: +UP
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=14 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=15 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : enp4s0f0 : could not bring up interface: Invalid argument
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Got message type=signal sender=org.freedesktop.DBus destination=:1.1 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: Got message type=signal sender=org.freedesktop.DBus destination=:1.1 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=4 reply_cookie=0 error=n/a
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : flags change: +LOWER_UP +RUNNING
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=16 reply_cookie=0 error=n/a
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=17 reply_cookie=0 error=n/a
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : gained carrier
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : acquiring DHCPv4 lease
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: DHCP CLIENT (0xc7096673): STARTED on ifindex 4
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2cb80 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.0/net/enp4s0f0'
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: device 0x7fef36c2cb80 filled with db file data
Feb 11 14:21:51 jekeller-copperpass systemd-networkd[1068]: DHCP CLIENT (0xc7096673): DISCOVER
Feb 11 14:21:52 jekeller-copperpass systemd-networkd[1068]: DHCP CLIENT (0xc7096673): could not receive message from raw socket: Invalid argument
Lennart Poettering
2015-02-12 10:45:19 UTC
Permalink
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
What about network device renames? Devices are renamed after they
appear. Is it also possible that networkd is started too early and thus
the devices haven't actually appeared yet? But.. then it should get the
message once it does appear? (assuming here that it continues listening
to rtnl messages about links)
networkd should get all netlink msgs about all of this just fine, and
be able to track renames and everything.
Post by Keller, Jacob E
How difficult would it be to create an RPM?
Well, you can get the spec file from fedpkg and then use it on a
tarball from systemd git made with "make dist". But you probably need
to make a number of updates to the .spec file. Unless you are an RPM
guru I wouldn't bother.
Lennart
Ok. I got the upstream systemd installed. Now I get a very different
behavior, which I am unsure of.
I cut off the last message as it repeated some odd ten thousand times or
so... Presumably retry/failing...
At any rate, it appears possible that some configuration is wrong, or
similar? Any suggestions/thoughts now?
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : flags change: +UP
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : enp4s0f0 : could not bring up interface: Invalid argument
OK so this is already weird.
Post by Keller, Jacob E
Feb 11 14:21:52 jekeller-copperpass systemd-networkd[1068]: DHCP CLIENT (0xc7096673): could not receive message from raw socket: Invalid argument
Hmm, this one might actually be caused by
d6d810fbf8071f8510450dbacd1d083f37603656. WIll revert that, please
retest then!

Lennart
--
Lennart Poettering, Red Hat
Tom Gundersen
2015-02-12 13:23:49 UTC
Permalink
On Thu, Feb 12, 2015 at 11:45 AM, Lennart Poettering
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
What about network device renames? Devices are renamed after they
appear. Is it also possible that networkd is started too early and thus
the devices haven't actually appeared yet? But.. then it should get the
message once it does appear? (assuming here that it continues listening
to rtnl messages about links)
networkd should get all netlink msgs about all of this just fine, and
be able to track renames and everything.
Post by Keller, Jacob E
How difficult would it be to create an RPM?
Well, you can get the spec file from fedpkg and then use it on a
tarball from systemd git made with "make dist". But you probably need
to make a number of updates to the .spec file. Unless you are an RPM
guru I wouldn't bother.
Lennart
Ok. I got the upstream systemd installed. Now I get a very different
behavior, which I am unsure of.
I cut off the last message as it repeated some odd ten thousand times or
so... Presumably retry/failing...
At any rate, it appears possible that some configuration is wrong, or
similar? Any suggestions/thoughts now?
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : flags change: +UP
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : enp4s0f0 : could not bring up interface: Invalid argument
OK so this is already weird.
This should now be fixed in git (5c3072eab6d2e11d89452987b017541d4654ac05).
Post by Lennart Poettering
Post by Keller, Jacob E
Feb 11 14:21:52 jekeller-copperpass systemd-networkd[1068]: DHCP CLIENT (0xc7096673): could not receive message from raw socket: Invalid argument
Hmm, this one might actually be caused by
d6d810fbf8071f8510450dbacd1d083f37603656. WIll revert that, please
retest then!
Lennart
--
Lennart Poettering, Red Hat
_______________________________________________
systemd-devel mailing list
http://lists.freedesktop.org/mailman/listinfo/systemd-devel
Keller, Jacob E
2015-02-12 17:48:07 UTC
Permalink
Post by Tom Gundersen
On Thu, Feb 12, 2015 at 11:45 AM, Lennart Poettering
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
What about network device renames? Devices are renamed after they
appear. Is it also possible that networkd is started too early and thus
the devices haven't actually appeared yet? But.. then it should get the
message once it does appear? (assuming here that it continues listening
to rtnl messages about links)
networkd should get all netlink msgs about all of this just fine, and
be able to track renames and everything.
Post by Keller, Jacob E
How difficult would it be to create an RPM?
Well, you can get the spec file from fedpkg and then use it on a
tarball from systemd git made with "make dist". But you probably need
to make a number of updates to the .spec file. Unless you are an RPM
guru I wouldn't bother.
Lennart
Ok. I got the upstream systemd installed. Now I get a very different
behavior, which I am unsure of.
I cut off the last message as it repeated some odd ten thousand times or
so... Presumably retry/failing...
At any rate, it appears possible that some configuration is wrong, or
similar? Any suggestions/thoughts now?
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : flags change: +UP
Feb 11 14:21:47 jekeller-copperpass systemd-networkd[1068]: enp4s0f0 : enp4s0f0 : could not bring up interface: Invalid argument
OK so this is already weird.
This should now be fixed in git (5c3072eab6d2e11d89452987b017541d4654ac05).
Post by Lennart Poettering
Post by Keller, Jacob E
Feb 11 14:21:52 jekeller-copperpass systemd-networkd[1068]: DHCP CLIENT (0xc7096673): could not receive message from raw socket: Invalid argument
Hmm, this one might actually be caused by
d6d810fbf8071f8510450dbacd1d083f37603656. WIll revert that, please
retest then!
Lennart
--
Lennart Poettering, Red Hat
_______________________________________________
systemd-devel mailing list
http://lists.freedesktop.org/mailman/listinfo/systemd-devel
Ok, I'll try with latest Git first, and if it still fails I'll try
reverting the patch Lennart suggested.

Thanks for the help!

Regards,
Jake
Lennart Poettering
2015-02-12 17:51:36 UTC
Permalink
Post by Keller, Jacob E
Ok, I'll try with latest Git first, and if it still fails I'll try
reverting the patch Lennart suggested.
git already has that patch reverted now.

Lennart
--
Lennart Poettering, Red Hat
Keller, Jacob E
2015-02-12 18:28:43 UTC
Permalink
Post by Lennart Poettering
Post by Keller, Jacob E
Ok, I'll try with latest Git first, and if it still fails I'll try
reverting the patch Lennart suggested.
git already has that patch reverted now.
Lennart
Ah :) Well using the upstream Git works perfectly :)

Thanks for helping resolve this issue.

Regards,
Jake
Tom Gundersen
2015-02-12 19:15:22 UTC
Permalink
On Thu, Feb 12, 2015 at 7:28 PM, Keller, Jacob E
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
Ok, I'll try with latest Git first, and if it still fails I'll try
reverting the patch Lennart suggested.
git already has that patch reverted now.
Lennart
Ah :) Well using the upstream Git works perfectly :)
Thanks for helping resolve this issue.
Thanks for testing!

Cheers,

Tom
Keller, Jacob E
2015-02-12 22:30:37 UTC
Permalink
Post by Tom Gundersen
On Thu, Feb 12, 2015 at 7:28 PM, Keller, Jacob E
Now I'm having an interesting issue with the current git of
systemd-networkd... It works just fine on load, and I get an ip address
and everything is happy. After some amount of time, systemd-networkd
goes into "shutting down.." and I lose my ip address.. There is nothing
obvious in the log to indicate that it was turned off.

Any suggestions? I'm capturing a clean boot with the issue with
SYSTEMD_LOG_LEVEL=debug enabled for more information now.

Regards,
Jake
Keller, Jacob E
2015-02-12 23:23:40 UTC
Permalink
Post by Keller, Jacob E
Post by Tom Gundersen
On Thu, Feb 12, 2015 at 7:28 PM, Keller, Jacob E
Now I'm having an interesting issue with the current git of
systemd-networkd... It works just fine on load, and I get an ip address
and everything is happy. After some amount of time, systemd-networkd
goes into "shutting down.." and I lose my ip address.. There is nothing
obvious in the log to indicate that it was turned off.
Any suggestions? I'm capturing a clean boot with the issue with
SYSTEMD_LOG_LEVEL=debug enabled for more information now.
Regards,
Jake
Here's the output... of systemctl status and journalctl after a boot
up..

My suspicion is that these lines are somehow related to the shutdown:

Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddMatch cookie=26 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=ReleaseName cookie=27 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=:1.3 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameLost cookie=7 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=n/a object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameOwnerChanged cookie=11 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): FREE

I'm unsure what this is,

-- Logs begin at Mon 2012-12-31 20:02:09 PST, end at Thu 2015-02-12 14:32:54 PST. --
Feb 12 14:30:17 jekeller-copperpass systemd[1]: Starting Network Service...
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hello cookie=1 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.3 object=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddMatch cookie=2 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=RequestName cookie=3 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: timestamp of '/etc/systemd/network' changed
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: timestamp of '/usr/lib/systemd/network' changed
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens9 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens9 : link 7 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911116e0 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:09.0/0000:09:00.0/net/ens9'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911116e0 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens9 : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens9 : saved original MTU: 1500
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens8 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens8 : link 6 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991112df0 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:08.0/0000:08:00.0/net/ens8'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991112df0 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens8 : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens8 : saved original MTU: 1500
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f3 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f3 : link 5 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991114470 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.3/net/enp4s0f3'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991114470 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f3 : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f3 : saved original MTU: 1500
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f2 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f2 : link 4 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911158d0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.2/net/enp4s0f2'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911158d0 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f2 : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f2 : saved original MTU: 1500
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f1 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f1 : link 3 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991116cf0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.1/net/enp4s0f1'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991116cf0 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f1 : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f1 : saved original MTU: 1500
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : link 2 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911180c0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.0/net/enp4s0f0'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911180c0 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : saved original MTU: 1500
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: lo : flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=4 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: lo : link 1 added
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911195a0 has devpath '/devices/virtual/net/lo'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd9911195a0 filled with db file data
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: lo : udev initialized link
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: lo : saved original MTU: 0
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: lo : Adding address: ::1/128 (valid for ever)
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: lo : Adding address: 127.0.0.1/8 (valid for ever)
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Enumeration completed
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens9 : link state is up-to-date
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99110b990 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:09.0/0000:09:00.0'
Feb 12 14:30:18 jekeller-copperpass systemd[1]: Started Network Service.
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens9 : unmanaged
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_37 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=5 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens8 : link state is up-to-date
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99111bc60 has devpath '/devices/pci0000:00/0000:00:02.0/0000:06:00.0/0000:07:08.0/0000:08:00.0'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: ens8 : unmanaged
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_36 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=6 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f3 : link state is up-to-date
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99110bc20 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.3'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f3 : unmanaged
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_35 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=7 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f2 : link state is up-to-date
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99110ec40 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.2'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f2 : unmanaged
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=8 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f1 : link state is up-to-date
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99110c060 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.1'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f1 : unmanaged
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=9 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : link state is up-to-date
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991119fc0 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.0'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : found matching network '/etc/systemd/network/enp4s0f0.network'
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=10 reply_cookie=0 error=n/a
Feb 12 14:30:18 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : bringing link up
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=11 reply_cookie=0 error=n/a
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=12 reply_cookie=0 error=n/a
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: lo : link state is up-to-date
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: lo : unmanaged
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=13 reply_cookie=0 error=n/a
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : flags change: +UP
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=14 reply_cookie=0 error=n/a
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=15 reply_cookie=0 error=n/a
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=:1.3 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=2 reply_cookie=0 error=n/a
Feb 12 14:30:19 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=:1.3 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcquired cookie=4 reply_cookie=0 error=n/a
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : flags change: +LOWER_UP +RUNNING
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=16 reply_cookie=0 error=n/a
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=17 reply_cookie=0 error=n/a
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : gained carrier
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : acquiring DHCPv4 lease
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): STARTED on ifindex 2
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99110aa50 has devpath '/devices/pci0000:00/0000:00:01.1/0000:04:00.0/net/enp4s0f0'
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99110aa50 filled with db file data
Feb 12 14:30:22 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): DISCOVER
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): OFFER
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): REQUEST (requesting)
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): ACK
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): lease expires in 9min 58.078661s
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): T2 expires in 8min 42.632530s
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): T1 expires in 4min 59.633034s
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : DHCPv4 address 192.168.0.236/24 via 192.168.0.1
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : Adding address: 192.168.0.236/24 (valid for 10min)
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=18 reply_cookie=0 error=n/a
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=19 reply_cookie=0 error=n/a
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : link configured
Feb 12 14:30:23 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=20 reply_cookie=0 error=n/a
Feb 12 14:30:24 jekeller-copperpass systemd-networkd[1012]: enp4s0f0 : Adding address: fe80::21e:67ff:fe9a:c01f/64 (valid for ever)
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : link 8 added
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: device 0x7fd991110ba0 has devpath '/devices/virtual/net/virbr0'
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: no db file to read /run/udev/data/n8: No such file or directory
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : link pending udev initialization...
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : saved original MTU: 1500
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : udev initialized link
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : link state is up-to-date
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : unmanaged
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_38 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=21 reply_cookie=0 error=n/a
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : flags change: +MULTICAST +BROADCAST
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : link 9 added
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: device 0x7fd99111b720 has devpath '/devices/virtual/net/virbr0-nic'
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: no db file to read /run/udev/data/n9: No such file or directory
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : link pending udev initialization...
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : saved original MTU: 1500
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : MAC address: 52:54:00:84:d2:d5
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : udev initialized link
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : link state is up-to-date
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : unmanaged
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_39 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=22 reply_cookie=0 error=n/a
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0 : MAC address: 52:54:00:84:d2:d5
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : flags change: +UP +LOWER_UP +RUNNING
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_39 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=23 reply_cookie=0 error=n/a
Feb 12 14:30:26 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : gained carrier
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0 : Adding address: 192.168.122.1/24 (valid for ever)
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0 : flags change: +UP
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_38 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=24 reply_cookie=0 error=n/a
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : flags change: -UP -LOWER_UP -RUNNING
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_39 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=25 reply_cookie=0 error=n/a
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : lost carrier
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : Removing non-existent address: fe80::5054:ff:fe84:d2d5/64 (valid for ever)
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddMatch cookie=26 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=ReleaseName cookie=27 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=:1.3 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameLost cookie=7 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=n/a object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameOwnerChanged cookie=11 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): FREE

● systemd-networkd.service - Network Service
Loaded: loaded (/usr/lib/systemd/system/systemd-networkd.service; enabled; vendor preset: disabled)
Drop-In: /etc/systemd/system/systemd-networkd.service.d
└─override.conf
Active: inactive (dead) since Thu 2015-02-12 14:31:02 PST; 3min 34s ago
Docs: man:systemd-networkd.service(8)
Process: 1012 ExecStart=/usr/lib/systemd/systemd-networkd (code=exited, status=0/SUCCESS)
Main PID: 1012 (code=exited, status=0/SUCCESS)
Status: "Shutting down..."

Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_38 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=24 reply_cookie=0 error=n/a
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : flags change: -UP -LOWER_UP -RUNNING
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: Sent message type=signal sender=n/a destination=n/a object=/org/freedesktop/network1/link/_39 interface=org.freedesktop.DBus.Properties member=PropertiesChanged cookie=25 reply_cookie=0 error=n/a
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : lost carrier
Feb 12 14:30:27 jekeller-copperpass systemd-networkd[1012]: virbr0-nic : Removing non-existent address: fe80::5054:ff:fe84:d2d5/64 (valid for ever)
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddMatch cookie=26 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=ReleaseName cookie=27 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=:1.3 object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameLost cookie=7 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: Got message type=signal sender=org.freedesktop.DBus destination=n/a object=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameOwnerChanged cookie=11 reply_cookie=0 error=n/a
Feb 12 14:31:02 jekeller-copperpass systemd-networkd[1012]: DHCP CLIENT (0x926441b5): FREE
Lennart Poettering
2015-02-13 09:49:12 UTC
Permalink
Post by Keller, Jacob E
Post by Tom Gundersen
On Thu, Feb 12, 2015 at 7:28 PM, Keller, Jacob E
Now I'm having an interesting issue with the current git of
systemd-networkd... It works just fine on load, and I get an ip address
and everything is happy. After some amount of time, systemd-networkd
goes into "shutting down.." and I lose my ip address.. There is nothing
obvious in the log to indicate that it was turned off.
Any suggestions? I'm capturing a clean boot with the issue with
SYSTEMD_LOG_LEVEL=debug enabled for more information now.
This might be caused by the new exit-on-idle logic Tom added. But that
should leave the IP address untouched when exiting.

Tom, can you look at this?

Lennart
--
Lennart Poettering, Red Hat
Zbigniew Jędrzejewski-Szmek
2015-02-14 17:03:41 UTC
Permalink
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Tom Gundersen
On Thu, Feb 12, 2015 at 7:28 PM, Keller, Jacob E
Now I'm having an interesting issue with the current git of
systemd-networkd... It works just fine on load, and I get an ip address
and everything is happy. After some amount of time, systemd-networkd
goes into "shutting down.." and I lose my ip address.. There is nothing
obvious in the log to indicate that it was turned off.
Any suggestions? I'm capturing a clean boot with the issue with
SYSTEMD_LOG_LEVEL=debug enabled for more information now.
This might be caused by the new exit-on-idle logic Tom added. But that
should leave the IP address untouched when exiting.
Tom, can you look at this?
This should be fixed by

commit a8ba6cd15d3a5edf1f9fbb4fd08dc428c3939072
Author: Tom Gundersen <***@jklm.no>
Date: Fri Feb 13 16:20:45 2015 +0100

exit-on-idle: only exit if actually idle

sd_event_wait() returning 0 usually means that it timed out, which means it must
have been idle. However, sd_event_wait() may return 0 in case an event was triggered
but it turned out there was nothing to do. Make the check for idle explicit to avoid
this edge-case.

Zbyszek
Keller, Jacob E
2015-02-17 18:08:13 UTC
Permalink
Post by Zbigniew Jędrzejewski-Szmek
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Tom Gundersen
On Thu, Feb 12, 2015 at 7:28 PM, Keller, Jacob E
Now I'm having an interesting issue with the current git of
systemd-networkd... It works just fine on load, and I get an ip address
and everything is happy. After some amount of time, systemd-networkd
goes into "shutting down.." and I lose my ip address.. There is nothing
obvious in the log to indicate that it was turned off.
Any suggestions? I'm capturing a clean boot with the issue with
SYSTEMD_LOG_LEVEL=debug enabled for more information now.
This might be caused by the new exit-on-idle logic Tom added. But that
should leave the IP address untouched when exiting.
Tom, can you look at this?
This should be fixed by
commit a8ba6cd15d3a5edf1f9fbb4fd08dc428c3939072
Date: Fri Feb 13 16:20:45 2015 +0100
exit-on-idle: only exit if actually idle
sd_event_wait() returning 0 usually means that it timed out, which means it must
have been idle. However, sd_event_wait() may return 0 in case an event was triggered
but it turned out there was nothing to do. Make the check for idle explicit to avoid
this edge-case.
Zbyszek
Yep this was fixed. Thanks so much for the help!

Regards,
Jake

Zbigniew Jędrzejewski-Szmek
2015-02-12 14:29:47 UTC
Permalink
Post by Lennart Poettering
Post by Keller, Jacob E
Post by Lennart Poettering
Post by Keller, Jacob E
I actually see the same behavior now again, so the removal of
biosdevname does not solve this problem! :(
Is there any more information I can provide?
Hmm, it appears as if networkd completely misses the netlink messages
describing your em0 link.
Yes. I believe that possibly the netlink messages are occurring too
early before networkd has started. Thus, when I restart the service
after startup it works fine.
Well, the first thing after subscribing to links coming/going that
networkd does is query the kernel for the list of devices it has. This
means that it should always get all links, regardless when it is started.
Post by Keller, Jacob E
Post by Lennart Poettering
To debug this it might be worse adding debug log messages to
manager_rtnl_process_link() to see if any rtnl messages announcing the
interface are received by networkd. If no such message arrives there,
then this indicates a kernel issue, otherwise a bug in networkd.
Lennart
How would I go about doing that? I am guessing that means modifying the
source of networkd?
Correct.
Post by Keller, Jacob E
I'm also somewhat unfamiliar with the best practice for installing a
local copy of systemd from source rather than from the Fedora RPMs..
Most of us tend to run git versions of systemd, and simply build them
with "./autogen.sh c && sudo make install". But of course, you should
know what you do then, and there's no easy path back to the FEdora version...
For the case of trying to install a specific utility from git, full
installation is overkill. It's enough to do
git clone
./autogen.sh c && make
install ./systemd-networkd /usr/lib/systemd/

Zbyszek
Continue reading on narkive:
Loading...