pam_systemd(login:session): Failed to release session on shutdown

Ocypheum · 2015-10-12 01:44:30

This message appears in journalctl after every shutdown or reboot:

pam_systemd(login:session): Failed to release session: Interrupted system call

Here's a little more context:

Oct 11 18:41:45 arch dbus[327]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
Oct 11 18:41:45 arch systemd[1]: Starting Authorization Manager...
Oct 11 18:41:45 arch polkitd[2459]: Started polkitd version 0.113
Oct 11 18:41:45 arch polkitd[2459]: Loading rules from directory /etc/polkit-1/rules.d
Oct 11 18:41:45 arch polkitd[2459]: Loading rules from directory /usr/share/polkit-1/rules.d
Oct 11 18:41:45 arch polkitd[2459]: Finished loading, compiling and executing 1 rules
Oct 11 18:41:45 arch dbus[327]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Oct 11 18:41:45 arch systemd[1]: Started Authorization Manager.
Oct 11 18:41:45 arch polkitd[2459]: Acquired the name org.freedesktop.PolicyKit1 on the system bus
Oct 11 18:41:45 arch polkitd[2459]: Registered Authentication Agent for unix-process:2455:59992 (system bus name :1.6 [/usr/bin/pkttyagent --notify-fd 5 --fallback], object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8)
Oct 11 18:41:45 arch systemd-logind[325]: System is rebooting.
Oct 11 18:41:45 arch systemd[1]: Removed slice system-systemd\x2dfsck.slice.
Oct 11 18:41:45 arch systemd[1]: Unmounting /run/user/1000...
Oct 11 18:41:45 arch systemd[1]: Stopped target Timers.
Oct 11 18:41:45 arch systemd[1]: Deactivating swap /dev/disk/by-partuuid/b237248d-36ed-4d69-a4a2-e3c4d8ae744e...
Oct 11 18:41:45 arch polkitd[2459]: Unregistered Authentication Agent for unix-process:2455:59992 (system bus name :1.6, object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from bus)
Oct 11 18:41:45 arch systemd[1]: Stopping User Manager for UID 1000...
Oct 11 18:41:45 arch systemd[1]: Stopped target Graphical Interface.
Oct 11 18:41:45 arch login[2251]: pam_unix(login:session): session closed for user dave
Oct 11 18:41:45 arch systemd[1]: Stopped target Multi-User System.
Oct 11 18:41:45 arch polkitd[2459]: Lost the name org.freedesktop.PolicyKit1 - exiting
Oct 11 18:41:45 arch systemd[1]: Stopped Daily verification of password and group files.
Oct 11 18:41:45 arch dhcpcd[2480]: sending signal TERM to pid 2308
Oct 11 18:41:45 arch systemd[1]: Stopped target Login Prompts.
Oct 11 18:41:45 arch dhcpcd[2480]: waiting for pid 2308 to exit
Oct 11 18:41:45 arch systemd[1]: Stopping Getty on tty2...
Oct 11 18:41:45 arch dhcpcd[2308]: received SIGTERM, stopping
Oct 11 18:41:45 arch systemd[1]: Stopping Getty on tty1...
Oct 11 18:41:45 arch dhcpcd[2308]: wlp2s0: removing interface
Oct 11 18:41:45 arch systemd[1]: Stopping D-Bus System Message Bus...
Oct 11 18:41:45 arch dhcpcd[2308]: dhcpcd exited
Oct 11 18:41:45 arch systemd[1]: Stopped target Network.
Oct 11 18:41:45 arch login[2251]: pam_systemd(login:session): Failed to release session: Interrupted system call
Oct 11 18:41:45 arch systemd[1]: Stopping Automatically generated profile by wifi-menu...
Oct 11 18:41:45 arch systemd[1]: Stopped Daily man-db cache update.
Oct 11 18:41:45 arch systemd[1]: Starting Generate shutdown-ramfs...
Oct 11 18:41:45 arch systemd[1]: Stopping Authorization Manager...
Oct 11 18:41:45 arch systemd[2311]: Stopped target Default.
Oct 11 18:41:46 arch systemd[1]: run-user-1000.mount: Mount process exited, code=exited status=32
Oct 11 18:41:46 arch kernel: wl0: link down (wlp2s0)
Oct 11 18:41:45 arch systemd[1]: Stopped target Sound Card.
Oct 11 18:41:45 arch systemd[2311]: Stopped target Basic System.
Oct 11 18:41:45 arch systemd[1]: Stopped Daily rotation of log files.
Oct 11 18:41:45 arch systemd[2311]: Stopped target Paths.
Oct 11 18:41:46 arch systemd[2313]: pam_unix(systemd-user:session): session closed for user dave
Oct 11 18:41:45 arch systemd[1]: Stopping Session c1 of user dave.
Oct 11 18:41:45 arch systemd[2311]: Stopped target Timers.
Oct 11 18:41:45 arch systemd[2311]: Stopped target Sockets.
Oct 11 18:41:45 arch systemd[2311]: Closed D-Bus User Message Bus Socket.
Oct 11 18:41:45 arch systemd[2311]: Reached target Shutdown.
Oct 11 18:41:45 arch systemd[1]: Stopped D-Bus System Message Bus.
Oct 11 18:41:45 arch systemd[2311]: Starting Exit the Session...
Oct 11 18:41:46 arch systemd[2311]: Received SIGRTMIN+24 from PID 2482 (kill).
Oct 11 18:41:46 arch systemd[1]: Stopped User Manager for UID 1000.
Oct 11 18:41:46 arch systemd[1]: Stopping Permit User Sessions...
Oct 11 18:41:46 arch systemd[1]: Removed slice user-1000.slice.
Oct 11 18:41:46 arch systemd[1]: Stopped Apply Kernel Variables.
Oct 11 18:41:46 arch systemd[1]: Stopped Create Static Device Nodes in /dev.
Oct 11 18:41:46 arch systemd[1]: Stopping Load/Save Random Seed...
Oct 11 18:41:46 arch systemd[1]: Stopping Load/Save RF Kill Switch Status of rfkill0...
Oct 11 18:41:46 arch systemd[1]: Stopping Load/Save RF Kill Switch Status of rfkill1...
Oct 11 18:41:46 arch systemd[1]: Stopped target Encrypted Volumes.
Oct 11 18:41:46 arch umount[2468]: umount: /run/user/1000: target is busy
Oct 11 18:41:46 arch umount[2468]: (In some cases useful info about processes that
Oct 11 18:41:46 arch umount[2468]: use the device is found by lsof(8) or fuser(1).)
Oct 11 18:41:46 arch network[2471]: Stopping network profile 'wlp2s0-OpenWrt'...
Oct 11 18:41:46 arch network[2471]: Stopped network profile 'wlp2s0-OpenWrt'
Oct 11 18:41:46 arch systemd[1]: Stopped Setup Virtual Console.
Oct 11 18:41:46 arch systemd[1]: Stopped target Paths.
Oct 11 18:41:46 arch systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
Oct 11 18:41:46 arch systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
Oct 11 18:41:46 arch systemd[1]: Unmounted Temporary Directory.
Oct 11 18:41:46 arch systemd[1]: Stopped Load/Save RF Kill Switch Status of rfkill2.
Oct 11 18:41:46 arch systemd[1]: Stopped Update UTMP about System Boot/Shutdown.
Oct 11 18:41:46 arch systemd[1]: Stopped Load/Save Screen Backlight Brightness of backlight:intel_backlight.
Oct 11 18:41:46 arch systemd[1]: Stopped Load/Save Random Seed.
Oct 11 18:41:46 arch systemd[1]: Stopped Load/Save RF Kill Switch Status of rfkill0.
Oct 11 18:41:46 arch systemd[1]: Stopped Load/Save RF Kill Switch Status of rfkill1.
Oct 11 18:41:46 arch systemd[1]: Removed slice system-systemd\x2dbacklight.slice.
Oct 11 18:41:46 arch systemd[1]: Stopped Create Volatile Files and Directories.
Oct 11 18:41:46 arch systemd[1]: Stopped Remount Root and Kernel File Systems.
Oct 11 18:41:46 arch systemd[1]: Removed slice system-systemd\x2drfkill.slice.
Oct 11 18:41:46 arch systemd[1]: Reached target Unmount All Filesystems.
Oct 11 18:41:46 arch mkinitcpio[2472]: ==> Starting build: none
Oct 11 18:41:46 arch mkinitcpio[2472]: -> Running build hook: [sd-shutdown]
Oct 11 18:41:47 arch mkinitcpio[2472]: ==> Build complete.
Oct 11 18:41:47 arch systemd[1]: Started Generate shutdown-ramfs.
Oct 11 18:41:47 arch systemd[1]: Reached target Shutdown.
Oct 11 18:41:47 arch systemd[1]: Reached target Final Step.
Oct 11 18:41:47 arch systemd[1]: Starting Reboot...
Oct 11 18:41:47 arch systemd[1]: Shutting down.
Oct 11 18:41:47 arch systemd[1]: Hardware watchdog 'iTCO_wdt', version 0
Oct 11 18:41:47 arch systemd[1]: Set hardware watchdog to 10min.
Oct 11 18:41:47 arch kernel: watchdog watchdog0: watchdog did not stop!
Oct 11 18:41:47 arch systemd-shutdown[1]: Sending SIGTERM to remaining processes...
Oct 11 18:41:47 arch systemd-journal[177]: Journal stopped

My google fu has failed me, and I can't find anything concrete in forum search. I read `man pam_systemd`, `man pam` and `man logind.conf`. The only idea I have at this point is to set KillUserProcesses to yes in logind.conf, but I don't see why that would be necessary.

I noticed one thing that was strange though after reading `man pam_systemd`:

On login, this module ensures the following:
1. If it does not exist yet, the user runtime directory /run/user/$USER is created and its ownership changed to the user that is logging in.

This is strange because $USER is "dave", but there is no /run/user/dave on my system - only /run/user/1000. Related?

mauritiusdadd · 2015-10-12 05:55:57

Ocypheum wrote:

This is strange because $USER is "dave", but there is no /run/user/dave on my system - only /run/user/1000. Related?

Here $USER refers to your UID (or user id) and not to your user name.

However, I see that message too, but my system works fine. If you do not experience any issue, I think you can just ignore it.

Arch Linux

#1 2015-10-12 01:44:30

pam_systemd(login:session): Failed to release session on shutdown

#2 2015-10-12 05:55:57

Re: pam_systemd(login:session): Failed to release session on shutdown

Board footer