You are not logged in.

#1 2022-06-06 14:29:26

deltaQuadrantTux
Member
Registered: 2021-12-15
Posts: 9

Shutting down computer can sometimes take nearly two minutes.

I am trying to identify whatever is causing my computer to be delayed when I shut it down. Presently, one of two event-cycles occur when I shutdown: it either shuts down as expected, or it hangs for a minute or so before finishing up. I have not figured out what causes this behavior, so I am posting here to receive help in debugging. I am posting a `journalctl` log from a shutdown where this delay occurs. Because of file/text limitations, I am going to post a snippet where the time gap occurs and not the full thing. Similarly, I have another log from a normal/functioning shutdown - if that may be helpful please let me know and I will share it.

You can notice a time gap of a little over a minute occur on the line regarding WPA_SUPPLICANT - I have prefixed these lines with four asterisks (****) to help with identifying them. Near this are notices about `SDDM` processes. Previously, I had issues where SDDM caused a delayed shutdown. In this case I am not certain that is the problem given that it occurs after the delayed timestamp.

Delayed Shutdown journalctl log (Partial):

Jun 05 21:41:16 theBeard systemd[1]: Unmounted /run/user/1000.
Jun 05 21:41:16 theBeard audit: BPF prog-id=0 op=UNLOAD
Jun 05 21:41:16 theBeard systemd-logind[931]: Received VT_PROCESS signal without a registered session, restoring VT.
Jun 05 21:41:16 theBeard systemd-logind[931]: Failed to release VT, ignoring: Invalid argument
Jun 05 21:41:16 theBeard systemd[1]: user-runtime-dir@1000.service: Deactivated successfully.
Jun 05 21:41:16 theBeard systemd[1]: Stopped User Runtime Directory /run/user/1000.
Jun 05 21:41:16 theBeard audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user-runtime-dir@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 21:41:16 theBeard systemd[1]: Removed slice User Slice of UID 1000.
Jun 05 21:41:16 theBeard systemd[1]: user-1000.slice: Consumed 35.669s CPU time.
****Jun 05 21:41:27 theBeard wpa_supplicant[1029]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-60 noise=9999 txrate=866700
****Jun 05 21:42:16 theBeard wpa_supplicant[1029]: wlo1: CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-51 noise=9999 txrate=866700
Jun 05 21:42:45 theBeard systemd[1]: sddm.service: State 'stop-sigterm' timed out. Killing.
Jun 05 21:42:45 theBeard systemd[1]: sddm.service: Killing process 971 (sddm) with signal SIGKILL.
Jun 05 21:42:45 theBeard systemd[1]: sddm.service: Killing process 2565 (Xorg) with signal SIGKILL.
Jun 05 21:42:45 theBeard systemd[1]: sddm.service: Killing process 990 (n/a) with signal SIGKILL.
Jun 05 21:42:45 theBeard systemd[1]: sddm.service: Main process exited, code=killed, status=9/KILL
Jun 05 21:42:45 theBeard systemd[1]: sddm.service: Failed with result 'timeout'.
Jun 05 21:42:45 theBeard systemd[1]: Stopped Simple Desktop Display Manager.
Jun 05 21:42:45 theBeard audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=sddm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Jun 05 21:42:45 theBeard systemd[1]: Stopping User Login Management...
Jun 05 21:42:45 theBeard kernel: kauditd_printk_skb: 7 callbacks suppressed
Jun 05 21:42:45 theBeard kernel: audit: type=1131 audit(1654479765.637:117): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=sddm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Jun 05 21:42:45 theBeard systemd[1]: Stopping Permit User Sessions...
Jun 05 21:42:45 theBeard systemd[1]: systemd-user-sessions.service: Deactivated successfully.
Jun 05 21:42:45 theBeard systemd[1]: Stopped Permit User Sessions.
Jun 05 21:42:45 theBeard audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-user-sessions comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 21:42:45 theBeard systemd[1]: Stopped target Network.
Jun 05 21:42:45 theBeard systemd[1]: Stopped target Remote File Systems.
Jun 05 21:42:45 theBeard systemd[1]: Stopping Network Manager...
Jun 05 21:42:45 theBeard systemd[1]: Stopping WPA supplicant...
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.6451] caught SIGTERM, shutting down normally.
Jun 05 21:42:45 theBeard kernel: audit: type=1131 audit(1654479765.640:118): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-user-sessions comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 21:42:45 theBeard wpa_supplicant[1029]: p2p-dev-wlo1: CTRL-EVENT-DSCP-POLICY clear_all
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.6460] device (wlo1): state change: activated -> deactivating (reason 'unmanaged', sys-iface-state: 'managed')
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.6462] manager: NetworkManager state is now DISCONNECTING
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.5' (uid=0 pid=949 comm="/usr/bin/NetworkManager --no-daemon")
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Jun 05 21:42:45 theBeard NetworkManager[949]: <warn>  [1654479765.6472] dispatcher: (8) failed: Refusing activation, D-Bus is shutting down.
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.6473] device (wlo1): state change: deactivating -> unmanaged (reason 'removed', sys-iface-state: 'managed')
Jun 05 21:42:45 theBeard wpa_supplicant[1029]: p2p-dev-wlo1: CTRL-EVENT-DSCP-POLICY clear_all
Jun 05 21:42:45 theBeard wpa_supplicant[1029]: nl80211: deinit ifname=p2p-dev-wlo1 disabled_11b_rates=0
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.7021] dhcp4 (wlo1): canceled DHCP transaction
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.7021] dhcp4 (wlo1): activation: beginning transaction (timeout in 45 seconds)
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.7022] dhcp4 (wlo1): state changed no lease
Jun 05 21:42:45 theBeard wpa_supplicant[1029]: p2p-dev-wlo1: CTRL-EVENT-TERMINATING
Jun 05 21:42:45 theBeard kernel: wlo1: deauthenticating from 48:5d:36:8e:a4:7c by local choice (Reason: 3=DEAUTH_LEAVING)
Jun 05 21:42:45 theBeard systemd[1]: systemd-logind.service: Deactivated successfully.
Jun 05 21:42:45 theBeard systemd[1]: Stopped User Login Management.
Jun 05 21:42:45 theBeard audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-logind comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 21:42:45 theBeard systemd[1]: Stopped target User and Group Name Lookups.
Jun 05 21:42:45 theBeard kernel: audit: type=1131 audit(1654479765.734:119): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-logind comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 05 21:42:45 theBeard wpa_supplicant[1029]: wlo1: CTRL-EVENT-DISCONNECTED bssid=48:5d:36:8e:a4:7c reason=3 locally_generated=1
Jun 05 21:42:45 theBeard wpa_supplicant[1029]: wlo1: CTRL-EVENT-DSCP-POLICY clear_all
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.7544] manager: NetworkManager state is now DISCONNECTED
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.5' (uid=0 pid=949 comm="/usr/bin/NetworkManager --no-daemon")
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.7564] device (p2p-dev-wlo1): state change: disconnected -> unmanaged (reason 'removed', sys-iface-state: 'removed')
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.5' (uid=0 pid=949 comm="/usr/bin/NetworkManager --no-daemon")
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.5' (uid=0 pid=949 comm="/usr/bin/NetworkManager --no-daemon")
Jun 05 21:42:45 theBeard dbus-daemon[930]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down.
Jun 05 21:42:45 theBeard audit: BPF prog-id=0 op=UNLOAD
Jun 05 21:42:45 theBeard NetworkManager[949]: <info>  [1654479765.7670] exiting (success)
Jun 05 21:42:45 theBeard kernel: audit: type=1334 audit(1654479765.764:120): prog-id=0 op=UNLOAD
Jun 05 21:42:45 theBeard systemd[1]: NetworkManager.service: Deactivated successfully.
Jun 05 21:42:45 theBeard systemd[1]: Stopped Network Manager.

Offline

Board footer

Powered by FluxBB