[SOLVED] Login (and every other user process) SIGKILLed at shutdown

mesaprotector · 2024-11-26 05:26:45

I sort of gave up on solving this a while ago; V1del told me it was likely my user session was somehow broken, but I couldn't figure out how. Well, I'm trying again. Relevant previous threads: https://bbs.archlinux.org/viewtopic.php?id=298940 https://bbs.archlinux.org/viewtopic.php?id=299271 Especially my last post in the last thread, where I post all of the session information from a shutdown hang back when I could still get the hangs to occur: https://bbs.archlinux.org/viewtopic.php … 8#p2195258

Doesn't happen most of the time; roughly 1 out of 5 shutdown/reboots. It's been going on for a while - I first noticed this behavior in August (when I mentioned it here), and there were cases of it going as far back as the log extended at that time (early July, if I remember right).

In August I tried messing with user@.service, changing KillMode from the default "mixed" (which the documentation admits will send SIGKILLs in some situations) to "control-group". This had the effect of eliminating the SIGKILLs but hanging shutdown until it timed out anyway; I changed it back.

I wondered if it had to do with me trapping the login shell to guarantee .bash_logout ran on exit, which was the subject of my post at that time; I recently wrote a systemd service to replace that, which as far as I can tell works perfectly. After reconfirming that the trap wasn't the root cause of the SIGKILLs, I tried changing KillMode again to "control-group", along with SendSIGKILL to "no" - but now it doesn't seem to have any effect. The SIGKILLicide seems more likely to happen when more is going on in the session, especially when sound is currently playing, but that could just be my imagination. I searched through every single instance and there was no process in common.

Searching this forum for "(login) with signal SIGKILL" finds 8 pages of posts, which I read all of earlier today. Most seem irrelevant; nearly all involve a 90-second delay before the SIGKILL. There are quite a few with KDE and some compositing hangup around 2021-22; also some still more ancient issues with GDM and Nvidia. There's only a single post other than my own with that exact string, and the person who made it was complaining about a totally unrelated issue: https://bbs.archlinux.org/viewtopic.php … 5#p2067935

Here is the shutdown process the most recent time it happened.

Nov 25 15:35:29 Marojejy systemd-logind[999]: System is powering down.
Nov 25 15:35:29 Marojejy nordvpnd[991]: 2024/11/25 15:35:29 [Info] got dbus signal - shutdown detected!
Nov 25 15:35:29 Marojejy systemd-logind[999]: The system will power off now!
Nov 25 15:35:29 Marojejy sudo[1574]: pam_unix(sudo:session): session closed for user root
Nov 25 15:35:29 Marojejy nordvpnd[991]: 2024/11/25 15:35:29 [Debug] 1 active user processes found
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Failed to kill control group /user.slice/user-1000.slice/session-1.scope, ignoring: Invalid argument
Nov 25 15:35:29 Marojejy login[1219]: pam_unix(login:session): session closed for user asuka
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1219 (login) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1521 (startx) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1536 (xinit) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1537 (Xorg) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1552 (openbox) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1562 (copyq) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1566 (kitty) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1574 (sudo) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1584 (xwallpaper) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1590 (picom) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1621 (xbindkeys) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1715 (copyq) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2165 (evsieve) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2666 (boxxy) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2667 (boxxy) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2676 (firefox) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2746 (Socket Process) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2762 (Privileged Cont) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2825 (WebExtensions) with signal SIGKILL.
Nov 25 15:35:29 Marojejy at-spi2-registryd[1734]: X connection to :0 broken (explicit kill or server shutdown).
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2892 (Utility Process) with signal SIGKILL.
Nov 25 15:35:29 Marojejy dunst[1773]: X connection to :0 broken (explicit kill or server shutdown).
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 4537 (RDD Process) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 180850 (nemo) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 197737 (Isolated Web Co) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 197740 (Isolated Web Co) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199514 (Isolated Servic) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199569 (Web Content) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204230 (Web Content) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204233 (Web Content) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204580 (slock) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 1718 (kitty:sh0) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2749 (AsyncSi~lThread) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2868 (TaskCon~ller #5) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 2901 (ProfilerChild) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 3116 (TaskCon~ller #5) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 8459 (Timer) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 10956 (speechd init) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 180853 (gdbus) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199522 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199526 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199561 (Worker Launcher) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199575 (Socket Thread) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199578 (StyleThread#2) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199580 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199582 (JS Watchdog) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199586 (ImageIO) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199587 (ImageBridgeChld) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199588 (RemoteLzyStream) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 199589 (ProcessHangMon) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204207 (StreamTrans #15) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204218 (DOM Worker) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204219 (Backgro~Pool #2) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204225 (TaskCon~ller #4) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204226 (TaskCon~ller #5) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204237 (AsyncSi~lThread) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204241 (HTML5 Parser) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204242 (StyleThread#1) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204246 (StyleThread#5) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204248 (Backgro~Pool #1) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204249 (Timer) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204250 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204251 (ImageIO) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204252 (AsyncSi~lThread) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204253 (IPC I/O Child) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204254 (ImageBridgeChld) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204255 (RemoteLzyStream) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204256 (ProcessHangMon) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204257 (ProfilerChild) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204259 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204260 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204261 (StyleThread#2) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204263 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204264 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204265 (JS Watchdog) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204266 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204267 (Timer) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204268 (RemVidChild) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204269 (ImageIO) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204270 (ImageBridgeChld) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204271 (RemoteLzyStream) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204272 (n/a) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Killing process 204273 (ProfilerChild) with signal SIGKILL.
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Session 1 of User asuka...
Nov 25 15:35:29 Marojejy flameshot[168833]: The X11 connection broke (error 1). Did the X11 server die?
Nov 25 15:35:29 Marojejy systemd[1]: Removed slice Slice /system/modprobe.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Bluetooth Support.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Graphical Interface.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Host and Network Name Lookups.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Sound Card.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Timer Units.
Nov 25 15:35:29 Marojejy systemd[1]: archlinux-keyring-wkd-sync.timer: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Refresh existing PGP keys of archlinux-keyring regularly.
Nov 25 15:35:29 Marojejy systemd[1]: man-db.timer: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Daily man-db regeneration.
Nov 25 15:35:29 Marojejy systemd[1]: pkgstats.timer: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Weekly pkgstats submission.
Nov 25 15:35:29 Marojejy systemd[1453]: dunst.service: Main process exited, code=exited, status=1/FAILURE
Nov 25 15:35:29 Marojejy systemd[1]: shadow.timer: Deactivated successfully.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Terminating
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Daily verification of password and group files.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/ldac
Nov 25 15:35:29 Marojejy systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/aptx_hd
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Daily Cleanup of Temporary Directories.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aptx_hd
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target System Time Set.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/aptx
Nov 25 15:35:29 Marojejy systemd[1453]: dunst.service: Failed with result 'exit-code'.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aptx
Nov 25 15:35:29 Marojejy systemd[1]: systemd-rfkill.socket: Deactivated successfully.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/aac
Nov 25 15:35:29 Marojejy systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aac
Nov 25 15:35:29 Marojejy systemd[1453]: dunst.service: Consumed 2.302s CPU time, 6.7M memory peak.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/opus_g
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Bluetooth service...
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/opus_g
Nov 25 15:35:29 Marojejy systemd[1453]: dbus-:1.14-org.a11y.atspi.Registry@0.service: Main process exited, code=exited, status=1/FAILURE
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/sbc
Nov 25 15:35:29 Marojejy systemd[1453]: dbus-:1.14-org.a11y.atspi.Registry@0.service: Failed with result 'exit-code'.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/sbc
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aptx_ll_1
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aptx_ll_0
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_1
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_0
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/faststream
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/faststream_duplex
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/opus_05
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/opus_05
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSink/opus_05_duplex
Nov 25 15:35:29 Marojejy bluetoothd[987]: Endpoint unregistered: sender=:1.38 path=/MediaEndpoint/A2DPSource/opus_05_duplex
Nov 25 15:35:29 Marojejy systemd[1453]: dbus-:1.14-org.a11y.atspi.Registry@0.service: Consumed 6.106s CPU time, 2.7M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: Starting Generate shutdown-ramfs...
Nov 25 15:35:29 Marojejy systemd[1]: Stopping NordVPN Daemon...
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Authorization Manager...
Nov 25 15:35:29 Marojejy dbus-broker[983]: A security policy denied :1.5 to send method call /midi/profile:org.bluez.GattProfile1.Release to :1.39.
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Sleep Daemon...
Nov 25 15:35:29 Marojejy systemd[1453]: kitty-1566-0.scope: Consumed 1min 45.382s CPU time, 1.1G memory peak.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Battery Provider Manager destroyed
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Enable Persistent Storage in systemd-networkd...
Nov 25 15:35:29 Marojejy bluetoothd[987]: Stopping SDP server
Nov 25 15:35:29 Marojejy systemd[1]: systemd-udev-load-credentials.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy bluetoothd[987]: Exit
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Load udev Rules from Credentials.
Nov 25 15:35:29 Marojejy systemd[1453]: dbus-:1.2-org.flameshot.Flameshot@0.service: Main process exited, code=exited, status=1/FAILURE
Nov 25 15:35:29 Marojejy systemd[1453]: dbus-:1.2-org.flameshot.Flameshot@0.service: Failed with result 'exit-code'.
Nov 25 15:35:29 Marojejy systemd[1453]: dbus-:1.2-org.flameshot.Flameshot@0.service: Consumed 2.716s CPU time, 9.2M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: Stopping TLP system startup/shutdown...
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Disk Manager...
Nov 25 15:35:29 Marojejy udisksd[180860]: udisks daemon version 2.10.1 exiting
Nov 25 15:35:29 Marojejy systemd[1]: run-credentials-systemd\x2dudev\x2dload\x2dcredentials.service.mount: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: bluetooth.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Bluetooth service.
Nov 25 15:35:29 Marojejy systemd[1]: sleepdaemon.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Sleep Daemon.
Nov 25 15:35:29 Marojejy systemd[1]: sleepdaemon.service: Consumed 5.866s CPU time, 3.1M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: getty@tty1.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy nordvpnd[991]: 2024/11/25 15:35:29 [Debug] 0 active user processes found
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Getty on tty1.
Nov 25 15:35:29 Marojejy systemd[1]: run-credentials-getty\x40tty1.service.mount: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: polkit.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Authorization Manager.
Nov 25 15:35:29 Marojejy systemd[1]: polkit.service: Consumed 3.854s CPU time, 5.3M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: udisks2.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Disk Manager.
Nov 25 15:35:29 Marojejy systemd-logind[999]: Session 1 logged out. Waiting for processes to exit.
Nov 25 15:35:29 Marojejy systemd[1]: Removed slice Slice /system/getty.
Nov 25 15:35:29 Marojejy dbus-broker-launch[981]: Activation request for 'org.bluez' failed.
Nov 25 15:35:29 Marojejy dbus-broker-launch[981]: Activation request for 'org.bluez' failed.
Nov 25 15:35:29 Marojejy systemd[1]: systemd-networkd-persistent-storage.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Enable Persistent Storage in systemd-networkd.
Nov 25 15:35:29 Marojejy systemd[1]: tlp.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped TLP system startup/shutdown.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Multi-User System.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped target Login Prompts.
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Deferred execution scheduler...
Nov 25 15:35:29 Marojejy avahi-daemon[986]: Got SIGTERM, quitting.
Nov 25 15:35:29 Marojejy avahi-daemon[986]: Leaving mDNS multicast group on interface wlan0.IPv6 with address 2600:1700:5b2f:810:f66a:ddff:fef3:fb83.
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Avahi mDNS/DNS-SD Stack...
Nov 25 15:35:29 Marojejy avahi-daemon[986]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.204.
Nov 25 15:35:29 Marojejy avahi-daemon[986]: Leaving mDNS multicast group on interface lo.IPv6 with address ::1.
Nov 25 15:35:29 Marojejy avahi-daemon[986]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Nov 25 15:35:29 Marojejy avahi-daemon[986]: avahi-daemon 0.8 exiting.
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Clean up directories and files at shutdown...
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Command Scheduler...
Nov 25 15:35:29 Marojejy crond[1218]: (CRON) INFO (Shutting down)
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Initialize hardware monitoring sensors...
Nov 25 15:35:29 Marojejy /usr/bin/nvidia-powerd[992]: watchEvent failure : status : 1
Nov 25 15:35:29 Marojejy systemd[1]: Stopping nvidia-powerd service...
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Postfix Mail Transport Agent...
Nov 25 15:35:29 Marojejy systemd[1]: Stopping Prompts user to add comments to certain sudo commands...
Nov 25 15:35:29 Marojejy systemd[1]: avahi-daemon.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Avahi mDNS/DNS-SD Stack.
Nov 25 15:35:29 Marojejy systemd[1]: avahi-daemon.service: Consumed 3.140s CPU time, 2M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: sudo-comment.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Prompts user to add comments to certain sudo commands.
Nov 25 15:35:29 Marojejy systemd[1]: sudo-comment.service: Consumed 2.190s CPU time, 6.3M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: atd.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Deferred execution scheduler.
Nov 25 15:35:29 Marojejy systemd[1]: atd.service: Consumed 103ms CPU time, 86.7M memory peak.
Nov 25 15:35:29 Marojejy systemd[1]: cronie.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Command Scheduler.
Nov 25 15:35:29 Marojejy mkinitcpio[205710]: ==> Starting build: 'none'
Nov 25 15:35:29 Marojejy (modprobe)[205782]: lm_sensors.service: Referenced but unset environment variable evaluates to an empty string: BUS_MODULES
Nov 25 15:35:29 Marojejy sh[205815]: rm: cannot remove '/home/asuka/.thunderbird': No such file or directory
Nov 25 15:35:29 Marojejy sh[205815]: rm: cannot remove '/home/asuka/.golly': No such file or directory
Nov 25 15:35:29 Marojejy sh[205815]: rm: cannot remove '/home/asuka/.beersmith3': No such file or directory
Nov 25 15:35:29 Marojejy sh[205815]: rm: cannot remove '/home/asuka/.Superposition': No such file or directory
Nov 25 15:35:29 Marojejy sh[205815]: rm: cannot remove '/home/asuka/.praat-dir': No such file or directory
Nov 25 15:35:29 Marojejy sh[205815]: rm: cannot remove '/home/asuka/.pki': No such file or directory
Nov 25 15:35:29 Marojejy systemd[1]: bash-logout.service: Control process exited, code=exited, status=1/FAILURE
Nov 25 15:35:29 Marojejy systemd[1]: bash-logout.service: Failed with result 'exit-code'.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Clean up directories and files at shutdown.
Nov 25 15:35:29 Marojejy mkinitcpio[205710]:   -> Running build hook: [sd-shutdown]
Nov 25 15:35:29 Marojejy postfix[205832]: postfix/postlog: stopping the Postfix mail system
Nov 25 15:35:29 Marojejy postfix/postfix-script[205832]: stopping the Postfix mail system
Nov 25 15:35:29 Marojejy postfix/master[1289]: terminating on signal 15
Nov 25 15:35:29 Marojejy systemd[1]: postfix.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Postfix Mail Transport Agent.
Nov 25 15:35:29 Marojejy systemd[1]: lm_sensors.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Stopped Initialize hardware monitoring sensors.
Nov 25 15:35:29 Marojejy mkinitcpio[205710]: ==> Build complete.
Nov 25 15:35:29 Marojejy systemd[1]: mkinitcpio-generate-shutdown-ramfs.service: Deactivated successfully.
Nov 25 15:35:29 Marojejy systemd[1]: Finished Generate shutdown-ramfs.
Nov 25 15:35:30 Marojejy nordvpnd[991]: 2024/11/25 15:35:30 [NC] stoping NC management loop
Nov 25 15:35:30 Marojejy nordvpnd[991]: 2024/11/25 15:35:30 [NC] stopping management loop
Nov 25 15:35:30 Marojejy nordvpnd[991]: 2024/11/25 15:35:30 [NC] stopped management loop
Nov 25 15:35:30 Marojejy nordvpnd[991]: 2024/11/25 15:35:30 [NC] stopped NC management loop
Nov 25 15:35:30 Marojejy systemd[1]: nordvpnd.service: Deactivated successfully.
Nov 25 15:35:30 Marojejy systemd[1]: nordvpnd.service: Unit process 205890 (ps) remains running after unit stopped.
Nov 25 15:35:30 Marojejy systemd[1]: Stopped NordVPN Daemon.
Nov 25 15:35:30 Marojejy systemd[1]: nordvpnd.service: Consumed 5min 6.608s CPU time, 223.9M memory peak.
Nov 25 15:35:31 Marojejy /usr/bin/nvidia-powerd[992]: Failed to initialize Dynamic Boost
Nov 25 15:35:31 Marojejy /usr/bin/nvidia-powerd[992]: Failed to detach GPU id 256
Nov 25 15:35:31 Marojejy systemd[1]: nvidia-powerd.service: Main process exited, code=exited, status=1/FAILURE
Nov 25 15:35:31 Marojejy systemd[1]: nvidia-powerd.service: Failed with result 'exit-code'.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped nvidia-powerd service.
Nov 25 15:35:31 Marojejy systemd[1]: nvidia-powerd.service: Consumed 2.629s CPU time, 1.9M memory peak.
Nov 25 15:35:31 Marojejy systemd[1]: session-1.scope: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped Session 1 of User asuka.
Nov 25 15:35:31 Marojejy systemd[1]: session-1.scope: Consumed 4h 46min 58.196s CPU time, 9.8G memory peak.
Nov 25 15:35:31 Marojejy systemd[1]: Stopping User Manager for UID 1000...
Nov 25 15:35:31 Marojejy systemd[1453]: Activating special unit Exit the Session...
Nov 25 15:35:31 Marojejy systemd[1453]: Removed slice Slice /app/dbus-:1.14-org.a11y.atspi.Registry.
Nov 25 15:35:31 Marojejy systemd[1453]: app-dbus\x2d:1.14\x2dorg.a11y.atspi.Registry.slice: Consumed 6.106s CPU time, 2.8M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Removed slice Slice /app/dbus-:1.2-org.flameshot.Flameshot.
Nov 25 15:35:31 Marojejy systemd[1453]: app-dbus\x2d:1.2\x2dorg.flameshot.Flameshot.slice: Consumed 2.716s CPU time, 9.2M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped target Main User Target.
Nov 25 15:35:31 Marojejy dbus-broker[1733]: Dispatched 15071 messages @ 4(±4)μs / message.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping Accessibility services bus...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping User preferences database...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping Virtual filesystem service...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping Virtual filesystem metadata service...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping Virtual filesystem service - disk device monitor...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping PipeWire PulseAudio...
Nov 25 15:35:31 Marojejy systemd[1]: run-user-1000-gvfs.mount: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Unmounted /run/user/1000/gvfs.
Nov 25 15:35:31 Marojejy systemd[1453]: gvfs-daemon.service: Killing process 180872 (pool-spawner) with signal SIGKILL.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped Accessibility services bus.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped PipeWire PulseAudio.
Nov 25 15:35:31 Marojejy systemd[1453]: pipewire-pulse.service: Consumed 1min 47.020s CPU time, 34M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped Virtual filesystem metadata service.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped User preferences database.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping Multimedia Service Session Manager...
Nov 25 15:35:31 Marojejy wireplumber[2006]: wireplumber: stopped by signal: Terminated
Nov 25 15:35:31 Marojejy wireplumber[2006]: wireplumber: disconnected from pipewire
Nov 25 15:35:31 Marojejy wireplumber[2006]: pw.core: 0x5896835b16d0: leaked proxy 0x5896836851d0 id:3
Nov 25 15:35:31 Marojejy wireplumber[2006]: pw.core: 0x5896835b16d0: leaked proxy 0x589683744540 id:8
Nov 25 15:35:31 Marojejy wireplumber[2006]: pw.core: 0x5896835b16d0: leaked proxy 0x5896837e94f0 id:9
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped Virtual filesystem service - disk device monitor.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped Virtual filesystem service.
Nov 25 15:35:31 Marojejy systemd-logind[999]: Removed session 1.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped Multimedia Service Session Manager.
Nov 25 15:35:31 Marojejy systemd[1453]: wireplumber.service: Consumed 19.916s CPU time, 14.5M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping PipeWire Multimedia Service...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped PipeWire Multimedia Service.
Nov 25 15:35:31 Marojejy systemd[1453]: pipewire.service: Consumed 37.662s CPU time, 12.4M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped target Basic System.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped target Paths.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped target Sockets.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped target Timers.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed GnuPG network certificate management daemon.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Nov 25 15:35:31 Marojejy systemd[1453]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Nov 25 15:35:31 Marojejy systemd[1453]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Nov 25 15:35:31 Marojejy systemd[1453]: Closed GnuPG cryptographic agent and passphrase cache.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed GnuPG public key management service.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed p11-kit server.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed PipeWire PulseAudio.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed PipeWire Multimedia System Sockets.
Nov 25 15:35:31 Marojejy dbus-broker[1540]: Dispatched 2818 messages @ 5(±8)μs / message.
Nov 25 15:35:31 Marojejy systemd[1453]: Stopping D-Bus User Message Bus...
Nov 25 15:35:31 Marojejy systemd[1453]: Stopped D-Bus User Message Bus.
Nov 25 15:35:31 Marojejy systemd[1453]: Removed slice User Core Session Slice.
Nov 25 15:35:31 Marojejy systemd[1453]: session.slice: Consumed 2min 45.919s CPU time, 71.3M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Closed D-Bus User Message Bus Socket.
Nov 25 15:35:31 Marojejy systemd[1453]: Removed slice User Application Slice.
Nov 25 15:35:31 Marojejy systemd[1453]: app.slice: Consumed 11.163s CPU time, 17.6M memory peak.
Nov 25 15:35:31 Marojejy systemd[1453]: Reached target Shutdown.
Nov 25 15:35:31 Marojejy systemd[1453]: Finished Exit the Session.
Nov 25 15:35:31 Marojejy systemd[1453]: Reached target Exit the Session.
Nov 25 15:35:31 Marojejy (sd-pam)[1455]: pam_unix(systemd-user:session): session closed for user asuka
Nov 25 15:35:31 Marojejy systemd[1]: user@1000.service: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped User Manager for UID 1000.
Nov 25 15:35:31 Marojejy systemd[1]: user@1000.service: Consumed 6min 4.878s CPU time, 1.3G memory peak.
Nov 25 15:35:31 Marojejy systemd[1]: Stopping Permit User Sessions...
Nov 25 15:35:31 Marojejy systemd[1]: Stopping User Runtime Directory /run/user/1000...
Nov 25 15:35:31 Marojejy systemd[1]: run-user-1000.mount: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Unmounted /run/user/1000.
Nov 25 15:35:31 Marojejy systemd[1]: systemd-user-sessions.service: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped Permit User Sessions.
Nov 25 15:35:31 Marojejy systemd[1]: user-runtime-dir@1000.service: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped User Runtime Directory /run/user/1000.
Nov 25 15:35:31 Marojejy systemd[1]: Removed slice User Slice of UID 1000.
Nov 25 15:35:31 Marojejy systemd[1]: user-1000.slice: Consumed 4h 53min 3.102s CPU time, 10.2G memory peak.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped target Network.
Nov 25 15:35:31 Marojejy iwd[988]: Terminate
Nov 25 15:35:31 Marojejy iwd[988]: Removing scan context for wdev 2
Nov 25 15:35:31 Marojejy systemd[1]: Stopping Wireless service...
Nov 25 15:35:31 Marojejy systemd[1]: Stopping User Login Management...
Nov 25 15:35:31 Marojejy systemd[1]: Stopping Network Configuration...
Nov 25 15:35:31 Marojejy systemd-networkd[611]: wlan0: DHCP lease lost
Nov 25 15:35:31 Marojejy systemd[1]: Stopping TPM PCR Barrier (User)...
Nov 25 15:35:31 Marojejy kernel: wlan0: deauthenticating from 06:93:97:5e:d6:63 by local choice (Reason: 3=DEAUTH_LEAVING)
Nov 25 15:35:31 Marojejy systemd-pcrextend[205927]: Extended PCR index 11 with 'shutdown' (banks sha256).
Nov 25 15:35:31 Marojejy systemd[1]: systemd-pcrphase.service: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped TPM PCR Barrier (User).
Nov 25 15:35:31 Marojejy systemd[1]: Stopped target Remote File Systems.
Nov 25 15:35:31 Marojejy systemd[1]: systemd-logind.service: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped User Login Management.
Nov 25 15:35:31 Marojejy systemd-networkd[611]: wlan0: DHCPv6 lease lost
Nov 25 15:35:31 Marojejy systemd-networkd[611]: wlan0: Link DOWN
Nov 25 15:35:31 Marojejy systemd-networkd[611]: wlan0: Lost carrier
Nov 25 15:35:31 Marojejy systemd[1]: systemd-networkd.service: Deactivated successfully.
Nov 25 15:35:31 Marojejy systemd[1]: Stopped Network Configuration.
Nov 25 15:35:31 Marojejy systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully.
Nov 25 15:35:32 Marojejy iwd[988]: D-Bus disconnected, quitting...
Nov 25 15:35:32 Marojejy systemd[1]: iwd.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped Wireless service.
Nov 25 15:35:32 Marojejy systemd[1]: iwd.service: Consumed 7.716s CPU time, 4M memory peak.
Nov 25 15:35:32 Marojejy systemd[1]: run-credentials-iwd.service.mount: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Basic System.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Preparation for Network.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Path Units.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Slice Units.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice User and Session Slice.
Nov 25 15:35:32 Marojejy systemd[1]: user.slice: Consumed 4h 53min 3.102s CPU time, 10.2G memory peak.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Socket Units.
Nov 25 15:35:32 Marojejy systemd[1]: avahi-daemon.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed Avahi mDNS/DNS-SD Stack Activation Socket.
Nov 25 15:35:32 Marojejy systemd[1]: cups.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed CUPS Scheduler.
Nov 25 15:35:32 Marojejy systemd[1]: dirmngr@etc-pacman.d-gnupg.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed GnuPG network certificate management daemon for /etc/pacman.d/gnupg.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice Slice /system/dirmngr.
Nov 25 15:35:32 Marojejy systemd[1]: gpg-agent-browser@etc-pacman.d-gnupg.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers) for /etc/pacman.d/gnupg.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent-browser.
Nov 25 15:35:32 Marojejy systemd[1]: gpg-agent-extra@etc-pacman.d-gnupg.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed GnuPG cryptographic agent and passphrase cache (restricted) for /etc/pacman.d/gnupg.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent-extra.
Nov 25 15:35:32 Marojejy systemd[1]: gpg-agent-ssh@etc-pacman.d-gnupg.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed GnuPG cryptographic agent (ssh-agent emulation) for /etc/pacman.d/gnupg.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent-ssh.
Nov 25 15:35:32 Marojejy systemd[1]: gpg-agent@etc-pacman.d-gnupg.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed GnuPG cryptographic agent and passphrase cache for /etc/pacman.d/gnupg.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent.
Nov 25 15:35:32 Marojejy systemd[1]: keyboxd@etc-pacman.d-gnupg.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed GnuPG public key management service for /etc/pacman.d/gnupg.
Nov 25 15:35:32 Marojejy systemd[1]: Removed slice Slice /system/keyboxd.
Nov 25 15:35:32 Marojejy systemd[1]: nordvpnd.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed NordVPN Daemon Socket.
Nov 25 15:35:32 Marojejy systemd[1]: sshd-unix-local.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
Nov 25 15:35:32 Marojejy systemd[1]: systemd-hostnamed.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed Hostname Service Socket.
Nov 25 15:35:32 Marojejy systemd[1]: systemd-networkd.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed Network Service Netlink Socket.
Nov 25 15:35:32 Marojejy dbus-broker[983]: Dispatched 78747 messages @ 8(±11)μs / message.
Nov 25 15:35:32 Marojejy systemd[1]: Stopping D-Bus System Message Bus...
Nov 25 15:35:32 Marojejy systemd[1]: systemd-network-generator.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped Generate network units from Kernel command line.
Nov 25 15:35:32 Marojejy systemd[1]: Stopping TPM PCR Barrier (Initialization)...
Nov 25 15:35:32 Marojejy systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: dbus-broker.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped D-Bus System Message Bus.
Nov 25 15:35:32 Marojejy systemd[1]: dbus-broker.service: Consumed 5.433s CPU time, 5.5M memory peak.
Nov 25 15:35:32 Marojejy systemd[1]: dbus.socket: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Closed D-Bus System Message Bus Socket.
Nov 25 15:35:32 Marojejy systemd-pcrextend[205946]: Extended PCR index 11 with 'final' (banks sha256).
Nov 25 15:35:32 Marojejy systemd[1]: systemd-pcrphase-sysinit.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped TPM PCR Barrier (Initialization).
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target System Initialization.
Nov 25 15:35:32 Marojejy systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Local Encrypted Volumes.
Nov 25 15:35:32 Marojejy systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
Nov 25 15:35:32 Marojejy systemd[1]: systemd-ask-password-wall.path: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Local Integrity Protected Volumes.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Local Verity Protected Volumes.
Nov 25 15:35:32 Marojejy systemd[1]: Stopping Load/Save Screen Backlight Brightness of backlight:nvidia_wmi_ec_backlight...
Nov 25 15:35:32 Marojejy systemd[1]: Stopping Load/Save Screen Backlight Brightness of leds:platform::kbd_backlight...
Nov 25 15:35:32 Marojejy systemd[1]: Stopping Load/Save Screen Backlight Brightness of leds:platform::kbd_backlight_1...
Nov 25 15:35:32 Marojejy systemd[1]: systemd-boot-random-seed.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped Update Boot Loader Random Seed.
Nov 25 15:35:32 Marojejy systemd[1]: systemd-pcrmachine.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped TPM PCR Machine ID Measurement.
Nov 25 15:35:32 Marojejy systemd[1]: Stopping Load/Save OS Random Seed...
Nov 25 15:35:32 Marojejy systemd[1]: Stopping Network Name Resolution...
Nov 25 15:35:32 Marojejy systemd[1]: Stopping Network Time Synchronization...
Nov 25 15:35:32 Marojejy systemd[1]: systemd-tpm2-setup.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped TPM SRK Setup.
Nov 25 15:35:32 Marojejy systemd[1]: systemd-tpm2-setup-early.service: Deactivated successfully.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped Early TPM SRK Setup.
Nov 25 15:35:32 Marojejy systemd[1]: Stopped target Trusted Platform Module.
Nov 25 15:35:33 Marojejy systemd[1]: Stopping Record System Boot/Shutdown in UTMP...
Nov 25 15:35:33 Marojejy systemd[1]: systemd-timesyncd.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Network Time Synchronization.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-backlight@leds:platform::kbd_backlight.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Load/Save Screen Backlight Brightness of leds:platform::kbd_backlight.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-backlight@backlight:nvidia_wmi_ec_backlight.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Load/Save Screen Backlight Brightness of backlight:nvidia_wmi_ec_backlight.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-backlight@leds:platform::kbd_backlight_1.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Load/Save Screen Backlight Brightness of leds:platform::kbd_backlight_1.
Nov 25 15:35:33 Marojejy systemd[1]: Removed slice Slice /system/systemd-backlight.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-resolved.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Network Name Resolution.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-resolved.service: Consumed 10.424s CPU time, 6.8M memory peak.
Nov 25 15:35:33 Marojejy systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-sysctl.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Apply Kernel Variables.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-coredump.socket: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Closed Process Core Dump Socket.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-modules-load.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Load Kernel Modules.
Nov 25 15:35:33 Marojejy systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-random-seed.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Load/Save OS Random Seed.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-update-utmp.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Record System Boot/Shutdown in UTMP.
Nov 25 15:35:33 Marojejy systemd[1]: Stopping Security Audit Logging Service...
Nov 25 15:35:33 Marojejy auditd[974]: The audit daemon is exiting.
Nov 25 15:35:33 Marojejy systemd[1]: auditd.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Security Audit Logging Service.
Nov 25 15:35:33 Marojejy kernel: kauditd_printk_skb: 785 callbacks suppressed
Nov 25 15:35:33 Marojejy kernel: audit: type=1305 audit(1732577733.099:692): op=set audit_pid=0 old=974 auid=4294967295 ses=4294967295 subj=unconfined res=1
Nov 25 15:35:33 Marojejy kernel: audit: type=1300 audit(1732577733.099:692): arch=c000003e syscall=44 success=yes exit=60 a0=3 a1=7ffec8bec4a0 a2=3c a3=0 items=0 ppid=1 pid=974 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/usr/bin/auditd" subj=unconfined key=(null)
Nov 25 15:35:33 Marojejy kernel: audit: type=1327 audit(1732577733.099:692): proctitle="/usr/bin/auditd"
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.099:693): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=auditd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Create System Files and Directories.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped target Local File Systems.
Nov 25 15:35:33 Marojejy systemd[1]: Unmounting /efi...
Nov 25 15:35:33 Marojejy systemd[1]: Unmounting /home/asuka/media...
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.099:694): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy systemd[1]: Unmounting Temporary Directory /tmp...
Nov 25 15:35:33 Marojejy systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: tmp.mount: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Unmounted Temporary Directory /tmp.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped target Swaps.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivating swap /dev/disk/by-diskseq/1-part3...
Nov 25 15:35:33 Marojejy kernel: EXT4-fs (nvme0n1p6): unmounting filesystem fb9ba13d-f683-47ab-a426-8c7978592375.
Nov 25 15:35:33 Marojejy systemd[1]: home-asuka-media.mount: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Unmounted /home/asuka/media.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-fsck@dev-disk-by\x2duuid-fb9ba13d\x2df683\x2d47ab\x2da426\x2d8c7978592375.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped File System Check on /dev/disk/by-uuid/fb9ba13d-f683-47ab-a426-8c7978592375.
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.196:695): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-fsck@dev-disk-by\x2duuid-fb9ba13d\x2df683\x2d47ab\x2da426\x2d8c7978592375 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart-by\x2dpartuuid-e5aaad33\x2d41bd\x2d4f84\x2dabe8\x2deb1881c4bc71.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part/by-partuuid/e5aaad33-41bd-4f84-abe8-eb1881c4bc71.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2did-nvme\x2dINTEL_SSDPEK1A118GA_PHOC331400BP118B\x2dpart3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-id/nvme-INTEL_SSDPEK1A118GA_PHOC331400BP118B-part3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2ddiskseq-1\x2dpart3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-diskseq/1-part3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2dpartuuid-e5aaad33\x2d41bd\x2d4f84\x2dabe8\x2deb1881c4bc71.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-partuuid/e5aaad33-41bd-4f84-abe8-eb1881c4bc71.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2did-nvme\x2deui.5cd2e449a94f0100\x2dpart3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-id/nvme-eui.5cd2e449a94f0100-part3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart-by\x2dpartnum-3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part/by-partnum/3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2did-nvme\x2dINTEL_SSDPEK1A118GA_PHOC331400BP118B_1\x2dpart3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-id/nvme-INTEL_SSDPEK1A118GA_PHOC331400BP118B_1-part3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart-by\x2duuid-c713acd7\x2d942b\x2d4948\x2d9cc3\x2d5c7d8636a30b.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part/by-uuid/c713acd7-942b-4948-9cc3-5c7d8636a30b.
Nov 25 15:35:33 Marojejy systemd[1]: dev-nvme1n1p3.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/nvme1n1p3.
Nov 25 15:35:33 Marojejy systemd[1]: dev-disk-by\x2duuid-c713acd7\x2d942b\x2d4948\x2d9cc3\x2d5c7d8636a30b.swap: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Deactivated swap /dev/disk/by-uuid/c713acd7-942b-4948-9cc3-5c7d8636a30b.
Nov 25 15:35:33 Marojejy systemd[1]: efi.mount: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Unmounted /efi.
Nov 25 15:35:33 Marojejy systemd[1]: Reached target Unmount All Filesystems.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-fsck@dev-disk-by\x2duuid-CEF9\x2d64E0.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped File System Check on /dev/disk/by-uuid/CEF9-64E0.
Nov 25 15:35:33 Marojejy systemd[1]: Removed slice Slice /system/systemd-fsck.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped target Preparation for Local File Systems.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-remount-fs.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Remount Root and Kernel File Systems.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped File System Check on Root Device.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Create Static Device Nodes in /dev.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
Nov 25 15:35:33 Marojejy systemd[1]: Reached target System Shutdown.
Nov 25 15:35:33 Marojejy systemd[1]: Reached target Late Shutdown Services.
Nov 25 15:35:33 Marojejy systemd[1]: systemd-poweroff.service: Deactivated successfully.
Nov 25 15:35:33 Marojejy systemd[1]: Finished System Power Off.
Nov 25 15:35:33 Marojejy systemd[1]: Reached target System Power Off.
Nov 25 15:35:33 Marojejy systemd[1]: Shutting down.
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.366:696): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-fsck@dev-disk-by\x2duuid-CEF9\x2d64E0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.366:697): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.366:698): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy kernel: audit: type=1131 audit(1732577733.366:699): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Nov 25 15:35:33 Marojejy systemd-shutdown[1]: Syncing filesystems and block devices.
Nov 25 15:35:33 Marojejy systemd-shutdown[1]: Sending SIGTERM to remaining processes...
Nov 25 15:35:33 Marojejy systemd-journald[276]: Received SIGTERM from PID 1 (systemd-shutdow).
Nov 25 15:35:33 Marojejy systemd-journald[276]: Journal stopped

EDIT: It occured to me that I've been saving my root partition in full every month going back to February, but being that /var/log/journal is in some binary format I don't expect it to be easy to search through what's saved on an external drive. It does seem to contain enough text that maybe I can just grep SIGKILL or something; of course it's entirely possible that the SIGKILLs go back even further.

Last edited by mesaprotector (2024-11-30 20:41:26)

seth · 2024-11-26 20:05:58

EDIT: It occured to me that I've been saving my root partition in full every month going back to February, but being that /var/log/journal is in some binary format I don't expect it to be easy to search through what's saved on an external drive. It does seem to contain enough text that maybe I can just grep SIGKILL or something; of course it's entirely possible that the SIGKILLs go back even further.

https://wiki.archlinux.org/title/System … al_to_view

Nov 25 15:35:29 Marojejy sudo[1574]: pam_unix(sudo:session): session closed for user root
Nov 25 15:35:29 Marojejy nordvpnd[991]: 2024/11/25 15:35:29 [Debug] 1 active user processes found
Nov 25 15:35:29 Marojejy systemd[1]: session-1.scope: Failed to kill control group /user.slice/user-1000.slice/session-1.scope, ignoring: Invalid argument
Nov 25 15:35:29 Marojejy login[1219]: pam_unix(login:session): session closed for user asuka

Try to replicate this w/o nordvpn (resp. check your journals for the SIGKILL condition w/o nordvpn being in use)

mesaprotector · 2024-11-26 21:43:30

I admit I was really hoping it would be as simple as installing a better VPN. Sadly with nordvpn.socket and nordvpn.service disabled (and stopped) I was still able to get the SIGKILL to happen on the fifth try.

Thank you for the tip about journalctl -D. There is no SIGKILL in any of the journals through the 4th of June. After this, I have no logs until the 15th of June, and then I have everything from then to the present. The pattern is very odd:

June 16th
June 30th
July 1st
July 2nd
July 12th
August 13th
August 19th... and then after this one it happens frequently, up until now.

The bug starting out as rare and becoming common almost reminds me of something that'd be caused by hardware failure. I have no idea if the 16th is the first example, of course, since there's a ten-day gap in the journal just before that (I used to have a lower size limit for it). I'll be looking very carefully through my pacman log for the first two weeks of June.

EDIT: Out of the new packages I installed between the 4th and 16th, the most suspicious to me is probably kvantum, followed by maybe slock-git (I was already using regular slock before that though). A number of the posts here about shutdown hangs involved kwin issues with themes using qml or... something, I don't really know anything about it. My .xinitrc starts copyq, which is a qt5 application even though I run it exclusively in the background, so maybe something relevant.

Last edited by mesaprotector (2024-11-26 22:31:04)

seth · 2024-11-27 08:10:36

In August I tried messing with user@.service, changing KillMode from the default "mixed" (which the documentation admits will send SIGKILLs in some situations) to "control-group". This had the effect of eliminating the SIGKILLs but hanging shutdown until it timed out anyway; I changed it back.

Still, there's an effort to kill the controlgroup.
What if you set this to process?

Also please post your .xinitrc and describe how exactly it's invoked (manually or through some - and what - autologin mechanism or some DM)

mesaprotector · 2024-11-27 17:01:41

seth wrote:

Still, there's an effort to kill the controlgroup.
What if you set this to process?

Ha. In that case systemd complains about the bad setting because apparently only control-group and mixed are allowed (something about pam), and user@asuka.service won't start. Of course then I can't even start X at all.

seth wrote:

Also please post your .xinitrc and describe how exactly it's invoked (manually or through some - and what - autologin mechanism or some DM)

I log in to the TTY with no display manager, and type "startx" (or "startxquiet", which uses a nearly identical .xinitrc except that it doesn't start the chat applications). I noticed just now that I inadvertently put a bashism ('&>') into it, even though it has a #!/bin/sh shebang by default; that line was not present when I started seeing the bug though. Actually I didn't edit .xinitrc at all from May 22nd until some point in August. The edit on May 22nd appears to be to add mousekeys.sh, volumeicon.sh (which works around a bug with volumeicon and pipewire), and to change the kitty line to use setsid, which I now realize is improperly quoted, though I don't see how that would cause all these problems. In any case the alternate quiet .xinitrc does not have the setsid line.

The file paths I included are not in the actual files; other than that I didn't change anything.

#/home/asuka/.xinitrc
#!/bin/sh

userresources=$HOME/.Xresources
usermodmap=$HOME/.Xmodmap
sysresources=/etc/X11/xinit/.Xresources
sysmodmap=/etc/X11/xinit/.Xmodmap

# merge in defaults and keymaps

if [ -f $sysresources ]; then







    xrdb -merge $sysresources

fi

if [ -f $sysmodmap ]; then
    xmodmap $sysmodmap
fi

if [ -f "$userresources" ]; then







    xrdb -merge "$userresources"

fi

if [ -f "$usermodmap" ]; then
    xmodmap "$usermodmap"
fi

# start some nice programs

if [ -d /etc/X11/xinit/xinitrc.d ] ; then
 for f in /etc/X11/xinit/xinitrc.d/?*.sh ; do
  [ -x "$f" ] && . "$f"
 done
 unset f
fi

echo 'journalctl -b -1 | grep -q "login) with signal SIGKILL" && echo "sigkill on previous shutdown!"' | at now & 

touch /tmp/discordstatus &
picom -b &
xwallpaper --daemon --stretch ~/.local/share/slock/gs0.jpg &
xss-lock -l slock &
tint2 &
copyq &
aa-notify -p -s 1 -w 60 -f /var/log/audit/audit.log &
echo /tmp/discordstatus | entr -np boxxy discord &> /dev/null &
kitty --title "asuka@Marojejy" --hold sh -c 'setsid kitty --title "Matrix" sh -c 'gomuks' &' &
hunter.sh &
boxxy thunderbird &
boxxy discord &

xkbset exp =mousekeys &
xbindkeys &
xmodmap -e "pointer = 3 2 1" &
numlockx &
sudo /usr/local/bin/mousekeys.sh &
kboptions.sh &
volumeicon.sh &
lxpolkit &

exec openbox

#/usr/local/bin/mousekeys.sh
#!/bin/bash
sleep 3 && 
evsieve --input /dev/input/by-id/usb-ITE_Tech._Inc._ITE_Device_8176_-event-kbd \
	--hook key:numlock exec-shell="bash /usr/local/bin/xkbset.sh"

#/usr/local/bin/xkbset.sh
#!/bin/bash
sleep 0.5
if
	[ "`cat /sys/class/leds/*numlock/brightness | tail -n1`" = '1' ]; then
	xkbset -mousekeys
else
	xkbset mousekeys
fi

#/home/asuka/.local/bin/hunter.sh
#!/bin/bash
sleep 1 &&
if [ "`pgrep -x "firefox" | wc -l`" = 0 ]; then 
	rm -rf /home/asuka/.mozilla
fi

#/home/asuka/.local/bin/kboptions.sh
#!/bin/bash
sleep 5 &&
setxkbmap -option lv3:ralt_switch_multikey &&
xmodmap -e "keycode 96 shift = F12 dead_greek" &&
xinput list --id-only keyboard:"ITE Tech. Inc. ITE Device(8176) Keyboard" \
> /tmp/kbid

#/home/asuka/.local/bin/volumeicon.sh
#!/bin/bash
sleep 5 &&
volumeicon

Last edited by mesaprotector (2024-11-27 17:21:07)

seth · 2024-11-27 17:28:13

boxxy kinda yells at me…

mesaprotector · 2024-11-27 17:35:35

boxxy does feel like the kind of thing that would cause problems with exit handling, except that I only installed it for the first time on August 27th, after this bug was already happening every 3-5 shutdowns. I hope I haven't been overemphasizing the timeline, but it feels like important evidence to me. I'll try not launching anything through it (the quiet .xinitrc doesn't have anything that uses it, and I'll avoid starting firefox & co.) and see if I can recreate the bug anyway.

It's also true that boxxy is exactly what caused me to notice this bug in the first place, because it being SIGKILLed gives it no change to clean up the dotfiles it's redirecting; the only other major symptom is lost shell history, which I'm currently working around with history -a.

EDIT: I think I have a better guess. I think it's sudo mousekeys.sh causing the issue; I have an exception in /etc/sudoers allowing user 'asuka' to run this script, but perhaps that doesn't mean 'asuka' can terminate it. I can probably write a systemd service that monitors a file in /tmp and runs it on a change; then have .xinitrc just touch that file. I didn't do that previously because mousekeys would fail and exit if it tried running before X was up, and in May I wasn't familiar with stuff like inotify/entr.

Last edited by mesaprotector (2024-11-27 17:59:39)

seth · 2024-11-27 18:29:11

systemd[1]: session-1.scope: Killing process 1219 (login) with signal SIGKILL.

Who own's PID1?

The nasty part is "Doesn't happen most of the time" - otherwise you could just bisect the session processes.

The only other match is btw https://bbs.archlinux.org/viewtopic.php?id=294858 where this happened at the tail of more severe issues during the session.

mesaprotector · 2024-11-28 02:22:19

Something very, very strange is going on here.

Seth's post made me think maybe I should be searching not for login getting SIGKILLed, but for "Failed to kill control group". And it turns out that message periodically shows up going ALL the way back in the log. Only that from February through May, it exclusively happened to pulseaudio. Then in late May to mid June, after I'd dropped pulseaudio in favor of pipewire, it exclusively happened to gvfs-daemon. And then starting in mid-June, it switched to the session getting killed. Of course I'd much rather my audio get unceremoniously executed than my entire login session, but I'm under the impression that "failed to kill control group: invalid argument" should, generally, not happen at all. So perhaps whatever part of systemd is doing the killing is where the problem lies.

In addition, there is some possible relation to bluetooth, as a few of the mass SIGKILLs have bluetooth messages perfectly interleaved (see below).

Jun 30 08:08:15 Marojejy systemd-logind[803]: The system will power off now!
Jun 30 08:08:15 Marojejy systemd-logind[803]: System is powering down.
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Failed to kill control group /user.slice/user-1000.slice/session-1.scope, ignoring: Invalid argument
Jun 30 08:08:15 Marojejy sudo[1344]: pam_unix(sudo:session): session closed for user root
Jun 30 08:08:15 Marojejy nordvpnd[911]: 2024/06/30 08:08:15 [Info] unsetting DNS
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 913 (login) with signal SIGKILL.
Jun 30 08:08:15 Marojejy at-spi2-registryd[1562]: X connection to :0 broken (explicit kill or server shutdown).
Jun 30 08:08:15 Marojejy polkitd[1374]: 08:08:15.859: Unregistered Authentication Agent for unix-session:1 (system bus name :1.21, object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from bus)
Jun 30 08:08:15 Marojejy polkitd[1374]: Unregistered Authentication Agent for unix-session:1 (system bus name :1.21, object path /org/freedesktop/PolicyKit1/AuthenticationAgent, locale en_US.UTF-8) (disconnected from bus)
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1287 (bash) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Terminating
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1300 (startx) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/ldac
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1315 (xinit) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/aptx_hd
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1316 (Xorg) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aptx_hd
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1327 (openbox) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/aptx
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1335 (tint2) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aptx
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1338 (kitty) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/aac
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1344 (sudo) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aac
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1347 (lxpolkit) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/opus_g
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1352 (sudo) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/opus_g
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1373 (xbindkeys) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/sbc
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1414 (aa-notify) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/sbc
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1444 (copyq) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aptx_ll_1
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1477 (evsieve) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aptx_ll_0
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1354 (picom) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_1
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1318 (Xorg:disk$0) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_0
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1383 (QXcbEventQueue) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/faststream
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1385 (picom:gdrv0) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/faststream_duplex
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1425 (kitty:disk$0) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/opus_05
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1426 (kitty:sh0) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/opus_05
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1441 (QXcbEventQueue) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSink/opus_05_duplex
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1454 (tint2) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Endpoint unregistered: sender=:1.27 path=/MediaEndpoint/A2DPSource/opus_05_duplex
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Killing process 1558 (volumeicon) with signal SIGKILL.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Battery Provider Manager destroyed
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Session 1 of User asuka...
Jun 30 08:08:15 Marojejy bluetoothd[834]: Stopping SDP server
Jun 30 08:08:15 Marojejy systemd[1]: Removed slice Slice /system/modprobe.
Jun 30 08:08:15 Marojejy bluetoothd[834]: Exit
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Bluetooth Support.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Graphical Interface.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Host and Network Name Lookups.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Sound Card.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Timer Units.
Jun 30 08:08:15 Marojejy systemd[1]: archlinux-keyring-wkd-sync.timer: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Refresh existing PGP keys of archlinux-keyring regularly.
Jun 30 08:08:15 Marojejy systemd[1]: man-db.timer: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Daily man-db regeneration.
Jun 30 08:08:15 Marojejy systemd[1]: pkgstats.timer: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Weekly pkgstats submission.
Jun 30 08:08:15 Marojejy systemd[1]: shadow.timer: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Daily verification of password and group files.
Jun 30 08:08:15 Marojejy systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Daily Cleanup of Temporary Directories.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target System Time Set.
Jun 30 08:08:15 Marojejy systemd[1]: systemd-rfkill.socket: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Bluetooth service...
Jun 30 08:08:15 Marojejy systemd[1]: Starting Generate shutdown-ramfs...
Jun 30 08:08:15 Marojejy systemd[1]: Stopping NordVPN Daemon...
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Authorization Manager...
Jun 30 08:08:15 Marojejy systemd[1278]: dbus-:1.21-org.a11y.atspi.Registry@0.service: Main process exited, code=exited, status=1/FAILURE
Jun 30 08:08:15 Marojejy systemd[1278]: dbus-:1.21-org.a11y.atspi.Registry@0.service: Failed with result 'exit-code'.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Sleep Daemon...
Jun 30 08:08:15 Marojejy dbus-broker[795]: A security policy denied :1.7 to send method call /midi/profile:org.bluez.GattProfile1.Release to :1.28.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Enable Persistent Storage in systemd-networkd...
Jun 30 08:08:15 Marojejy systemd[1]: systemd-udev-load-credentials.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Load udev Rules from Credentials.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping TLP system startup/shutdown...
Jun 30 08:08:15 Marojejy systemd[1]: run-credentials-systemd\x2dudev\x2dload\x2dcredentials.service.mount: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: sleepdaemon.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Sleep Daemon.
Jun 30 08:08:15 Marojejy systemd[1]: bluetooth.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Bluetooth service.
Jun 30 08:08:15 Marojejy systemd[1]: getty@tty1.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Getty on tty1.
Jun 30 08:08:15 Marojejy systemd[1]: run-credentials-getty\x40tty1.service.mount: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: polkit.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Authorization Manager.
Jun 30 08:08:15 Marojejy systemd[1]: session-1.scope: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Session 1 of User asuka.
Jun 30 08:08:15 Marojejy systemd-logind[803]: Session 1 logged out. Waiting for processes to exit.
Jun 30 08:08:15 Marojejy nordvpnd[911]: 2024/06/30 08:08:15 [Info] unset dns for interface [] using: resolved
Jun 30 08:08:15 Marojejy nordvpnd[911]: 2024/06/30 08:08:15 [NC] stop
Jun 30 08:08:15 Marojejy systemd[1]: Removed slice Slice /system/getty.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping User Manager for UID 1000...
Jun 30 08:08:15 Marojejy dbus-broker-launch[793]: Activation request for 'org.bluez' failed.
Jun 30 08:08:15 Marojejy systemd-logind[803]: Removed session 1.
Jun 30 08:08:15 Marojejy systemd[1]: nordvpnd.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped NordVPN Daemon.
Jun 30 08:08:15 Marojejy systemd[1]: systemd-networkd-persistent-storage.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Enable Persistent Storage in systemd-networkd.
Jun 30 08:08:15 Marojejy systemd[1278]: Activating special unit Exit the Session...
Jun 30 08:08:15 Marojejy dbus-broker-launch[793]: Activation request for 'org.bluez' failed.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Network is Online.
Jun 30 08:08:15 Marojejy systemd[1278]: Removed slice Slice /app/dbus-:1.21-org.a11y.atspi.Registry.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped target Main User Target.
Jun 30 08:08:15 Marojejy dbus-broker[1554]: Dispatched 863 messages @ 4(±4)μs / message.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopping Accessibility services bus...
Jun 30 08:08:15 Marojejy systemd[1278]: Stopping Virtual filesystem service...
Jun 30 08:08:15 Marojejy systemd[1278]: Stopping PipeWire PulseAudio...
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped PipeWire PulseAudio.
Jun 30 08:08:15 Marojejy systemd[1]: run-user-1000-gvfs.mount: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Unmounted /run/user/1000/gvfs.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped Accessibility services bus.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopping Multimedia Service Session Manager...
Jun 30 08:08:15 Marojejy wireplumber[1413]: wireplumber: stopped by signal: Terminated
Jun 30 08:08:15 Marojejy wireplumber[1413]: wireplumber: disconnected from pipewire
Jun 30 08:08:15 Marojejy wireplumber[1413]: pw.core: 0x60e68a9cd900: leaked proxy 0x60e68aa6c090 id:10
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped Multimedia Service Session Manager.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopping PipeWire Multimedia Service...
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped PipeWire Multimedia Service.
Jun 30 08:08:15 Marojejy systemd[1]: tlp.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped Virtual filesystem service.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped TLP system startup/shutdown.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped target Basic System.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped target Paths.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped target Sockets.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped target Timers.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Multi-User System.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped target Login Prompts.
Jun 30 08:08:15 Marojejy systemd[1278]: Closed GnuPG network certificate management daemon.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Deferred execution scheduler...
Jun 30 08:08:15 Marojejy avahi-daemon[797]: Got SIGTERM, quitting.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Avahi mDNS/DNS-SD Stack...
Jun 30 08:08:15 Marojejy systemd[1278]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Jun 30 08:08:15 Marojejy avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv6 with address 2600:1700:5b2f:810:f66a:ddff:fef3:fb83.
Jun 30 08:08:15 Marojejy avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.204.
Jun 30 08:08:15 Marojejy systemd[1278]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Jun 30 08:08:15 Marojejy avahi-daemon[797]: Leaving mDNS multicast group on interface lo.IPv6 with address ::1.
Jun 30 08:08:15 Marojejy avahi-daemon[797]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Jun 30 08:08:15 Marojejy systemd[1278]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Jun 30 08:08:15 Marojejy systemd[1278]: Closed GnuPG cryptographic agent and passphrase cache.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping Initialize hardware monitoring sensors...
Jun 30 08:08:15 Marojejy systemd[1278]: Closed GnuPG public key management service.
Jun 30 08:08:15 Marojejy systemd[1]: Stopping nvidia-powerd service...
Jun 30 08:08:15 Marojejy systemd[1]: Stopping User Login Management...
Jun 30 08:08:15 Marojejy systemd[1278]: Closed p11-kit server.
Jun 30 08:08:15 Marojejy systemd[1278]: Closed PipeWire PulseAudio.
Jun 30 08:08:15 Marojejy systemd[1]: atd.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1278]: Closed PipeWire Multimedia System Sockets.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Deferred execution scheduler.
Jun 30 08:08:15 Marojejy dbus-broker[1331]: Dispatched 646 messages @ 4(±7)μs / message.
Jun 30 08:08:15 Marojejy systemd[1278]: Stopping D-Bus User Message Bus...
Jun 30 08:08:15 Marojejy avahi-daemon[797]: avahi-daemon 0.8 exiting.
Jun 30 08:08:15 Marojejy systemd[1]: avahi-daemon.service: Deactivated successfully.
Jun 30 08:08:15 Marojejy systemd[1]: Stopped Avahi mDNS/DNS-SD Stack.
Jun 30 08:08:15 Marojejy (modprobe)[7422]: lm_sensors.service: Referenced but unset environment variable evaluates to an empty string: BUS_MODULES
Jun 30 08:08:15 Marojejy systemd[1278]: Stopped D-Bus User Message Bus.
Jun 30 08:08:16 Marojejy systemd[1278]: Removed slice User Core Session Slice.
Jun 30 08:08:16 Marojejy systemd[1278]: Closed D-Bus User Message Bus Socket.
Jun 30 08:08:16 Marojejy systemd[1278]: Removed slice User Application Slice.
Jun 30 08:08:16 Marojejy systemd[1278]: Reached target Shutdown.
Jun 30 08:08:16 Marojejy systemd[1278]: Finished Exit the Session.
Jun 30 08:08:16 Marojejy systemd[1278]: Reached target Exit the Session.
Jun 30 08:08:16 Marojejy mkinitcpio[7357]: ==> Starting build: 'none'
Jun 30 08:08:16 Marojejy mkinitcpio[7357]:   -> Running build hook: [sd-shutdown]
Jun 30 08:08:16 Marojejy (sd-pam)[1280]: pam_unix(systemd-user:session): session closed for user asuka
Jun 30 08:08:16 Marojejy systemd[1]: user@1000.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped User Manager for UID 1000.
Jun 30 08:08:16 Marojejy /usr/bin/nvidia-powerd[801]: Quit successfully
Jun 30 08:08:16 Marojejy systemd[1]: nvidia-powerd.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped nvidia-powerd service.
Jun 30 08:08:16 Marojejy systemd[1]: lm_sensors.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped Initialize hardware monitoring sensors.
Jun 30 08:08:16 Marojejy systemd[1]: systemd-logind.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped User Login Management.
Jun 30 08:08:16 Marojejy systemd[1]: Stopping Permit User Sessions...
Jun 30 08:08:16 Marojejy systemd[1]: Stopping User Runtime Directory /run/user/1000...
Jun 30 08:08:16 Marojejy systemd[1]: run-user-1000.mount: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Unmounted /run/user/1000.
Jun 30 08:08:16 Marojejy systemd[1]: systemd-user-sessions.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped Permit User Sessions.
Jun 30 08:08:16 Marojejy systemd[1]: user-runtime-dir@1000.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped User Runtime Directory /run/user/1000.
Jun 30 08:08:16 Marojejy systemd[1]: Removed slice User Slice of UID 1000.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped target Network.
Jun 30 08:08:16 Marojejy systemd[1]: Stopping Wireless service...
Jun 30 08:08:16 Marojejy iwd[798]: Terminate
Jun 30 08:08:16 Marojejy iwd[798]: Removing scan context for wdev 2
Jun 30 08:08:16 Marojejy systemd[1]: Stopping Network Configuration...
Jun 30 08:08:16 Marojejy systemd-networkd[656]: wlan0: DHCP lease lost
Jun 30 08:08:16 Marojejy kernel: wlan0: deauthenticating from 06:93:97:5e:d6:63 by local choice (Reason: 3=DEAUTH_LEAVING)
Jun 30 08:08:16 Marojejy systemd[1]: Stopping TPM PCR Barrier (User)...
Jun 30 08:08:16 Marojejy mkinitcpio[7357]: ==> Build complete.
Jun 30 08:08:16 Marojejy systemd-pcrextend[7540]: Extended PCR index 11 with 'shutdown' (banks sha256).
Jun 30 08:08:16 Marojejy systemd[1]: systemd-pcrphase.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped TPM PCR Barrier (User).
Jun 30 08:08:16 Marojejy systemd[1]: Stopped target Remote File Systems.
Jun 30 08:08:16 Marojejy systemd[1]: mkinitcpio-generate-shutdown-ramfs.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Finished Generate shutdown-ramfs.
Jun 30 08:08:16 Marojejy systemd-networkd[656]: wlan0: DHCPv6 lease lost
Jun 30 08:08:16 Marojejy systemd[1]: systemd-networkd.service: Deactivated successfully.
Jun 30 08:08:16 Marojejy systemd[1]: Stopped Network Configuration.
Jun 30 08:08:16 Marojejy systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy iwd[798]: D-Bus disconnected, quitting...
Jun 30 08:08:17 Marojejy systemd[1]: iwd.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Wireless service.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Basic System.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Preparation for Network.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Path Units.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Slice Units.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice User and Session Slice.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Socket Units.
Jun 30 08:08:17 Marojejy systemd[1]: avahi-daemon.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed Avahi mDNS/DNS-SD Stack Activation Socket.
Jun 30 08:08:17 Marojejy systemd[1]: cups.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed CUPS Scheduler.
Jun 30 08:08:17 Marojejy systemd[1]: dirmngr@etc-pacman.d-gnupg.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed GnuPG network certificate management daemon for /etc/pacman.d/gnupg.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/dirmngr.
Jun 30 08:08:17 Marojejy systemd[1]: gpg-agent-browser@etc-pacman.d-gnupg.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers) for /etc/pacman.d/gnupg.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent-browser.
Jun 30 08:08:17 Marojejy systemd[1]: gpg-agent-extra@etc-pacman.d-gnupg.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed GnuPG cryptographic agent and passphrase cache (restricted) for /etc/pacman.d/gnupg.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent-extra.
Jun 30 08:08:17 Marojejy systemd[1]: gpg-agent-ssh@etc-pacman.d-gnupg.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed GnuPG cryptographic agent (ssh-agent emulation) for /etc/pacman.d/gnupg.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent-ssh.
Jun 30 08:08:17 Marojejy systemd[1]: gpg-agent@etc-pacman.d-gnupg.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed GnuPG cryptographic agent and passphrase cache for /etc/pacman.d/gnupg.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/gpg-agent.
Jun 30 08:08:17 Marojejy systemd[1]: keyboxd@etc-pacman.d-gnupg.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed GnuPG public key management service for /etc/pacman.d/gnupg.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/keyboxd.
Jun 30 08:08:17 Marojejy systemd[1]: nordvpnd.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed NordVPN Daemon Socket.
Jun 30 08:08:17 Marojejy systemd[1]: sshd-unix-local.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
Jun 30 08:08:17 Marojejy systemd[1]: systemd-hostnamed.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed Hostname Service Socket.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-networkd.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed Network Service Netlink Socket.
Jun 30 08:08:17 Marojejy dbus-broker[795]: Dispatched 4215 messages @ 7(±11)μs / message.
Jun 30 08:08:17 Marojejy systemd[1]: Stopping D-Bus System Message Bus...
Jun 30 08:08:17 Marojejy systemd[1]: systemd-network-generator.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Generate network units from Kernel command line.
Jun 30 08:08:17 Marojejy systemd[1]: Stopping TPM PCR Barrier (Initialization)...
Jun 30 08:08:17 Marojejy systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: dbus-broker.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped D-Bus System Message Bus.
Jun 30 08:08:17 Marojejy systemd[1]: dbus.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed D-Bus System Message Bus Socket.
Jun 30 08:08:17 Marojejy systemd-pcrextend[7552]: Extended PCR index 11 with 'final' (banks sha256).
Jun 30 08:08:17 Marojejy systemd[1]: systemd-pcrphase-sysinit.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped TPM PCR Barrier (Initialization).
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target System Initialization.
Jun 30 08:08:17 Marojejy systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Local Encrypted Volumes.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-ask-password-wall.path: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Local Integrity Protected Volumes.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Local Verity Protected Volumes.
Jun 30 08:08:17 Marojejy systemd[1]: Stopping Load/Save Screen Backlight Brightness of backlight:nvidia_wmi_ec_backlight...
Jun 30 08:08:17 Marojejy systemd[1]: systemd-boot-random-seed.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Update Boot Loader Random Seed.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-pcrmachine.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped TPM PCR Machine ID Measurement.
Jun 30 08:08:17 Marojejy systemd[1]: Stopping Load/Save OS Random Seed...
Jun 30 08:08:17 Marojejy systemd[1]: Stopping Network Name Resolution...
Jun 30 08:08:17 Marojejy systemd[1]: Stopping Network Time Synchronization...
Jun 30 08:08:17 Marojejy systemd[1]: systemd-tpm2-setup.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped TPM SRK Setup.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-tpm2-setup-early.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Early TPM SRK Setup.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Trusted Platform Module.
Jun 30 08:08:17 Marojejy systemd[1]: Stopping Record System Boot/Shutdown in UTMP...
Jun 30 08:08:17 Marojejy systemd[1]: systemd-timesyncd.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Network Time Synchronization.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-resolved.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Network Name Resolution.
Jun 30 08:08:17 Marojejy systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-backlight@backlight:nvidia_wmi_ec_backlight.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Load/Save Screen Backlight Brightness of backlight:nvidia_wmi_ec_backlight.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/systemd-backlight.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-sysctl.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Apply Kernel Variables.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-coredump.socket: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Closed Process Core Dump Socket.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-modules-load.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Load Kernel Modules.
Jun 30 08:08:17 Marojejy systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-update-utmp.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Record System Boot/Shutdown in UTMP.
Jun 30 08:08:17 Marojejy systemd[1]: Stopping Security Audit Logging Service...
Jun 30 08:08:17 Marojejy systemd[1]: systemd-random-seed.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Load/Save OS Random Seed.
Jun 30 08:08:17 Marojejy auditd[754]: The audit daemon is exiting.
Jun 30 08:08:17 Marojejy kernel: kauditd_printk_skb: 478 callbacks suppressed
Jun 30 08:08:17 Marojejy kernel: audit: type=1305 audit(1719760097.497:378): op=set audit_pid=0 old=754 auid=4294967295 ses=4294967295 subj=unconfined res=1
Jun 30 08:08:17 Marojejy kernel: audit: type=1300 audit(1719760097.497:378): arch=c000003e syscall=44 success=yes exit=60 a0=3 a1=7fffe2c87d90 a2=3c a3=0 items=0 ppid=1 pid=754 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditd" exe="/usr/bin/auditd" subj=unconfined key=(null)
Jun 30 08:08:17 Marojejy kernel: audit: type=1327 audit(1719760097.497:378): proctitle="/usr/bin/auditd"
Jun 30 08:08:17 Marojejy systemd[1]: auditd.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Security Audit Logging Service.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Create System Files and Directories.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Local File Systems.
Jun 30 08:08:17 Marojejy systemd[1]: Unmounting /efi...
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.500:379): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=auditd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.500:380): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy systemd[1]: Unmounting /home/asuka/Downloads...
Jun 30 08:08:17 Marojejy systemd[1]: Unmounting Temporary Directory /tmp...
Jun 30 08:08:17 Marojejy systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: tmp.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Unmounted Temporary Directory /tmp.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Swaps.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivating swap /dev/disk/by-diskseq/1-part3...
Jun 30 08:08:17 Marojejy kernel: EXT4-fs (nvme0n1p6): unmounting filesystem fb9ba13d-f683-47ab-a426-8c7978592375.
Jun 30 08:08:17 Marojejy systemd[1]: efi.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Unmounted /efi.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-fsck@dev-disk-by\x2duuid-CEF9\x2d64E0.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped File System Check on /dev/disk/by-uuid/CEF9-64E0.
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.527:381): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-fsck@dev-disk-by\x2duuid-CEF9\x2d64E0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy systemd[1]: home-asuka-Downloads.mount: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Unmounted /home/asuka/Downloads.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-fsck@dev-disk-by\x2duuid-fb9ba13d\x2df683\x2d47ab\x2da426\x2d8c7978592375.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped File System Check on /dev/disk/by-uuid/fb9ba13d-f683-47ab-a426-8c7978592375.
Jun 30 08:08:17 Marojejy systemd[1]: Removed slice Slice /system/systemd-fsck.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped target Preparation for Local File Systems.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-remount-fs.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Remount Root and Kernel File Systems.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped File System Check on Root Device.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Create Static Device Nodes in /dev.
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.550:382): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-fsck@dev-disk-by\x2duuid-fb9ba13d\x2df683\x2d47ab\x2da426\x2d8c7978592375 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.550:383): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.550:384): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy kernel: audit: type=1131 audit(1719760097.550:385): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=unconfined msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Jun 30 08:08:17 Marojejy systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Stopped Create Static Device Nodes in /dev gracefully.
Jun 30 08:08:17 Marojejy systemd[1]: Reached target System Shutdown.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2did-nvme\x2dINTEL_SSDPEK1A118GA_PHOC331400BP118B\x2dpart3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-id/nvme-INTEL_SSDPEK1A118GA_PHOC331400BP118B-part3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart-by\x2duuid-c713acd7\x2d942b\x2d4948\x2d9cc3\x2d5c7d8636a30b.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part/by-uuid/c713acd7-942b-4948-9cc3-5c7d8636a30b.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart-by\x2dpartnum-3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part/by-partnum/3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2dpartuuid-e5aaad33\x2d41bd\x2d4f84\x2dabe8\x2deb1881c4bc71.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-partuuid/e5aaad33-41bd-4f84-abe8-eb1881c4bc71.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2did-nvme\x2dINTEL_SSDPEK1A118GA_PHOC331400BP118B_1\x2dpart3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-id/nvme-INTEL_SSDPEK1A118GA_PHOC331400BP118B_1-part3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2dpath-pci\x2d0000:07:00.0\x2dnvme\x2d1\x2dpart-by\x2dpartuuid-e5aaad33\x2d41bd\x2d4f84\x2dabe8\x2deb1881c4bc71.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-path/pci-0000:07:00.0-nvme-1-part/by-partuuid/e5aaad33-41bd-4f84-abe8-eb1881c4bc71.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2did-nvme\x2deui.5cd2e449a94f0100\x2dpart3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-id/nvme-eui.5cd2e449a94f0100-part3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2ddiskseq-1\x2dpart3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-diskseq/1-part3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-nvme1n1p3.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/nvme1n1p3.
Jun 30 08:08:17 Marojejy systemd[1]: dev-disk-by\x2duuid-c713acd7\x2d942b\x2d4948\x2d9cc3\x2d5c7d8636a30b.swap: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Deactivated swap /dev/disk/by-uuid/c713acd7-942b-4948-9cc3-5c7d8636a30b.
Jun 30 08:08:17 Marojejy systemd[1]: Reached target Unmount All Filesystems.
Jun 30 08:08:17 Marojejy systemd[1]: Reached target Late Shutdown Services.
Jun 30 08:08:17 Marojejy systemd[1]: systemd-poweroff.service: Deactivated successfully.
Jun 30 08:08:17 Marojejy systemd[1]: Finished System Power Off.
Jun 30 08:08:17 Marojejy systemd[1]: Reached target System Power Off.
Jun 30 08:08:17 Marojejy systemd[1]: Shutting down.
Jun 30 08:08:17 Marojejy systemd-shutdown[1]: Syncing filesystems and block devices.
Jun 30 08:08:17 Marojejy systemd-shutdown[1]: Sending SIGTERM to remaining processes...
Jun 30 08:08:17 Marojejy systemd-journald[267]: Received SIGTERM from PID 1 (systemd-shutdow).
Jun 30 08:08:17 Marojejy systemd-journald[267]: Journal stopped

So far I haven't had another session SIGKILL, but I've only gone through five cycles since my guess about sudo mousekeys.sh.

seth wrote:

systemd[1]: session-1.scope: Killing process 1219 (login) with signal SIGKILL.
Who own's PID1?
The nasty part is "Doesn't happen most of the time" - otherwise you could just bisect the session processes.
The only other match is btw https://bbs.archlinux.org/viewtopic.php?id=294858 where this happened at the tail of more severe issues during the session.

systemd of course is running as root, unless you mean Lennart. I am really hoping that this issue is not Nvidia related like in that thread, I've lost enough of my life debugging Nvidia stuff :þ

EDIT: Bluetooth might be a red herring, it looks like journalctl just orders things in a weird way. 13 shutdown cycles now, still no more mass/session SIGKILLs, but gvfs-daemon occasionally SIGKILLs one of its subprocesses (not consistent which one it is).

Last edited by mesaprotector (2024-11-28 18:58:01)

seth · 2024-11-28 19:30:09

I am really hoping that this issue is not Nvidia related like in that thread

Hope isn't a strategy - do you have similar errors ahead of the affected shutdowns?

I should be searching not for login getting SIGKILLed, but for "Failed to kill control group"

You'd only be interested in "Failed to kill control group /user.slice/user-1000.slice/session" (skipping the session index) and also that's probably just symptom of a previous timeout that's not covered by your journal tail snippets.

mesaprotector · 2024-11-30 10:42:41

This will be a long reply, but the short version is: yes, it was sudo mousekeys.sh causing the problem.

The slightly longer version is, use systemd-analyze log-level debug and look through it very carefully if you're having a shutdown issue (either a classic timeout hang or the instant mass death I ran into; though in all my web searching I didn't find a single other case of an instant mass SIGKILL of the user session). I'm still not entirely sure why this happened, and especially not why it was so uncommon until late August.

The very long version—read only if you're interested, or if you're reading this in 2029 praying to God that at least one other person has had this issue before and knows how to fix it—is this. After two days (and twenty boot cycles) with no problem after replacing mousekeys with a service, I was pretty sure that had been the issue, so I made a silly script that loops forever and ran it as root (with a sudoers exception) in .xinitrc. It caused no problems at all. If I went all-out and had it ignore both SIGHUP and SIGTERM (which seem to be received almost simultaneously), it would stall the shutdown, then get killed at timeout—long after everything else had exited perfectly well. mousekeys runs evsieve, and I know some applications interpret SIGHUP as "reload configuration", but the lead dev of evsieve explicitly says it exits on SIGHUP. Only after I did that I discovered the existence of "systemd-analyze log-level", which would've diagnosed the problem immediately. (It's not even documented! The man page systemd-analyze(1) literally does not mention it!) So, I put the mousekeys script back into .xinitrc, ran "sudo systemd-analyze log-level debug", and rebooted a few times. Pretty soon:

Nov 30 00:57:47 Marojejy systemd[1]: Failed to open pidfd for pid 1618: Invalid argument
Nov 30 00:57:47 Marojejy systemd[1]: Failed to read pidref from cgroup '/user.slice/user-1000.slice/session-1.scope': Invalid argument
Nov 30 00:57:47 Marojejy systemd[1]: Failed to kill processes in cgroup '/user.slice/user-1000.slice/session-1.scope' item cgroup.procs: Invalid argument
Nov 30 00:57:47 Marojejy systemd[1]: session-1.scope: Failed to kill control group /user.slice/user-1000.slice/session-1.scope, ignoring: Invalid argument

There's no record of exactly what 1618 was (I could reboot another few times taking more careful notes - I probably will tomorrow), but "sudo" was 1600 and 1616; calls to sudo seem to result in a minimum of two processes each time, with the process actually run by sudo being launched immediately after the second one. There's no other call to sudo, direct or indirect, in my .xinitrc, so it's obvious what it has to be. The earlier part of the log shows mousekeys.sh getting called between PID 1606 (atd) and 1634 (xbindkeys).

"Failed to open pidfd" is not exactly a term with a lot of web search results, but there is a decent man page for the pidfd_open syscall. The error it gave me, EINVAL, is listed as meaning either the pid or the flags were invalid. An "invalid" pid appears to mean one numbered 0 or a negative; so instead that must mean the flags are invalid here: the relevant line in the kernel is:

if (flags & ~(PIDFD_NONBLOCK | PIDFD_THREAD))

That's about as far as I could make sense of it, though. I'll mark this solved in a couple days if there are no further issues, after finishing what debugging I'm capable of; given that running something as root in .xinitrc wasn't enough to cause the issue by itself, I want to see what is.

seth · 2024-11-30 17:58:43

the moneyquote wrote:

Only after I did that I discovered the existence of "systemd-analyze log-level", which would've diagnosed the problem immediately. (It's not even documented! The man page systemd-analyze(1) literally does not mention it!) So, I put the mousekeys script back into .xinitrc, ran "sudo systemd-analyze log-level debug", and rebooted a few times.

\o/
Please always remember to mark resolved threads by editing your initial posts subject - so others will know that there's no task left, but maybe a solution to find.
Thanks.

mesaprotector · 2024-12-01 07:31:20

https://lore.kernel.org/lkml/20230807-p … brauner/T/

It seems that I might have run into an obscure race condition that, at one point, was going to be patched, but the patch never went through (see "2)" in the lead email). Instead the most recent relevant change to the kernel was to add PIDFD_THREAD. It being a race explains the inconsistency of it; I'll likely never know what changed about the shutdown process in late August that made the reaping more likely to fit within the narrow time window necessary to return EINVAL. Perhaps neither evsieve nor anything I did is to blame. The process that falls victim to the race is /bin/bash mousekeys.sh rather than its child evsieve; when called by a systemd service, it still errors on pidfd_open during shutdown but returns ESRCH, and causes no problems.

I would question systemd's wisdom in SIGKILLing everything in a control group the moment one of its members fails to get a descriptor opened, but maybe there's some worse issue it prevents.

Last edited by mesaprotector (2024-12-01 07:32:24)

seth · 2024-12-03 21:35:53

I would question systemd's wisdom

Full stop

Does it help to nohup mousekeys/evsieve?

Sidebar:
Why are you running
1. sudo evsieve from a user session itfp?
2. running a script that's called mousekeys.sh on X11?
https://aur.archlinux.org/packages/xkbset (+m) and also https://wiki.archlinux.org/title/X_keyb … se_control - and https://confluence.jaytaala.com/display … s+in+Linux unless your mousekeys work the other way round and map buttons to keys

Arch Linux

#1 2024-11-26 05:26:45

[SOLVED] Login (and every other user process) SIGKILLed at shutdown

#2 2024-11-26 20:05:58

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#3 2024-11-26 21:43:30

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#4 2024-11-27 08:10:36

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#5 2024-11-27 17:01:41

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#6 2024-11-27 17:28:13

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#7 2024-11-27 17:35:35

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#8 2024-11-27 18:29:11

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#9 2024-11-28 02:22:19

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#10 2024-11-28 19:30:09

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#11 2024-11-30 10:42:41

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#12 2024-11-30 17:58:43

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#13 2024-12-01 07:31:20

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

#14 2024-12-03 21:35:53

Re: [SOLVED] Login (and every other user process) SIGKILLed at shutdown

Board footer