Increased time for system shutdown

Bluboy234 · 2021-05-24 02:42:51

The time for shutdown is not increased by much. It is still around or less than 1 minute but it still bothers me what might be reason for increase. I think it only happens when my system is up for a while.
Here are my logs of journalctl

May 24 00:15:58 alkalimetals systemd-journald[217]: Journal stopped
May 24 00:15:58 alkalimetals systemd-shutdown[1]: Sending SIGTERM to remaining processes...
May 24 00:15:58 alkalimetals systemd-shutdown[1]: Syncing filesystems and block devices.
May 24 00:15:58 alkalimetals audit: BPF prog-id=14 op=UNLOAD
May 24 00:15:58 alkalimetals audit: BPF prog-id=15 op=UNLOAD
May 24 00:15:58 alkalimetals audit: BPF prog-id=16 op=UNLOAD
May 24 00:15:58 alkalimetals audit: BPF prog-id=11 op=UNLOAD
May 24 00:15:58 alkalimetals audit: BPF prog-id=12 op=UNLOAD
May 24 00:15:58 alkalimetals audit: BPF prog-id=13 op=UNLOAD
May 24 00:15:58 alkalimetals systemd[1]: Shutting down.
May 24 00:15:58 alkalimetals systemd[1]: Reached target Power-Off.
May 24 00:15:58 alkalimetals systemd[1]: Finished Power-Off.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-poweroff comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-poweroff comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-poweroff.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Reached target Final Step.
May 24 00:15:58 alkalimetals systemd[1]: Reached target Shutdown.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=lvm2-monitor comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
May 24 00:15:58 alkalimetals systemd[1]: lvm2-monitor.service: Deactivated successfully.
May 24 00:15:58 alkalimetals audit: BPF prog-id=17 op=UNLOAD
May 24 00:15:58 alkalimetals systemd[1]: Reached target Unmount All Filesystems.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-uuid/753adafe-7a05-4d8e-9370-a47856e004b3.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2duuid-753adafe\x2d7a05\x2d4d8e\x2d9370\x2da47856e004b3.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/sda7.
May 24 00:15:58 alkalimetals systemd[1]: dev-sda7.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-id/ata-SAMSUNG_MZNLN256HAJQ-00000_S3TSNA0K801437-part7.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2did-ata\x2dSAMSUNG_MZNLN256HAJQ\x2d00000_S3TSNA0K801437\x2dpart7.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:00:17.0-ata-2-part7.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:00:17.0\x2data\x2d2\x2dpart7.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-partlabel/swap.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2dpartlabel-swap.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:00:17.0-ata-2.0-part7.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:00:17.0\x2data\x2d2.0\x2dpart7.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-id/wwn-0x5002538d00792af0-part7.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2did-wwn\x2d0x5002538d00792af0\x2dpart7.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivated swap /dev/disk/by-partuuid/4cd3ed6c-9364-4146-aeb0-daf627166747.
May 24 00:15:58 alkalimetals systemd[1]: dev-disk-by\x2dpartuuid-4cd3ed6c\x2d9364\x2d4146\x2daeb0\x2ddaf627166747.swap: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Remount Root and Kernel File Systems.
May 24 00:15:58 alkalimetals systemd[1]: systemd-remount-fs.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Create System Users.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-sysusers.service: Deactivated successfully.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Create Static Device Nodes in /dev.
May 24 00:15:58 alkalimetals systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopping Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Local File Systems (Pre).
May 24 00:15:58 alkalimetals systemd[1]: Removed slice system-systemd\x2dfsck.slice.
May 24 00:15:58 alkalimetals systemd[1]: Stopped File System Check on /dev/disk/by-uuid/EA4E-818A.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-EA4E\x2d818A comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-fsck@dev-disk-by\x2duuid-EA4E\x2d818A.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Deactivating swap /dev/disk/by-id/ata-SAMSUNG_MZNLN256HAJQ-00000_S3TSNA0K801437-part7...
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Swap.
May 24 00:15:58 alkalimetals systemd[1]: Unmounted Temporary Directory (/tmp).
May 24 00:15:58 alkalimetals systemd[1]: tmp.mount: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Unmounted /boot.
May 24 00:15:58 alkalimetals systemd[1]: boot.mount: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Unmounted /run/user/1000.
May 24 00:15:58 alkalimetals systemd[1]: run-user-1000.mount: Failed with result 'exit-code'.
May 24 00:15:58 alkalimetals systemd[1]: run-user-1000.mount: Mount process exited, code=exited, status=32/n/a
May 24 00:15:58 alkalimetals umount[153395]: umount: /run/user/1000: no mount point specified.
May 24 00:15:58 alkalimetals systemd[1]: Unmounting Temporary Directory (/tmp)...
May 24 00:15:58 alkalimetals systemd[1]: Unmounting /run/user/1000...
May 24 00:15:58 alkalimetals systemd[1]: Unmounting /boot...
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Local File Systems.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Create Volatile Files and Directories.
May 24 00:15:58 alkalimetals systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Network Time Synchronization.
May 24 00:15:58 alkalimetals systemd[1]: systemd-timesyncd.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Update UTMP about System Boot/Shutdown.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-update-utmp.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Removed slice system-systemd\x2dbacklight.slice.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Load/Save Screen Backlight Brightness of leds:kbd_backlight.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-backlight@leds:kbd_backlight comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-backlight@leds:kbd_backlight.service: Deactivated successfully.
May 24 00:15:58 alkalimetals audit[153391]: SYSTEM_SHUTDOWN pid=153391 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals kernel: audit: type=1131 audit(1621795558.188:516): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-backlight@backlight:intel_backlight comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Load/Save Screen Backlight Brightness of backlight:intel_backlight.
May 24 00:15:58 alkalimetals systemd[1]: systemd-backlight@backlight:intel_backlight.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopping Update UTMP about System Boot/Shutdown...
May 24 00:15:58 alkalimetals systemd[1]: Stopped Rebuild Journal Catalog.
May 24 00:15:58 alkalimetals systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Rebuild Dynamic Linker Cache.
May 24 00:15:58 alkalimetals systemd[1]: ldconfig.service: Deactivated successfully.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Update is Completed.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-update-done.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopping Network Time Synchronization...
May 24 00:15:58 alkalimetals systemd[1]: Stopped Load Kernel Modules.
May 24 00:15:58 alkalimetals systemd[1]: systemd-modules-load.service: Deactivated successfully.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: Stopped Apply Kernel Variables.
May 24 00:15:58 alkalimetals systemd[1]: systemd-sysctl.service: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopping Load/Save Screen Backlight Brightness of leds:kbd_backlight...
May 24 00:15:58 alkalimetals systemd[1]: Stopping Load/Save Screen Backlight Brightness of backlight:intel_backlight...
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Local Verity Integrity Protected Volumes.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
May 24 00:15:58 alkalimetals kernel: audit: type=1131 audit(1621795558.148:515): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=netctl@wlp2s0\x2dBlu comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: systemd-ask-password-wall.path: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
May 24 00:15:58 alkalimetals systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Local Encrypted Volumes.
May 24 00:15:58 alkalimetals systemd[1]: Stopped target System Initialization.
May 24 00:15:58 alkalimetals systemd[1]: Closed D-Bus System Message Bus Socket.
May 24 00:15:58 alkalimetals systemd[1]: dbus.socket: Deactivated successfully.
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Sockets.
May 24 00:15:58 alkalimetals systemd[1]: user.slice: Consumed 1d 9h 40min 16.379s CPU time.
May 24 00:15:58 alkalimetals systemd[1]: Removed slice User and Session Slice.
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Slices.
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Paths.
May 24 00:15:58 alkalimetals systemd[1]: Stopped target Basic System.
May 24 00:15:58 alkalimetals systemd[1]: system-netctl.slice: Consumed 9.263s CPU time.
May 24 00:15:58 alkalimetals systemd[1]: Removed slice system-netctl.slice.
May 24 00:15:58 alkalimetals systemd[1]: netctl@wlp2s0\x2dBlu.service: Consumed 6.860s CPU time.
May 24 00:15:58 alkalimetals systemd[1]: Stopped Networking for netctl profile wlp2s0-Blu.
May 24 00:15:58 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=netctl@wlp2s0\x2dBlu comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:58 alkalimetals systemd[1]: netctl@wlp2s0\x2dBlu.service: Deactivated successfully.
May 24 00:15:58 alkalimetals network[153369]: Stopped network profile 'wlp2s0-Blu'
May 24 00:15:57 alkalimetals kernel: wlp2s0: deauthenticating from 24:0b:88:b2:b9:b9 by local choice (Reason: 3=DEAUTH_LEAVING)
May 24 00:15:57 alkalimetals dhcpcd[35170]: wlp2s0: removing interface
May 24 00:15:57 alkalimetals dhcpcd[35170]: received SIGTERM, stopping
May 24 00:15:57 alkalimetals dhcpcd[153374]: waiting for pid 35169 to exit
May 24 00:15:57 alkalimetals dhcpcd[153374]: sending signal TERM to pid 35169
May 24 00:15:57 alkalimetals kernel: audit: type=1131 audit(1621795557.388:514): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dbus comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:57 alkalimetals network[153369]: Stopping network profile 'wlp2s0-Blu'...
May 24 00:15:57 alkalimetals systemd[1]: dbus.service: Consumed 52.227s CPU time.
May 24 00:15:57 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dbus comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:57 alkalimetals systemd[1]: Stopped D-Bus System Message Bus.
May 24 00:15:57 alkalimetals systemd[1]: dbus.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[1]: Stopping Networking for netctl profile wlp2s0-Blu...
May 24 00:15:57 alkalimetals systemd[1]: Stopped target Remote File Systems.
May 24 00:15:57 alkalimetals systemd[1]: Stopped target Network.
May 24 00:15:57 alkalimetals kernel: audit: type=1131 audit(1621795557.358:513): 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'
May 24 00:15:57 alkalimetals 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'
May 24 00:15:57 alkalimetals systemd[1]: Stopped Permit User Sessions.
May 24 00:15:57 alkalimetals systemd[1]: systemd-user-sessions.service: Deactivated successfully.
May 24 00:15:57 alkalimetals kernel: audit: type=1131 audit(1621795557.348:512): 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'
May 24 00:15:57 alkalimetals systemd[1]: Stopping Permit User Sessions...
May 24 00:15:57 alkalimetals systemd[1]: Stopping D-Bus System Message Bus...
May 24 00:15:57 alkalimetals systemd[1]: user-1000.slice: Consumed 1d 9h 40min 15.658s CPU time.
May 24 00:15:57 alkalimetals systemd[1]: Removed slice User Slice of UID 1000.
May 24 00:15:57 alkalimetals 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'
May 24 00:15:57 alkalimetals systemd[1]: Stopped User Runtime Directory /run/user/1000.
May 24 00:15:57 alkalimetals systemd[1]: user-runtime-dir@1000.service: Deactivated successfully.
May 24 00:15:57 alkalimetals kernel: audit: type=1131 audit(1621795557.338:511): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:57 alkalimetals systemd[1]: Stopping User Runtime Directory /run/user/1000...
May 24 00:15:57 alkalimetals systemd[1]: user@1000.service: Consumed 1d 5h 24min 45.766s CPU time.
May 24 00:15:57 alkalimetals systemd[1]: Stopped User Manager for UID 1000.
May 24 00:15:57 alkalimetals audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:57 alkalimetals systemd[1]: user@1000.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[658]: pam_warn(systemd-user:setcred): function=[pam_sm_setcred] flags=0x8004 service=[systemd-user] terminal=[] user=[iwantroca] ruser=[<unknown>] rhost=[<unknown>]
May 24 00:15:57 alkalimetals systemd[657]: Reached target Exit the Session.
May 24 00:15:57 alkalimetals systemd[657]: Finished Exit the Session.
May 24 00:15:57 alkalimetals systemd[657]: systemd-exit.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Reached target Shutdown.
May 24 00:15:57 alkalimetals systemd[657]: app.slice: Consumed 1d 5h 22min 3.794s CPU time.
May 24 00:15:57 alkalimetals systemd[657]: Removed slice User Application Slice.
May 24 00:15:57 alkalimetals systemd[657]: Closed Sound System.
May 24 00:15:57 alkalimetals systemd[657]: pulseaudio.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed Multimedia System.
May 24 00:15:57 alkalimetals systemd[657]: pipewire.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed p11-kit server.
May 24 00:15:57 alkalimetals systemd[657]: p11-kit-server.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed GnuPG cryptographic agent and passphrase cache.
May 24 00:15:57 alkalimetals systemd[657]: gpg-agent.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed GnuPG cryptographic agent (ssh-agent emulation).
May 24 00:15:57 alkalimetals systemd[657]: gpg-agent-ssh.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
May 24 00:15:57 alkalimetals systemd[657]: gpg-agent-extra.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
May 24 00:15:57 alkalimetals systemd[657]: gpg-agent-browser.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed GnuPG network certificate management daemon.
May 24 00:15:57 alkalimetals systemd[657]: dirmngr.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Closed D-Bus User Message Bus Socket.
May 24 00:15:57 alkalimetals systemd[657]: dbus.socket: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Stopped target Timers.
May 24 00:15:57 alkalimetals systemd[657]: Stopped target Sockets.
May 24 00:15:57 alkalimetals systemd[657]: Stopped target Paths.
May 24 00:15:57 alkalimetals systemd[657]: Stopped target Basic System.
May 24 00:15:57 alkalimetals systemd[657]: pulseaudio.service: Consumed 3h 1min 25.998s CPU time.
May 24 00:15:57 alkalimetals systemd[657]: Stopped Sound Service.
May 24 00:15:57 alkalimetals systemd[657]: pulseaudio.service: Deactivated successfully.
May 24 00:15:57 alkalimetals kernel: audit: type=1334 audit(1621795557.268:510): prog-id=18 op=UNLOAD
May 24 00:15:57 alkalimetals kernel: audit: type=1334 audit(1621795557.268:509): prog-id=19 op=UNLOAD
May 24 00:15:57 alkalimetals kernel: audit: type=1334 audit(1621795557.268:508): prog-id=20 op=UNLOAD
May 24 00:15:57 alkalimetals systemd[657]: mpd.service: Consumed 5min 37.753s CPU time.
May 24 00:15:57 alkalimetals systemd[657]: Stopped Music Player Daemon.
May 24 00:15:57 alkalimetals systemd[657]: mpd.service: Deactivated successfully.
May 24 00:15:57 alkalimetals audit: BPF prog-id=18 op=UNLOAD
May 24 00:15:57 alkalimetals audit: BPF prog-id=19 op=UNLOAD
May 24 00:15:57 alkalimetals audit: BPF prog-id=20 op=UNLOAD
May 24 00:15:57 alkalimetals kernel: audit: type=1131 audit(1621795557.208:507): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-logind comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
May 24 00:15:57 alkalimetals kernel: kauditd_printk_skb: 8 callbacks suppressed
May 24 00:15:57 alkalimetals systemd[1]: systemd-logind.service: Consumed 6.552s CPU time.
May 24 00:15:57 alkalimetals 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'
May 24 00:15:57 alkalimetals systemd[1]: Stopped User Login Management.
May 24 00:15:57 alkalimetals systemd[1]: systemd-logind.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: at-spi-dbus-bus.service: Consumed 56.245s CPU time.
May 24 00:15:57 alkalimetals systemd[657]: Stopped Accessibility services bus.
May 24 00:15:57 alkalimetals systemd[657]: at-spi-dbus-bus.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: dbus.service: Consumed 1min 49.749s CPU time.
May 24 00:15:57 alkalimetals systemd[657]: Stopped D-Bus User Message Bus.
May 24 00:15:57 alkalimetals systemd[657]: dbus.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Stopped Bluetooth OBEX service.
May 24 00:15:57 alkalimetals systemd[657]: obex.service: Failed with result 'exit-code'.
May 24 00:15:57 alkalimetals systemd[657]: obex.service: Main process exited, code=exited, status=1/FAILURE
May 24 00:15:57 alkalimetals systemd[657]: Stopped User preferences database.
May 24 00:15:57 alkalimetals systemd[657]: dconf.service: Deactivated successfully.
May 24 00:15:57 alkalimetals systemd[657]: Stopping Sound Service...
May 24 00:15:57 alkalimetals systemd[657]: Stopping Bluetooth OBEX service...
May 24 00:15:57 alkalimetals systemd[657]: Stopping Music Player Daemon...
May 24 00:15:57 alkalimetals systemd[657]: Stopping User preferences database...
May 24 00:15:57 alkalimetals systemd[657]: Stopping D-Bus User Message Bus...
May 24 00:15:57 alkalimetals systemd[657]: Stopping Accessibility services bus...
May 24 00:15:57 alkalimetals systemd[657]: Stopped target Main User Target.
May 24 00:15:57 alkalimetals systemd[657]: Stopped target Bluetooth.
May 24 00:15:57 alkalimetals systemd[657]: background.slice: Consumed 2min 37.816s CPU time.
May 24 00:15:57 alkalimetals systemd[657]: Removed slice User Background Tasks Slice.
May 24 00:15:57 alkalimetals systemd-logind[390]: Removed session 2.
May 24 00:15:57 alkalimetals systemd[1]: Stopping User Manager for UID 1000...
May 24 00:15:57 alkalimetals systemd[1]: Stopping User Login Management...
May 24 00:15:57 alkalimetals systemd[1]: session-2.scope: Consumed 4h 15min 29.877s CPU time.
May 24 00:15:57 alkalimetals systemd[1]: Stopped Session 2 of user iwantroca.
May 24 00:15:57 alkalimetals systemd[1]: session-2.scope: Deactivated successfully.
May 24 00:15:56 alkalimetals kwin_x11[755]: Couldn't start kglobalaccel from org.kde.kglobalaccel.service: QDBusError("org.freedesktop.DBus.Error.NoReply", "Did not receive a reply. Possible causes include: the remote application did not send a reply, the message bus security policy blocked the reply, the reply timeout expired, or the network connection was broken.")

Last edited by Bluboy234 (2021-05-24 03:09:00)

koffeinfriedhof · 2021-06-16 09:09:36

Hi!

Seems you've posted the working last two seconds of your shutdown-process. Perhaps there was a inhibitor performing saving, cleaning, syncing, whatever before the shutdown finally ran. See

systemd-inhibit --list

to know which application uses this mech and systemd-inhibit as a reference.

Wild Penguin · 2021-06-16 09:48:07

What koffeinfriendhof said - post a more complete log.

FWIW I've had a few services (just badly made software) which sometimes used to crash / be in a weird state at shutdown, hence they can not shut down cleanly. What happens then is that systemd will wait for ages for every single service which has not shut down correctly.

Parameters in /etc/system.conf control the deafault behavior:

DefaultTimeoutStartSec=, DefaultTimeoutStopSec=, DefaultTimeoutAbortSec=, DefaultRestartSec=
Configures the default timeouts for starting, stopping and aborting of units, as well as the default time to sleep between automatic restarts of units, as configured per-unit in TimeoutStartSec=, TimeoutStopSec=, TimeoutAbortSec= and RestartSec= (for services, see systemd.service(5) for details on the per-unit settings). Disabled by default, when service with Type=oneshot is used. For non-service units, DefaultTimeoutStartSec= sets the default TimeoutSec= value. DefaultTimeoutStartSec= and DefaultTimeoutStopSec= default to 90s. DefaultTimeoutAbortSec= is not set by default so that all units fall back to TimeoutStopSec=. DefaultRestartSec= defaults to 100ms.

Of course it is best to determine why a process stalls at shutdown. However the default of 90s is excessive for most desktop users IMHO. I've set it to 15s, as for my usage, I can safely assume anything taking longer than that to shut down has crashed. YMMV!

(you can override this per-service, if you find problematic ones, but can not solve the actual problem)

Last edited by Wild Penguin (2021-06-16 09:50:48)

kokoko3k · 2021-06-16 12:24:06

To lower the timeout, as Wild Penguin suggested, try to set the values not only in /etc/systemd/system.conf, but also in /etc/systemd/user.conf :

DefaultTimeoutStopSec=5s
DefaultTimeoutAbortSec=5s

Arch Linux

#1 2021-05-24 02:42:51

Increased time for system shutdown

#2 2021-06-16 09:09:36

Re: Increased time for system shutdown

#3 2021-06-16 09:48:07

Re: Increased time for system shutdown

#4 2021-06-16 12:24:06

Re: Increased time for system shutdown

Board footer