-
Bug
-
Resolution: Unresolved
-
Major
-
None
-
4.13.z
-
Important
-
No
-
False
-
On new node creation, nodes undergo upgrades from CoreOS 8 to CoreOS 9 and `nmstatectl persist-nic-names` is run. On some nodes the NIC names are persisted to the kernel command line as `ifname` parameters. These NIC name/MAC address pairs do not save driver info and causes name collisions on Azure VMs that have Accelerated Networking enabled.
May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Will persist the interface enP17595s1 driver mlx5_core with MAC 60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] systemd network link file created at /rootfs/etc/systemd/network/98-nmstate-enP17595s1.link May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Kernel argument added: ifname=enP17595s1:60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Will persist the interface eth0 driver hv_netvsc with MAC 60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] systemd network link file created at /rootfs/etc/systemd/network/98-nmstate-eth0.link May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Kernel argument added: ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: I0528 21:45:35.644467 1 daemon.go:1762] Running: chroot /rootfs rpm-ostree kargs --append ifname=enP17595s1:60:45:BD:FF:F0:E0 --append ifname=eth0:60:45:BD:FF:F0:E0 -- Boot -- May 28 21:47:36 localhost kernel: Command line: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.0/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:47:36 localhost dracut-pre-udev[427]: Warning: ifname=eth0 uses the kernel name space for interfaces May 28 21:47:36 localhost dracut-pre-udev[427]: Warning: Multiple interface names specified for MAC 60:45:bd:ff:f0:e0: enP17595s1 May 28 21:47:37 localhost systemd-udevd[505]: eth0: Failed to rename network interface 3 from 'eth1' to 'eth0': File exists May 28 21:47:37 localhost systemd-udevd[505]: eth0: Failed to process device, ignoring: File exists May 28 21:47:40 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff enP17595s1: renamed from eth0 May 28 21:47:40 nodename systemd-udevd[851]: enP17595s1: Failed to rename network interface 3 from 'eth1' to 'enP17595s1': File exists May 28 21:47:40 nodename systemd-udevd[851]: enP17595s1: Failed to process device, ignoring: File exists
I suspect persisting to the kernel command-line only happens on clusters that use old base images. The cluster we observed this on uses 44.81.20200425 as it's base image. The cluster is currently running 4.13.40.
[core@nodename ~]$ journalctl | fgrep -e eth0 -e eth1 -e enP17595s1 -e "-- Boot" May 28 21:44:30 localhost kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: VF slot 1 added May 28 21:44:31 localhost kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready May 28 21:44:31 localhost kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready May 28 21:44:31 localhost dracut-initqueue[783]: Starting dhcp for interface eth0 May 28 21:44:31 localhost dracut-initqueue[783]: dhcp: PREINIT eth0 up May 28 21:44:31 localhost dhclient[835]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3 (xid=0x13efdc43) May 28 21:44:31 localhost dhclient[835]: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x13efdc43) May 28 21:44:31 localhost dracut-initqueue[783]: dhcp: BOND setting eth0 May 28 21:45:08 localhost dracut-pre-pivot[1240]: cat: /sys/class/net/eth0/name_assign_type: Invalid argument May 28 21:45:08 localhost dracut-pre-pivot[1240]: cat: /sys/class/net/eth0/name_assign_type: Invalid argument May 28 21:45:13 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: VF registering: eth1 May 28 21:45:13 nodename kernel: mlx5_core 44bb:00:02.0 eth1: joined to eth0 May 28 21:45:13 nodename kernel: mlx5_core 44bb:00:02.0 enP17595s1: renamed from eth1 May 28 21:45:19 nodename NetworkManager[1880]: <info> [1716932719.7106] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2) May 28 21:45:19 nodename NetworkManager[1880]: <info> [1716932719.7307] settings: (eth0): created default wired connection 'Wired connection 1' May 28 21:45:19 nodename NetworkManager[1880]: <info> [1716932719.7312] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 28 21:45:19 nodename kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready May 28 21:45:20 nodename kernel: mlx5_core 44bb:00:02.0 enP17595s1: Link up May 28 21:45:20 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: Data path switched to VF: enP17595s1 May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0331] device (eth0): carrier: link connected May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0351] device (enP17595s1): carrier: link connected May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0358] manager: (enP17595s1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/3) May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0359] device (enP17595s1): enslaved to non-master-type device eth0; ignoring May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0481] device (eth0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0493] device (eth0): Activation: starting connection 'Wired connection 1' (3ee9f663-2c3d-3587-969a-9e8a42784f00) May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0494] device (eth0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0498] device (eth0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0503] device (eth0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0508] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds) May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.0662] dhcp4 (eth0): dhclient started with pid 1921 May 28 21:45:20 nodename dhclient[1921]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7 (xid=0xabd9741d) May 28 21:45:20 nodename dhclient[1921]: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0xabd9741d) May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2045] dhcp4 (eth0): address 10.236.40.135 May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2045] dhcp4 (eth0): plen 25 (255.255.255.128) May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2045] dhcp4 (eth0): classless static route 168.63.129.16/32 gw 10.236.40.129 May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2046] dhcp4 (eth0): classless static route 169.254.169.254/32 gw 10.236.40.129 May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2046] dhcp4 (eth0): gateway 10.236.40.129 May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2046] dhcp4 (eth0): lease time 4294967295 May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2046] dhcp4 (eth0): nameserver '168.63.129.16' May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2046] dhcp4 (eth0): domain name 'yked05wnvz0udnxvne12wzdtic.bx.internal.cloudapp.net' May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2046] dhcp4 (eth0): state changed unknown -> bound May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2060] device (eth0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2091] device (eth0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2093] device (eth0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2102] policy: set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS May 28 21:45:20 nodename NetworkManager[1880]: <info> [1716932720.2109] device (eth0): Activation: successful, device activated. May 28 21:45:20 nodename unknown[1983]: 99-DNSMASQ-RESTART SCRIPT[1983]: /etc/NetworkManager/dispatcher.d/99-dnsmasq-restart up happened on eth0, connection state is now May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Will persist the interface enP17595s1 driver mlx5_core with MAC 60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] systemd network link file created at /rootfs/etc/systemd/network/98-nmstate-enP17595s1.link May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Kernel argument added: ifname=enP17595s1:60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Will persist the interface eth0 driver hv_netvsc with MAC 60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] systemd network link file created at /rootfs/etc/systemd/network/98-nmstate-eth0.link May 28 21:45:35 nodename podman[2088]: [2024-05-28T21:45:35Z INFO nmstatectl::persist_nic] Kernel argument added: ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:45:35 nodename podman[2088]: I0528 21:45:35.644467 1 daemon.go:1762] Running: chroot /rootfs rpm-ostree kargs --append ifname=enP17595s1:60:45:BD:FF:F0:E0 --append ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:45:35 nodename dbus-daemon[1662]: [system] Activating via systemd: service name='org.projectatomic.rpmostree1' unit='rpm-ostreed.service' requested by ':1.20' (uid=0 pid=2139 comm="rpm-ostree kargs --append ifname=enP17595s1:60:45:" label="system_u:system_r:spc_t:s0") May 28 21:47:21 nodename NetworkManager[1880]: <info> [1716932841.5653] dhcp4 (eth0): canceled DHCP transaction, DHCP client pid 1921 May 28 21:47:21 nodename NetworkManager[1880]: <info> [1716932841.5654] dhcp4 (eth0): state changed bound -> done May 28 21:47:21 nodename NetworkManager[1880]: <info> [1716932841.5654] device (eth0): DHCPv4: 480 seconds grace period started -- Boot de5e222fe0554379abf69a7b4b02c61d -- May 28 21:47:36 localhost kernel: Command line: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.0/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:47:36 localhost kernel: Kernel command line: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.0/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:47:36 localhost kernel: Unknown kernel command line parameters "BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 ostree=/ostree/boot.0/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ifname=eth0:60:45:BD:FF:F0:E0", will be passed to user space. May 28 21:47:36 localhost kernel: ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:47:36 localhost dracut-cmdline[308]: Using kernel command line parameters: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.0/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:47:36 localhost dracut-pre-udev[427]: Warning: ifname=eth0 uses the kernel name space for interfaces May 28 21:47:36 localhost dracut-pre-udev[427]: Warning: Multiple interface names specified for MAC 60:45:bd:ff:f0:e0: enP17595s1 May 28 21:47:37 localhost kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: VF slot 1 added May 28 21:47:37 localhost kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: VF registering: eth1 May 28 21:47:37 localhost kernel: mlx5_core 44bb:00:02.0 eth1: joined to eth0 May 28 21:47:37 localhost systemd-udevd[505]: eth0: Failed to rename network interface 3 from 'eth1' to 'eth0': File exists May 28 21:47:37 localhost systemd-udevd[505]: eth0: Failed to process device, ignoring: File exists May 28 21:47:39 nodename bash[830]: removed '/etc/systemd/network/98-nmstate-enP17595s1.link' May 28 21:47:39 nodename bash[830]: removed '/etc/systemd/network/98-nmstate-eth0.link' May 28 21:47:40 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff enP17595s1: renamed from eth0 May 28 21:47:40 nodename systemd-udevd[851]: enP17595s1: Failed to rename network interface 3 from 'eth1' to 'enP17595s1': File exists May 28 21:47:40 nodename systemd-udevd[851]: enP17595s1: Failed to process device, ignoring: File exists May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.1484] manager: (enP17595s1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2) May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.1493] settings: (enP17595s1): created default wired connection 'Wired connection 1' May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.1494] device (enP17595s1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 28 21:47:41 nodename kernel: mlx5_core 44bb:00:02.0 eth1: Link up May 28 21:47:41 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff enP17595s1: Data path switched to VF: eth1 May 28 21:47:41 nodename kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enP17595s1: link becomes ready May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2472] device (eth1): carrier: link connected May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2481] manager: (eth1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/3) May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2491] settings: (eth1): created default wired connection 'Wired connection 2' May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2491] device (eth1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2525] device (enP17595s1): carrier: link connected May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2566] device (enP17595s1): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2572] device (eth1): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2580] device (enP17595s1): Activation: starting connection 'Wired connection 1' (3ee9f663-2c3d-3587-969a-9e8a42784f00) May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2582] device (eth1): Activation: starting connection 'Wired connection 2' (38816c57-97cf-3d61-951b-daf3d988513c) May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2583] device (enP17595s1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2585] device (enP17595s1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2591] device (eth1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2594] device (eth1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2596] device (enP17595s1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2601] dhcp4 (enP17595s1): activation: beginning transaction (timeout in 45 seconds) May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2604] device (eth1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2606] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2757] dhcp4 (enP17595s1): state changed new lease, address=10.236.40.135 May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2760] policy: set 'Wired connection 1' (enP17595s1) as default for IPv4 routing and DNS May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.2835] device (enP17595s1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.3171] device (enP17595s1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.3173] device (enP17595s1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') May 28 21:47:41 nodename NetworkManager[1199]: <info> [1716932861.3177] device (enP17595s1): Activation: successful, device activated. May 28 21:47:41 nodename unknown[1312]: 99-DNSMASQ-RESTART SCRIPT[1312]: /etc/NetworkManager/dispatcher.d/99-dnsmasq-restart up happened on enP17595s1, connection state is now May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2617] device (eth1): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed') May 28 21:48:41 nodename NetworkManager[1199]: <warn> [1716932921.2622] device (eth1): Activation: failed for connection 'Wired connection 2' May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2624] device (eth1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2727] dhcp4 (eth1): canceled DHCP transaction May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2727] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2728] dhcp4 (eth1): state changed no lease May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2740] device (eth1): Activation: starting connection 'Wired connection 2' (38816c57-97cf-3d61-951b-daf3d988513c) May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2741] device (eth1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2743] device (eth1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2746] device (eth1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:48:41 nodename NetworkManager[1199]: <info> [1716932921.2748] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:49:22 nodename NetworkManager[1199]: <info> [1716932962.4587] dhcp4 (enP17595s1): canceled DHCP transaction May 28 21:49:22 nodename NetworkManager[1199]: <info> [1716932962.4587] dhcp4 (enP17595s1): activation: beginning transaction (timeout in 45 seconds) May 28 21:49:22 nodename NetworkManager[1199]: <info> [1716932962.4588] dhcp4 (enP17595s1): state changed no lease May 28 21:49:22 nodename NetworkManager[1199]: <info> [1716932962.4677] dhcp4 (eth1): canceled DHCP transaction May 28 21:49:22 nodename NetworkManager[1199]: <info> [1716932962.4677] dhcp4 (eth1): state changed no lease -- Boot 64761d46354d49a69e7f97fdb91771e4 -- May 28 21:49:33 localhost kernel: Command line: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.1/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 systemd.unified_cgroup_hierarchy=0 systemd.legacy_systemd_cgroup_controller=1 May 28 21:49:33 localhost kernel: Kernel command line: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.1/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 systemd.unified_cgroup_hierarchy=0 systemd.legacy_systemd_cgroup_controller=1 May 28 21:49:33 localhost kernel: Unknown kernel command line parameters "BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 ostree=/ostree/boot.1/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ifname=eth0:60:45:BD:FF:F0:E0", will be passed to user space. May 28 21:49:33 localhost kernel: ifname=eth0:60:45:BD:FF:F0:E0 May 28 21:49:33 localhost dracut-cmdline[317]: Using kernel command line parameters: BOOT_IMAGE=(hd0,gpt1)/ostree/rhcos-9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/vmlinuz-5.14.0-284.59.1.el9_2.x86_64 rhcos.root=crypt_rootfs console=tty0 console=ttyS0,115200n8 rd.luks.options=discard ostree=/ostree/boot.1/rhcos/9d949414af8a5aeed59d276888071478d09891bce4830c2510dc8030bd94607a/0 ignition.platform.id=azure ifname=enP17595s1:60:45:BD:FF:F0:E0 ifname=eth0:60:45:BD:FF:F0:E0 systemd.unified_cgroup_hierarchy=0 systemd.legacy_systemd_cgroup_controller=1 May 28 21:49:33 localhost dracut-pre-udev[445]: Warning: ifname=eth0 uses the kernel name space for interfaces May 28 21:49:33 localhost dracut-pre-udev[445]: Warning: Multiple interface names specified for MAC 60:45:bd:ff:f0:e0: enP17595s1 May 28 21:49:33 localhost kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: VF slot 1 added May 28 21:49:34 localhost kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff eth0: VF registering: eth1 May 28 21:49:34 localhost kernel: mlx5_core 44bb:00:02.0 eth1: joined to eth0 May 28 21:49:34 localhost systemd-udevd[536]: eth0: Failed to rename network interface 3 from 'eth1' to 'eth0': File exists May 28 21:49:34 localhost systemd-udevd[536]: eth0: Failed to process device, ignoring: File exists May 28 21:49:37 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff enP17595s1: renamed from eth0 May 28 21:49:37 nodename systemd-udevd[913]: enP17595s1: Failed to rename network interface 3 from 'eth1' to 'enP17595s1': File exists May 28 21:49:37 nodename systemd-udevd[913]: enP17595s1: Failed to process device, ignoring: File exists May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.1590] manager: (enP17595s1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2) May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.1599] settings: (enP17595s1): created default wired connection 'Wired connection 1' May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.1600] device (enP17595s1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 28 21:49:39 nodename kernel: mlx5_core 44bb:00:02.0 eth1: Link up May 28 21:49:39 nodename kernel: hv_netvsc 6045bdff-f0e0-6045-bdff-f0e06045bdff enP17595s1: Data path switched to VF: eth1 May 28 21:49:39 nodename kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enP17595s1: link becomes ready May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2550] device (eth1): carrier: link connected May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2556] manager: (eth1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/3) May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2566] settings: (eth1): created default wired connection 'Wired connection 2' May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2566] device (eth1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2596] device (enP17595s1): carrier: link connected May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2630] device (enP17595s1): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2637] device (eth1): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2646] device (enP17595s1): Activation: starting connection 'Wired connection 1' (3ee9f663-2c3d-3587-969a-9e8a42784f00) May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2648] device (eth1): Activation: starting connection 'Wired connection 2' (38816c57-97cf-3d61-951b-daf3d988513c) May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2649] device (enP17595s1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2652] device (enP17595s1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2657] device (eth1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2661] device (eth1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2664] device (enP17595s1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2670] dhcp4 (enP17595s1): activation: beginning transaction (timeout in 45 seconds) May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2674] device (eth1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2676] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2697] dhcp4 (enP17595s1): state changed new lease, address=10.236.40.135 May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2699] policy: set 'Wired connection 1' (enP17595s1) as default for IPv4 routing and DNS May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.2845] device (enP17595s1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.3167] device (enP17595s1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.3169] device (enP17595s1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') May 28 21:49:39 nodename NetworkManager[1248]: <info> [1716932979.3173] device (enP17595s1): Activation: successful, device activated. May 28 21:49:39 nodename unknown[1362]: 99-DNSMASQ-RESTART SCRIPT[1362]: /etc/NetworkManager/dispatcher.d/99-dnsmasq-restart up happened on enP17595s1, connection state is now May 28 21:50:39 nodename configure-ovs.sh[1459]: enP17595s1:ethernet:connected:full:full:/org/freedesktop/NetworkManager/Devices/2:Wired connection 1:3ee9f663-2c3d-3587-969a-9e8a42784f00:/org/freedesktop/NetworkManager/ActiveConnection/2 May 28 21:50:39 nodename configure-ovs.sh[1459]: eth1:ethernet:connecting (getting IP configuration):none:none:/org/freedesktop/NetworkManager/Devices/3:Wired connection 2:38816c57-97cf-3d61-951b-daf3d988513c:/org/freedesktop/NetworkManager/ActiveConnection/3 May 28 21:50:39 nodename configure-ovs.sh[1463]: Wired connection 2:38816c57-97cf-3d61-951b-daf3d988513c:802-3-ethernet:0:never:yes:-999:no:/org/freedesktop/NetworkManager/Settings/3:yes:eth1:activating:/org/freedesktop/NetworkManager/ActiveConnection/3::/run/NetworkManager/system-connections/Wired connection 2.nmconnection May 28 21:50:39 nodename configure-ovs.sh[1463]: Wired connection 1:3ee9f663-2c3d-3587-969a-9e8a42784f00:802-3-ethernet:1716932979:Tue May 28 21\:49\:39 2024:yes:-999:no:/org/freedesktop/NetworkManager/Settings/2:yes:enP17595s1:activated:/org/freedesktop/NetworkManager/ActiveConnection/2::/run/NetworkManager/system-connections/Wired connection 1.nmconnection May 28 21:50:39 nodename configure-ovs.sh[1467]: 2: enP17595s1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000 May 28 21:50:39 nodename configure-ovs.sh[1467]: inet 10.236.40.135/25 brd 10.236.40.255 scope global noprefixroute enP17595s1 May 28 21:50:39 nodename configure-ovs.sh[1467]: 3: eth1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master enP17595s1 state UP group default qlen 1000 May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.2685] device (eth1): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed') May 28 21:50:39 nodename NetworkManager[1248]: <warn> [1716933039.2689] device (eth1): Activation: failed for connection 'Wired connection 2' May 28 21:50:39 nodename configure-ovs.sh[1468]: default via 10.236.40.129 dev enP17595s1 proto dhcp src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1468]: 10.236.40.128/25 dev enP17595s1 proto kernel scope link src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1468]: 168.63.129.16 via 10.236.40.129 dev enP17595s1 proto dhcp src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1468]: 169.254.169.254 via 10.236.40.129 dev enP17595s1 proto dhcp src 10.236.40.135 metric 100 May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.2690] device (eth1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:50:39 nodename configure-ovs.sh[1469]: fe80::/64 dev enP17595s1 proto kernel metric 1024 pref medium May 28 21:50:39 nodename configure-ovs.sh[1469]: fe80::/64 dev eth1 proto kernel metric 1024 pref medium May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3899] dhcp4 (eth1): canceled DHCP transaction May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3900] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3900] dhcp4 (eth1): state changed no lease May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3921] device (eth1): Activation: starting connection 'Wired connection 2' (38816c57-97cf-3d61-951b-daf3d988513c) May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3922] device (eth1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3924] device (eth1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3927] device (eth1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:50:39 nodename NetworkManager[1248]: <info> [1716933039.3930] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:50:39 nodename configure-ovs.sh[1491]: enP17595s1:ethernet:connected:full:full:/org/freedesktop/NetworkManager/Devices/2:Wired connection 1:3ee9f663-2c3d-3587-969a-9e8a42784f00:/org/freedesktop/NetworkManager/ActiveConnection/2 May 28 21:50:39 nodename configure-ovs.sh[1491]: eth1:ethernet:connecting (getting IP configuration):none:none:/org/freedesktop/NetworkManager/Devices/3:Wired connection 2:38816c57-97cf-3d61-951b-daf3d988513c:/org/freedesktop/NetworkManager/ActiveConnection/4 May 28 21:50:39 nodename configure-ovs.sh[1495]: Wired connection 2:38816c57-97cf-3d61-951b-daf3d988513c:802-3-ethernet:0:never:yes:-999:no:/org/freedesktop/NetworkManager/Settings/3:yes:eth1:activating:/org/freedesktop/NetworkManager/ActiveConnection/4::/run/NetworkManager/system-connections/Wired connection 2.nmconnection May 28 21:50:39 nodename configure-ovs.sh[1495]: Wired connection 1:3ee9f663-2c3d-3587-969a-9e8a42784f00:802-3-ethernet:1716932979:Tue May 28 21\:49\:39 2024:yes:-999:no:/org/freedesktop/NetworkManager/Settings/2:yes:enP17595s1:activated:/org/freedesktop/NetworkManager/ActiveConnection/2::/run/NetworkManager/system-connections/Wired connection 1.nmconnection May 28 21:50:39 nodename configure-ovs.sh[1499]: 2: enP17595s1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000 May 28 21:50:39 nodename configure-ovs.sh[1499]: inet 10.236.40.135/25 brd 10.236.40.255 scope global noprefixroute enP17595s1 May 28 21:50:39 nodename configure-ovs.sh[1499]: 3: eth1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master enP17595s1 state UP group default qlen 1000 May 28 21:50:39 nodename bash[1503]: OVS SDN mode - br-ex not found, using device eth1 May 28 21:50:39 nodename configure-ovs.sh[1500]: default via 10.236.40.129 dev enP17595s1 proto dhcp src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1500]: 10.236.40.128/25 dev enP17595s1 proto kernel scope link src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1500]: 168.63.129.16 via 10.236.40.129 dev enP17595s1 proto dhcp src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1500]: 169.254.169.254 via 10.236.40.129 dev enP17595s1 proto dhcp src 10.236.40.135 metric 100 May 28 21:50:39 nodename configure-ovs.sh[1501]: fe80::/64 dev enP17595s1 proto kernel metric 1024 pref medium May 28 21:50:39 nodename configure-ovs.sh[1501]: fe80::/64 dev eth1 proto kernel metric 1024 pref medium May 28 21:50:40 nodename kubenswrapper[1581]: I0528 21:50:40.310495 1581 manager.go:212] Machine: {Timestamp:2024-05-28 21:50:40.310258731 +0000 UTC m=+0.178005302 CPUVendorID:GenuineIntel NumCores:4 NumPhysicalCores:2 NumSockets:1 CpuFrequency:2800000 MemoryCapacity:16770740224 MemoryByType:map[] NVMInfo:{MemoryModeCapacity:0 AppDirectModeCapacity:0 AvgPowerBudget:0} HugePages:[{PageSize:1048576 NumPages:0} {PageSize:2048 NumPages:0}] MachineID:8c5ff14d51c04969b715b61813bc5d5d SystemUUID:7dd2df08-5c3d-1943-8418-b96a8cd7ddff BootID:64761d46-354d-49a6-9e7f-97fdb91771e4 Filesystems:[{Device:/sys/fs/cgroup DeviceMajor:0 DeviceMinor:26 Capacity:4194304 Type:vfs Inodes:1024 HasInodes:true} {Device:/dev/mapper/coreos-luks-root-nocrypt DeviceMajor:253 DeviceMinor:0 Capacity:136873750528 Type:vfs Inodes:66837952 HasInodes:true} {Device:/tmp DeviceMajor:0 DeviceMinor:44 Capacity:8385372160 Type:vfs Inodes:1048576 HasInodes:true} {Device:/dev/sda1 DeviceMajor:8 DeviceMinor:1 Capacity:379452416 Type:vfs Inodes:98304 HasInodes:true} {Device:/dev/shm DeviceMajor:0 DeviceMinor:23 Capacity:8385368064 Type:vfs Inodes:2047209 HasInodes:true} {Device:/run DeviceMajor:0 DeviceMinor:25 Capacity:3354148864 Type:vfs Inodes:819200 HasInodes:true}] DiskMap:map[253:0:{Name:dm-0 Major:253 Minor:0 Size:136884239872 Scheduler:none} 8:0:{Name:sda Major:8 Minor:0 Size:137438953472 Scheduler:mq-deadline}] NetworkDevices:[{Name:enP17595s1 MacAddress:60:45:bd:ff:f0:e0 Speed:100000 Mtu:1500} {Name:eth1 MacAddress:60:45:bd:ff:f0:e0 Speed:100000 Mtu:1500}] Topology:[{Id:0 Memory:16770740224 HugePages:[{PageSize:1048576 NumPages:0} {PageSize:2048 NumPages:0}] Cores:[{Id:0 Threads:[0 1] Caches:[{Id:0 Size:49152 Type:Data Level:1} {Id:0 Size:32768 Type:Instruction Level:1} {Id:0 Size:1310720 Type:Unified Level:2}] UncoreCaches:[] SocketID:0} {Id:1 Threads:[2 3] Caches:[{Id:1 Size:49152 Type:Data Level:1} {Id:1 Size:32768 Type:Instruction Level:1} {Id:1 Size:1310720 Type:Unified Level:2}] UncoreCaches:[] SocketID:0}] Caches:[{Id:0 Size:50331648 Type:Unified Level:3}] Distances:[10]}] CloudProvider:Azure InstanceType:Unknown InstanceID:7dd2df08-5c3d-1943-8418-b96a8cd7ddff} May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.3971] device (eth1): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed') May 28 21:51:39 nodename NetworkManager[1248]: <warn> [1716933099.3977] device (eth1): Activation: failed for connection 'Wired connection 2' May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.3979] device (eth1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4079] dhcp4 (eth1): canceled DHCP transaction May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4079] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4079] dhcp4 (eth1): state changed no lease May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4091] device (eth1): Activation: starting connection 'Wired connection 2' (38816c57-97cf-3d61-951b-daf3d988513c) May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4092] device (eth1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4094] device (eth1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4098] device (eth1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') May 28 21:51:39 nodename NetworkManager[1248]: <info> [1716933099.4100] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:52:39 nodename NetworkManager[1248]: <info> [1716933159.4252] device (eth1): state change: ip-config -> failed (reason 'ip-config-unavailable', sys-iface-state: 'managed') May 28 21:52:39 nodename NetworkManager[1248]: <warn> [1716933159.4257] device (eth1): Activation: failed for connection 'Wired connection 2' May 28 21:52:39 nodename NetworkManager[1248]: <info> [1716933159.4258] device (eth1): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed') May 28 21:52:39 nodename NetworkManager[1248]: <info> [1716933159.4349] dhcp4 (eth1): canceled DHCP transaction May 28 21:52:39 nodename NetworkManager[1248]: <info> [1716933159.4349] dhcp4 (eth1): activation: beginning transaction (timeout in 45 seconds) May 28 21:52:39 nodename NetworkManager[1248]: <info> [1716933159.4349] dhcp4 (eth1): state changed no lease
- is duplicated by
-
OCPBUGS-44132 nmstatectl "persist-nic-names" creates colliding kernel arguments on Azure
- Closed