[packman] broadcom-* package (wl module) makes systemd-udev crazy breaking the ifnames

Daniel Pecka dpecka at opensuse.org
Fri Feb 3 14:46:44 CET 2017 

Servus ...

current version of broadcom-* packages breaks the ifnames in 42.2 ...
systemd ppl say it's problem of our suse systemd-udev, that we don't follow
upstream defaults in order to keep inane backword compatibility and kernel
guys says, it's clearly the problem of wl module forcefully binding to eth0
(shall be - and always was wlan0)


there are attached two files with some log outputs showing, what happens ..

my personal opinion is that current broadcom-* packages in suse are broken
...

regards, daniel



S Pozdravem / Best Regards
-------------------------------------------------------

Daniel Pecka - dpecka at opensuse.org
SCSA, SCNA, RHCE, CLP
-------------- next part --------------
# dmesg  | egrep rename\|eth
[    4.526318] eth0: Broadcom BCM4353 802.11 Hybrid Wireless Controller 6.30.223.248 (r487574)
[   16.544259] r8169 0000:03:00.0 eth1: RTL8168d/8111d at 0xffffc90000c88000, c8:0a:a9:1d:3c:ad, XID 083000c0 IRQ 31
[   16.544264] r8169 0000:03:00.0 eth1: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[   16.659970] r8169 0000:03:00.0 rename3: renamed from eth1
[  107.217449] r8169 0000:03:00.0 rename3: link down
[  107.217468] r8169 0000:03:00.0 rename3: link down
[  115.773881] r8169 0000:03:00.0 rename3: link up



# ip a
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1
    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: <NO-CARRIER,BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state DORMANT group default qlen 1000
    link/ether 00:26:82:66:0e:c8 brd ff:ff:ff:ff:ff:ff
3: rename3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc pfifo_fast state UP group default qlen 1000
    link/ether c8:0a:a9:1d:3c:ad brd ff:ff:ff:ff:ff:ff
    inet 10.0.164.15/22 brd 10.0.167.255 scope global dynamic rename3
       valid_lft 86292sec preferred_lft 86292sec
    inet6 fe80::ca0a:a9ff:fe1d:3cad/64 scope link 
       valid_lft forever preferred_lft forever



# cat 70-persistent-net.rules 
# This file was automatically generated by the /usr/lib/udev/write_net_rules
# program, run by the persistent-net-generator.rules rules file.
#
# You can modify it, as long as you keep each rule on a single
# line, and change only the value of the NAME= key.

# PCI device 0x10ec:0x8168 (r8169)
SUBSYSTEM=="net", ACTION=="add", DRIVERS=="?*", ATTR{address}=="c8:0a:a9:1d:3c:ad", ATTR{dev_id}=="0x0", ATTR{type}=="1", KERNEL=="eth*", NAME="eth0"

# PCI device 0x14e4:0x4353 (bcma-pci-bridge)
SUBSYSTEM=="net", ACTION=="add", DRIVERS=="?*", ATTR{address}=="00:26:82:66:0e:c8", ATTR{dev_id}=="0x0", ATTR{type}=="1", KERNEL=="wlan*", NAME="wlan0"





# journalctl -b | egrep rename\|eth
Feb 03 09:51:56 linux-yet3 kernel: eth0: Broadcom BCM4353 802.11 Hybrid Wireless Controller 6.30.223.248 (r487574)
Feb 03 09:52:08 linux-yet3 kernel: r8169 0000:03:00.0 eth1: RTL8168d/8111d at 0xffffc90000c88000, c8:0a:a9:1d:3c:ad, XID 083000c0 IRQ 31
Feb 03 09:52:08 linux-yet3 kernel: r8169 0000:03:00.0 eth1: jumbo features [frames: 9200 bytes, tx checksumming: ko]
Feb 03 09:52:08 linux-yet3 kernel: r8169 0000:03:00.0 rename3: renamed from eth1
Feb 03 09:52:18 linux-yet3 NetworkManager[1328]: <info>  Loaded device plugin: NMVethFactory (internal)
Feb 03 09:52:18 linux-yet3 SuSEfirewall2[1365]: using default zone 'ext' for interface eth0
Feb 03 09:52:18 linux-yet3 SuSEfirewall2[1368]: using default zone 'ext' for interface rename3
Feb 03 09:52:19 linux-yet3.suse NetworkManager[1328]: <warn>  (rename3): failed to find device 3 'rename3' with udev
Feb 03 09:52:19 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): new Ethernet device (carrier: OFF, driver: 'r8169', ifindex: 3)
Feb 03 09:52:19 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): using nl80211 for WiFi device control
Feb 03 09:52:19 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): new 802.11 WiFi device (carrier: UNKNOWN, driver: 'wl', ifindex: 2)
Feb 03 09:52:19 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 03 09:52:20 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): supplicant interface state: init -> starting
Feb 03 09:52:20 linux-yet3.suse NetworkManager[1328]: <info>  (eth0) supports 1 scan SSIDs
Feb 03 09:52:20 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): supplicant interface state: starting -> ready
Feb 03 09:52:20 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Feb 03 09:52:22 linux-yet3.suse NetworkManager[1328]: <info>  (eth0): supplicant interface state: ready -> inactive
Feb 03 09:53:08 linux-yet3.suse systemd-udevd[632]: seq 2248 '/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth1' is taking a long time
Feb 03 09:53:38 linux-yet3.suse systemd-udevd[731]: error changing net interface name 'eth1' to 'eth0': File exists
Feb 03 09:53:38 linux-yet3.suse systemd-udevd[731]: could not rename interface '3' from 'eth1' to 'eth0': File exists
Feb 03 09:53:38 linux-yet3.suse systemd[1735]: sys-subsystem-net-devices-eth0.device: Dev sys-subsystem-net-devices-eth0.device appeared twice with different sysfs paths /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 and /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth1
Feb 03 09:53:38 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 03 09:53:38 linux-yet3.suse systemd[1785]: sys-subsystem-net-devices-eth0.device: Dev sys-subsystem-net-devices-eth0.device appeared twice with different sysfs paths /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 and /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth1
Feb 03 09:53:38 linux-yet3.suse systemd[1]: sys-subsystem-net-devices-eth0.device: Dev sys-subsystem-net-devices-eth0.device appeared twice with different sysfs paths /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 and /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth1
Feb 03 09:53:38 linux-yet3.suse systemd[1735]: sys-subsystem-net-devices-eth0.device: Dev sys-subsystem-net-devices-eth0.device appeared twice with different sysfs paths /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 and /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/rename3
Feb 03 09:53:38 linux-yet3.suse systemd[1785]: sys-subsystem-net-devices-eth0.device: Dev sys-subsystem-net-devices-eth0.device appeared twice with different sysfs paths /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 and /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/rename3
Feb 03 09:53:38 linux-yet3.suse systemd[1]: sys-subsystem-net-devices-eth0.device: Dev sys-subsystem-net-devices-eth0.device appeared twice with different sysfs paths /sys/devices/pci0000:00/0000:00:1c.0/0000:02:00.0/net/eth0 and /sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/rename3
Feb 03 09:53:38 linux-yet3.suse kernel: r8169 0000:03:00.0 rename3: link down
Feb 03 09:53:38 linux-yet3.suse kernel: r8169 0000:03:00.0 rename3: link down
Feb 03 09:53:38 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): created default wired connection 'Wired connection 1'
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): link connected
Feb 03 09:53:47 linux-yet3.suse kernel: r8169 0000:03:00.0 rename3: link up
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): Activation: starting connection 'Wired connection 1' (f4732d9a-6b03-46e9-80cf-f2e0c4a70fe4)
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: prepare -> config (reason 'none') [40 50 0]
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: config -> ip-config (reason 'none') [50 70 0]
Feb 03 09:53:47 linux-yet3.suse NetworkManager[1328]: <info>  Activation (rename3) Beginning DHCPv4 transaction (timeout in 45 seconds)
Feb 03 09:53:53 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): DHCPv4 state changed unknown -> bound
Feb 03 09:53:53 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
Feb 03 09:53:53 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Feb 03 09:53:53 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): device state change: secondaries -> activated (reason 'none') [90 100 0]
Feb 03 09:53:53 linux-yet3.suse NetworkManager[1328]: <info>  Policy set 'Wired connection 1' (rename3) as default for IPv4 routing and DNS.
Feb 03 09:53:54 linux-yet3.suse NetworkManager[1328]: <info>  (rename3): Activation: successful, device activated.
Feb 03 09:53:54 linux-yet3.suse nm-dispatcher[2275]: Dispatching action 'up' for rename3




# journalctl -b | grep udev
Feb 03 09:51:55 linux-yet3 systemd[1]: Listening on udev Control Socket.
Feb 03 09:51:55 linux-yet3 systemd[1]: Starting dracut pre-udev hook...
Feb 03 09:51:55 linux-yet3 systemd[1]: Started dracut pre-udev hook.
Feb 03 09:51:55 linux-yet3 systemd[1]: Starting udev Kernel Device Manager...
Feb 03 09:51:55 linux-yet3 systemd-udevd[256]: Network interface NamePolicy= disabled by default.
Feb 03 09:51:55 linux-yet3 systemd[1]: Started udev Kernel Device Manager.
Feb 03 09:51:56 linux-yet3 systemd[1]: Starting udev Coldplug all Devices...
Feb 03 09:51:56 linux-yet3 systemd-udevd[256]: Network interface NamePolicy= disabled by default.
Feb 03 09:51:56 linux-yet3 systemd[1]: Started udev Coldplug all Devices.
Feb 03 09:51:58 linux-yet3 systemd[1]: Stopped udev Coldplug all Devices.
Feb 03 09:51:58 linux-yet3 systemd[1]: Stopping udev Kernel Device Manager...
Feb 03 09:51:58 linux-yet3 systemd[1]: Stopped udev Kernel Device Manager.
Feb 03 09:51:58 linux-yet3 systemd[1]: Stopped dracut pre-udev hook.
Feb 03 09:51:58 linux-yet3 systemd[1]: Closed udev Control Socket.
Feb 03 09:51:58 linux-yet3 systemd[1]: Closed udev Kernel Socket.
Feb 03 09:51:58 linux-yet3 systemd[1]: Starting Cleanup udevd DB...
Feb 03 09:51:58 linux-yet3 systemd[1]: Started Cleanup udevd DB.
Feb 03 09:52:04 linux-yet3 systemd-udevd[632]: Network interface NamePolicy= disabled by default.
Feb 03 09:52:06 linux-yet3 systemd[1]: Started udev Kernel Device Manager.
Feb 03 09:52:07 linux-yet3 systemd-udevd[701]: Process '/usr/sbin/crda' failed with exit code 249.
Feb 03 09:52:09 linux-yet3 systemd-udevd[632]: Network interface NamePolicy= disabled by default.
Feb 03 09:52:19 linux-yet3.suse NetworkManager[1328]: <warn>  (rename3): failed to find device 3 'rename3' with udev
Feb 03 09:53:08 linux-yet3.suse systemd-udevd[632]: seq 2248 '/devices/pci0000:00/0000:00:1c.1/0000:03:00.0/net/eth1' is taking a long time
Feb 03 09:53:38 linux-yet3.suse systemd-udevd[731]: error changing net interface name 'eth1' to 'eth0': File exists
Feb 03 09:53:38 linux-yet3.suse systemd-udevd[731]: could not rename interface '3' from 'eth1' to 'eth0': File exists
-------------- next part --------------
# ip a
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1
    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 pfifo_fast state UP group default qlen 1000
    link/ether c8:0a:a9:1d:3c:ad brd ff:ff:ff:ff:ff:ff
    inet 10.0.164.15/22 brd 10.0.167.255 scope global dynamic eth0
       valid_lft 86263sec preferred_lft 86263sec
    inet6 fe80::ca0a:a9ff:fe1d:3cad/64 scope link 
       valid_lft forever preferred_lft forever
3: wlan0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc mq state DOWN group default qlen 1000
    link/ether 00:26:82:66:0e:c8 brd ff:ff:ff:ff:ff:ff

# journalctl -b | egrep rename\|eth\|udev
Feb 03 11:47:37 linux-yet3 systemd[1]: Listening on udev Control Socket.
Feb 03 11:47:37 linux-yet3 systemd[1]: Starting dracut pre-udev hook...
Feb 03 11:47:38 linux-yet3 systemd[1]: Started dracut pre-udev hook.
Feb 03 11:47:38 linux-yet3 systemd[1]: Starting udev Kernel Device Manager...
Feb 03 11:47:38 linux-yet3 systemd-udevd[257]: Network interface NamePolicy= disabled by default.
Feb 03 11:47:38 linux-yet3 systemd[1]: Started udev Kernel Device Manager.
Feb 03 11:47:38 linux-yet3 systemd[1]: Starting udev Coldplug all Devices...
Feb 03 11:47:38 linux-yet3 systemd-udevd[257]: Network interface NamePolicy= disabled by default.
Feb 03 11:47:38 linux-yet3 systemd[1]: Started udev Coldplug all Devices.
Feb 03 11:47:40 linux-yet3 systemd[1]: Stopped udev Coldplug all Devices.
Feb 03 11:47:40 linux-yet3 systemd[1]: Stopping udev Kernel Device Manager...
Feb 03 11:47:40 linux-yet3 systemd[1]: Stopped udev Kernel Device Manager.
Feb 03 11:47:40 linux-yet3 systemd[1]: Stopped dracut pre-udev hook.
Feb 03 11:47:40 linux-yet3 systemd[1]: Closed udev Kernel Socket.
Feb 03 11:47:40 linux-yet3 systemd[1]: Closed udev Control Socket.
Feb 03 11:47:40 linux-yet3 systemd[1]: Starting Cleanup udevd DB...
Feb 03 11:47:40 linux-yet3 systemd[1]: Started Cleanup udevd DB.
Feb 03 11:47:46 linux-yet3 systemd-udevd[629]: Network interface NamePolicy= disabled by default.
Feb 03 11:47:48 linux-yet3 systemd[1]: Started udev Kernel Device Manager.
Feb 03 11:47:50 linux-yet3 kernel: r8169 0000:03:00.0 eth0: RTL8168d/8111d at 0xffffc90000c88000, c8:0a:a9:1d:3c:ad, XID 083000c0 IRQ 31
Feb 03 11:47:50 linux-yet3 kernel: r8169 0000:03:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
Feb 03 11:48:00 linux-yet3 NetworkManager[1332]: <info>  Loaded device plugin: NMVethFactory (internal)
Feb 03 11:48:01 linux-yet3 SuSEfirewall2[1378]: using default zone 'ext' for interface eth0
Feb 03 11:48:01 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): new Ethernet device (carrier: OFF, driver: 'r8169', ifindex: 2)
Feb 03 11:48:01 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 03 11:48:02 linux-yet3.suse kernel: r8169 0000:03:00.0 eth0: link down
Feb 03 11:48:02 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): created default wired connection 'Wired connection 1'
Feb 03 11:50:10 linux-yet3.suse kernel: r8169 0000:03:00.0 eth0: link up
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): link connected
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: unavailable -> disconnected (reason 'carrier-changed') [20 30 40]
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): Activation: starting connection 'Wired connection 1' (aac36fc3-2ee6-42e6-88a1-e05305d15849)
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
Feb 03 11:50:10 linux-yet3.suse NetworkManager[1332]: <info>  Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Feb 03 11:50:17 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): DHCPv4 state changed unknown -> bound
Feb 03 11:50:17 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
Feb 03 11:50:17 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
Feb 03 11:50:17 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): device state change: secondaries -> activated (reason 'none') [90 100 0]
Feb 03 11:50:17 linux-yet3.suse NetworkManager[1332]: <info>  Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
Feb 03 11:50:18 linux-yet3.suse NetworkManager[1332]: <info>  (eth0): Activation: successful, device activated.
Feb 03 11:50:18 linux-yet3.suse nm-dispatcher[2356]: Dispatching action 'up' for eth0
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Closed udev Control Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Stopping udev Control Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: systemd-udevd-control.socket: Socket service systemd-udevd.service already active, refusing.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Failed to listen on udev Control Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Closed udev Kernel Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Stopping udev Kernel Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: systemd-udevd-kernel.socket: Socket service systemd-udevd.service already active, refusing.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Failed to listen on udev Kernel Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Stopping udev Kernel Device Manager...
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Stopped udev Kernel Device Manager.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Listening on udev Control Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Listening on udev Kernel Socket.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Starting udev Kernel Device Manager...
Feb 03 11:50:59 linux-yet3.suse systemd-udevd[2550]: Network interface NamePolicy= disabled by default.
Feb 03 11:50:59 linux-yet3.suse systemd[1]: Started udev Kernel Device Manager.

More information about the Packman mailing list