https://bugs.kde.org/show_bug.cgi?id=469387
Bug ID: 469387
Summary: Hibernation fails without Error Output
Classification: Plasma
Product: Powerdevil
Version: 5.27.4
Platform: Archlinux
OS: Linux
Status: REPORTED
Severity: normal
Priority: NOR
Component: general
Assignee: plasma-b...@kde.org
Reporter: leo.bar...@gmail.com
CC: m...@ratijas.tk, natalie_clar...@yahoo.de
Target Milestone: ---
SUMMARY
***
When I use powerdevil to Hibernate it often fails even with correct setup. It
might be a systemd or kernel bug but I am not sure.
***
STEPS TO REPRODUCE:
swapon --show:
NAME TYPE SIZE USED PRIO
/dev/nvme0n1p4 partition 31,3G 3,5G -2
free -h:
total used free shared Buffers cached
RAM: 7,1Gi 4,4Gi 1,5Gi 184Mi 1,1Gi
2,3Gi
Swap: 31Gi 3,5Gi 27Gi
lsblk:
nvme0n1 259:0 0 476,9G 0 disk
├─nvme0n1p1 259:1 0 511M 0 part /boot
├─nvme0n1p2 259:2 0 98,1G 0 part /
├─nvme0n1p3 259:3 0 347,1G 0 part /home
└─nvme0n1p4 259:4 0 31,3G 0 part [SWAP]
lsblk -dno UUID /dev/nvme0n1p4:
ee28083a-4963-429b-b1c9-8d045cba8f05
/etc/fstab:
# /dev/nvme0n1p4
UUID=ee28083a-4963-429b-b1c9-8d045cba8f05 none swap defaults 0 0
/etc/default/grub:
GRUB_CMDLINE_LINUX_DEFAULT="systemd.zram=0 loglevel=3 quiet
resume=UUID=ee28083a-4963-429b-b1c9-8d045cba8f05"
/etc/mkinitcpio.conf:
HOOKS=(base systemd autodetect keyboard sd-vconsole modconf block resume
filesystems fsck)
executed:
sudo grub-mkconfig -o /boot/grub/grub.cfg
sudo mkinitcpio -p linux
systemctl hibernate
journalctl -u systemd-hibernate.service:
-- Boot f765e8261b17431c998b641c69f6f5b2 --
Mai 05 00:39:52 archlinux systemd[1]: Starting Hibernate...
Mai 05 00:39:52 archlinux systemd-sleep[15949]: Entering sleep state
'hibernate'...
Mai 05 00:39:58 archlinux systemd-sleep[15949]: Failed to put system to sleep.
System resumed again: Cannot allocate memory
Mai 05 00:39:58 archlinux systemd[1]: systemd-hibernate.service: Main process
exited, code=exited, status=1/FAILURE
Mai 05 00:39:58 archlinux systemd[1]: systemd-hibernate.service: Failed with
result 'exit-code'.
Mai 05 00:39:58 archlinux systemd[1]: Failed to start Hibernate.
Mai 05 00:39:58 archlinux systemd[1]: systemd-hibernate.service: Consumed
4.313s CPU time.
dmesg:
[12302.049869] PM: hibernation: hibernation entry
[12302.059305] Filesystems sync: 0.003 seconds
[12302.059422] Freezing user space processes
[12302.062076] Freezing user space processes completed (elapsed 0.002 seconds)
[12302.062081] OOM killer disabled.
[12302.062156] PM: hibernation: Marking nosave pages: [mem
0x00000000-0x00000fff]
[12302.062159] PM: hibernation: Marking nosave pages: [mem
0x000a0000-0x000fffff]
[12302.062161] PM: hibernation: Marking nosave pages: [mem
0x09ed0000-0x09ffffff]
[12302.062163] PM: hibernation: Marking nosave pages: [mem
0x0a200000-0x0a20ffff]
[12302.062164] PM: hibernation: Marking nosave pages: [mem
0xc4219000-0xc422cfff]
[12302.062165] PM: hibernation: Marking nosave pages: [mem
0xc5b9b000-0xc5b9bfff]
[12302.062166] PM: hibernation: Marking nosave pages: [mem
0xc5dfa000-0xc5ffffff]
[12302.062170] PM: hibernation: Marking nosave pages: [mem
0xc728a000-0xccffdfff]
[12302.062326] PM: hibernation: Marking nosave pages: [mem
0xce000000-0xffffffff]
[12302.062970] PM: hibernation: Basic memory bitmaps created
[12302.063793] PM: hibernation: Preallocating image memory
[12306.297472] PM: hibernation: Allocated 919729 pages for snapshot
[12306.297477] PM: hibernation: Allocated 3678916 kbytes in 4.23 seconds
(869.72 MB/s)
[12306.297480] Freezing remaining freezable tasks
[12306.298934] Freezing remaining freezable tasks completed (elapsed 0.001
seconds)
[12306.299061] printk: Suspending console(s) (use no_console_suspend to debug)
[12306.327918] atkbd serio0: Disabling IRQ1 wakeup source to avoid platform
firmware bug
[12306.327925] ------------[ cut here ]------------
[12306.327926] Unbalanced IRQ 1 wake disable
[12306.327932] WARNING: CPU: 2 PID: 15949 at kernel/irq/manage.c:910
irq_set_irq_wake+0x15e/0x1b0
[12306.327944] Modules linked in: udp_diag tcp_diag inet_diag xfs btrfs
blake2b_generic xor raid6_pq libcrc32c snd_seq_dummy snd_hrtimer snd_seq
snd_seq_device hid_logitech_hidpp tun hid_logitech_dj amdgpu snd_acp3x_rn
snd_acp3x_pdm_dma snd_soc_dmic snd_sof_amd_rembrandt snd_sof_amd_renoir
snd_sof_amd_acp snd_sof_pci snd_sof_xtensa_dsp snd_sof snd_hda_codec_realtek
snd_sof_utils snd_hda_codec_generic snd_soc_core ledtrig_audio
snd_hda_codec_hdmi snd_compress ac97_bus snd_pcm_dmaengine snd_hda_intel btusb
snd_pci_ps snd_intel_dspcfg btrtl snd_intel_sdw_acpi btbcm snd_rpl_pci_acp6x
snd_hda_codec btintel snd_acp_pci drm_buddy btmtk uas snd_pci_acp6x
snd_hda_core gpu_sched snd_hwdep i2c_algo_bit bluetooth drm_ttm_helper snd_pcm
snd_pci_acp5x usb_storage usbhid ecdh_generic ttm snd_timer snd_rn_pci_acp3x
drm_display_helper snd snd_acp_config cec snd_soc_acpi soundcore snd_pci_acp3x
cdc_mbim intel_rapl_msr cdc_wdm intel_rapl_common edac_mce_amd kvm_amd
rtw88_8821ce rtw88_8821c joydev rtw88_pci mousedev ccp rtw88_core kvm
[12306.328033] uvcvideo mac80211 irqbypass videobuf2_vmalloc uvc
crct10dif_pclmul crc32_pclmul libarc4 vfat videobuf2_memops polyval_clmulni
hp_wmi videobuf2_v4l2 fat hid_multitouch polyval_generic cdc_ncm sparse_keymap
cfg80211 gf128mul wmi_bmof cdc_ether platform_profile ghash_clmulni_intel
videodev sha512_ssse3 aesni_intel usbnet sp5100_tco videobuf2_common
crypto_simd cryptd rapl video pcspkr mii k10temp i2c_piix4 rfkill mc wmi
wireless_hotkey i2c_hid_acpi i2c_hid acpi_cpufreq amd_pmc acpi_tad mac_hid
vboxnetflt(OE) vboxnetadp(OE) vboxdrv(OE) pkcs8_key_parser crypto_user fuse
dm_mod loop ip_tables x_tables ext4 crc32c_generic crc16 mbcache jbd2 serio_raw
atkbd libps2 vivaldi_fmap nvme nvme_core xhci_pci crc32c_intel xhci_pci_renesas
nvme_common i8042 serio
[12306.328111] CPU: 2 PID: 15949 Comm: systemd-sleep Tainted: G OE
6.3.1-arch1-1 #1 2f4443c3fa3529b1ac13dc02f36f7de43ade3ecd
[12306.328116] Hardware name: HP HP Laptop 15s-eq1xxx/8707, BIOS F.61
03/25/2022
[12306.328119] RIP: 0010:irq_set_irq_wake+0x15e/0x1b0
[12306.328125] Code: f6 e8 06 ca c1 00 89 c5 85 c0 74 38 c7 83 c4 00 00 00 01
00 00 00 e9 32 ff ff ff 44 89 e6 48 c7 c7 68 33 a3 91 e8 e2 64 f6 ff <0f> 0b 31
ed e9 1a ff ff ff 48 8b 43 78 31 ed 81 08 00 40 00 00 e9
[12306.328128] RSP: 0018:ffffb709057ffc98 EFLAGS: 00010082
[12306.328132] RAX: 0000000000000000 RBX: ffff993280184000 RCX:
0000000000000027
[12306.328134] RDX: ffff9933876a1688 RSI: 0000000000000001 RDI:
ffff9933876a1680
[12306.328136] RBP: 00000000ffffffea R08: ffffffff9225a1c0 R09:
0000000000ffff0a
[12306.328138] R10: 0000000000000000 R11: 000000002d2d2d2d R12:
0000000000000001
[12306.328140] R13: 0000000000000000 R14: ffffffff919f55e6 R15:
ffff993314ed06e0
[12306.328142] FS: 00007f40f5f81d80(0000) GS:ffff993387680000(0000)
knlGS:0000000000000000
[12306.328145] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[12306.328147] CR2: 00007f26beffe896 CR3: 0000000118930000 CR4:
0000000000350ee0
[12306.328150] Call Trace:
[12306.328154] <TASK>
[12306.328160] ? __pfx_acpi_subsys_freeze+0x10/0x10
[12306.328170] amd_pmc_suspend_handler+0x90/0x100 [amd_pmc
0053ed6b09580e821e32aa43c6b7d3132e80e1af]
[12306.328183] ? acpi_subsys_freeze+0x14/0x20
[12306.328187] dpm_run_callback+0x8c/0x1e0
[12306.328194] __device_suspend+0x10a/0x560
[12306.328198] dpm_suspend+0x18d/0x2f0
[12306.328203] hibernation_snapshot+0xc4/0x650
[12306.328209] hibernate+0xd6/0x390
[12306.328213] state_store+0xc3/0xd0
[12306.328220] kernfs_fop_write_iter+0x136/0x1d0
[12306.328228] vfs_write+0x239/0x3f0
[12306.328238] ksys_write+0x6f/0xf0
[12306.328242] do_syscall_64+0x60/0x90
[12306.328250] ? exc_page_fault+0x7c/0x180
[12306.328256] entry_SYSCALL_64_after_hwframe+0x72/0xdc
[12306.328263] RIP: 0033:0x7f40f5b0fbc4
[12306.328311] Code: 15 99 11 0e 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7
0f 1f 00 f3 0f 1e fa 80 3d 3d 99 0e 00 00 74 13 b8 01 00 00 00 0f 05 <48> 3d 00
f0 ff ff 77 54 c3 0f 1f 00 48 83 ec 28 48 89 54 24 18 48
[12306.328314] RSP: 002b:00007ffed7800318 EFLAGS: 00000202 ORIG_RAX:
0000000000000001
[12306.328317] RAX: ffffffffffffffda RBX: 0000000000000005 RCX:
00007f40f5b0fbc4
[12306.328319] RDX: 0000000000000005 RSI: 00007ffed7800400 RDI:
0000000000000004
[12306.328321] RBP: 00007ffed7800400 R08: 000055eef51424b0 R09:
0000000000000001
[12306.328323] R10: 000055eef4057da8 R11: 0000000000000202 R12:
0000000000000005
[12306.328324] R13: 000055eef51412d0 R14: 0000000000000005 R15:
00007f40f5bedca0
[12306.328329] </TASK>
[12306.328330] ---[ end trace 0000000000000000 ]---
[12306.608249] kworker/u32:0: page allocation failure: order:0,
mode:0x100c02(GFP_NOIO|__GFP_HIGHMEM|__GFP_HARDWALL),
nodemask=(null),cpuset=/,mems_allowed=0
[12306.608273] CPU: 1 PID: 8327 Comm: kworker/u32:0 Tainted: G W OE
6.3.1-arch1-1 #1 2f4443c3fa3529b1ac13dc02f36f7de43ade3ecd
[12306.608279] Hardware name: HP HP Laptop 15s-eq1xxx/8707, BIOS F.61
03/25/2022
[12306.608283] Workqueue: events_unbound async_run_entry_fn
[12306.608294] Call Trace:
[12306.608298] <TASK>
[12306.608304] dump_stack_lvl+0x47/0x60
[12306.608314] warn_alloc+0x165/0x1e0
[12306.608322] __alloc_pages_slowpath.constprop.0+0xd1d/0xde0
[12306.608330] __alloc_pages+0x32d/0x350
[12306.608339] ttm_pool_alloc+0x2cf/0x630 [ttm
aa66ad325a6f240ed95f8310c2d7ec36d2065cab]
[12306.608364] amdgpu_ttm_tt_populate+0x39/0x90 [amdgpu
ce772bc03400808253ec2c29f4f799fa4c517d4f]
[12306.609058] ttm_tt_populate+0xa1/0x140 [ttm
aa66ad325a6f240ed95f8310c2d7ec36d2065cab]
[12306.609077] ttm_bo_handle_move_mem+0x15f/0x170 [ttm
aa66ad325a6f240ed95f8310c2d7ec36d2065cab]
[12306.609096] ttm_mem_evict_first+0x20a/0x520 [ttm
aa66ad325a6f240ed95f8310c2d7ec36d2065cab]
[12306.609112] ? asm_common_interrupt+0x26/0x40
[12306.609122] ttm_resource_manager_evict_all+0xa7/0x1d0 [ttm
aa66ad325a6f240ed95f8310c2d7ec36d2065cab]
[12306.609143] amdgpu_device_suspend+0x56/0x180 [amdgpu
ce772bc03400808253ec2c29f4f799fa4c517d4f]
[12306.609816] amdgpu_pmops_freeze+0x25/0x70 [amdgpu
ce772bc03400808253ec2c29f4f799fa4c517d4f]
[12306.610481] pci_pm_freeze+0x58/0xc0
[12306.610489] ? __pfx_pci_pm_freeze+0x10/0x10
[12306.610492] dpm_run_callback+0x8c/0x1e0
[12306.610499] __device_suspend+0x10a/0x560
[12306.610503] async_suspend+0x1e/0x70
[12306.610507] async_run_entry_fn+0x34/0x130
[12306.610512] process_one_work+0x1c7/0x3d0
[12306.610521] worker_thread+0x51/0x390
[12306.610526] ? __pfx_worker_thread+0x10/0x10
[12306.610530] kthread+0xde/0x110
[12306.610535] ? __pfx_kthread+0x10/0x10
[12306.610539] ret_from_fork+0x2c/0x50
[12306.610549] </TASK>
[12306.610598] Mem-Info:
[12306.610601] active_anon:9177 inactive_anon:36904 isolated_anon:0
active_file:10105 inactive_file:12821 isolated_file:0
unevictable:256 dirty:0 writeback:0
slab_reclaimable:21877 slab_unreclaimable:53122
mapped:26023 shmem:6130 pagetables:13057
sec_pagetables:0 bounce:0
kernel_misc_reclaimable:0
free:47719 free_pcp:150 free_cma:0
[12306.610610] Node 0 active_anon:36708kB inactive_anon:147616kB
active_file:40420kB inactive_file:51284kB unevictable:1024kB isolated(anon):0kB
isolated(file):0kB mapped:104092kB dirty:0kB writeback:0kB shmem:24520kB
shmem_thp: 0kB shmem_pmdmapped: 0kB anon_thp: 32768kB writeback_tmp:0kB
kernel_stack:21776kB pagetables:52228kB sec_pagetables:0kB all_unreclaimable?
no
[12306.610619] Node 0 DMA free:14348kB boost:0kB min:136kB low:168kB high:200kB
reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:0kB
inactive_file:0kB unevictable:0kB writepending:0kB present:15996kB
managed:15372kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:0kB
[12306.610628] lowmem_reserve[]: 0 3108 7264 7264 7264
[12306.610633] Node 0 DMA32 free:82088kB boost:36864kB min:65720kB low:72932kB
high:80144kB reserved_highatomic:0KB active_anon:9112kB inactive_anon:51260kB
active_file:17020kB inactive_file:19984kB unevictable:0kB writepending:0kB
present:3259672kB managed:3194048kB mlocked:0kB bounce:0kB free_pcp:248kB
local_pcp:248kB free_cma:0kB
[12306.610643] lowmem_reserve[]: 0 0 4156 4156 4156
[12306.610647] Node 0 Normal free:94440kB boost:67584kB min:106168kB
low:115812kB high:125456kB reserved_highatomic:0KB active_anon:27596kB
inactive_anon:96356kB active_file:23400kB inactive_file:31300kB
unevictable:1024kB writepending:0kB present:4443392kB managed:4263072kB
mlocked:1024kB bounce:0kB free_pcp:352kB local_pcp:352kB free_cma:0kB
[12306.610656] lowmem_reserve[]: 0 0 0 0 0
[12306.610660] Node 0 DMA: 1*4kB (U) 1*8kB (U) 0*16kB 0*32kB 0*64kB 0*128kB
0*256kB 0*512kB 0*1024kB 1*2048kB (M) 3*4096kB (M) = 14348kB
[12306.610679] Node 0 DMA32: 7634*4kB (UM) 3788*8kB (UM) 942*16kB (UM) 27*32kB
(UM) 1*64kB (U) 3*128kB (U) 3*256kB (U) 2*512kB (U) 1*1024kB (U) 1*2048kB (U)
0*4096kB = 82088kB
[12306.610700] Node 0 Normal: 17278*4kB (UME) 3018*8kB (UME) 68*16kB (UM)
3*32kB (U) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 94440kB
[12306.610718] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0
hugepages_size=1048576kB
[12306.610722] Node 0 hugepages_total=0 hugepages_free=0 hugepages_surp=0
hugepages_size=2048kB
[12306.610724] 44686 total pagecache pages
[12306.610726] 15628 pages in swap cache
[12306.610727] Free swap = 28406268kB
[12306.610728] Total swap = 32769020kB
[12306.610729] 1929765 pages RAM
[12306.610730] 0 pages HighMem/MovableOnly
[12306.610731] 61642 pages reserved
[12306.610732] 0 pages cma reserved
[12306.610733] 0 pages hwpoisoned
[12306.612009] [drm] evicting device resources failed
[12306.612013] amdgpu 0000:03:00.0: PM: pci_pm_freeze():
amdgpu_pmops_freeze+0x0/0x70 [amdgpu] returns -12
[12306.612682] amdgpu 0000:03:00.0: PM: dpm_run_callback():
pci_pm_freeze+0x0/0xc0 returns -12
[12306.612692] amdgpu 0000:03:00.0: PM: failed to freeze async: error -12
[12306.678283] usb usb1: root hub lost power or was reset
[12306.678284] usb usb3: root hub lost power or was reset
[12306.678288] usb usb2: root hub lost power or was reset
[12306.678289] usb usb4: root hub lost power or was reset
[12306.678325] pci 0000:00:00.2: can't derive routing for PCI INT A
[12306.678331] pci 0000:00:00.2: PCI INT A: no GSI
[12306.689865] nvme nvme0: 16/0/0 default/read/poll queues
[12306.961502] usb 1-3: reset high-speed USB device number 3 using xhci_hcd
[12306.961538] usb 3-2: reset high-speed USB device number 2 using xhci_hcd
[12307.228379] usb 1-2: reset full-speed USB device number 2 using xhci_hcd
[12307.491571] usb 1-4: reset full-speed USB device number 4 using xhci_hcd
[12307.760292] usb 2-1: reset SuperSpeed USB device number 2 using xhci_hcd
[12308.078007] PM: hibernation: Basic memory bitmaps freed
[12308.078304] OOM killer enabled.
[12308.078306] Restarting tasks ...
[12308.081374] Bluetooth: hci0: RTL: examining hci_ver=08 hci_rev=000c
lmp_ver=08 lmp_subver=8821
[12308.082281] Bluetooth: hci0: RTL: rom_version status=0 version=1
[12308.082286] Bluetooth: hci0: RTL: loading rtl_bt/rtl8821c_fw.bin
[12308.082311] Bluetooth: hci0: RTL: loading rtl_bt/rtl8821c_config.bin
[12308.082333] Bluetooth: hci0: RTL: cfg_sz 10, total sz 34926
[12308.084839] done.
[12308.183597] PM: hibernation: hibernation exit
OBSERVED RESULT
Screen goes black except the mouse pointer. It goes to the screen of the locked
Session but the Mouse can't be moved. It goes black again. Then it goes back to
the Loginscreen an is responsive.
That also happens wehn using systemctl hibernate.
Sometimes the log of systemd-hibernate.service is different but the behaviour
is the same:
Mai 05 09:42:58 archlinux systemd[1]: Starting Hibernate...
Mai 05 09:42:58 archlinux systemd-sleep[3069]: Entering sleep state
'hibernate'...
Mai 05 09:44:59 archlinux systemd-sleep[3069]: System returned from sleep
state.
Mai 05 09:44:59 archlinux systemd[1]: systemd-hibernate.service: Deactivated
successfully.
Mai 05 09:45:00 archlinux systemd[1]: Finished Hibernate.
Mai 05 09:45:00 archlinux systemd[1]: systemd-hibernate.service: Consumed
4.335s CPU time.
Hibernation is successfull but the behaviour is the same as described above.
The Reason can be anything, AMD APU, Kernel, Systemd, Swap, Powerdevil usw.
Anyways it would be helpful if there would be a Errormessage.
EXPECTED RESULT
Goes black and the PC Powerindicatorled goes off.
ADDITIONAL INFORMATION
--
You are receiving this mail because:
You are watching all bug changes.
