On 15.03.2022 14:13, Carlos E. R. wrote:
On Wednesday, 2022-03-02 at 21:36 +0100, Carlos E. R. wrote:
On Wednesday, 2022-03-02 at 20:52 +0100, Carlos E. R. wrote:
On Wednesday, 2022-03-02 at 22:00 +0300, Andrei Borzenkov wrote:
Ah, wait... you say "Still, systemd will start units listed in target's OnFailure when target start job failed".
So, maybe edit "sleep.target"
...
Well, we'll see if it works :-)
Thanks for the idea.
After days of hibernation without problems, yesterday I had a failed one, but this service/script did not trigger.
Current status:
Telcontar:~ # systemctl cat sleep.target
Unit that failed was hibernate.target, not sleep.target.
# /usr/lib/systemd/system/sleep.target # SPDX-License-Identifier: LGPL-2.1+ # # This file is part of systemd. # # systemd is free software; you can redistribute it and/or modify it # under the terms of the GNU Lesser General Public License as published by # the Free Software Foundation; either version 2.1 of the License, or # (at your option) any later version.
[Unit] Description=Sleep Documentation=man:systemd.special(7) DefaultDependencies=no RefuseManualStart=yes StopWhenUnneeded=yes
# /etc/systemd/system/sleep.target.d/override.conf [Unit] OnFailure=sleepfailure.service Telcontar:~ #
Telcontar:~ # systemctl cat sleepfailure.service # /etc/systemd/system/sleepfailure.service [Unit] Description=Plays something when sleep.target fails #After=sleep.target Do not want to run always.
[Service] Type=oneshot RemainAfterExit=true StandardOutput=tty ExecStart=-/root/ThingsNeededForBoot/sleepfailed Telcontar:~ #
Telcontar:~ # cat /root/ThingsNeededForBoot/sleepfailed #!/bin/bash # -x
su cer -c "XDG_RUNTIME_DIR=/run/user/1000 aplay /usr/local/share/cosas/abitsilly.wav" /usr/bin/logger -t Mine -p daemon.info "Something failed on hibernate, sound play." Telcontar:~ #
Journal:
Mar 15 00:16:57 Telcontar sudo[13785]: root : TTY=pts/15 ; PWD=/root ; USER=root ; COMMAND=/usr/bin/systemctl hibernate Mar 15 00:16:57 Telcontar sudo[13785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 00:16:57 Telcontar sudo[13785]: pam_unix(sudo:session): session closed for user root Mar 15 00:16:58 Telcontar systemd[1]: Reached target Sleep. Mar 15 00:16:58 Telcontar systemd[1]: Starting Hibernate... Mar 15 00:16:58 Telcontar systemd-sleep[13827]: INFO: running /usr/lib/systemd/system-sleep/grub2.sleep for hibernate Mar 15 00:16:58 Telcontar systemd-sleep[13827]: INFO: Running prepare-grub .. Mar 15 00:16:58 Telcontar systemd-sleep[13840]: 2022-03-15 00:16:58+01:00 - Hibernating the system now... Mar 15 00:16:58 Telcontar systemd-sh[13841]: Hibernating the system now... Mar 15 00:16:58 Telcontar systemd-sleep[13832]: service: no such service upsd.service Mar 15 00:16:58 Telcontar systemd-sleep[13827]: running kernel is grub menu entry main-os (vmlinuz-5.3.18-150300.59.54-default) Mar 15 00:16:58 Telcontar systemd-sleep[13827]: preparing boot-loader: selecting entry main-os, kernel /boot/5.3.18-150300.59.54-default Mar 15 00:16:58 Telcontar systemd-sleep[13827]: running /usr/sbin/grub2-once "main-os" Mar 15 00:16:58 Telcontar systemd-sleep[13827]: time needed for sync: 0.1 seconds, time needed for grub: 0.1 seconds. Mar 15 00:16:58 Telcontar systemd-sleep[13827]: INFO: Done. Mar 15 00:17:01 Telcontar sudo[14094]: root : TTY=pts/15 ; PWD=/root ; USER=root ; COMMAND=/usr/local/sbin/beep Mar 15 00:17:01 Telcontar sudo[14094]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 00:17:01 Telcontar sudo[14094]: pam_unix(sudo:session): session closed for user root Mar 15 00:17:03 Telcontar systemd-sleep[13811]: Suspending system... Mar 15 00:17:03 Telcontar kernel: PM: hibernation: hibernation entry Mar 15 00:17:10 Telcontar kernel: Filesystems sync: 0.067 seconds Mar 15 00:17:11 Telcontar kernel: Freezing user space processes ... (elapsed 0.002 seconds) done. Mar 15 00:17:11 Telcontar kernel: OOM killer disabled. Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0x00000000-0x00000fff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0x000a0000-0x000fffff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0x09d82000-0x09ffffff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0x0a200000-0x0a20bfff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0x0b000000-0x0b01ffff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xe790d000-0xe790dfff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xe7929000-0xe792afff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xe7938000-0xe7938fff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xe7aa3000-0xe7afffff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xe7b08000-0xe7b08fff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xe7b2f000-0xe7b2ffff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xeb8ee000-0xeba37fff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xebaf9000-0xecb1dfff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Marking nosave pages: [mem 0xef000000-0xffffffff] Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Basic memory bitmaps created Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Preallocating image memory Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Allocated 3261370 pages for shapshot Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Allocated 13045480 kbytes in 3.38 seconds (3859.60 MB/s) Mar 15 00:17:11 Telcontar kernel: Freezing remaining freezable tasks ... (elapsed 0.002 seconds) done. Mar 15 00:17:11 Telcontar kernel: printk: Suspending console(s) (use no_console_suspend to debug) Mar 15 00:17:11 Telcontar kernel: r8169 0000:22:00.0 eth0: Link is Down Mar 15 00:17:11 Telcontar kernel: serial 00:05: disabled Mar 15 00:17:11 Telcontar kernel: parport_pc 00:04: disabled Mar 15 00:17:11 Telcontar kernel: amdgpu 0000:27:00.0: amdgpu: GPU BACO reset Mar 15 00:17:11 Telcontar kernel: ACPI: Preparing to enter system sleep state S4 Mar 15 00:17:11 Telcontar kernel: PM: Saving platform NVS memory Mar 15 00:17:11 Telcontar kernel: Disabling non-boot CPUs ... Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 1 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 2 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 3 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 4 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 5 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 6 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 7 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 8 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 9 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 10 is now offline Mar 15 00:17:11 Telcontar kernel: smpboot: CPU 11 is now offline Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Creating image: Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Need to copy 4373949 pages Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Normal pages needed: 4373949 + 1024, available pages: 4001491 Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Not enough free memory <===================== Mar 15 00:17:11 Telcontar kernel: smpboot: Booting Node 0 Processor 10 APIC 0xb Mar 15 00:17:11 Telcontar kernel: microcode: CPU10: patch_level=0x08701013 Mar 15 00:17:11 Telcontar kernel: ACPI: \_PR_.C009: Found 2 idle states Mar 15 00:17:11 Telcontar kernel: CPU10 is up Mar 15 00:17:11 Telcontar kernel: smpboot: Booting Node 0 Processor 11 APIC 0xd Mar 15 00:17:11 Telcontar kernel: microcode: CPU11: patch_level=0x08701013 Mar 15 00:17:11 Telcontar kernel: ACPI: \_PR_.C00B: Found 2 idle states Mar 15 00:17:11 Telcontar kernel: CPU11 is up Mar 15 00:17:11 Telcontar kernel: ACPI: Waking up from system sleep state S4 Mar 15 00:17:11 Telcontar kernel: parport_pc 00:04: activated Mar 15 00:17:11 Telcontar kernel: serial 00:05: activated Mar 15 00:17:11 Telcontar kernel: ata6: SATA link down (SStatus 0 SControl 330) Mar 15 00:17:11 Telcontar kernel: ata5: SATA link down (SStatus 0 SControl 330) Mar 15 00:17:11 Telcontar kernel: ata10: SATA link down (SStatus 0 SControl 300) Mar 15 00:17:11 Telcontar kernel: ata7: SATA link down (SStatus 0 SControl 330) Mar 15 00:17:11 Telcontar kernel: ata9: SATA link down (SStatus 0 SControl 300) Mar 15 00:17:11 Telcontar kernel: nvme nvme0: Shutdown timeout set to 8 seconds Mar 15 00:17:11 Telcontar kernel: ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Mar 15 00:17:11 Telcontar kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Mar 15 00:17:11 Telcontar kernel: ata8: SATA link up 1.5 Gbps (SStatus 113 SControl 300) Mar 15 00:17:11 Telcontar kernel: ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Mar 15 00:17:11 Telcontar kernel: ata2.00: configured for UDMA/133 Mar 15 00:17:11 Telcontar kernel: ata8.00: configured for UDMA/133 Mar 15 00:17:11 Telcontar kernel: [drm] PCIE GART of 256M enabled (table at 0x000000F400000000). Mar 15 00:17:11 Telcontar kernel: ata1.00: configured for UDMA/133 Mar 15 00:17:11 Telcontar kernel: nvme nvme0: 32/0/0 default/read/poll queues Mar 15 00:17:11 Telcontar kernel: r8169 0000:22:00.0 eth0: Link is Down Mar 15 00:17:11 Telcontar kernel: ata4.00: configured for UDMA/133 Mar 15 00:17:11 Telcontar kernel: ata3: SATA link up 6.0 Gbps (SStatus 133 SControl 300) Mar 15 00:17:11 Telcontar kernel: ata3.00: configured for UDMA/133 Mar 15 00:17:11 Telcontar kernel: [drm] Fence fallback timer expired on ring sdma0 Mar 15 00:17:11 Telcontar kernel: [drm] Fence fallback timer expired on ring sdma0 Mar 15 00:17:11 Telcontar kernel: [drm] UVD and UVD ENC initialized successfully. Mar 15 00:17:11 Telcontar kernel: [drm] VCE initialized successfully. Mar 15 00:17:11 Telcontar kernel: PM: hibernation: Basic memory bitmaps freed Mar 15 00:17:11 Telcontar kernel: OOM killer enabled. Mar 15 00:17:11 Telcontar kernel: Restarting tasks ... done. Mar 15 00:17:11 Telcontar kernel: PM: hibernation: hibernation exit Mar 15 00:17:10 Telcontar rtkit-daemon[11233]: The canary thread is apparently starving. Taking action. Mar 15 00:17:10 Telcontar systemd-sleep[13811]: Failed to suspend system. System resumed again: Cannot allocate memory <===================== Mar 15 00:17:10 Telcontar rtkit-daemon[11233]: Demoting known real-time threads. Mar 15 00:17:10 Telcontar rtkit-daemon[11233]: Successfully demoted thread 6931 of process 12325 (/usr/lib64/firefox/firefox). Mar 15 00:17:10 Telcontar rtkit-daemon[11233]: Demoted 1 threads. Mar 15 00:17:11 Telcontar systemd-sleep[14272]: 2022-03-15 00:17:11+01:00 - Thawing the system now... Mar 15 00:17:11 Telcontar systemd-sleep[14215]: INFO: running /usr/lib/systemd/system-sleep/grub2.sleep for hibernate Mar 15 00:17:11 Telcontar systemd-sleep[14215]: INFO: Running grub-once-restore .. Mar 15 00:17:11 Telcontar systemd-sleep[14215]: INFO: Done. Mar 15 00:17:11 Telcontar systemd-sh[14274]: Thawing the system now... Mar 15 00:17:11 Telcontar systemd-sleep[14221]: service: no such service upsd.service Mar 15 00:17:11 Telcontar [14214]: /usr/lib/systemd/system-sleep/nut.sh failed with exit status 1. Mar 15 00:17:11 Telcontar systemd[1]: systemd-hibernate.service: Main process exited, code=exited, status=1/FAILURE Mar 15 00:17:11 Telcontar systemd[1]: systemd-hibernate.service: Failed with result 'exit-code'. Mar 15 00:17:11 Telcontar systemd[1]: Failed to start Hibernate. Mar 15 00:17:11 Telcontar systemd[1]: Dependency failed for Hibernate. Mar 15 00:17:11 Telcontar systemd[1]: hibernate.target: Job hibernate.target/start failed with result 'dependency'. Mar 15 00:17:11 Telcontar systemd-logind[1797]: Operation 'sleep' finished. Mar 15 00:17:11 Telcontar systemd[1]: Stopped target Sleep. Mar 15 00:17:12 Telcontar kernel: r8169 0000:22:00.0 eth0: Link is Up - 1Gbps/Full - flow control off Mar 15 00:17:12 Telcontar wickedd[1847]: route ipv4 0.0.0.0/0 via 192.168.1.1 dev eth0#2 type unicast table main scope universe protocol boot covered by a ipv4:static lease Mar 15 00:17:13 Telcontar ModemManager[1813]: <info> Couldn't check support for device '/sys/devices/pci0000:00/0000:00:01.3/0000:03:00.2/0000:20:01.0/0000:22:00.0': not supported by any plugin Mar 15 00:19:41 Telcontar leafnode[13563]:
205 Always happy to serve! Mar 15 00:19:41 Telcontar leafnode[13564]: 205 Always happy to serve! Mar 15 00:19:41 Telcontar systemd[1]: Started Leafnode NNTP server (127.0.0.1:46746). Mar 15 00:19:41 Telcontar systemd[1]: Started Leafnode NNTP server (127.0.0.1:46748). ... I noticed some minutes later, and tried again, which worked. But got no warning notice or bell as intended. Not even a mention of trying to run my hook in the logs.