Scsi_bus_resume+0x0/0x90 returns -5 when resuming from s3 sleep

[TW <dalzot@gmail.com>]

I have been having issues with the 6.x series of kernels resuming from 
suspend with one of my drives. Far as I can tell it has trouble with the 
cache on the drive when coming out of s3 sleep. Tried a few different 
distros (Manjaro, OpenMandriva Rome, EndeavourOS) all that give the same 
error message. It appears to work fine on the 5.15 kernel just fine however.


This is the error or errors that I have been getting and assume has been 
holding up the system from resuming from suspend.
Jul 20 04:13:41 rageworks kernel: ata10.00: device reported invalid CHS 
sector 0
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Start/Stop Unit 
failed: Result: hostbyte=DID_OK driverbyte=DRIVER_OK
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Sense Key : Illegal 
Request [current]
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Add. Sense: 
Unaligned write command
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: PM: dpm_run_callback(): 
scsi_bus_resume+0x0/0x90 returns -5
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: PM: failed to resume 
async: error -5

The full suspend log.
Jul 20 04:12:50 rageworks systemd-logind[869]: The system will suspend now!
Jul 20 04:12:50 rageworks ModemManager[902]: <info> 
[sleep-monitor-systemd] system is about to suspend
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.4923] 
manager: sleep: sleep requested (sleeping: no enabled: yes)
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.4924] 
manager: NetworkManager state is now ASLEEP
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.4926] 
device (enp9s0): state change: activated -> deactivating (reason 
'sleeping', sys-iface-state: 'managed')
Jul 20 04:12:50 rageworks dbus-daemon[866]: [system] Activating via 
systemd: service name='org.freedesktop.nm_dispatcher' 
unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.6' 
(uid=0 pid=894 comm="/usr/bin/NetworkManager --no-daemon")
Jul 20 04:12:50 rageworks systemd[1]: Starting Network Manager Script 
Dispatcher Service...
Jul 20 04:12:50 rageworks dbus-daemon[866]: [system] Successfully 
activated service 'org.freedesktop.nm_dispatcher'
Jul 20 04:12:50 rageworks systemd[1]: Started Network Manager Script 
Dispatcher Service.
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.6544] 
device (enp9s0): state change: deactivating -> disconnected (reason 
'sleeping', sys-iface-state: 'managed')
Jul 20 04:12:50 rageworks avahi-daemon[864]: Withdrawing address record 
for fe80::881:c55d:1583:20f5 on enp9s0.
Jul 20 04:12:50 rageworks avahi-daemon[864]: Leaving mDNS multicast 
group on interface enp9s0.IPv6 with address fe80::881:c55d:1583:20f5.
Jul 20 04:12:50 rageworks avahi-daemon[864]: Interface enp9s0.IPv6 no 
longer relevant for mDNS.
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.6726] 
dhcp4 (enp9s0): canceled DHCP transaction
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.6726] 
dhcp4 (enp9s0): activation: beginning transaction (timeout in 45 seconds)
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.6727] 
dhcp4 (enp9s0): state changed no lease
Jul 20 04:12:50 rageworks avahi-daemon[864]: Withdrawing address record 
for 192.168.1.3 on enp9s0.
Jul 20 04:12:50 rageworks avahi-daemon[864]: Leaving mDNS multicast 
group on interface enp9s0.IPv4 with address 192.168.1.3.
Jul 20 04:12:50 rageworks avahi-daemon[864]: Interface enp9s0.IPv4 no 
longer relevant for mDNS.
Jul 20 04:12:50 rageworks NetworkManager[894]: <info> [1689847970.7576] 
device (enp9s0): state change: disconnected -> unmanaged (reason 
'sleeping', sys-iface-state: 'managed')
Jul 20 04:12:50 rageworks kernel: r8169 0000:09:00.0 enp9s0: Link is Down
Jul 20 04:12:50 rageworks systemd[1]: Reached target Sleep.
Jul 20 04:12:50 rageworks systemd[1]: Starting NVIDIA system suspend 
actions...
Jul 20 04:12:50 rageworks suspend[2051]: nvidia-suspend.service
Jul 20 04:12:50 rageworks logger[2051]: <13>Jul 20 04:12:50 suspend: 
nvidia-suspend.service
Jul 20 04:12:51 rageworks systemd[1]: nvidia-suspend.service: 
Deactivated successfully.
Jul 20 04:12:51 rageworks systemd[1]: Finished NVIDIA system suspend 
actions.
Jul 20 04:12:51 rageworks systemd[1]: Starting System Suspend...
Jul 20 04:12:51 rageworks systemd-sleep[2059]: Entering sleep state 
'suspend'...
Jul 20 04:12:51 rageworks kernel: PM: suspend entry (deep)
Jul 20 04:13:41 rageworks kernel: Filesystems sync: 0.284 seconds
Jul 20 04:13:41 rageworks kernel: Freezing user space processes
Jul 20 04:13:41 rageworks kernel: Freezing user space processes 
completed (elapsed 0.001 seconds)
Jul 20 04:13:41 rageworks kernel: OOM killer disabled.
Jul 20 04:13:41 rageworks kernel: Freezing remaining freezable tasks
Jul 20 04:13:41 rageworks kernel: Freezing remaining freezable tasks 
completed (elapsed 0.001 seconds)
Jul 20 04:13:41 rageworks kernel: printk: Suspending console(s) (use 
no_console_suspend to debug)
Jul 20 04:13:41 rageworks kernel: serial 00:05: disabled
Jul 20 04:13:41 rageworks kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Jul 20 04:13:41 rageworks kernel: sd 1:0:0:0: [sdb] Synchronizing SCSI cache
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Synchronizing SCSI cache
Jul 20 04:13:41 rageworks kernel: sd 1:0:0:0: [sdb] Stopping disk
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Stopping disk
Jul 20 04:13:41 rageworks kernel: sd 0:0:0:0: [sda] Stopping disk
Jul 20 04:13:41 rageworks kernel: ACPI: PM: Preparing to enter system 
sleep state S3
Jul 20 04:13:41 rageworks kernel: ACPI: PM: Saving platform NVS memory
Jul 20 04:13:41 rageworks kernel: Disabling non-boot CPUs ...
Jul 20 04:13:41 rageworks kernel: smpboot: CPU 1 is now offline
Jul 20 04:13:41 rageworks kernel: smpboot: CPU 2 is now offline
Jul 20 04:13:41 rageworks kernel: smpboot: CPU 3 is now offline
Jul 20 04:13:41 rageworks kernel: ACPI: PM: Low-level resume complete
Jul 20 04:13:41 rageworks kernel: ACPI: PM: Restoring platform NVS memory
Jul 20 04:13:41 rageworks kernel: Enabling non-boot CPUs ...
Jul 20 04:13:41 rageworks kernel: x86: Booting SMP configuration:
Jul 20 04:13:41 rageworks kernel: smpboot: Booting Node 0 Processor 1 
APIC 0x1
Jul 20 04:13:41 rageworks kernel: microcode: CPU1: patch_level=0x08101016
Jul 20 04:13:41 rageworks kernel: CPU1 is up
Jul 20 04:13:41 rageworks kernel: smpboot: Booting Node 0 Processor 2 
APIC 0x2
Jul 20 04:13:41 rageworks kernel: microcode: CPU2: patch_level=0x08101016
Jul 20 04:13:41 rageworks kernel: CPU2 is up
Jul 20 04:13:41 rageworks kernel: smpboot: Booting Node 0 Processor 3 
APIC 0x3
Jul 20 04:13:41 rageworks kernel: microcode: CPU3: patch_level=0x08101016
Jul 20 04:13:41 rageworks kernel: CPU3 is up
Jul 20 04:13:41 rageworks kernel: ACPI: PM: Waking up from system sleep 
state S3
Jul 20 04:13:41 rageworks kernel: xhci_hcd 0000:02:00.0: xHC error in 
resume, USBSTS 0x401, Reinit
Jul 20 04:13:41 rageworks kernel: usb usb1: root hub lost power or was reset
Jul 20 04:13:41 rageworks kernel: usb usb2: root hub lost power or was reset
Jul 20 04:13:41 rageworks kernel: serial 00:05: activated
Jul 20 04:13:41 rageworks kernel: sd 0:0:0:0: [sda] Starting disk
Jul 20 04:13:41 rageworks kernel: sd 1:0:0:0: [sdb] Starting disk
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Starting disk
Jul 20 04:13:41 rageworks kernel: ata5: SATA link down (SStatus 0 
SControl 330)
Jul 20 04:13:41 rageworks kernel: ata9: SATA link down (SStatus 0 
SControl 300)
Jul 20 04:13:41 rageworks kernel: ata6: SATA link down (SStatus 0 
SControl 330)
Jul 20 04:13:41 rageworks kernel: usb 1-7: reset full-speed USB device 
number 2 using xhci_hcd
Jul 20 04:13:41 rageworks kernel: usb 1-10: reset full-speed USB device 
number 4 using xhci_hcd
Jul 20 04:13:41 rageworks kernel: usb 1-8: reset full-speed USB device 
number 3 using xhci_hcd
Jul 20 04:13:41 rageworks kernel: ata2: found unknown device (class 0)
Jul 20 04:13:41 rageworks kernel: ata1: found unknown device (class 0)
Jul 20 04:13:41 rageworks kernel: ata10: found unknown device (class 0)
Jul 20 04:13:41 rageworks kernel: ata2: SATA link up 6.0 Gbps (SStatus 
133 SControl 300)
Jul 20 04:13:41 rageworks kernel: ata2.00: configured for UDMA/133
Jul 20 04:13:41 rageworks kernel: ata1: SATA link up 6.0 Gbps (SStatus 
133 SControl 300)
Jul 20 04:13:41 rageworks kernel: ata1.00: configured for UDMA/133
Jul 20 04:13:41 rageworks kernel: ata10: SATA link up 6.0 Gbps (SStatus 
133 SControl 300)
Jul 20 04:13:41 rageworks kernel: ata10.00: configured for UDMA/133
Jul 20 04:13:41 rageworks kernel: ata10: SATA link up 6.0 Gbps (SStatus 
133 SControl 300)
Jul 20 04:13:41 rageworks kernel: ata10.00: configured for UDMA/133
Jul 20 04:13:41 rageworks kernel: ata10.00: device reported invalid CHS 
sector 0
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Start/Stop Unit 
failed: Result: hostbyte=DID_OK driverbyte=DRIVER_OK
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Sense Key : Illegal 
Request [current]
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: [sdc] Add. Sense: 
Unaligned write command
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: PM: dpm_run_callback(): 
scsi_bus_resume+0x0/0x90 returns -5
Jul 20 04:13:41 rageworks kernel: sd 9:0:0:0: PM: failed to resume 
async: error -5
Jul 20 04:13:41 rageworks kernel: OOM killer enabled.
Jul 20 04:13:41 rageworks kernel: Restarting tasks ... done.
Jul 20 04:13:41 rageworks kernel: random: crng reseeded on system resumption
Jul 20 04:13:41 rageworks kernel: PM: suspend exit
Jul 20 04:13:41 rageworks rtkit-daemon[1349]: The canary thread is 
apparently starving. Taking action.
Jul 20 04:13:41 rageworks systemd-sleep[2059]: System returned from 
sleep state.
Jul 20 04:13:41 rageworks rtkit-daemon[1349]: Demoting known real-time 
threads.
Jul 20 04:13:41 rageworks systemd[1]: NetworkManager-dispatcher.service: 
Deactivated successfully.
Jul 20 04:13:41 rageworks rtkit-daemon[1349]: Successfully demoted 
thread 1597 of process 1342.
Jul 20 04:13:41 rageworks rtkit-daemon[1349]: Successfully demoted 
thread 1342 of process 1342.
Jul 20 04:13:41 rageworks rtkit-daemon[1349]: Demoted 2 threads.
Jul 20 04:13:42 rageworks systemd[1]: systemd-suspend.service: 
Deactivated successfully.
Jul 20 04:13:42 rageworks systemd[1]: Finished System Suspend.
Jul 20 04:13:42 rageworks systemd[1]: Stopped target Sleep.
Jul 20 04:13:42 rageworks systemd[1]: Reached target Suspend.
Jul 20 04:13:42 rageworks systemd[1]: Stopped target Suspend.
Jul 20 04:13:42 rageworks systemd-logind[869]: Operation 'sleep' finished.


Thanks