[systemd-devel] 'systemctl poweroff' no longer shuts down system -- instead, reboots ?

grantksupport at operamail.com grantksupport at operamail.com
Wed Oct 29 21:20:23 PDT 2014


journalctl across the failed shutdown procedure shows the systemd "Power-Off" process starting, the journal stopping, then, a "--Reboot--"

	...
	Oct 29 21:02:12 desk0107 systemd[1]: Starting Power-Off...
	Oct 29 21:02:12 desk0107 systemd-shutdown[1]: Sending SIGTERM to remaining processes...
	Oct 29 21:02:12 desk0107 sshd[3987]: Exiting on signal 15
	Oct 29 21:02:12 desk0107 sshd[2603]: Exiting on signal 15
	Oct 29 21:02:12 desk0107 systemd-journal[634]: Journal stopped
	-- Reboot --
	...


Here's the complete output,

	...
	Oct 29 21:00:01 desk0107 systemd[1]: Starting Session 5 of user root.

<======== "systemctl poweroff" exec'd here

	Oct 29 21:02:02 desk0107 systemd[1]: Deactivating swap /dev/dm-1...
	Oct 29 21:02:02 desk0107 systemd[1]: Deactivating swap /dev/dm-1...
	Oct 29 21:02:02 desk0107 systemd[1]: Deactivating swap /dev/dm-1...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopped target Sound Card.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopped target Bluetooth.
	Oct 29 21:02:02 desk0107 systemd[1]: Removed slice system-systemd\x2dfsck.slice.
	Oct 29 21:02:02 desk0107 systemd[1]: Removed slice system-wol.slice.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopped Stop Read-Ahead Data Collection 10s After Completed Startup.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopped target System Time Synchronized.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping Manage, Install and Generate Color Profiles...
	Oct 29 21:02:02 desk0107 bluetoothd[1110]: Terminating
	Oct 29 21:02:02 desk0107 bluetoothd[1110]: Stopping SDP server
	Oct 29 21:02:02 desk0107 bluetoothd[1110]: Exit
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping ifup managed network interface eth0...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping Graphical Interface.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping Multi-User System.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping LSB: ha_logd logging daemon...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping LSB: VirtualBox web service API...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping LSB: irqbalance daemon providing irq balancing on MP-machines...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping LSB: VirtualBox watchdog daemon...
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopping Basic System.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopped target Basic System.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopping Paths.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopped target Paths.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopping Timers.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopped target Timers.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopping Sockets.
	Oct 29 21:02:02 desk0107 systemd[2150]: Stopped target Sockets.
	Oct 29 21:02:02 desk0107 systemd[2150]: Starting Shutdown.
	Oct 29 21:02:02 desk0107 systemd[2150]: Reached target Shutdown.
	Oct 29 21:02:02 desk0107 systemd[2150]: Starting Exit the Session...
	Oct 29 21:02:02 desk0107 logd[5027]: [5027]: debug: Stopping ha_logd with pid 2123
	Oct 29 21:02:02 desk0107 logd[5027]: [5027]: info: Waiting for pid=2123 to exit
	Oct 29 21:02:02 desk0107 logd[2123]: [2123]: debug: logd_term_action: received SIGTERM
	Oct 29 21:02:02 desk0107 logd[2123]: [2123]: debug: logd_term_action: waiting for 0 messages to be read by write process
	Oct 29 21:02:02 desk0107 logd[2123]: [2123]: debug: logd_term_action: sending SIGTERM to write process
	Oct 29 21:02:02 desk0107 logd[2166]: [2166]: info: logd_term_write_action: received SIGTERM
	Oct 29 21:02:02 desk0107 logd[2166]: [2166]: debug: Writing out 0 messages then quitting
	Oct 29 21:02:02 desk0107 logd[2166]: [2166]: info: Exiting write process
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping LSB: Supports the direct execution of binary formats....
	Oct 29 21:02:02 desk0107 systemd[2150]: Received SIGRTMIN+24 from PID 5023 (kill).
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping Login Prompts.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopped target Login Prompts.
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping Serial Getty on ttyS0...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping RPC Bind...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping CUPS Printing Service...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping Command Scheduler...
	Oct 29 21:02:02 desk0107 systemd[1]: Stopping MariaDB database server...
	Oct 29 21:02:03 desk0107 systemd[1]: Stopping System Logging Service...
	Oct 29 21:02:03 desk0107 systemd[1]: Stopping Postfix MTA Daemon...
	Oct 29 21:02:03 desk0107 systemd[1]: Stopping Login Service...
	Oct 29 21:02:03 desk0107 jexec[5034]: Stopping jexec services
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped /etc/init.d/after.local Compatibility.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopping Getty on tty1...
	Oct 29 21:02:03 desk0107 systemd[1]: Starting Store Sound Card State...
	Oct 29 21:02:03 desk0107 sshd[2184]: Received signal 15; terminating.
	Oct 29 21:02:03 desk0107 systemd[1]: Deactivated swap /dev/mapper/VG0-SWAP.
	Oct 29 21:02:03 desk0107 systemd[1]: Deactivated swap /dev/disk/by-id/dm-uuid-LVM-BUSEZNjd3H2AiJEWGp5rFdpfR7ToGldmIjkFGakMHpE69UuJU42UycrqPid8Qbds.
	Oct 29 21:02:03 desk0107 systemd[1]: Deactivated swap /dev/disk/by-id/dm-name-VG0-SWAP.
	Oct 29 21:02:03 desk0107 systemd[1]: Deactivated swap /dev/VG0/LV_SWAP.
	Oct 29 21:02:03 desk0107 systemd-journal[634]: Forwarding to syslog missed 1 messages.
	Oct 29 21:02:03 desk0107 postfix/postfix-script[5106]: stopping the Postfix mail system
	Oct 29 21:02:03 desk0107 postfix/master[2485]: terminating on signal 15
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped Machine Check Exception Logging Daemon.
	Oct 29 21:02:03 desk0107 systemd[1]: rpcbind.service: main process exited, code=exited, status=2/INVALIDARGUMENT
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped RPC Bind.
	Oct 29 21:02:03 desk0107 systemd[1]: Unit rpcbind.service entered failed state.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped System Logging Service.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped CUPS Printing Service.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped Command Scheduler.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped Login Service.
	Oct 29 21:02:03 desk0107 irq_balancer[5018]: Shutting down irqbalance ..done
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped User Manager for UID 0.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped Automounts filesystems on demand.
	Oct 29 21:02:03 desk0107 systemd[1]: Deactivated swap /dev/dm-1.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: VirtualBox web service API.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: irqbalance daemon providing irq balancing on MP-machines.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: VirtualBox watchdog daemon.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: VirtualBox autostart service.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: Supports the direct execution of binary formats..
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped Postfix MTA Daemon.
	Oct 29 21:02:03 desk0107 logd[5027]: [5027]: info: Pid 2123 exited
	Oct 29 21:02:03 desk0107 logd[5014]: Stopping ha_logd: stopped
	Oct 29 21:02:03 desk0107 xdm[5013]: Shutting down service kdm..done
	Oct 29 21:02:03 desk0107 sshd-custom[5016]: Shutting down SSH daemon..done
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: X Display Manager.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopped LSB: Start the sshd daemon.
	Oct 29 21:02:03 desk0107 systemd[1]: Stopping ntpd...
	Oct 29 21:02:03 desk0107 systemd[1]: Stopping ACPI Event Daemon...
	Oct 29 21:02:03 desk0107 ifdown[5012]: eth0      device: NVIDIA Corporation MCP51 Ethernet Controller (rev a3)
	Oct 29 21:02:04 desk0107 acpid[1121]: exiting
	Oct 29 21:02:04 desk0107 ifdown[5162]: eth0      device: NVIDIA Corporation MCP51 Ethernet Controller (rev a3)
	Oct 29 21:02:04 desk0107 systemd[1]: Stopping LSB: VirtualBox Linux kernel module...
	Oct 29 21:02:04 desk0107 systemd[1]: Stopped D-Bus System Message Bus.
	Oct 29 21:02:04 desk0107 systemd[1]: Stopped ntpd.
	Oct 29 21:02:04 desk0107 systemd[1]: Stopped ACPI Event Daemon.
	Oct 29 21:02:04 desk0107 vboxdrv[5169]: Stopping VirtualBox kernel modules..done
	Oct 29 21:02:04 desk0107 systemd[1]: Stopped ifup managed network interface eth0.
	Oct 29 21:02:04 desk0107 systemd[1]: Stopping system-network.slice.
	Oct 29 21:02:04 desk0107 systemd[1]: Removed slice system-network.slice.
	Oct 29 21:02:05 desk0107 mysqld_safe[2059]: 141029 21:02:05 mysqld_safe mysqld from pid file /var/cache/mariadb/mariadb.pid ended
	Oct 29 21:02:09 desk0107 systemd[1]: Stopped Getty on tty1.
	Oct 29 21:02:09 desk0107 systemd[1]: Starting Restore /run/initramfs...
	Oct 29 21:02:09 desk0107 systemd[1]: Stopping system-getty.slice.
	Oct 29 21:02:09 desk0107 systemd[1]: Stopping system-serial\x2dgetty.slice.
	Oct 29 21:02:09 desk0107 systemd[1]: Removed slice system-serial\x2dgetty.slice.
	Oct 29 21:02:09 desk0107 systemd[1]: Stopping /etc/init.d/boot.local Compatibility...
	Oct 29 21:02:09 desk0107 systemd[1]: Stopped /etc/init.d/boot.local Compatibility.
	Oct 29 21:02:09 desk0107 systemd[1]: Stopping Permit User Sessions...
	Oct 29 21:02:09 desk0107 dracut-initramfs-restore[5305]: gzip: stdin: not in gzip format
	Oct 29 21:02:09 desk0107 systemd[1]: Stopping Remote File Systems.
	Oct 29 21:02:09 desk0107 systemd[1]: Stopped target Remote File Systems.
	Oct 29 21:02:09 desk0107 systemd[1]: Stopping Fix against deadlock at shutdown...
	Oct 29 21:02:09 desk0107 dracut-initramfs-restore[5305]: cpio: premature end of archive
	Oct 29 21:02:10 desk0107 autofs[5312]: Shutting down automount ..done
	Oct 29 21:02:10 desk0107 nfs[5321]: Shutting down NFS client services:..done
	Oct 29 21:02:10 desk0107 systemd[1]: Stopping Host and Network Name Lookups.
	Oct 29 21:02:10 desk0107 systemd[1]: Stopped target Host and Network Name Lookups.
	Oct 29 21:02:10 desk0107 systemd[1]: Stopping Remote File Systems (Pre).
	Oct 29 21:02:10 desk0107 systemd[1]: Stopped target Remote File Systems (Pre).
	Oct 29 21:02:10 desk0107 systemd[1]: Stopping LSB: NFS client services...
	Oct 29 21:02:10 desk0107 nfs[5344]: Shutting down NFS client services:..done
	Oct 29 21:02:10 desk0107 systemd[1]: Stopping RPC Port Mapper.
	Oct 29 21:02:10 desk0107 systemd[1]: Stopped target RPC Port Mapper.
	Oct 29 21:02:10 desk0107 systemd[1]: Stopping Network.
	Oct 29 21:02:10 desk0107 systemd[1]: Stopped target Network.
	Oct 29 21:02:10 desk0107 systemd[1]: Stopping LSB: Configure network interfaces and set up routing...
	Oct 29 21:02:10 desk0107 network[5363]: Shutting down network interfaces:
	Oct 29 21:02:10 desk0107 systemd[1]: Stopped ifup managed network interface eth0.
	Oct 29 21:02:11 desk0107 systemd[1]: Started Restore /run/initramfs.
	Oct 29 21:02:11 desk0107 network[5363]: ..done..doneShutting down service network  .  .  .  .  .  .  .  .  .  .  .  ...done
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Basic System.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Basic System.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Slices.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Slices.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping User and Session Slice.
	Oct 29 21:02:11 desk0107 systemd[1]: Removed slice User and Session Slice.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Paths.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Paths.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Dispatch Password Requests to Console Directory Watch.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Timers.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Timers.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Daily Cleanup of Temporary Directories.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Daily Cleanup of Temporary Directories.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Sockets.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Sockets.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping PC/SC Smart Card Daemon Activation Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Closed PC/SC Smart Card Daemon Activation Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Syslog Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Closed Syslog Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping RPCbind Server Activation Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Closed RPCbind Server Activation Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping D-Bus System Message Bus Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Closed D-Bus System Message Bus Socket.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping System Initialization.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target System Initialization.
	Oct 29 21:02:11 desk0107 systemd[1]: Starting Console System Shutdown Logging...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Load/Save RF Kill Switch Status of rfkill0...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /sys/kernel/debug...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Setup Virtual Console...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Setup Virtual Console.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Apply Kernel Variables...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Apply Kernel Variables.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Load Kernel Modules...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Load Kernel Modules.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Encrypted Volumes.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Encrypted Volumes.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Swap.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Swap.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Entropy Daemon based on the HAVEGE algorithm...
	Oct 29 21:02:11 desk0107 haveged[682]: haveged: Stopping due to signal 15
	Oct 29 21:02:11 desk0107 haveged[682]: haveged starting up
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Load/Save RF Kill Switch Status of rfkill0.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Update UTMP about System Reboot/Shutdown.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped LSB: Set default boot entry if called.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Create Volatile Files and Directories...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Create Volatile Files and Directories.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Local File Systems.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped target Local File Systems.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /var/lib/mariadb...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /var/lib/mysql...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /var/chroot/ntp/proc...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /var/chroot/ntp/etc/services...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /var/chroot/ntp/etc/resolv.conf...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /var/chroot/ntp/etc/ntp.conf...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounting /boot...
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping system-systemd\x2drfkill.slice.
	Oct 29 21:02:11 desk0107 systemd[1]: Removed slice system-systemd\x2drfkill.slice.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopping Load/Save Random Seed...
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/lib/mariadb.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/lib/mysql.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/chroot/ntp/proc.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/chroot/ntp/lib.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/chroot/ntp/etc/services.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/chroot/ntp/etc/resolv.conf.
	Oct 29 21:02:11 desk0107 systemd[1]: Stopped Load/Save Random Seed.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /var/chroot/ntp/etc/ntp.conf.
	Oct 29 21:02:11 desk0107 systemd[1]: Unmounted /VirtualBox.
	Oct 29 21:02:12 desk0107 systemd[1]: Unmounting /var/cache...
	Oct 29 21:02:12 desk0107 systemd[1]: Unmounted /boot.
	Oct 29 21:02:12 desk0107 systemd[1]: Starting Unmount All Filesystems.
	Oct 29 21:02:12 desk0107 systemd[1]: Reached target Unmount All Filesystems.
	Oct 29 21:02:12 desk0107 systemd[1]: Stopping Local File Systems (Pre).
	Oct 29 21:02:12 desk0107 systemd[1]: Stopped target Local File Systems (Pre).
	Oct 29 21:02:12 desk0107 systemd[1]: Stopping Remount Root and Kernel File Systems...
	Oct 29 21:02:12 desk0107 systemd[1]: Stopped Remount Root and Kernel File Systems.
	Oct 29 21:02:12 desk0107 systemd[1]: Stopping Replay Read-Ahead Data...
	Oct 29 21:02:12 desk0107 systemd[1]: Stopped Replay Read-Ahead Data.
	Oct 29 21:02:12 desk0107 systemd[1]: Stopping Collect Read-Ahead Data...
	Oct 29 21:02:12 desk0107 systemd[1]: Stopped Collect Read-Ahead Data.
	Oct 29 21:02:12 desk0107 systemd[1]: Starting Shutdown.
	Oct 29 21:02:12 desk0107 systemd[1]: Reached target Shutdown.
	Oct 29 21:02:12 desk0107 systemd[1]: Starting /etc/init.d/halt.local Compatibility...
	Oct 29 21:02:12 desk0107 systemd[1]: Started /etc/init.d/halt.local Compatibility.
	Oct 29 21:02:12 desk0107 systemd[1]: Starting Final Step.
	Oct 29 21:02:12 desk0107 systemd[1]: Reached target Final Step.
	Oct 29 21:02:12 desk0107 systemd[1]: Starting Power-Off...
	Oct 29 21:02:12 desk0107 systemd-shutdown[1]: Sending SIGTERM to remaining processes...
	Oct 29 21:02:12 desk0107 sshd[3987]: Exiting on signal 15
	Oct 29 21:02:12 desk0107 sshd[2603]: Exiting on signal 15
	Oct 29 21:02:12 desk0107 systemd-journal[634]: Journal stopped
	-- Reboot --
	Oct 29 21:03:04 localhost systemd-journal[183]: Runtime journal is using 8.0M (max allowed 399.9M, trying to leave 599.9M free of 3.8G available → current limit 399.9M).
	Oct 29 21:03:04 localhost systemd-journal[183]: Runtime journal is using 8.0M (max allowed 399.9M, trying to leave 599.9M free of 3.8G available → current limit 399.9M).
	Oct 29 21:03:04 localhost kernel: Initializing cgroup subsys cpuset
	Oct 29 21:03:04 localhost kernel: Initializing cgroup subsys cpu
	Oct 29 21:03:04 localhost kernel: Initializing cgroup subsys cpuacct
	...


More information about the systemd-devel mailing list