Bug#912406: systemd: reboot/poweroff usually gets stuck

Wed Nov 7 03:24:03 GMT 2018

Hi,

I tried with another kernel from backports:

root at entry115:~# uname -a
Linux entry115 4.18.0-0.bpo.1-686-pae #1 SMP Debian 4.18.6-1~bpo9+1 
(2018-09-13) i686 GNU/Linux

The same issue was reproduced with kernel 4.18.0-0.bpo.1-686-pae. Here 
is the trace:

root at entry115:~# reboot
[   24.992363] systemd[1]: Stopped target Sound Card.
[   24.998264] systemd[1]: user-runtime-dir at 0.service: Unit not needed 
anymore. Stopping.
[   25.006518] systemd[1]: Stopping User Manager for UID 0...
[   25.012668] systemd[1]: Stopped target Timers.
[   25.017780] systemd[1]: Stopped Daily Cleanup of Temporary Directories.
[   25.025207] systemd[1]: Stopping Session 1 of user root.
[   25.030887] systemd[1]: Stopped Daily apt upgrade and clean activities.
[   25.038321] systemd[1]: Stopped Daily apt download activities.
[   25.044844] systemd[1]: Stopped target System Time Synchronized.
[   25.051519] systemd[1]: Stopped target Graphical Interface.
[   25.057735] systemd[1]: Stopped target Multi-User System.
[   25.063894] systemd[1]: Stopping OpenBSD Secure Shell server...
[   25.070520] systemd[1]: Stopping irqbalance daemon...
[   25.076251] systemd[1]: Stopping Regular background program 
processing daemon...
[   25.085986] systemd-logind[408]: Failed to abandon session scope, 
ignoring: Connection reset by peer
[   25.086184] systemd[1]: Stopping D-Bus System Message Bus...
[   25.095311] systemd-logind[408]: Session 1 logged out. Waiting for 
processes to exit.
[   25.097157] systemd[451]: Stopped target Default.
[   25.097194] systemd[451]: Stopped target Basic System.
[   25.097228] systemd[451]: Stopped target Sockets.
[   25.097399] systemd[451]: Closed GnuPG cryptographic agent (ssh-agent 
emulation).
[   25.097545] systemd[451]: Closed GnuPG cryptographic agent and 
passphrase cache.
[   25.097692] systemd[451]: Closed GnuPG cryptographic agent (access 
for web browsers).
[   25.097727] systemd[451]: Stopped target Paths.
[   25.097876] systemd[451]: Closed GnuPG cryptographic agent and 
passphrase cache (restricted).
[   25.097911] systemd[451]: Stopped target Timers.
[   25.097969] systemd[451]: Reached target Shutdown.
[   25.170019] systemd[451]: Starting Exit the Session...
[   25.185639] systemd[1]: Stopped target Login Prompts.
[   25.201585] systemd[1]: Stopping Getty on tty1...
[   25.217008] systemd[1]: Stopping Serial Getty on ttyS0...
[   25.233029] systemd[1]: Stopping System Logging Service...
[   25.250194] systemd[1]: Stopped Regular background program processing 
daemon.
[   25.269131] systemd[1]: Stopped D-Bus System Message Bus.
[   25.288921] systemd[1]: Stopped System Logging Service.
[   25.317982] systemd[1]: Stopped irqbalance daemon.
[   25.402687] systemd[1]: Stopped Getty on tty1.
[   25.409524] do_IRQ: 2.35 No irq handler for vector
[   25.414974] systemd[1]: Stopped Serial Getty on ttyS0.
[   25.422081] systemd[1]: Stopped OpenBSD Secure Shell server.
[   25.429548] systemd[1]: Stopped User Manager for UID 0.
[   25.435189] systemd[1]: user-runtime-dir at 0.service: Unit not needed 
anymore. Stopping.
[   25.445265] systemd[1]: Stopped Session 1 of user root.
[   25.453390] systemd[1]: Stopping Login Service...
[   25.459021] systemd[1]: Removed slice User Slice of UID 0.
[   25.466098] systemd[1]: Stopping /run/user/0 mount wrapper...
[   25.472694] systemd[1]: Removed slice system-serial\x2dgetty.slice.
[   25.479857] systemd[1]: Removed slice system-getty.slice.
[   25.487826] systemd[1]: Stopping Permit User Sessions...
[   25.495701] systemd[1]: Unmounted /run/user/0.
[   25.501803] systemd[1]: Stopped /run/user/0 mount wrapper.
[   25.508743] systemd[1]: Stopped Permit User Sessions.
[   25.514529] systemd[1]: Stopped target Remote File Systems.
[   25.520524] systemd[1]: Stopped target Network.
[   25.526657] systemd[1]: Stopping ifup for enp2s0...
[   25.533353] systemd[1]: Stopping Raise network interfaces...
[   25.539970] systemd[1]: Removed slice 
system-user\x2druntime\x2ddir.slice.
[   25.576147] systemd[1]: Stopped Raise network interfaces.
[   25.602356] systemd[1]: Stopped ifup for enp2s0.
[   25.707825] systemd[1]: Stopped Login Service.
[   25.713604] systemd[1]: Stopped target Basic System.
[   25.719204] systemd[1]: Stopped target Slices.
[   25.724438] systemd[1]: Removed slice User and Session Slice.
[   25.730624] systemd[1]: Stopped target Sockets.
[   25.735655] systemd[1]: Closed D-Bus System Message Bus Socket.
[   25.742099] systemd[1]: Closed Syslog Socket.
[   25.746818] systemd[1]: Stopped target System Initialization.
[   25.754189] systemd[1]: Stopping Load/Save Random Seed...
[   25.761232] systemd[1]: Stopping Update UTMP about System 
Boot/Shutdown...
[   25.769737] systemd[1]: Stopped Apply Kernel Variables.
[   25.781394] systemd[1]: Stopped Load Kernel Modules.
[   25.795447] systemd[1]: Stopped target Swap.
[   25.809631] systemd[1]: Deactivating swap 
/dev/disk/by-id/wwn-0x5000c500b23c6367-part5...
[   25.828414] systemd[1]: Stopped target Local Encrypted Volumes.
[   25.844940] systemd[1]: Stopping Network Time Synchronization...
[   25.861453] systemd[1]: Stopped target Paths.
[   25.889272] systemd[1]: Stopped Forward Password Requests to Wall 
Directory Watch.
[   25.928727] systemd[1]: Stopped Dispatch Password Requests to Console 
Directory Watch.
[   25.959270] systemd[1]: Deactivated swap 
/dev/disk/by-path/pci-0000:00:13.0-ata-1-part5.
[   25.994176] systemd[1]: Deactivated swap 
/dev/disk/by-partuuid/99c48f43-05.
[   26.016421] systemd[1]: Deactivated swap 
/dev/disk/by-id/wwn-0x5000c500b23c6367-part5.
[   26.035686] systemd[1]: Deactivated swap 
/dev/disk/by-id/ata-ST1000DM010-2EP102_Z9AW21V3-part5.
[   26.055900] systemd[1]: Deactivated swap /dev/sda5.
[   26.073952] systemd[1]: Stopped Network Time Synchronization.
[   26.092720] systemd[1]: Stopped Load/Save Random Seed.
[   26.110225] systemd[1]: Deactivated swap 
/dev/disk/by-uuid/99465a82-976c-4f78-9eec-7427dbc8db91.
[   26.138071] systemd[1]: Reached target Unmount All Filesystems.
[   26.156928] systemd[1]: Stopped Update UTMP about System Boot/Shutdown.
[   26.177568] systemd[1]: Stopped Create Volatile Files and Directories.
[   26.196221] systemd[1]: Stopped target Local File Systems.
[   26.213723] systemd[1]: Stopped target Local File Systems (Pre).
[   26.231766] systemd[1]: Stopped Create Static Device Nodes in /dev.
[   26.250204] systemd[1]: Stopped Create System Users.
[   26.267344] systemd[1]: Stopped Remount Root and Kernel File Systems.
[   26.286000] systemd[1]: Reached target Shutdown.
[   26.302919] systemd[1]: Reached target Final Step.
[   26.321292] systemd[1]: Starting Reboot...
[   26.342472] systemd[1]: Shutting down.
[   26.441115] systemd[1]: Hardware watchdog 'iTCO_wdt', version 0
[   26.456482] systemd[1]: Set hardware watchdog to 10min.
[   26.467009] watchdog: watchdog0: watchdog did not stop!
[   26.588907] systemd-shutdown[1]: Syncing filesystems and block devices.
[   26.658406] systemd-shutdown[1]: Sending SIGTERM to remaining 
processes...
[   26.685084] systemd-journald[234]: Received SIGTERM from PID 1 
(systemd-shutdow).
[   26.725264] systemd-shutdown[1]: Sending SIGKILL to remaining 
processes...
[   26.749976] systemd-shutdown[1]: Hardware watchdog 'iTCO_wdt', version 0
[   26.766755] systemd-shutdown[1]: Unmounting file systems.
[   26.780610] [505]: Remounting '/' read-only in with options 
'errors=remount-ro'.
[   26.873790] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro
[   26.888584] systemd-shutdown[1]: All filesystems unmounted.
[   26.903481] systemd-shutdown[1]: Deactivating swaps.
[   26.913865] systemd-shutdown[1]: All swaps deactivated.
[   26.924147] systemd-shutdown[1]: Detaching loop devices.
[   26.934660] systemd-shutdown[1]: All loop devices detached.
[   26.945223] systemd-shutdown[1]: Detaching DM devices.
[   26.955424] systemd-shutdown[1]: All DM devices detached.
[   26.966113] systemd-shutdown[1]: All filesystems, swaps, loop devices 
and DM devices detached.
[   27.001630] systemd-shutdown[1]: Syncing filesystems and block devices.
[   27.016688] systemd-shutdown[1]: Rebooting.
[   27.025861] kvm: exiting hardware virtualization
[   27.039863] sd 0:0:0:0: [sda] Synchronizing SCSI cache

Stuck here for 16 minutes before pressing power button for 4 seconds to 
force power off.

Regards, Andrew

On 31/10/18 20:27, Michael Biebl wrote:
> Am 31.10.18 um 09:42 schrieb Andrew Pavlomanolakos:
>
>> [   55.288313] systemd-shutdown[1]: Rebooting.
> This is logged right before systemd-shutdown calls the final
> reboot() system call. If it get's stuck after that, it looks like it is
> a kernel issue.
> Can you try with a different kernel?
>
>

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://alioth-lists.debian.net/pipermail/pkg-systemd-maintainers/attachments/20181107/79cc3d08/attachment-0002.html>