[Solved] "A stop job is running for User Manager..." then timeout 2min

Atari · 2020-12-13 07:55:41

Hi all,

since recently, most of (all?) my computer shutdowns take more than 2 minutes perform, with a timeout related to a message in the console "A stop job is running for User Manager for UID 1000 ...".

In the log, at line ~ 212, it prints the sequence:

Dec 12 22:55:01 ws20lnx systemd[799]: tracker-miner-fs-3.service: Succeeded.
Dec 12 22:57:01 ws20lnx systemd[1]: user@1000.service: State 'stop-sigterm' timed out. Killing.
Dec 12 22:57:01 ws20lnx systemd[1]: user@1000.service: Killing process 799 (systemd) with signal SIGKILL.
Dec 12 22:57:01 ws20lnx systemd[1]: user@1000.service: Killing process 8368 (dbus-daemon) with signal SIGKILL.
Dec 12 22:57:01 ws20lnx systemd[1]: user@1000.service: Main process exited, code=killed, status=9/KILL
Dec 12 22:57:01 ws20lnx systemd[1]: user@1000.service: Failed with result 'timeout'.
Dec 12 22:57:01 ws20lnx systemd[1]: Stopped User Manager for UID 1000.
Dec 12 22:57:01 ws20lnx 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=failed'
Dec 12 22:57:01 ws20lnx kernel: kauditd_printk_skb: 7 callbacks suppressed
Dec 12 22:57:01 ws20lnx kernel: audit: type=1131 audit(1607810221.196:128): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'

Because this is happening since a recent system update, I suspect this is some kind of regression. Log from pacman: https://pastebin.com/MPvz5UzT

Any idea on how to further inspect the cause & resolve this issue?

Last edited by Atari (2020-12-13 21:29:56)

Head_on_a_Stick · 2020-12-13 10:05:52

Some suggestions here: https://github.com/systemd/systemd/issues/12262

spiri · 2020-12-13 12:32:40

Hello,

I'm having the same issue and I don't know what is causing this issue.

Also, after waiting two minutes for the User Manager to stop watchdog did not stop and system didn't reboot after 5 minutes so I pressed cold reboot button.

Here is my log beginning where timeout happens till I pressed the cold reboot button after watchdog did not stop:

déc 13 13:19:17 somebody dhcpcd[1650]: wlp0: carrier lost
déc 13 13:19:17 somebody dhcpcd[120674]: wlp0: ps_bpf_recvbpf: Network is down
déc 13 13:19:17 somebody dhcpcd[1650]: wlp0: deleting address fe80::b4ce:ea90:3c34:81dc
déc 13 13:19:17 somebody dhcpcd[1650]: wlp0: deleting route to 192.168.178.0/24
déc 13 13:19:17 somebody dhcpcd[1650]: wlp0: removing interface
déc 13 13:20:52 somebody systemd[1]: user@1000.service: State 'stop-sigterm' timed out. Killing.
déc 13 13:20:52 somebody systemd[1]: user@1000.service: Killing process 2177 (systemd) with signal SIGKILL.
déc 13 13:20:52 somebody systemd[1]: user@1000.service: Killing process 151264 (dbus-daemon) with signal SIGKILL.
déc 13 13:20:52 somebody systemd[1]: user@1000.service: Main process exited, code=killed, status=9/KILL
déc 13 13:20:52 somebody systemd[1]: user@1000.service: Failed with result 'timeout'.
déc 13 13:20:52 somebody systemd[1]: Stopped User Manager for UID 1000.
déc 13 13:20:52 somebody 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=failed'
déc 13 13:20:52 somebody systemd[1]: Stopping User Runtime Directory /run/user/1000...
déc 13 13:20:52 somebody systemd[1]: run-user-1000.mount: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Unmounted /run/user/1000.
déc 13 13:20:52 somebody kernel: kauditd_printk_skb: 8 callbacks suppressed
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.253:185): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=user@1000 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
déc 13 13:20:52 somebody systemd[1]: user-runtime-dir@1000.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped User Runtime Directory /run/user/1000.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Removed slice User Slice of UID 1000.
déc 13 13:20:52 somebody systemd[1]: Stopping Permit User Sessions...
déc 13 13:20:52 somebody systemd[1]: systemd-user-sessions.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Permit User Sessions.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopped target Network.
déc 13 13:20:52 somebody systemd[1]: Stopped target User and Group Name Lookups.
déc 13 13:20:52 somebody systemd[1]: Stopped target Remote File Systems.
déc 13 13:20:52 somebody systemd[1]: Stopping dhcpcd on all interfaces...
déc 13 13:20:52 somebody systemd[1]: Stopping WPA supplicant...
déc 13 13:20:52 somebody dhcpcd[151286]: sending signal TERM to pid 1649
déc 13 13:20:52 somebody dhcpcd[151286]: waiting for pid 1649 to exit
déc 13 13:20:52 somebody dhcpcd[151286]: sending signal TERM to pid 1649
déc 13 13:20:52 somebody dhcpcd[151286]: waiting for pid 1649 to exit
déc 13 13:20:52 somebody dhcpcd[1650]: received SIGTERM, stopping
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.263:186): 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'
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.263:187): 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'
déc 13 13:20:52 somebody dhcpcd[1650]: enp0: removing interface
déc 13 13:20:52 somebody dhcpcd[1650]: enp0: deleting address fe80::724d:7bff:fe2e:2289
déc 13 13:20:52 somebody systemd[1]: wpa_supplicant.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped WPA supplicant.
déc 13 13:20:52 somebody dhcpcd[1650]: enp0: deleting route to 192.168.0.0/24
déc 13 13:20:52 somebody dhcpcd[1650]: enp0: deleting default route via 192.168.0.1
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wpa_supplicant comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Stopping D-Bus System Message Bus...
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.273:188): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wpa_supplicant comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: dbus.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped D-Bus System Message Bus.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.313:189): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dbus comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: dhcpcd.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped dhcpcd on all interfaces.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dhcpcd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Stopping WPA supplicant daemon (interface-specific version)...
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.373:190): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=dhcpcd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody wpa_supplicant[1642]: ioctl[SIOCGIWMODE]: No such device
déc 13 13:20:52 somebody wpa_supplicant[1642]: ioctl[SIOCSIWAP]: No such device
déc 13 13:20:52 somebody wpa_supplicant[1642]: ioctl[SIOCSIWESSID]: No such device
déc 13 13:20:52 somebody wpa_supplicant[1642]: Could not read interface wlp0 flags: No such device
déc 13 13:20:52 somebody wpa_supplicant[1642]: wlp0: CTRL-EVENT-TERMINATING
déc 13 13:20:52 somebody systemd[1]: wpa_supplicant@wlp0.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped WPA supplicant daemon (interface-specific version).
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wpa_supplicant@wlp0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Removed slice system-wpa_supplicant.slice.
déc 13 13:20:52 somebody systemd[1]: Stopped target Basic System.
déc 13 13:20:52 somebody systemd[1]: Stopped target Paths.
déc 13 13:20:52 somebody systemd[1]: cups.path: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped CUPS Scheduler.
déc 13 13:20:52 somebody systemd[1]: Stopped target Slices.
déc 13 13:20:52 somebody systemd[1]: Removed slice User and Session Slice.
déc 13 13:20:52 somebody systemd[1]: Stopped target Sockets.
déc 13 13:20:52 somebody systemd[1]: cups.socket: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Closed CUPS Scheduler.
déc 13 13:20:52 somebody systemd[1]: dbus.socket: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Closed D-Bus System Message Bus Socket.
déc 13 13:20:52 somebody systemd[1]: Stopped target System Initialization.
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.493:191): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=wpa_supplicant@wlp0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Stopped target Local Encrypted Volumes.
déc 13 13:20:52 somebody systemd[1]: systemd-ask-password-console.path: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
déc 13 13:20:52 somebody systemd[1]: systemd-ask-password-wall.path: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
déc 13 13:20:52 somebody systemd[1]: Stopping Cryptography Setup for backup...
déc 13 13:20:52 somebody systemd[1]: Stopping Cryptography Setup for music...
déc 13 13:20:52 somebody systemd[1]: Stopping Cryptography Setup for pictures...
déc 13 13:20:52 somebody systemd[1]: Stopping Cryptography Setup for videos...
déc 13 13:20:52 somebody systemd[1]: systemd-sysctl.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Apply Kernel Variables.
déc 13 13:20:52 somebody systemd[1]: systemd-modules-load.service: Succeeded.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopped Load Kernel Modules.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopping Network Time Synchronization...
déc 13 13:20:52 somebody systemd[1]: systemd-update-done.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Update is Completed.
déc 13 13:20:52 somebody systemd[1]: ldconfig.service: Succeeded.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopped Rebuild Dynamic Linker Cache.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: systemd-journal-catalog-update.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Rebuild Journal Catalog.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopping Update UTMP about System Boot/Shutdown...
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.553:192): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.553:193): 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'
déc 13 13:20:52 somebody kernel: audit: type=1131 audit(1607862052.553:194): 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'
déc 13 13:20:52 somebody audit[151344]: SYSTEM_SHUTDOWN pid=151344 uid=0 auid=4294967295 ses=4294967295 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: systemd-update-utmp.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Update UTMP about System Boot/Shutdown.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: systemd-timesyncd.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Network Time Synchronization.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: systemd-tmpfiles-setup.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Create Volatile Files and Directories.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopped target Local File Systems.
déc 13 13:20:52 somebody systemd[1]: Unmounting /boot...
déc 13 13:20:52 somebody systemd[1]: Unmounting /home...
déc 13 13:20:52 somebody systemd[1]: Unmounting /root...
déc 13 13:20:52 somebody systemd[1]: Unmounting Temporary Directory (/tmp)...
déc 13 13:20:52 somebody systemd[1]: boot.mount: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Unmounted /boot.
déc 13 13:20:52 somebody systemd[1]: tmp.mount: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Unmounted Temporary Directory (/tmp).
déc 13 13:20:52 somebody systemd[1]: Stopped target Swap.
déc 13 13:20:52 somebody systemd[1]: systemd-fsck@dev-disk-by\x2duuid-6A53\x2dAE60.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped File System Check on /dev/disk/by-uuid/6A53-AE60.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-6A53\x2dAE60 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: root.mount: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Unmounted /root.
déc 13 13:20:52 somebody systemd[1]: systemd-fsck@dev-disk-by\x2duuid-4cf2a8da\x2d909b\x2d4abd\x2d9851\x2dbd3d445b981a.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped File System Check on /dev/disk/by-uuid/4cf2a8da-909b-4abd-9851-bd3d445b981a.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-4cf2a8da\x2d909b\x2d4abd\x2d9851\x2dbd3d445b981a comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: systemd-cryptsetup@backup.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Cryptography Setup for backup.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-cryptsetup@backup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: systemd-cryptsetup@music.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Cryptography Setup for music.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-cryptsetup@music comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: systemd-cryptsetup@pictures.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Cryptography Setup for pictures.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-cryptsetup@pictures comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: systemd-cryptsetup@videos.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Cryptography Setup for videos.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-cryptsetup@videos comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: home.mount: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Unmounted /home.
déc 13 13:20:52 somebody systemd[1]: Reached target Unmount All Filesystems.
déc 13 13:20:52 somebody systemd[1]: systemd-fsck@dev-disk-by\x2duuid-7840748c\x2d33c1\x2d4538\x2dbda8\x2d78e2bae59594.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped File System Check on /dev/disk/by-uuid/7840748c-33c1-4538-bda8-78e2bae59594.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-fsck@dev-disk-by\x2duuid-7840748c\x2d33c1\x2d4538\x2dbda8\x2d78e2bae59594 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Removed slice system-systemd\x2dfsck.slice.
déc 13 13:20:52 somebody systemd[1]: Stopped target Local File Systems (Pre).
déc 13 13:20:52 somebody systemd[1]: Stopping Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling...
déc 13 13:20:52 somebody systemd[1]: systemd-tmpfiles-setup-dev.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Create Static Device Nodes in /dev.
déc 13 13:20:52 somebody systemd[1]: systemd-sysusers.service: Succeeded.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopped Create System Users.
déc 13 13:20:52 somebody systemd[1]: systemd-remount-fs.service: Succeeded.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopped Remount Root and Kernel File Systems.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody lvm[151364]:   3 logical volume(s) in volume group "system" unmonitored
déc 13 13:20:52 somebody systemd[1]: lvm2-monitor.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped Monitoring of LVM2 mirrors, snapshots etc. using dmeventd or progress polling.
déc 13 13:20:52 somebody 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'
déc 13 13:20:52 somebody systemd[1]: Stopping LVM2 metadata daemon...
déc 13 13:20:52 somebody systemd[1]: lvm2-lvmetad.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Stopped LVM2 metadata daemon.
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=lvm2-lvmetad comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Reached target Shutdown.
déc 13 13:20:52 somebody systemd[1]: Reached target Final Step.
déc 13 13:20:52 somebody systemd[1]: lvm2-lvmetad.socket: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Closed LVM2 metadata daemon socket.
déc 13 13:20:52 somebody systemd[1]: systemd-reboot.service: Succeeded.
déc 13 13:20:52 somebody systemd[1]: Finished Reboot.
déc 13 13:20:52 somebody audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
déc 13 13:20:52 somebody systemd[1]: Reached target Reboot.
déc 13 13:20:52 somebody systemd[1]: Shutting down.
déc 13 13:20:52 somebody systemd[1]: Using hardware watchdog 'iTCO_wdt', version 0, device /dev/watchdog
déc 13 13:20:52 somebody systemd[1]: Set hardware watchdog to 10min.
déc 13 13:20:52 somebody kernel: watchdog: watchdog0: watchdog did not stop!

Last edited by spiri (2020-12-13 12:34:15)

Head_on_a_Stick · 2020-12-13 14:26:20

spiri wrote:

I'm having the same issue

Have you read through the systemd issue to which I linked above? Try some of the suggestions and report back.

spiri · 2020-12-13 15:02:14

Hello,

I added some kernel params "systemd.log_level=debug systemd.log_target=kmsg log_buf_len=1M printk.devkmsg=on" next to my "cryptdevice=..." param resulting in an unbootable system claiming the UUID of my encrypted device was not found anymore... So I reverted back my grub.cfg using a Live CD.

Furthermore I followed the instructions provided in your link. I started the debug-shell.service and powered off from there. After system was waiting for user@1000.service for two minutes I was able to get a stack trace from PID 1.

Using "pstack 1":

process: /proc/1/mem
thread: 0, lwp: 1, type: 0
#0  0x00007f3b2b57559a in epoll_wait!()+26 in /usr/lib/libc.so.6
#1  0x00007f3b2b905f7b in sd_event_wait!()+266 in /usr/lib/systemd/libsystemd-shared-247.so
#2  0x00007f3b2b907f9c in sd_event_run!()+315 in /usr/lib/systemd/libsystemd-shared-247.so
#3  0x0000558c54bb0f33 in <unknown>() in /usr/lib/systemd/systemd
#4  0x0000558c54b71208 in <unknown>() in /usr/lib/systemd/systemd
#5  0x00007f3b2b49d152 in __libc_start_main!()+241 in /usr/lib/libc.so.6
#6  0x0000558c54b7240e in <unknown>() in /usr/lib/systemd/systemd

CarbonChauvinist · 2020-12-13 15:20:53

@OP, If you're running gnome, which it looks like you are, you're more than likely seeing this issue

@spiri, your issue may be different? not sure depends, are you running gome?

Last edited by CarbonChauvinist (2020-12-13 15:25:17)

spiri · 2020-12-13 15:47:23

@CarbonChauvinist, the link you provided solved the problem, many thanks for this. I am also using Gnome.

However I don't really understand what I'm doing by overriding gnome-session-restart-dbus.service with the content you provided on the other thread.

CarbonChauvinist · 2020-12-13 17:00:20

Basically, this. Systemd changed the way they organize some things internally (using slices) which in turn meant that gnome needed to change some directives to make sure some services worked as expected.

The fix has been merged AFAICS, but until that gets to us in arch we can add the fix manually with an override.

Atari · 2020-12-13 21:28:55

Thanks!! I confirm the fix linked by CarbonChauvinist also solves my issue.

Arch Linux

#1 2020-12-13 07:55:41

[Solved] "A stop job is running for User Manager..." then timeout 2min

#2 2020-12-13 10:05:52

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#3 2020-12-13 12:32:40

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#4 2020-12-13 14:26:20

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#5 2020-12-13 15:02:14

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#6 2020-12-13 15:20:53

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#7 2020-12-13 15:47:23

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#8 2020-12-13 17:00:20

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

#9 2020-12-13 21:28:55

Re: [Solved] "A stop job is running for User Manager..." then timeout 2min

Board footer