Slow/delayed NetworkManager reconnect after suspend

English Network/Internet
1

I’m running an up-to-date openSUSE Leap 15.6 with XFCE.
System is great, however, it looks like there are some networking issues after resuming from suspend: the system comes back and greets me with lightdm lock screen.

After unlocking, XFCE desktop is back and you can see that the NetworkManager icon from XFCE’s taskbar is changing to disconnected, then slowly reconnecting.

The interesting part is, that this is not always happening (as if there are different sleep states): sometimes, the network is instantly there, and sometimes it gets lost after resuming.

You guys have any ideas what’s going on here?

Below’s the latest NetworkManager journal entry when this issue happened after resuming (journalctl -Stoday -U21:10 -u NetworkManager.service --no-hostname).

You can see that there’s

  • a DISCONNECTING at 21:09:44,
  • a DISCONNECTED at 21:09:56,
  • and CONNECTING at 21:09:59
Feb 24 21:09:43 NetworkManager[1496]: <info>  [1740427783.8895] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Feb 24 21:09:43 NetworkManager[1496]: <info>  [1740427783.8901] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Feb 24 21:09:44 NetworkManager[1496]: <info>  [1740427784.0798] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Feb 24 21:09:44 NetworkManager[1496]: <info>  [1740427784.0808] manager: NetworkManager state is now DISCONNECTING
Feb 24 21:09:55 NetworkManager[1496]: <info>  [1740427795.7175] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Feb 24 21:09:55 NetworkManager[1496]: <info>  [1740427795.7177] dhcp4 (wlan0): canceled DHCP transaction
Feb 24 21:09:55 NetworkManager[1496]: <info>  [1740427795.7177] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Feb 24 21:09:55 NetworkManager[1496]: <info>  [1740427795.7177] dhcp4 (wlan0): state changed no lease
Feb 24 21:09:55 NetworkManager[1496]: <info>  [1740427795.7482] device (wlan0): set-hw-addr: set MAC address to 52:11:xx:xx:xx:xx (scanning)
Feb 24 21:09:56 NetworkManager[1496]: <info>  [1740427796.1871] manager: NetworkManager state is now DISCONNECTED
Feb 24 21:09:56 NetworkManager[1496]: <info>  [1740427796.1899] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Feb 24 21:09:56 NetworkManager[1496]: <info>  [1740427796.1901] device (wlan0): supplicant interface state: interface_disabled -> disconnected
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5087] policy: auto-activating connection 'wlan0' (764cb5a5-d57d-3431-a116-c743516dceca)
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5100] device (wlan0): Activation: starting connection 'wlan0' (764cb5a5-d57d-3431-a116-c743516dceca)
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5102] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5111] manager: NetworkManager state is now CONNECTING
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5498] device (wlan0): set-hw-addr: reset MAC address to A4:C3:xx:xx:xx:xx (preserve)
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5573] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5582] device (wlan0): Activation: (wifi) access point 'wlan0' has security, but secrets are required.
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5583] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5590] sup-iface[415d14d2e88b4fe9,4,wlan0]: wps: type pbc start...
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5599] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5634] device (wlan0): supplicant interface state: interface_disabled -> disconnected
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5638] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5652] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5661] device (wlan0): Activation: (wifi) connection 'wlan0' has security, and secrets exist.  No new secrets needed.
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5662] Config: added 'ssid' value 'MyWiFiID'
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5662] Config: added 'scan_ssid' value '1'
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5662] Config: added 'bgscan' value 'simple:30:-65:300'
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5663] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5663] Config: added 'psk' value '<hidden>'
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.5705] device (wlan0): supplicant interface state: disconnected -> scanning
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.6273] device (wlan0): supplicant interface state: scanning -> authenticating
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.6348] device (wlan0): supplicant interface state: authenticating -> associating
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.6501] device (wlan0): supplicant interface state: associating -> associated
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.6659] device (wlan0): supplicant interface state: associated -> 4way_handshake
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.7284] device (wlan0): supplicant interface state: 4way_handshake -> completed
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.7285] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "MyWiFiID"
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.8547] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.8552] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.8599] dhcp4 (wlan0): state changed new lease, address=192.168.178.55
Feb 24 21:09:59 NetworkManager[1496]: <info>  [1740427799.8604] policy: set 'wlan0' (wlan0) as default for IPv4 routing and DNS
2

You have two interfaces, eth0 and wlan0.

Is your problem with eth0?

Would be good to post a good log and bad log for the relevant interface so things can be compared.

3

For me, the solution was to delete the files and folders in .cache folder.

Maybe you can also try to reboot your router to clean it caches.

4

Problem is with wlan0, no LAN cable connected.

Today, I am connected to a different wifi network, but showing the same issues.

In both scenarios, I had the laptop’s lid closed to make it suspend, then later opened it to wake it up from sleeping. First time, it went smooth and fast, the second time, there was that strange delay. (No AC adapter used, battery level > 50%.)

Here’s the good (fast) one:

Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.5936] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.5942] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.7854] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.8392] device (wlan0): set-hw-addr: set MAC address to 82:8E:F2:BC:DD:2F (scanning)
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.0657] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.0660] manager: NetworkManager state is now DISCONNECTED
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1123] device (wlan0): supplicant interface state: internal-starting -> disconnected
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1123] device (p2p-dev-wlan0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1129] Wi-Fi P2P device controlled by interface wlan0 created
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1132] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/6)
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1135] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1140] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.1145] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4464] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4481] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4484] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4495] manager: NetworkManager state is now CONNECTING
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4760] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (preserve)
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4834] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4843] device (wlan0): Activation: (wifi) access point 'a45bve1' has security, but secrets are required.
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4844] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4850] sup-iface[09b9aaee785bafe5,2,wlan0]: wps: type pbc start...
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4856] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4857] device (p2p-dev-wlan0): supplicant management interface state: disconnected -> interface_disabled
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4886] device (wlan0): supplicant interface state: interface_disabled -> inactive
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4886] device (p2p-dev-wlan0): supplicant management interface state: interface_disabled -> inactive
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4888] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4900] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4909] device (wlan0): Activation: (wifi) connection 'a45bve1' has security, and secrets exist.  No new secrets needed.
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'ssid' value 'a45bve1'
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'scan_ssid' value '1'
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'bgscan' value 'simple:30:-65:300'
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4911] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4911] Config: added 'auth_alg' value 'OPEN'
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4912] Config: added 'psk' value '<hidden>'
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4986] device (wlan0): supplicant interface state: inactive -> scanning
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.4987] device (p2p-dev-wlan0): supplicant management interface state: inactive -> scanning
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5657] device (wlan0): supplicant interface state: scanning -> authenticating
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5658] device (p2p-dev-wlan0): supplicant management interface state: scanning -> authenticating
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5727] device (wlan0): supplicant interface state: authenticating -> associating
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5728] device (p2p-dev-wlan0): supplicant management interface state: authenticating -> associating
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5847] device (wlan0): supplicant interface state: associating -> associated
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5847] device (p2p-dev-wlan0): supplicant management interface state: associating -> associated
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5922] device (wlan0): supplicant interface state: associated -> 4way_handshake
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.5922] device (p2p-dev-wlan0): supplicant management interface state: associated -> 4way_handshake
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.6723] device (wlan0): supplicant interface state: 4way_handshake -> completed
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.6724] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "a45bve1"
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.6725] device (p2p-dev-wlan0): supplicant management interface state: 4way_handshake -> completed
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.7765] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.7780] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.7871] dhcp4 (wlan0): state changed new lease, address=192.168.179.68
Mar 01 21:37:01 NetworkManager[1458]: <info>  [1740861421.7884] policy: set 'a45bve1' (wlan0) as default for IPv4 routing and DNS
Mar 01 21:37:02 NetworkManager[1458]: <info>  [1740861422.3863] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 NetworkManager[1458]: <info>  [1740861422.6687] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 NetworkManager[1458]: <info>  [1740861422.6692] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 NetworkManager[1458]: <info>  [1740861422.6706] manager: NetworkManager state is now CONNECTED_SITE
Mar 01 21:37:02 NetworkManager[1458]: <info>  [1740861422.6725] device (wlan0): Activation: successful, device activated.
Mar 01 21:37:02 NetworkManager[1458]: <info>  [1740861422.7395] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 01 21:53:29 NetworkManager[1458]: <info>  [1740862409.6987] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Mar 01 21:53:32 NetworkManager[1458]: <info>  [1740862412.9161] device (eth0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 21:53:33 NetworkManager[1458]: <info>  [1740862413.0191] device (p2p-dev-wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 21:53:33 NetworkManager[1458]: <info>  [1740862413.0199] manager: NetworkManager state is now ASLEEP
Mar 01 21:53:33 NetworkManager[1458]: <info>  [1740862413.0222] device (wlan0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 21:53:33 NetworkManager[1458]: <info>  [1740862413.2129] device (wlan0): supplicant interface state: completed -> disconnected

And here’s the bad (slow) one:

Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.6863] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.6870] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.8815] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.8822] manager: NetworkManager state is now DISCONNECTING
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0161] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0170] dhcp4 (wlan0): canceled DHCP transaction
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): state changed no lease
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0346] device (wlan0): set-hw-addr: set MAC address to 26:84:EC:E9:B9:61 (scanning)
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.3619] manager: NetworkManager state is now DISCONNECTED
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.6991] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7005] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7009] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7019] manager: NetworkManager state is now CONNECTING
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7435] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (preserve)
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7516] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7524] device (wlan0): Activation: (wifi) access point 'a45bve1' has security, but secrets are required.
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7525] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7531] sup-iface[09b9aaee785bafe5,2,wlan0]: wps: type pbc start...
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7539] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7580] device (wlan0): supplicant interface state: interface_disabled -> disconnected
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7583] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7592] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7601] device (wlan0): Activation: (wifi) connection 'a45bve1' has security, and secrets exist.  No new secrets needed.
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'ssid' value 'a45bve1'
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'scan_ssid' value '1'
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'bgscan' value 'simple:30:-65:300'
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7603] Config: added 'auth_alg' value 'OPEN'
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7603] Config: added 'psk' value '<hidden>'
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.7658] device (wlan0): supplicant interface state: disconnected -> scanning
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.8248] device (wlan0): supplicant interface state: scanning -> authenticating
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.8322] device (wlan0): supplicant interface state: authenticating -> associating
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.8445] device (wlan0): supplicant interface state: associating -> associated
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.8555] device (wlan0): supplicant interface state: associated -> 4way_handshake
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.9314] device (wlan0): supplicant interface state: 4way_handshake -> completed
Mar 01 22:52:46 NetworkManager[1458]: <info>  [1740865966.9315] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "a45bve1"
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.0347] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.0362] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.0462] dhcp4 (wlan0): state changed new lease, address=192.168.179.68
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.0474] policy: set 'a45bve1' (wlan0) as default for IPv4 routing and DNS
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.5929] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.8058] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.8063] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.8072] manager: NetworkManager state is now CONNECTED_SITE
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.8089] device (wlan0): Activation: successful, device activated.
Mar 01 22:52:47 NetworkManager[1458]: <info>  [1740865967.9117] manager: NetworkManager state is now CONNECTED_GLOBAL
5

It is possible that NetworkManager waits for DHCP. Check the full logs (not artificially restricted) what happens in this interval in good and bad cases. Enable NetworkManager trace level debugging to get more information. Try different DHCP client.

6

Did grep in the logs:

good (fast) bad (slow)
DISCONNECTED 21:36:58 22:52:43
CONNECTED_GLOBAL 21:37:02 22:52:47
Difference 00:00:04 00:00:04

So yes a strange delay that is not captured in your log.

Looking better the problem is not with the reconnect but the start of the log to disconnect. The bad log shows in that extra time

dhcp4 (wlan0): canceled DHCP transaction
dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
dhcp4 (wlan0): state changed no lease

The good log does not have that.

7

I think the issue is before that. In bad, there is a 13s delay before the DISCONNECTED:

Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.6863] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.6870] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.8815] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 NetworkManager[1458]: <info>  [1740865950.8822] manager: NetworkManager state is now DISCONNECTING
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0161] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0170] dhcp4 (wlan0): canceled DHCP transaction
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): state changed no lease
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.0346] device (wlan0): set-hw-addr: set MAC address to 26:84:EC:E9:B9:61 (scanning)
Mar 01 22:52:43 NetworkManager[1458]: <info>  [1740865963.3619] manager: NetworkManager state is now DISCONNECTED

Whereas there’s none in good:

Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.5936] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.5942] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.7854] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 NetworkManager[1458]: <info>  [1740861417.8392] device (wlan0): set-hw-addr: set MAC address to 82:8E:F2:BC:DD:2F (scanning)
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.0657] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 NetworkManager[1458]: <info>  [1740861418.0660] manager: NetworkManager state is now DISCONNECTED

Edit: Ah, now I see that this is what you already described…

8

Well, I hope it’s ok to include longer logs here in this forum…

The bad one shows quite some stuff going on: rtkit-daemon, ModemManager, avahi. However, rtkit’s activities focus on Firefox and pulse/pipewire stuff (checked the pids) and are not existing in other bad/slow cases. ModemManager messages occur also in good cases, and I think avahi is just reacting to the network issues. I also decided to disable IPv6 to rule out issues with it.

Anyways, here’s the bad one showing that 13 second gap until NetworkManager does its job:

Mar 01 22:52:30 calumma systemd-logind[1424]: Lid opened.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: The canary thread is apparently starving. Taking action.
Mar 01 22:52:30 calumma systemd[1]: Started Timeline of Snapper Snapshots.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Demoting known real-time threads.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2656 of process 2115.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2455 of process 2455.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2477 of process 2454.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2454 of process 2454.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2072 of process 2053.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2070 of process 2053.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Successfully demoted thread 2053 of process 2053.
Mar 01 22:52:30 calumma rtkit-daemon[2057]: Demoted 7 threads.
Mar 01 22:52:30 calumma systemd-sleep[6407]: System returned from sleep state.
Mar 01 22:52:30 calumma kernel: PM: suspend exit
Mar 01 22:52:30 calumma dbus-daemon[1173]: [system] Activating via systemd: service name='org.opensuse.Snapper' unit='snapperd.service' requested by ':1.144' (uid=0 pid=6450 comm="/usr/lib/snapper/systemd-helper --timeline ")
Mar 01 22:52:30 calumma systemd-sleep[6469]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 22:52:30 calumma systemd[1]: Starting DBus interface for snapper...
Mar 01 22:52:30 calumma systemd[1]: systemd-suspend.service: Deactivated successfully.
Mar 01 22:52:30 calumma dbus-daemon[1173]: [system] Successfully activated service 'org.opensuse.Snapper'
Mar 01 22:52:30 calumma systemd[1]: Finished System Suspend.
Mar 01 22:52:30 calumma systemd[1]: snapper-timeline.service: Deactivated successfully.
Mar 01 22:52:30 calumma systemd[1]: Started DBus interface for snapper.
Mar 01 22:52:30 calumma systemd[1]: Stopped target Sleep.
Mar 01 22:52:30 calumma systemd[1]: Reached target Suspend.
Mar 01 22:52:30 calumma systemd[1]: Stopped target Suspend.
Mar 01 22:52:30 calumma systemd-logind[1424]: Operation 'sleep' finished.
Mar 01 22:52:30 calumma ModemManager[1421]: <info>  [sleep-monitor-systemd] system is resuming
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.6863] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.6870] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.8815] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.8822] manager: NetworkManager state is now DISCONNECTING
Mar 01 22:52:32 calumma systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 01 22:52:33 calumma ModemManager[1421]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1c.2/0000:3b:00.0': not supported by any plugin
Mar 01 22:52:33 calumma ModemManager[1421]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1f.6': not supported by any plugin
Mar 01 22:52:36 calumma xfce4-screensaver-dialog[6477]: gkr-pam: unlocked login keyring
Mar 01 22:52:36 calumma xfce4-screensaver-dialog[6477]: pam_warn(xfce4-screensaver:account): function=[pam_sm_acct_mgmt] flags=0 service=[xfce4-screensaver] terminal=[:0.0] user=[chris] ruser=[<unknown>] rhost=[<unknown>]
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0161] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 22:52:43 calumma avahi-daemon[1171]: Withdrawing address record for fe80::745b:e4cd:caa6:4c4e on wlan0.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::745b:e4cd:caa6:4c4e.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Interface wlan0.IPv6 no longer relevant for mDNS.
Mar 01 22:52:43 calumma dbus-daemon[1173]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.8' (uid=0 pid=1458 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0170] dhcp4 (wlan0): canceled DHCP transaction
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): state changed no lease
Mar 01 22:52:43 calumma avahi-daemon[1171]: Interface wlan0.IPv4 no longer relevant for mDNS.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.179.68.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Withdrawing address record for 192.168.179.68 on wlan0.
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0346] device (wlan0): set-hw-addr: set MAC address to 26:84:EC:E9:B9:61 (scanning)
Mar 01 22:52:43 calumma avahi-daemon[1171]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.179.68.
Mar 01 22:52:43 calumma avahi-daemon[1171]: New relevant interface wlan0.IPv4 for mDNS.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Registering new address record for 192.168.179.68 on wlan0.IPv4.
Mar 01 22:52:43 calumma systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 01 22:52:43 calumma dbus-daemon[1173]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 01 22:52:43 calumma systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 01 22:52:43 calumma dns-dnsmasq.sh[6573]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:43 calumma avahi-daemon[1171]: Files changed, reloading.
Mar 01 22:52:43 calumma avahi-daemon[1171]: No service file found in /etc/avahi/services.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Withdrawing address record for 192.168.179.68 on wlan0.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.179.68.
Mar 01 22:52:43 calumma avahi-daemon[1171]: Interface wlan0.IPv4 no longer relevant for mDNS.
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.3619] manager: NetworkManager state is now DISCONNECTED
Mar 01 22:52:43 calumma chronyd[1581]: Forward time jump detected!
Mar 01 22:52:43 calumma chronyd[1581]: Can't synchronise: no selectable sources
Mar 01 22:52:43 calumma chronyd[1581]: Source 192.168.178.1 offline
Mar 01 22:52:43 calumma chronyd[1581]: Source 80.153.195.191 offline
Mar 01 22:52:43 calumma dns-dnsmasq.sh[6659]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:43 calumma dns-dnsmasq.sh[6677]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.6991] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7005] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7009] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7019] manager: NetworkManager state is now CONNECTING
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7435] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (preserve)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7516] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7524] device (wlan0): Activation: (wifi) access point 'a45bve1' has security, but secrets are required.
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7525] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7531] sup-iface[09b9aaee785bafe5,2,wlan0]: wps: type pbc start...
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7539] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7580] device (wlan0): supplicant interface state: interface_disabled -> disconnected
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7583] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7592] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7601] device (wlan0): Activation: (wifi) connection 'a45bve1' has security, and secrets exist.  No new secrets needed.
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'ssid' value 'a45bve1'
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'scan_ssid' value '1'
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'bgscan' value 'simple:30:-65:300'
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7602] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7603] Config: added 'auth_alg' value 'OPEN'
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7603] Config: added 'psk' value '<hidden>'
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7658] device (wlan0): supplicant interface state: disconnected -> scanning
Mar 01 22:52:46 calumma kernel: wlan0: authenticate with 1c:ed:6f:13:f3:58 (local address=a4:c3:f0:96:99:43)
Mar 01 22:52:46 calumma kernel: wlan0: send auth to 1c:ed:6f:13:f3:58 (try 1/3)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.8248] device (wlan0): supplicant interface state: scanning -> authenticating
Mar 01 22:52:46 calumma kernel: wlan0: authenticated
Mar 01 22:52:46 calumma kernel: wlan0: associate with 1c:ed:6f:13:f3:58 (try 1/3)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.8322] device (wlan0): supplicant interface state: authenticating -> associating
Mar 01 22:52:46 calumma kernel: wlan0: RX AssocResp from 1c:ed:6f:13:f3:58 (capab=0x1511 status=0 aid=2)
Mar 01 22:52:46 calumma kernel: wlan0: associated
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.8445] device (wlan0): supplicant interface state: associating -> associated
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.8555] device (wlan0): supplicant interface state: associated -> 4way_handshake
Mar 01 22:52:46 calumma kernel: wlan0: Limiting TX power to 27 (30 - 3) dBm as advertised by 1c:ed:6f:13:f3:58
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.9314] device (wlan0): supplicant interface state: 4way_handshake -> completed
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.9315] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "a45bve1"
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.0347] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.0362] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 22:52:47 calumma avahi-daemon[1171]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::745b:e4cd:caa6:4c4e.
Mar 01 22:52:47 calumma avahi-daemon[1171]: New relevant interface wlan0.IPv6 for mDNS.
Mar 01 22:52:47 calumma avahi-daemon[1171]: Registering new address record for fe80::745b:e4cd:caa6:4c4e on wlan0.*.
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.0462] dhcp4 (wlan0): state changed new lease, address=192.168.179.68
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.0474] policy: set 'a45bve1' (wlan0) as default for IPv4 routing and DNS
Mar 01 22:52:47 calumma avahi-daemon[1171]: Files changed, reloading.
Mar 01 22:52:47 calumma avahi-daemon[1171]: No service file found in /etc/avahi/services.
Mar 01 22:52:47 calumma avahi-daemon[1171]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.179.68.
Mar 01 22:52:47 calumma avahi-daemon[1171]: New relevant interface wlan0.IPv4 for mDNS.
Mar 01 22:52:47 calumma avahi-daemon[1171]: Registering new address record for 192.168.179.68 on wlan0.IPv4.
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.5929] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 calumma dns-dnsmasq.sh[6784]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.8058] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.8063] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.8072] manager: NetworkManager state is now CONNECTED_SITE
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.8089] device (wlan0): Activation: successful, device activated.
Mar 01 22:52:47 calumma chronyd[1581]: Source 192.168.178.1 online
Mar 01 22:52:47 calumma chronyd[1581]: Source 80.153.195.191 online
Mar 01 22:52:47 calumma NetworkManager[1458]: <info>  [1740865967.9117] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 01 22:52:48 calumma dns-dnsmasq.sh[6817]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:48 calumma dns-dnsmasq.sh[6835]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:48 calumma dns-dnsmasq.sh[6851]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 22:52:52 calumma chronyd[1581]: Selected source 80.153.195.191 (0.opensuse.pool.ntp.org)
Mar 01 22:52:58 calumma systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 01 22:52:58 calumma systemd[1]: NetworkManager-dispatcher.service: Consumed 1.229s CPU time.
Mar 01 22:53:10 calumma sudo[6924]:    chris : TTY=pts/0 ; PWD=/home/chris ; USER=root ; COMMAND=/usr/bin/bash
Mar 01 22:53:10 calumma sudo[6924]: pam_unix(sudo:session): session opened for user root by (uid=1000)
Mar 01 22:53:30 calumma systemd[1]: snapperd.service: Deactivated successfully.

Versus the good one:

Mar 01 21:36:57 calumma systemd-logind[1424]: Lid opened.
Mar 01 21:36:57 calumma systemd-sleep[4274]: System returned from sleep state.
Mar 01 21:36:57 calumma kernel: PM: suspend exit
Mar 01 21:36:57 calumma systemd[1]: Started Timeline of Snapper Snapshots.
Mar 01 21:36:57 calumma dbus-daemon[1173]: [system] Activating via systemd: service name='org.opensuse.Snapper' unit='snapperd.service' requested by ':1.97' (uid=0 pid=4327 comm="/usr/lib/snapper/systemd-helper --timeline ")
Mar 01 21:36:57 calumma systemd-sleep[4335]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 21:36:57 calumma systemd[1]: systemd-suspend.service: Deactivated successfully.
Mar 01 21:36:57 calumma systemd[1]: Finished System Suspend.
Mar 01 21:36:57 calumma systemd[1]: Stopped target Sleep.
Mar 01 21:36:57 calumma systemd[1]: Reached target Suspend.
Mar 01 21:36:57 calumma systemd-logind[1424]: Operation 'sleep' finished.
Mar 01 21:36:57 calumma ModemManager[1421]: <info>  [sleep-monitor-systemd] system is resuming
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.5936] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.5942] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 calumma systemd[1]: Starting DBus interface for snapper...
Mar 01 21:36:57 calumma systemd[1]: Stopped target Suspend.
Mar 01 21:36:57 calumma dbus-daemon[1173]: [system] Successfully activated service 'org.opensuse.Snapper'
Mar 01 21:36:57 calumma systemd[1]: Started DBus interface for snapper.
Mar 01 21:36:57 calumma systemd[1]: snapper-timeline.service: Deactivated successfully.
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.7854] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.8392] device (wlan0): set-hw-addr: set MAC address to 82:8E:F2:BC:DD:2F (scanning)
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.0657] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.0660] manager: NetworkManager state is now DISCONNECTED
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1123] device (wlan0): supplicant interface state: internal-starting -> disconnected
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1123] device (p2p-dev-wlan0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1129] Wi-Fi P2P device controlled by interface wlan0 created
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1132] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/6)
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1135] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1140] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1145] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:00 calumma ModemManager[1421]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1c.2/0000:3b:00.0': not supported by any plugin
Mar 01 21:37:00 calumma ModemManager[1421]: <info>  [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1f.6': not supported by any plugin
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4464] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4481] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4484] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4495] manager: NetworkManager state is now CONNECTING
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4760] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (preserve)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4834] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4843] device (wlan0): Activation: (wifi) access point 'a45bve1' has security, but secrets are required.
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4844] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4850] sup-iface[09b9aaee785bafe5,2,wlan0]: wps: type pbc start...
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4856] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4857] device (p2p-dev-wlan0): supplicant management interface state: disconnected -> interface_disabled
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4886] device (wlan0): supplicant interface state: interface_disabled -> inactive
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4886] device (p2p-dev-wlan0): supplicant management interface state: interface_disabled -> inactive
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4888] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4900] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4909] device (wlan0): Activation: (wifi) connection 'a45bve1' has security, and secrets exist.  No new secrets needed.
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'ssid' value 'a45bve1'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'scan_ssid' value '1'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'bgscan' value 'simple:30:-65:300'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4911] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4911] Config: added 'auth_alg' value 'OPEN'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4912] Config: added 'psk' value '<hidden>'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4986] device (wlan0): supplicant interface state: inactive -> scanning
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4987] device (p2p-dev-wlan0): supplicant management interface state: inactive -> scanning
Mar 01 21:37:01 calumma kernel: wlan0: authenticate with 1c:ed:6f:13:f3:58 (local address=a4:c3:f0:96:99:43)
Mar 01 21:37:01 calumma kernel: wlan0: send auth to 1c:ed:6f:13:f3:58 (try 1/3)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5657] device (wlan0): supplicant interface state: scanning -> authenticating
Mar 01 21:37:01 calumma systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5658] device (p2p-dev-wlan0): supplicant management interface state: scanning -> authenticating
Mar 01 21:37:01 calumma kernel: wlan0: authenticated
Mar 01 21:37:01 calumma kernel: wlan0: associate with 1c:ed:6f:13:f3:58 (try 1/3)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5727] device (wlan0): supplicant interface state: authenticating -> associating
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5728] device (p2p-dev-wlan0): supplicant management interface state: authenticating -> associating
Mar 01 21:37:01 calumma kernel: wlan0: RX AssocResp from 1c:ed:6f:13:f3:58 (capab=0x1511 status=0 aid=2)
Mar 01 21:37:01 calumma kernel: wlan0: associated
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5847] device (wlan0): supplicant interface state: associating -> associated
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5847] device (p2p-dev-wlan0): supplicant management interface state: associating -> associated
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5922] device (wlan0): supplicant interface state: associated -> 4way_handshake
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5922] device (p2p-dev-wlan0): supplicant management interface state: associated -> 4way_handshake
Mar 01 21:37:01 calumma kernel: wlan0: Limiting TX power to 27 (30 - 3) dBm as advertised by 1c:ed:6f:13:f3:58
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.6723] device (wlan0): supplicant interface state: 4way_handshake -> completed
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.6724] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "a45bve1"
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.6725] device (p2p-dev-wlan0): supplicant management interface state: 4way_handshake -> completed
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7765] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7780] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 21:37:01 calumma avahi-daemon[1171]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::745b:e4cd:caa6:4c4e.
Mar 01 21:37:01 calumma avahi-daemon[1171]: New relevant interface wlan0.IPv6 for mDNS.
Mar 01 21:37:01 calumma avahi-daemon[1171]: Registering new address record for fe80::745b:e4cd:caa6:4c4e on wlan0.*.
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7871] dhcp4 (wlan0): state changed new lease, address=192.168.179.68
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7884] policy: set 'a45bve1' (wlan0) as default for IPv4 routing and DNS
Mar 01 21:37:01 calumma avahi-daemon[1171]: Files changed, reloading.
Mar 01 21:37:01 calumma avahi-daemon[1171]: No service file found in /etc/avahi/services.
Mar 01 21:37:02 calumma avahi-daemon[1171]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.179.68.
Mar 01 21:37:02 calumma avahi-daemon[1171]: New relevant interface wlan0.IPv4 for mDNS.
Mar 01 21:37:02 calumma avahi-daemon[1171]: Registering new address record for 192.168.179.68 on wlan0.IPv4.
Mar 01 21:37:02 calumma dbus-daemon[1173]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.8' (uid=0 pid=1458 comm="/usr/sbin/NetworkManager --no-daemon ")
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.3863] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 calumma systemd[1]: Starting Network Manager Script Dispatcher Service...
Mar 01 21:37:02 calumma dbus-daemon[1173]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mar 01 21:37:02 calumma systemd[1]: Started Network Manager Script Dispatcher Service.
Mar 01 21:37:02 calumma dns-dnsmasq.sh[4501]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6687] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6692] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6706] manager: NetworkManager state is now CONNECTED_SITE
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6725] device (wlan0): Activation: successful, device activated.
Mar 01 21:37:02 calumma chronyd[1581]: Forward time jump detected!
Mar 01 21:37:02 calumma chronyd[1581]: Source 192.168.178.1 online
Mar 01 21:37:02 calumma chronyd[1581]: Source 80.153.195.191 online
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.7395] manager: NetworkManager state is now CONNECTED_GLOBAL
Mar 01 21:37:02 calumma dns-dnsmasq.sh[4534]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 21:37:03 calumma dns-dnsmasq.sh[4551]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 21:37:03 calumma dns-dnsmasq.sh[4567]: <debug> NETWORKMANAGER_DNS_FORWARDER is not set to "dnsmasq" in /etc/sysconfig/network/config -> exit
Mar 01 21:37:07 calumma chronyd[1581]: Selected source 80.153.195.191 (0.opensuse.pool.ntp.org)
Mar 01 21:37:07 calumma chronyd[1581]: System clock wrong by 2.047931 seconds
Mar 01 21:37:07 calumma xfce4-screensaver-dialog[4343]: gkr-pam: unlocked login keyring
Mar 01 21:37:07 calumma xfce4-screensaver-dialog[4343]: pam_warn(xfce4-screensaver:account): function=[pam_sm_acct_mgmt] flags=0 service=[xfce4-screensaver] terminal=[:0.0] user=[chris] ruser=[<unknown>] rhost=[<unknown>]
Mar 01 21:37:13 calumma systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully.
Mar 01 21:37:57 calumma systemd[1]: snapperd.service: Deactivated successfully.
Mar 01 21:37:58 calumma systemd[1925]: Created slice User Background Tasks Slice.
Mar 01 21:37:58 calumma systemd[1925]: Starting Cleanup of User's Temporary Files and Directories...
Mar 01 21:37:58 calumma systemd[1925]: Finished Cleanup of User's Temporary Files and Directories.

For me, the only difference I see is related to NetworkManager’s message NetworkManager state is now DISCONNECTING which only happens in the bad case (an argument to enable trace level debugging)

(I was also checking DHCP lease time, but that one is until March 12; also thought that maybe it’s an issue with the order of things happening, but I have no clue here, either)

But maybe your eyes are better than mine and will spot the issue already from these logs.

Otherwise, my next step will be enabling trace level debugging, before changing the DHCP client.

Thank you so much for supporting me here!

9

You still omit previous logs from the point where sleep has been requested. Which gives incomplete picture.

Educated guess is that NetworkManager is processing sleep request asynchronously and sometimes it competes deactivation of the current connection before system enters sleep and sometimes it continues after resume.

I bet you will the same state change: deactivating -> disconnected (reason 'sleeping' as well as DHCP messages before sleep in the good case. You probably won’t see DEACTIVATING, but you should see ASLEEP.

10

Ah, now I got it. I thought you wanted to see not only NetworkManager after suspend, but the whole other log entries.

As the forum limits the number of characters, I now did the following:

journalctl -S '2025-03-01 19:00' -U '2025-03-01 23:55' -u NetworkManager -u systemd-suspend.service

This way, we will see the session before the good one, then the good one, followed by the bad one.

journalctl -S '2025-03-01 19:00' -U '2025-03-01 23:55' -u NetworkManager -u systemd-suspend.service

Session before going into good sleep

Mar 01 19:03:11 calumma systemd-sleep[3765]: System returned from sleep state.
Mar 01 19:03:11 calumma systemd-sleep[3854]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 19:03:11 calumma systemd[1]: systemd-suspend.service: Deactivated successfully.
Mar 01 19:03:11 calumma systemd[1]: Finished System Suspend.
Mar 01 19:03:11 calumma NetworkManager[1458]: <info>  [1740852191.6842] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 19:03:11 calumma NetworkManager[1458]: <info>  [1740852191.6848] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 19:03:11 calumma NetworkManager[1458]: <info>  [1740852191.8830] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 19:03:11 calumma NetworkManager[1458]: <info>  [1740852191.9404] device (wlan0): set-hw-addr: set MAC address to 1E:9A:F6:6B:2D:71 (scanning)
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.1770] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.1778] manager: NetworkManager state is now DISCONNECTED
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2294] device (wlan0): supplicant interface state: internal-starting -> disconnected
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2295] device (p2p-dev-wlan0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2309] Wi-Fi P2P device controlled by interface wlan0 created
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2317] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/5)
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2327] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2341] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Mar 01 19:03:12 calumma NetworkManager[1458]: <info>  [1740852192.2357] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6080] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6098] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6101] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6111] manager: NetworkManager state is now CONNECTING
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6847] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (preserve)
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6924] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6934] device (wlan0): Activation: (wifi) access point 'a45bve1' has security, but secrets are required.
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6935] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6941] sup-iface[88e061e2038cf5a3,1,wlan0]: wps: type pbc start...
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6950] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6951] device (p2p-dev-wlan0): supplicant management interface state: disconnected -> interface_disabled
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6988] device (wlan0): supplicant interface state: interface_disabled -> inactive
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6989] device (p2p-dev-wlan0): supplicant management interface state: interface_disabled -> inactive
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.6991] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7004] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7014] device (wlan0): Activation: (wifi) connection 'a45bve1' has security, and secrets exist.  No new secrets needed.
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7015] Config: added 'ssid' value 'a45bve1'
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7015] Config: added 'scan_ssid' value '1'
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7016] Config: added 'bgscan' value 'simple:30:-70:86400'
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7016] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7017] Config: added 'auth_alg' value 'OPEN'
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7017] Config: added 'psk' value '<hidden>'
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7086] device (wlan0): supplicant interface state: inactive -> scanning
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7086] device (p2p-dev-wlan0): supplicant management interface state: inactive -> scanning
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7755] device (wlan0): supplicant interface state: scanning -> authenticating
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7757] device (p2p-dev-wlan0): supplicant management interface state: scanning -> authenticating
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7829] device (wlan0): supplicant interface state: authenticating -> associating
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7829] device (p2p-dev-wlan0): supplicant management interface state: authenticating -> associating
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7968] device (wlan0): supplicant interface state: associating -> associated
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.7969] device (p2p-dev-wlan0): supplicant management interface state: associating -> associated
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.8046] device (wlan0): supplicant interface state: associated -> 4way_handshake
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.8048] device (p2p-dev-wlan0): supplicant management interface state: associated -> 4way_handshake
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.8844] device (wlan0): supplicant interface state: 4way_handshake -> completed
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.8845] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "a45bve1"
Mar 01 19:03:15 calumma NetworkManager[1458]: <info>  [1740852195.8846] device (p2p-dev-wlan0): supplicant management interface state: 4way_handshake -> completed
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.0321] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.0330] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.0444] dhcp4 (wlan0): state changed new lease, address=192.168.179.68
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.0449] policy: set 'a45bve1' (wlan0) as default for IPv4 routing and DNS
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.5082] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.7931] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.7937] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.7950] manager: NetworkManager state is now CONNECTED_SITE
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.7969] device (wlan0): Activation: successful, device activated.
Mar 01 19:03:16 calumma NetworkManager[1458]: <info>  [1740852196.8901] manager: NetworkManager state is now CONNECTED_GLOBAL

Here comes the sleep request, followed by the ASLEEP and state change: deactivating -> disconnected (reason 'sleeping'

Mar 01 19:04:24 calumma NetworkManager[1458]: <info>  [1740852264.8701] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.1061] device (eth0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.2089] device (p2p-dev-wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.2097] manager: NetworkManager state is now ASLEEP
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.2101] device (wlan0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.3959] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.3981] dhcp4 (wlan0): canceled DHCP transaction
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.3982] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.3982] dhcp4 (wlan0): state changed no lease
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.4262] device (wlan0): set-hw-addr: set MAC address to 9A:CD:AD:04:A9:08 (scanning)
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.9387] device (wlan0): supplicant interface state: completed -> disconnected
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.9391] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.9392] device (wlan0): supplicant interface state: interface_disabled -> disconnected
Mar 01 19:04:28 calumma NetworkManager[1458]: <info>  [1740852268.9408] device (wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 19:04:29 calumma NetworkManager[1458]: <info>  [1740852269.1853] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (unmanage)
Mar 01 19:04:29 calumma systemd[1]: Starting System Suspend...
Mar 01 19:04:29 calumma systemd-sleep[4280]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 19:04:29 calumma systemd-sleep[4274]: Entering sleep state 'suspend'...

Returning from sleep w/o issues

Mar 01 21:36:57 calumma systemd-sleep[4274]: System returned from sleep state.
Mar 01 21:36:57 calumma systemd-sleep[4335]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 21:36:57 calumma systemd[1]: systemd-suspend.service: Deactivated successfully.
Mar 01 21:36:57 calumma systemd[1]: Finished System Suspend.
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.5936] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.5942] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.7854] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:57 calumma NetworkManager[1458]: <info>  [1740861417.8392] device (wlan0): set-hw-addr: set MAC address to 82:8E:F2:BC:DD:2F (scanning)
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.0657] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.0660] manager: NetworkManager state is now DISCONNECTED
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1123] device (wlan0): supplicant interface state: internal-starting -> disconnected
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1123] device (p2p-dev-wlan0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1129] Wi-Fi P2P device controlled by interface wlan0 created
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1132] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/6)
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1135] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1140] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-state: 'managed')
Mar 01 21:36:58 calumma NetworkManager[1458]: <info>  [1740861418.1145] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4464] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4481] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4484] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4495] manager: NetworkManager state is now CONNECTING
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4760] device (wlan0): set-hw-addr: reset MAC address to A4:C3:F0:96:99:43 (preserve)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4834] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4843] device (wlan0): Activation: (wifi) access point 'a45bve1' has security, but secrets are required.
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4844] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4850] sup-iface[09b9aaee785bafe5,2,wlan0]: wps: type pbc start...
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4856] device (wlan0): supplicant interface state: disconnected -> interface_disabled
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4857] device (p2p-dev-wlan0): supplicant management interface state: disconnected -> interface_disabled
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4886] device (wlan0): supplicant interface state: interface_disabled -> inactive
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4886] device (p2p-dev-wlan0): supplicant management interface state: interface_disabled -> inactive
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4888] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4900] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4909] device (wlan0): Activation: (wifi) connection 'a45bve1' has security, and secrets exist.  No new secrets needed.
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'ssid' value 'a45bve1'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'scan_ssid' value '1'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4910] Config: added 'bgscan' value 'simple:30:-65:300'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4911] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK SAE FT-SAE'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4911] Config: added 'auth_alg' value 'OPEN'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4912] Config: added 'psk' value '<hidden>'
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4986] device (wlan0): supplicant interface state: inactive -> scanning
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.4987] device (p2p-dev-wlan0): supplicant management interface state: inactive -> scanning
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5657] device (wlan0): supplicant interface state: scanning -> authenticating
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5658] device (p2p-dev-wlan0): supplicant management interface state: scanning -> authenticating
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5727] device (wlan0): supplicant interface state: authenticating -> associating
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5728] device (p2p-dev-wlan0): supplicant management interface state: authenticating -> associating
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5847] device (wlan0): supplicant interface state: associating -> associated
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5847] device (p2p-dev-wlan0): supplicant management interface state: associating -> associated
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5922] device (wlan0): supplicant interface state: associated -> 4way_handshake
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.5922] device (p2p-dev-wlan0): supplicant management interface state: associated -> 4way_handshake
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.6723] device (wlan0): supplicant interface state: 4way_handshake -> completed
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.6724] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "a45bve1"
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.6725] device (p2p-dev-wlan0): supplicant management interface state: 4way_handshake -> completed
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7765] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7780] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7871] dhcp4 (wlan0): state changed new lease, address=192.168.179.68
Mar 01 21:37:01 calumma NetworkManager[1458]: <info>  [1740861421.7884] policy: set 'a45bve1' (wlan0) as default for IPv4 routing and DNS
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.3863] device (wlan0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6687] device (wlan0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6692] device (wlan0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6706] manager: NetworkManager state is now CONNECTED_SITE
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.6725] device (wlan0): Activation: successful, device activated.
Mar 01 21:37:02 calumma NetworkManager[1458]: <info>  [1740861422.7395] manager: NetworkManager state is now CONNECTED_GLOBAL

Here comes the next sleep request (contains also an ASLEEP, but no state change: deactivating -> disconnected (reason 'sleeping')

Mar 01 21:53:29 calumma NetworkManager[1458]: <info>  [1740862409.6987] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Mar 01 21:53:32 calumma NetworkManager[1458]: <info>  [1740862412.9161] device (eth0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 21:53:33 calumma NetworkManager[1458]: <info>  [1740862413.0191] device (p2p-dev-wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 21:53:33 calumma NetworkManager[1458]: <info>  [1740862413.0199] manager: NetworkManager state is now ASLEEP
Mar 01 21:53:33 calumma NetworkManager[1458]: <info>  [1740862413.0222] device (wlan0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 21:53:33 calumma NetworkManager[1458]: <info>  [1740862413.2129] device (wlan0): supplicant interface state: completed -> disconnected
Mar 01 21:53:34 calumma systemd[1]: Starting System Suspend...
Mar 01 21:53:34 calumma systemd-sleep[6409]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 21:53:34 calumma systemd-sleep[6407]: Entering sleep state 'suspend'...

Now, here comes the slow wakeup:

Mar 01 22:52:30 calumma systemd-sleep[6407]: System returned from sleep state.
Mar 01 22:52:30 calumma systemd-sleep[6469]: INFO: Skip running /usr/lib/systemd/system-sleep/grub2.sleep for suspend
Mar 01 22:52:30 calumma systemd[1]: systemd-suspend.service: Deactivated successfully.
Mar 01 22:52:30 calumma systemd[1]: Finished System Suspend.
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.6863] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.6870] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.8815] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mar 01 22:52:30 calumma NetworkManager[1458]: <info>  [1740865950.8822] manager: NetworkManager state is now DISCONNECTING

And here the ones that should have been in the suspend before, right?

Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0161] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0170] dhcp4 (wlan0): canceled DHCP transaction
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0171] dhcp4 (wlan0): state changed no lease

From here it continues normally

Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.0346] device (wlan0): set-hw-addr: set MAC address to 26:84:EC:E9:B9:61 (scanning)
Mar 01 22:52:43 calumma NetworkManager[1458]: <info>  [1740865963.3619] manager: NetworkManager state is now DISCONNECTED
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.6991] policy: auto-activating connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7005] device (wlan0): Activation: starting connection 'a45bve1' (0114be61-951f-4f11-81ff-24b46bd4f1cf)
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7009] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Mar 01 22:52:46 calumma NetworkManager[1458]: <info>  [1740865966.7019] manager: NetworkManager state is now CONNECTING
...

So it’s as it is? There is nothing I can change?

11

You are probably not aware of:

https://paste.opensuse.org/

… to upload lengthy logs and such

1 Like
12

You can try increasing InhibitDelayMaxSec= in logind.conf. See man logind.conf and man systemd-inhibit.

1 Like
13

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.