[erledigt] Netzwerk beim booten dauert ewig?!

[alex0801]

Hallo zusammen,

ich habe meinem Rechner ein Rundum-Upgrade gegönnt und seit dem dauert der Boot-Vorgang dank des Netzwerks gefühlte Ewigkeiten:


cat /var/log/syslog | grep -i "networkmanager"

Code: Alles auswählen

Mar 11 17:47:09 alexpc NetworkManager[684]: <info>  [1615481229.8532] NetworkManager (version 1.30.0) is starting... (for the first time)
Mar 11 17:47:09 alexpc NetworkManager[684]: <info>  [1615481229.8533] Read config: /etc/NetworkManager/NetworkManager.conf (lib: no-mac-addr-change.conf)
Mar 11 17:47:09 alexpc NetworkManager[684]: <info>  [1615481229.8550] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
Mar 11 17:47:09 alexpc NetworkManager[684]: <info>  [1615481229.8700] manager[0x5652c6912010]: monitoring kernel firmware directory '/lib/firmware'.
Mar 11 17:47:09 alexpc NetworkManager[684]: <info>  [1615481229.8700] monitoring ifupdown state file '/run/network/ifstate'.
Mar 11 17:47:09 alexpc dbus-daemon[679]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=684 comm="/usr/sbi
n/NetworkManager --no-daemon ")
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1710] hostname: hostname: using hostnamed
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1710] hostname: hostname changed from (none) to "alexpc"
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1713] dns-mgr[0x5652c6907170]: init: dns=default,systemd-resolved rc-manager=symlink (auto)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1751] manager[0x5652c6912010]: rfkill: Wi-Fi hardware radio set enabled
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1751] manager[0x5652c6912010]: rfkill: WWAN hardware radio set enabled
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1907] Loaded device plugin: NMTeamFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-team.so)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1920] Loaded device plugin: NMAtmManager (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-adsl.so)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1973] Loaded device plugin: NMBluezManager (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-bluetooth.so)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1985] Loaded device plugin: NMWifiFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-wifi.so)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1995] Loaded device plugin: NMWwanFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-wwan.so)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1997] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1998] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1999] manager: Networking is enabled by state file
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.1999] dhcp-init: Using DHCP client 'internal'
Mar 11 17:47:10 alexpc dbus-daemon[679]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=684 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2016] settings: Loaded settings plugin: ifupdown ("/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-settings-plugin-ifupdown.so")
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2016] settings: Loaded settings plugin: keyfile (internal)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2016] ifupdown: management mode: unmanaged
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2016] ifupdown:       interface-parser: parsing file /etc/network/interfaces
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2016] ifupdown:       interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d/*
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2017] ifupdown:       interface-parser: parsing file /etc/network/interfaces.d/br0
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2017] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces.d/br0
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2017] ifupdown:       interface-parser: parsing file /etc/network/interfaces.d/br0~
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2017] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces.d/br0~
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2017] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2063] device (lo): carrier: link connected
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2065] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2073] manager: (enp9s0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.2080] manager: (br0): new Bridge device (/org/freedesktop/NetworkManager/Devices/3)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6221] device (br0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 11 17:47:10 alexpc NetworkManager[684]: <warn>  [1615481230.6249] Error: failed to open /run/network/ifstate
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6283] modem-manager: ModemManager available
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6283] device (br0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6288] policy: auto-activating connection 'br0' (d1ac9120-ceb7-4b7c-9ee1-b5c9cb1ae3d6)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6291] device (br0): Activation: starting connection 'br0' (d1ac9120-ceb7-4b7c-9ee1-b5c9cb1ae3d6)
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6292] device (br0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6294] manager: NetworkManager state is now CONNECTING
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6304] device (br0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6307] device (br0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6308] device (br0): IPv4 config waiting until carrier is on
Mar 11 17:47:10 alexpc NetworkManager[684]: <info>  [1615481230.6308] device (br0): IPv6 config waiting until carrier is on
Mar 11 17:47:11 alexpc NetworkManager[684]: <info>  [1615481231.9668] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 11 17:47:15 alexpc NetworkManager[684]: <info>  [1615481235.2472] device (enp9s0): carrier: link connected
Mar 11 17:47:21 alexpc systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Mar 11 17:47:45 alexpc NetworkManager[684]: <info>  [1615481265.3763] device (br0): carrier: link connected
Mar 11 17:47:45 alexpc NetworkManager[684]: <info>  [1615481265.3765] device (br0): IPv4 config waiting until slaves are ready
Mar 11 17:47:45 alexpc NetworkManager[684]: <info>  [1615481265.3765] device (br0): IPv6 config waiting until slaves are ready
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.0413] manager: (virbr0): new Bridge device (/org/freedesktop/NetworkManager/Devices/4)
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3233] device (virbr0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3254] device (virbr0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3259] device (virbr0): Activation: starting connection 'virbr0' (512da462-f02a-4fba-b388-e7578edbaa50)
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3260] device (virbr0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3263] device (virbr0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3265] device (virbr0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3266] device (virbr0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc dbus-daemon[679]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=684 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3354] device (virbr0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3356] device (virbr0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3364] policy: set 'br0' (br0) as default for IPv4 routing and DNS
Mar 11 17:47:47 alexpc NetworkManager[684]: <info>  [1615481267.3365] device (virbr0): Activation: successful, device activated.
Mar 11 17:47:57 alexpc systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Mar 11 17:47:57 alexpc NetworkManager[684]: <info>  [1615481277.9488] agent-manager: agent[10429e3cb46aa600,:1.84/org.gnome.Shell.NetworkAgent/1000]: agent registered

48 Sekunden, und der Boot-Vorgang ist lahmgelegt mit einem (exemplarisch aus dem Foto beim hochfahren abgetippt):

A start job is running for Raise network interfaces ... (43s / 5min 2s)

nmcli connection show

Code: Alles auswählen

root@alexpc:~# nmcli connection show
NAME      UUID                                  TYPE      DEVICE 
br0       d1ac9120-ceb7-4b7c-9ee1-b5c9cb1ae3d6  bridge    br0    
virbr0    512da462-f02a-4fba-b388-e7578edbaa50  bridge    virbr0 
br0slave  915c30f1-95b1-49ab-a933-8a944d73956b  ethernet  --     
LAN       9aa91e40-ec00-4fa2-9265-227f029137a4  ethernet  --     

cat /etc/network/interfaces

Code: Alles auswählen

# This file describes the network interfaces available on your system
# and how to activate them. For more information, see interfaces(5).

source /etc/network/interfaces.d/*

# The loopback network interface
auto lo
iface lo inet loopback

cat /etc/network/interfaces.d/br0

Code: Alles auswählen

## DHCP ip config file for br0 ##
auto br0
 
# Bridge setup
iface br0 inet dhcp
  bridge_ports enp9s0


iface br0 inet6 auto

enp9s0 ist die eigentliche Realtek-Netzwerkkarte (Firmware ist installiert). Da ich aber libvirt für meine Virtualisierung benutze, brauche ich ein gebrücktes Netzwerkinterfaces. Deshalb "br0" als Brücke und enp9s0 ist (zusammen mit "br0slave") Teil davon.

Es funktioniert auch alles, ich finde keine Fehlermeldung, aber es dauert "ewig" beim starten.

Ein

Code: Alles auswählen

systemctl disable NetworkManager-wait-online.service

hab ich schon probiert. Installation von "haveged" (soll im zusammenhang mit der notwendigen Entropy für WPA, was ich aber nicht nutze, es aber dennoch installiert ist, stehen) hat auch nix gebracht.

Mit meinem bisherigen Rechner war genau dieses Setup kein Thema.
Sieht anhand des Logs jemand woran das nun liegt? Oder hat jemand noch einen Tipp wo ich noch schauen könnte?

Gruß
Alex

[eggy]

systemd-analyze blame

[alex0801]

Hmmm:

systemd-analyze blame

Code: Alles auswählen

34.311s networking.service                                                       
 4.259s plymouth-quit-wait.service                                               
 2.654s ifupdown-pre.service                                                     
 1.269s nvidia-persistenced.service                                              
 1.103s nfs-server.service                                                       
  959ms udisks2.service                                                          
  572ms cpufrequtils.service                                                     
  542ms fwupd.service                                                            
  462ms systemd-modules-load.service                                             
  418ms upower.service                                                           
  240ms dev-nvme0n1p2.device                                                     
  213ms accounts-daemon.service                                                  
  200ms NetworkManager.service                                                   
  185ms avahi-daemon.service                                                     
  167ms apparmor.service                                                         
  163ms polkit.service                                                           
  147ms switcheroo-control.service                                               
  139ms libvirtd.service                                                         
  134ms media-mirror.mount                                                       
  129ms user@1000.service                                                        
  126ms systemd-logind.service                                                   

Liefert keine wirklich neuen Erkenntnisse würde ich sagen.

[alex0801]

[eggy]

Liefert keine wirklich neuen Erkenntnisse würde ich sagen.

Liefert die Erkenntnis, dass das langsame Netzwerk nicht auf was noch langsameres wartet.

[MSfree]

cat /etc/network/interfaces.d/br0

Code: Alles auswählen

## DHCP ip config file for br0 ##
auto br0
 
# Bridge setup
iface br0 inet dhcp
  bridge_ports enp9s0

iface br0 inet6 auto

Steck mal

Code: Alles auswählen

    bridge_fd 5

in deine Bridge-Konfiguration:

Code: Alles auswählen

iface br0 inet dhcp
  bridge_ports enp9s0
  bridge_fd 5

Das sollte den Aufbau der Bridge auf max. 5 Sekunden beschleunigen.

[alex0801]

Danke @MSfree, hat nicht zu 100% geholfen, aber die Situation verbessert. Die "Warteanzeige" beim booten kommt immer noch.

systemd-analyze blame

Code: Alles auswählen

21.537s networking.service                                                       
 4.434s plymouth-quit-wait.service                                               
 2.695s ifupdown-pre.service                                                     
 1.273s nvidia-persistenced.service                                              
 1.104s nfs-server.service                                                       
  964ms udisks2.service                                                          
  632ms apt-daily-upgrade.service                                                
  626ms cpufrequtils.service                                                     
  624ms man-db.service                                                           
  535ms systemd-modules-load.service                                             
  531ms fwupd.service                                                                                           

syslog:

Code: Alles auswählen

Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.0429] NetworkManager (version 1.30.0) is starting... (for the first time)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.0429] Read config: /etc/NetworkManager/NetworkManager.conf (lib: no-mac-addr-change.conf)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.0754] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.0999] manager[0x5602fa946010]: monitoring kernel firmware directory '/lib/firmware'.
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.1000] monitoring ifupdown state file '/run/network/ifstate'.
Mar 11 22:28:35 alexpc dbus-daemon[684]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=685 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.3252] hostname: hostname: using hostnamed
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.3252] hostname: hostname changed from (none) to "alexpc"
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.3255] dns-mgr[0x5602fa93b170]: init: dns=default,systemd-resolved rc-manager=symlink (auto)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.5863] manager[0x5602fa946010]: rfkill: Wi-Fi hardware radio set enabled
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.5864] manager[0x5602fa946010]: rfkill: WWAN hardware radio set enabled
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6017] Loaded device plugin: NMTeamFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-team.so)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6030] Loaded device plugin: NMAtmManager (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-adsl.so)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6080] Loaded device plugin: NMBluezManager (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-bluetooth.so)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6091] Loaded device plugin: NMWifiFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-wifi.so)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6106] Loaded device plugin: NMWwanFactory (/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-device-plugin-wwan.so)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6108] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6108] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6109] manager: Networking is enabled by state file
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6110] dhcp-init: Using DHCP client 'internal'
Mar 11 22:28:35 alexpc dbus-daemon[684]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=685 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6121] settings: Loaded settings plugin: ifupdown ("/usr/lib/x86_64-linux-gnu/NetworkManager/1.30.0/libnm-settings-plugin-ifupdown.so")
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6121] settings: Loaded settings plugin: keyfile (internal)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6121] ifupdown: management mode: unmanaged
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6121] ifupdown:       interface-parser: parsing file /etc/network/interfaces
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6121] ifupdown:       interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d/*
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6122] ifupdown:       interface-parser: parsing file /etc/network/interfaces.d/br0
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6122] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces.d/br0
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6122] ifupdown:       interface-parser: parsing file /etc/network/interfaces.d/br0~
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6122] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces.d/br0~
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6122] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6166] device (lo): carrier: link connected
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6169] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6176] manager: (enp9s0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6183] manager: (br0): new Bridge device (/org/freedesktop/NetworkManager/Devices/3)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6318] device (br0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 11 22:28:35 alexpc NetworkManager[685]: <warn>  [1615498115.6330] Error: failed to open /run/network/ifstate
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6358] modem-manager: ModemManager available
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6358] device (br0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6363] policy: auto-activating connection 'br0' (d1ac9120-ceb7-4b7c-9ee1-b5c9cb1ae3d6)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6367] device (br0): Activation: starting connection 'br0' (d1ac9120-ceb7-4b7c-9ee1-b5c9cb1ae3d6)
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6368] device (br0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6371] manager: NetworkManager state is now CONNECTING
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6382] device (br0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6388] device (br0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6390] device (br0): IPv4 config waiting until carrier is on
Mar 11 22:28:35 alexpc NetworkManager[685]: <info>  [1615498115.6390] device (br0): IPv6 config waiting until carrier is on
Mar 11 22:28:37 alexpc NetworkManager[685]: <info>  [1615498117.1090] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 11 22:28:40 alexpc NetworkManager[685]: <info>  [1615498120.3764] device (enp9s0): carrier: link connected
Mar 11 22:28:46 alexpc systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Mar 11 22:28:50 alexpc NetworkManager[685]: <info>  [1615498130.6654] device (br0): carrier: link connected
Mar 11 22:28:50 alexpc NetworkManager[685]: <info>  [1615498130.6655] device (br0): IPv4 config waiting until slaves are ready
Mar 11 22:28:50 alexpc NetworkManager[685]: <info>  [1615498130.6656] device (br0): IPv6 config waiting until slaves are ready
Mar 11 22:28:59 alexpc NetworkManager[685]: <info>  [1615498139.9982] manager: (virbr0): new Bridge device (/org/freedesktop/NetworkManager/Devices/4)
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3002] device (virbr0): state change: unmanaged -> unavailable (reason 'connection-assumed', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3025] device (virbr0): state change: unavailable -> disconnected (reason 'connection-assumed', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3031] device (virbr0): Activation: starting connection 'virbr0' (d73390e1-448f-4c38-8296-8dd5d15cc14a)
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3031] device (virbr0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3034] device (virbr0): state change: prepare -> config (reason 'none', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3037] device (virbr0): state change: config -> ip-config (reason 'none', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3039] device (virbr0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc dbus-daemon[684]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.10' (uid=0 pid=685 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3155] device (virbr0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3158] device (virbr0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'external')
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3168] policy: set 'br0' (br0) as default for IPv4 routing and DNS
Mar 11 22:29:00 alexpc NetworkManager[685]: <info>  [1615498140.3169] device (virbr0): Activation: successful, device activated.
Mar 11 22:29:10 alexpc systemd[1]: NetworkManager-dispatcher.service: Succeeded.

Irgendwas blockiert da noch. Allein die Differenz von 10sek zwischen den letzten beiden Logzeilen sind nicht wirklch erklärbar. Ich muss mal schauen ob ich den NetworkManager noch auf DBEUG Log oder so bekomme.

Auf meinem Rechner zuvor hatte ich selbes Linux mit eigentlich selber Bridge. Hab da jetzt keine genauen Zeiten, aber beim booten ist mir da nix aufgefallen. Die Ausgaben sind "einfach so vorbeigerauscht", fertig war der Bootvorgang und der Login-Screen stand da.

[MSfree]

Danke @MSfree, hat nicht zu 100% geholfen, aber die Situation verbessert. Die "Warteanzeige" beim booten kommt immer noch.

Du kannst es ja auch mit kleineren Werten als 5 versuchen. Ganz loswerden wirst du diese Wartezeit nicht. Ich erinnere mich nur, daß ich auch plötzlich Wartezeiten mit Bridges hatte, nachdem mal ein Update eingetrudelt ist (ist zu lange her, um mich an die genauen Versionen zu erinnern).

Im Extremfall kannst du es auch mit 1 versuchen. Wenn damit alles reibungslos funktioniert, sollte doch die Restwartezeit verschmerzbar sein.

[alex0801]

Bin noch am recherchieren... DebugModus soll es geben, habs aber mit Debian noch nciht aktiviert bekommen.

Während dessen hab ich mir mal den dmesg-Outout angeschau während ich ein /etc/init.d/networking restart gemacht habe:

Code: Alles auswählen

[ 6097.356603] br0: port 1(enp9s0) entered disabled state
[ 6097.401498] device enp9s0 left promiscuous mode
[ 6097.401576] br0: port 1(enp9s0) entered disabled state
[ 6097.847935] br0: port 1(enp9s0) entered blocking state
[ 6097.847938] br0: port 1(enp9s0) entered disabled state
[ 6097.848028] device enp9s0 entered promiscuous mode
[ 6097.850353] enp9s0: 0xffffb0214006d000, 18:c0:4d:55:7c:4e, IRQ 41
[ 6097.905369] br0: port 1(enp9s0) entered blocking state
[ 6097.905374] br0: port 1(enp9s0) entered forwarding state
[ 6097.905761] device enp9s0 left promiscuous mode
[ 6097.905833] br0: port 1(enp9s0) entered disabled state
[ 6100.975962] r8168: enp9s0: link up
-----
[ 6159.716546] br0: port 1(enp9s0) entered blocking state
[ 6159.716551] br0: port 1(enp9s0) entered disabled state
[ 6159.716721] device enp9s0 entered promiscuous mode
[ 6159.716843] br0: port 1(enp9s0) entered blocking state
[ 6159.716846] br0: port 1(enp9s0) entered listening state
-----
[ 6174.932632] br0: port 1(enp9s0) entered learning state
[ 6190.039498] br0: port 1(enp9s0) entered forwarding state
[ 6190.039501] br0: topology change detected, propagating

Die ----- symbolisieren (neben der lesbaren Zeitangabe) die Wartepausen.

Die Netzwerkkarte ist es nicht... die ist nach gut 3sek wieder da. Liegt tatsächlich an der Bridge.

Bleibt nur die Frage: Was tut der da so lange?!

Ich schau weiter nach dem Debug-Modus. Lässt mir keine Ruhe. Da hast du einen Top-Modernen Rechner mit 8c/16t, ewig viel RAM und furchtbar schnelle PCIe SSD und dann legt die Netzwerkbrücke alles lahm

[alex0801]

Debug-Modus hab ich gefunden, hat mich aber auch nicht weiter gebracht.
Habs aber hin bekommen dass er ratz-fatz bootet:

Code: Alles auswählen

## DHCP ip config file for br0 ##
auto br0
 
# Bridge setup
iface br0 inet dhcp
  bridge_ports enp9s0
  bridge_fd 0
  bridge_stp off
  bridge_maxwait 0

iface br0 inet6 auto

Da ich die Brücke nur für meine lokalen 1-2 VMs brauche, kann ich auf STP verzichten. Und zusammen mit maxwait0 und fd 0 flutscht es jetzt:

Code: Alles auswählen

4.644s plymouth-quit-wait.service                                               
4.219s networking.service                                                       
2.694s ifupdown-pre.service                                                     
1.270s nvidia-persistenced.service                                              
1.118s nfs-server.service                                                       
 955ms udisks2.service                                                          
 586ms cpufrequtils.service                                                     
 549ms fwupd.service                                                            
 546ms systemd-modules-load.service                                             
 297ms upower.service                                                           
 290ms dev-nvme0n1p2.device           
 

[unitra]

Das worauf der Rechner wartet, bzw die Bridge die mit einem redundantem Link angebunden ist, die Bridge versucht die Schleifenfreiheit sicher zu stellen. Erkennbar an den Stati:
* blocking
* listening
* learning
* forwarding

Das sind die Stati des Spannig Tree Algorithmus. Damit keine Schleife zwischen deinem Rechner und dem direkt verbundenem Switch entsteht. Ethernet Rahmen haben keine TTL wie IP Pakete, deswegen stellt das STP (Spanning Tree Protokoll) die Schleifenfreiheit sicher. https://en.wikipedia.org/wiki/Spanning_ ... rt_states

Code: Alles auswählen

[ 6159.716546] br0: port 1(enp9s0) entered blocking state
...
[ 6159.716846] br0: port 1(enp9s0) entered listening state
...
[ 6174.932632] br0: port 1(enp9s0) entered learning state
...
[ 6190.039498] br0: port 1(enp9s0) entered forwarding state
...

Würde man einen physischen Link raus nehmen, dann würde da booten wesentlich schneller gehen. Die Frage ist benötigt man in diesem Setup wirklich 2 physische Links in einer Broadcastdomäne? Wenn ja, dann musst man sich darauf einstellen, dass das STP immer zuerst die Schleifenfreiheit sicherstellen wird bevor der Rechner Netzkonnektivität bekommt, bei 2 gleichzeitig aktiven Links.

STP abschalten ist die Schlechteste aller Lösungen, und ich würde nicht raten das STP abzuschalten, eher suche nach einer Lösung wie man den 2-ten Link da raus bekommt.

Bin noch am recherchieren... DebugModus soll es geben, habs aber mit Debian noch nciht aktiviert bekommen.

Während dessen hab ich mir mal den dmesg-Outout angeschau während ich ein /etc/init.d/networking restart gemacht habe:

Code: Alles auswählen

[ 6097.356603] br0: port 1(enp9s0) entered disabled state
[ 6097.401498] device enp9s0 left promiscuous mode
[ 6097.401576] br0: port 1(enp9s0) entered disabled state
[ 6097.847935] br0: port 1(enp9s0) entered blocking state
[ 6097.847938] br0: port 1(enp9s0) entered disabled state
[ 6097.848028] device enp9s0 entered promiscuous mode
[ 6097.850353] enp9s0: 0xffffb0214006d000, 18:c0:4d:55:7c:4e, IRQ 41
[ 6097.905369] br0: port 1(enp9s0) entered blocking state
[ 6097.905374] br0: port 1(enp9s0) entered forwarding state
[ 6097.905761] device enp9s0 left promiscuous mode
[ 6097.905833] br0: port 1(enp9s0) entered disabled state
[ 6100.975962] r8168: enp9s0: link up
-----
[ 6159.716546] br0: port 1(enp9s0) entered blocking state
[ 6159.716551] br0: port 1(enp9s0) entered disabled state
[ 6159.716721] device enp9s0 entered promiscuous mode
[ 6159.716843] br0: port 1(enp9s0) entered blocking state
[ 6159.716846] br0: port 1(enp9s0) entered listening state
-----
[ 6174.932632] br0: port 1(enp9s0) entered learning state
[ 6190.039498] br0: port 1(enp9s0) entered forwarding state
[ 6190.039501] br0: topology change detected, propagating

Die ----- symbolisieren (neben der lesbaren Zeitangabe) die Wartepausen.

Die Netzwerkkarte ist es nicht... die ist nach gut 3sek wieder da. Liegt tatsächlich an der Bridge.

Bleibt nur die Frage: Was tut der da so lange?!

Ich schau weiter nach dem Debug-Modus. Lässt mir keine Ruhe. Da hast du einen Top-Modernen Rechner mit 8c/16t, ewig viel RAM und furchtbar schnelle PCIe SSD und dann legt die Netzwerkbrücke alles lahm

[alex0801]

Würde man einen physischen Link raus nehmen, dann würde da booten wesentlich schneller gehen. Die Frage ist benötigt man in diesem Setup wirklich 2 physische Links in einer Broadcastdomäne?

Okay, verstanden. Stehe dennoch auf dem Schlauch... Die Brücke "br0" hat als einzig physikalisches Device enp9s0 als Link drin stehen. D.h. beim booten - während er diesen Test macht, gibt es nur das echte Device enp9s0 das auf meinen Switch im netzwerk gesteckt ist, und... eben die "sonst leere Brücke" br0... er wird beim booten da nix finden können. Sackgasse. Oder sehe ich das falsch?
Alles andere sind dann VMs, welche aber beim Booten gar nicht aktiv sind. Die werden nur bei Bedarf hoch gefahren.

Oder erkennst du noch andere physikalische Links in meinen Logs von denen ich weder etwas sehe noch von ihnen weiß?

Gruß
Alex

[unitra]

Verstanden du hast nur ein einziges Kabel, aber 3 Brücken:

Code: Alles auswählen

root@alexpc:~# nmcli connection show
NAME      UUID                                  TYPE      DEVICE 
br0       d1ac9120-ceb7-4b7c-9ee1-b5c9cb1ae3d6  bridge    br0    
virbr0    512da462-f02a-4fba-b388-e7578edbaa50  bridge    virbr0 
br0slave  915c30f1-95b1-49ab-a933-8a944d73956b  ethernet  --     
LAN       9aa91e40-ec00-4fa2-9265-227f029137a4  ethernet  --     

Eigentlich bräuchte man gar keine Brücke, oder nur genau eine einzige Brücke. Aber nicht 3 Brücken. Das Setup ist zu überladen, deswegen die Verzögerung und deswegen das lange STP. So kann man STP abschalten, nur das ist nicht die Lösung des Problems, sondern die Bekämpfung der Symptome (langes STP) aber die Ursache für das lange Booten ist dass irgendeine Komponte in dem Netzwerkaufbau denkt dass man Schleifenfreiheit sicherstellen muß. Reduziere die Anzahl der Brücken oder eliminiere Alle dann bootet der Rechner wieder schnell.

[alex0801]

Okay es sind zwei Brücken. Aber nur in einer ist eine echte Netzwerkkarte, und das ist br0.

virbr0 ist eine separate Brücke die libvirt mit sich bringt. Die wird AFAIK intern von libvirt gebraucht um ein Netzwerk zwischen den VMs, die nicht auf das physikalische Netzwerk gebrückt sind herzustellen.

virbr0 hat zum Bootzeitpunkt also weder ein physikalisches Netzwerkdevice noch ein virtuelles Netzwerkdevice verknüpft. STP würde hier vollkommen ins leere laufen. Als br0 noch nicht eingerichtet war, hat dies auch keinerlei Bootverzögerung verursacht. Ergo ist nicht virbr0 die Bremse. Die kam erst mit br0.

br0slave ist keine Brücke (siehe Typ Spalte in deinem quote), eher ein missglückten Versuch Gnome zu überreden im Networkmanager Applet ein verbundenes Device anzuzeigen. Auch ohne br0slave war der Bootvorgang langsam.

Lange Rede kurzer Sinn: die Verzögerung kam einzig und alleine von br0. Hab allerdings nicht probiert ob es alleine am deaktivierten STP liegt. Hab ja noch weitere Parameter ergänzt. Siehe oben...
Kann ich bei Gelegenheit ausprobieren.