You are not logged in.
After a long overdue update (laptop was not used for a few months, basically sitting idle since April), my Thinkpad W520 is no longer able to come back from sleep/suspend. It suspends correctly, but when trying to wake it up, the screen illuminates and then it freezes. Laptop is completely unresponsive to keyboard commands and won't allow me to get to an alternative text console (I'm running KDE as DE). Only a hard shutdown gets me out of it.
I was finally able to get some debug info by logging in remotely from my desktop and issueing a dmesg -w command before putting the laptop to sleep. This seems to have worked, as I found quite a substantial output when, as expected, resuming from sleep failed.
Here is dmesg output just after the successful suspend:
Broadcast message from root@hippias on pts/5 (Wed 2022-10-05 20:56:16 CEST):
The system is going down for suspend NOW!
[ 1419.406077] audit: type=1106 audit(1664996176.339:116): pid=2870 uid=1001 auid=1001 ses=3 msg='op=PAM:session_close grantors=pam_systemd_home,pam_limits,pam_unix,pam_permit acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 1419.406154] audit: type=1104 audit(1664996176.339:117): pid=2870 uid=1001 auid=1001 ses=3 msg='op=PAM:setcred grantors=pam_faillock,pam_permit,pam_faillock acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=/dev/pts/5 res=success'
[ 1419.419378] audit: type=1130 audit(1664996176.352:118): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1419.604129] wlp3s0: deauthenticating from f4:f5:e8:6b:4b:19 by local choice (Reason: 3=DEAUTH_LEAVING)
[ 1419.821020] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[ 1420.115194] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[ 1420.829702] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[ 1421.124940] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[ 1421.440519] PM: suspend entry (deep)
[ 1423.154763] Filesystems sync: 1.714 seconds
[ 1424.928473] Freezing user space processes ... (elapsed 0.015 seconds) done.
[ 1424.944422] OOM killer disabled.
[ 1424.944426] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[ 1424.946277] printk: Suspending console(s) (use no_console_suspend to debug)
[ 1425.235475] e1000e: EEE TX LPI TIMER: 00000011
[ 1425.259218] sd 0:0:0:0: [sda] Synchronizing SCSI cache
[ 1425.259721] sd 0:0:0:0: [sda] Stopping disk
[ 1426.398316] ACPI: EC: interrupt blocked
[ 1426.416259] ACPI: PM: Preparing to enter system sleep state S3
[ 1426.428869] ACPI: EC: event blocked
[ 1426.428873] ACPI: EC: EC stopped
[ 1426.428875] ACPI: PM: Saving platform NVS memory
[ 1426.428947] Disabling non-boot CPUs ...
[ 1426.430771] smpboot: CPU 1 is now offline
[ 1426.432852] smpboot: CPU 2 is now offline
[ 1426.435224] smpboot: CPU 3 is now offline
[ 1426.437234] smpboot: CPU 4 is now offline
[ 1426.439097] smpboot: CPU 5 is now offline
[ 1426.441271] smpboot: CPU 6 is now offline
[ 1426.443021] smpboot: CPU 7 is now offline
[ 1426.444514] ACPI: PM: Low-level resume complete
[ 1426.444543] ACPI: EC: EC started
[ 1426.444544] ACPI: PM: Restoring platform NVS memory
[ 1426.444913] Enabling non-boot CPUs ...
[ 1426.444983] x86: Booting SMP configuration:
[ 1426.444984] smpboot: Booting Node 0 Processor 1 APIC 0x1
[ 1426.445417] Disabled fast string operations
[ 1426.448506] CPU1 is up
[ 1426.448556] smpboot: Booting Node 0 Processor 2 APIC 0x2
[ 1426.448892] Disabled fast string operations
[ 1426.451447] CPU2 is up
[ 1426.451489] smpboot: Booting Node 0 Processor 3 APIC 0x3
[ 1426.451819] Disabled fast string operations
[ 1426.454471] CPU3 is up
[ 1426.454525] smpboot: Booting Node 0 Processor 4 APIC 0x4
[ 1426.454860] Disabled fast string operations
[ 1426.457587] CPU4 is up
[ 1426.457630] smpboot: Booting Node 0 Processor 5 APIC 0x5
[ 1426.457963] Disabled fast string operations
[ 1426.460741] CPU5 is up
[ 1426.460782] smpboot: Booting Node 0 Processor 6 APIC 0x6
[ 1426.461119] Disabled fast string operations
[ 1426.464000] CPU6 is up
[ 1426.464048] smpboot: Booting Node 0 Processor 7 APIC 0x7
[ 1426.464386] Disabled fast string operations
[ 1426.467301] CPU7 is up
[ 1426.475014] ACPI: PM: Waking up from system sleep state S3
[ 1426.500492] ACPI: EC: interrupt unblocked
[ 1426.504110] sdhci-pci 0000:0d:00.0: MMC controller base frequency changed to 50Mhz.
[ 1426.504831] xhci_hcd 0000:0e:00.0: xHC error in resume, USBSTS 0x401, Reinit
[ 1426.504836] usb usb3: root hub lost power or was reset
[ 1426.504838] usb usb4: root hub lost power or was reset
[ 1426.512514] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[ 1426.514858] sd 0:0:0:0: [sda] Starting disk
[ 1426.530480] ACPI: EC: event unblocked
[ 1426.773771] usb 1-1.6: reset high-speed USB device number 5 using ehci-pci
[ 1426.803159] iwlwifi 0000:03:00.0: Radio type=0x1-0x2-0x0
[ 1426.856626] ata4: SATA link down (SStatus 0 SControl 300)
[ 1426.856880] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1426.856902] ata5: SATA link down (SStatus 0 SControl 300)
[ 1426.859671] ata2.00: ACPI cmd ef/10:03:00:00:00:a0(SET FEATURES) filtered out
[ 1426.864149] ata2.00: ACPI cmd ef/10:03:00:00:00:a0(SET FEATURES) filtered out
[ 1426.865731] ata2.00: configured for UDMA/66
[ 1426.953836] usb 1-1.4: reset full-speed USB device number 4 using ehci-pci
[ 1427.087252] firewire_core 0000:0d:00.3: rediscovered device fw0
[ 1427.215522] psmouse serio1: synaptics: queried max coordinates: x [..5888], y [..4820]
[ 1429.800552] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1430.020344] ata1.00: ACPI cmd f5/00:00:00:00:00:a0(SECURITY FREEZE LOCK) filtered out
[ 1430.020352] ata1.00: ACPI cmd ef/10:03:00:00:00:a0(SET FEATURES) filtered out
[ 1430.025033] ata1.00: ACPI cmd f5/00:00:00:00:00:a0(SECURITY FREEZE LOCK) filtered out
[ 1430.025042] ata1.00: ACPI cmd ef/10:03:00:00:00:a0(SET FEATURES) filtered out
[ 1430.027093] ata1.00: configured for UDMA/100
[ 1430.081188] OOM killer enabled.
[ 1430.081195] Restarting tasks ...
[ 1430.081499] usb 1-1.3: USB disconnect, device number 3
[ 1430.084775] done.
[ 1430.084785] random: crng reseeded on system resumption
[ 1430.093411] audit: type=1130 audit(1664996197.643:119): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1430.095312] audit: type=1131 audit(1664996197.646:120): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1430.104563] video LNXVIDEO:01: Restoring backlight state
[ 1430.105587] PM: suspend exit
[ 1430.107827] audit: type=1130 audit(1664996197.659:121): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1430.107834] audit: type=1131 audit(1664996197.659:122): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-suspend comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1430.212576] e1000e 0000:00:19.0 enp0s25: NIC Link is Down
[ 1432.127501] nvidia-modeset: ERROR: GPU:0: Idling display engine timed out: 0x0000857d:0:0
[ 1433.831020] wlp3s0: authenticate with f4:f5:e8:6b:4b:19
[ 1433.831037] wlp3s0: bad VHT capabilities, disabling VHT
[ 1433.833043] wlp3s0: send auth to f4:f5:e8:6b:4b:19 (try 1/3)
[ 1433.836432] wlp3s0: authenticated
[ 1433.837139] wlp3s0: associate with f4:f5:e8:6b:4b:19 (try 1/3)
[ 1433.847189] wlp3s0: RX AssocResp from f4:f5:e8:6b:4b:19 (capab=0x1411 status=0 aid=6)
[ 1433.872426] wlp3s0: associated
[ 1434.351385] wlp3s0: Limiting TX power to 30 (30 - 0) dBm as advertised by f4:f5:e8:6b:4b:19
[ 1434.351417] IPv6: ADDRCONF(NETDEV_CHANGE): wlp3s0: link becomes ready
[ 1435.098087] audit: type=1131 audit(1664996202.649:123): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-rfkill comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1435.298169] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1436.338179] usb 1-1-port3: cannot reset (err = -110)
[ 1437.378185] usb 1-1-port3: cannot reset (err = -110)
[ 1438.418184] usb 1-1-port3: cannot reset (err = -110)
[ 1439.458884] usb 1-1-port3: cannot reset (err = -110)
[ 1439.458892] usb 1-1-port3: Cannot enable. Maybe the USB cable is bad?
[ 1440.498194] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1440.498196] usb 1-1-port3: cannot disable (err = -110)
[ 1441.538913] usb 1-1-port3: cannot reset (err = -110)
[ 1442.578238] usb 1-1-port4: cannot disable (err = -110)
[ 1442.578254] usb 1-1-port3: cannot reset (err = -110)
[ 1442.578269] btusb: probe of 1-1.4:1.0 failed with error -22
[ 1442.578282] btusb: probe of 1-1.4:1.1 failed with error -22
[ 1443.618217] usb 1-1-port3: cannot reset (err = -110)
[ 1444.658915] usb 1-1-port3: cannot reset (err = -110)
[ 1445.698217] usb 1-1-port3: cannot reset (err = -110)
[ 1445.698218] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1445.698226] usb 1-1-port3: Cannot enable. Maybe the USB cable is bad?
[ 1446.738937] usb 1-1-port3: cannot disable (err = -110)
[ 1446.738953] usb 1-1-port3: attempt power cycle
[ 1447.778258] usb 1-1-port6: cannot disable (err = -110)
[ 1450.181323] usb 1-1-port3: cannot reset (err = -110)
[ 1451.218257] usb 1-1-port3: cannot reset (err = -110)
[ 1452.261589] usb 1-1-port3: cannot reset (err = -110)
[ 1453.297462] usb 1-1-port3: cannot reset (err = -110)
[ 1454.338957] usb 1-1-port3: cannot reset (err = -110)
[ 1454.338968] usb 1-1-port3: Cannot enable. Maybe the USB cable is bad?
[ 1455.380867] usb 1-1-port3: cannot disable (err = -110)
[ 1456.418268] usb 1-1-port3: cannot reset (err = -110)
[ 1457.461612] usb 1-1-port3: cannot reset (err = -110)
[ 1458.498284] usb 1-1-port3: cannot reset (err = -110)
[ 1459.538987] usb 1-1-port3: cannot reset (err = -110)
[ 1460.578140] usb 1-1-port3: cannot reset (err = -110)
[ 1460.578153] usb 1-1-port3: Cannot enable. Maybe the USB cable is bad?
[ 1460.900623] ata1.00: exception Emask 0x0 SAct 0x400 SErr 0x50000 action 0x6 frozen
[ 1460.900631] ata1: SError: { PHYRdyChg CommWake }
[ 1460.900634] ata1.00: failed command: READ FPDMA QUEUED
[ 1460.900636] ata1.00: cmd 60/c0:50:20:1b:25/00:00:20:00:00/40 tag 10 ncq dma 98304 in
res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
[ 1460.900641] ata1.00: status: { DRDY }
[ 1460.900645] ata1: hard resetting link
[ 1461.216888] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1461.619005] usb 1-1-port3: cannot disable (err = -110)
[ 1461.619030] usb 1-1-port3: unable to enumerate USB device
[ 1462.658306] usb 1-1-port3: cannot disable (err = -110)
[ 1462.923588] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1466.317375] ata1.00: qc timeout (cmd 0xec)
[ 1466.317397] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 1466.317399] ata1.00: revalidation failed (errno=-5)
[ 1466.317404] ata1: hard resetting link
[ 1466.633648] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1467.861678] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1468.020651] ata2.00: qc timeout (cmd 0xa1)
[ 1468.020673] ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 1468.020678] ata2.00: revalidation failed (errno=-5)
[ 1468.333634] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1473.061687] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1476.770691] ata1.00: qc timeout (cmd 0xec)
[ 1476.770711] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 1476.770713] ata1.00: revalidation failed (errno=-5)
[ 1476.770720] ata1: limiting SATA link speed to 1.5 Gbps
[ 1476.770724] ata1: hard resetting link
[ 1477.083686] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[ 1478.258380] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1478.474035] ata2.00: qc timeout (cmd 0xa1)
[ 1478.474059] ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 1478.474063] ata2.00: revalidation failed (errno=-5)
[ 1478.790353] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1483.458406] hub 1-1:1.0: hub_ext_port_status failed (err = -110)
[ 1483.458430] usb 1-1-port4: device 1-1.4 not suspended yet
[ 1508.557512] ata1.00: qc timeout (cmd 0xec)
[ 1508.557534] ata1.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 1508.557536] ata1.00: revalidation failed (errno=-5)
[ 1508.557540] ata1.00: disable device
[ 1508.873829] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 310)
[ 1508.873871] sd 0:0:0:0: [sda] tag#10 FAILED Result: hostbyte=DID_OK driverbyte=DRIVER_OK cmd_age=78s
[ 1508.873881] sd 0:0:0:0: [sda] tag#10 Sense Key : Not Ready [current]
[ 1508.873883] sd 0:0:0:0: [sda] tag#10 Add. Sense: Logical unit not ready, hard reset required
[ 1508.873886] sd 0:0:0:0: [sda] tag#10 CDB: Read(10) 28 00 20 25 1b 20 00 00 c0 00
[ 1508.873888] I/O error, dev sda, sector 539302688 op 0x0:(READ) flags 0x80700 phys_seg 22 prio class 0
[ 1508.873909] ata1: EH complete
[ 1508.873943] sd 0:0:0:0: [sda] tag#28 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=78s
[ 1508.873943] sd 0:0:0:0: [sda] tag#11 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.873945] sd 0:0:0:0: [sda] tag#28 CDB: Synchronize Cache(10) 35 00 00 00 00 00 00 00 00 00
[ 1508.873947] I/O error, dev sda, sector 505528304 op 0x1:(WRITE) flags 0x800 phys_seg 1 prio class 0
[ 1508.873948] sd 0:0:0:0: [sda] tag#11 CDB: Write(10) 2a 00 03 47 0c 58 00 00 78 00
[ 1508.873952] I/O error, dev sda, sector 54987864 op 0x1:(WRITE) flags 0x800 phys_seg 12 prio class 0
[ 1508.873958] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 20186553 starting block 6873483)
[ 1508.873960] sd 0:0:0:0: [sda] tag#29 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.873961] Aborting journal on device sda3-8.
[ 1508.873962] sd 0:0:0:0: [sda] tag#29 CDB: Read(10) 28 00 20 25 1b 60 00 00 08 00
[ 1508.873963] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 20189869 starting block 6873495)
[ 1508.873963] I/O error, dev sda, sector 539302752 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[ 1508.873965] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 20188053 starting block 6873496)
[ 1508.873968] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 20186457 starting block 6873497)
[ 1508.873970] sd 0:0:0:0: [sda] tag#30 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.873973] sd 0:0:0:0: [sda] tag#30 CDB: Write(10) 2a 00 09 37 63 e0 00 00 10 00
[ 1508.873975] I/O error, dev sda, sector 154624992 op 0x1:(WRITE) flags 0x0 phys_seg 2 prio class 0
[ 1508.873979] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 18237885 starting block 19328124)
[ 1508.873984] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 21019333 starting block 19328125)
[ 1508.873987] EXT4-fs error (device sda3): ext4_journal_check_start:83: comm kworker/u16:1: Detected aborted journal
[ 1508.873994] sd 0:0:0:0: [sda] tag#0 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.873997] sd 0:0:0:0: [sda] tag#0 CDB: Write(10) 2a 00 09 3e a2 c0 00 00 08 00
[ 1508.873999] I/O error, dev sda, sector 155099840 op 0x1:(WRITE) flags 0x800 phys_seg 1 prio class 0
[ 1508.874003] sd 0:0:0:0: [sda] tag#31 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.874003] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 3146640 starting block 19387480)
[ 1508.874007] sd 0:0:0:0: [sda] tag#31 CDB: Read(10) 28 00 20 25 1b 60 00 00 08 00
[ 1508.874009] sd 0:0:0:0: [sda] tag#1 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.874009] I/O error, dev sda, sector 539302752 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[ 1508.874012] sd 0:0:0:0: [sda] tag#1 CDB: Write(10) 2a 00 0a e3 08 30 00 00 08 00
[ 1508.874013] I/O error, dev sda, sector 182650928 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 0
[ 1508.874017] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 18237885 starting block 22831366)
[ 1508.874021] Buffer I/O error on device sda3, logical block 18496006
[ 1508.874033] sd 0:0:0:0: [sda] tag#2 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.874037] sd 0:0:0:0: [sda] tag#2 CDB: Write(10) 2a 00 0b 27 7d a0 00 00 18 00
[ 1508.874038] I/O error, dev sda, sector 187137440 op 0x1:(WRITE) flags 0x0 phys_seg 2 prio class 0
[ 1508.874042] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 20187342 starting block 23392180)
[ 1508.874043] sd 0:0:0:0: [sda] tag#13 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1508.874044] Buffer I/O error on device sda3, logical block 19056820
[ 1508.874045] sd 0:0:0:0: [sda] tag#13 CDB: Read(10) 28 00 20 25 1b 60 00 00 08 00
[ 1508.874047] I/O error, dev sda, sector 539302752 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0
[ 1508.874048] EXT4-fs warning (device sda3): ext4_end_bio:343: I/O error 10 writing to inode 20187342 starting block 23392181)
[ 1508.874053] Buffer I/O error on device sda3, logical block 19087360
[ 1508.874062] Buffer I/O error on device sda3, logical block 51404800
[ 1508.874070] Buffer I/O error on device sda3, logical block 51404816
[ 1508.874076] Buffer I/O error on device sda3, logical block 51404820
[ 1508.874116] Buffer I/O error on dev sda3, logical block 58753024, lost sync page write
[ 1508.874129] JBD2: Error -5 detected when updating journal superblock for sda3-8.
[ 1508.874183] EXT4-fs error (device sda3): ext4_journal_check_start:83: comm AUTHENTICATE: Detected aborted journal
[ 1508.874222] EXT4-fs error (device sda3): ext4_journal_check_start:83: comm systemd-journal: Detected aborted journal
[ 1508.874236] Buffer I/O error on device sda3, logical block 18959872
[ 1508.874247] Buffer I/O error on dev sda3, logical block 80740400, lost async page write
[ 1508.874254] Buffer I/O error on dev sda3, logical block 80741050, lost async page write
[ 1508.874258] Buffer I/O error on dev sda3, logical block 80741051, lost async page write
[ 1508.874264] Buffer I/O error on dev sda3, logical block 7340067, lost async page write
[ 1508.874270] Buffer I/O error on device sda3, logical block 15119109
[ 1508.874309] Buffer I/O error on dev sda3, logical block 82843604, lost async page write
[ 1508.874316] Buffer I/O error on dev sda3, logical block 80749276, lost async page write
[ 1508.874335] Buffer I/O error on dev sda3, logical block 0, lost sync page write
[ 1508.874344] EXT4-fs (sda3): I/O error while writing superblock
[ 1508.874347] Buffer I/O error on device sda3, logical block 51389571
[ 1508.874348] EXT4-fs (sda3): Remounting filesystem read-only
[ 1508.874351] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 20186553, error -30)
[ 1508.874355] Buffer I/O error on device sda3, logical block 51389568
[ 1508.874374] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 20189869, error -30)
[ 1508.874386] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 20188053, error -30)
[ 1508.874392] Buffer I/O error on dev sda3, logical block 0, lost sync page write
[ 1508.874396] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 20186457, error -30)
[ 1508.874406] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 18237885, error -30)
[ 1508.874409] EXT4-fs (sda3): previous I/O error to superblock detected
[ 1508.874413] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 21019333, error -30)
[ 1508.874423] EXT4-fs (sda3): failed to convert unwritten extents to written extents -- potential data loss! (inode 3146640, error -30)
[ 1508.874469] Buffer I/O error on dev sda3, logical block 0, lost sync page write
[ 1508.894659] audit: type=1130 audit(1664996276.446:124): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1508.901157] EXT4-fs error (device sda3): __ext4_find_entry:1658: inode #20185430: comm AudioIP~ver RPC: reading directory lblock 0
[ 1508.912881] EXT4-fs error (device sda3): __ext4_find_entry:1658: inode #20185430: comm AudioIP~ver RPC: reading directory lblock 0
[ 1508.917940] audit: type=1701 audit(1664996276.469:125): auid=1001 uid=1001 gid=1000 ses=3 pid=1160 comm=417564696F49507E76657220525043 exe="/usr/lib/firefox-developer-edition/firefox" sig=7 res=1
[ 1508.930872] audit: type=1334 audit(1664996276.482:126): prog-id=23 op=LOAD
[ 1508.931071] audit: type=1334 audit(1664996276.482:127): prog-id=24 op=LOAD
[ 1508.931127] audit: type=1334 audit(1664996276.482:128): prog-id=25 op=LOAD
[ 1508.932777] audit: type=1130 audit(1664996276.482:129): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@1-3049-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1508.935124] audit: type=1131 audit(1664996276.486:130): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=systemd-coredump@1-3049-0 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1509.079301] audit: type=1701 audit(1664996276.629:131): auid=1001 uid=1001 gid=1000 ses=3 pid=927 comm="dropbox" exe="/opt/dropbox/dropbox" sig=7 res=1
[ 1509.086672] audit: type=1334 audit(1664996276.636:132): prog-id=26 op=LOAD
[ 1509.086785] audit: type=1334 audit(1664996276.636:133): prog-id=27 op=LOAD
[ 1510.264187] ata2.00: qc timeout (cmd 0xa1)
[ 1510.264214] ata2.00: failed to IDENTIFY (I/O error, err_mask=0x4)
[ 1510.264217] ata2.00: revalidation failed (errno=-5)
[ 1510.264222] ata2.00: disable device
[ 1510.580494] ata2: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 1518.905011] kauditd_printk_skb: 9 callbacks suppressed
[ 1518.905015] audit: type=1131 audit(1664996286.456:143): pid=1 uid=0 auid=4294967295 ses=4294967295 msg='unit=NetworkManager-dispatcher comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
[ 1597.301162] INFO: task khugepaged:74 blocked for more than 124 seconds.
[ 1597.301170] Tainted: P OE 5.19.13-arch1-1 #1
[ 1597.301172] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1597.301173] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 1597.301178] Call Trace:
[ 1597.301179] <TASK>
[ 1597.301181] __schedule+0x356/0x11a0
[ 1597.301188] schedule+0x5e/0xd0
[ 1597.301190] schedule_timeout+0x11c/0x150
[ 1597.301194] __wait_for_common+0x94/0x1d0
[ 1597.301196] ? usleep_range_state+0x90/0x90
[ 1597.301199] __flush_work.isra.0+0x171/0x230
[ 1597.301204] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 1597.301207] __lru_add_drain_all+0x160/0x1f0
[ 1597.301212] khugepaged+0x75/0x2200
[ 1597.301218] ? cpuacct_percpu_seq_show+0x20/0x20
[ 1597.301222] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 1597.301225] kthread+0xde/0x110
[ 1597.301227] ? kthread_complete_and_exit+0x20/0x20
[ 1597.301230] ret_from_fork+0x22/0x30
[ 1597.301235] </TASK>
[ 1720.181515] INFO: task khugepaged:74 blocked for more than 247 seconds.
[ 1720.181523] Tainted: P OE 5.19.13-arch1-1 #1
[ 1720.181525] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1720.181527] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 1720.181531] Call Trace:
[ 1720.181533] <TASK>
[ 1720.181535] __schedule+0x356/0x11a0
[ 1720.181542] schedule+0x5e/0xd0
[ 1720.181544] schedule_timeout+0x11c/0x150
[ 1720.181548] __wait_for_common+0x94/0x1d0
[ 1720.181550] ? usleep_range_state+0x90/0x90
[ 1720.181554] __flush_work.isra.0+0x171/0x230
[ 1720.181558] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 1720.181561] __lru_add_drain_all+0x160/0x1f0
[ 1720.181566] khugepaged+0x75/0x2200
[ 1720.181573] ? cpuacct_percpu_seq_show+0x20/0x20
[ 1720.181576] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 1720.181580] kthread+0xde/0x110
[ 1720.181582] ? kthread_complete_and_exit+0x20/0x20
[ 1720.181585] ret_from_fork+0x22/0x30
[ 1720.181591] </TASK>
[ 1843.061765] INFO: task khugepaged:74 blocked for more than 370 seconds.
[ 1843.061774] Tainted: P OE 5.19.13-arch1-1 #1
[ 1843.061776] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1843.061777] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 1843.061782] Call Trace:
[ 1843.061783] <TASK>
[ 1843.061785] __schedule+0x356/0x11a0
[ 1843.061793] schedule+0x5e/0xd0
[ 1843.061795] schedule_timeout+0x11c/0x150
[ 1843.061799] __wait_for_common+0x94/0x1d0
[ 1843.061801] ? usleep_range_state+0x90/0x90
[ 1843.061805] __flush_work.isra.0+0x171/0x230
[ 1843.061809] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 1843.061812] __lru_add_drain_all+0x160/0x1f0
[ 1843.061818] khugepaged+0x75/0x2200
[ 1843.061824] ? cpuacct_percpu_seq_show+0x20/0x20
[ 1843.061828] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 1843.061831] kthread+0xde/0x110
[ 1843.061834] ? kthread_complete_and_exit+0x20/0x20
[ 1843.061836] ret_from_fork+0x22/0x30
[ 1843.061842] </TASK>
[ 1921.155403] scsi_io_completion_action: 323 callbacks suppressed
[ 1921.155411] sd 0:0:0:0: [sda] tag#29 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 1921.155417] sd 0:0:0:0: [sda] tag#29 CDB: ATA command pass through(16) 85 06 20 00 00 00 00 00 00 00 00 00 00 00 e5 00
[ 1965.941968] INFO: task khugepaged:74 blocked for more than 493 seconds.
[ 1965.941977] Tainted: P OE 5.19.13-arch1-1 #1
[ 1965.941979] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1965.941981] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 1965.941985] Call Trace:
[ 1965.941987] <TASK>
[ 1965.941989] __schedule+0x356/0x11a0
[ 1965.941997] schedule+0x5e/0xd0
[ 1965.941999] schedule_timeout+0x11c/0x150
[ 1965.942004] __wait_for_common+0x94/0x1d0
[ 1965.942006] ? usleep_range_state+0x90/0x90
[ 1965.942010] __flush_work.isra.0+0x171/0x230
[ 1965.942014] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 1965.942018] __lru_add_drain_all+0x160/0x1f0
[ 1965.942023] khugepaged+0x75/0x2200
[ 1965.942030] ? cpuacct_percpu_seq_show+0x20/0x20
[ 1965.942034] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 1965.942037] kthread+0xde/0x110
[ 1965.942040] ? kthread_complete_and_exit+0x20/0x20
[ 1965.942043] ret_from_fork+0x22/0x30
[ 1965.942049] </TASK>
[ 2088.822139] INFO: task khugepaged:74 blocked for more than 616 seconds.
[ 2088.822148] Tainted: P OE 5.19.13-arch1-1 #1
[ 2088.822150] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2088.822152] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 2088.822156] Call Trace:
[ 2088.822158] <TASK>
[ 2088.822160] __schedule+0x356/0x11a0
[ 2088.822167] schedule+0x5e/0xd0
[ 2088.822169] schedule_timeout+0x11c/0x150
[ 2088.822174] __wait_for_common+0x94/0x1d0
[ 2088.822176] ? usleep_range_state+0x90/0x90
[ 2088.822179] __flush_work.isra.0+0x171/0x230
[ 2088.822184] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 2088.822187] __lru_add_drain_all+0x160/0x1f0
[ 2088.822192] khugepaged+0x75/0x2200
[ 2088.822199] ? cpuacct_percpu_seq_show+0x20/0x20
[ 2088.822202] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 2088.822206] kthread+0xde/0x110
[ 2088.822208] ? kthread_complete_and_exit+0x20/0x20
[ 2088.822211] ret_from_fork+0x22/0x30
[ 2088.822217] </TASK>
[ 2211.702290] INFO: task khugepaged:74 blocked for more than 738 seconds.
[ 2211.702299] Tainted: P OE 5.19.13-arch1-1 #1
[ 2211.702301] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2211.702302] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 2211.702307] Call Trace:
[ 2211.702309] <TASK>
[ 2211.702311] __schedule+0x356/0x11a0
[ 2211.702318] schedule+0x5e/0xd0
[ 2211.702320] schedule_timeout+0x11c/0x150
[ 2211.702325] __wait_for_common+0x94/0x1d0
[ 2211.702327] ? usleep_range_state+0x90/0x90
[ 2211.702330] __flush_work.isra.0+0x171/0x230
[ 2211.702335] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 2211.702338] __lru_add_drain_all+0x160/0x1f0
[ 2211.702344] khugepaged+0x75/0x2200
[ 2211.702350] ? cpuacct_percpu_seq_show+0x20/0x20
[ 2211.702354] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 2211.702357] kthread+0xde/0x110
[ 2211.702360] ? kthread_complete_and_exit+0x20/0x20
[ 2211.702362] ret_from_fork+0x22/0x30
[ 2211.702368] </TASK>
[ 2334.582430] INFO: task khugepaged:74 blocked for more than 861 seconds.
[ 2334.582439] Tainted: P OE 5.19.13-arch1-1 #1
[ 2334.582441] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2334.582442] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 2334.582447] Call Trace:
[ 2334.582448] <TASK>
[ 2334.582450] __schedule+0x356/0x11a0
[ 2334.582457] schedule+0x5e/0xd0
[ 2334.582459] schedule_timeout+0x11c/0x150
[ 2334.582464] __wait_for_common+0x94/0x1d0
[ 2334.582466] ? usleep_range_state+0x90/0x90
[ 2334.582470] __flush_work.isra.0+0x171/0x230
[ 2334.582474] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 2334.582477] __lru_add_drain_all+0x160/0x1f0
[ 2334.582483] khugepaged+0x75/0x2200
[ 2334.582489] ? cpuacct_percpu_seq_show+0x20/0x20
[ 2334.582493] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 2334.582496] kthread+0xde/0x110
[ 2334.582499] ? kthread_complete_and_exit+0x20/0x20
[ 2334.582502] ret_from_fork+0x22/0x30
[ 2334.582507] </TASK>
[ 2457.462560] INFO: task khugepaged:74 blocked for more than 984 seconds.
[ 2457.462570] Tainted: P OE 5.19.13-arch1-1 #1
[ 2457.462572] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2457.462574] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 2457.462578] Call Trace:
[ 2457.462580] <TASK>
[ 2457.462582] __schedule+0x356/0x11a0
[ 2457.462590] schedule+0x5e/0xd0
[ 2457.462592] schedule_timeout+0x11c/0x150
[ 2457.462597] __wait_for_common+0x94/0x1d0
[ 2457.462599] ? usleep_range_state+0x90/0x90
[ 2457.462603] __flush_work.isra.0+0x171/0x230
[ 2457.462608] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 2457.462611] __lru_add_drain_all+0x160/0x1f0
[ 2457.462617] khugepaged+0x75/0x2200
[ 2457.462624] ? cpuacct_percpu_seq_show+0x20/0x20
[ 2457.462628] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 2457.462631] kthread+0xde/0x110
[ 2457.462634] ? kthread_complete_and_exit+0x20/0x20
[ 2457.462637] ret_from_fork+0x22/0x30
[ 2457.462643] </TASK>
[ 2521.067597] sd 0:0:0:0: [sda] tag#30 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 2521.067608] sd 0:0:0:0: [sda] tag#30 CDB: ATA command pass through(16) 85 06 20 00 00 00 00 00 00 00 00 00 00 00 e5 00
[ 2580.342670] INFO: task khugepaged:74 blocked for more than 1107 seconds.
[ 2580.342678] Tainted: P OE 5.19.13-arch1-1 #1
[ 2580.342680] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2580.342682] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 2580.342686] Call Trace:
[ 2580.342687] <TASK>
[ 2580.342690] __schedule+0x356/0x11a0
[ 2580.342697] schedule+0x5e/0xd0
[ 2580.342699] schedule_timeout+0x11c/0x150
[ 2580.342704] __wait_for_common+0x94/0x1d0
[ 2580.342706] ? usleep_range_state+0x90/0x90
[ 2580.342710] __flush_work.isra.0+0x171/0x230
[ 2580.342714] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 2580.342718] __lru_add_drain_all+0x160/0x1f0
[ 2580.342723] khugepaged+0x75/0x2200
[ 2580.342729] ? cpuacct_percpu_seq_show+0x20/0x20
[ 2580.342733] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 2580.342736] kthread+0xde/0x110
[ 2580.342739] ? kthread_complete_and_exit+0x20/0x20
[ 2580.342742] ret_from_fork+0x22/0x30
[ 2580.342747] </TASK>
[ 2703.222794] INFO: task khugepaged:74 blocked for more than 1230 seconds.
[ 2703.222803] Tainted: P OE 5.19.13-arch1-1 #1
[ 2703.222805] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2703.222806] task:khugepaged state:D stack: 0 pid: 74 ppid: 2 flags:0x00004000
[ 2703.222810] Call Trace:
[ 2703.222812] <TASK>
[ 2703.222814] __schedule+0x356/0x11a0
[ 2703.222822] schedule+0x5e/0xd0
[ 2703.222824] schedule_timeout+0x11c/0x150
[ 2703.222828] __wait_for_common+0x94/0x1d0
[ 2703.222830] ? usleep_range_state+0x90/0x90
[ 2703.222834] __flush_work.isra.0+0x171/0x230
[ 2703.222838] ? flush_workqueue_prep_pwqs+0x110/0x110
[ 2703.222842] __lru_add_drain_all+0x160/0x1f0
[ 2703.222847] khugepaged+0x75/0x2200
[ 2703.222854] ? cpuacct_percpu_seq_show+0x20/0x20
[ 2703.222857] ? collapse_pte_mapped_thp+0x3c0/0x3c0
[ 2703.222860] kthread+0xde/0x110
[ 2703.222863] ? kthread_complete_and_exit+0x20/0x20
[ 2703.222865] ret_from_fork+0x22/0x30
[ 2703.222871] </TASK>
[ 2732.155347] perf: interrupt took too long (2501 > 2500), lowering kernel.perf_event_max_sample_rate to 79800
[ 3121.157586] sd 0:0:0:0: [sda] tag#1 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 3121.157594] sd 0:0:0:0: [sda] tag#1 CDB: ATA command pass through(16) 85 06 20 00 00 00 00 00 00 00 00 00 00 00 e5 00
[ 3520.730527] perf: interrupt took too long (3128 > 3126), lowering kernel.perf_event_max_sample_rate to 63900
[ 3721.157325] sd 0:0:0:0: [sda] tag#0 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 3721.157333] sd 0:0:0:0: [sda] tag#0 CDB: ATA command pass through(16) 85 06 20 00 00 00 00 00 00 00 00 00 00 00 e5 00
[ 4321.144505] sd 0:0:0:0: [sda] tag#3 FAILED Result: hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK cmd_age=0s
[ 4321.144513] sd 0:0:0:0: [sda] tag#3 CDB: ATA command pass through(16) 85 06 20 00 00 00 00 00 00 00 00 00 00 00 e5 00The first error occurs at 1432.127501, issued by nvidia, and it is all downhill from there. BTW, the remote connection to my desktop resumed as well, but most commands are disabled with the error:
stefano@hippias ~ $ top
-bash: /usr/bin/top: Input/output errorprobably because the hard drive seems to have become inaccessible. The laptop has an Nvidia M1000 card and runs nvidia legacy drivers.
I really cannot make any sense out of it and any help is appreciated.
Last edited by stefano (2022-10-05 21:29:07)
Offline
So, it turns out that switching from the discrete nVidia card to Intel integrated graphics (in the Bios) makes all problems disappear. So it definitely seems to be an nVidia-driver related issue. Not marking the issue as solved because I would really like to get the discrete card in working conditions, eventually...
Offline
If it's Nvidia, you can try the modern restoration hooks: https://wiki.archlinux.org/title/NVIDIA … er_suspend
Offline
If it's Nvidia, you can try the modern restoration hooks: https://wiki.archlinux.org/title/NVIDIA … er_suspend
Thanks for the hint. I may try that, I wasn't aware of that particular trick. I never needed it before, I am not sure what changed after April 2022 (when the problem started to occur on my laptop).
Offline