Hi, in one my previous posts to the list, I complained that hibernation was somewhat broken on my Thinkpad and it would generate filesystem corruption on resume. With the help of the list, I was able to fix that problem. However, I still have occasional problems with resume from suspend to RAM.
For example, last night I put my laptop into suspend at 11:45 pm; these are the last lines from yesterday in /var/log/messages: Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.4945] manager: sleep requested (sleeping: no enabled: yes) Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.4945] manager: sleeping... Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.4947] device (enp9s0): state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37] Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.5387] device (cdc-wdm2): state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37] Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.5390] manager: NetworkManager state is now ASLEEP Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.5674] device (wlp3s0): state change: activated -> deactivating (reason 'sleeping') [100 110 37] Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.6673] device (wlp3s0): state change: deactivating -> disconnected (reason 'sleeping') [110 30 37] Jan 21 23:45:38 e130 kernel: [46283.023798] wlp3s0: deauthenticating from a0:f3:c1:3b:a4:8c by local choice (Reason: 3=DEAUTH_LEAVING) Jan 21 23:45:38 e130 NetworkManager[2498]: <info> [1579650338.6990] device (wlp3s0): set-hw-addr: set MAC address to 86:63:57:C5:A6:C0 (scanning) Jan 21 23:45:38 e130 kernel: [46283.054944] iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled Jan 21 23:45:38 e130 kernel: [46283.062774] iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled Jan 21 23:45:38 e130 kernel: [46283.062881] iwlwifi 0000:03:00.0: Radio type=0x2-0x0-0x0 Jan 21 23:45:38 e130 kernel: [46283.323094] iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled Jan 21 23:45:38 e130 kernel: [46283.330925] iwlwifi 0000:03:00.0: L1 Enabled - LTR Disabled Jan 21 23:45:38 e130 kernel: [46283.331021] iwlwifi 0000:03:00.0: Radio type=0x2-0x0-0x0 Jan 21 23:45:39 e130 kernel: [46283.390930] IPv6: ADDRCONF(NETDEV_UP): wlp3s0: link is not ready Jan 21 23:45:39 e130 NetworkManager[2498]: <warn> [1579650339.0401] sup-iface[0x55e6ac5cd210,wlp3s0]: connection disconnected (reason -3) Jan 21 23:45:39 e130 NetworkManager[2498]: <info> [1579650339.0410] device (wlp3s0): state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37] Jan 21 23:45:39 e130 NetworkManager[2498]: <info> [1579650339.4359] device (wlp3s0): set-hw-addr: reset MAC address to 84:A6:C8:A8:DE:BE (unmanage) Jan 21 23:45:40 e130 kernel: [46284.302216] PM: Syncing filesystems ... done. I disconnected the laptop from mains and I left it on the table. Nobody touched it. Then, I woke up at about 8 am and the laptop was switched off and the battery completely drained. In /var/log/messages, there is a gap between "Jan 21 23:45:40" and "Jan 22 05:00:23". Something must have woken the laptop at 5 am: Jan 22 05:00:23 e130 kernel: [46284.735850] (NULL device *): firmware: direct-loading firmware iwlwifi-2030-6.ucode Jan 22 05:00:23 e130 kernel: [46284.735911] Freezing user space processes ... (elapsed 0.060 seconds) done. Jan 22 05:00:23 e130 kernel: [46284.795963] Freezing remaining freezable tasks ... (elapsed 0.025 seconds) done. Jan 22 05:00:23 e130 kernel: [46284.821238] Suspending console(s) (use no_console_suspend to debug) Jan 22 05:00:23 e130 kernel: [46284.971415] sd 0:0:0:0: [sda] Synchronizing SCSI cache Jan 22 05:00:23 e130 kernel: [46284.987208] sd 0:0:0:0: [sda] Stopping disk Jan 22 05:00:23 e130 kernel: [46285.460455] PM: suspend of devices complete after 638.911 msecs Jan 22 05:00:23 e130 kernel: [46285.479805] PM: late suspend of devices complete after 19.341 msecs Jan 22 05:00:23 e130 kernel: [46285.481780] ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI Jan 22 05:00:23 e130 kernel: [46285.482265] xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI Jan 22 05:00:23 e130 kernel: [46285.519765] PM: noirq suspend of devices complete after 39.955 msecs Jan 22 05:00:23 e130 kernel: [46285.520062] ACPI: Preparing to enter system sleep state S3 Jan 22 05:00:23 e130 kernel: [46285.599904] ACPI : EC: event blocked Jan 22 05:00:23 e130 kernel: [46285.599904] ACPI : EC: EC stopped Jan 22 05:00:23 e130 kernel: [46285.599906] PM: Saving platform NVS memory Jan 22 05:00:23 e130 kernel: [46285.599932] Disabling non-boot CPUs ... Jan 22 05:00:23 e130 kernel: [46285.600444] Broke affinity for irq 16 Jan 22 05:00:23 e130 kernel: [46285.600453] Broke affinity for irq 23 Jan 22 05:00:23 e130 kernel: [46285.600459] Broke affinity for irq 30 Jan 22 05:00:23 e130 kernel: [46285.601795] smpboot: CPU 1 is now offline Jan 22 05:00:23 e130 kernel: [46285.603069] Broke affinity for irq 16 Jan 22 05:00:23 e130 kernel: [46285.603083] Broke affinity for irq 23 Jan 22 05:00:23 e130 kernel: [46285.603093] Broke affinity for irq 26 Jan 22 05:00:23 e130 kernel: [46285.603101] Broke affinity for irq 30 Jan 22 05:00:23 e130 kernel: [46285.604854] smpboot: CPU 2 is now offline Jan 22 05:00:23 e130 kernel: [46285.605936] Broke affinity for irq 1 Jan 22 05:00:23 e130 kernel: [46285.605947] Broke affinity for irq 9 Jan 22 05:00:23 e130 kernel: [46285.605953] Broke affinity for irq 12 Jan 22 05:00:23 e130 kernel: [46285.605960] Broke affinity for irq 16 Jan 22 05:00:23 e130 kernel: [46285.605971] Broke affinity for irq 23 Jan 22 05:00:23 e130 kernel: [46285.605979] Broke affinity for irq 26 Jan 22 05:00:23 e130 kernel: [46285.605983] Broke affinity for irq 27 Jan 22 05:00:23 e130 kernel: [46285.605986] Broke affinity for irq 28 Jan 22 05:00:23 e130 kernel: [46285.605989] Broke affinity for irq 30 Jan 22 05:00:23 e130 kernel: [46285.607020] smpboot: CPU 3 is now offline Jan 22 05:00:23 e130 kernel: [46285.609517] ACPI: Low-level resume complete Jan 22 05:00:23 e130 kernel: [46285.609582] ACPI : EC: EC started Jan 22 05:00:23 e130 kernel: [46285.609582] PM: Restoring platform NVS memory Jan 22 05:00:23 e130 kernel: [46285.609890] Suspended for 18880.745 seconds Jan 22 05:00:23 e130 kernel: [46285.609975] Enabling non-boot CPUs ... This is my /proc/acpi/wakeup: Device S-state Status Sysfs node P0P1 S4 *disabled EHC1 S3 *enabled pci:0000:00:1d.0 EHC2 S3 *disabled pci:0000:00:1a.0 XHC S3 *enabled pci:0000:00:14.0 HDEF S4 *disabled pci:0000:00:1b.0 RP04 S4 *disabled RP06 S4 *disabled pci:0000:00:1c.5 PXSX S4 *disabled pci:0000:09:00.0 BLAN S4 *disabled PXSX S4 *disabled PEG0 S4 *disabled PEGP S4 *disabled PEG1 S4 *disabled PEG2 S4 *disabled PEG3 S4 *disabled LID S4 *enabled platform:PNP0C0D:00 $ uname -a Linux e130 4.9.0-11-amd64 #1 SMP Debian 4.9.189-3+deb9u1 (2019-09-20) x86_64 GNU/Linux Any help will be appreciated.
