[SOLVED] suspend-then-hibernate freezes on hibernation entry

sloth1999 · 2024-09-08 22:25:14

Hello everyone,

I've been stuck on this problem for a few weeks now and don't know what to do anymore so i came asking for help.
So first: Sleep /suspend works perfectly fine as does hibernation on its own but when trying to suspend-then-hibernate, the system always freezes with a black screen with the last log message being: "kernel: PM: hibernation: hibernation entry".

I have a standard arch installation with the Plasma DE running on a Thinkpad T14s Gen 4 AMD.
I use a 32GB swap partition on my ssd, which is automatically detected by systemd.
The freeze happened from linux version 6.9 until now (6.10.8.arch1-1) as well as on the linux-lts kernel.

This post also describes my problem well, but the solution does not solve it: https://bbs.archlinux.org/viewtopic.php?id=274366.

journalctl log of successful hibernation:

 Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x1b1cd000-0x2144cfff]              <---------this goes on for like another 15 lines, afterwards it shuts off
Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x1b1a8000-0x1b1a8fff]
Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x1b1a5000-0x1b1a5fff]
Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x09f00000-0x09f3bfff]
Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x09c00000-0x09dd0fff]
Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x000a0000-0x000fffff]
Sep 08 22:37:13 hostname kernel: PM: hibernation: Marking nosave pages: [mem 0x00000000-0x00000fff]
Sep 08 22:37:13 hostname kernel: OOM killer disabled.
Sep 08 22:37:13 hostname kernel: Freezing user space processes completed (elapsed 0.001 seconds)
Sep 08 22:37:13 hostname kernel: Freezing user space processes
Sep 08 22:37:13 hostname kernel: Filesystems sync: 0.010 seconds
Sep 08 22:36:14 hostname kernel: PM: hibernation: hibernation entry                                         <---------------------stops here for suspend-then-hibernate
Sep 08 22:36:14 hostname kernel: PM: Image not found (code -16)                                               <---------------------happens most times, sometimes not, doesn't seem to affect anything
Sep 08 22:36:14 hostname systemd-sleep[2082]: Performing sleep operation 'hibernate'...
Sep 08 22:36:14 hostname systemd-sleep[2082]: Successfully froze unit 'user.slice'.
Sep 08 22:36:14 hostname systemd[1]: Starting System Hibernate...
Sep 08 22:36:14 hostname systemd[1]: Reached target Sleep.
Sep 08 22:36:14 hostname wpa_supplicant[647]: nl80211: deinit ifname=wlp1s0 disabled_11b_rates=0
Sep 08 22:36:14 hostname wpa_supplicant[647]: wlp1s0: CTRL-EVENT-DSCP-POLICY clear_all
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3730] device (enp197s0f3u1): state change: disconnected -> unmanaged (reason 'unmanaged-sleeping', sys-iface-state: 'managed')
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3718] device (enp197s0f3u1): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Sep 08 22:36:14 hostname systemd[1]: Started Network Manager Script Dispatcher Service.
Sep 08 22:36:14 hostname wpa_supplicant[647]: wlp1s0: CTRL-EVENT-DSCP-POLICY clear_all
Sep 08 22:36:14 hostname systemd[1]: Starting Network Manager Script Dispatcher Service...
Sep 08 22:36:14 hostname kded6[955]: org.kde.plasma.nm.kded: Unhandled active connection state change:  3
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3225] dhcp4 (enp197s0f3u1): state changed no lease
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3225] dhcp4 (enp197s0f3u1): activation: beginning transaction (timeout in 45 seconds)
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3225] dhcp4 (enp197s0f3u1): canceled DHCP transaction
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3225] device (enp197s0f3u1): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.3223] manager: NetworkManager state is now ASLEEP
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.2582] device (wlp1s0): set-hw-addr: reset MAC address to 04:7B:CB:CC:B8:EE (unmanage)
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.1443] device (wlp1s0): state change: disconnected -> unmanaged (reason 'unmanaged-sleeping', sys-iface-state: 'managed')
Sep 08 22:36:14 hostname NetworkManager[591]: <info>  [1725827774.1441] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Sep 08 22:36:14 hostname systemd-logind[595]: The system will hibernate now!

Unsuccessful suspend-then-hibernate

 
Sep 08 22:39:01 hostname kernel: PM: hibernation: hibernation entry                                                    <------- nothing after that, have to kill my pc after freeze
Sep 08 22:39:01 hostname systemd-sleep[2332]: Performing sleep operation 'hibernate'...
Sep 08 22:39:01 hostname kernel: PM: suspend exit
Sep 08 22:39:01 hostname bluetoothd[593]: Controller resume with wake event 0x0
Sep 08 22:39:01 hostname systemd-sleep[2332]: System returned from sleep operation 'suspend-then-hibernate'.
Sep 08 22:39:00 hostname kernel: random: crng reseeded on system resumption
Sep 08 22:39:00 hostname kernel: Restarting tasks ... done.
Sep 08 22:39:00 hostname kernel: OOM killer enabled.
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: PM: failed to resume async: error -5
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xf0 returns -5
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: failed to resume core: -5
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: failed to resume hif during resume: -5
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: failed to resume mhi: -5
Sep 08 22:39:00 hostname kernel: mhi mhi0: Did not enter M0 state, MHI state: M3, PM state: M3->M0
Sep 08 22:39:00 hostname kernel: [drm] ring sdma_32788.3.3 ib test pass
Sep 08 22:39:00 hostname kernel: [drm] ring compute_32788.2.2 ib test pass
Sep 08 22:39:00 hostname kernel: [drm] ring gfx_32788.1.1 ib test pass
Sep 08 22:39:00 hostname kernel: [drm] ring sdma_32788.3.3 was added
Sep 08 22:39:00 hostname kernel: [drm] ring compute_32788.2.2 was added
Sep 08 22:39:00 hostname kernel: [drm] ring gfx_32788.1.1 was added
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring mes_kiq_3.1.0 uses VM inv eng 13 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring jpeg_dec uses VM inv eng 1 on hub 8
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring vcn_unified_0 uses VM inv eng 0 on hub 8
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring sdma0 uses VM inv eng 12 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 11 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 10 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 9 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 8 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 7 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 6 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: ring gfx_0.0.0 uses VM inv eng 0 on hub 0
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: [drm:jpeg_v4_0_hw_init [amdgpu]] JPEG decode initialized successfully.
Sep 08 22:39:00 hostname kernel: [drm] VCN decode and encode initialized successfully(under DPG Mode).
Sep 08 22:39:00 hostname kernel: nvme nvme0: 16/0/0 default/read/poll queues
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: SMU is resumed successfully!
Sep 08 22:39:00 hostname kernel: amdgpu 0000:c3:00.0: amdgpu: SMU is resuming...
Sep 08 22:39:00 hostname kernel: [drm] PCIE GART of 512M enabled (table at 0x0000008000900000).
Sep 08 22:39:00 hostname kernel: mhi mhi0: Resuming from non M3 state (RESET)
Sep 08 22:39:00 hostname kernel: ACPI: EC: interrupt unblocked
Sep 08 22:39:00 hostname kernel: ACPI: EC: interrupt blocked
Sep 08 22:39:00 hostname kernel: pcieport 0000:00:08.3: quirk: disabling D3cold for suspend
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: failed to suspend core: -11
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: failed to enable wow during suspend: -11
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: failed to issue wow enable: -11
Sep 08 22:39:00 hostname kernel: ath11k_pci 0000:01:00.0: wmi command 77828 timeout
Sep 08 22:39:00 hostname kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Sep 08 22:39:00 hostname kernel: Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
Sep 08 22:39:00 hostname kernel: Freezing remaining freezable tasks
Sep 08 22:39:00 hostname kernel: OOM killer disabled.
Sep 08 22:39:00 hostname kernel: Freezing user space processes completed (elapsed 0.001 seconds)
Sep 08 22:39:00 hostname kernel: Freezing user space processes
Sep 08 22:38:23 hostname kernel: Filesystems sync: 0.010 seconds
Sep 08 22:38:23 hostname kernel: PM: suspend entry (s2idle)
Sep 08 22:38:23 hostname systemd-sleep[2332]: Performing sleep operation 'suspend'...
Sep 08 22:38:23 hostname systemd-sleep[2332]: Successfully froze unit 'user.slice'.
Sep 08 22:38:23 hostname systemd[1]: Starting System Suspend then Hibernate...
Sep 08 22:38:23 hostname kernel: ath11k_pci 0000:01:00.0: failed to enable PMF QOS: (-11
Sep 08 22:38:23 hostname kernel: ath11k_pci 0000:01:00.0: failed to send WMI_PDEV_SET_PARAM cmd
Sep 08 22:38:23 hostname kernel: ath11k_pci 0000:01:00.0: wmi command 16387 timeout
Sep 08 22:38:23 hostname systemd[1]: Reached target Sleep.
Sep 08 22:38:23 hostname wpa_supplicant[647]: wlp1s0: CTRL-EVENT-DSCP-POLICY clear_all
Sep 08 22:38:23 hostname NetworkManager[591]: <info>  [1725827903.0865] manager: NetworkManager state is now ASLEEP
Sep 08 22:38:23 hostname wpa_supplicant[647]: wlp1s0: Failed to initialize driver interface
Sep 08 22:38:23 hostname NetworkManager[591]: <info>  [1725827903.0855] device (wlp1s0): state change: unavailable -> unmanaged (reason 'unmanaged-sleeping', sys-iface-state: 'managed')
Sep 08 22:38:23 hostname wpa_supplicant[647]: WEXT: Could not set interface 'wlp1s0' UP
Sep 08 22:38:23 hostname wpa_supplicant[647]: Could not set interface wlp1s0 flags (UP): Resource temporarily unavailable
Sep 08 22:38:21 hostname NetworkManager[591]: <info>  [1725827901.5396] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Sep 08 22:38:21 hostname systemd-logind[595]: The system will suspend and later hibernate now!

Both logs were acquired using systemctl hibernate/suspend-then-hibernate commands.
Using KDEs built in power settings and closing my laptop lid leads to similar results.

My current sleep.conf:

 [Sleep]
#AllowSuspend=yes
#AllowHibernation=yes
AllowSuspendThenHibernate=yes
#AllowHybridSleep=yes
#SuspendState=mem standby freeze
HibernateMode=shutdown
#MemorySleepMode=
HibernateDelaySec=15                             <--------------15s used for testing purposes 
#SuspendEstimationSec=60min

Thank you in advance and any help is appreciated

Last edited by sloth1999 (2024-10-02 07:41:49)

V1del · 2024-09-10 12:22:05

Try disabling the user session freezing introduced in recent systemd versions: https://bbs.archlinux.org/viewtopic.php … 7#p2181817 (also see the last post, you'll have to create such an override for every sleep state/explicitly for suspend-then-hibernate as well)

Last edited by V1del (2024-09-10 12:23:34)

sloth1999 · 2024-10-02 07:41:28

Hello
Thanks for the reply
This unfortunately did not solve the issue.
I managed to get it to work by changing some bios parameters (called something like "allow Windows Firmware updates"), which allowed me to update the firmware using fwupd.

After this, hibernation worked flawlessly for about 3 weeks but now i loose all network connections and network manager does not work until reboot.
But since the hibernation itself works, i will mark it as solved.

Last edited by sloth1999 (2024-10-02 07:42:10)

Arch Linux

#1 2024-09-08 22:25:14

[SOLVED] suspend-then-hibernate freezes on hibernation entry

#2 2024-09-10 12:22:05

Re: [SOLVED] suspend-then-hibernate freezes on hibernation entry

#3 2024-10-02 07:41:28

Re: [SOLVED] suspend-then-hibernate freezes on hibernation entry

Board footer