Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

systemd-networkd does not configure eth* devices on boot (since 243 onwards) #15445

Closed
lepokle opened this issue Apr 16, 2020 · 21 comments
Closed
Labels

Comments

@lepokle
Copy link

lepokle commented Apr 16, 2020

systemd version the issue has been seen with

systemd 245 (245.4-2-arch)
+PAM +AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=hybrid

Used distribution

ArchLinux

Expected behaviour you didn't see

eth* interfaces are configured properly

IDX LINK TYPE OPERATIONAL SETUP
1 lo loopback carrier unmanaged
2 eth0 ether routable configured
3 eth1 ether routable configured
4 wg0 wireguard routable unmanaged

Unexpected behaviour you saw

eth* interface stay in state "pending"

IDX LINK TYPE OPERATIONAL SETUP
1 lo loopback carrier unmanaged
2 eth0 ether routable pending
3 eth1 ether degraded pending
4 wg0 wireguard routable unmanaged

Steps to reproduce the problem

  • use at least version 243

  • create two files for the interfaces

    [Match]
    # eth0 (Internet)
    MACAddress=9a:ac:cb:68:b1:60
    [Network]
    DHCP=yes
    
    [Match]
    # eth1
    MACAddress=9a:ac:cb:68:b1:61
    [Network]
    Address=172.20.1.5/24
    
  • reboot

Notes

Apr 16 10:16:07 srv0005.cloud.scansation.de systemd[1]: Starting Network Service...
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Bus bus-api-network: changing state UNSET → OPENING
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run on bus bus-api-network: 2
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run/dbus on bus bus-api-network: -1
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Bus bus-api-network: changing state OPENING → WATCH_BIND
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: timestamp of '/etc/systemd/network' changed
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: timestamp of '/usr/lib/systemd/network' changed
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Virtualization KVM Server found in DMI (/sys/class/dmi/id/product_name)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Virtualization found, CPUID=KVMKVMKVM
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Found VM virtualization kvm
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: /usr/lib/systemd/network/80-container-host0.network: Conditions in the file do not match the system environment, skipping.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: New device has no master, continuing without
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Flags change: +UP +LOWER_UP +RUNNING +MULTICAST +BROADCAST
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Link 3 added
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: udev initialized link
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: State changed: pending -> initialized
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Saved original MTU: 1500
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: New device has no master, continuing without
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Flags change: +UP +LOWER_UP +RUNNING +MULTICAST +BROADCAST
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Link 2 added
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: udev initialized link
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: State changed: pending -> initialized
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Saved original MTU: 1500
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: New device has no master, continuing without
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Link 1 added
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: udev initialized link
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: State changed: pending -> initialized
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Saved original MTU: 65536
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding address: fe80::98ac:cbff:fe68:b161/64 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Gained IPv6LL
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding address: fe80::98ac:cbff:fe68:b160/64 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Gained IPv6LL
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding address: ::1/128 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding address: 192.145.44.196/22 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding address: 127.0.0.1/8 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: ff00::/8, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: ff00::/8, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: fe80::98ac:cbff:fe68:b161/128, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: fe80::98ac:cbff:fe68:b160/128, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding route: dst: ::1/128, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: fe80::/64, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: fe80::/64, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding route: dst: ::1/128, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.47.255/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.44.196/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.44.0/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding route: dst: 127.255.255.255/32, src: n/a, gw: n/a, prefsrc: 127.0.0.1
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding route: dst: 127.0.0.1/32, src: n/a, gw: n/a, prefsrc: 127.0.0.1
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding route: dst: 127.0.0.0/8, src: n/a, gw: n/a, prefsrc: 127.0.0.1
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Adding route: dst: 127.0.0.0/32, src: n/a, gw: n/a, prefsrc: 127.0.0.1
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.44.0/22, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: n/a, src: n/a, gw: 192.145.44.1, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: rtnl: received address with invalid family 129, ignoring
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: rtnl: received address with invalid family 128, ignoring
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Enumeration completed
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Link state is up-to-date
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: found matching network '/etc/systemd/network/vlan0.network'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Setting '/proc/sys/net/ipv6/conf/eth1/proxy_ndp' to '0'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Setting '/proc/sys/net/ipv6/conf/eth1/use_tempaddr' to '0'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Setting '/proc/sys/net/ipv6/conf/eth1/accept_ra' to '0'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd[1]: Started Network Service.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Started LLDP client
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Started LLDP.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Setting address genmode for link
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Discovering IPv6 routers
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Started IPv6 Router Solicitation client
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: State changed: initialized -> configuring
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Setting addresses
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 4s
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Link state is up-to-date
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: found matching network '/etc/systemd/network/eth0.network'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing address 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Setting '/proc/sys/net/ipv6/conf/eth0/proxy_ndp' to '0'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Setting '/proc/sys/net/ipv6/conf/eth0/use_tempaddr' to '0'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Setting '/proc/sys/net/ipv6/conf/eth0/accept_ra' to '0'
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Started LLDP client
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Started LLDP.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Setting address genmode for link
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Acquiring DHCPv4 lease
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): STARTED on ifindex 2
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Discovering IPv6 routers
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Started IPv6 Router Solicitation client
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: State changed: initialized -> configuring
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 4s
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): DISCOVER
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Link state is up-to-date
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: Unmanaged
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: lo: State changed: initialized -> unmanaged
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Updating address: 172.20.1.5/24 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.5/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.255/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.0/24, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.0/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Addresses set
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing address: 192.145.44.196/22 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing route: dst: 192.145.44.0/22, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing route: dst: 192.145.47.255/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing route: dst: 192.145.44.0/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing route: dst: 192.145.44.196/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Removing route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Ignoring option DHCP option 61 while parsing.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Ignoring option DHCP option 81 while parsing.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): OFFER
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): REQUEST (requesting)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Ignoring option DHCP option 61 while parsing.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: Ignoring option DHCP option 81 while parsing.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): ACK
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): lease expires in 1month 13h 29min 57s
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): T2 expires in 3w 6d 2h 59min 57s
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: DHCP CLIENT (0xe19d1767): T1 expires in 2w 1d 11h 59min 59s
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: DHCPv4 address 192.145.44.196/22 via 192.145.44.1
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Updating address: 192.145.44.196/22 (valid for 1month 13h 30min)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.44.196/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.47.255/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.44.0/22, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Adding route: dst: 192.145.44.0/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: DHCP: No routes received from DHCP server: No data available
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: 192.145.44.1/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: 192.145.44.1, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Updating route: dst: 192.145.44.1/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Updating route: dst: n/a, src: n/a, gw: 192.145.44.1, prefsrc: 192.145.44.196
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Updating route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configured
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: State changed: configuring -> configured
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Got inotify event on bus bus-api-network.
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run on bus bus-api-network: 2
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run/dbus on bus bus-api-network: -1
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Got inotify event on bus bus-api-network.
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run on bus bus-api-network: 2
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run/dbus on bus bus-api-network: -1
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Got inotify event on bus bus-api-network.
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run on bus bus-api-network: 2
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run/dbus on bus bus-api-network: -1
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Got inotify event on bus bus-api-network.
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run on bus bus-api-network: 2
Apr 16 10:16:08 srv0005.cloud.scansation.de systemd-networkd[688]: Added inotify watch for /run/dbus on bus bus-api-network: -1
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got inotify event on bus bus-api-network.
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Bus bus-api-network: changing state WATCH_BIND → AUTHENTICATING
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Bus bus-api-network: changing state AUTHENTICATING → HELLO
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Hell>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=Requ>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=AddM>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1 interface=org.freedesktop.DBus.Properties member=PropertiesC>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_31 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_32 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.1 path=n/a interface=n/a member=n/a cookie=1 reply_cookie=1 signature>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Bus bus-api-network: changing state HELLO → RUNNING
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got message type=signal sender=org.freedesktop.DBus.Local destination=n/a path=/org/freedesktop/DBus/Local interface=org.freedesktop.DBus.Local>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got message type=signal sender=org.freedesktop.DBus destination=:1.1 path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcqui>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got message type=signal sender=org.freedesktop.DBus destination=:1.1 path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=NameAcqui>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.1 path=n/a interface=n/a member=n/a cookie=4 reply_cookie=2 signature>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Successfully acquired requested service name.
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.1 path=n/a interface=n/a member=n/a cookie=5 reply_cookie=3 signature>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Match type='signal',sender='org.freedesktop.login1',path='/org/freedesktop/login1',interface='org.freedesktop.login1.Manager',member='PrepareFo>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: New device has no master, continuing without
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: MAC address not found for new device, continuing without
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Flags change: +POINTOPOINT +NOARP
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Link 4 added
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: link pending udev initialization...
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Saved original MTU: 1420
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: udev initialized link
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: State changed: pending -> initialized
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Link state is up-to-date
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Unmanaged
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: State changed: initialized -> unmanaged
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Adding address: 192.168.200.5/32 (valid forever)
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Adding route: dst: 192.168.200.5/32, src: n/a, gw: n/a, prefsrc: 192.168.200.5
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Flags change: +UP +LOWER_UP +RUNNING
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_34 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Gained carrier
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Adding route: dst: ff00::/8, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:09 srv0005.cloud.scansation.de systemd-networkd[688]: wg0: Adding route: dst: 192.168.200.0/24, src: n/a, gw: n/a, prefsrc: n/a
Apr 16 10:16:11 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 8s
Apr 16 10:16:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'added' event.
Apr 16 10:16:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: No RA received before link confirmation timeout
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'timeout' event.
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Configured
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: State changed: configuring -> configured
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: No RA received before link confirmation timeout
Apr 16 10:16:19 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'timeout' event.
Apr 16 10:16:20 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 17s
Apr 16 10:16:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'added' event.
Apr 16 10:16:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:16:37 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 35s
Apr 16 10:16:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:16:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:17:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:17:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:17:12 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 1min 10s
Apr 16 10:17:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:17:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:17:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:17:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:17:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:17:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:17:59 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:17:59 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:17:59 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:18:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:18:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:18:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:18:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:18:22 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 2min 14s
Apr 16 10:18:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:18:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:18:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:18:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:18:59 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:18:59 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:18:59 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:19:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:19:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:19:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:19:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:19:26 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:19:26 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:19:26 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:19:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:19:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:19:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:19:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:20:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:20:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:20:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:20:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:20:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:20:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:20:37 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 4min 33s
Apr 16 10:20:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:20:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:20:50 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:20:50 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:20:50 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:21:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:21:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:21:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:21:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:21:21 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:21:21 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:21:21 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:21:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:21:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:21:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:21:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:22:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:22:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:22:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:22:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:22:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:22:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:22:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:22:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:23:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:23:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:23:09 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:23:09 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:23:09 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:23:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:23:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:23:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:23:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:23:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:23:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:24:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:24:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:24:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:24:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:24:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:24:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:24:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:24:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:25:04 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Received Router Advertisement: flags none preference medium lifetime 1800 sec
Apr 16 10:25:04 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Invoking callback for 'router' event.
Apr 16 10:25:04 srv0005.cloud.scansation.de systemd-networkd[688]: eth0: Configuring route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a
Apr 16 10:25:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:25:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:25:10 srv0005.cloud.scansation.de systemd-networkd[688]: NDISC: Sent Router Solicitation, next solicitation in 9min 8s
Apr 16 10:25:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:25:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:25:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:25:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:25:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:25:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:26:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:26:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:26:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:26:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:26:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:26:36 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:26:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:26:43 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:27:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:27:06 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
Apr 16 10:27:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Invoking callback for 'refreshed' event.
Apr 16 10:27:13 srv0005.cloud.scansation.de systemd-networkd[688]: LLDP: Successfully processed LLDP datagram.
  • This is the log with version 245, at least these lines are missing
> Apr 16 10:04:56 srv0005.cloud.scansation.de systemd[1]: Starting Network Service...
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Bus bus-api-network: changing state UNSET → OPENING
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Added inotify watch for /run on bus bus-api-network: 2
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Added inotify watch for /run/dbus on bus bus-api-network: -1
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Bus bus-api-network: changing state OPENING → WATCH_BIND
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: timestamp of '/etc/systemd/network' changed
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Virtualization KVM Server found in DMI (/sys/class/dmi/id/product_name)
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Virtualization found, CPUID=KVMKVMKVM
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: Found VM virtualization kvm
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: /usr/lib/systemd/network/80-container-host0.network: Conditions in the file do not match the system environment, skipping.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: New device has no master, continuing without
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Flags change: +UP +LOWER_UP +RUNNING +MULTICAST +BROADCAST
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Link 3 added
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: link pending udev initialization...
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Saved original MTU: 1500
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: New device has no master, continuing without
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Flags change: +UP +LOWER_UP +RUNNING +MULTICAST +BROADCAST
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Link 2 added
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: link pending udev initialization...
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Saved original MTU: 1500
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: New device has no master, continuing without
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Flags change: +LOOPBACK +UP +LOWER_UP +RUNNING
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Link 1 added
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: udev initialized link
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: State changed: pending -> initialized
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Saved original MTU: 65536
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Remembering foreign address: fe80::98ac:cbff:fe68:b161/64 (valid forever)
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Gained IPv6LL
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering foreign address: fe80::98ac:cbff:fe68:b160/64 (valid forever)
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Gained IPv6LL
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering foreign address: ::1/128 (valid forever)
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering foreign address: 192.145.44.196/22 (valid forever)
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering foreign address: 127.0.0.1/8 (valid forever)
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: rtnl: received non-static neighbor, ignoring.
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: ff00::/8, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: local, proto: boot, type: unicast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Remembering route: dst: ff00::/8, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: local, proto: boot, type: unicast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Remembering route: dst: fe80::98ac:cbff:fe68:b161/128, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: local, proto: kernel, type:>
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: fe80::98ac:cbff:fe68:b160/128, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: local, proto: kernel, type:>
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering route: dst: ::1/128, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: local, proto: kernel, type: local
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: n/a, src: n/a, gw: fe80::1, prefsrc: n/a, scope: global, table: main, proto: ra, type: unicast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: fe80::/64, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: main, proto: kernel, type: unicast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth1: Remembering route: dst: fe80::/64, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: main, proto: kernel, type: unicast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering route: dst: ::1/128, src: n/a, gw: n/a, prefsrc: n/a, scope: global, table: main, proto: kernel, type: unicast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: 192.145.47.255/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196, scope: link, table: local, proto: kernel, type: br>
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: 192.145.44.196/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196, scope: host, table: local, proto: kernel, type: lo>
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: eth0: Remembering route: dst: 192.145.44.0/32, src: n/a, gw: n/a, prefsrc: 192.145.44.196, scope: link, table: local, proto: kernel, type: broa>
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering route: dst: 127.255.255.255/32, src: n/a, gw: n/a, prefsrc: 127.0.0.1, scope: link, table: local, proto: kernel, type: broadcast
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering route: dst: 127.0.0.1/32, src: n/a, gw: n/a, prefsrc: 127.0.0.1, scope: host, table: local, proto: kernel, type: local
Apr 16 10:04:57 srv0005.cloud.scansation.de systemd-networkd[640]: lo: Remembering route: dst: 127.0.0.0/8, src: n/a, gw: n/a, prefsrc: 127.0.0.1, scope: host, table: local, proto: kernel, type: local
@ssahani
Copy link
Contributor

ssahani commented Apr 16, 2020

Do you have a LinkLocal address in the interface or have you disabled ipv6 ?

Please paste the output of ip a

@ssahani
Copy link
Contributor

ssahani commented Apr 16, 2020

I am not able to reproduce this in latest version that is 245.

➜  network networkctl 
IDX LINK  TYPE     OPERATIONAL SETUP     
  1 lo    loopback carrier     unmanaged 
  2 ens33 ether    routable    configured
  3 ens37 ether    routable    configured
  4 ens38 ether    routable    configured

4 links listed.
➜  network networkctl
IDX LINK  TYPE     OPERATIONAL SETUP     
  1 lo    loopback carrier     unmanaged 
  2 ens33 ether    routable    configured
  3 ens37 ether    routable    configured
  4 ens38 ether    routable    configured

4 links listed.
➜  network networkctl status ens38
● 4: ens38                                                                                            
             Link File: /usr/lib/systemd/network/99-default.link                                      
          Network File: /usr/lib/systemd/network/10-ens38.network                                     
                  Type: ether                                                                         
                 State: routable (configured)                               
     Alternative Names: enp2s6                                                                        
                  Path: pci-0000:02:06.0                                                              
                Driver: e1000                                                                         
                Vendor: Intel Corporation                                                             
                 Model: 82545EM Gigabit Ethernet Controller (Copper) (PRO/1000 MT Single Port Adapter)
            HW Address: 00:0c:29:d2:42:7c (VMware, Inc.)                                              
                   MTU: 1500 (min: 46, max: 16110)                                                    
  Queue Length (Tx/Rx): 1/1                                                                           
      Auto negotiation: yes                                                                           
                 Speed: 1Gbps                                                                         
                Duplex: full                                                                          
                  Port: tp                                                                            
               Address: 172.20.1.5                                                                    
                        fe80::20c:29ff:fed2:427c                                                      

Apr 16 13:31:08 Zeus systemd-networkd[20608]: ens38: Link UP
Apr 16 13:31:08 Zeus systemd-networkd[20608]: ens38: Gained carrier
Apr 16 13:31:09 Zeus systemd-networkd[20608]: ens38: Gained IPv6LL
Apr 16 13:31:44 Zeus systemd-networkd[20608]: ens38: Link DOWN
Apr 16 13:31:44 Zeus systemd-networkd[20608]: ens38: Lost carrier
Apr 16 13:31:46 Zeus systemd-networkd[20608]: ens38: Link UP
Apr 16 13:31:46 Zeus systemd-networkd[20608]: ens38: Gained carrier
Apr 16 13:31:47 Zeus systemd-networkd[20608]: ens38: Gained IPv6LL
Apr 16 13:43:22 Zeus systemd-networkd[21069]: ens38: Gained IPv6LL
Apr 16 13:43:22 Zeus systemd-networkd[21069]: ens38: IPv6 successfully enabled
➜  network cat 10-ens38.network 
[Match]
Name=ens38

[Network]
Address=172.20.1.5/24

@lepokle
Copy link
Author

lepokle commented Apr 16, 2020

Hi,
thanks for the quick reply!

ipv6 is enabled.

Output of ip a (v242)

1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 scope host lo
valid_lft forever preferred_lft forever
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 9a:ac:cb:68:b1:60 brd ff:ff:ff:ff:ff:ff
inet 192.145.44.196/22 brd 192.145.47.255 scope global dynamic eth0
valid_lft 2674106sec preferred_lft 2674106sec
inet6 fe80::98ac:cbff:fe68:b160/64 scope link
valid_lft forever preferred_lft forever
3: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 9a:ac:cb:68:b1:61 brd ff:ff:ff:ff:ff:ff
inet 172.20.1.5/24 brd 172.20.1.255 scope global eth1
valid_lft forever preferred_lft forever
inet6 fe80::98ac:cbff:fe68:b161/64 scope link
valid_lft forever preferred_lft forever
4: wg0: <POINTOPOINT,NOARP,UP,LOWER_UP> mtu 1420 qdisc noqueue state UNKNOWN group default qlen 1000
link/none
inet 192.168.200.5/32 scope global wg0
valid_lft forever preferred_lft forever

Output of ip a (v245)

1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 scope host lo
valid_lft forever preferred_lft forever
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 9a:ac:cb:68:b1:60 brd ff:ff:ff:ff:ff:ff
altname enp0s3
inet 192.145.44.196/22 brd 192.145.47.255 scope global eth0
valid_lft forever preferred_lft forever
inet6 fe80::98ac:cbff:fe68:b160/64 scope link
valid_lft forever preferred_lft forever
3: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 9a:ac:cb:68:b1:61 brd ff:ff:ff:ff:ff:ff
altname enp0s6
inet6 fe80::98ac:cbff:fe68:b161/64 scope link
valid_lft forever preferred_lft forever
4: wg0: <POINTOPOINT,NOARP,UP,LOWER_UP> mtu 1420 qdisc noqueue state UNKNOWN group default qlen 1000
link/none
inet 192.168.200.5/32 scope global wg0
valid_lft forever preferred_lft forever

@ssahani
Copy link
Contributor

ssahani commented Apr 16, 2020

Can you reproduce this in 245 ?

@lepokle
Copy link
Author

lepokle commented Apr 16, 2020

Sorry, second output was from v245. As you can see, address for eth1 is missing.

@ssahani
Copy link
Contributor

ssahani commented Apr 16, 2020

Yes but Is the debug logs from 242 the address is getting set and I don't see it's dropping . Can you paste the logs of 245.

orkd[688]: lo: State changed: initialized -> unmanaged
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Updating address: 172.20.1.5/24 (valid forever)
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.5/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.255/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.0/24, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Adding route: dst: 172.20.1.0/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5
Apr 16 10:16:07 srv0005.cloud.scansation.de systemd-networkd[688]: eth1: Addresses set

@lepokle
Copy link
Author

lepokle commented Apr 16, 2020

Log is already in the first post. Sorry, the formatting was broken. I've fixed it.

These lines from your last comment are missing this output.

@ssahani
Copy link
Contributor

ssahani commented Apr 16, 2020

242 logs

ation.de systemd-networkd[688]: eth1: found matching network '/etc/systemd/network/vlan0.network'  <==== why it's saying  vlan0.network ?

And in 245 logs, it seems networkd not able read the .network matching file.
No logs been seen as setting / updating address .

@lepokle
Copy link
Author

lepokle commented Apr 16, 2020

I've called the file vlan0.network because the interface eth1 will be part of a vlan between several servers.
I've no idea why the file should not be found. I do not touch it when switching between 242 and 245.
Permissions:

root@srv0005 ~ # ll /etc/systemd/network                                                                                                                                                                    
total 8
-rw-r--r-- 1 root root 116 Apr 16 10:02 eth0.network
-rw-r--r-- 1 root root 122 Apr 16 11:59 vlan0.network

@lepokle
Copy link
Author

lepokle commented Apr 16, 2020

I've called the following on the running system (245):

networkctl reload
networkctl renew eth1 
# => Failed to renew dynamic configuration of interface eth1: Interface eth1 is not managed by systemd-networkd
networkctl reconfigure eth1
# status switched to initialized
networkctl reconfigure eth1
# status switched to configuring, then to configured

Result:

IDX LINK TYPE      OPERATIONAL SETUP     
  1 lo   loopback  carrier     unmanaged 
  2 eth0 ether     routable    pending   
  3 eth1 ether     routable    configured
  4 wg0  wireguard routable    unmanaged 

4 links listed.

Corresponding log

Apr 16 12:53:33 srv0005.cloud.scansation.de systemd-networkd[641]: Got message type=method_call sender=:1.11 destination=org.freedesktop.network1 path=/org/freedesktop/network1 interface=org.freedesktop.network>
Apr 16 12:53:33 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=error sender=n/a destination=:1.11 path=n/a interface=n/a member=n/a cookie=20 reply_cookie=2 signature=s error-name=org.free>
Apr 16 12:53:33 srv0005.cloud.scansation.de systemd-networkd[641]: Failed to process message type=method_call sender=:1.11 destination=org.freedesktop.network1 path=/org/freedesktop/network1 interface=org.freed>
Apr 16 12:53:39 srv0005.cloud.scansation.de systemd-networkd[641]: Got message type=method_call sender=:1.12 destination=org.freedesktop.network1 path=/org/freedesktop/network1 interface=org.freedesktop.network>
Apr 16 12:53:39 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=GetC>
Apr 16 12:53:39 srv0005.cloud.scansation.de systemd-networkd[641]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.2 path=n/a interface=n/a member=n/a cookie=7 reply_cookie=21 signatur>
Apr 16 12:53:39 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: State changed: pending -> initialized
Apr 16 12:53:39 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 12:53:39 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=method_return sender=n/a destination=:1.12 path=n/a interface=n/a member=n/a cookie=23 reply_cookie=2 signature=n/a error-nam>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Got message type=method_call sender=:1.13 destination=org.freedesktop.network1 path=/org/freedesktop/network1 interface=org.freedesktop.network>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=method_call sender=n/a destination=org.freedesktop.DBus path=/org/freedesktop/DBus interface=org.freedesktop.DBus member=GetC>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Got message type=method_return sender=org.freedesktop.DBus destination=:1.2 path=n/a interface=n/a member=n/a cookie=8 reply_cookie=24 signatur>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=method_return sender=n/a destination=:1.13 path=n/a interface=n/a member=n/a cookie=25 reply_cookie=2 signature=n/a error-nam>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: found matching network '/etc/systemd/network/vlan0.network'
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Re-configuring with /etc/systemd/network/vlan0.network
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Setting '/proc/sys/net/ipv6/conf/eth1/disable_ipv6' to '0'
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: IPv6 successfully enabled
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Setting '/proc/sys/net/ipv6/conf/eth1/proxy_ndp' to '0'
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Setting '/proc/sys/net/ipv6/conf/eth1/use_tempaddr' to '0'
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Setting '/proc/sys/net/ipv6/conf/eth1/accept_ra' to '0'
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: LLDP: Started LLDP client
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Started LLDP.
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Setting address genmode for link
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Setting address genmode done.
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Discovering IPv6 routers
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: NDISC: Started IPv6 Router Solicitation client
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: State changed: initialized -> configuring
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Setting addresses
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Remembering updated address: 172.20.1.5/24 (valid forever)
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Remembering route: dst: 172.20.1.5/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5, scope: host, table: local, proto: kernel, type: local
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Remembering route: dst: 172.20.1.255/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5, scope: link, table: local, proto: kernel, type: broadcast
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Remembering route: dst: 172.20.1.0/24, src: n/a, gw: n/a, prefsrc: 172.20.1.5, scope: link, table: main, proto: kernel, type: unicast
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Remembering route: dst: 172.20.1.0/32, src: n/a, gw: n/a, prefsrc: 172.20.1.5, scope: link, table: local, proto: kernel, type: broadcast
Apr 16 12:54:00 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: Addresses set
Apr 16 12:54:01 srv0005.cloud.scansation.de systemd-networkd[641]: NDISC: Sent Router Solicitation, next solicitation in 4s
Apr 16 12:54:06 srv0005.cloud.scansation.de systemd-networkd[641]: NDISC: Sent Router Solicitation, next solicitation in 8s
Apr 16 12:54:12 srv0005.cloud.scansation.de systemd-networkd[641]: NDISC: No RA received before link confirmation timeout
Apr 16 12:54:12 srv0005.cloud.scansation.de systemd-networkd[641]: NDISC: Invoking callback for 'timeout' event.
Apr 16 12:54:12 srv0005.cloud.scansation.de systemd-networkd[641]: eth1: State changed: configuring -> configured
Apr 16 12:54:12 srv0005.cloud.scansation.de systemd-networkd[641]: Sent message type=signal sender=n/a destination=n/a path=/org/freedesktop/network1/link/_33 interface=org.freedesktop.DBus.Properties member=Pr>
Apr 16 12:54:12 srv0005.cloud.scansation.de systemd-networkd[641]: LLDP: Invoking callback for 'added' event.
Apr 16 12:54:12 srv0005.cloud.scansation.de systemd-networkd[641]: LLDP: Successfully processed LLDP datagram.
Apr 16 12:54:14 srv0005.cloud.scansation.de systemd-networkd[641]: NDISC: Sent Router Solicitation, next solicitation in 16s

It seems strange to me, that the file could be found then.

@ssahani

This comment has been minimized.

@lepokle

This comment has been minimized.

@ssahani

This comment has been minimized.

@lepokle

This comment has been minimized.

@lepokle

This comment has been minimized.

@yuwata

This comment has been minimized.

@yuwata yuwata added the needs-reporter-feedback ❓ There's an unanswered question, the reporter needs to answer label Jun 1, 2020
@keszybz
Copy link
Member

keszybz commented Jun 2, 2020

I marked the part of discussion about file permissions as resolved — the files are readable.

I guess that /sys is read-write. The directory /sys must be read-only on VM.

I think you were thinking about a container. This is a VM, and /sys should be readable.

OK, so for some reason networkd is not matching the interface with .network file. According to the ip a output, the device has the expected MAC address, and the match line in the network file looks like it should match. So the first order of business would be to figure out why it doesn't match.

With 242 there is:

systemd-networkd[688]: eth0: udev initialized link
systemd-networkd[688]: eth0: State changed: pending -> initialized

but with 245:

eth0: link pending udev initialization...

And in fact, networkctl shows:

2 eth0 ether routable pending

So systemd-networkd does nothing with the link because for whatever reason it thinks udev is not done with the link and the link is in pending state. But then when you reconfigure the link, it is picked up correctly, which suggests that udev did initialize the device, but that networkd didn't pick up on this. It might be a networkd problem, but it might also be udevd problem.

@lepokle can you attach also logs from udevd (i.e. turn on debug logging for udevd too)? Maybe something like journalctl -b -o short-monotonic --no-hostname -u systemd-udevd -u systemd-networkd.

@eppesuig
Copy link

I have a similar (the same?) problem and tried to analyse it. On an apple powerbook there are two network interfaces, one cabled and one wireless. They are recognized as eth0 and eth1. Both names are now saved in /etc/udev/rules.d/70-persistent-net.rules file. Usually the first recognized by kernel is the cabled one, but sometimes, the kernel recognized them in the opposite order and almost at the same time. When this happens, I see these messages in /var/log/syslog and no interface is configured by systemd networkd.

# egrep 'rename|udev|eth' /var/log/syslog
Aug 16 09:05:40 titanium4 systemd-udevd[192]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Aug 16 09:05:40 titanium4 systemd-udevd[193]: Using default interface naming scheme 'v245'.
Aug 16 09:05:40 titanium4 systemd-udevd[198]: Using default interface naming scheme 'v245'.
Aug 16 09:05:40 titanium4 systemd-udevd[193]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Aug 16 09:05:40 titanium4 systemd-udevd[193]: eth0: Failed to rename network interface 2 from 'eth0' to 'eth1': File exists
Aug 16 09:05:40 titanium4 systemd-udevd[193]: eth0: Failed to process device, ignoring: File exists
Aug 16 09:05:40 titanium4 systemd-udevd[198]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Aug 16 09:05:40 titanium4 systemd-udevd[198]: eth1: Failed to rename network interface 3 from 'eth1' to 'eth0': File exists
Aug 16 09:05:40 titanium4 systemd-udevd[198]: eth1: Failed to process device, ignoring: File exists
Aug 16 09:05:40 titanium4 kernel: [   28.358783] gem 0002:24:0f.0 eth1: Sun GEM (PCI) 10/100/1000BaseT Ethernet 00:03:93:cd:e0:16

To solve this problem, I renamed eth1 in wlan0 on 70-persistent-net.rules file.

@lepokle
Copy link
Author

lepokle commented Sep 13, 2020

Thanks to the comments from @keszybz and @eppesuig I've found out that there seems to be a naming conflict between udevd and systemd-networkd.

Log output from journalctl -b -o short-monotonic --no-hostname -u systemd-udevd -u systemd-networkd:

-- Logs begin at Wed 2020-04-22 19:57:52 UTC, end at Sun 2020-09-13 07:12:53 UTC. --
[  671.094933] systemd[1]: Started Rule-based Manager for Device Events and Files.
[  671.182102] systemd-udevd[513]: Using default interface naming scheme 'v245'.
[  671.184123] systemd-udevd[513]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  671.184536] systemd-udevd[513]: eth0: Failed to rename network interface 2 from 'eth0' to 'ens3': Device or resource busy
[  671.184647] systemd-udevd[513]: eth0: Failed to process device, ignoring: Device or resource busy
[  671.187890] systemd-udevd[526]: Using default interface naming scheme 'v245'.
[  671.189083] systemd-udevd[526]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  671.189325] systemd-udevd[526]: eth1: Failed to rename network interface 3 from 'eth1' to 'ens6': Device or resource busy
[  671.189463] systemd-udevd[526]: eth1: Failed to process device, ignoring: Device or resource busy
[  671.261230] systemd-udevd[522]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.882593] systemd[1]: Starting Network Service...
[  673.932716] systemd-udevd[527]: Using default interface naming scheme 'v245'.
[  673.933047] systemd-udevd[527]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.960438] systemd-udevd[523]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.960812] systemd-udevd[525]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.961036] systemd-udevd[514]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.961240] systemd-udevd[514]: Using default interface naming scheme 'v245'.
[  673.961736] systemd-udevd[527]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.964273] systemd-udevd[526]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  673.977686] systemd-udevd[507]: Using default interface naming scheme 'v245'.
[  673.977964] systemd-udevd[507]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  674.306679] systemd-udevd[523]: Using default interface naming scheme 'v245'.
[  674.306951] systemd-udevd[523]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  674.314200] systemd-networkd[694]: lxdprm483: netdev ready
[  674.316817] systemd-networkd[694]: eth1: Gained IPv6LL
[  674.316942] systemd-networkd[694]: eth0: Gained IPv6LL
[  674.317941] systemd-networkd[694]: Enumeration completed
[  674.318043] systemd[1]: Started Network Service.
[  674.322160] systemd-networkd[694]: lxdprm483_x: IPv6 successfully enabled
[  674.327122] systemd-networkd[694]: lxdprm483: Link UP
[  674.327157] systemd-networkd[694]: lxdprm483: Could not bring up interface: Operation not supported
[  674.327180] systemd-networkd[694]: lxdprm483_x: Link UP
[  674.358127] systemd-networkd[694]: lxdprm483_x: Gained carrier
[  675.456407] systemd-networkd[694]: gre_sys: Gained IPv6LL
[  675.563053] systemd-networkd[694]: lxdprm483_x: Gained IPv6LL
[  794.579374] systemd-udevd[1628]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  794.579786] systemd-udevd[1628]: Using default interface naming scheme 'v245'.
[  794.658228] systemd-networkd[694]: wg0: Link UP
[  794.658634] systemd-networkd[694]: wg0: Gained carrier
[  796.426196] systemd-udevd[1627]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  796.426458] systemd-udevd[1627]: Using default interface naming scheme 'v245'.
[  796.426508] systemd-udevd[1628]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  796.426673] systemd-networkd[694]: vethb776805a: Link UP
[  796.790421] systemd-networkd[694]: vethb776805a: Gained carrier
[  801.883272] systemd-udevd[1628]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  801.888706] systemd-udevd[1718]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  801.889278] systemd-udevd[1718]: Using default interface naming scheme 'v245'.
[  801.897673] systemd-networkd[694]: veth28f4cae1: Link UP
[  802.340105] systemd-networkd[694]: veth28f4cae1: Gained carrier
[  807.451469] systemd-udevd[4964]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  807.452015] systemd-udevd[4964]: Using default interface naming scheme 'v245'.
[  807.454044] systemd-udevd[5391]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  807.454756] systemd-udevd[5391]: Using default interface naming scheme 'v245'.
[  807.460359] systemd-networkd[694]: veth470e6f23: Link UP
[  807.790897] systemd-networkd[694]: veth470e6f23: Gained carrier
[  812.864010] systemd-udevd[6137]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  812.864754] systemd-udevd[6137]: Using default interface naming scheme 'v245'.
[  812.864845] systemd-udevd[6139]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  812.865134] systemd-udevd[6139]: Using default interface naming scheme 'v245'.
[  812.865173] systemd-networkd[694]: veth48d7ff5d: Link UP
[  813.163626] systemd-networkd[694]: veth48d7ff5d: Gained carrier
[  818.242714] systemd-udevd[6778]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  818.243020] systemd-udevd[6778]: Using default interface naming scheme 'v245'.
[  818.243077] systemd-udevd[6779]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  818.243262] systemd-udevd[6779]: Using default interface naming scheme 'v245'.
[  818.246580] systemd-networkd[694]: vethb7bc4223: Link UP
[  818.573397] systemd-networkd[694]: vethb7bc4223: Gained carrier
[  823.656903] systemd-udevd[7004]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  823.657215] systemd-udevd[7004]: Using default interface naming scheme 'v245'.
[  823.658690] systemd-udevd[7006]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  823.659149] systemd-udevd[7006]: Using default interface naming scheme 'v245'.
[  823.660386] systemd-networkd[694]: veth878456b5: Link UP
[  824.043756] systemd-networkd[694]: veth878456b5: Gained carrier
[  827.935988] systemd-networkd[694]: lxdprm483: Gained carrier
[  829.113686] systemd-udevd[7356]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  829.114190] systemd-udevd[7356]: Using default interface naming scheme 'v245'.
[  829.114857] systemd-udevd[7358]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  829.115199] systemd-udevd[7358]: Using default interface naming scheme 'v245'.
[  829.116690] systemd-networkd[694]: vethce61f6e1: Link UP
[  829.483421] systemd-networkd[694]: vethce61f6e1: Gained carrier
[  834.557996] systemd-udevd[7523]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  834.558409] systemd-udevd[7523]: Using default interface naming scheme 'v245'.
[  834.560334] systemd-udevd[7524]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  834.560685] systemd-udevd[7524]: Using default interface naming scheme 'v245'.
[  834.562114] systemd-networkd[694]: veth865c54b8: Link UP
[  834.924374] systemd-networkd[694]: veth865c54b8: Gained carrier
[  840.003422] systemd-networkd[694]: veth22eae5f7: Link UP
[  840.004330] systemd-udevd[7825]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  840.004743] systemd-udevd[7825]: Using default interface naming scheme 'v245'.
[  840.004816] systemd-udevd[7826]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  840.005070] systemd-udevd[7826]: Using default interface naming scheme 'v245'.
[  840.363843] systemd-networkd[694]: veth22eae5f7: Gained carrier
[ 1947.815995] systemd-networkd[694]: wg0: Link DOWN
[ 1947.816316] systemd-networkd[694]: wg0: Lost carrier
[ 1959.886121] systemd-udevd[19329]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1959.887100] systemd-networkd[694]: veth22eae5f7: Lost carrier
[ 1959.975909] systemd-networkd[694]: rtnl: received neighbor for link '30' we don't know about, ignoring.
[ 1959.976096] systemd-networkd[694]: rtnl: received neighbor for link '30' we don't know about, ignoring.
[ 1959.978722] systemd-networkd[694]: veth22eae5f7: Link DOWN
[ 1961.108202] systemd-udevd[19341]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1961.108430] systemd-networkd[694]: veth865c54b8: Lost carrier
[ 1961.202513] systemd-networkd[694]: rtnl: received neighbor for link '28' we don't know about, ignoring.
[ 1961.202650] systemd-networkd[694]: rtnl: received neighbor for link '28' we don't know about, ignoring.
[ 1961.205742] systemd-networkd[694]: veth865c54b8: Link DOWN
[ 1974.192901] systemd-udevd[19585]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1974.193449] systemd-networkd[694]: veth470e6f23: Lost carrier
[ 1974.282551] systemd-networkd[694]: rtnl: received neighbor for link '18' we don't know about, ignoring.
[ 1974.282727] systemd-networkd[694]: rtnl: received neighbor for link '18' we don't know about, ignoring.
[ 1974.284867] systemd-networkd[694]: veth470e6f23: Link DOWN
[ 1976.852073] systemd-networkd[694]: veth48d7ff5d: Lost carrier
[ 1977.031013] systemd-udevd[19598]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1977.135832] systemd-networkd[694]: rtnl: received neighbor for link '20' we don't know about, ignoring.
[ 1977.136048] systemd-networkd[694]: rtnl: received neighbor for link '20' we don't know about, ignoring.
[ 1977.138211] systemd-networkd[694]: veth48d7ff5d: Link DOWN
[ 1978.832391] systemd-udevd[19598]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1978.835255] systemd-networkd[694]: vethb7bc4223: Lost carrier
[ 1978.923522] systemd-networkd[694]: rtnl: received neighbor for link '22' we don't know about, ignoring.
[ 1978.923926] systemd-networkd[694]: rtnl: received neighbor for link '22' we don't know about, ignoring.
[ 1978.927142] systemd-networkd[694]: vethb7bc4223: Link DOWN
[ 1979.952960] systemd-udevd[19598]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1979.954403] systemd-networkd[694]: veth878456b5: Lost carrier
[ 1980.055878] systemd-networkd[694]: rtnl: received neighbor for link '24' we don't know about, ignoring.
[ 1980.056122] systemd-networkd[694]: rtnl: received neighbor for link '24' we don't know about, ignoring.
[ 1980.060044] systemd-networkd[694]: veth878456b5: Link DOWN
[ 1981.044122] systemd-udevd[19598]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1981.047602] systemd-networkd[694]: vethce61f6e1: Lost carrier
[ 1981.156209] systemd-networkd[694]: rtnl: received neighbor for link '26' we don't know about, ignoring.
[ 1981.156358] systemd-networkd[694]: rtnl: received neighbor for link '26' we don't know about, ignoring.
[ 1981.161160] systemd-networkd[694]: vethce61f6e1: Link DOWN
[ 1993.844884] systemd-networkd[694]: veth28f4cae1: Lost carrier
[ 1993.845214] systemd-udevd[20236]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1993.922686] systemd-networkd[694]: rtnl: received neighbor for link '16' we don't know about, ignoring.
[ 1993.922849] systemd-networkd[694]: rtnl: received neighbor for link '16' we don't know about, ignoring.
[ 1993.924902] systemd-networkd[694]: veth28f4cae1: Link DOWN
[ 1994.950486] systemd-udevd[20247]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[ 1994.951447] systemd-networkd[694]: vethb776805a: Lost carrier
[ 1995.042711] systemd-networkd[694]: rtnl: received neighbor for link '14' we don't know about, ignoring.
[ 1995.042854] systemd-networkd[694]: rtnl: received neighbor for link '14' we don't know about, ignoring.
[ 1995.044828] systemd-networkd[694]: vethb776805a: Link DOWN
[ 1995.962839] systemd-networkd[694]: lxdprm483: Lost carrier
[ 1996.108968] systemd[1]: Stopping Network Service...
[ 1996.109432] systemd-networkd[694]: lxdprm483_x: DHCPv6 lease lost
[ 1996.136028] systemd[1]: systemd-networkd.service: Succeeded.
[ 1996.138118] systemd[1]: Stopped Network Service.

After setting net.ifnames=0 for booting, the problem has been solved:

-- Logs begin at Wed 2020-04-22 19:57:52 UTC, end at Sun 2020-09-13 07:19:49 UTC. --
[   14.252275] systemd-udevd[491]: Network interface NamePolicy= disabled on kernel command line, ignoring.
[   14.324439] systemd[1]: Started Rule-based Manager for Device Events and Files.
[   14.336264] systemd-udevd[498]: Using default interface naming scheme 'v245'.
[   14.337042] systemd-udevd[501]: Using default interface naming scheme 'v245'.
[   14.343041] systemd-udevd[501]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   14.349744] systemd-udevd[498]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   14.400212] systemd-udevd[498]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.316733] systemd[1]: Starting Network Service...
[   17.338918] systemd-udevd[505]: Using default interface naming scheme 'v245'.
[   17.340884] systemd-udevd[505]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.362210] systemd-udevd[499]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.362567] systemd-udevd[505]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.362626] systemd-udevd[506]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.362848] systemd-udevd[506]: Using default interface naming scheme 'v245'.
[   17.362887] systemd-udevd[511]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.373347] systemd-udevd[511]: Using default interface naming scheme 'v245'.
[   17.373391] systemd-udevd[511]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.384951] systemd-udevd[505]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.716553] systemd-udevd[511]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   17.722738] systemd-networkd[681]: lxdprm483: netdev ready
[   17.725692] systemd-networkd[681]: eth1: Gained IPv6LL
[   17.725842] systemd-networkd[681]: eth0: Gained IPv6LL
[   17.728115] systemd-networkd[681]: Enumeration completed
[   17.728168] systemd[1]: Started Network Service.
[   17.728429] systemd-networkd[681]: lxdprm483_x: IPv6 successfully enabled
[   17.730353] systemd-networkd[681]: eth1: IPv6 successfully enabled
[   17.751094] systemd-networkd[681]: eth0: IPv6 successfully enabled
[   17.819948] systemd-networkd[681]: lxdprm483: Link UP
[   17.820461] systemd-networkd[681]: lxdprm483: Could not bring up interface: Operation not supported
[   17.820532] systemd-networkd[681]: lxdprm483_x: Link UP
[   17.861383] systemd-networkd[681]: lxdprm483_x: Gained carrier
[   17.866358] systemd-networkd[681]: eth0: DHCPv4 address 192.145.44.196/22 via 192.145.44.1
[   18.363848] systemd-udevd[505]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   18.461399] systemd-networkd[681]: wg0: Link UP
[   18.461680] systemd-networkd[681]: wg0: Gained carrier
[   18.461734] systemd-networkd[681]: gre_sys: Gained IPv6LL
[   19.355246] systemd-networkd[681]: lxdprm483_x: Gained IPv6LL
[   20.381053] systemd-udevd[511]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   20.381901] systemd-udevd[499]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   20.382339] systemd-udevd[499]: Using default interface naming scheme 'v245'.
[   20.386485] systemd-networkd[681]: veth026f4aac: Link UP
[   20.796184] systemd-networkd[681]: veth026f4aac: Gained carrier
[   25.903890] systemd-udevd[1526]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   25.904509] systemd-udevd[1526]: Using default interface naming scheme 'v245'.
[   25.904582] systemd-udevd[1527]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   25.904876] systemd-udevd[1527]: Using default interface naming scheme 'v245'.
[   25.904944] systemd-networkd[681]: veth7e084358: Link UP
[   26.257065] systemd-networkd[681]: veth7e084358: Gained carrier
[   31.339682] systemd-udevd[1908]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   31.340070] systemd-udevd[1908]: Using default interface naming scheme 'v245'.
[   31.340257] systemd-udevd[1909]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   31.340508] systemd-udevd[1909]: Using default interface naming scheme 'v245'.
[   31.343215] systemd-networkd[681]: veth189c614a: Link UP
[   31.713171] systemd-networkd[681]: veth189c614a: Gained carrier
[   36.788004] systemd-udevd[2778]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   36.788502] systemd-udevd[2778]: Using default interface naming scheme 'v245'.
[   36.788695] systemd-udevd[2779]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   36.794953] systemd-udevd[2779]: Using default interface naming scheme 'v245'.
[   36.795031] systemd-networkd[681]: vethdfe5a742: Link UP
[   37.123394] systemd-networkd[681]: vethdfe5a742: Gained carrier
[   42.205826] systemd-udevd[3364]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   42.206629] systemd-udevd[3364]: Using default interface naming scheme 'v245'.
[   42.206680] systemd-udevd[3366]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   42.206965] systemd-udevd[3366]: Using default interface naming scheme 'v245'.
[   42.208290] systemd-networkd[681]: vethaf11bf2b: Link UP
[   42.555722] systemd-networkd[681]: vethaf11bf2b: Gained carrier
[   47.642741] systemd-udevd[3714]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   47.643485] systemd-udevd[3714]: Using default interface naming scheme 'v245'.
[   47.643624] systemd-udevd[3715]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   47.644005] systemd-udevd[3715]: Using default interface naming scheme 'v245'.
[   47.644074] systemd-networkd[681]: veth28e9d0e2: Link UP
[   47.995490] systemd-networkd[681]: veth28e9d0e2: Gained carrier
[   51.381574] systemd-networkd[681]: lxdprm483: Gained carrier
[   53.063594] systemd-udevd[3877]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   53.064055] systemd-udevd[3877]: Using default interface naming scheme 'v245'.
[   53.064626] systemd-networkd[681]: vethd60c0e84: Link UP
[   53.065613] systemd-udevd[3879]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   53.065877] systemd-udevd[3879]: Using default interface naming scheme 'v245'.
[   53.412842] systemd-networkd[681]: vethd60c0e84: Gained carrier
[   58.488397] systemd-udevd[4177]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   58.490408] systemd-networkd[681]: vethbc6c5433: Link UP
[   58.490954] systemd-udevd[4177]: Using default interface naming scheme 'v245'.
[   58.491030] systemd-udevd[4179]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   58.491328] systemd-udevd[4179]: Using default interface naming scheme 'v245'.
[   58.809235] systemd-networkd[681]: vethbc6c5433: Gained carrier
[   63.887710] systemd-udevd[4378]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   63.888068] systemd-udevd[4378]: Using default interface naming scheme 'v245'.
[   63.889421] systemd-udevd[4377]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[   63.889877] systemd-udevd[4377]: Using default interface naming scheme 'v245'.
[   63.891764] systemd-networkd[681]: vethef150cb8: Link UP
[   64.256457] systemd-networkd[681]: vethef150cb8: Gained carrier
[  345.905124] systemd-networkd[681]: wg0: Link DOWN
[  345.911464] systemd-networkd[681]: wg0: Lost carrier
[  357.959738] systemd-networkd[681]: vethef150cb8: Lost carrier
[  357.960155] systemd-udevd[9503]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  358.044733] systemd-networkd[681]: rtnl: received neighbor for link '30' we don't know about, ignoring.
[  358.044922] systemd-networkd[681]: rtnl: received neighbor for link '30' we don't know about, ignoring.
[  358.047060] systemd-networkd[681]: vethef150cb8: Link DOWN
[  359.185544] systemd-udevd[9503]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  359.185930] systemd-networkd[681]: vethbc6c5433: Lost carrier
[  359.288115] systemd-networkd[681]: rtnl: received neighbor for link '28' we don't know about, ignoring.
[  359.288321] systemd-networkd[681]: rtnl: received neighbor for link '28' we don't know about, ignoring.
[  359.293809] systemd-networkd[681]: vethbc6c5433: Link DOWN
[  372.398993] systemd-networkd[681]: veth189c614a: Lost carrier
[  372.399990] systemd-udevd[9837]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  372.528424] systemd-networkd[681]: rtnl: received neighbor for link '18' we don't know about, ignoring.
[  372.528828] systemd-networkd[681]: rtnl: received neighbor for link '18' we don't know about, ignoring.
[  372.531809] systemd-networkd[681]: veth189c614a: Link DOWN
[  375.011755] systemd-networkd[681]: vethdfe5a742: Lost carrier
[  375.179551] systemd-udevd[9849]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  375.288134] systemd-networkd[681]: rtnl: received neighbor for link '20' we don't know about, ignoring.
[  375.288332] systemd-networkd[681]: rtnl: received neighbor for link '20' we don't know about, ignoring.
[  375.291323] systemd-networkd[681]: vethdfe5a742: Link DOWN
[  376.957388] systemd-udevd[9849]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  376.959799] systemd-networkd[681]: vethaf11bf2b: Lost carrier
[  377.078181] systemd-networkd[681]: rtnl: received neighbor for link '22' we don't know about, ignoring.
[  377.078381] systemd-networkd[681]: rtnl: received neighbor for link '22' we don't know about, ignoring.
[  377.085000] systemd-networkd[681]: vethaf11bf2b: Link DOWN
[  378.105093] systemd-udevd[9849]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  378.105460] systemd-networkd[681]: veth28e9d0e2: Lost carrier
[  378.202249] systemd-networkd[681]: rtnl: received neighbor for link '24' we don't know about, ignoring.
[  378.202503] systemd-networkd[681]: rtnl: received neighbor for link '24' we don't know about, ignoring.
[  378.210672] systemd-networkd[681]: veth28e9d0e2: Link DOWN
[  379.229104] systemd-udevd[9849]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  379.229409] systemd-networkd[681]: vethd60c0e84: Lost carrier
[  379.328147] systemd-networkd[681]: rtnl: received neighbor for link '26' we don't know about, ignoring.
[  379.328359] systemd-networkd[681]: rtnl: received neighbor for link '26' we don't know about, ignoring.
[  379.332216] systemd-networkd[681]: vethd60c0e84: Link DOWN
[  391.994272] systemd-udevd[10484]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  391.995356] systemd-networkd[681]: veth7e084358: Lost carrier
[  392.076511] systemd-networkd[681]: rtnl: received neighbor for link '16' we don't know about, ignoring.
[  392.076925] systemd-networkd[681]: rtnl: received neighbor for link '16' we don't know about, ignoring.
[  392.077487] systemd-networkd[681]: veth7e084358: Link DOWN
[  393.062719] systemd-networkd[681]: veth026f4aac: Lost carrier
[  393.063125] systemd-udevd[10498]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
[  393.154608] systemd-networkd[681]: rtnl: received neighbor for link '14' we don't know about, ignoring.
[  393.154808] systemd-networkd[681]: rtnl: received neighbor for link '14' we don't know about, ignoring.
[  393.157286] systemd-networkd[681]: veth026f4aac: Link DOWN
[  394.078384] systemd-networkd[681]: lxdprm483: Lost carrier
[  394.128969] systemd[1]: Stopping Network Service...
[  394.129230] systemd-networkd[681]: lxdprm483_x: DHCPv6 lease lost
[  394.129501] systemd-networkd[681]: eth0: DHCP lease lost
[  394.133730] systemd-networkd[681]: eth0: DHCPv6 lease lost
[  394.133979] systemd-networkd[681]: eth1: DHCPv6 lease lost
[  394.233136] systemd[1]: systemd-networkd.service: Succeeded.
[  394.233858] systemd[1]: Stopped Network Service.

I've no idea why this behaviour changed with version 243 onwards, but at least for me the problem has been solved now.

@chaos-prevails
Copy link

Hello,

I experienced the same situation after adding a 4x1GB Intel 82571EB/82571GB card on Ubuntu 20.04.2 all updated on a DL360G6 (systemd v 245.4-4ubuntu3.4). There are no firmware updates for this card.

two (eth2, eth3) of the four ports showed up as pending under networkctl. Configuring them with netplan did not solve the issue. They didn't come up until I added a IP address manually via

ip address add [ip]/[mask-digits] dev ethXX

so netplan seemed be be ignored?

regular errors in /var/log/syslog:

Feb  9 13:26:55 XXX systemd[1]: Starting Wait for Network to be Configured...
Feb  9 13:28:55 XXX systemd-networkd-wait-online[104942]: Event loop failed: Connection timed out
Feb  9 13:28:55 XXX systemd[1]: systemd-networkd-wait-online.service: Main process exited, code=exited, status=1/FAILURE
Feb  9 13:28:55 XXX systemd[1]: systemd-networkd-wait-online.service: Failed with result 'exit-code'.
Feb  9 13:28:55 XXX systemd[1]: Failed to start Wait for Network to be Configured.

Errors when trying
systemctl restart systemd-networkd-wait-online
....
Feb 9 16:52:10 XXXX systemd-udevd[800]: eth3: Failed to rename network interface 7 from 'eth3' to 'ens2f1': File exists
Feb 9 16:52:10 XXXX systemd-udevd[800]: eth3: Failed to process device, ignoring: File exists
....

So I started to rename the interfaces with:
/etc/udev/rules.d/80-ifnames.rules:
SUBSYSTEM=="net", ACTION=="add", ATTR{address}=="XXXX", NAME="ens4f0"

and reloaded udev rules:
udevadm control --reload-rules
udevadm trigger --attr-match=subsystem=net

then systemctl restart systemd-networkd-wait-online didn't throw any error any more.

However, upon next reboot, a previously renamed, but not configured ethernet port (ens2f1) suddenly showed up as eth1, and was giving errors.

so I ended up renaming all unconfigured ethernet ports via udev rules, and now after reboot all is fine:

 1 lo       loopback carrier     unmanaged 
  2 enp2s0f0 ether    enslaved    configured
  3 enp2s0f1 ether    enslaved    configured
  4 ens2f0   ether    routable    configured <-- configured in netplan
  5 ens4f1   ether    off         unmanaged <--- not configured in netplan
  6 ens4f0   ether    off         unmanaged <--- not configured in netplan
  7 ens6f0   ether    off         unmanaged <--- not configured in netplan
  8 bond0    bond     enslaved    configured
  9 br-kvm   bridge   routable    configured

Hopefully I can help somebody solve this quicker. As this is a production server, I was very hesitant doing any quick changes.

@keszybz keszybz removed the needs-reporter-feedback ❓ There's an unanswered question, the reporter needs to answer label Jan 11, 2022
@poettering
Copy link
Member

I've no idea why this behaviour changed with version 243 onwards, but at least for me the problem has been solved now.

Closing hence.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Development

No branches or pull requests

7 participants