[SOLVED] hibernate - fresh boot - resume

espritlibre · 2023-05-17 20:15:11

since my thinkpad uses a lot of battery when suspending, i followed the wiki and set up hibernation with swapfile on the root partition. the notebook enters hibernation just fine but when trying to resume the machine reboots and i get greeted by lenovos post image then grub to enter the decryption password followed by grubs menu. when the machine reaches systemd it starts to resume and i end up in the session where i hibernated. on a sidenote i'd like to mention that bluetooth is enabled but it was disabled when hibernated.
i use full disk encryption with encrypted boot, basically an ext4 VolGroup inside and luks1 encrypted partition. running kernel is linux-hardened with

CONFIG_HIBERNATION=y

but the result is the same on the distro kernel linux 6.3.2
where am i screwing up?

blkid
/dev/mapper/espritlibre_VolGroup-root: UUID="60d51440-8fd1-4979-a5b5-893601df59bd" BLOCK_SIZE="4096" TYPE="ext4"
/dev/nvme0n1p1: UUID="138E-D91B" BLOCK_SIZE="512" TYPE="vfat" PARTLABEL="EFI system partition" PARTUUID="8c0ad4ca-0ca5-47ab-a81c-f3b5644c95a4"
/dev/nvme0n1p2: UUID="4f1ca192-c9f6-4d00-8ce7-a9615f3b1635" TYPE="crypto_LUKS" PARTLABEL="Linux LUKS" PARTUUID="22292a7e-b349-48db-94e1-76e4db685864"
/dev/mapper/espritlibre_VolGroup-home: UUID="d51de7b7-07ed-4c47-a6b8-999250409d67" BLOCK_SIZE="4096" TYPE="ext4"
/dev/mapper/cryptlvm: UUID="BifA6o-UugM-Asc8-mrJE-BJEQ-Fu5X-dZuGwf" TYPE="LVM2_member"
/dev/mapper/espritlibre_VolGroup-files: UUID="0829ce3b-dfff-4489-920b-c7c9a35fb233" BLOCK_SIZE="4096" TYPE="ext4"

findmnt -no UUID -T /swapfile
60d51440-8fd1-4979-a5b5-893601df59bd

filefrag -v /swapfile | awk '$1=="0:" {print substr($4, 1, length($4)-2)}'
16762880

cat /etc/mkinitcpion.conf
...
HOOKS=(base udev autodetect keyboard keymap consolefont modconf block encrypt lvm2 resume filesystems fsck)
...

cat /proc/cmdline                                                                                                                                                       
pti=on page_alloc.shuffle=1 BOOT_IMAGE=/boot/vmlinuz-linux-hardened root=/dev/mapper/espritlibre_VolGroup-root rw cryptdevice=UUID=4f1ca192-c9f6-4d00-8ce7-a9615f3b1635:cryptlvm cryptkey=rootfs:/root/cryptlvm.keyfile ibt=off net.ifnames=0 lsm=landlock,yama,apparmor,integrity,bpf nvidia_drm.modeset=1 intel_iommu=on iommu=pt ipv6.disable=1 loglevel=3 resume=UUID=60d51440-8fd1-4979-a5b5-893601df59bd resume_offset=16762880

journalctl
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x0009f000-0x000fffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x85a11000-0x85c51fff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x86b6d000-0x86b6dfff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x8bb6f000-0x8bc3efff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x8bc3f000-0x90b2efff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x90b2f000-0x9172efff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x9172f000-0x917fefff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x91800000-0x95ffffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x96000000-0x97dfffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x97e00000-0x97ffffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x98000000-0x987fffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0x98800000-0xa07fffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0xa0800000-0xbfffffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0xc0000000-0xcfffffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0xd0000000-0xfed1ffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0xfed20000-0xfed7ffff]
Mai 16 17:41:24 P14s kernel: PM: hibernation: Registered nosave memory: [mem 0xfed80000-0xffffffff]
Mai 16 17:41:30 P14s gsd-media-keys[2122]: Failed to grab accelerator for keybinding settings:hibernate
Mai 16 17:41:36 P14s gsd-media-keys[2961]: Failed to grab accelerator for keybinding settings:hibernate
Mai 16 17:44:07 P14s systemd-logind[1601]: The system will hibernate now!
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.1550] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Mai 16 17:44:07 P14s polkitd[1771]: Unregistered Authentication Agent for unix-process:5545:17233 (system bus name :1.99, object path /org/freedesktop/PolicyKit1/Authentica>
Mai 16 17:44:07 P14s variety[3073]: gtk_widget_get_scale_factor: assertion 'GTK_IS_WIDGET (widget)' failed
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.5553] device (eth0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mai 16 17:44:07 P14s kernel: e1000e 0000:00:1f.6 eth0: NIC Link is Down
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.6422] device (p2p-dev-wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'm>
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.6422] manager: NetworkManager state is now ASLEEP
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.6423] device (wlan0): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Mai 16 17:44:07 P14s dbus-daemon[1595]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requ>
Mai 16 17:44:07 P14s systemd[1]: Starting Network Manager Script Dispatcher Service...
Mai 16 17:44:07 P14s dbus-daemon[1595]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Mai 16 17:44:07 P14s systemd[1]: Started Network Manager Script Dispatcher Service.
Mai 16 17:44:07 P14s kernel: wlan0: deauthenticating from 2c:3a:fd:16:63:89 by local choice (Reason: 3=DEAUTH_LEAVING)
Mai 16 17:44:07 P14s wpa_supplicant[1976]: wlan0: CTRL-EVENT-DISCONNECTED bssid=2c:3a:fd:16:63:89 reason=3 locally_generated=1
Mai 16 17:44:07 P14s wpa_supplicant[1976]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.8323] device (wlan0): supplicant interface state: completed -> disconnected
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.8324] device (wlan0): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'manage>
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.8684] dhcp4 (wlan0): canceled DHCP transaction
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.8684] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds)
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.8685] dhcp4 (wlan0): state changed no lease
Mai 16 17:44:07 P14s wpa_supplicant[1976]: wlan0: PMKSA-CACHE-REMOVED 2c:3a:fd:16:63:89 0
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.9048] device (wlan0): set-hw-addr: set MAC address to EA:F4:A5:BA:9D:53 (scanning)
Mai 16 17:44:07 P14s NetworkManager[1614]: <info>  [1684251847.9557] device (wlan0): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Mai 16 17:44:08 P14s NetworkManager[1614]: <info>  [1684251848.0232] device (wlan0): set-hw-addr: reset MAC address to 04:CF:4B:78:54:DD (unmanage)
Mai 16 17:44:08 P14s nm-dispatcher[5588]: Chrony going offline.
Mai 16 17:44:08 P14s chronyd[1608]: Source 148.251.54.81 offline
Mai 16 17:44:08 P14s nm-dispatcher[5588]: 200 OK
Mai 16 17:44:08 P14s chronyd[1608]: Source 162.159.200.123 offline
Mai 16 17:44:08 P14s chronyd[1608]: Source 88.99.86.9 offline
Mai 16 17:44:08 P14s chronyd[1608]: Can't synchronise: no selectable sources
Mai 16 17:44:08 P14s chronyd[1608]: Source 178.215.228.24 offline
Mai 16 17:44:08 P14s wpa_supplicant[1976]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 16 17:44:08 P14s wpa_supplicant[1976]: p2p-dev-wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 16 17:44:08 P14s wpa_supplicant[1976]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0
Mai 16 17:44:08 P14s wpa_supplicant[1976]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
lines 2255-2300
Mai 16 17:44:08 P14s wpa_supplicant[1976]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all
Mai 16 17:44:08 P14s wpa_supplicant[1976]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Mai 16 17:44:08 P14s systemd[1]: Reached target Sleep.
Mai 16 17:44:08 P14s systemd[1]: Starting NVIDIA system hibernate actions...
Mai 16 17:44:08 P14s hibernate[5595]: nvidia-hibernate.service
Mai 16 17:44:08 P14s logger[5595]: <13>May 16 17:44:08 hibernate: nvidia-hibernate.service
Mai 16 17:44:08 P14s kernel: rfkill: input handler enabled
Mai 16 17:44:08 P14s gsd-media-keys[2961]: gvc_mixer_card_get_index: assertion 'GVC_IS_MIXER_CARD (card)' failed
Mai 16 17:44:08 P14s gsd-media-keys[2961]: gvc_mixer_card_get_index: assertion 'GVC_IS_MIXER_CARD (card)' failed
Mai 16 17:44:08 P14s gsd-media-keys[2961]: Unable to get default sink
Mai 16 17:44:09 P14s gnome-shell[2558]: gvc_mixer_card_get_index: assertion 'GVC_IS_MIXER_CARD (card)' failed
Mai 16 17:44:09 P14s gnome-shell[2558]: gvc_mixer_card_get_index: assertion 'GVC_IS_MIXER_CARD (card)' failed
Mai 16 17:44:10 P14s systemd[1]: nvidia-hibernate.service: Deactivated successfully.
Mai 16 17:44:10 P14s systemd[1]: Finished NVIDIA system hibernate actions.
Mai 16 17:44:10 P14s systemd[1]: Starting Hibernate...
Mai 16 17:44:10 P14s systemd-sleep[5638]: Entering sleep state 'hibernate'...
Mai 16 17:44:10 P14s kernel: PM: hibernation: hibernation entry
Mai 16 17:46:08 P14s kernel: Filesystems sync: 0.009 seconds
Mai 16 17:46:08 P14s kernel: Freezing user space processes
Mai 16 17:46:08 P14s kernel: Freezing user space processes completed (elapsed 0.001 seconds)
Mai 16 17:46:08 P14s kernel: OOM killer disabled.
Mai 16 17:46:08 P14s kernel: PM: hibernation: Marking nosave pages: [mem 0x00000000-0x00000fff]
Mai 16 17:46:08 P14s kernel: PM: hibernation: Marking nosave pages: [mem 0x0009f000-0x000fffff]
Mai 16 17:46:08 P14s kernel: PM: hibernation: Marking nosave pages: [mem 0x85a11000-0x85c51fff]
Mai 16 17:46:08 P14s kernel: PM: hibernation: Marking nosave pages: [mem 0x86b6d000-0x86b6dfff]
Mai 16 17:46:08 P14s kernel: PM: hibernation: Marking nosave pages: [mem 0x8bb6f000-0x917fefff]
Mai 16 17:46:08 P14s kernel: PM: hibernation: Marking nosave pages: [mem 0x91800000-0xffffffff]
Mai 16 17:46:08 P14s kernel: PM: hibernation: Basic memory bitmaps created
Mai 16 17:46:08 P14s kernel: PM: hibernation: Preallocating image memory
Mai 16 17:46:08 P14s kernel: PM: hibernation: Allocated 1125890 pages for snapshot
Mai 16 17:46:08 P14s kernel: PM: hibernation: Allocated 4503560 kbytes in 0.55 seconds (8188.29 MB/s)
Mai 16 17:46:08 P14s kernel: Freezing remaining freezable tasks
Mai 16 17:46:08 P14s kernel: Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
Mai 16 17:46:08 P14s kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Mai 16 17:46:08 P14s kernel: ACPI: EC: interrupt blocked
Mai 16 17:46:08 P14s kernel: ACPI: PM: Preparing to enter system sleep state S4
Mai 16 17:46:08 P14s kernel: ACPI: EC: event blocked
Mai 16 17:46:08 P14s kernel: ACPI: EC: EC stopped
Mai 16 17:46:08 P14s kernel: ACPI: PM: Saving platform NVS memory
Mai 16 17:46:08 P14s kernel: Disabling non-boot CPUs ...
Mai 16 17:46:08 P14s kernel: smpboot: CPU 1 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 2 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 3 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 4 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 5 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 6 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 7 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 8 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 9 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 10 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 11 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 12 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 13 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 14 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 15 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 16 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 17 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 18 is now offline
Mai 16 17:46:08 P14s kernel: smpboot: CPU 19 is now offline
Mai 16 17:46:08 P14s kernel: PM: hibernation: Creating image:
Mai 16 17:46:08 P14s kernel: PM: hibernation: Need to copy 1102024 pages
Mai 16 17:46:08 P14s kernel: PM: hibernation: Normal pages needed: 1102024 + 1024, available pages: 7199736
Mai 16 17:46:08 P14s kernel: PM: hibernation: free pages cleared after restore
Mai 16 17:46:08 P14s kernel: ACPI: PM: Restoring platform NVS memory
Mai 16 17:46:08 P14s kernel: ACPI: EC: EC started
Mai 16 17:46:08 P14s kernel: Enabling non-boot CPUs ...
Mai 16 17:46:08 P14s kernel: x86: Booting SMP configuration:
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Mai 16 17:46:08 P14s kernel: CPU1 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 2 APIC 0x8
Mai 16 17:46:08 P14s kernel: CPU2 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 3 APIC 0x9
Mai 16 17:46:08 P14s kernel: CPU3 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 4 APIC 0x10
Mai 16 17:46:08 P14s kernel: CPU4 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 5 APIC 0x11
Mai 16 17:46:08 P14s kernel: CPU5 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 6 APIC 0x18
Mai 16 17:46:08 P14s kernel: CPU6 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 7 APIC 0x19
Mai 16 17:46:08 P14s kernel: CPU7 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 8 APIC 0x20
Mai 16 17:46:08 P14s kernel: CPU8 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 9 APIC 0x21
Mai 16 17:46:08 P14s kernel: CPU9 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 10 APIC 0x28
Mai 16 17:46:08 P14s kernel: CPU10 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 11 APIC 0x29
Mai 16 17:46:08 P14s kernel: CPU11 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 12 APIC 0x30
Mai 16 17:46:08 P14s kernel: core: cpu_atom PMU driver: PEBS-via-PT 
Mai 16 17:46:08 P14s kernel: ... version:                5
Mai 16 17:46:08 P14s kernel: ... bit width:              48
Mai 16 17:46:08 P14s kernel: ... generic registers:      6
Mai 16 17:46:08 P14s kernel: ... value mask:             0000ffffffffffff
Mai 16 17:46:08 P14s kernel: ... max period:             00007fffffffffff
Mai 16 17:46:08 P14s kernel: ... fixed-purpose events:   3
Mai 16 17:46:08 P14s kernel: ... event mask:             000000070000003f
Mai 16 17:46:08 P14s kernel: CPU12 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 13 APIC 0x32
Mai 16 17:46:08 P14s kernel: CPU13 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 14 APIC 0x34
Mai 16 17:46:08 P14s kernel: CPU14 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 15 APIC 0x36
Mai 16 17:46:08 P14s kernel: CPU15 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 16 APIC 0x38
Mai 16 17:46:08 P14s kernel: CPU16 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 17 APIC 0x3a
Mai 16 17:46:08 P14s kernel: CPU17 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 18 APIC 0x3c
Mai 16 17:46:08 P14s kernel: CPU18 is up
Mai 16 17:46:08 P14s kernel: smpboot: Booting Node 0 Processor 19 APIC 0x3e
Mai 16 17:46:08 P14s kernel: CPU19 is up
Mai 16 17:46:08 P14s kernel: ACPI: PM: Waking up from system sleep state S4
Mai 16 17:46:08 P14s kernel: ACPI: EC: interrupt unblocked
Mai 16 17:46:08 P14s kernel: ACPI: EC: event unblocked
Mai 16 17:46:08 P14s kernel: usb usb1: root hub lost power or was reset
Mai 16 17:46:08 P14s kernel: usb usb2: root hub lost power or was reset
Mai 16 17:46:08 P14s kernel: usb usb3: root hub lost power or was reset
Mai 16 17:46:08 P14s kernel: usb usb4: root hub lost power or was reset
Mai 16 17:46:08 P14s kernel: nvme nvme0: Shutdown timeout set to 10 seconds
Mai 16 17:46:08 P14s kernel: nvme nvme0: 20/0/0 default/read/poll queues
Mai 16 17:46:08 P14s kernel: i915 0000:00:02.0: [drm] GuC firmware i915/adlp_guc_70.bin version 70.5.1
Mai 16 17:46:08 P14s kernel: i915 0000:00:02.0: [drm] HuC firmware i915/tgl_huc.bin version 7.9.3
Mai 16 17:46:08 P14s kernel: i915 0000:00:02.0: [drm] HuC authenticated
Mai 16 17:46:08 P14s kernel: i915 0000:00:02.0: [drm] GuC submission enabled
Mai 16 17:46:08 P14s kernel: i915 0000:00:02.0: [drm] GuC SLPC enabled
Mai 16 17:46:08 P14s kernel: i915 0000:00:02.0: [drm] GuC RC: enabled
Mai 16 17:46:08 P14s kernel: usb 3-3: reset full-speed USB device number 3 using xhci_hcd
Mai 16 17:46:08 P14s kernel: usb 3-4: reset high-speed USB device number 4 using xhci_hcd
Mai 16 17:46:08 P14s kernel: usb 3-1: reset full-speed USB device number 2 using xhci_hcd
Mai 16 17:46:08 P14s kernel: PM: hibernation: Basic memory bitmaps freed
Mai 16 17:46:08 P14s kernel: mei_hdcp 0000:00:16.0-b638ab7e-94e2-4ea2-a552-d1c54b627f04: bound 0000:00:02.0 (ops i915_hdcp_component_ops [i915])
Mai 16 17:46:08 P14s kernel: OOM killer enabled.
Mai 16 17:46:08 P14s kernel: Restarting tasks ... 
Mai 16 17:46:08 P14s kernel: mei_pxp 0000:00:16.0-fbf6fcf1-96cf-4e2e-a6a6-1bab8cbe36b1: bound 0000:00:02.0 (ops i915_pxp_tee_component_ops [i915])
Mai 16 17:46:08 P14s kernel: done.
Mai 16 17:46:08 P14s zcfan[1603]: [FAN] Temperature now 67C, fan set to low
Mai 16 17:46:08 P14s tlp[5771]: Warning: systemd-rfkill.service is not masked, radio device switching may not work as configured.
Mai 16 17:46:08 P14s tlp[5771]: >>> Invoke 'systemctl mask systemd-rfkill.service' to correct this.
Mai 16 17:46:08 P14s tlp[5771]: Warning: systemd-rfkill.socket is not masked, radio device switching may not work as configured.
Mai 16 17:46:08 P14s tlp[5771]: >>> Invoke 'systemctl mask systemd-rfkill.socket' to correct this.
Mai 16 17:46:08 P14s systemd-sleep[5638]: System returned from sleep state.
Mai 16 17:46:08 P14s kernel: PM: hibernation: hibernation exit
Mai 16 17:46:08 P14s kernel: usb 3-10: new full-speed USB device number 7 using xhci_hcd
Mai 16 17:46:09 P14s kernel: usb 3-10: New USB device found, idVendor=8087, idProduct=0033, bcdDevice= 0.00
Mai 16 17:46:09 P14s kernel: usb 3-10: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Device revision is 0
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Secure boot is enabled
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: OTP lock is enabled
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: API lock is enabled
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Debug lock is disabled
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Minimum firmware build 1 week 10 2014
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Bootloader timestamp 2019.40 buildtype 1 build 38
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Found device firmware: intel/ibt-0040-0041.sfi
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Boot Address: 0x100800
Mai 16 17:46:09 P14s kernel: Bluetooth: hci0: Firmware Version: 172-6.23
Mai 16 17:46:09 P14s systemd[1]: Starting Load/Save RF Kill Switch Status...
Mai 16 17:46:09 P14s systemd[1]: systemd-hibernate.service: Deactivated successfully.
Mai 16 17:46:09 P14s systemd[1]: Finished Hibernate.
Mai 16 17:46:09 P14s systemd[1]: systemd-hibernate.service: Consumed 4.119s CPU time.
Mai 16 17:46:09 P14s systemd[1]: Reached target System Hibernation.
Mai 16 17:46:09 P14s systemd[1]: Stopped target Sleep.
Mai 16 17:46:09 P14s systemd-logind[1601]: Operation 'sleep' finished.
Mai 16 17:46:09 P14s kernel: rfkill: input handler disabled
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.1555] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.1558] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mai 16 17:46:09 P14s systemd[1]: Starting NVIDIA system resume actions...
Mai 16 17:46:09 P14s systemd[1]: Stopped target System Hibernation.
Mai 16 17:46:09 P14s suspend[5989]: nvidia-resume.service
Mai 16 17:46:09 P14s logger[5989]: <13>May 16 17:46:09 suspend: nvidia-resume.service
Mai 16 17:46:09 P14s systemd[1]: nvidia-resume.service: Deactivated successfully.
Mai 16 17:46:09 P14s systemd[1]: Finished NVIDIA system resume actions.
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3520] device (wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3522] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'ext>
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3523] manager: NetworkManager state is now DISCONNECTED
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3872] device (wlan0): supplicant interface state: internal-starting -> disconnected
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3872] device (p2p-dev-wlan0): state change: unavailable -> unmanaged (reason 'removed', sys-iface-state: 'rem>
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3874] Wi-Fi P2P device controlled by interface wlan0 created
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3875] manager: (p2p-dev-wlan0): new 802.11 Wi-Fi P2P device (/org/freedesktop/NetworkManager/Devices/5)
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3876] device (p2p-dev-wlan0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'ext>
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3879] device (wlan0): state change: unavailable -> disconnected (reason 'supplicant-available', sys-iface-sta>
Mai 16 17:46:09 P14s NetworkManager[1614]: <info>  [1684251969.3881] device (p2p-dev-wlan0): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'man>
Mai 16 17:46:09 P14s wireplumber[2768]: <WpPortalPermissionStorePlugin:0x1da809bf5b0> Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for>
lines 2439-2484
Mai 16 17:46:09 P14s wireplumber[2768]: <WpPortalPermissionStorePlugin:0x1da809bf5b0> Failed to call Lookup: GDBus.Error:org.freedesktop.portal.Error.NotFound: No entry for>
Mai 16 17:46:09 P14s gsd-media-keys[2961]: gvc_mixer_card_get_index: assertion 'GVC_IS_MIXER_CARD (card)' failed
Mai 16 17:46:09 P14s gsd-media-keys[2961]: Unable to get default source
Mai 16 17:46:09 P14s systemd[2406]: Reached target Bluetooth.
Mai 16 17:46:09 P14s systemd[1]: Started Load/Save RF Kill Switch Status.
Mai 16 17:46:09 P14s systemd[1]: Reached target Bluetooth Support.
Mai 16 17:46:09 P14s dbus-daemon[1595]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' requested by ':1.105>
Mai 16 17:46:09 P14s dbus-daemon[1595]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.Avahi.service': Unit dbus-org.freedesktop.Avahi.service not fo>
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Waiting for firmware download to complete
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Firmware loaded in 1632110 usecs
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Waiting for device to boot
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Malformed MSFT vendor event: 0x02
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Device booted in 16182 usecs
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-0040-0041.ddc
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Applying Intel DDC parameters completed
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Firmware timestamp 2023.6 buildtype 1 build 59564
Mai 16 17:46:10 P14s bluetoothd[1592]: Battery Provider Manager created
Mai 16 17:46:10 P14s kernel: Bluetooth: MGMT ver 1.22
Mai 16 17:46:10 P14s kernel: Bluetooth: RFCOMM TTY layer initialized
Mai 16 17:46:10 P14s kernel: Bluetooth: RFCOMM socket layer initialized
Mai 16 17:46:10 P14s kernel: Bluetooth: RFCOMM ver 1.11
Mai 16 17:46:10 P14s bluetoothd[1592]: src/device.c:device_set_wake_support() Unable to set wake_support without RPA resolution
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: offload codecs enable 1 changed 1
Mai 16 17:46:10 P14s kernel: Bluetooth: ISO socket registration failed
Mai 16 17:46:10 P14s bluetoothd[1592]: Adv Monitor Manager created with supported features:0x00000001, enabled features:0x00000001, max number of supported monitors:32, max>
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/ldac
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/aptx_hd
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aptx_hd
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/aptx
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aptx
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/aac
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aac
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/sbc
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/sbc
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/sbc_xq
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/sbc_xq
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aptx_ll_1
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aptx_ll_0
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_1
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/aptx_ll_duplex_0
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/faststream
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/faststream_duplex
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/opus_05
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/opus_05
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSink/opus_05_duplex
Mai 16 17:46:10 P14s bluetoothd[1592]: Endpoint registered: sender=:1.103 path=/MediaEndpoint/A2DPSource/opus_05_duplex

Last edited by espritlibre (2023-05-17 21:21:52)

seth · 2023-05-17 20:43:41

but when trying to resume the machine reboots and i get greeted by lenovos post image then grub to enter the decryption password followed by grubs menu. when the machine reaches systemd it starts to resume and i end up in the session where i hibernated.

So?
Where's the problem?

on a sidenote i'd like to mention that bluetooth is enabled but it was disabled when hibernated.

Do yo upossibly use sth. like https://wiki.archlinux.org/title/Blueto … le_devices ?

espritlibre · 2023-05-17 20:50:25

seth wrote:

So?
Where's the problem?

my expectation from hibernation is when i resume i land directly at the gdm login screen, just like when suspending. no?

seth wrote:

Do yo upossibly use sth. like https://wiki.archlinux.org/title/Blueto … le_devices ?

no, bluetooth is disabled when i start hibernating

seth · 2023-05-17 21:01:25

S3 doesn't land you "at the the gdm login screen", the session just gets locked and the locker looks like GDM
S4 is effectively a reboot, but the memory gets saved and then reloaded from disk.
Whether that also results in a locked session is down to the sessions config (though w/ gnome I'd expect so)
What you see is most likely normal. Your windows are all still there again and even unsaved documents are exactly as you left them?

no, bluetooth is disabled when i start hibernating

The relevant part of that thing would be

ExecStop=/usr/bin/bluetoothctl power on

Mai 16 17:46:08 P14s tlp[5771]: Warning: systemd-rfkill.service is not masked, radio device switching may not work as configured.
Mai 16 17:46:08 P14s tlp[5771]: >>> Invoke 'systemctl mask systemd-rfkill.service' to correct this.
Mai 16 17:46:08 P14s tlp[5771]: Warning: systemd-rfkill.socket is not masked, radio device switching may not work as configured.
Mai 16 17:46:08 P14s tlp[5771]: >>> Invoke 'systemctl mask systemd-rfkill.socket' to correct this.

and

Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Waiting for firmware download to complete
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Firmware loaded in 1632110 usecs
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Waiting for device to boot
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Malformed MSFT vendor event: 0x02
Mai 16 17:46:10 P14s kernel: Bluetooth: hci0: Device booted in 16182 usecs

might be relevant (the BT firmware is loaded after the hibernation finished)
Is this also a problem w/ the regular S3?

You can try to lift the linked service and only

ExecStop=/usr/bin/bluetoothctl power off

espritlibre · 2023-05-17 21:21:23

thank you for the clarification what hibernation means, since i'm setting up hibernation for the first time i didn't know what to expect. (on previous notebooks i didn't have so much battery usage while suspending)
i'm not using s3 since that caused trouble when i first set up this notebook, i'm using modern standby s0 or "Windows & Linux" how the firmware calls it, with older kernels i got 1%/h but with newer kernel i get like 3%/h battery usage which is too much. i don't know when this started happening but i hope this will get fixed again in the kernel.

i'm marking this thread as solved, thanks seth

rep_movsd · 2023-07-03 10:54:30

espritlibre wrote:

thank you for the clarification what hibernation means, since i'm setting up hibernation for the first time i didn't know what to expect. (on previous notebooks i didn't have so much battery usage while suspending)
i'm not using s3 since that caused trouble when i first set up this notebook, i'm using modern standby s0 or "Windows & Linux" how the firmware calls it, with older kernels i got 1%/h but with newer kernel i get like 3%/h battery usage which is too much. i don't know when this started happening but i hope this will get fixed again in the kernel.
i'm marking this thread as solved, thanks seth

Intel has gotten rid of S3 in most new processors, so chances of getting proper "sleep/suspend" mode are 0

seth · 2023-07-03 15:09:15

Source?
https://en.wikipedia.org/wiki/ACPI#Global_states isn't even a processor state.

Arch Linux

#1 2023-05-17 20:15:11

[SOLVED] hibernate - fresh boot - resume

#2 2023-05-17 20:43:41

Re: [SOLVED] hibernate - fresh boot - resume

#3 2023-05-17 20:50:25

Re: [SOLVED] hibernate - fresh boot - resume

#4 2023-05-17 21:01:25

Re: [SOLVED] hibernate - fresh boot - resume

#5 2023-05-17 21:21:23

Re: [SOLVED] hibernate - fresh boot - resume

#6 2023-07-03 10:54:30

Re: [SOLVED] hibernate - fresh boot - resume

#7 2023-07-03 15:09:15

Re: [SOLVED] hibernate - fresh boot - resume

Board footer