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.