The officially official Devuan Forum!

You are not logged in.

#1 2024-05-24 11:47:16

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Suspend lockup lately

On 6.6. and 6.7 kernels from backports I see frequent lockups when the computer goes into suspend.

Update: its not only the backports kernels, also the stock kernel does it.

Involved HW: X570 main board, Ryzen 9 5900X 12-core CPU, RX6700XT graphics, an illuminated keyboard. 
Involved SW: Devuan Daedalus in efi mode, Lighdm/Cinnamon as DE, linux-image-amd64 from backports is installed.

In the Engergy Settings the monitor is blanked after 15m, suspend starts at 20m. That setting was executed as desired usually. When suspend starts the keyboard lights go off and the power on LED on the case starts to blink. I can wake up the PC by using any key on the keyboard which happens pretty fast. Mouse movements do nothing. When I am away from the PC for longer than half an hour or so, the system goes into hibernate, don't know exactly how long the delay really is. In this case wake up performs a reboot, I see the bios screen, and finally I am back on the user login and find what I have left after logging in. Used to work fine with some rare exceptions with the early backports kernels.

With the 6.6. and 6.7 kernels I am frequently faced with a PC that is completely locked, a hard reset is required to get it back to work. The monitor LED shows no input signal, the keyboard light still are on, the PC does not react to any key, Numlock isn't working. For the time being I have disabled suspend/hibernate.

I have digged out about 900 lines from the syslog that show that there is a problem with the GPU. Here are the first 100 lines that end in a crash dump. Comments?

BTW: Is there something like a NoPaste area for longer outputs like there is on the German Debian forum? 

2024-05-22T10:57:06.865339+02:00 rh060 dbus-daemon[5722]: [session uid=1000 pid=5720] Successfully activated service 'org.cinnamon.ScreenSaver'
2024-05-22T10:57:06.898671+02:00 rh060 x-session-manager[5684]: WARNING: t+5027,65597s: Detected that screensaver has appeared on the bus
2024-05-22T11:02:06.825480+02:00 rh060 ModemManager[4834]: <info>  [sleep-monitor-systemd] system is about to suspend
2024-05-22T11:02:06.825488+02:00 rh060 NetworkManager[4816]: <info>  [1716368526.8254] manager: sleep: sleep requested (sleeping: no  enabled: yes)
2024-05-22T11:02:06.832353+02:00 rh060 kernel: [ 5974.632834] igb 0000:06:00.0 eth0: left promiscuous mode
2024-05-22T11:02:06.900347+02:00 rh060 kernel: [ 5974.697940] PM: suspend entry (deep)
2024-05-22T11:02:06.912345+02:00 rh060 kernel: [ 5974.712957] Filesystems sync: 0.015 seconds
2024-05-22T11:02:12.547957+02:00 rh060 kernel: [ 5974.713615] Freezing user space processes
2024-05-22T11:02:12.548013+02:00 rh060 kernel: [ 5974.715469] Freezing user space processes completed (elapsed 0.001 seconds)
2024-05-22T11:02:12.548014+02:00 rh060 kernel: [ 5974.715471] OOM killer disabled.
2024-05-22T11:02:12.548014+02:00 rh060 kernel: [ 5974.715472] Freezing remaining freezable tasks
2024-05-22T11:02:12.548016+02:00 rh060 kernel: [ 5974.716849] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
2024-05-22T11:02:12.548017+02:00 rh060 kernel: [ 5974.716866] printk: Suspending console(s) (use no_console_suspend to debug)
2024-05-22T11:02:12.548017+02:00 rh060 kernel: [ 5974.793519] sd 9:0:0:0: [sdd] Synchronizing SCSI cache
2024-05-22T11:02:12.548017+02:00 rh060 kernel: [ 5974.795481] ata10.00: Entering standby power mode
2024-05-22T11:02:12.548018+02:00 rh060 kernel: [ 5974.801022] sd 4:0:0:0: [sdc] Synchronizing SCSI cache
2024-05-22T11:02:12.548018+02:00 rh060 kernel: [ 5974.801046] sd 8:0:0:0: [sdb] Synchronizing SCSI cache
2024-05-22T11:02:12.548018+02:00 rh060 kernel: [ 5974.801046] sd 5:0:0:0: [sda] Synchronizing SCSI cache
2024-05-22T11:02:12.548019+02:00 rh060 kernel: [ 5974.801338] ata9.00: Entering standby power mode
2024-05-22T11:02:12.548020+02:00 rh060 kernel: [ 5974.802907] ata6.00: Entering standby power mode
2024-05-22T11:02:12.548020+02:00 rh060 kernel: [ 5974.804526] ata5.00: Entering standby power mode
2024-05-22T11:02:12.548021+02:00 rh060 kernel: [ 5974.925255] xhci_hcd 0000:05:00.0: PM: pci_pm_suspend(): hcd_pci_suspend+0x0/0x20 [usbcore] returns -16
2024-05-22T11:02:12.548023+02:00 rh060 kernel: [ 5974.925280] xhci_hcd 0000:05:00.0: PM: dpm_run_callback(): pci_pm_suspend+0x0/0x170 returns -16
2024-05-22T11:02:12.548023+02:00 rh060 kernel: [ 5974.925287] xhci_hcd 0000:05:00.0: PM: failed to suspend async: error -16
2024-05-22T11:02:12.548023+02:00 rh060 kernel: [ 5975.432312] PM: Some devices failed to suspend, or early wake event detected
2024-05-22T11:02:12.548024+02:00 rh060 kernel: [ 5975.432975] [drm] PCIE GART of 512M enabled (table at 0x0000008000300000).
2024-05-22T11:02:12.548024+02:00 rh060 kernel: [ 5975.432995] [drm] PSP is resuming...
2024-05-22T11:02:12.548025+02:00 rh060 kernel: [ 5975.441679] nvme nvme0: Shutdown timeout set to 10 seconds
2024-05-22T11:02:12.548025+02:00 rh060 kernel: [ 5975.442611] nvme nvme0: 8/0/0 default/read/poll queues
2024-05-22T11:02:12.548026+02:00 rh060 kernel: [ 5975.442907] nvme nvme0: Ignoring bogus Namespace Identifiers
2024-05-22T11:02:12.548026+02:00 rh060 kernel: [ 5975.466719] nvme nvme1: 31/0/0 default/read/poll queues
2024-05-22T11:02:12.548027+02:00 rh060 kernel: [ 5975.467043] [drm] reserve 0xa00000 from 0x82fd000000 for PSP TMR
2024-05-22T11:02:12.548027+02:00 rh060 kernel: [ 5975.599145] amdgpu 0000:0c:00.0: amdgpu: RAS: optional ras ta ucode is not available
2024-05-22T11:02:12.548028+02:00 rh060 kernel: [ 5975.621369] amdgpu 0000:0c:00.0: amdgpu: SECUREDISPLAY: securedisplay ta ucode is not available
2024-05-22T11:02:12.548028+02:00 rh060 kernel: [ 5975.621371] amdgpu 0000:0c:00.0: amdgpu: SMU is resuming...
2024-05-22T11:02:12.548029+02:00 rh060 kernel: [ 5975.621374] amdgpu 0000:0c:00.0: amdgpu: smu driver if version = 0x0000000e, smu fw if version = 0x00000012, smu fw program = 0, version = 0x00413900 (65.57.0)
2024-05-22T11:02:12.548029+02:00 rh060 kernel: [ 5975.621377] amdgpu 0000:0c:00.0: amdgpu: SMU driver if version not matched
2024-05-22T11:02:12.548030+02:00 rh060 kernel: [ 5975.621449] amdgpu 0000:0c:00.0: amdgpu: use vbios provided pptable
2024-05-22T11:02:12.548030+02:00 rh060 kernel: [ 5975.909281] ata10: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-05-22T11:02:12.548031+02:00 rh060 kernel: [ 5975.909289] ata10.00: Entering active power mode
2024-05-22T11:02:12.548031+02:00 rh060 kernel: [ 5975.909458] ata6: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-05-22T11:02:12.548032+02:00 rh060 kernel: [ 5975.909459] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
2024-05-22T11:02:12.548033+02:00 rh060 kernel: [ 5975.909464] ata6.00: Entering active power mode
2024-05-22T11:02:12.548033+02:00 rh060 kernel: [ 5975.909480] ata9: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-05-22T11:02:12.548034+02:00 rh060 kernel: [ 5975.909487] ata9.00: Entering active power mode
2024-05-22T11:02:12.548034+02:00 rh060 kernel: [ 5975.909492] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
2024-05-22T11:02:12.548034+02:00 rh060 kernel: [ 5975.909503] ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-05-22T11:02:12.548035+02:00 rh060 kernel: [ 5975.909511] ata5.00: Entering active power mode
2024-05-22T11:02:12.548035+02:00 rh060 kernel: [ 5975.910024] ata5.00: supports DRM functions and may not be fully accessible
2024-05-22T11:02:12.548035+02:00 rh060 kernel: [ 5975.910229] ata10.00: supports DRM functions and may not be fully accessible
2024-05-22T11:02:12.548036+02:00 rh060 kernel: [ 5975.910252] ata6.00: supports DRM functions and may not be fully accessible
2024-05-22T11:02:12.548036+02:00 rh060 kernel: [ 5975.911149] ata10.00: supports DRM functions and may not be fully accessible
2024-05-22T11:02:12.548037+02:00 rh060 kernel: [ 5975.911186] ata6.00: supports DRM functions and may not be fully accessible
2024-05-22T11:02:12.548037+02:00 rh060 kernel: [ 5975.911861] ata10.00: configured for UDMA/133
2024-05-22T11:02:12.548037+02:00 rh060 kernel: [ 5975.911920] ata6.00: configured for UDMA/133
2024-05-22T11:02:12.548038+02:00 rh060 kernel: [ 5975.912293] ata4.00: configured for UDMA/133
2024-05-22T11:02:12.548038+02:00 rh060 kernel: [ 5975.914896] ata3.00: configured for UDMA/100
2024-05-22T11:02:12.548039+02:00 rh060 kernel: [ 5975.915152] ata5.00: supports DRM functions and may not be fully accessible
2024-05-22T11:02:12.548039+02:00 rh060 kernel: [ 5975.919948] ata5.00: configured for UDMA/133
2024-05-22T11:02:12.548040+02:00 rh060 kernel: [ 5975.930160] ata5.00: Enabling discard_zeroes_data
2024-05-22T11:02:12.548040+02:00 rh060 kernel: [ 5978.356220] ata9.00: configured for UDMA/133
2024-05-22T11:02:12.548040+02:00 rh060 kernel: [ 5980.329904] amdgpu 0000:0c:00.0: amdgpu: SMU: I'm not done with your previous command: SMN_C2PMSG_66:0x00000036 SMN_C2PMSG_82:0x00000000
2024-05-22T11:02:12.548041+02:00 rh060 kernel: [ 5980.329906] amdgpu 0000:0c:00.0: amdgpu: RunDcBtc failed!
2024-05-22T11:02:12.548041+02:00 rh060 kernel: [ 5980.329907] amdgpu 0000:0c:00.0: amdgpu: Failed to setup smc hw!
2024-05-22T11:02:12.548041+02:00 rh060 kernel: [ 5980.329908] [drm:amdgpu_device_ip_resume_phase2 [amdgpu]] *ERROR* resume of IP block <smu> failed -62
2024-05-22T11:02:12.548042+02:00 rh060 kernel: [ 5980.330026] amdgpu 0000:0c:00.0: amdgpu: amdgpu_device_ip_resume failed (-62).
2024-05-22T11:02:12.548042+02:00 rh060 kernel: [ 5980.330027] amdgpu 0000:0c:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xf0 returns -62
2024-05-22T11:02:12.548042+02:00 rh060 kernel: [ 5980.330031] amdgpu 0000:0c:00.0: PM: failed to resume async: error -62
2024-05-22T11:02:12.548043+02:00 rh060 kernel: [ 5980.330604] igb 0000:06:00.0 eth0: igb: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
2024-05-22T11:02:12.548043+02:00 rh060 kernel: [ 5980.348488] OOM killer enabled.
2024-05-22T11:02:12.552348+02:00 rh060 kernel: [ 5980.348490] Restarting tasks ... done.
2024-05-22T11:02:12.552350+02:00 rh060 kernel: [ 5980.351845] random: crng reseeded on system resumption
2024-05-22T11:02:12.552351+02:00 rh060 kernel: [ 5980.351849] PM: suspend exit
2024-05-22T11:02:12.552351+02:00 rh060 kernel: [ 5980.351907] PM: suspend entry (s2idle)
2024-05-22T11:02:12.556342+02:00 rh060 kernel: [ 5980.355931] amdgpu: Move buffer fallback to memcpy unavailable
2024-05-22T11:02:12.560343+02:00 rh060 kernel: [ 5980.360945] snd_hda_intel 0000:0c:00.1: Refused to change power state from D0 to D3hot
2024-05-22T11:02:32.570256+02:00 rh060 kernel: [ 5980.366015] Filesystems sync: 0.014 seconds
2024-05-22T11:02:32.570269+02:00 rh060 kernel: [ 5980.366280] Freezing user space processes
2024-05-22T11:02:32.570340+02:00 rh060 kernel: [ 5982.489672] igb 0000:06:00.0: exceed max 2 second
2024-05-22T11:02:32.570344+02:00 rh060 kernel: [ 5990.601623] [drm:amdgpu_job_timedout [amdgpu]] *ERROR* ring sdma0 timeout, signaled seq=5850, emitted seq=5852
2024-05-22T11:02:32.570345+02:00 rh060 kernel: [ 5990.601949] [drm:amdgpu_job_timedout [amdgpu]] *ERROR* Process information: process  pid 0 thread  pid 0
2024-05-22T11:02:32.570346+02:00 rh060 kernel: [ 5990.602173] amdgpu 0000:0c:00.0: amdgpu: GPU reset begin!
2024-05-22T11:02:32.570350+02:00 rh060 kernel: [ 5990.602373] amdgpu 0000:0c:00.0: amdgpu: Failed to disallow df cstate
2024-05-22T11:02:32.570350+02:00 rh060 kernel: [ 5990.906203] ------------[ cut here ]------------
2024-05-22T11:02:32.570351+02:00 rh060 kernel: [ 5990.906204] WARNING: CPU: 3 PID: 11 at drivers/gpu/drm/amd/amdgpu/amdgpu_irq.c:615 amdgpu_irq_put+0x49/0x70 [amdgpu]
2024-05-22T11:02:32.570352+02:00 rh060 kernel: [ 5990.906320] Modules linked in: qrtr nct6775 nct6775_core hwmon_vid lm92 vboxnetflt(OE) vboxnetadp(OE) vboxdrv(OE) parport_pc ppdev lp parport nfsd auth_rpcgss nfs_acl nfs lockd grace fscache netfs sunrpc nls_ascii nls_cp437 vfat fat pktcdvd amdgpu drm_exec amdxcp drm_buddy snd_hda_codec_realtek intel_rapl_msr gpu_sched intel_rapl_common snd_hda_codec_generic drm_suballoc_helper snd_hda_codec_hdmi edac_mce_amd drm_display_helper kvm_amd cec snd_hda_intel rc_core snd_intel_dspcfg drm_ttm_helper snd_intel_sdw_acpi kvm ttm snd_hda_codec drm_kms_helper snd_hda_core eeepc_wmi asus_wmi joydev asus_ec_sensors evdev snd_hwdep irqbypass battery drm snd_pcm ledtrig_audio sparse_keymap platform_profile snd_timer sp5100_tco rfkill snd rapl video pcspkr wmi_bmof mxm_wmi watchdog efi_pstore acpi_cpufreq k10temp soundcore ccp button efivarfs ext4 crc16 mbcache jbd2 crc32c_generic dm_crypt dm_mod hid_generic usbhid crc32_pclmul hid crc32c_intel sg uas ghash_clmulni_intel sr_mod sha512_ssse3 usb_storage sd_mod cdrom sha256_ssse3
2024-05-22T11:02:32.570354+02:00 rh060 kernel: [ 5990.906380]  sha1_ssse3 nvme ahci nvme_core xhci_pci libahci t10_pi xhci_hcd libata crc64_rocksoft_generic crc64_rocksoft aesni_intel crc_t10dif igb usbcore crct10dif_generic scsi_mod crypto_simd crct10dif_pclmul cryptd i2c_algo_bit crc64 i2c_piix4 scsi_common dca usb_common crct10dif_common wmi
2024-05-22T11:02:32.570357+02:00 rh060 kernel: [ 5990.906398] CPU: 3 PID: 11 Comm: kworker/u64:0 Tainted: G           OE      6.6.13+bpo-amd64 #1  Debian 6.6.13-1~bpo12+1
2024-05-22T11:02:32.570357+02:00 rh060 kernel: [ 5990.906400] Hardware name: System manufacturer System Product Name/PRIME X570-PRO, BIOS 5013 03/22/2024
2024-05-22T11:02:32.570358+02:00 rh060 kernel: [ 5990.906402] Workqueue: amdgpu-reset-dev drm_sched_job_timedout [gpu_sched]
2024-05-22T11:02:32.570359+02:00 rh060 kernel: [ 5990.906407] RIP: 0010:amdgpu_irq_put+0x49/0x70 [amdgpu]
2024-05-22T11:02:32.570360+02:00 rh060 kernel: [ 5990.906518] Code: 48 8b 4e 10 48 83 39 00 74 2c 89 d1 48 8d 04 88 8b 08 85 c9 74 14 f0 ff 08 b8 00 00 00 00 74 05 e9 9c d0 d2 d1 e9 57 fd ff ff <0f> 0b b8 ea ff ff ff e9 8b d0 d2 d1 b8 ea ff ff ff e9 81 d0 d2 d1
2024-05-22T11:02:32.570361+02:00 rh060 kernel: [ 5990.906519] RSP: 0018:ffffb6c780147c88 EFLAGS: 00010246
2024-05-22T11:02:32.570361+02:00 rh060 kernel: [ 5990.906521] RAX: ffff8c79811eaaa8 RBX: ffff8c798ba00000 RCX: 0000000000000000
2024-05-22T11:02:32.570362+02:00 rh060 kernel: [ 5990.906522] RDX: 0000000000000000 RSI: ffff8c798ba27988 RDI: ffff8c798ba00000
2024-05-22T11:02:32.570362+02:00 rh060 kernel: [ 5990.906523] RBP: ffff8c798ba00000 R08: 00000000000395c0 R09: 0000000000000006
2024-05-22T11:02:32.570363+02:00 rh060 kernel: [ 5990.906524] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000001050
2024-05-22T11:02:32.570364+02:00 rh060 kernel: [ 5990.906525] R13: ffff8c798ba414c8 R14: ffff8c7a588d7800 R15: 0000000000000000
2024-05-22T11:02:32.570364+02:00 rh060 kernel: [ 5990.906526] FS:  0000000000000000(0000) GS:ffff8c886eac0000(0000) knlGS:0000000000000000
2024-05-22T11:02:32.570364+02:00 rh060 kernel: [ 5990.906527] CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
2024-05-22T11:02:32.570365+02:00 rh060 kernel: [ 5990.906528] CR2: 00007f42e27fe990 CR3: 00000002165c0000 CR4: 0000000000750ee0
2024-05-22T11:02:32.570365+02:00 rh060 kernel: [ 5990.906529] PKRU: 55555554

Last edited by rolfie (2024-06-01 20:03:48)

Offline

#2 2024-05-30 11:39:11

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

Update: its not only the backports kernels, suspend also locks up with the same symptoms with the latest 6.1.21 kernel.

I have looked at the updates installed from end of April onward, and also installed firmware-amd-graphics_20230625-2_all from Testing. Still the same issue.

Anybody here with the same kind of problems?

Offline

#3 2024-06-02 13:51:10

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

Maybe this is related to the xorg update installed end of April. I would like to pickup the previous versions and install them. How can I find them now? Is there an archive that holds the older versions?

I am after this 3 files in their earlier version:
xserver-xorg-core:amd64 (2:21.1.7-3+deb12u5devuan1, 2:21.1.7-3+deb12u7devuan1)
xserver-xorg-legacy:amd64 (2:21.1.7-3+deb12u5devuan1, 2:21.1.7-3+deb12u7devuan1)
xserver-common:amd64 (2:21.1.7-3+deb12u5devuan1, 2:21.1.7-3+deb12u7devuan1)

Last edited by rolfie (2024-06-02 15:15:36)

Offline

#4 2024-06-02 14:15:14

ralph.ronnquist
Administrator
From: Battery Point, Tasmania, AUS
Registered: 2016-11-30
Posts: 1,173  

Re: Suspend lockup lately

This looks like "the broken xhci_hcd problem" from 2015 is back... at the time it was solved by unloading the module before sleep and loading it back on resume.

Offline

#5 2024-06-02 14:24:56

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

Strangely enough, it works on an older AM3+-PC. On my Ryzen9 workstation, I can reproduce it with any kernel available now. There I have keyboard and mouse connected via USB, while the older PC has the keyboard on the PS2 port.

I have also spend some time to install Excalibur on this troublemaker. It behaves a bit different in detail, but also locks up.

Is there anything I can do to prove the theory? Can I do anything to fix this? Or should I write a bug report? To Debian?

Last edited by rolfie (2024-06-02 14:57:26)

Offline

#6 2024-06-02 20:29:59

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

News: I have installed my old RX5500 graphics card since somebody mentioned to me that the card may have a defect. Same behaviour, lockup with Daedalus.
Watched whats happening: the screensaver cuts in, the monitor goes into power saving mode, 5 mins later the keyboard illu goes off for a second, comes back on, monitor comes on again and switches back into power saving mode within a second, illu stays on, PC is locked.

Messages are different though, no sign of xhci_hcd now:

2024-06-02T21:56:33.525704+02:00 rh060 x-session-manager[5552]: WARNING: t+682,46566s: Detected that screensaver has appeared on the bus
2024-06-02T22:01:33.413777+02:00 rh060 NetworkManager[4803]: <info>  [1717358493.4137] manager: sleep: sleep requested (sleeping: no  enabled: yes)
2024-06-02T22:01:33.413789+02:00 rh060 ModemManager[4833]: <info>  [sleep-monitor-systemd] system is about to suspend
2024-06-02T22:01:33.528847+02:00 rh060 kernel: [ 1020.484202] PM: suspend entry (deep)
2024-06-02T22:01:39.783970+02:00 rh060 kernel: [ 1020.496288] Filesystems sync: 0.012 seconds
2024-06-02T22:01:39.783984+02:00 rh060 kernel: [ 1020.496943] Freezing user space processes
2024-06-02T22:01:39.784028+02:00 rh060 kernel: [ 1020.498314] Freezing user space processes completed (elapsed 0.001 seconds)
2024-06-02T22:01:39.784029+02:00 rh060 kernel: [ 1020.498316] OOM killer disabled.
2024-06-02T22:01:39.784030+02:00 rh060 kernel: [ 1020.498317] Freezing remaining freezable tasks
2024-06-02T22:01:39.784030+02:00 rh060 kernel: [ 1020.499426] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
2024-06-02T22:01:39.784031+02:00 rh060 kernel: [ 1020.499447] printk: Suspending console(s) (use no_console_suspend to debug)
2024-06-02T22:01:39.784032+02:00 rh060 kernel: [ 1020.607504] sd 8:0:0:0: [sdb] Synchronizing SCSI cache
2024-06-02T22:01:39.784032+02:00 rh060 kernel: [ 1020.607507] sd 9:0:0:0: [sdd] Synchronizing SCSI cache
2024-06-02T22:01:39.784032+02:00 rh060 kernel: [ 1020.608051] ata9.00: Entering standby power mode
2024-06-02T22:01:39.784033+02:00 rh060 kernel: [ 1020.609483] ata10.00: Entering standby power mode
2024-06-02T22:01:39.784033+02:00 rh060 kernel: [ 1020.611468] sd 5:0:0:0: [sda] Synchronizing SCSI cache
2024-06-02T22:01:39.784034+02:00 rh060 kernel: [ 1020.611935] sd 4:0:0:0: [sdc] Synchronizing SCSI cache
2024-06-02T22:01:39.784034+02:00 rh060 kernel: [ 1020.613347] ata6.00: Entering standby power mode
2024-06-02T22:01:39.784035+02:00 rh060 kernel: [ 1020.615521] ata5.00: Entering standby power mode
2024-06-02T22:01:39.784035+02:00 rh060 kernel: [ 1020.772430] xhci_hcd 0000:05:00.0: PM: pci_pm_suspend(): hcd_pci_suspend+0x0/0x20 [usbcore] returns -16
2024-06-02T22:01:39.784036+02:00 rh060 kernel: [ 1020.772454] xhci_hcd 0000:05:00.0: PM: dpm_run_callback(): pci_pm_suspend+0x0/0x170 returns -16
2024-06-02T22:01:39.784036+02:00 rh060 kernel: [ 1020.772464] xhci_hcd 0000:05:00.0: PM: failed to suspend async: error -16
2024-06-02T22:01:39.784037+02:00 rh060 kernel: [ 1021.246710] PM: Some devices failed to suspend, or early wake event detected
2024-06-02T22:01:39.784037+02:00 rh060 kernel: [ 1021.247205] [drm] PCIE GART of 512M enabled (table at 0x0000008000300000).
2024-06-02T22:01:39.784038+02:00 rh060 kernel: [ 1021.247227] [drm] PSP is resuming...
2024-06-02T22:01:39.784038+02:00 rh060 kernel: [ 1021.251809] nvme nvme0: Shutdown timeout set to 10 seconds
2024-06-02T22:01:39.784039+02:00 rh060 kernel: [ 1021.252772] nvme nvme0: 8/0/0 default/read/poll queues
2024-06-02T22:01:39.784039+02:00 rh060 kernel: [ 1021.253079] nvme nvme0: Ignoring bogus Namespace Identifiers
2024-06-02T22:01:39.784039+02:00 rh060 kernel: [ 1021.278701] [drm] reserve 0x900000 from 0x81fd000000 for PSP TMR
2024-06-02T22:01:39.784040+02:00 rh060 kernel: [ 1021.281270] nvme nvme1: 31/0/0 default/read/poll queues
2024-06-02T22:01:39.784040+02:00 rh060 kernel: [ 1021.352609] amdgpu 0000:0c:00.0: amdgpu: RAS: optional ras ta ucode is not available
2024-06-02T22:01:39.784041+02:00 rh060 kernel: [ 1021.364610] amdgpu 0000:0c:00.0: amdgpu: RAP: optional rap ta ucode is not available
2024-06-02T22:01:39.784041+02:00 rh060 kernel: [ 1021.364611] amdgpu 0000:0c:00.0: amdgpu: SECUREDISPLAY: securedisplay ta ucode is not available
2024-06-02T22:01:39.784042+02:00 rh060 kernel: [ 1021.364614] amdgpu 0000:0c:00.0: amdgpu: SMU is resuming...
2024-06-02T22:01:39.784042+02:00 rh060 kernel: [ 1021.364654] amdgpu 0000:0c:00.0: amdgpu: use vbios provided pptable
2024-06-02T22:01:39.784043+02:00 rh060 kernel: [ 1021.364656] amdgpu 0000:0c:00.0: amdgpu: smc_dpm_info table revision(format.content): 4.5
2024-06-02T22:01:39.784043+02:00 rh060 kernel: [ 1021.723468] ata10: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-02T22:01:39.784043+02:00 rh060 kernel: [ 1021.723469] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
2024-06-02T22:01:39.784044+02:00 rh060 kernel: [ 1021.723651] ata5: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-02T22:01:39.784044+02:00 rh060 kernel: [ 1021.723656] ata4: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
2024-06-02T22:01:39.784044+02:00 rh060 kernel: [ 1021.723680] ata9: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-02T22:01:39.784045+02:00 rh060 kernel: [ 1021.723709] ata6: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-02T22:01:39.784045+02:00 rh060 kernel: [ 1021.723876] ata5.00: supports DRM functions and may not be fully accessible
2024-06-02T22:01:39.784046+02:00 rh060 kernel: [ 1021.724361] ata10.00: supports DRM functions and may not be fully accessible
2024-06-02T22:01:39.784046+02:00 rh060 kernel: [ 1021.724472] ata6.00: supports DRM functions and may not be fully accessible
2024-06-02T22:01:39.784047+02:00 rh060 kernel: [ 1021.724942] sd 8:0:0:0: [sdb] Starting disk
2024-06-02T22:01:39.784047+02:00 rh060 kernel: [ 1021.725153] sd 9:0:0:0: [sdd] Starting disk
2024-06-02T22:01:39.784048+02:00 rh060 kernel: [ 1021.725246] ata10.00: supports DRM functions and may not be fully accessible
2024-06-02T22:01:39.784048+02:00 rh060 kernel: [ 1021.725349] sd 5:0:0:0: [sda] Starting disk
2024-06-02T22:01:39.784049+02:00 rh060 kernel: [ 1021.725458] ata6.00: supports DRM functions and may not be fully accessible
2024-06-02T22:01:39.784049+02:00 rh060 kernel: [ 1021.725954] ata10.00: configured for UDMA/133
2024-06-02T22:01:39.784050+02:00 rh060 kernel: [ 1021.726206] ata6.00: configured for UDMA/133
2024-06-02T22:01:39.784050+02:00 rh060 kernel: [ 1021.726212] ata9.00: configured for UDMA/133
2024-06-02T22:01:39.784050+02:00 rh060 kernel: [ 1021.726233] ata6.00: Entering active power mode
2024-06-02T22:01:39.784051+02:00 rh060 kernel: [ 1021.726270] ata9.00: Entering active power mode
2024-06-02T22:01:39.784051+02:00 rh060 kernel: [ 1021.726458] ata4.00: configured for UDMA/133
2024-06-02T22:01:39.784051+02:00 rh060 kernel: [ 1021.727117] ata3.00: configured for UDMA/100
2024-06-02T22:01:39.784052+02:00 rh060 kernel: [ 1021.728835] sd 4:0:0:0: [sdc] Starting disk
2024-06-02T22:01:39.784052+02:00 rh060 kernel: [ 1021.729051] ata5.00: supports DRM functions and may not be fully accessible
2024-06-02T22:01:39.784053+02:00 rh060 kernel: [ 1021.733845] ata5.00: configured for UDMA/133
2024-06-02T22:01:39.784053+02:00 rh060 kernel: [ 1021.744125] ata5.00: Enabling discard_zeroes_data
2024-06-02T22:01:39.784054+02:00 rh060 kernel: [ 1026.575005] amdgpu 0000:0c:00.0: amdgpu: SMU: I'm not done with your previous command: SMN_C2PMSG_66:0x0000003A SMN_C2PMSG_82:0x00000000
2024-06-02T22:01:39.784054+02:00 rh060 kernel: [ 1026.575007] amdgpu 0000:0c:00.0: amdgpu: RunBtc failed!
2024-06-02T22:01:39.784055+02:00 rh060 kernel: [ 1026.575008] amdgpu 0000:0c:00.0: amdgpu: Failed to setup smc hw!
2024-06-02T22:01:39.784055+02:00 rh060 kernel: [ 1026.575009] [drm:amdgpu_device_ip_resume_phase2 [amdgpu]] *ERROR* resume of IP block <smu> failed -62
2024-06-02T22:01:39.784056+02:00 rh060 kernel: [ 1026.575138] amdgpu 0000:0c:00.0: amdgpu: amdgpu_device_ip_resume failed (-62).
2024-06-02T22:01:39.784056+02:00 rh060 kernel: [ 1026.575139] amdgpu 0000:0c:00.0: PM: dpm_run_callback(): pci_pm_resume+0x0/0xf0 returns -62
2024-06-02T22:01:39.784057+02:00 rh060 kernel: [ 1026.575144] amdgpu 0000:0c:00.0: PM: failed to resume async: error -62
2024-06-02T22:01:39.784057+02:00 rh060 kernel: [ 1026.575745] igb 0000:06:00.0 eth0: igb: eth0 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX/TX
2024-06-02T22:01:39.784058+02:00 rh060 kernel: [ 1026.742645] OOM killer enabled.
2024-06-02T22:01:39.784842+02:00 rh060 kernel: [ 1026.742647] Restarting tasks ... done.
2024-06-02T22:01:39.784846+02:00 rh060 kernel: [ 1026.743149] random: crng reseeded on system resumption
2024-06-02T22:01:39.784848+02:00 rh060 kernel: [ 1026.743153] PM: suspend exit
2024-06-02T22:01:39.784848+02:00 rh060 kernel: [ 1026.743293] PM: suspend entry (s2idle)
2024-06-02T22:01:39.796824+02:00 rh060 kernel: [ 1026.755576] snd_hda_intel 0000:0c:00.1: Refused to change power state from D0 to D3hot

Last edited by rolfie (2024-06-03 06:55:37)

Offline

#7 2024-06-02 21:01:06

ralph.ronnquist
Administrator
From: Battery Point, Tasmania, AUS
Registered: 2016-11-30
Posts: 1,173  

Re: Suspend lockup lately

???

2024-06-02T22:01:39.784035+02:00 rh060 kernel: [ 1020.772430] xhci_hcd 0000:05:00.0: PM: pci_pm_suspend(): hcd_pci_suspend+0x0/0x20 [usbcore] returns -16
2024-06-02T22:01:39.784036+02:00 rh060 kernel: [ 1020.772454] xhci_hcd 0000:05:00.0: PM: dpm_run_callback(): pci_pm_suspend+0x0/0x170 returns -16
2024-06-02T22:01:39.784036+02:00 rh060 kernel: [ 1020.772464] xhci_hcd 0000:05:00.0: PM: failed to suspend async: error -16
2024-06-02T22:01:39.784037+02:00 rh060 kernel: [ 1021.246710] PM: Some devices failed to suspend, or early wake event detected

Offline

#8 2024-06-03 06:54:42

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

Uff! Must have been too tired ...

Offline

#9 2024-06-03 07:13:08

ralph.ronnquist
Administrator
From: Battery Point, Tasmania, AUS
Registered: 2016-11-30
Posts: 1,173  

Re: Suspend lockup lately

try with

SUSPEND_MODULES=xhci_hcd

in /etc/pm/config.d/something

EDIT: That of course will stop wake-up via USB keyboard...

Offline

#10 2024-06-03 09:21:28

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

Looks like I haven't got a /etc/pm/config.d/ directory, neither on my Daedalus nor on my Excalibur installation. Also my travelling laptop does not show this directory.

Is that probably outdated info or do I need to create that directory?

Offline

#11 2024-06-03 09:55:23

ralph.ronnquist
Administrator
From: Battery Point, Tasmania, AUS
Registered: 2016-11-30
Posts: 1,173  

Re: Suspend lockup lately

hmm that's from pm-utils ... I guess I assumed you suspended via pm-suspend but apparently you suspend in some other way, so you may need to use its way of unloading modules.

Offline

#12 2024-06-03 15:50:25

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

pm-utils are not installed, I am on Cinnamon desktop (no-install-recommends) and use the Cinnamon settings dialogue/Power settings to configure the screensaver and suspend/hibernate.

Have to find out how that can be configured.

Dear Ralph, thank you for your hints.

Offline

#13 2024-06-05 08:35:15

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

I am after this 3 files in their earlier version:
xserver-xorg-core:amd64 (2:21.1.7-3+deb12u5devuan1, 2:21.1.7-3+deb12u7devuan1)
xserver-xorg-legacy:amd64 (2:21.1.7-3+deb12u5devuan1, 2:21.1.7-3+deb12u7devuan1)
xserver-common:amd64 (2:21.1.7-3+deb12u5devuan1, 2:21.1.7-3+deb12u7devuan1)

I am step further down the line with these files. Digged out how to address the earlier version:

# apt install xserver-common=21.1.7-3+deb12u5devuan1

Unfortunately the deb12u5-version isn't found any more in the repository. I can get the earlier deb12u2.

Offline

#14 2024-06-05 10:02:24

ralph.ronnquist
Administrator
From: Battery Point, Tasmania, AUS
Registered: 2016-11-30
Posts: 1,173  

Re: Suspend lockup lately

Well, you'd want the forked packages.

I still think your issue is that the USB host (xhci_hcd) driver fails to suspend, which the log says, and it willl need explicit unloading beforehand. I.e. it's not related to which Xorg packages you have.

Maybe worth a try to install pm-utils and trial it with the pm-suspend command?

Offline

#15 2024-06-05 20:07:24

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

@Ralph:

Maybe worth a try to install pm-utils and trial it with the pm-suspend command?

That is on my todo list. Along with reinstalling/overwriting the parallel Excalibur installation with a native Daedalus with the earlier xorg version. Do to other commitments it may take a few days before I can go to realize this. I am using my travelling laptop meanwhile.

Offline

#16 2024-06-05 20:15:22

rolfie
Member
Registered: 2017-11-25
Posts: 1,105  

Re: Suspend lockup lately

So far I haven't got any clue if and how I could convince the Cinnamon Python screensaver to unload/load the xhci-hid module.

Well I have used an older AM3+ computer in the basement for some experiments with suspend. It runs Daedalus in efi mode, also Cinnamon as DE, and originally had a PS2 keyboard connected. Suspend worked like a charm.

Yesterday and today I did some experiments with an USB keyboard, as well on USB2 as on USB3 ports. Here is the log with some comments:

System information:

~$ inxi -Fz
System:
  Kernel: 6.7.12+bpo-amd64 arch: x86_64 bits: 64 Desktop: Cinnamon v: 5.6.8
    Distro: Devuan GNU/Linux 5 (daedalus)
Machine:
  Type: Desktop Mobo: ASUSTeK model: SABERTOOTH 990FX R2.0 v: Rev 1.xx
    serial: <superuser required> UEFI: American Megatrends v: 2901
    date: 05/04/2016
CPU:
  Info: 8-core model: AMD FX-8370 bits: 64 type: MT MCP cache: L2: 8 MiB
  Speed (MHz): avg: 3849 min/max: N/A cores: 1: 4108 2: 4106 3: 3197 4: 3512
    5: 4119 6: 3637 7: 4014 8: 4106
Graphics:
  Device-1: AMD Lexa PRO [Radeon 540/540X/550/550X / RX 540X/550/550X]
    driver: amdgpu v: kernel
  Display: x11 server: X.Org v: 1.21.1.7 driver: X: loaded: amdgpu
    unloaded: fbdev,modesetting,radeon,vesa dri: radeonsi gpu: amdgpu
    resolution: 1920x1200~60Hz
  API: OpenGL v: 4.6 Mesa 22.3.6 renderer: AMD Radeon RX 550 / 550 Series
    (polaris12 LLVM 15.0.6 DRM 3.57 6.7.12+bpo-amd64)

Messages when keyboard connected to USB2 port, suspend works. Fast wakeup:

2024-06-04T21:21:11.501064+02:00 rh032 dbus-daemon[2334]: [session uid=1000 pid=2332] Activating service name='org.cinnamon.ScreenSaver' requested by ':1.49' (uid=1000 pid=4373 comm="/usr/bin/python3 /usr/bin/cinnamon-screensaver-com")
2024-06-04T21:21:11.692959+02:00 rh032 dbus-daemon[2334]: [session uid=1000 pid=2332] Successfully activated service 'org.cinnamon.ScreenSaver'
2024-06-04T21:21:11.766224+02:00 rh032 x-session-manager[2295]: WARNING: t+680,69284s: Detected that screensaver has appeared on the bus
2024-06-04T21:22:37.172859+02:00 rh032 ntpd[2137]: PROTO: 176.9.90.88 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:22:41.172820+02:00 rh032 ntpd[2137]: PROTO: 217.144.138.234 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:22:45.172760+02:00 rh032 ntpd[2137]: PROTO: 78.46.204.247 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:25:06.172864+02:00 rh032 ntpd[2137]: PROTO: 128.140.109.119 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:11.559479+02:00 rh032 NetworkManager[2048]: <info>  [1717529171.5594] manager: sleep: sleep requested (sleeping: no  enabled: yes)
2024-06-04T21:26:11.789923+02:00 rh032 kernel: [ 1027.012870] PM: suspend entry (deep)
2024-06-04T21:26:11.793832+02:00 rh032 kernel: [ 1027.014718] Filesystems sync: 0.001 seconds
2024-06-04T21:26:11.793845+02:00 rh032 kernel: [ 1027.018262] Freezing user space processes
2024-06-04T21:26:27.928140+02:00 rh032 kernel: [ 1027.019996] Freezing user space processes completed (elapsed 0.001 seconds)
2024-06-04T21:26:27.928160+02:00 rh032 kernel: [ 1027.020000] OOM killer disabled.
2024-06-04T21:26:27.928161+02:00 rh032 kernel: [ 1027.020002] Freezing remaining freezable tasks
2024-06-04T21:26:27.928162+02:00 rh032 kernel: [ 1027.021197] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
2024-06-04T21:26:27.928164+02:00 rh032 kernel: [ 1027.021211] printk: Suspending console(s) (use no_console_suspend to debug)
2024-06-04T21:26:27.928166+02:00 rh032 kernel: [ 1027.622446] sd 0:0:0:0: [sda] Synchronizing SCSI cache
2024-06-04T21:26:27.928167+02:00 rh032 kernel: [ 1027.622447] sd 3:0:0:0: [sdb] Synchronizing SCSI cache
2024-06-04T21:26:27.928168+02:00 rh032 kernel: [ 1027.626383] ata1.00: Entering standby power mode
2024-06-04T21:26:27.928169+02:00 rh032 kernel: [ 1027.626420] ata4.00: Entering standby power mode
2024-06-04T21:26:27.928170+02:00 rh032 kernel: [ 1027.650822] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-04T21:26:27.928171+02:00 rh032 kernel: [ 1028.212379] ACPI: EC: interrupt blocked
2024-06-04T21:26:27.928171+02:00 rh032 kernel: [ 1028.224459] amdgpu 0000:01:00.0: amdgpu: PCI CONFIG reset
2024-06-04T21:26:27.928172+02:00 rh032 kernel: [ 1028.249469] ACPI: PM: Preparing to enter system sleep state S3
2024-06-04T21:26:27.928174+02:00 rh032 kernel: [ 1028.249533] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
2024-06-04T21:26:27.928175+02:00 rh032 kernel: [ 1028.249910] ACPI: EC: event blocked
2024-06-04T21:26:27.928176+02:00 rh032 kernel: [ 1028.249911] ACPI: EC: EC stopped
2024-06-04T21:26:27.928177+02:00 rh032 kernel: [ 1028.249912] ACPI: PM: Saving platform NVS memory
2024-06-04T21:26:27.928177+02:00 rh032 kernel: [ 1028.250135] Disabling non-boot CPUs ...
2024-06-04T21:26:27.928179+02:00 rh032 kernel: [ 1028.252272] smpboot: CPU 1 is now offline
2024-06-04T21:26:27.928180+02:00 rh032 kernel: [ 1028.254795] smpboot: CPU 2 is now offline
2024-06-04T21:26:27.928181+02:00 rh032 kernel: [ 1028.256495] smpboot: CPU 3 is now offline
2024-06-04T21:26:27.928182+02:00 rh032 kernel: [ 1028.258117] smpboot: CPU 4 is now offline
2024-06-04T21:26:27.928183+02:00 rh032 kernel: [ 1028.260149] smpboot: CPU 5 is now offline
2024-06-04T21:26:27.928184+02:00 rh032 kernel: [ 1028.261750] smpboot: CPU 6 is now offline
2024-06-04T21:26:27.928185+02:00 rh032 kernel: [ 1028.263743] smpboot: CPU 7 is now offline
2024-06-04T21:26:27.928186+02:00 rh032 kernel: [ 1028.269767] ACPI: PM: Low-level resume complete
2024-06-04T21:26:27.928186+02:00 rh032 kernel: [ 1028.269783] ACPI: EC: EC started
2024-06-04T21:26:27.928188+02:00 rh032 kernel: [ 1028.269785] ACPI: PM: Restoring platform NVS memory
2024-06-04T21:26:27.928189+02:00 rh032 kernel: [ 1028.269972] PCI-DMA: Resuming GART IOMMU
2024-06-04T21:26:27.928190+02:00 rh032 kernel: [ 1028.269972] PCI-DMA: Restoring GART aperture settings
2024-06-04T21:26:27.928191+02:00 rh032 kernel: [ 1028.269978] LVT offset 0 assigned for vector 0x400
2024-06-04T21:26:27.928192+02:00 rh032 kernel: [ 1028.275256] Enabling non-boot CPUs ...
2024-06-04T21:26:27.928193+02:00 rh032 kernel: [ 1028.275292] smpboot: Booting Node 0 Processor 1 APIC 0x11
2024-06-04T21:26:27.928194+02:00 rh032 kernel: [ 1028.283336] ACPI: \_PR_.P002: Found 2 idle states
2024-06-04T21:26:27.928195+02:00 rh032 kernel: [ 1028.283540] CPU1 is up
2024-06-04T21:26:27.928196+02:00 rh032 kernel: [ 1028.283562] smpboot: Booting Node 0 Processor 2 APIC 0x12
2024-06-04T21:26:27.928197+02:00 rh032 kernel: [ 1028.292497] ACPI: \_PR_.P003: Found 2 idle states
2024-06-04T21:26:27.928198+02:00 rh032 kernel: [ 1028.292629] CPU2 is up
2024-06-04T21:26:27.928199+02:00 rh032 kernel: [ 1028.292650] smpboot: Booting Node 0 Processor 3 APIC 0x13
2024-06-04T21:26:27.928201+02:00 rh032 kernel: [ 1028.302108] ACPI: \_PR_.P004: Found 2 idle states
2024-06-04T21:26:27.928202+02:00 rh032 kernel: [ 1028.302283] CPU3 is up
2024-06-04T21:26:27.928203+02:00 rh032 kernel: [ 1028.302308] smpboot: Booting Node 0 Processor 4 APIC 0x14
2024-06-04T21:26:27.928204+02:00 rh032 kernel: [ 1028.311764] ACPI: \_PR_.P005: Found 2 idle states
2024-06-04T21:26:27.928204+02:00 rh032 kernel: [ 1028.311957] CPU4 is up
2024-06-04T21:26:27.928205+02:00 rh032 kernel: [ 1028.311980] smpboot: Booting Node 0 Processor 5 APIC 0x15
2024-06-04T21:26:27.928206+02:00 rh032 kernel: [ 1028.320673] ACPI: \_PR_.P006: Found 2 idle states
2024-06-04T21:26:27.928207+02:00 rh032 kernel: [ 1028.320888] CPU5 is up
2024-06-04T21:26:27.928207+02:00 rh032 kernel: [ 1028.320913] smpboot: Booting Node 0 Processor 6 APIC 0x16
2024-06-04T21:26:27.928209+02:00 rh032 kernel: [ 1028.330291] ACPI: \_PR_.P007: Found 2 idle states
2024-06-04T21:26:27.928212+02:00 rh032 kernel: [ 1028.330512] CPU6 is up
2024-06-04T21:26:27.928213+02:00 rh032 kernel: [ 1028.330533] smpboot: Booting Node 0 Processor 7 APIC 0x17
2024-06-04T21:26:27.928213+02:00 rh032 kernel: [ 1028.340051] ACPI: \_PR_.P008: Found 2 idle states
2024-06-04T21:26:27.928214+02:00 rh032 kernel: [ 1028.340308] CPU7 is up
2024-06-04T21:26:27.928215+02:00 rh032 kernel: [ 1028.350321] ACPI: PM: Waking up from system sleep state S3
2024-06-04T21:26:27.928216+02:00 rh032 kernel: [ 1028.350655] ACPI: EC: interrupt unblocked
2024-06-04T21:26:27.928217+02:00 rh032 kernel: [ 1028.364563] ACPI: EC: event unblocked
2024-06-04T21:26:27.928218+02:00 rh032 kernel: [ 1028.392532] xhci_hcd 0000:04:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-04T21:26:27.928219+02:00 rh032 kernel: [ 1028.392537] usb usb5: root hub lost power or was reset
2024-06-04T21:26:27.928220+02:00 rh032 kernel: [ 1028.392539] usb usb7: root hub lost power or was reset
2024-06-04T21:26:27.928221+02:00 rh032 kernel: [ 1028.392707] xhci_hcd 0000:0b:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-04T21:26:27.928222+02:00 rh032 kernel: [ 1028.392709] xhci_hcd 0000:0c:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-04T21:26:27.928223+02:00 rh032 kernel: [ 1028.392713] usb usb8: root hub lost power or was reset
2024-06-04T21:26:27.928224+02:00 rh032 kernel: [ 1028.392714] usb usb11: root hub lost power or was reset
2024-06-04T21:26:27.928225+02:00 rh032 kernel: [ 1028.392715] usb usb10: root hub lost power or was reset
2024-06-04T21:26:27.928226+02:00 rh032 kernel: [ 1028.392716] usb usb13: root hub lost power or was reset
2024-06-04T21:26:27.928227+02:00 rh032 kernel: [ 1028.474284] nvme nvme0: 8/0/0 default/read/poll queues
2024-06-04T21:26:27.928229+02:00 rh032 kernel: [ 1028.476510] [drm] PCIE GART of 256M enabled (table at 0x000000F400300000).
2024-06-04T21:26:27.928230+02:00 rh032 kernel: [ 1028.643100] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-04T21:26:27.928232+02:00 rh032 kernel: [ 1028.798507] [drm] UVD and UVD ENC initialized successfully.
2024-06-04T21:26:27.928233+02:00 rh032 kernel: [ 1028.908474] [drm] VCE initialized successfully.
2024-06-04T21:26:27.928233+02:00 rh032 kernel: [ 1029.039866] ata8: SATA link down (SStatus 0 SControl 300)
2024-06-04T21:26:27.928234+02:00 rh032 kernel: [ 1029.039899] ata7: SATA link down (SStatus 0 SControl 300)
2024-06-04T21:26:27.928235+02:00 rh032 kernel: [ 1029.039926] ata6: SATA link down (SStatus 0 SControl 300)
2024-06-04T21:26:27.928236+02:00 rh032 kernel: [ 1029.039959] ata5: SATA link down (SStatus 0 SControl 300)
2024-06-04T21:26:27.928236+02:00 rh032 kernel: [ 1029.198131] OOM killer enabled.
2024-06-04T21:26:27.930115+02:00 rh032 kernel: [ 1029.198133] Restarting tasks ... done.
2024-06-04T21:26:27.930124+02:00 rh032 kernel: [ 1029.199394] random: crng reseeded on system resumption
2024-06-04T21:26:27.930125+02:00 rh032 kernel: [ 1029.199398] PM: suspend exit
2024-06-04T21:26:27.930126+02:00 rh032 kernel: [ 1029.200287] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-04T21:26:27.931676+02:00 rh032 NetworkManager[2048]: <info>  [1717529187.9316] manager: sleep: wake requested (sleeping: yes  enabled: yes)
2024-06-04T21:26:27.934108+02:00 rh032 kernel: [ 1029.201318] ata1.00: supports DRM functions and may not be fully accessible
2024-06-04T21:26:27.934133+02:00 rh032 kernel: [ 1029.202759] sd 0:0:0:0: [sda] Starting disk
2024-06-04T21:26:27.934135+02:00 rh032 kernel: [ 1029.202871] ata1.00: supports DRM functions and may not be fully accessible
2024-06-04T21:26:27.934136+02:00 rh032 kernel: [ 1029.203763] ata1.00: configured for UDMA/133
2024-06-04T21:26:29.133142+02:00 rh032 ntpd[2137]: IO: Deleting interface #3 eth0, 10.6.6.32#123, interface stats: received=302, sent=302, dropped=0, active_time=999 secs
2024-06-04T21:26:29.133174+02:00 rh032 ntpd[2137]: PROTO: 116.202.171.176 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133178+02:00 rh032 ntpd[2137]: PROTO: 131.188.3.221 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133183+02:00 rh032 ntpd[2137]: PROTO: 217.91.44.17 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133187+02:00 rh032 ntpd[2137]: PROTO: 185.232.69.65 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133196+02:00 rh032 ntpd[2137]: PROTO: 144.76.76.107 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133211+02:00 rh032 ntpd[2137]: PROTO: 213.209.109.45 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133216+02:00 rh032 ntpd[2137]: PROTO: 78.46.238.113 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133220+02:00 rh032 ntpd[2137]: PROTO: 178.254.21.34 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133224+02:00 rh032 ntpd[2137]: PROTO: 212.132.75.208 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133228+02:00 rh032 ntpd[2137]: PROTO: 131.188.3.220 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133251+02:00 rh032 ntpd[2137]: PROTO: 212.18.3.19 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:26:29.133278+02:00 rh032 ntpd[2137]: IO: Deleting interface #5 eth0, fe80::4216:7eff:fe28:8ff6%2#123, interface stats: received=0, sent=0, dropped=0, active_time=999 secs

    Immediate wakeup

2024-06-04T21:26:30.223639+02:00 rh032 NetworkManager[2048]: <info>  [1717529190.2236] device (eth0): carrier: link connected
2024-06-04T21:26:30.226153+02:00 rh032 kernel: [ 1031.493493] r8169 0000:0a:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx
2024-06-04T21:26:32.133116+02:00 rh032 ntpd[2137]: IO: Listen normally on 6 eth0 10.6.6.32:123
2024-06-04T21:26:32.133168+02:00 rh032 ntpd[2137]: IO: Listen normally on 7 eth0 [fe80::4216:7eff:fe28:8ff6%2]:123
2024-06-04T21:26:32.133301+02:00 rh032 ntpd[2137]: IO: new interface(s) found: waking up resolver
2024-06-04T21:26:32.166064+02:00 rh032 kernel: [ 1033.432315] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
2024-06-04T21:26:32.170067+02:00 rh032 kernel: [ 1033.436338] sd 3:0:0:0: [sdb] Starting disk
2024-06-04T21:26:32.170073+02:00 rh032 kernel: [ 1033.437724] ata4.00: configured for UDMA/133
2024-06-04T21:27:14.335046+02:00 rh032 x-session-manager[2295]: WARNING: t+1029,30144s: Detected that screensaver has left the bus
2024-06-04T21:29:40.132991+02:00 rh032 ntpd[2137]: PROTO: 131.188.3.220 unlink local addr 10.6.6.32 -> <null>
2024-06-04T21:30:53.125752+02:00 rh032 dbus-daemon[4842]: [session uid=0 pid=4840] Activating service name='ca.desrt.dconf' requested by ':1.0' (uid=0 pid=4824 comm="/usr/bin/gnome-system-log")
2024-06-04T21:30:53.130272+02:00 rh032 dbus-daemon[4842]: [session uid=0 pid=4840] Successfully activated service 'ca.desrt.dconf'

Summary: works on USB2 despite the message "xhci_hcd 0000:04:00.0: xHC error in resume, USBSTS 0x401, Reinit"

Messages when keyboard connected to USB3 port, suspend works. Delayed wakeup:

2024-06-05T11:56:47.685375+02:00 rh032 x-session-manager[2267]: WARNING: t+1,51267s: Detected that screensaver has appeared on the bus
2024-06-05T11:56:47.842752+02:00 rh032 NetworkManager[2044]: <info>  [1717581407.8427] agent-manager: agent[b2d4b5c7c59ea262,:1.37/org.freedesktop.nm-applet/1000]: agent registered
2024-06-05T11:57:17.702302+02:00 rh032 x-session-manager[2267]: WARNING: t+31,52960s: Detected that screensaver has left the bus
2024-06-05T12:01:47.309096+02:00 rh032 dbus-daemon[2306]: [session uid=1000 pid=2304] Activating service name='org.cinnamon.ScreenSaver' requested by ':1.44' (uid=1000 pid=2849 comm="/usr/bin/python3 /usr/bin/cinnamon-screensaver-com")
2024-06-05T12:01:47.494988+02:00 rh032 dbus-daemon[2306]: [session uid=1000 pid=2304] Successfully activated service 'org.cinnamon.ScreenSaver'
2024-06-05T12:01:47.567878+02:00 rh032 x-session-manager[2267]: WARNING: t+301,39521s: Detected that screensaver has appeared on the bus
2024-06-05T12:06:47.375819+02:00 rh032 NetworkManager[2044]: <info>  [1717582007.3758] manager: sleep: sleep requested (sleeping: no  enabled: yes)
2024-06-05T12:06:47.602071+02:00 rh032 kernel: [  640.994541] PM: suspend entry (deep)
2024-06-05T12:06:47.602079+02:00 rh032 kernel: [  640.996502] Filesystems sync: 0.001 seconds

    Wakeup from suspend

2024-06-05T12:23:08.518005+02:00 rh032 kernel: [  641.000052] Freezing user space processes
2024-06-05T12:23:08.518078+02:00 rh032 kernel: [  641.001484] Freezing user space processes completed (elapsed 0.001 seconds)
2024-06-05T12:23:08.518080+02:00 rh032 kernel: [  641.001488] OOM killer disabled.
2024-06-05T12:23:08.518081+02:00 rh032 kernel: [  641.001489] Freezing remaining freezable tasks
2024-06-05T12:23:08.518082+02:00 rh032 kernel: [  641.002609] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
2024-06-05T12:23:08.518083+02:00 rh032 kernel: [  641.002623] printk: Suspending console(s) (use no_console_suspend to debug)
2024-06-05T12:23:08.518086+02:00 rh032 kernel: [  641.293654] sd 0:0:0:0: [sda] Synchronizing SCSI cache
2024-06-05T12:23:08.518087+02:00 rh032 kernel: [  641.293667] sd 3:0:0:0: [sdb] Synchronizing SCSI cache
2024-06-05T12:23:08.518088+02:00 rh032 kernel: [  641.294674] ata4.00: Entering standby power mode
2024-06-05T12:23:08.518089+02:00 rh032 kernel: [  641.297806] ata1.00: Entering standby power mode
2024-06-05T12:23:08.518089+02:00 rh032 kernel: [  641.326527] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-05T12:23:08.518090+02:00 rh032 kernel: [  641.873642] ACPI: EC: interrupt blocked
2024-06-05T12:23:08.518092+02:00 rh032 kernel: [  641.885627] amdgpu 0000:01:00.0: amdgpu: PCI CONFIG reset
2024-06-05T12:23:08.518094+02:00 rh032 kernel: [  641.910515] ACPI: PM: Preparing to enter system sleep state S3
2024-06-05T12:23:08.518095+02:00 rh032 kernel: [  641.910578] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
2024-06-05T12:23:08.518096+02:00 rh032 kernel: [  641.910938] ACPI: EC: event blocked
2024-06-05T12:23:08.518098+02:00 rh032 kernel: [  641.910939] ACPI: EC: EC stopped
2024-06-05T12:23:08.518099+02:00 rh032 kernel: [  641.910939] ACPI: PM: Saving platform NVS memory
2024-06-05T12:23:08.518100+02:00 rh032 kernel: [  641.911148] Disabling non-boot CPUs ...
2024-06-05T12:23:08.518101+02:00 rh032 kernel: [  641.913243] smpboot: CPU 1 is now offline
2024-06-05T12:23:08.518102+02:00 rh032 kernel: [  641.915066] smpboot: CPU 2 is now offline
2024-06-05T12:23:08.518103+02:00 rh032 kernel: [  641.917130] smpboot: CPU 3 is now offline
2024-06-05T12:23:08.518104+02:00 rh032 kernel: [  641.918681] smpboot: CPU 4 is now offline
2024-06-05T12:23:08.518104+02:00 rh032 kernel: [  641.920728] smpboot: CPU 5 is now offline
2024-06-05T12:23:08.518105+02:00 rh032 kernel: [  641.922943] smpboot: CPU 6 is now offline
2024-06-05T12:23:08.518106+02:00 rh032 kernel: [  641.924525] smpboot: CPU 7 is now offline
2024-06-05T12:23:08.518107+02:00 rh032 kernel: [  641.930497] ACPI: PM: Low-level resume complete
2024-06-05T12:23:08.518108+02:00 rh032 kernel: [  641.930514] ACPI: EC: EC started
2024-06-05T12:23:08.518109+02:00 rh032 kernel: [  641.930515] ACPI: PM: Restoring platform NVS memory
2024-06-05T12:23:08.518110+02:00 rh032 kernel: [  641.930688] PCI-DMA: Resuming GART IOMMU
2024-06-05T12:23:08.518111+02:00 rh032 kernel: [  641.930689] PCI-DMA: Restoring GART aperture settings
2024-06-05T12:23:08.518112+02:00 rh032 kernel: [  641.930695] LVT offset 0 assigned for vector 0x400
2024-06-05T12:23:08.518113+02:00 rh032 kernel: [  641.935978] Enabling non-boot CPUs ...
2024-06-05T12:23:08.518114+02:00 rh032 kernel: [  641.936012] smpboot: Booting Node 0 Processor 1 APIC 0x11
2024-06-05T12:23:08.518115+02:00 rh032 kernel: [  641.944093] ACPI: \_PR_.P002: Found 2 idle states
2024-06-05T12:23:08.518116+02:00 rh032 kernel: [  641.944295] CPU1 is up
2024-06-05T12:23:08.518117+02:00 rh032 kernel: [  641.944319] smpboot: Booting Node 0 Processor 2 APIC 0x12
2024-06-05T12:23:08.518118+02:00 rh032 kernel: [  641.953608] ACPI: \_PR_.P003: Found 2 idle states
2024-06-05T12:23:08.518119+02:00 rh032 kernel: [  641.953740] CPU2 is up
2024-06-05T12:23:08.518121+02:00 rh032 kernel: [  641.953761] smpboot: Booting Node 0 Processor 3 APIC 0x13
2024-06-05T12:23:08.518122+02:00 rh032 kernel: [  641.963268] ACPI: \_PR_.P004: Found 2 idle states
2024-06-05T12:23:08.518123+02:00 rh032 kernel: [  641.963435] CPU3 is up
2024-06-05T12:23:08.518124+02:00 rh032 kernel: [  641.963460] smpboot: Booting Node 0 Processor 4 APIC 0x14
2024-06-05T12:23:08.518125+02:00 rh032 kernel: [  641.972898] ACPI: \_PR_.P005: Found 2 idle states
2024-06-05T12:23:08.518126+02:00 rh032 kernel: [  641.973073] CPU4 is up
2024-06-05T12:23:08.518127+02:00 rh032 kernel: [  641.973097] smpboot: Booting Node 0 Processor 5 APIC 0x15
2024-06-05T12:23:08.518128+02:00 rh032 kernel: [  641.981861] ACPI: \_PR_.P006: Found 2 idle states
2024-06-05T12:23:08.518129+02:00 rh032 kernel: [  641.982070] CPU5 is up
2024-06-05T12:23:08.518130+02:00 rh032 kernel: [  641.982091] smpboot: Booting Node 0 Processor 6 APIC 0x16
2024-06-05T12:23:08.518131+02:00 rh032 kernel: [  641.991471] ACPI: \_PR_.P007: Found 2 idle states
2024-06-05T12:23:08.518133+02:00 rh032 kernel: [  641.991700] CPU6 is up
2024-06-05T12:23:08.518134+02:00 rh032 kernel: [  641.991721] smpboot: Booting Node 0 Processor 7 APIC 0x17
2024-06-05T12:23:08.518135+02:00 rh032 kernel: [  642.001117] ACPI: \_PR_.P008: Found 2 idle states
2024-06-05T12:23:08.518136+02:00 rh032 kernel: [  642.001355] CPU7 is up
2024-06-05T12:23:08.518137+02:00 rh032 kernel: [  642.011286] ACPI: PM: Waking up from system sleep state S3
2024-06-05T12:23:08.518138+02:00 rh032 kernel: [  642.011607] ACPI: EC: interrupt unblocked
2024-06-05T12:23:08.518139+02:00 rh032 kernel: [  642.024915] ACPI: EC: event unblocked
2024-06-05T12:23:08.518140+02:00 rh032 kernel: [  642.051800] xhci_hcd 0000:04:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T12:23:08.518141+02:00 rh032 kernel: [  642.051805] usb usb5: root hub lost power or was reset
2024-06-05T12:23:08.518143+02:00 rh032 kernel: [  642.051807] usb usb7: root hub lost power or was reset
2024-06-05T12:23:08.518144+02:00 rh032 kernel: [  642.051931] xhci_hcd 0000:0b:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T12:23:08.518145+02:00 rh032 kernel: [  642.051937] usb usb8: root hub lost power or was reset
2024-06-05T12:23:08.518146+02:00 rh032 kernel: [  642.051939] usb usb10: root hub lost power or was reset
2024-06-05T12:23:08.518147+02:00 rh032 kernel: [  642.051955] xhci_hcd 0000:0c:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T12:23:08.518149+02:00 rh032 kernel: [  642.051960] usb usb11: root hub lost power or was reset
2024-06-05T12:23:08.518150+02:00 rh032 kernel: [  642.051962] usb usb13: root hub lost power or was reset
2024-06-05T12:23:08.518150+02:00 rh032 kernel: [  642.132937] nvme nvme0: 8/0/0 default/read/poll queues
2024-06-05T12:23:08.518152+02:00 rh032 kernel: [  642.135739] [drm] PCIE GART of 256M enabled (table at 0x000000F400300000).
2024-06-05T12:23:08.518153+02:00 rh032 kernel: [  642.290327] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-05T12:23:08.518154+02:00 rh032 kernel: [  642.457731] [drm] UVD and UVD ENC initialized successfully.
2024-06-05T12:23:08.518155+02:00 rh032 kernel: [  642.567697] [drm] VCE initialized successfully.
2024-06-05T12:23:08.518156+02:00 rh032 kernel: [  643.059206] ata5: SATA link down (SStatus 0 SControl 300)
2024-06-05T12:23:08.518158+02:00 rh032 kernel: [  643.059211] ata8: SATA link down (SStatus 0 SControl 300)
2024-06-05T12:23:08.518158+02:00 rh032 kernel: [  643.059244] ata6: SATA link down (SStatus 0 SControl 300)
2024-06-05T12:23:08.518159+02:00 rh032 kernel: [  643.059251] ata7: SATA link down (SStatus 0 SControl 300)
2024-06-05T12:23:08.518161+02:00 rh032 kernel: [  643.107837] usb 5-1: reset low-speed USB device number 2 using xhci_hcd
2024-06-05T12:23:08.518162+02:00 rh032 kernel: [  643.219603] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-05T12:23:08.518163+02:00 rh032 kernel: [  643.220443] ata1.00: supports DRM functions and may not be fully accessible
2024-06-05T12:23:08.518164+02:00 rh032 kernel: [  643.221211] sd 0:0:0:0: [sda] Starting disk
2024-06-05T12:23:08.518165+02:00 rh032 kernel: [  643.221299] ata1.00: supports DRM functions and may not be fully accessible
2024-06-05T12:23:08.518166+02:00 rh032 kernel: [  643.222005] ata1.00: configured for UDMA/133
2024-06-05T12:23:08.518167+02:00 rh032 kernel: [  643.448725] OOM killer enabled.
2024-06-05T12:23:08.520751+02:00 rh032 kernel: [  643.448727] Restarting tasks ... done.
2024-06-05T12:23:08.520763+02:00 rh032 kernel: [  643.449825] random: crng reseeded on system resumption
2024-06-05T12:23:08.520764+02:00 rh032 kernel: [  643.449830] PM: suspend exit
2024-06-05T12:23:08.521005+02:00 rh032 NetworkManager[2044]: <info>  [1717582988.5209] manager: sleep: wake requested (sleeping: yes  enabled: yes)
2024-06-05T12:23:10.198643+02:00 rh032 NetworkManager[2044]: <info>  [1717582990.1986] device (eth0): carrier: link connected
2024-06-05T12:23:10.200707+02:00 rh032 kernel: [  645.129183] r8169 0000:0a:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx
2024-06-05T12:23:12.192737+02:00 rh032 kernel: [  647.119510] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
2024-06-05T12:23:12.192769+02:00 rh032 kernel: [  647.120850] sd 3:0:0:0: [sdb] Starting disk
2024-06-05T12:23:12.192771+02:00 rh032 kernel: [  647.122245] ata4.00: configured for UDMA/133

Messages when keyboard connected to USB3 port, suspend works, long sleep & hibernate? No complete wakeup, keyboard is locked up. Power LED was blinking, came on on keystroke, monitor remains in power save, Numlock doesn't work:

2024-06-05T12:23:50.222261+02:00 rh032 x-session-manager[2267]: WARNING: t+645,57905s: Detected that screensaver has left the bus
2024-06-05T12:30:55.229417+02:00 rh032 dbus-daemon[2306]: [session uid=1000 pid=2304] Activating service name='org.cinnamon.ScreenSaver' requested by ':1.52' (uid=1000 pid=3275 comm="/usr/bin/python3 /usr/bin/cinnamon-screensaver-com")
2024-06-05T12:30:55.415393+02:00 rh032 dbus-daemon[2306]: [session uid=1000 pid=2304] Successfully activated service 'org.cinnamon.ScreenSaver'
2024-06-05T12:30:55.487926+02:00 rh032 x-session-manager[2267]: WARNING: t+1070,84473s: Detected that screensaver has appeared on the bus
2024-06-05T12:35:27.546461+02:00 rh032 ntpd[2133]: CLOCK: time stepped by 0.187459
2024-06-05T12:35:27.546509+02:00 rh032 ntpd[2133]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes
2024-06-05T12:35:55.481701+02:00 rh032 NetworkManager[2044]: <info>  [1717583755.4816] manager: sleep: sleep requested (sleeping: no  enabled: yes)

    Reboot on hard reset

2024-06-05T14:02:51.579576+02:00 rh032 kernel: [    0.000000] Linux version 6.7.12+bpo-amd64 (debian-kernel@lists.debian.org) (x86_64-linux-gnu-gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC Debian 6.7.12-1~bpo12+1 (2024-05-06)
2024-06-05T14:02:51.579655+02:00 rh032 kernel: [    0.000000] Command line: BOOT_IMAGE=/vmlinuz-6.7.12+bpo-amd64 root=/dev/mapper/vgDsys-1_root ro quiet

Messages when keyboard connected to USB2 port, suspend works. Short term wakeup plus waiting for hibernation: works fine.

2024-06-05T14:17:00.018368+02:00 rh032 dbus-daemon[2314]: [session uid=1000 pid=2312] Successfully activated service 'org.cinnamon.ScreenSaver'
2024-06-05T14:17:00.090775+02:00 rh032 x-session-manager[2275]: WARNING: t+306,71767s: Detected that screensaver has appeared on the bus
2024-06-05T14:17:01.456076+02:00 rh032 CRON[2898]: (root) CMD (cd / && run-parts --report /etc/cron.hourly)

    Suspend started

2024-06-05T14:21:59.880310+02:00 rh032 NetworkManager[2047]: <info>  [1717590119.8802] manager: sleep: sleep requested (sleeping: no  enabled: yes)
2024-06-05T14:22:00.114719+02:00 rh032 kernel: [  671.385115] PM: suspend entry (deep)
2024-06-05T14:22:00.114726+02:00 rh032 kernel: [  671.387362] Filesystems sync: 0.002 seconds

    Wake up short after Suspending

2024-06-05T14:35:06.918374+02:00 rh032 kernel: [  671.392365] Freezing user space processes
2024-06-05T14:35:06.918441+02:00 rh032 kernel: [  671.393831] Freezing user space processes completed (elapsed 0.001 seconds)
2024-06-05T14:35:06.918443+02:00 rh032 kernel: [  671.393836] OOM killer disabled.
2024-06-05T14:35:06.918444+02:00 rh032 kernel: [  671.393837] Freezing remaining freezable tasks
2024-06-05T14:35:06.918447+02:00 rh032 kernel: [  671.394957] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
2024-06-05T14:35:06.918448+02:00 rh032 kernel: [  671.394971] printk: Suspending console(s) (use no_console_suspend to debug)
2024-06-05T14:35:06.918449+02:00 rh032 kernel: [  671.687438] sd 3:0:0:0: [sdb] Synchronizing SCSI cache
2024-06-05T14:35:06.918450+02:00 rh032 kernel: [  671.691547] sd 0:0:0:0: [sda] Synchronizing SCSI cache
2024-06-05T14:35:06.918451+02:00 rh032 kernel: [  671.703884] ata1.00: Entering standby power mode
2024-06-05T14:35:06.918453+02:00 rh032 kernel: [  671.703902] ata4.00: Entering standby power mode
2024-06-05T14:35:06.918454+02:00 rh032 kernel: [  671.731683] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-05T14:35:06.918456+02:00 rh032 kernel: [  672.288330] ACPI: EC: interrupt blocked
2024-06-05T14:35:06.918457+02:00 rh032 kernel: [  672.300400] amdgpu 0000:01:00.0: amdgpu: PCI CONFIG reset
2024-06-05T14:35:06.918458+02:00 rh032 kernel: [  672.324884] ACPI: PM: Preparing to enter system sleep state S3
2024-06-05T14:35:06.918459+02:00 rh032 kernel: [  672.324965] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
2024-06-05T14:35:06.918461+02:00 rh032 kernel: [  672.325327] ACPI: EC: event blocked
2024-06-05T14:35:06.918462+02:00 rh032 kernel: [  672.325328] ACPI: EC: EC stopped
2024-06-05T14:35:06.918463+02:00 rh032 kernel: [  672.325329] ACPI: PM: Saving platform NVS memory
2024-06-05T14:35:06.918643+02:00 rh032 kernel: [  672.325549] Disabling non-boot CPUs ...
2024-06-05T14:35:06.918649+02:00 rh032 kernel: [  672.327696] smpboot: CPU 1 is now offline
2024-06-05T14:35:06.918650+02:00 rh032 kernel: [  672.329445] smpboot: CPU 2 is now offline
2024-06-05T14:35:06.918652+02:00 rh032 kernel: [  672.331519] smpboot: CPU 3 is now offline
2024-06-05T14:35:06.918653+02:00 rh032 kernel: [  672.333098] smpboot: CPU 4 is now offline
2024-06-05T14:35:06.918654+02:00 rh032 kernel: [  672.335135] smpboot: CPU 5 is now offline
2024-06-05T14:35:06.918655+02:00 rh032 kernel: [  672.336855] smpboot: CPU 6 is now offline
2024-06-05T14:35:06.918657+02:00 rh032 kernel: [  672.338824] smpboot: CPU 7 is now offline
2024-06-05T14:35:06.918659+02:00 rh032 kernel: [  672.344695] ACPI: PM: Low-level resume complete
2024-06-05T14:35:06.918660+02:00 rh032 kernel: [  672.344712] ACPI: EC: EC started
2024-06-05T14:35:06.918661+02:00 rh032 kernel: [  672.344713] ACPI: PM: Restoring platform NVS memory
2024-06-05T14:35:06.918663+02:00 rh032 kernel: [  672.344897] PCI-DMA: Resuming GART IOMMU
2024-06-05T14:35:06.918664+02:00 rh032 kernel: [  672.344898] PCI-DMA: Restoring GART aperture settings
2024-06-05T14:35:06.918665+02:00 rh032 kernel: [  672.344904] LVT offset 0 assigned for vector 0x400
2024-06-05T14:35:06.918666+02:00 rh032 kernel: [  672.350194] Enabling non-boot CPUs ...
2024-06-05T14:35:06.918667+02:00 rh032 kernel: [  672.350228] smpboot: Booting Node 0 Processor 1 APIC 0x11
2024-06-05T14:35:06.918668+02:00 rh032 kernel: [  672.358245] ACPI: \_PR_.P002: Found 2 idle states
2024-06-05T14:35:06.918670+02:00 rh032 kernel: [  672.358452] CPU1 is up
2024-06-05T14:35:06.918672+02:00 rh032 kernel: [  672.358476] smpboot: Booting Node 0 Processor 2 APIC 0x12
2024-06-05T14:35:06.918672+02:00 rh032 kernel: [  672.367659] ACPI: \_PR_.P003: Found 2 idle states
2024-06-05T14:35:06.918749+02:00 rh032 kernel: [  672.367793] CPU2 is up
2024-06-05T14:35:06.918750+02:00 rh032 kernel: [  672.367814] smpboot: Booting Node 0 Processor 3 APIC 0x13
2024-06-05T14:35:06.918751+02:00 rh032 kernel: [  672.377320] ACPI: \_PR_.P004: Found 2 idle states
2024-06-05T14:35:06.918752+02:00 rh032 kernel: [  672.377492] CPU3 is up
2024-06-05T14:35:06.918752+02:00 rh032 kernel: [  672.377517] smpboot: Booting Node 0 Processor 4 APIC 0x14
2024-06-05T14:35:06.918753+02:00 rh032 kernel: [  672.386999] ACPI: \_PR_.P005: Found 2 idle states
2024-06-05T14:35:06.918754+02:00 rh032 kernel: [  672.387182] CPU4 is up
2024-06-05T14:35:06.918754+02:00 rh032 kernel: [  672.387211] smpboot: Booting Node 0 Processor 5 APIC 0x15
2024-06-05T14:35:06.918755+02:00 rh032 kernel: [  672.395814] ACPI: \_PR_.P006: Found 2 idle states
2024-06-05T14:35:06.918756+02:00 rh032 kernel: [  672.396021] CPU5 is up
2024-06-05T14:35:06.918757+02:00 rh032 kernel: [  672.396045] smpboot: Booting Node 0 Processor 6 APIC 0x16
2024-06-05T14:35:06.918757+02:00 rh032 kernel: [  672.405569] ACPI: \_PR_.P007: Found 2 idle states
2024-06-05T14:35:06.918758+02:00 rh032 kernel: [  672.405793] CPU6 is up
2024-06-05T14:35:06.918759+02:00 rh032 kernel: [  672.405827] smpboot: Booting Node 0 Processor 7 APIC 0x17
2024-06-05T14:35:06.918760+02:00 rh032 kernel: [  672.415278] ACPI: \_PR_.P008: Found 2 idle states
2024-06-05T14:35:06.918760+02:00 rh032 kernel: [  672.415523] CPU7 is up
2024-06-05T14:35:06.918761+02:00 rh032 kernel: [  672.425492] ACPI: PM: Waking up from system sleep state S3
2024-06-05T14:35:06.918762+02:00 rh032 kernel: [  672.425807] ACPI: EC: interrupt unblocked
2024-06-05T14:35:06.918762+02:00 rh032 kernel: [  672.439073] ACPI: EC: event unblocked
2024-06-05T14:35:06.918763+02:00 rh032 kernel: [  672.465719] xhci_hcd 0000:04:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T14:35:06.918764+02:00 rh032 kernel: [  672.465724] usb usb5: root hub lost power or was reset
2024-06-05T14:35:06.918765+02:00 rh032 kernel: [  672.465725] usb usb7: root hub lost power or was reset
2024-06-05T14:35:06.918766+02:00 rh032 kernel: [  672.465803] xhci_hcd 0000:0b:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T14:35:06.918766+02:00 rh032 kernel: [  672.465808] usb usb8: root hub lost power or was reset
2024-06-05T14:35:06.918767+02:00 rh032 kernel: [  672.465810] usb usb10: root hub lost power or was reset
2024-06-05T14:35:06.918768+02:00 rh032 kernel: [  672.465862] xhci_hcd 0000:0c:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T14:35:06.918769+02:00 rh032 kernel: [  672.465867] usb usb11: root hub lost power or was reset
2024-06-05T14:35:06.918769+02:00 rh032 kernel: [  672.465869] usb usb13: root hub lost power or was reset
2024-06-05T14:35:06.918770+02:00 rh032 kernel: [  672.546280] nvme nvme0: 8/0/0 default/read/poll queues
2024-06-05T14:35:06.918771+02:00 rh032 kernel: [  672.549647] [drm] PCIE GART of 256M enabled (table at 0x000000F400300000).
2024-06-05T14:35:06.918772+02:00 rh032 kernel: [  672.708485] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-05T14:35:06.918773+02:00 rh032 kernel: [  672.871644] [drm] UVD and UVD ENC initialized successfully.
2024-06-05T14:35:06.918774+02:00 rh032 kernel: [  672.981604] [drm] VCE initialized successfully.
2024-06-05T14:35:06.918775+02:00 rh032 kernel: [  673.180963] ata5: SATA link down (SStatus 0 SControl 300)
2024-06-05T14:35:06.918776+02:00 rh032 kernel: [  673.181098] ata7: SATA link down (SStatus 0 SControl 300)
2024-06-05T14:35:06.918778+02:00 rh032 kernel: [  673.181099] ata6: SATA link down (SStatus 0 SControl 300)
2024-06-05T14:35:06.918779+02:00 rh032 kernel: [  673.181129] ata8: SATA link down (SStatus 0 SControl 300)
2024-06-05T14:35:06.918780+02:00 rh032 kernel: [  673.263331] OOM killer enabled.
2024-06-05T14:35:06.920388+02:00 rh032 kernel: [  673.263333] Restarting tasks ... done.
2024-06-05T14:35:06.920396+02:00 rh032 kernel: [  673.264069] random: crng reseeded on system resumption
2024-06-05T14:35:06.920397+02:00 rh032 kernel: [  673.264073] PM: suspend exit
2024-06-05T14:35:06.920824+02:00 rh032 NetworkManager[2047]: <info>  [1717590906.9208] manager: sleep: wake requested (sleeping: yes  enabled: yes)
2024-06-05T14:35:06.924319+02:00 rh032 kernel: [  673.265543] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-05T14:35:06.924335+02:00 rh032 kernel: [  673.266518] ata1.00: supports DRM functions and may not be fully accessible
2024-06-05T14:35:06.924336+02:00 rh032 kernel: [  673.268134] sd 0:0:0:0: [sda] Starting disk
2024-06-05T14:35:06.924337+02:00 rh032 kernel: [  673.268236] ata1.00: supports DRM functions and may not be fully accessible
2024-06-05T14:35:06.924338+02:00 rh032 kernel: [  673.268963] ata1.00: configured for UDMA/133
2024-06-05T14:35:08.578196+02:00 rh032 ntpd[2137]: IO: Deleting interface #3 eth0, 10.6.6.32#123, interface stats: received=238, sent=240, dropped=0, active_time=630 secs
2024-06-05T14:35:08.578221+02:00 rh032 ntpd[2137]: PROTO: 131.188.3.223 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578226+02:00 rh032 ntpd[2137]: PROTO: 82.165.53.158 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578230+02:00 rh032 ntpd[2137]: PROTO: 62.113.219.231 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578235+02:00 rh032 ntpd[2137]: PROTO: 46.4.104.234 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578239+02:00 rh032 ntpd[2137]: PROTO: 213.239.234.28 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578243+02:00 rh032 ntpd[2137]: PROTO: 51.75.67.47 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578250+02:00 rh032 ntpd[2137]: PROTO: 88.198.200.96 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578254+02:00 rh032 ntpd[2137]: PROTO: 185.13.148.71 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578258+02:00 rh032 ntpd[2137]: PROTO: 5.45.111.220 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578262+02:00 rh032 ntpd[2137]: PROTO: 185.248.188.98 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578272+02:00 rh032 ntpd[2137]: PROTO: 129.250.35.251 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578305+02:00 rh032 ntpd[2137]: PROTO: 80.153.195.191 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578317+02:00 rh032 ntpd[2137]: PROTO: 78.46.102.180 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578321+02:00 rh032 ntpd[2137]: PROTO: 178.215.228.24 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578325+02:00 rh032 ntpd[2137]: PROTO: 161.97.164.152 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578329+02:00 rh032 ntpd[2137]: PROTO: 80.86.84.49 unlink local addr 10.6.6.32 -> <null>
2024-06-05T14:35:08.578337+02:00 rh032 ntpd[2137]: IO: Deleting interface #5 eth0, fe80::4216:7eff:fe28:8ff6%2#123, interface stats: received=0, sent=0, dropped=0, active_time=630 secs
2024-06-05T14:35:09.195447+02:00 rh032 NetworkManager[2047]: <info>  [1717590909.1954] device (eth0): carrier: link connected
2024-06-05T14:35:09.196363+02:00 rh032 kernel: [  675.540215] r8169 0000:0a:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx
2024-06-05T14:35:10.578120+02:00 rh032 ntpd[2137]: IO: Listen normally on 6 eth0 10.6.6.32:123
2024-06-05T14:35:10.578165+02:00 rh032 ntpd[2137]: IO: Listen normally on 7 eth0 [fe80::4216:7eff:fe28:8ff6%2]:123
2024-06-05T14:35:10.578352+02:00 rh032 ntpd[2137]: IO: new interface(s) found: waking up resolver
2024-06-05T14:35:11.136439+02:00 rh032 kernel: [  677.477466] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
2024-06-05T14:35:11.136462+02:00 rh032 kernel: [  677.478815] sd 3:0:0:0: [sdb] Starting disk
2024-06-05T14:35:11.136464+02:00 rh032 kernel: [  677.480141] ata4.00: configured for UDMA/133
2024-06-05T14:41:38.951185+02:00 rh032 ntpd[2137]: CLOCK: time stepped by 0.357147
2024-06-05T14:41:38.951211+02:00 rh032 ntpd[2137]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes
2024-06-05T14:41:41.935239+02:00 rh032 ntpd[2137]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101
2024-06-05T14:41:41.952488+02:00 rh032 ntpd[2137]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101
2024-06-05T14:41:41.952508+02:00 rh032 ntpd[2137]: DNS: Pool taking: 185.163.116.98
2024-06-05T14:41:41.952558+02:00 rh032 ntpd[2137]: DNS: Pool taking: 49.12.125.53
2024-06-05T14:41:41.952581+02:00 rh032 ntpd[2137]: DNS: Pool taking: 79.133.44.143
2024-06-05T14:41:41.952604+02:00 rh032 ntpd[2137]: DNS: Pool taking: 144.76.76.107
2024-06-05T14:41:41.952625+02:00 rh032 ntpd[2137]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8
2024-06-05T14:45:07.470616+02:00 rh032 NetworkManager[2047]: <info>  [1717591507.4706] manager: sleep: sleep requested (sleeping: no  enabled: yes)
2024-06-05T14:45:07.709466+02:00 rh032 kernel: [ 1273.689131] PM: suspend entry (deep)
2024-06-05T14:45:07.717444+02:00 rh032 kernel: [ 1273.696510] Filesystems sync: 0.007 seconds

    Wake up from Suspend (Hibernation?)

2024-06-05T16:14:02.922701+02:00 rh032 kernel: [ 1273.699773] Freezing user space processes
2024-06-05T16:14:02.922771+02:00 rh032 kernel: [ 1273.701216] Freezing user space processes completed (elapsed 0.001 seconds)
2024-06-05T16:14:02.922775+02:00 rh032 kernel: [ 1273.701221] OOM killer disabled.
2024-06-05T16:14:02.922776+02:00 rh032 kernel: [ 1273.701222] Freezing remaining freezable tasks
2024-06-05T16:14:02.922777+02:00 rh032 kernel: [ 1273.702228] Freezing remaining freezable tasks completed (elapsed 0.001 seconds)
2024-06-05T16:14:02.922778+02:00 rh032 kernel: [ 1273.702242] printk: Suspending console(s) (use no_console_suspend to debug)
2024-06-05T16:14:02.922779+02:00 rh032 kernel: [ 1273.958347] sd 0:0:0:0: [sda] Synchronizing SCSI cache
2024-06-05T16:14:02.922780+02:00 rh032 kernel: [ 1273.958347] sd 3:0:0:0: [sdb] Synchronizing SCSI cache
2024-06-05T16:14:02.922781+02:00 rh032 kernel: [ 1274.001020] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-05T16:14:02.922782+02:00 rh032 kernel: [ 1274.353024] ata4.00: Entering standby power mode
2024-06-05T16:14:02.922783+02:00 rh032 kernel: [ 1274.353697] ata1.00: Entering standby power mode
2024-06-05T16:14:02.922785+02:00 rh032 kernel: [ 1274.938051] ACPI: EC: interrupt blocked
2024-06-05T16:14:02.922786+02:00 rh032 kernel: [ 1274.950086] amdgpu 0000:01:00.0: amdgpu: PCI CONFIG reset
2024-06-05T16:14:02.922787+02:00 rh032 kernel: [ 1274.974948] ACPI: PM: Preparing to enter system sleep state S3
2024-06-05T16:14:02.922788+02:00 rh032 kernel: [ 1274.975263] ACPI: EC: event blocked
2024-06-05T16:14:02.922790+02:00 rh032 kernel: [ 1274.975264] ACPI: EC: EC stopped
2024-06-05T16:14:02.922791+02:00 rh032 kernel: [ 1274.975265] ACPI: PM: Saving platform NVS memory
2024-06-05T16:14:02.922792+02:00 rh032 kernel: [ 1274.975508] Disabling non-boot CPUs ...
2024-06-05T16:14:02.922931+02:00 rh032 kernel: [ 1274.977619] smpboot: CPU 1 is now offline
2024-06-05T16:14:02.922940+02:00 rh032 kernel: [ 1274.979376] smpboot: CPU 2 is now offline
2024-06-05T16:14:02.922941+02:00 rh032 kernel: [ 1274.981422] smpboot: CPU 3 is now offline
2024-06-05T16:14:02.922942+02:00 rh032 kernel: [ 1274.983843] smpboot: CPU 4 is now offline
2024-06-05T16:14:02.922944+02:00 rh032 kernel: [ 1274.985549] smpboot: CPU 5 is now offline
2024-06-05T16:14:02.922945+02:00 rh032 kernel: [ 1274.987749] smpboot: CPU 6 is now offline
2024-06-05T16:14:02.922946+02:00 rh032 kernel: [ 1274.989242] smpboot: CPU 7 is now offline
2024-06-05T16:14:02.922947+02:00 rh032 kernel: [ 1274.995064] ACPI: PM: Low-level resume complete
2024-06-05T16:14:02.922949+02:00 rh032 kernel: [ 1274.995080] ACPI: EC: EC started
2024-06-05T16:14:02.922950+02:00 rh032 kernel: [ 1274.995081] ACPI: PM: Restoring platform NVS memory
2024-06-05T16:14:02.922951+02:00 rh032 kernel: [ 1274.995266] PCI-DMA: Resuming GART IOMMU
2024-06-05T16:14:02.922953+02:00 rh032 kernel: [ 1274.995267] PCI-DMA: Restoring GART aperture settings
2024-06-05T16:14:02.922954+02:00 rh032 kernel: [ 1274.995273] LVT offset 0 assigned for vector 0x400
2024-06-05T16:14:02.922955+02:00 rh032 kernel: [ 1275.000563] Enabling non-boot CPUs ...
2024-06-05T16:14:02.922957+02:00 rh032 kernel: [ 1275.000598] smpboot: Booting Node 0 Processor 1 APIC 0x11
2024-06-05T16:14:02.922958+02:00 rh032 kernel: [ 1275.008604] ACPI: \_PR_.P002: Found 2 idle states
2024-06-05T16:14:02.922959+02:00 rh032 kernel: [ 1275.008808] CPU1 is up
2024-06-05T16:14:02.922960+02:00 rh032 kernel: [ 1275.008832] smpboot: Booting Node 0 Processor 2 APIC 0x12
2024-06-05T16:14:02.922961+02:00 rh032 kernel: [ 1275.018115] ACPI: \_PR_.P003: Found 2 idle states
2024-06-05T16:14:02.922962+02:00 rh032 kernel: [ 1275.018251] CPU2 is up
2024-06-05T16:14:02.922963+02:00 rh032 kernel: [ 1275.018272] smpboot: Booting Node 0 Processor 3 APIC 0x13
2024-06-05T16:14:02.922964+02:00 rh032 kernel: [ 1275.027778] ACPI: \_PR_.P004: Found 2 idle states
2024-06-05T16:14:02.922965+02:00 rh032 kernel: [ 1275.027948] CPU3 is up
2024-06-05T16:14:02.922966+02:00 rh032 kernel: [ 1275.027973] smpboot: Booting Node 0 Processor 4 APIC 0x14
2024-06-05T16:14:02.922967+02:00 rh032 kernel: [ 1275.036515] ACPI: \_PR_.P005: Found 2 idle states
2024-06-05T16:14:02.922968+02:00 rh032 kernel: [ 1275.036688] CPU4 is up
2024-06-05T16:14:02.922969+02:00 rh032 kernel: [ 1275.036719] smpboot: Booting Node 0 Processor 5 APIC 0x15
2024-06-05T16:14:02.922970+02:00 rh032 kernel: [ 1275.046098] ACPI: \_PR_.P006: Found 2 idle states
2024-06-05T16:14:02.922972+02:00 rh032 kernel: [ 1275.046302] CPU5 is up
2024-06-05T16:14:02.922973+02:00 rh032 kernel: [ 1275.046323] smpboot: Booting Node 0 Processor 6 APIC 0x16
2024-06-05T16:14:02.922974+02:00 rh032 kernel: [ 1275.055702] ACPI: \_PR_.P007: Found 2 idle states
2024-06-05T16:14:02.922975+02:00 rh032 kernel: [ 1275.055923] CPU6 is up
2024-06-05T16:14:02.922976+02:00 rh032 kernel: [ 1275.055947] smpboot: Booting Node 0 Processor 7 APIC 0x17
2024-06-05T16:14:02.922977+02:00 rh032 kernel: [ 1275.064591] ACPI: \_PR_.P008: Found 2 idle states
2024-06-05T16:14:02.922978+02:00 rh032 kernel: [ 1275.064832] CPU7 is up
2024-06-05T16:14:02.922979+02:00 rh032 kernel: [ 1275.074825] ACPI: PM: Waking up from system sleep state S3
2024-06-05T16:14:02.922981+02:00 rh032 kernel: [ 1275.075136] ACPI: EC: interrupt unblocked
2024-06-05T16:14:02.922981+02:00 rh032 kernel: [ 1275.088689] ACPI: EC: event unblocked
2024-06-05T16:14:02.922982+02:00 rh032 kernel: [ 1275.116445] xhci_hcd 0000:04:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T16:14:02.922984+02:00 rh032 kernel: [ 1275.116460] usb usb5: root hub lost power or was reset
2024-06-05T16:14:02.922985+02:00 rh032 kernel: [ 1275.116462] usb usb7: root hub lost power or was reset
2024-06-05T16:14:02.922986+02:00 rh032 kernel: [ 1275.116553] xhci_hcd 0000:0b:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T16:14:02.922987+02:00 rh032 kernel: [ 1275.116558] usb usb8: root hub lost power or was reset
2024-06-05T16:14:02.922988+02:00 rh032 kernel: [ 1275.116560] usb usb10: root hub lost power or was reset
2024-06-05T16:14:02.922990+02:00 rh032 kernel: [ 1275.116610] xhci_hcd 0000:0c:00.0: xHC error in resume, USBSTS 0x401, Reinit
2024-06-05T16:14:02.922991+02:00 rh032 kernel: [ 1275.116623] usb usb11: root hub lost power or was reset
2024-06-05T16:14:02.922992+02:00 rh032 kernel: [ 1275.116645] usb usb13: root hub lost power or was reset
2024-06-05T16:14:02.922993+02:00 rh032 kernel: [ 1275.197247] nvme nvme0: 8/0/0 default/read/poll queues
2024-06-05T16:14:02.922994+02:00 rh032 kernel: [ 1275.200422] [drm] PCIE GART of 256M enabled (table at 0x000000F400300000).
2024-06-05T16:14:02.922995+02:00 rh032 kernel: [ 1275.363013] r8169 0000:0a:00.0 eth0: Link is Down
2024-06-05T16:14:02.923017+02:00 rh032 kernel: [ 1275.522406] [drm] UVD and UVD ENC initialized successfully.
2024-06-05T16:14:02.923019+02:00 rh032 kernel: [ 1275.632377] [drm] VCE initialized successfully.
2024-06-05T16:14:02.923020+02:00 rh032 kernel: [ 1275.835783] ata8: SATA link down (SStatus 0 SControl 300)
2024-06-05T16:14:02.923022+02:00 rh032 kernel: [ 1275.835788] ata5: SATA link down (SStatus 0 SControl 300)
2024-06-05T16:14:02.923023+02:00 rh032 kernel: [ 1275.835816] ata7: SATA link down (SStatus 0 SControl 300)
2024-06-05T16:14:02.923025+02:00 rh032 kernel: [ 1275.835838] ata6: SATA link down (SStatus 0 SControl 300)
2024-06-05T16:14:02.923026+02:00 rh032 kernel: [ 1275.918044] OOM killer enabled.
2024-06-05T16:14:02.924678+02:00 rh032 kernel: [ 1275.918046] Restarting tasks ... done.
2024-06-05T16:14:02.924686+02:00 rh032 kernel: [ 1275.918634] random: crng reseeded on system resumption
2024-06-05T16:14:02.924688+02:00 rh032 kernel: [ 1275.918638] PM: suspend exit
2024-06-05T16:14:02.925105+02:00 rh032 NetworkManager[2047]: <info>  [1717596842.9250] manager: sleep: wake requested (sleeping: yes  enabled: yes)
2024-06-05T16:14:02.928692+02:00 rh032 kernel: [ 1275.920261] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
2024-06-05T16:14:02.928702+02:00 rh032 kernel: [ 1275.920978] ata1.00: supports DRM functions and may not be fully accessible
2024-06-05T16:14:02.928703+02:00 rh032 kernel: [ 1275.921868] sd 0:0:0:0: [sda] Starting disk
2024-06-05T16:14:02.928705+02:00 rh032 kernel: [ 1275.922034] ata1.00: supports DRM functions and may not be fully accessible
2024-06-05T16:14:02.928705+02:00 rh032 kernel: [ 1275.922751] ata1.00: configured for UDMA/133
2024-06-05T16:14:04.926623+02:00 rh032 ntpd[2137]: IO: Deleting interface #6 eth0, 10.6.6.32#123, interface stats: received=228, sent=243, dropped=0, active_time=600 secs
2024-06-05T16:14:04.926649+02:00 rh032 ntpd[2137]: PROTO: 144.76.76.107 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926653+02:00 rh032 ntpd[2137]: PROTO: 79.133.44.143 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926657+02:00 rh032 ntpd[2137]: PROTO: 49.12.125.53 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926662+02:00 rh032 ntpd[2137]: PROTO: 185.163.116.98 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926666+02:00 rh032 ntpd[2137]: PROTO: 80.86.84.49 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926670+02:00 rh032 ntpd[2137]: PROTO: 161.97.164.152 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926675+02:00 rh032 ntpd[2137]: PROTO: 178.215.228.24 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926679+02:00 rh032 ntpd[2137]: PROTO: 78.46.102.180 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926683+02:00 rh032 ntpd[2137]: PROTO: 80.153.195.191 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926687+02:00 rh032 ntpd[2137]: PROTO: 129.250.35.251 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926701+02:00 rh032 ntpd[2137]: PROTO: 185.248.188.98 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926733+02:00 rh032 ntpd[2137]: PROTO: 5.45.111.220 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926739+02:00 rh032 ntpd[2137]: PROTO: 185.13.148.71 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926743+02:00 rh032 ntpd[2137]: PROTO: 88.198.200.96 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926747+02:00 rh032 ntpd[2137]: PROTO: 51.75.67.47 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926751+02:00 rh032 ntpd[2137]: PROTO: 213.239.234.28 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926755+02:00 rh032 ntpd[2137]: PROTO: 46.4.104.234 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926759+02:00 rh032 ntpd[2137]: PROTO: 62.113.219.231 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926765+02:00 rh032 ntpd[2137]: PROTO: 82.165.53.158 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926775+02:00 rh032 ntpd[2137]: PROTO: 131.188.3.223 unlink local addr 10.6.6.32 -> <null>
2024-06-05T16:14:04.926782+02:00 rh032 ntpd[2137]: IO: Deleting interface #7 eth0, fe80::4216:7eff:fe28:8ff6%2#123, interface stats: received=0, sent=0, dropped=0, active_time=600 secs
2024-06-05T16:14:05.193214+02:00 rh032 NetworkManager[2047]: <info>  [1717596845.1932] device (eth0): carrier: link connected
2024-06-05T16:14:05.196741+02:00 rh032 kernel: [ 1278.188411] r8169 0000:0a:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx
2024-06-05T16:14:06.926578+02:00 rh032 ntpd[2137]: IO: Listen normally on 8 eth0 10.6.6.32:123
2024-06-05T16:14:06.926622+02:00 rh032 ntpd[2137]: IO: Listen normally on 9 eth0 [fe80::4216:7eff:fe28:8ff6%2]:123
2024-06-05T16:14:06.926814+02:00 rh032 ntpd[2137]: IO: new interface(s) found: waking up resolver
2024-06-05T16:14:07.252807+02:00 rh032 kernel: [ 1280.244220] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
2024-06-05T16:14:07.252840+02:00 rh032 kernel: [ 1280.245523] sd 3:0:0:0: [sdb] Starting disk
2024-06-05T16:14:07.252842+02:00 rh032 kernel: [ 1280.246870] ata4.00: configured for UDMA/133
2024-06-05T16:14:48.922426+02:00 rh032 x-session-manager[2275]: WARNING: t+1257,26738s: Detected that screensaver has left the bus

Summary: works on USB2 despite the message "xhci_hcd 0000:04:00.0: xHC error in resume, USBSTS 0x401, Reinit"
Looks like it took more time for the computer to hibernate: that worked out fine on USB2.  

My conclusion is that the xhci_hcd-error may contribute but to mee it looks like there is some other factor that is relevant.

Offline

#17 2024-06-05 23:56:44

ralph.ronnquist
Administrator
From: Battery Point, Tasmania, AUS
Registered: 2016-11-30
Posts: 1,173  

Re: Suspend lockup lately

Yes quite possibly.

Check /prroc/acpi/wakeup for enabled wakeup sources.
The mapping from acpi names to devices may be difficult; this worked for me:

( grep enable /proc/acpi/wakeup | sed 's|.*0000:|-|' ; lspci ) | \
sort | grep -A1 '^-' | grep -v '^-'

It's possible to disable them; I'd need to google it.

Offline

Board footer