Fedora 36 slow wake time [Dell Inspiron - Ryzen 7 - Radeon Graphics]

dqalombardi · November 20, 2022, 9:42am

My machine always takes about 3 minutes to wake from suspension. It is often faster to just turn the machine off all the time and do cold-boots, but this is not an option because I often have many applications open. I really love Fedora otherwise, but at this point this issue is making it unusable for me.

I’ve looked at other related posts and they all seem to make mention of Nvidia graphics (which I don’t have) or MacBooks (which I’m not using). They also never seem to reach any solution.

I have posted output from inxi -Fzx below (as I saw requested in another post about this issue).

Thanks in advance for any help.

System:
  Kernel: 6.0.8-200.fc36.x86_64 arch: x86_64 bits: 64 compiler: gcc
    v: 2.37-36.fc36 Desktop: GNOME v: 42.6 Distro: Fedora release 36 (Thirty
    Six)
Machine:
  Type: Laptop System: Dell product: Inspiron 14 5425 v: 1.4.0
    serial: <superuser required>
  Mobo: Dell model: 0J9C2M v: A00 serial: <superuser required> UEFI: Dell
    v: 1.4.0 date: 06/27/2022
Battery:
  ID-1: BAT0 charge: 46.6 Wh (100.0%) condition: 46.6/54.0 Wh (86.3%)
    volts: 17.0 min: 15.0 model: SWD-ATL3.660 DELL VKYJX18 status: full
CPU:
  Info: 8-core model: AMD Ryzen 7 5825U with Radeon Graphics bits: 64
    type: MT MCP arch: Zen 3 rev: 0 cache: L1: 512 KiB L2: 4 MiB L3: 16 MiB
  Speed (MHz): avg: 528 high: 1765 min/max: 400/4546 boost: enabled cores:
    1: 400 2: 400 3: 400 4: 400 5: 400 6: 400 7: 400 8: 400 9: 400 10: 1085
    11: 400 12: 400 13: 400 14: 400 15: 1765 16: 400 bogomips: 63883
  Flags: avx avx2 ht lm nx pae sse sse2 sse3 sse4_1 sse4_2 sse4a ssse3 svm
Graphics:
  Device-1: AMD Barcelo vendor: Dell driver: amdgpu v: kernel arch: RDNA-2
    bus-ID: 04:00.0 temp: 46.0 C
  Device-2: Microdia Integrated_Webcam_FHD type: USB driver: uvcvideo
    bus-ID: 1-4:2
  Display: wayland server: X.Org v: 1.22.1.5 with: Xwayland v: 22.1.5
    compositor: gnome-shell driver: dri: radeonsi gpu: amdgpu
    resolution: 1280x800~60Hz
  OpenGL: renderer: AMD RENOIR (LLVM 14.0.0 DRM 3.48 6.0.8-200.fc36.x86_64)
    v: 4.6 Mesa 22.1.7 direct render: Yes
Audio:
  Device-1: AMD Renoir Radeon High Definition Audio vendor: Dell
    driver: snd_hda_intel v: kernel bus-ID: 04:00.1
  Device-2: AMD ACP/ACP3X/ACP6x Audio Coprocessor vendor: Dell
    driver: snd_rn_pci_acp3x v: kernel bus-ID: 04:00.5
  Device-3: AMD Family 17h/19h HD Audio vendor: Dell driver: snd_hda_intel
    v: kernel bus-ID: 04:00.6
  Sound API: ALSA v: k6.0.8-200.fc36.x86_64 running: yes
  Sound Server-1: PulseAudio v: 15.0 running: no
  Sound Server-2: PipeWire v: 0.3.60 running: yes
Network:
  Device-1: MEDIATEK MT7921 802.11ax PCI Express Wireless Network Adapter
    vendor: Foxconn driver: mt7921e v: kernel bus-ID: 03:00.0
  IF: wlp3s0 state: up mac: <filter>
Bluetooth:
  Device-1: Foxconn / Hon Hai Wireless_Device type: USB driver: btusb v: 0.8
    bus-ID: 3-3:2
  Report: rfkill ID: hci0 rfk-id: 0 state: down bt-service: enabled,running
    rfk-block: hardware: no software: yes address: see --recommends
Drives:
  Local Storage: total: 476.94 GiB used: 87.6 GiB (18.4%)
  ID-1: /dev/nvme0n1 vendor: KIOXIA model: KBG50ZNS512G NVMe 512GB
    size: 476.94 GiB temp: 31.9 C
Partition:
  ID-1: / size: 475.35 GiB used: 87.27 GiB (18.4%) fs: btrfs
    dev: /dev/nvme0n1p3
  ID-2: /boot size: 973.4 MiB used: 316 MiB (32.5%) fs: ext4
    dev: /dev/nvme0n1p2
  ID-3: /boot/efi size: 598.8 MiB used: 14 MiB (2.3%) fs: vfat
    dev: /dev/nvme0n1p1
  ID-4: /home size: 475.35 GiB used: 87.27 GiB (18.4%) fs: btrfs
    dev: /dev/nvme0n1p3
Swap:
  ID-1: swap-1 type: zram size: 8 GiB used: 0 KiB (0.0%) dev: /dev/zram0
Sensors:
  System Temperatures: cpu: 52.4 42.0 mobo: 36.0 42.0 sodimm: SODIMM 42.0
    gpu: amdgpu temp: 48.0 C
  Fan Speeds (RPM): cpu: 0
Info:
  Processes: 379 Uptime: 43m Memory: 14.98 GiB used: 3.4 GiB (22.7%)
  Init: systemd target: graphical (5) Compilers: gcc: 12.2.1 Packages: 21
  note: see --rpm Shell: Zsh v: 5.8.1 inxi: 3.3.22

ozeszty · November 20, 2022, 9:28pm

A BIOS update might resolve such issues.
Apart from that, you have to check system logs: run dmesg -W, suspend system, wake it up and see what pops up. You might find something useful also in sudo journalctl -e

dqalombardi · December 2, 2022, 8:47am

Problem persists even after BIOS update.

Any help would be very very much appreciated.

I ran dmseg -W while suspending and waking my machine as recommended by @ozeszty. Logs below. Wake-time was 61 seconds.

Suspend initiated at 10:15:27 (doesn’t appear in logs for some reason)
Wake-up initiated at 10:28:41
Wake-up finished at 10:29:42

[Fri Dec  2 09:56:18 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:22:01 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:22:04 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:22:04 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:22:04 2022] wlp3s0: deauthenticating from 00:5f:67:7e:df:c0 by local choice (Reason: 3=DEAUTH_LEAVING)
[Fri Dec  2 10:22:05 2022] PM: suspend entry (s2idle)
[Fri Dec  2 10:22:05 2022] Filesystems sync: 0.008 seconds
[Fri Dec  2 10:22:05 2022] Freezing user space processes ... (elapsed 0.001 seconds) done.
[Fri Dec  2 10:22:05 2022] OOM killer disabled.
[Fri Dec  2 10:22:05 2022] Freezing remaining freezable tasks ... (elapsed 0.000 seconds) done.
[Fri Dec  2 10:22:05 2022] printk: Suspending console(s) (use no_console_suspend to debug)
[Fri Dec  2 10:22:05 2022] queueing ieee80211 work while going to suspend
[Fri Dec  2 10:22:05 2022] PM: suspend devices took 0.060 seconds
[Fri Dec  2 10:22:05 2022] ACPI: EC: interrupt blocked
[Fri Dec  2 10:22:05 2022] ACPI: EC: interrupt unblocked
[Fri Dec  2 10:22:05 2022] pci 0000:00:00.2: can't derive routing for PCI INT A
[Fri Dec  2 10:22:05 2022] pci 0000:00:00.2: PCI INT A: no GSI
[Fri Dec  2 10:22:05 2022] [drm] PCIE GART of 1024M enabled.
[Fri Dec  2 10:22:05 2022] [drm] PTB located at 0x000000F400A00000
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resuming...
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: dpm has been disabled
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resumed successfully!
[Fri Dec  2 10:22:05 2022] [drm] VCN decode and encode initialized successfully(under DPG Mode).
[Fri Dec  2 10:22:05 2022] [drm] JPEG decode initialized successfully.
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring gfx uses VM inv eng 0 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 5 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 6 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 7 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 8 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 9 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 10 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring kiq_2.1.0 uses VM inv eng 11 on hub 0
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring sdma0 uses VM inv eng 0 on hub 1
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_dec uses VM inv eng 1 on hub 1
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc0 uses VM inv eng 4 on hub 1
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc1 uses VM inv eng 5 on hub 1
[Fri Dec  2 10:22:05 2022] amdgpu 0000:04:00.0: amdgpu: ring jpeg_dec uses VM inv eng 6 on hub 1
[Fri Dec  2 10:22:06 2022] ata2: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 10:22:06 2022] ata1: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 10:23:06 2022] nvme nvme0: I/O 975 (I/O Cmd) QID 4 timeout, aborting
[Fri Dec  2 10:23:06 2022] nvme nvme0: I/O 976 (I/O Cmd) QID 4 timeout, aborting
[Fri Dec  2 10:23:06 2022] nvme nvme0: I/O 977 (I/O Cmd) QID 4 timeout, aborting
[Fri Dec  2 10:23:06 2022] nvme nvme0: I/O 978 (I/O Cmd) QID 4 timeout, aborting
[Fri Dec  2 10:23:08 2022] nvme nvme0: I/O 14 QID 0 timeout, reset controller
[Fri Dec  2 10:23:08 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:23:08 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:23:08 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:23:08 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:23:08 2022] nvme 0000:02:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xe0 returns -16
[Fri Dec  2 10:23:08 2022] nvme 0000:02:00.0: PM: failed to resume async: error -16
[Fri Dec  2 10:23:08 2022] PM: resume devices took 62.739 seconds
[Fri Dec  2 10:23:08 2022] ------------[ cut here ]------------
[Fri Dec  2 10:23:08 2022] Component: resume devices, time: 62739
[Fri Dec  2 10:23:08 2022] WARNING: CPU: 14 PID: 8057 at kernel/power/suspend_test.c:53 suspend_test_finish+0x70/0x80
[Fri Dec  2 10:23:08 2022] Modules linked in: tls uinput rfcomm snd_seq_dummy snd_hrtimer nft_objref nf_conntrack_netbios_ns nf_conntrack_broadcast nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject nft_ct nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set nf_tables nfnetlink qrtr bnep sunrpc snd_soc_dmic snd_acp3x_pdm_dma snd_acp3x_rn snd_sof_amd_renoir intel_rapl_msr intel_rapl_common snd_sof_amd_acp snd_sof_pci mt7921e snd_ctl_led snd_sof mt7921_common snd_hda_codec_realtek mt76_connac_lib snd_sof_utils snd_hda_codec_generic snd_hda_codec_hdmi dell_laptop snd_soc_core mt76 snd_hda_intel vfat snd_intel_dspcfg snd_compress dell_smm_hwmon edac_mce_amd snd_intel_sdw_acpi fat btusb ac97_bus snd_hda_codec kvm_amd btrtl uvcvideo snd_pcm_dmaengine mac80211 snd_hda_core btbcm videobuf2_vmalloc snd_rpl_pci_acp6x snd_pci_acp6x snd_hwdep videobuf2_memops btintel libarc4 videobuf2_v4l2 btmtk kvm dell_wmi snd_seq videobuf2_common
[Fri Dec  2 10:23:08 2022]  snd_seq_device dell_smbios irqbypass bluetooth videodev snd_pcm rapl cfg80211 dcdbas pcspkr ledtrig_audio sparse_keymap joydev wmi_bmof dell_wmi_descriptor snd_timer mc snd_pci_acp5x snd snd_rn_pci_acp3x k10temp snd_acp_config snd_soc_acpi i2c_piix4 snd_pci_acp3x soundcore dell_rbtn rfkill amd_pmc acpi_tad zram amdgpu drm_ttm_helper ttm iommu_v2 gpu_sched nvme drm_buddy nvme_core drm_display_helper hid_multitouch ucsi_acpi crct10dif_pclmul crc32_pclmul crc32c_intel polyval_clmulni polyval_generic ghash_clmulni_intel typec_ucsi serio_raw sp5100_tco ccp cec typec nvme_common wmi video i2c_hid_acpi i2c_hid ip6_tables ip_tables fuse
[Fri Dec  2 10:23:08 2022] CPU: 14 PID: 8057 Comm: systemd-sleep Tainted: G        W          6.0.10-200.fc36.x86_64 #1
[Fri Dec  2 10:23:08 2022] Hardware name: Dell Inc. Inspiron 14 5425/0J9C2M, BIOS 1.5.0 09/13/2022
[Fri Dec  2 10:23:08 2022] RIP: 0010:suspend_test_finish+0x70/0x80
[Fri Dec  2 10:23:08 2022] Code: 03 00 00 29 c1 e8 0e 4f bf 00 81 fb 10 27 00 00 77 07 5b 5d c3 cc cc cc cc 89 da 48 89 ee 48 c7 c7 eb 16 75 88 e8 18 ed be 00 <0f> 0b 5b 5d c3 cc cc cc cc cc cc cc cc cc cc cc 0f 1f 44 00 00 0f
[Fri Dec  2 10:23:08 2022] RSP: 0018:ffffa121d009fd90 EFLAGS: 00010292
[Fri Dec  2 10:23:08 2022] RAX: 0000000000000026 RBX: 000000000000f513 RCX: 0000000000000000
[Fri Dec  2 10:23:08 2022] RDX: 0000000000000001 RSI: ffffffff887b08f2 RDI: 00000000ffffffff
[Fri Dec  2 10:23:08 2022] RBP: ffffffff8875160b R08: ffffffff890662e0 R09: 0000000000000004
[Fri Dec  2 10:23:08 2022] R10: ffffffffffffffff R11: ffffffff89c06bd6 R12: ffffffff887515c6
[Fri Dec  2 10:23:08 2022] R13: ffff8aa08c9b4498 R14: 0000000000000004 R15: ffff8aa08da519a0
[Fri Dec  2 10:23:08 2022] FS:  00007f2c91b0fb40(0000) GS:ffff8aa37e980000(0000) knlGS:0000000000000000
[Fri Dec  2 10:23:08 2022] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Fri Dec  2 10:23:08 2022] CR2: 000055b56d4e1630 CR3: 000000011a888000 CR4: 0000000000750ee0
[Fri Dec  2 10:23:08 2022] PKRU: 55555554
[Fri Dec  2 10:23:08 2022] Call Trace:
[Fri Dec  2 10:23:08 2022]  <TASK>
[Fri Dec  2 10:23:08 2022]  suspend_devices_and_enter+0x1bd/0x870
[Fri Dec  2 10:23:08 2022]  pm_suspend.cold+0x2d2/0x35e
[Fri Dec  2 10:23:08 2022]  state_store+0x68/0xd0
[Fri Dec  2 10:23:08 2022]  kernfs_fop_write_iter+0x11e/0x1f0
[Fri Dec  2 10:23:08 2022]  vfs_write+0x222/0x3e0
[Fri Dec  2 10:23:08 2022]  ksys_write+0x5b/0xd0
[Fri Dec  2 10:23:08 2022]  do_syscall_64+0x5b/0x80
[Fri Dec  2 10:23:08 2022]  entry_SYSCALL_64_after_hwframe+0x63/0xcd
[Fri Dec  2 10:23:08 2022] RIP: 0033:0x7f2c92501977
[Fri Dec  2 10:23:08 2022] Code: 0f 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
[Fri Dec  2 10:23:08 2022] RSP: 002b:00007fffa341d548 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[Fri Dec  2 10:23:08 2022] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f2c92501977
[Fri Dec  2 10:23:08 2022] RDX: 0000000000000004 RSI: 00007fffa341d630 RDI: 0000000000000004
[Fri Dec  2 10:23:08 2022] RBP: 00007fffa341d630 R08: 000055eed03501f0 R09: 0000000000000000
[Fri Dec  2 10:23:08 2022] R10: 000055eecff48158 R11: 0000000000000246 R12: 0000000000000004
[Fri Dec  2 10:23:08 2022] R13: 000055eed034c3e0 R14: 0000000000000004 R15: 00007f2c925f49e0
[Fri Dec  2 10:23:08 2022]  </TASK>
[Fri Dec  2 10:23:08 2022] ---[ end trace 0000000000000000 ]---
[Fri Dec  2 10:23:08 2022] OOM killer enabled.
[Fri Dec  2 10:23:08 2022] Restarting tasks ... done.
[Fri Dec  2 10:23:08 2022] random: crng reseeded on system resumption
[Fri Dec  2 10:23:08 2022] PM: suspend exit
[Fri Dec  2 10:23:08 2022] nvme nvme0: 16/0/0 default/read/poll queues
[Fri Dec  2 10:23:12 2022] usb 3-4: reset full-speed USB device number 4 using xhci_hcd
[Fri Dec  2 10:23:12 2022] wlp3s0: authenticate with 00:5f:67:7e:df:c0
[Fri Dec  2 10:23:12 2022] wlp3s0: Invalid HE elem, Disable HE
[Fri Dec  2 10:23:12 2022] wlp3s0: send auth to 00:5f:67:7e:df:c0 (try 1/3)
[Fri Dec  2 10:23:12 2022] wlp3s0: authenticated
[Fri Dec  2 10:23:12 2022] wlp3s0: associate with 00:5f:67:7e:df:c0 (try 1/3)
[Fri Dec  2 10:23:12 2022] wlp3s0: RX AssocResp from 00:5f:67:7e:df:c0 (capab=0x11 status=0 aid=2)
[Fri Dec  2 10:23:12 2022] wlp3s0: associated
[Fri Dec  2 10:23:12 2022] usb 3-4: reset full-speed USB device number 4 using xhci_hcd
[Fri Dec  2 10:23:12 2022] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[Fri Dec  2 10:27:02 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:28:13 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:28:34 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:28:38 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:28:39 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:28:39 2022] Lockdown: systemd-logind: hibernation is restricted; see man kernel_lockdown.7
[Fri Dec  2 10:28:40 2022] wlp3s0: deauthenticating from 00:5f:67:7e:df:c0 by local choice (Reason: 3=DEAUTH_LEAVING)
[Fri Dec  2 10:28:41 2022] PM: suspend entry (s2idle)
[Fri Dec  2 10:28:41 2022] Filesystems sync: 0.018 seconds
[Fri Dec  2 10:28:41 2022] Freezing user space processes ... (elapsed 0.001 seconds) done.
[Fri Dec  2 10:28:41 2022] OOM killer disabled.
[Fri Dec  2 10:28:41 2022] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[Fri Dec  2 10:28:41 2022] printk: Suspending console(s) (use no_console_suspend to debug)
[Fri Dec  2 10:28:41 2022] queueing ieee80211 work while going to suspend
[Fri Dec  2 10:28:41 2022] PM: suspend devices took 0.070 seconds
[Fri Dec  2 10:28:41 2022] ACPI: EC: interrupt blocked
[Fri Dec  2 10:28:41 2022] ACPI: EC: interrupt unblocked
[Fri Dec  2 10:28:41 2022] pci 0000:00:00.2: can't derive routing for PCI INT A
[Fri Dec  2 10:28:41 2022] pci 0000:00:00.2: PCI INT A: no GSI
[Fri Dec  2 10:28:41 2022] [drm] PCIE GART of 1024M enabled.
[Fri Dec  2 10:28:41 2022] [drm] PTB located at 0x000000F400A00000
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resuming...
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: dpm has been disabled
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resumed successfully!
[Fri Dec  2 10:28:41 2022] [drm] VCN decode and encode initialized successfully(under DPG Mode).
[Fri Dec  2 10:28:41 2022] [drm] JPEG decode initialized successfully.
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring gfx uses VM inv eng 0 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 5 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 6 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 7 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 8 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 9 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 10 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring kiq_2.1.0 uses VM inv eng 11 on hub 0
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring sdma0 uses VM inv eng 0 on hub 1
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_dec uses VM inv eng 1 on hub 1
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc0 uses VM inv eng 4 on hub 1
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc1 uses VM inv eng 5 on hub 1
[Fri Dec  2 10:28:41 2022] amdgpu 0000:04:00.0: amdgpu: ring jpeg_dec uses VM inv eng 6 on hub 1
[Fri Dec  2 10:28:41 2022] ata1: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 10:28:41 2022] ata2: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 10:29:27 2022] nvme nvme0: I/O 916 (I/O Cmd) QID 9 timeout, aborting
[Fri Dec  2 10:29:27 2022] nvme nvme0: I/O 917 (I/O Cmd) QID 9 timeout, aborting
[Fri Dec  2 10:29:27 2022] nvme nvme0: I/O 918 (I/O Cmd) QID 9 timeout, aborting
[Fri Dec  2 10:29:27 2022] nvme nvme0: I/O 919 (I/O Cmd) QID 9 timeout, aborting
[Fri Dec  2 10:29:41 2022] nvme nvme0: I/O 27 QID 0 timeout, reset controller
[Fri Dec  2 10:29:41 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:29:41 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:29:41 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:29:41 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 10:29:41 2022] nvme 0000:02:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xe0 returns -16
[Fri Dec  2 10:29:41 2022] nvme 0000:02:00.0: PM: failed to resume async: error -16
[Fri Dec  2 10:29:41 2022] PM: resume devices took 60.093 seconds
[Fri Dec  2 10:29:41 2022] ------------[ cut here ]------------
[Fri Dec  2 10:29:41 2022] Component: resume devices, time: 60093
[Fri Dec  2 10:29:41 2022] WARNING: CPU: 4 PID: 9586 at kernel/power/suspend_test.c:53 suspend_test_finish+0x70/0x80
[Fri Dec  2 10:29:41 2022] Modules linked in: tls uinput rfcomm snd_seq_dummy snd_hrtimer nft_objref nf_conntrack_netbios_ns nf_conntrack_broadcast nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject nft_ct nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set nf_tables nfnetlink qrtr bnep sunrpc snd_soc_dmic snd_acp3x_pdm_dma snd_acp3x_rn snd_sof_amd_renoir intel_rapl_msr intel_rapl_common snd_sof_amd_acp snd_sof_pci mt7921e snd_ctl_led snd_sof mt7921_common snd_hda_codec_realtek mt76_connac_lib snd_sof_utils snd_hda_codec_generic snd_hda_codec_hdmi dell_laptop snd_soc_core mt76 snd_hda_intel vfat snd_intel_dspcfg snd_compress dell_smm_hwmon edac_mce_amd snd_intel_sdw_acpi fat btusb ac97_bus snd_hda_codec kvm_amd btrtl uvcvideo snd_pcm_dmaengine mac80211 snd_hda_core btbcm videobuf2_vmalloc snd_rpl_pci_acp6x snd_pci_acp6x snd_hwdep videobuf2_memops btintel libarc4 videobuf2_v4l2 btmtk kvm dell_wmi snd_seq videobuf2_common
[Fri Dec  2 10:29:41 2022]  snd_seq_device dell_smbios irqbypass bluetooth videodev snd_pcm rapl cfg80211 dcdbas pcspkr ledtrig_audio sparse_keymap joydev wmi_bmof dell_wmi_descriptor snd_timer mc snd_pci_acp5x snd snd_rn_pci_acp3x k10temp snd_acp_config snd_soc_acpi i2c_piix4 snd_pci_acp3x soundcore dell_rbtn rfkill amd_pmc acpi_tad zram amdgpu drm_ttm_helper ttm iommu_v2 gpu_sched nvme drm_buddy nvme_core drm_display_helper hid_multitouch ucsi_acpi crct10dif_pclmul crc32_pclmul crc32c_intel polyval_clmulni polyval_generic ghash_clmulni_intel typec_ucsi serio_raw sp5100_tco ccp cec typec nvme_common wmi video i2c_hid_acpi i2c_hid ip6_tables ip_tables fuse
[Fri Dec  2 10:29:41 2022] CPU: 4 PID: 9586 Comm: systemd-sleep Tainted: G        W          6.0.10-200.fc36.x86_64 #1
[Fri Dec  2 10:29:41 2022] Hardware name: Dell Inc. Inspiron 14 5425/0J9C2M, BIOS 1.5.0 09/13/2022
[Fri Dec  2 10:29:41 2022] RIP: 0010:suspend_test_finish+0x70/0x80
[Fri Dec  2 10:29:41 2022] Code: 03 00 00 29 c1 e8 0e 4f bf 00 81 fb 10 27 00 00 77 07 5b 5d c3 cc cc cc cc 89 da 48 89 ee 48 c7 c7 eb 16 75 88 e8 18 ed be 00 <0f> 0b 5b 5d c3 cc cc cc cc cc cc cc cc cc cc cc 0f 1f 44 00 00 0f
[Fri Dec  2 10:29:41 2022] RSP: 0018:ffffa121d39cbcd0 EFLAGS: 00010292
[Fri Dec  2 10:29:41 2022] RAX: 0000000000000026 RBX: 000000000000eabd RCX: 0000000000000000
[Fri Dec  2 10:29:41 2022] RDX: 0000000000000001 RSI: ffffffff887b08f2 RDI: 00000000ffffffff
[Fri Dec  2 10:29:41 2022] RBP: ffffffff8875160b R08: ffffffff890662e0 R09: 0000000000000004
[Fri Dec  2 10:29:41 2022] R10: ffffffffffffffff R11: ffffffff89c08ec6 R12: ffffffff887515c6
[Fri Dec  2 10:29:41 2022] R13: ffff8aa08108bb10 R14: 0000000000000004 R15: ffff8aa0846f2e60
[Fri Dec  2 10:29:41 2022] FS:  00007ff72ca28b40(0000) GS:ffff8aa37e700000(0000) knlGS:0000000000000000
[Fri Dec  2 10:29:41 2022] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Fri Dec  2 10:29:41 2022] CR2: 00007f4290010396 CR3: 00000001f5524000 CR4: 0000000000750ee0
[Fri Dec  2 10:29:41 2022] PKRU: 55555554
[Fri Dec  2 10:29:41 2022] Call Trace:
[Fri Dec  2 10:29:41 2022]  <TASK>
[Fri Dec  2 10:29:41 2022]  suspend_devices_and_enter+0x1bd/0x870
[Fri Dec  2 10:29:41 2022]  pm_suspend.cold+0x2d2/0x35e
[Fri Dec  2 10:29:41 2022]  state_store+0x68/0xd0
[Fri Dec  2 10:29:41 2022]  kernfs_fop_write_iter+0x11e/0x1f0
[Fri Dec  2 10:29:41 2022]  vfs_write+0x222/0x3e0
[Fri Dec  2 10:29:41 2022]  ksys_write+0x5b/0xd0
[Fri Dec  2 10:29:41 2022]  do_syscall_64+0x5b/0x80
[Fri Dec  2 10:29:41 2022]  ? syscall_exit_to_user_mode+0x17/0x40
[Fri Dec  2 10:29:41 2022]  ? do_syscall_64+0x67/0x80
[Fri Dec  2 10:29:41 2022]  ? handle_mm_fault+0xae/0x290
[Fri Dec  2 10:29:41 2022]  ? do_user_addr_fault+0x1ef/0x690
[Fri Dec  2 10:29:41 2022]  ? syscall_exit_to_user_mode+0x17/0x40
[Fri Dec  2 10:29:41 2022]  ? exc_page_fault+0x70/0x170
[Fri Dec  2 10:29:41 2022]  entry_SYSCALL_64_after_hwframe+0x63/0xcd
[Fri Dec  2 10:29:41 2022] RIP: 0033:0x7ff72cd01977
[Fri Dec  2 10:29:41 2022] Code: 0f 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
[Fri Dec  2 10:29:41 2022] RSP: 002b:00007ffd3757f128 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[Fri Dec  2 10:29:41 2022] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007ff72cd01977
[Fri Dec  2 10:29:41 2022] RDX: 0000000000000004 RSI: 00007ffd3757f210 RDI: 0000000000000004
[Fri Dec  2 10:29:41 2022] RBP: 00007ffd3757f210 R08: 000055a4ebccc1f0 R09: 0000000000000000
[Fri Dec  2 10:29:41 2022] R10: 000055a4ea6db158 R11: 0000000000000246 R12: 0000000000000004
[Fri Dec  2 10:29:41 2022] R13: 000055a4ebcc83e0 R14: 0000000000000004 R15: 00007ff72cdf49e0
[Fri Dec  2 10:29:41 2022]  </TASK>
[Fri Dec  2 10:29:41 2022] ---[ end trace 0000000000000000 ]---
[Fri Dec  2 10:29:41 2022] OOM killer enabled.
[Fri Dec  2 10:29:41 2022] Restarting tasks ... done.
[Fri Dec  2 10:29:41 2022] random: crng reseeded on system resumption
[Fri Dec  2 10:29:41 2022] PM: suspend exit
[Fri Dec  2 10:29:41 2022] nvme nvme0: 16/0/0 default/read/poll queues
[Fri Dec  2 10:29:41 2022] usb 3-4: reset full-speed USB device number 4 using xhci_hcd
[Fri Dec  2 10:29:42 2022] usb 3-4: reset full-speed USB device number 4 using xhci_hcd

These are the only steps I see where there is a very long interval between (46 seconds and 14 seconds respectively).

[Fri Dec  2 10:28:41 2022] ata2: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 10:29:27 2022] nvme nvme0: I/O 916 (I/O Cmd) QID 9 timeout, aborting

[Fri Dec  2 10:29:27 2022] nvme nvme0: I/O 919 (I/O Cmd) QID 9 timeout, aborting
[Fri Dec  2 10:29:41 2022] nvme nvme0: I/O 27 QID 0 timeout, reset controller

I also ran inxi -Fzx again in case anything relevant changed.

System:
  Kernel: 6.0.10-200.fc36.x86_64 arch: x86_64 bits: 64 compiler: gcc
    v: 2.37-37.fc36 Desktop: GNOME v: 42.6 Distro: Fedora release 36 (Thirty
    Six)
Machine:
  Type: Laptop System: Dell product: Inspiron 14 5425 v: 1.5.0
    serial: <superuser required>
  Mobo: Dell model: 0J9C2M v: A00 serial: <superuser required> UEFI: Dell
    v: 1.5.0 date: 09/13/2022
Battery:
  ID-1: BAT0 charge: 46.6 Wh (100.0%) condition: 46.6/54.0 Wh (86.3%)
    volts: 17.0 min: 15.0 model: SWD-ATL3.660 DELL VKYJX18 status: full
CPU:
  Info: 8-core model: AMD Ryzen 7 5825U with Radeon Graphics bits: 64
    type: MT MCP arch: Zen 3 rev: 0 cache: L1: 512 KiB L2: 4 MiB L3: 16 MiB
  Speed (MHz): avg: 962 high: 1993 min/max: 400/4546 boost: enabled cores:
    1: 1993 2: 400 3: 1597 4: 400 5: 400 6: 400 7: 1597 8: 1596 9: 400 10: 400
    11: 1831 12: 1597 13: 400 14: 400 15: 1596 16: 400 bogomips: 63882
  Flags: avx avx2 ht lm nx pae sse sse2 sse3 sse4_1 sse4_2 sse4a ssse3 svm
Graphics:
  Device-1: AMD Barcelo vendor: Dell driver: amdgpu v: kernel arch: RDNA-2
    bus-ID: 04:00.0 temp: 48.0 C
  Device-2: Microdia Integrated_Webcam_FHD type: USB driver: uvcvideo
    bus-ID: 1-4:2
  Display: wayland server: X.Org v: 1.22.1.5 with: Xwayland v: 22.1.5
    compositor: gnome-shell driver: dri: radeonsi gpu: amdgpu
    resolution: 1280x800~60Hz
  API: OpenGL v: 4.6 Mesa 22.1.7 renderer: AMD RENOIR (LLVM 14.0.0 DRM 3.48
    6.0.10-200.fc36.x86_64) direct render: Yes
Audio:
  Device-1: AMD Renoir Radeon High Definition Audio vendor: Dell
    driver: snd_hda_intel v: kernel bus-ID: 04:00.1
  Device-2: AMD ACP/ACP3X/ACP6x Audio Coprocessor vendor: Dell
    driver: snd_rn_pci_acp3x v: kernel bus-ID: 04:00.5
  Device-3: AMD Family 17h/19h HD Audio vendor: Dell driver: snd_hda_intel
    v: kernel bus-ID: 04:00.6
  Sound API: ALSA v: k6.0.10-200.fc36.x86_64 running: yes
  Sound Server-1: PulseAudio v: 15.0 running: no
  Sound Server-2: PipeWire v: 0.3.61 running: yes
Network:
  Device-1: MEDIATEK MT7921 802.11ax PCI Express Wireless Network Adapter
    vendor: Foxconn driver: mt7921e v: kernel bus-ID: 03:00.0
  IF: wlp3s0 state: up mac: <filter>
Bluetooth:
  Device-1: Foxconn / Hon Hai Wireless_Device type: USB driver: btusb v: 0.8
    bus-ID: 3-3:3
  Report: rfkill ID: hci0 rfk-id: 0 state: down bt-service: enabled,running
    rfk-block: hardware: no software: yes address: see --recommends
Drives:
  Local Storage: total: 476.94 GiB used: 63.33 GiB (13.3%)
  ID-1: /dev/nvme0n1 vendor: KIOXIA model: KBG50ZNS512G NVMe 512GB
    size: 476.94 GiB temp: 33.9 C
Partition:
  ID-1: / size: 475.35 GiB used: 62.97 GiB (13.2%) fs: btrfs
    dev: /dev/nvme0n1p3
  ID-2: /boot size: 973.4 MiB used: 316.6 MiB (32.5%) fs: ext4
    dev: /dev/nvme0n1p2
  ID-3: /boot/efi size: 598.8 MiB used: 52.2 MiB (8.7%) fs: vfat
    dev: /dev/nvme0n1p1
  ID-4: /home size: 475.35 GiB used: 62.97 GiB (13.2%) fs: btrfs
    dev: /dev/nvme0n1p3
Swap:
  ID-1: swap-1 type: zram size: 8 GiB used: 0 KiB (0.0%) dev: /dev/zram0
Sensors:
  System Temperatures: cpu: 61.6 42.0 mobo: 36.0 42.0 sodimm: SODIMM 42.0
    gpu: amdgpu temp: 48.0 C
  Fan Speeds (RPM): cpu: 0
Info:
  Processes: 404 Uptime: 1m Memory: 14.98 GiB used: 2.34 GiB (15.6%)
  Init: systemd target: graphical (5) Compilers: gcc: 12.2.1 Packages: 21
  note: see --rpm Shell: Zsh v: 5.8.1 inxi: 3.3.23

ozeszty · December 2, 2022, 1:09pm

You don’t have any SATA device, it looks like that stall is caused by some NVME disk/controller error.

That disk has a firmware update available https://fwupd.org/lvfs/devices/com.dell.111195.firmware
Backup your data and try to update it (if you haven’t yet) sudo fwupdmgr refresh || sudo fwupdmgr get-updates

If the issue persists, search for similar nvme related suspend issues, look at NVME power management options and check disk logs using tools from nvme-cli package. In case of reporting the issue, include nvme related messages and the trace from your dmesg output.

dqalombardi · December 2, 2022, 3:35pm

Unfortunately, even after the BIOS update and firmware updates, I’m still experiencing the same wake-times. Although, I’m now seeing a consistent 60 second bottleneck during the following process. So it must have something to do with my SSDs.

Big thank you for the help @ozeszty , apparently that BIOS update was quite important !

[Fri Dec  2 16:10:56 2022] ata2: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 16:10:56 2022] ata1: SATA link down (SStatus 0 SControl 300)
[Fri Dec  2 16:11:31 2022] nvme nvme0: I/O 904 (I/O Cmd) QID 10 timeout, aborting
[Fri Dec  2 16:11:31 2022] nvme nvme0: I/O 905 (I/O Cmd) QID 10 timeout, aborting
[Fri Dec  2 16:11:31 2022] nvme nvme0: I/O 906 (I/O Cmd) QID 10 timeout, aborting
[Fri Dec  2 16:11:31 2022] nvme nvme0: I/O 907 (I/O Cmd) QID 10 timeout, aborting
[Fri Dec  2 16:11:56 2022] nvme nvme0: I/O 20 QID 0 timeout, reset controller
[Fri Dec  2 16:11:56 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 16:11:56 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 16:11:56 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 16:11:56 2022] nvme nvme0: Abort status: 0x371
[Fri Dec  2 16:11:56 2022] nvme 0000:02:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xe0 returns -16
[Fri Dec  2 16:11:56 2022] nvme 0000:02:00.0: PM: failed to resume async: error -16
[Fri Dec  2 16:11:56 2022] PM: resume devices took 60.253 seconds

I have taken a look using some of the log tools in nvme-cli and haven’t found anything out of the ordinary, but then again I don’t know exactly what I’m looking for.

I have thus far been putting off updating to Fedora 37. Is there any reason to believe that might help ? Again, I’m a total newbie so I don’t have an intuition for what might help.

dqalombardi · December 2, 2022, 3:54pm

I stumbled upon the idea that maybe Hibernate is not enabled on my machine ?

Here is the output of cat /sys/power/state.

freeze mem

Apparently, disk should be on this list for my machine to enter correctly into hibernation mode. Not sure if this is even true, just something I came across when googling error messages above.

computersavvy · December 2, 2022, 5:00pm

Your nvme controller is showing errors. That should be fixed either by firmware, or potentially by cleaning the contacts and reseating the drive. Errors at that level may develop into something worse.
On my systems (both laptop with nvme and desktop with SSD and HDD) I see exactly what you posted.

dqalombardi · December 2, 2022, 10:27pm

My understanding is that cleaning contacts is something one usually has to do when a drive get old. It’s surprising to me because this is a brand new laptop. I got it just a few months ago.

Since this is an ultrabook, opening it up and fiddling with the drive is quite a nontrivial task, so it would be nice to be quite sure before trying that. Do you have any recommendations for further diagnostics or logs to check ? Or do you feel quite confident that cleaning the contacts and reseating the drive should do the trick ?

Could you also maybe elaborate on this ?

On my systems (both laptop with nvme and desktop with SSD and HDD) I see exactly what you posted.

Do you mean you’re experiencing the same problem ?

dqalombardi · December 2, 2022, 10:34pm

Does anything seem obviously the matter with my drive ?

Output of df -Th:

Filesystem     Type      Size  Used Avail Use% Mounted on
devtmpfs       devtmpfs  4.0M     0  4.0M   0% /dev
tmpfs          tmpfs     7.5G  268K  7.5G   1% /dev/shm
tmpfs          tmpfs     3.0G  2.0M  3.0G   1% /run
/dev/nvme0n1p3 btrfs     476G   63G  411G  14% /
/dev/nvme0n1p3 btrfs     476G   63G  411G  14% /home
/dev/nvme0n1p2 ext4      974M  317M  590M  35% /boot
tmpfs          tmpfs     7.5G   16K  7.5G   1% /tmp
/dev/nvme0n1p1 vfat      599M   53M  547M   9% /boot/efi
tmpfs          tmpfs     1.5G  328K  1.5G   1% /run/user/1000

Output of fdisk -1:

Disk /dev/nvme0n1: 476.94 GiB, 512110190592 bytes, 1000215216 sectors
Disk model: KBG50ZNS512G NVMe KIOXIA 512GB          
Units: sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disklabel type: gpt
Disk identifier: 40D4D3BB-7A25-487D-BA8B-7CDA7A376CC6

Device           Start        End   Sectors   Size Type
/dev/nvme0n1p1    2048    1230847   1228800   600M EFI System
/dev/nvme0n1p2 1230848    3327999   2097152     1G Linux filesystem
/dev/nvme0n1p3 3328000 1000214527 996886528 475.4G Linux filesystem


Disk /dev/zram0: 8 GiB, 8589934592 bytes, 2097152 sectors
Units: sectors of 1 * 4096 = 4096 bytes
Sector size (logical/physical): 4096 bytes / 4096 bytes
I/O size (minimum/optimal): 4096 bytes / 4096 bytes

computersavvy · December 3, 2022, 2:18am

I see

# cat /sys/power/state
freeze mem

and no drive errors logged. Thus I see exactly the same result from the /sys/power/state file.

What concerns me and led to the suggestion to possibly clean the drive contact was the errors being reported by nvme. Even if it eventually works those errors should not occur and may result in total failure at some point, probably when it is most inconvenient.

Those errors may be a problem with the drive, either physical or firmware or they may be controller or contact / connection errors. Although not common they also could be triggered by a mismatch of some sort between the controller and the drive electronics simply as a result of out-of-spec performance.

Cleaning and reseating the contacts is easily do-able. Anything else except firmware may require parts.

dqalombardi · December 3, 2022, 10:07am

Thanks for the explanation @computersavvy !

Unfortunately I just cleaned the contacts and reseated the drive and still no change.

Here is the most recent wake-up log.

[Sat Dec  3 11:06:23 2022] ACPI: EC: interrupt unblocked
[Sat Dec  3 11:06:24 2022] pci 0000:00:00.2: can't derive routing for PCI INT A
[Sat Dec  3 11:06:24 2022] pci 0000:00:00.2: PCI INT A: no GSI
[Sat Dec  3 11:06:24 2022] [drm] PCIE GART of 1024M enabled.
[Sat Dec  3 11:06:24 2022] [drm] PTB located at 0x000000F400A00000
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resuming...
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: dpm has been disabled
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resumed successfully!
[Sat Dec  3 11:06:24 2022] [drm] VCN decode and encode initialized successfully(under DPG Mode).
[Sat Dec  3 11:06:24 2022] [drm] JPEG decode initialized successfully.
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring gfx uses VM inv eng 0 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 5 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 6 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 7 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 8 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 9 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 10 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring kiq_2.1.0 uses VM inv eng 11 on hub 0
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring sdma0 uses VM inv eng 0 on hub 1
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_dec uses VM inv eng 1 on hub 1
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc0 uses VM inv eng 4 on hub 1
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc1 uses VM inv eng 5 on hub 1
[Sat Dec  3 11:06:24 2022] amdgpu 0000:04:00.0: amdgpu: ring jpeg_dec uses VM inv eng 6 on hub 1
[Sat Dec  3 11:06:24 2022] usb 3-4: reset full-speed USB device number 4 using xhci_hcd
[Sat Dec  3 11:06:24 2022] ata1: SATA link down (SStatus 0 SControl 300)
[Sat Dec  3 11:06:24 2022] ata2: SATA link down (SStatus 0 SControl 300)
[Sat Dec  3 11:06:24 2022] usb 3-2: reset full-speed USB device number 2 using xhci_hcd
[Sat Dec  3 11:06:24 2022] usb 3-3: reset high-speed USB device number 3 using xhci_hcd
[Sat Dec  3 11:07:08 2022] nvme nvme0: I/O 72 (I/O Cmd) QID 15 timeout, aborting
[Sat Dec  3 11:07:08 2022] nvme nvme0: I/O 73 (I/O Cmd) QID 15 timeout, aborting
[Sat Dec  3 11:07:08 2022] nvme nvme0: I/O 74 (I/O Cmd) QID 15 timeout, aborting
[Sat Dec  3 11:07:08 2022] nvme nvme0: I/O 75 (I/O Cmd) QID 15 timeout, aborting
[Sat Dec  3 11:07:24 2022] nvme nvme0: I/O 27 QID 0 timeout, reset controller
[Sat Dec  3 11:07:24 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 11:07:24 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 11:07:24 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 11:07:24 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 11:07:24 2022] nvme 0000:02:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xe0 returns -16
[Sat Dec  3 11:07:24 2022] nvme 0000:02:00.0: PM: failed to resume async: error -16
[Sat Dec  3 11:07:24 2022] PM: resume devices took 60.071 seconds

dqalombardi · December 3, 2022, 11:39am

Problem persists after update to Fedora 37.

Output of dmseg -T (for device wake-up):

[Sat Dec  3 12:30:55 2022] ACPI: EC: interrupt unblocked
[Sat Dec  3 12:30:55 2022] pci 0000:00:00.2: can't derive routing for PCI INT A
[Sat Dec  3 12:30:55 2022] pci 0000:00:00.2: PCI INT A: no GSI
[Sat Dec  3 12:30:55 2022] [drm] PCIE GART of 1024M enabled.
[Sat Dec  3 12:30:55 2022] [drm] PTB located at 0x000000F400A00000
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resuming...
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: dpm has been disabled
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: SMU is resumed successfully!
[Sat Dec  3 12:30:55 2022] [drm] VCN decode and encode initialized successfully(under DPG Mode).
[Sat Dec  3 12:30:55 2022] [drm] JPEG decode initialized successfully.
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring gfx uses VM inv eng 0 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.0 uses VM inv eng 1 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.0 uses VM inv eng 4 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.0 uses VM inv eng 5 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.0 uses VM inv eng 6 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.0.1 uses VM inv eng 7 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.1.1 uses VM inv eng 8 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.2.1 uses VM inv eng 9 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring comp_1.3.1 uses VM inv eng 10 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring kiq_2.1.0 uses VM inv eng 11 on hub 0
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring sdma0 uses VM inv eng 0 on hub 1
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_dec uses VM inv eng 1 on hub 1
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc0 uses VM inv eng 4 on hub 1
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring vcn_enc1 uses VM inv eng 5 on hub 1
[Sat Dec  3 12:30:55 2022] amdgpu 0000:04:00.0: amdgpu: ring jpeg_dec uses VM inv eng 6 on hub 1
[Sat Dec  3 12:30:55 2022] ata2: SATA link down (SStatus 0 SControl 300)
[Sat Dec  3 12:30:55 2022] ata1: SATA link down (SStatus 0 SControl 300)
[Sat Dec  3 12:31:30 2022] nvme nvme0: I/O 582 (I/O Cmd) QID 6 timeout, aborting
[Sat Dec  3 12:31:30 2022] nvme nvme0: I/O 583 (I/O Cmd) QID 6 timeout, aborting
[Sat Dec  3 12:31:30 2022] nvme nvme0: I/O 584 (I/O Cmd) QID 6 timeout, aborting
[Sat Dec  3 12:31:30 2022] nvme nvme0: I/O 585 (I/O Cmd) QID 6 timeout, aborting
[Sat Dec  3 12:31:55 2022] nvme nvme0: I/O 25 QID 0 timeout, reset controller
[Sat Dec  3 12:31:55 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 12:31:55 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 12:31:55 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 12:31:55 2022] nvme nvme0: Abort status: 0x371
[Sat Dec  3 12:31:55 2022] nvme 0000:02:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xe0 returns -16
[Sat Dec  3 12:31:55 2022] nvme 0000:02:00.0: PM: failed to resume async: error -16
[Sat Dec  3 12:31:55 2022] PM: resume devices took 60.365 seconds
[Sat Dec  3 12:31:55 2022] ------------[ cut here ]------------
[Sat Dec  3 12:31:55 2022] Component: resume devices, time: 60365
[Sat Dec  3 12:31:55 2022] WARNING: CPU: 14 PID: 7462 at kernel/power/suspend_test.c:53 suspend_test_finish+0x70/0x80
[Sat Dec  3 12:31:55 2022] Modules linked in: tls uinput rfcomm snd_seq_dummy snd_hrtimer nft_objref nf_conntrack_netbios_ns nf_conntrack_broadcast nft_fib_inet nft_fib_ipv4 nft_fib_ipv6 nft_fib nft_reject_inet nf_reject_ipv4 nf_reject_ipv6 nft_reject nft_ct nft_chain_nat nf_nat nf_conntrack nf_defrag_ipv6 nf_defrag_ipv4 ip_set nf_tables nfnetlink qrtr bnep sunrpc snd_acp3x_pdm_dma snd_soc_dmic snd_acp3x_rn snd_sof_amd_renoir snd_ctl_led intel_rapl_msr snd_sof_amd_acp intel_rapl_common snd_sof_pci snd_hda_codec_realtek vfat fat mt7921e snd_sof snd_hda_codec_generic snd_hda_codec_hdmi snd_sof_utils mt7921_common snd_hda_intel snd_soc_core snd_intel_dspcfg edac_mce_amd dell_laptop mt76_connac_lib snd_compress snd_intel_sdw_acpi ac97_bus dell_smm_hwmon mt76 kvm_amd snd_hda_codec snd_pcm_dmaengine uvcvideo btusb snd_hda_core snd_rpl_pci_acp6x snd_hwdep snd_pci_acp6x btrtl mac80211 kvm videobuf2_vmalloc btbcm videobuf2_memops snd_seq btintel videobuf2_v4l2 snd_seq_device irqbypass btmtk videobuf2_common
[Sat Dec  3 12:31:55 2022]  dell_wmi libarc4 snd_pcm rapl pcspkr bluetooth dell_smbios snd_pci_acp5x videodev snd_timer snd_rn_pci_acp3x dcdbas cfg80211 snd_acp_config ledtrig_audio snd mc snd_soc_acpi sparse_keymap joydev dell_wmi_descriptor wmi_bmof soundcore snd_pci_acp3x k10temp i2c_piix4 dell_rbtn rfkill amd_pmc acpi_tad zram amdgpu drm_ttm_helper ttm iommu_v2 nvme gpu_sched drm_buddy nvme_core drm_display_helper hid_multitouch ucsi_acpi crct10dif_pclmul crc32_pclmul crc32c_intel polyval_clmulni polyval_generic ghash_clmulni_intel typec_ucsi ccp serio_raw cec sp5100_tco typec nvme_common wmi video i2c_hid_acpi i2c_hid ip6_tables ip_tables fuse
[Sat Dec  3 12:31:55 2022] CPU: 14 PID: 7462 Comm: systemd-sleep Not tainted 6.0.10-300.fc37.x86_64 #1
[Sat Dec  3 12:31:55 2022] Hardware name: Dell Inc. Inspiron 14 5425/0J9C2M, BIOS 1.5.0 09/13/2022
[Sat Dec  3 12:31:55 2022] RIP: 0010:suspend_test_finish+0x70/0x80
[Sat Dec  3 12:31:55 2022] Code: 03 00 00 29 c1 e8 0e 4f bf 00 81 fb 10 27 00 00 77 07 5b 5d c3 cc cc cc cc 89 da 48 89 ee 48 c7 c7 eb 16 75 9e e8 18 ed be 00 <0f> 0b 5b 5d c3 cc cc cc cc cc cc cc cc cc cc cc 0f 1f 44 00 00 0f
[Sat Dec  3 12:31:55 2022] RSP: 0018:ffffbb2a511afd60 EFLAGS: 00010292
[Sat Dec  3 12:31:55 2022] RAX: 0000000000000026 RBX: 000000000000ebcd RCX: 0000000000000000
[Sat Dec  3 12:31:55 2022] RDX: 0000000000000001 RSI: ffffffff9e7b08f2 RDI: 00000000ffffffff
[Sat Dec  3 12:31:55 2022] RBP: ffffffff9e75160b R08: ffffffff9f0662e0 R09: 0000000000000004
[Sat Dec  3 12:31:55 2022] R10: ffffffffffffffff R11: ffffffff9fc04ae6 R12: ffffffff9e7515c6
[Sat Dec  3 12:31:55 2022] R13: ffff91a881267908 R14: 0000000000000004 R15: ffff91a889a98020
[Sat Dec  3 12:31:55 2022] FS:  00007faddae2eb40(0000) GS:ffff91ab7e980000(0000) knlGS:0000000000000000
[Sat Dec  3 12:31:55 2022] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[Sat Dec  3 12:31:55 2022] CR2: 00007faddb2a2be0 CR3: 000000021a6c4000 CR4: 0000000000750ee0
[Sat Dec  3 12:31:55 2022] PKRU: 55555554
[Sat Dec  3 12:31:55 2022] Call Trace:
[Sat Dec  3 12:31:55 2022]  <TASK>
[Sat Dec  3 12:31:55 2022]  suspend_devices_and_enter+0x1bd/0x870
[Sat Dec  3 12:31:55 2022]  pm_suspend.cold+0x2d2/0x35e
[Sat Dec  3 12:31:55 2022]  state_store+0x68/0xd0
[Sat Dec  3 12:31:55 2022]  kernfs_fop_write_iter+0x11e/0x1f0
[Sat Dec  3 12:31:55 2022]  vfs_write+0x222/0x3e0
[Sat Dec  3 12:31:55 2022]  ksys_write+0x5b/0xd0
[Sat Dec  3 12:31:55 2022]  do_syscall_64+0x5b/0x80
[Sat Dec  3 12:31:55 2022]  ? exc_page_fault+0x70/0x170
[Sat Dec  3 12:31:55 2022]  entry_SYSCALL_64_after_hwframe+0x63/0xcd
[Sat Dec  3 12:31:55 2022] RIP: 0033:0x7faddb11e0c4
[Sat Dec  3 12:31:55 2022] Code: 15 71 7d 0d 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 05 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
[Sat Dec  3 12:31:55 2022] RSP: 002b:00007ffed9bbe5b8 EFLAGS: 00000202 ORIG_RAX: 0000000000000001
[Sat Dec  3 12:31:55 2022] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007faddb11e0c4
[Sat Dec  3 12:31:55 2022] RDX: 0000000000000004 RSI: 00007ffed9bbe6a0 RDI: 0000000000000004
[Sat Dec  3 12:31:55 2022] RBP: 00007ffed9bbe6a0 R08: 0000563840d431c0 R09: 0000000000000000
[Sat Dec  3 12:31:55 2022] R10: 0000563840483158 R11: 0000000000000202 R12: 0000000000000004
[Sat Dec  3 12:31:55 2022] R13: 0000563840d3f3b0 R14: 0000000000000004 R15: 00007faddb1f2a00
[Sat Dec  3 12:31:55 2022]  </TASK>
[Sat Dec  3 12:31:55 2022] ---[ end trace 0000000000000000 ]---
[Sat Dec  3 12:31:55 2022] OOM killer enabled.
[Sat Dec  3 12:31:55 2022] Restarting tasks ... done.
[Sat Dec  3 12:31:55 2022] random: crng reseeded on system resumption
[Sat Dec  3 12:31:56 2022] nvme nvme0: 16/0/0 default/read/poll queues
[Sat Dec  3 12:31:56 2022] PM: suspend exit

So far I have:

Updated BIOS
Updated firmware
Cleaned drive contacts
Reseated drive
Updated to F37

Any further help would be appreciated ! I don’t know much about hardware so it’s hard for me to imagine where the problem might lie.

dqalombardi · December 3, 2022, 8:27pm

I found this post on an Arch forum which links to this post on the Linux kernel forum. Both seem to be very related, though I can’t quite make out what the solution was (if there was one).

The amount of people affected by this issue on other distros (I’ve seen Arch, Ubunto, and Fedora so far) and the fact that there was discussion about it on the kernel forum makes me thing I should seek help further up stream.

Thank you for the help ! If anyone has any further ideas (or can help me decipher the discussion on the kernel forum), I would really appreciate it !

dqalombardi · December 3, 2022, 8:39pm

I believe I found the root cause of the issue. Below is a bugzilla.kernel post addressing the bug.

Comment #25 seems to say that a fix for this bug will be released in Linux kernel 6.1.

Comment #37 says the fix could be cherry-picked by the Fedora kernel maintainers before 6.1 is rolled out in Fedora (maybe around Q2 2023).

How likely is it that the Fedora devs would do something like this ? (Again, I’m very new to the community so I don’t know much about capacity, scheduling, priority, etc.)

ozeszty · December 4, 2022, 11:15am

Well done!
Kernel 6.1 is just around the corner, with RC7 available https://koji.fedoraproject.org/koji/packageinfo?packageID=8, shortly after final release there will be packages for F37 and a test day: testdays

You’ll have to wait for a patch dedicated to your model, to speed thing up, upload acpidump (from acpica-tools) and dmidecode output to that bug report, as previously requested.

dqalombardi · December 4, 2022, 6:59pm

I was asked to open my own bugzilla.kernel ticket so conversation was moved here.

Maybe we could close/resolve/conclude this post ? Not sure if this qualifies as a ‘solution’, but the issue seems to have left the scope of this forum. I just wanted to document the new ticket in case anyone finds themself in a similar situation.

ozeszty · December 4, 2022, 10:54pm

Wait for the bug resolution in bugzilla, if it’s indeed same problem, summarize what and where needs to be reported (based on a couple of previous posts) and mark it as solution.