Something wakes my laptop from suspend to ram and I don't know what it is
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.
Reply to: