Ubuntu
linux package

Battery drain during sleep. System suspended before kernel suspends all tasks

Bug #1825636 reported by sknzl on 2019-04-20

378

This bug affects 24 people

Affects		Status	Importance	Assigned to	Milestone
	linux (Ubuntu)	Expired	Undecided	Unassigned

Bug Description

Description: Ubuntu 18.04.2 LTS
Release: 18.04

Expected behaviour:
No battery drain during suspend.

Ubuntu 18.04 running on a Thinkpad L390 has battery drain during suspend (~10% in 5 hours) in. I excluded wake ups during sleep and checking the logs I have realized that the kernel suspends too fast, before the full system is put to sleep.

Here are the syslogs after initiating the suspend mode:

Apr 19 11:59:53 ThinkPad-L390 systemd[1]: Starting TLP suspend/resume...
Apr 19 11:59:53 ThinkPad-L390 systemd[1]: Started TLP suspend/resume.
Apr 19 11:59:53 ThinkPad-L390 systemd[1]: Reached target Sleep.
Apr 19 11:59:53 ThinkPad-L390 systemd[1]: Starting Suspend...
Apr 19 11:59:53 ThinkPad-L390 kernel: [15459.897941] PM: suspend entry (deep)
Apr 19 11:59:53 ThinkPad-L390 systemd-sleep[28261]: Suspending system...
Apr 19 11:59:53 ThinkPad-L390 kernel: [15459.897944] PM: Syncing filesystems ... done.

And here the syslogs directly after waking up:

Apr 19 12:37:50 ThinkPad-L390 kernel: [15459.906928] Freezing user space processes ... (elapsed 0.004 seconds) done.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15459.910995] OOM killer disabled.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15459.910996] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15459.912868] Suspending console(s) (use no_console_suspend to debug)
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.032873] e1000e: EEE TX LPI TIMER: 00000011
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.204567] ACPI: EC: interrupt blocked
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.243401] ACPI: Preparing to enter system sleep state S3
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.249397] ACPI: EC: event blocked
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.249398] ACPI: EC: EC stopped
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.249399] PM: Saving platform NVS memory
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.249432] Disabling non-boot CPUs ...
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.263401] irq_migrate_all_off_this_cpu: 5 callbacks suppressed
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.263403] IRQ 141: no longer affine to CPU1
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.264445] smpboot: CPU 1 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.296469] smpboot: CPU 2 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.320478] smpboot: CPU 3 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.343319] IRQ 149: no longer affine to CPU4
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.345025] smpboot: CPU 4 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.375279] IRQ 17: no longer affine to CPU5
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.375285] IRQ 18: no longer affine to CPU5
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.375298] IRQ 148: no longer affine to CPU5
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.376311] smpboot: CPU 5 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.399234] IRQ 124: no longer affine to CPU6
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.400265] smpboot: CPU 6 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.423211] IRQ 123: no longer affine to CPU7
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.423222] IRQ 139: no longer affine to CPU7
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.423228] IRQ 140: no longer affine to CPU7
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.423235] IRQ 142: no longer affine to CPU7
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.424278] smpboot: CPU 7 is now offline
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.428456] ACPI: Low-level resume complete
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.428541] ACPI: EC: EC started
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.428541] PM: Restoring platform NVS memory
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429356] Enabling non-boot CPUs ...
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429403] x86: Booting SMP configuration:
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429404] smpboot: Booting Node 0 Processor 1 APIC 0x2
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429761] cache: parent cpu1 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429898] CPU1 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429929] smpboot: Booting Node 0 Processor 2 APIC 0x4
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.430294] cache: parent cpu2 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.430439] CPU2 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.430461] smpboot: Booting Node 0 Processor 3 APIC 0x6
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.430822] cache: parent cpu3 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.430978] CPU3 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.430999] smpboot: Booting Node 0 Processor 4 APIC 0x1
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.431434] cache: parent cpu4 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.431601] CPU4 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.431624] smpboot: Booting Node 0 Processor 5 APIC 0x3
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.431995] cache: parent cpu5 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.432165] CPU5 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.432187] smpboot: Booting Node 0 Processor 6 APIC 0x5
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.432563] cache: parent cpu6 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.432743] CPU6 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.432764] smpboot: Booting Node 0 Processor 7 APIC 0x7
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.433143] cache: parent cpu7 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.433337] CPU7 is up
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.438599] ACPI: Waking up from system sleep state S3
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.456686] ACPI: EC: interrupt unblocked
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.501513] ACPI: EC: event unblocked
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.502094] iwlwifi 0000:00:14.3: RF_KILL bit toggled to enable radio.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.716012] nvme nvme0: Shutdown timeout set to 8 seconds
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.735618] usb 1-5: reset high-speed USB device number 3 using xhci_hcd
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.011609] usb 1-7: reset full-speed USB device number 5 using xhci_hcd
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.287637] usb 1-10: reset full-speed USB device number 7 using xhci_hcd
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.440864] acpi LNXPOWER:05: Turning OFF
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.441846] OOM killer enabled.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.441847] Restarting tasks ...
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.443317] Bluetooth: hci0: Bootloader revision 0.1 build 42 week 52 2015
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444825] Bluetooth: hci0: Device revision is 2
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444830] Bluetooth: hci0: Secure boot is enabled
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444832] Bluetooth: hci0: OTP lock is enabled
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444833] Bluetooth: hci0: API lock is enabled
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444835] Bluetooth: hci0: Debug lock is disabled
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444838] Bluetooth: hci0: Minimum firmware build 1 week 10 2014
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.444845] Bluetooth: hci0: Found device firmware: intel/ibt-17-16-1.sfi
Apr 19 12:37:50 ThinkPad-L390 bluetoothd[951]: Endpoint unregistered: sender=:1.82 path=/MediaEndpoint/A2DPSource
Apr 19 12:37:50 ThinkPad-L390 bluetoothd[951]: Endpoint unregistered: sender=:1.82 path=/MediaEndpoint/A2DPSink
Apr 19 12:37:50 ThinkPad-L390 NetworkManager[1008]: <info> [1555673870.0221] bluez5: NAP: removed interface 48:F1:7F:83:35:C8
Apr 19 12:37:50 ThinkPad-L390 systemd-sleep[28261]: System resumed.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.451251] done.
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.451504] thermal thermal_zone7: failed to read out thermal zone (-61)
Apr 19 12:37:50 ThinkPad-L390 kernel: [15461.452657] PM: suspend exit
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: Started Suspend.
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: Stopped target Sleep.
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: Stopping TLP suspend/resume...
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: Reached target Suspend.
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: suspend.target: Unit not needed anymore. Stopping.
Apr 19 12:37:50 ThinkPad-L390 systemd[1]: Stopped target Suspend.

Note: Between the two syslog extracts are no further events.
As it can be seen in the logs, the CPU cores are only put the sleep after the suspend is finished, which leads to the messages like:

Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429761] cache: parent cpu1 should not be sleeping
Apr 19 12:37:50 ThinkPad-L390 kernel: [15460.429898] CPU1 is up

I reported this problem also here:
https://askubuntu.com/questions/1135240/battery-drain-when-sleeping-suspend-too-fast

Also it was already reported before here:
https://askubuntu.com/questions/828486/kernel-suspends-too-quickly-upon-resume-continues-suspend-tasks

https://askubuntu.com/questions/1072066/sleep-mode-drains-battery-very-fast

ProblemType: Bug
DistroRelease: Ubuntu 18.04
Package: linux-image-4.18.0-17-generic 4.18.0-17.18~18.04.1
ProcVersionSignature: Ubuntu 4.18.0-17.18~18.04.1-generic 4.18.20
Uname: Linux 4.18.0-17-generic x86_64
ApportVersion: 2.20.9-0ubuntu7.6
Architecture: amd64
CurrentDesktop: ubuntu:GNOME
Date: Sat Apr 20 12:34:18 2019
InstallationDate: Installed on 2019-03-30 (20 days ago)
InstallationMedia: Ubuntu 18.04.2 LTS "Bionic Beaver" - Release amd64 (20190210)
SourcePackage: linux-signed-hwe
UpgradeStatus: No upgrade log present (probably fresh install)

Tags:

Revision history for this message

sknzl (sknzl) wrote on 2019-04-20:

Dependencies.txt Edit (2.6 KiB, text/plain; charset="utf-8")
ProcCpuinfoMinimal.txt Edit (1.2 KiB, text/plain; charset="utf-8")
ProcEnviron.txt Edit (334 bytes, text/plain; charset="utf-8")

sknzl (sknzl) on 2019-04-20

summary:

- Battery drain during sleep. System suspended before kernel suspended all
+ Battery drain during sleep. System suspended before kernel suspends all
tasks

Revision history for this message

Launchpad Janitor (janitor) wrote on 2019-04-20:

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in linux-signed-hwe (Ubuntu):
status:	New → Confirmed

Revision history for this message

WinEunuchs2Unix (ricklee518) wrote on 2019-04-20:

Download full text (3.3 KiB)

Same problem has existed for years on Ubuntu 16.04 with systemd and TLP in use. pm-utils is installed by default but for the most part inactive (last log entry Oct 28 2018).

OP already reported this Ask Ubuntu Q&A link posted 2 1/2 years ago:
- https://askubuntu.com/questions/828486/kernel-suspends-too-quickly-upon-resume-continues-suspend-tasks

(The answer receiving bounty award does not work and has not been accepted!)

======================================================================

An ACPI linked question of the same "suspend to RAM too quickly" issue can be found here:
- https://unix.stackexchange.com/questions/502235/system-fails-to-suspend-acpi-ec-interrupt-blocked

There is an old ACPI bug report which I understand is because Manufacturers hacked the specs to please Microsoft and Linux doesn't work well during boot up. I don't think ACPI is related but just in case here is a refresher on the error messages during boot:

ACPI Error: [\_SB_.PCI0.XHC_.RHUB.HS11] Namespace lookup failure, AE_NOT_FOUND (20170728/dswload-210)
Apr 04 21:05:29 alien kernel: ACPI Exception: AE_NOT_FOUND, During name lookup/catalog (20170728/psobject-252)
Apr 04 21:05:29 alien kernel: ACPI Exception: AE_NOT_FOUND, (SSDT:xh_rvp10) while loading table (20170728/tbxfload-228)
Apr 04 21:05:29 alien kernel: ACPI Error: 1 table load failures, 9 successful (20170728/tbxfload-246)

========================================================================

My log is similar to OP except that systemd shows it's fingerprints in the suspend/freeze process:

Apr 20 01:42:39 alien systemd-sleep[32388]: /lib/systemd/system-sleep/r8169-reset failed wit
Apr 20 01:42:39 alien systemd-sleep[32387]: Suspending system...
-- Subject: System sleep state suspend entered
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- The system has now entered the suspend sleep state.
Apr 20 01:42:39 alien kernel: PM: suspend entry (deep)
Apr 20 01:42:39 alien kernel: PM: Syncing filesystems ... done.
Apr 20 08:57:10 alien kernel: Freezing user space processes ... (elapsed 0.016 seconds) done
Apr 20 08:57:10 alien kernel: OOM killer disabled.
Apr 20 08:57:10 alien kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds)
Apr 20 08:57:10 alien kernel: Suspending console(s) (use no_console_suspend to debug)
Apr 20 08:57:10 alien kernel: sd 1:0:0:0: [sda] Synchronizing SCSI cache
Apr 20 08:57:10 alien kernel: sd 1:0:0:0: [sda] Stopping disk
Apr 20 08:57:10 alien kernel: ACPI: EC: interrupt blocked
Apr 20 08:57:10 alien kernel: ACPI: Preparing to enter system sleep state S3
Apr 20 08:57:10 alien kernel: ACPI: EC: event blocked
Apr 20 08:57:10 alien kernel: ACPI: EC: EC stopped
Apr 20 08:57:10 alien kernel: PM: Saving platform NVS memory
Apr 20 08:57:10 alien kernel: Disabling non-boot CPUs ...
Apr 20 08:57:10 alien kernel: smpboot: CPU 1 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 2 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 3 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 4 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 5 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU ...

Same problem has existed for years on Ubuntu 16.04 with systemd and TLP in use. pm-utils is installed by default but for the most part inactive (last log entry Oct 28 2018).

OP already reported this Ask Ubuntu Q&A link posted 2 1/2 years ago:
- https://askubuntu.com/questions/828486/kernel-suspends-too-quickly-upon-resume-continues-suspend-tasks

(The answer receiving bounty award does not work and has not been accepted!)

======================================================================

An ACPI linked question of the same "suspend to RAM too quickly" issue can be found here:
- https://unix.stackexchange.com/questions/502235/system-fails-to-suspend-acpi-ec-interrupt-blocked

========================================================================

My log is similar to OP except that systemd shows it's fingerprints in the suspend/freeze process:

Apr 20 01:42:39 alien systemd-sleep[32388]: /lib/systemd/system-sleep/r8169-reset failed wit
Apr 20 01:42:39 alien systemd-sleep[32387]: Suspending system...
-- Subject: System sleep state suspend entered
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- The system has now entered the suspend sleep state.
Apr 20 01:42:39 alien kernel: PM: suspend entry (deep)
Apr 20 01:42:39 alien kernel: PM: Syncing filesystems ... done.
Apr 20 08:57:10 alien kernel: Freezing user space processes ... (elapsed 0.016 seconds) done
Apr 20 08:57:10 alien kernel: OOM killer disabled.
Apr 20 08:57:10 alien kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds)
Apr 20 08:57:10 alien kernel: Suspending console(s) (use no_console_suspend to debug)
Apr 20 08:57:10 alien kernel: sd 1:0:0:0: [sda] Synchronizing SCSI cache
Apr 20 08:57:10 alien kernel: sd 1:0:0:0: [sda] Stopping disk
Apr 20 08:57:10 alien kernel: ACPI: EC: interrupt blocked
Apr 20 08:57:10 alien kernel: ACPI: Preparing to enter system sleep state S3
Apr 20 08:57:10 alien kernel: ACPI: EC: event blocked
Apr 20 08:57:10 alien kernel: ACPI: EC: EC stopped
Apr 20 08:57:10 alien kernel: PM: Saving platform NVS memory
Apr 20 08:57:10 alien kernel: Disabling non-boot CPUs ...
Apr 20 08:57:10 alien kernel: smpboot: CPU 1 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 2 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 3 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 4 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 5 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 6 is now offline
Apr 20 08:57:10 alien kernel: smpboot: CPU 7 is now offline
Apr 20 08:57:10 alien kernel: ACPI: Low-level resume complete

Revision history for this message

Melanie Bacou (mbacou) wrote on 2019-05-14:

Download full text (23.7 KiB)

Also affecting Thinkpad X201 with Ubuntu 18.10 and TLP.

May 13 03:07:06 drus kernel: [49224.602185] PM: suspend entry (deep)
May 13 03:07:26 drus kernel: [49224.602189] PM: Syncing filesystems ... done.
May 13 03:07:26 drus kernel: [49224.616324] Freezing user space processes ... (elapsed 0.064 seconds) done.
May 13 03:07:26 drus kernel: [49224.680481] OOM killer disabled.
May 13 03:07:26 drus kernel: [49224.680482] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
May 13 03:07:26 drus kernel: [49224.682257] Suspending console(s) (use no_console_suspend to debug)
May 13 03:07:26 drus kernel: [49224.696263] sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 13 03:07:26 drus kernel: [49224.696948] e1000e: EEE TX LPI TIMER: 00000000
May 13 03:07:26 drus kernel: [49224.698248] sd 0:0:0:0: [sda] Stopping disk
May 13 03:07:26 drus kernel: [49224.808448] ACPI: EC: interrupt blocked
May 13 03:07:26 drus kernel: [49224.828883] ACPI: Preparing to enter system sleep state S3
May 13 03:07:26 drus kernel: [49224.841137] ACPI: EC: event blocked
May 13 03:07:26 drus kernel: [49224.841139] ACPI: EC: EC stopped
May 13 03:07:26 drus kernel: [49224.841140] PM: Saving platform NVS memory
May 13 03:07:26 drus kernel: [49224.842744] Disabling non-boot CPUs ...
May 13 03:07:26 drus kernel: [49224.857652] smpboot: CPU 1 is now offline
May 13 03:07:26 drus kernel: [49224.880441] IRQ 28: no longer affine to CPU2
May 13 03:07:26 drus kernel: [49224.881475] smpboot: CPU 2 is now offline
May 13 03:07:26 drus kernel: [49224.908229] IRQ 19: no longer affine to CPU3
May 13 03:07:26 drus kernel: [49224.909244] smpboot: CPU 3 is now offline
May 13 03:07:26 drus kernel: [49224.913129] ACPI: Low-level resume complete
May 13 03:07:26 drus kernel: [49224.913187] ACPI: EC: EC started
May 13 03:07:26 drus kernel: [49224.913188] PM: Restoring platform NVS memory
May 13 03:07:26 drus kernel: [49224.914427] Enabling non-boot CPUs ...
May 13 03:07:26 drus kernel: [49224.914548] x86: Booting SMP configuration:
May 13 03:07:26 drus kernel: [49224.914549] smpboot: Booting Node 0 Processor 1 APIC 0x1
May 13 03:07:26 drus kernel: [49224.916952]  cache: parent cpu1 should not be sleeping
May 13 03:07:26 drus kernel: [49224.921154] CPU1 is up
May 13 03:07:26 drus kernel: [49224.921202] smpboot: Booting Node 0 Processor 2 APIC 0x4
May 13 03:07:26 drus kernel: [49224.923903]  cache: parent cpu2 should not be sleeping
May 13 03:07:26 drus kernel: [49224.927941] CPU2 is up
May 13 03:07:26 drus kernel: [49224.927989] smpboot: Booting Node 0 Processor 3 APIC 0x5
May 13 03:07:26 drus kernel: [49224.930404]  cache: parent cpu3 should not be sleeping
May 13 03:07:26 drus kernel: [49224.934753] CPU3 is up
May 13 03:07:26 drus kernel: [49224.937580] ACPI: Waking up from system sleep state S3
May 13 03:07:26 drus kernel: [49224.957732] ACPI: EC: interrupt unblocked
May 13 03:07:26 drus kernel: [49225.012193] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
May 13 03:07:26 drus kernel: [49225.012216] ACPI: EC: event unblocked
May 13 03:07:26 drus kernel: [49225.013748] sd 0:0:0:0: [sda] Starting disk
May 13 03:07:26 drus kernel: [49225.178231] OOM killer enabled.
May 13 03:07:26 drus kernel: [49225.178233] Restarting tasks ... done.
May 13 03:07:26 drus kernel: [49225.211063] video LNXVIDEO:00: Restoring backlight state
May 13 03:07:26 drus kernel: [49225.211840] PM: suspend exit
May 13 03:07:26 drus kernel: [49225.265420] usb 1-1.6: new high-speed USB device number 39 using ehci-pci
May 13 03:07:26 drus kernel: [49225.359689] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
May 13 03:07:26 drus kernel: [49225.359722] ata6: SATA link down (SStatus 0 SControl 300)
May 13 03:07:26 drus kernel: [49225.359760] ata2: SATA link down (SStatus 0 SControl 300)
May 13 03:07:26 drus kernel: [49225.359887] ata5: SATA link down (SStatus 0 SControl 300)
May 13 03:07:26 drus kernel: [49225.360454] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
May 13 03:07:26 drus kernel: [49225.360457] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
May 13 03:07:26 drus kernel: [49225.360602] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) rejected by device (Stat=0x51 Err=0x04)
May 13 03:07:26 drus kernel: [49225.360604] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
May 13 03:07:26 drus kernel: [49225.363216] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
May 13 03:07:26 drus kernel: [49225.363221] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
May 13 03:07:26 drus kernel: [49225.363364] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) rejected by device (Stat=0x51 Err=0x04)
May 13 03:07:26 drus kernel: [49225.363367] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
May 13 03:07:26 drus kernel: [49225.365883] ata1.00: configured for UDMA/133
May 13 03:07:26 drus kernel: [49225.391000] usb 1-1.6: New USB device found, idVendor=17ef, idProduct=480c, bcdDevice=31.34
May 13 03:07:26 drus kernel: [49225.391003] usb 1-1.6: New USB device strings: Mfr=1, Product=0, SerialNumber=0
May 13 03:07:26 drus kernel: [49225.391005] usb 1-1.6: Manufacturer: Chicony Electronics Co., Ltd.
May 13 03:07:26 drus kernel: [49225.392629] uvcvideo: Found UVC 1.00 device <unnamed> (17ef:480c)
May 13 03:07:26 drus kernel: [49225.397498] uvcvideo 1-1.6:1.0: Entity type for entity Processing 2 was not initialized!
May 13 03:07:26 drus kernel: [49225.397501] uvcvideo 1-1.6:1.0: Entity type for entity Camera 1 was not initialized!
May 13 03:07:26 drus kernel: [49225.397610] input: UVC Camera (17ef:480c): Integra as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.0/input/input25
May 13 03:07:26 drus kernel: [49225.428468] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
May 13 03:07:26 drus kernel: [49225.637865] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
May 13 03:07:26 drus kernel: [49225.645947] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
May 13 03:07:28 drus kernel: [49227.187859] e1000e: enp0s25 NIC Link is Up 100 Mbps Full Duplex, Flow Control: Rx
May 13 03:07:28 drus kernel: [49227.187866] e1000e 0000:00:19.0 enp0s25: 10/100 speed: disabling TSO
May 13 03:07:28 drus kernel: [49227.187923] IPv6: ADDRCONF(NETDEV_CHANGE): enp0s25: link becomes ready
May 13 03:07:42 drus kernel: [49240.926522] e1000e: enp0s25 NIC Link is Down
May 13 03:07:42 drus kernel: [49241.060523] PM: suspend entry (deep)
May 13 03:07:42 drus kernel: [49241.060527] PM: Syncing filesystems ... done.
May 13 12:09:25 drus kernel: [49241.085449] Freezing user space processes ... (elapsed 0.008 seconds) done.
May 13 12:09:25 drus kernel: [49241.093540] OOM killer disabled.
May 13 12:09:25 drus kernel: [49241.093541] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
May 13 12:09:25 drus kernel: [49241.095236] Suspending console(s) (use no_console_suspend to debug)
May 13 12:09:25 drus kernel: [49241.108042] e1000e: EEE TX LPI TIMER: 00000000
May 13 12:09:25 drus kernel: [49241.121639] sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 13 12:09:25 drus kernel: [49241.122832] sd 0:0:0:0: [sda] Stopping disk
May 13 12:09:25 drus kernel: [49241.221776] ACPI: EC: interrupt blocked
May 13 12:09:25 drus kernel: [49241.242249] ACPI: Preparing to enter system sleep state S3
May 13 12:09:25 drus kernel: [49241.254109] ACPI: EC: event blocked
May 13 12:09:25 drus kernel: [49241.254110] ACPI: EC: EC stopped
May 13 12:09:25 drus kernel: [49241.254111] PM: Saving platform NVS memory
May 13 12:09:25 drus kernel: [49241.255211] Disabling non-boot CPUs ...
May 13 12:09:25 drus kernel: [49241.270869] smpboot: CPU 1 is now offline
May 13 12:09:25 drus kernel: [49241.294717] smpboot: CPU 2 is now offline
May 13 12:09:25 drus kernel: [49241.318562] smpboot: CPU 3 is now offline
May 13 12:09:25 drus kernel: [49241.321240] ACPI: Low-level resume complete
May 13 12:09:25 drus kernel: [49241.321300] ACPI: EC: EC started
May 13 12:09:25 drus kernel: [49241.321300] PM: Restoring platform NVS memory
May 13 12:09:25 drus kernel: [49241.322543] Enabling non-boot CPUs ...
May 13 12:09:25 drus kernel: [49241.322666] x86: Booting SMP configuration:
May 13 12:09:25 drus kernel: [49241.322667] smpboot: Booting Node 0 Processor 1 APIC 0x1
May 13 12:09:25 drus kernel: [49241.325107]  cache: parent cpu1 should not be sleeping
May 13 12:09:25 drus kernel: [49241.327186] CPU1 is up
May 13 12:09:25 drus kernel: [49241.327236] smpboot: Booting Node 0 Processor 2 APIC 0x4
May 13 12:09:25 drus kernel: [49241.329936]  cache: parent cpu2 should not be sleeping
May 13 12:09:25 drus kernel: [49241.331998] CPU2 is up
May 13 12:09:25 drus kernel: [49241.332043] smpboot: Booting Node 0 Processor 3 APIC 0x5
May 13 12:09:25 drus kernel: [49241.334453]  cache: parent cpu3 should not be sleeping
May 13 12:09:25 drus kernel: [49241.336524] CPU3 is up
May 13 12:09:25 drus kernel: [49241.339291] ACPI: Waking up from system sleep state S3
May 13 12:09:25 drus kernel: [49241.359097] ACPI: EC: interrupt unblocked
May 13 12:09:25 drus kernel: [49241.413639] sd 0:0:0:0: [sda] Starting disk
May 13 12:09:25 drus kernel: [49241.415288] ACPI: EC: event unblocked
May 13 12:09:25 drus kernel: [49241.415441] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
May 13 12:09:25 drus kernel: [49241.658890] usb 1-1.6: reset high-speed USB device number 39 using ehci-pci
May 13 12:09:25 drus kernel: [49241.765790] ata5: SATA link down (SStatus 0 SControl 300)
May 13 12:09:25 drus kernel: [49241.765881] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
May 13 12:09:25 drus kernel: [49241.765915] ata2: SATA link down (SStatus 0 SControl 300)
May 13 12:09:25 drus kernel: [49241.765948] ata6: SATA link down (SStatus 0 SControl 300)
May 13 12:09:25 drus kernel: [49241.768940] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
May 13 12:09:25 drus kernel: [49241.768945] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
May 13 12:09:25 drus kernel: [49241.769199] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) rejected by device (Stat=0x51 Err=0x04)
May 13 12:09:25 drus kernel: [49241.769203] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
May 13 12:09:25 drus kernel: [49241.772228] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
May 13 12:09:25 drus kernel: [49241.772232] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
May 13 12:09:25 drus kernel: [49241.772472] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) rejected by device (Stat=0x51 Err=0x04)
May 13 12:09:25 drus kernel: [49241.772477] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
May 13 12:09:25 drus kernel: [49241.775281] ata1.00: configured for UDMA/133
May 13 12:09:25 drus kernel: [49241.786455] OOM killer enabled.
May 13 12:09:25 drus kernel: [49241.786456] Restarting tasks ... done.
May 13 12:09:25 drus kernel: [49241.826935] video LNXVIDEO:00: Restoring backlight state
May 13 12:09:25 drus kernel: [49241.827664] PM: suspend exit
May 13 12:09:25 drus kernel: [49241.870788] usb 1-1.1: new low-speed USB device number 40 using ehci-pci
May 13 12:09:25 drus kernel: [49241.987872] usb 1-1.1: New USB device found, idVendor=045e, idProduct=0040, bcdDevice= 3.00
May 13 12:09:25 drus kernel: [49241.987876] usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
May 13 12:09:25 drus kernel: [49241.987878] usb 1-1.1: Product: Microsoft 3-Button Mouse with IntelliEye(TM)
May 13 12:09:25 drus kernel: [49241.987879] usb 1-1.1: Manufacturer: Microsoft
May 13 12:09:25 drus kernel: [49241.991029] input: Microsoft Microsoft 3-Button Mouse with IntelliEye(TM) as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.1/1-1.1:1.0/0003:045E:0040.0004/input/input26
May 13 12:09:25 drus kernel: [49241.991200] hid-generic 0003:045E:0040.0004: input,hidraw0: USB HID v1.10 Mouse [Microsoft Microsoft 3-Button Mouse with IntelliEye(TM)] on usb-0000:00:1a.0-1.1/input0
May 13 12:09:25 drus kernel: [49242.067604] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
May 13 12:09:25 drus kernel: [49242.275167] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
May 13 12:09:25 drus kernel: [49242.282715] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
May 13 12:09:27 drus kernel: [49243.841325] e1000e: enp0s25 NIC Link is Up 100 Mbps Full Duplex, Flow Control: Rx
May 13 12:09:27 drus kernel: [49243.841333] e1000e 0000:00:19.0 enp0s25: 10/100 speed: disabling TSO
May 13 12:09:27 drus kernel: [49243.841389] IPv6: ADDRCONF(NETDEV_CHANGE): enp0s25: link becomes ready
May 13 12:10:51 drus kernel: [49327.817905] usb 2-1.2: new full-speed USB device number 5 using ehci-pci
May 13 12:10:51 drus kernel: [49328.141434] usb 2-1.2: New USB device found, idVendor=0b0e, idProduct=0347, bcdDevice= 1.27
May 13 12:10:51 drus kernel: [49328.141437] usb 2-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
May 13 12:10:51 drus kernel: [49328.141439] usb 2-1.2: Product: Jabra UC VOICE 250a
May 13 12:10:51 drus kernel: [49328.141440] usb 2-1.2: Manufacturer: Jabra
May 13 12:10:51 drus kernel: [49328.141441] usb 2-1.2: SerialNumber: 000000000000
May 13 12:10:52 drus kernel: [49328.854837] input: Jabra Jabra UC VOICE 250a as /devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2/2-1.2:1.3/0003:0B0E:0347.0005/input/input27
May 13 12:10:52 drus kernel: [49328.900230] usb 2-1.2: Warning! Unlikely big volume range (=4096), cval->res is probably wrong.
May 13 12:10:52 drus kernel: [49328.900237] usb 2-1.2: [11] FU [Sidetone Playback Volume] ch = 1, val = 0/4096/1
May 13 12:10:52 drus kernel: [49328.905629] usbcore: registered new interface driver snd-usb-audio
May 13 12:10:52 drus kernel: [49328.914693] jabra 0003:0B0E:0347.0005: input,hiddev0,hidraw1: USB HID v1.00 Device [Jabra Jabra UC VOICE 250a] on usb-0000:00:1d.0-1.2/input3
May 13 12:10:52 drus kernel: [49329.042034] usb 2-1.2: 2:1: cannot get freq at ep 0x1
May 13 12:10:57 drus kernel: [49334.179000] usb 1-1.6: reset high-speed USB device number 39 using ehci-pci
May 13 12:10:57 drus kernel: [49334.257898] usb 1-1.6: device descriptor read/64, error -32
May 13 12:47:03 drus kernel: [51500.021225] retire_capture_urb: 17 callbacks suppressed
May 13 12:47:04 drus kernel: [51500.326130] usb 2-1.2: USB disconnect, device number 5
May 13 12:47:09 drus kernel: [51505.963286] usb 1-1.6: reset high-speed USB device number 39 using ehci-pci
May 13 12:47:12 drus kernel: [51509.063721] usb 1-1.6: USB disconnect, device number 39
May 13 17:24:23 drus kernel: [68139.127430] e1000e: enp0s25 NIC Link is Down
May 13 17:24:23 drus kernel: [68139.264511] PM: suspend entry (deep)
May 13 17:59:40 drus kernel: [68139.264515] PM: Syncing filesystems ... done.
May 13 17:59:40 drus kernel: [68139.277769] Freezing user space processes ... (elapsed 0.003 seconds) done.
May 13 17:59:40 drus kernel: [68139.281068] OOM killer disabled.
May 13 17:59:40 drus kernel: [68139.281069] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
May 13 17:59:40 drus kernel: [68139.282916] Suspending console(s) (use no_console_suspend to debug)
May 13 17:59:40 drus kernel: [68139.300568] e1000e: EEE TX LPI TIMER: 00000000
May 13 17:59:40 drus kernel: [68139.302430] sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 13 17:59:40 drus kernel: [68139.303955] sd 0:0:0:0: [sda] Stopping disk
May 13 17:59:40 drus kernel: [68139.410944] ACPI: EC: interrupt blocked
May 13 17:59:40 drus kernel: [68139.431400] ACPI: Preparing to enter system sleep state S3
May 13 17:59:40 drus kernel: [68139.443076] ACPI: EC: event blocked
May 13 17:59:40 drus kernel: [68139.443077] ACPI: EC: EC stopped
May 13 17:59:40 drus kernel: [68139.443077] PM: Saving platform NVS memory
May 13 17:59:40 drus kernel: [68139.444031] Disabling non-boot CPUs ...
May 13 17:59:40 drus kernel: [68139.459990] smpboot: CPU 1 is now offline
May 13 17:59:40 drus kernel: [68139.482796] IRQ 28: no longer affine to CPU2
May 13 17:59:40 drus kernel: [68139.483997] smpboot: CPU 2 is now offline
May 13 17:59:40 drus kernel: [68139.514698] IRQ 19: no longer affine to CPU3
May 13 17:59:40 drus kernel: [68139.514703] IRQ 23: no longer affine to CPU3
May 13 17:59:40 drus kernel: [68139.515717] smpboot: CPU 3 is now offline
May 13 17:59:40 drus kernel: [68139.518871] ACPI: Low-level resume complete
May 13 17:59:40 drus kernel: [68139.518929] ACPI: EC: EC started
May 13 17:59:40 drus kernel: [68139.518929] PM: Restoring platform NVS memory
May 13 17:59:40 drus kernel: [68139.520227] Enabling non-boot CPUs ...
May 13 17:59:40 drus kernel: [68139.520346] x86: Booting SMP configuration:
May 13 17:59:40 drus kernel: [68139.520347] smpboot: Booting Node 0 Processor 1 APIC 0x1
May 13 17:59:40 drus kernel: [68139.522738]  cache: parent cpu1 should not be sleeping
May 13 17:59:40 drus kernel: [68139.524975] CPU1 is up
May 13 17:59:40 drus kernel: [68139.525026] smpboot: Booting Node 0 Processor 2 APIC 0x4
May 13 17:59:40 drus kernel: [68139.527709]  cache: parent cpu2 should not be sleeping
May 13 17:59:40 drus kernel: [68139.530087] CPU2 is up
May 13 17:59:40 drus kernel: [68139.530156] smpboot: Booting Node 0 Processor 3 APIC 0x5
May 13 17:59:40 drus kernel: [68139.532556]  cache: parent cpu3 should not be sleeping
May 13 17:59:40 drus kernel: [68139.534812] CPU3 is up
May 13 17:59:40 drus kernel: [68139.537593] ACPI: Waking up from system sleep state S3
May 13 17:59:40 drus kernel: [68139.557519] ACPI: EC: interrupt unblocked
May 13 17:59:40 drus kernel: [68139.610956] iwlwifi 0000:02:00.0: RF_KILL bit toggled to disable radio.
May 13 17:59:40 drus kernel: [68139.611164] ACPI: EC: event unblocked
May 13 17:59:40 drus kernel: [68139.611953] sd 0:0:0:0: [sda] Starting disk
May 13 17:59:40 drus kernel: [68139.777511] OOM killer enabled.
May 13 17:59:40 drus kernel: [68139.777512] Restarting tasks ... done.
May 13 17:59:40 drus kernel: [68139.810143] video LNXVIDEO:00: Restoring backlight state
May 13 17:59:40 drus kernel: [68139.810780] PM: suspend exit
May 13 17:59:40 drus kernel: [68139.863986] usb 1-1.6: new high-speed USB device number 41 using ehci-pci
May 13 17:59:40 drus kernel: [68139.962509] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
May 13 17:59:40 drus kernel: [68139.962539] ata5: SATA link down (SStatus 0 SControl 300)
May 13 17:59:40 drus kernel: [68139.962571] ata2: SATA link down (SStatus 0 SControl 300)
May 13 17:59:40 drus kernel: [68139.963336] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
May 13 17:59:40 drus kernel: [68139.963340] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
May 13 17:59:40 drus kernel: [68139.963444] ata6: SATA link down (SStatus 0 SControl 300)
May 13 17:59:40 drus kernel: [68139.963486] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) rejected by device (Stat=0x51 Err=0x04)
May 13 17:59:40 drus kernel: [68139.963488] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
May 13 17:59:40 drus kernel: [68139.966546] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
May 13 17:59:40 drus kernel: [68139.966551] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
May 13 17:59:40 drus kernel: [68139.966696] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) rejected by device (Stat=0x51 Err=0x04)
May 13 17:59:40 drus kernel: [68139.966699] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
May 13 17:59:40 drus kernel: [68139.969292] ata1.00: configured for UDMA/133
May 13 17:59:40 drus kernel: [68139.977764] usb 1-1.6: New USB device found, idVendor=17ef, idProduct=480c, bcdDevice=31.34
May 13 17:59:40 drus kernel: [68139.977767] usb 1-1.6: New USB device strings: Mfr=1, Product=0, SerialNumber=0
May 13 17:59:40 drus kernel: [68139.977769] usb 1-1.6: Manufacturer: Chicony Electronics Co., Ltd.
May 13 17:59:40 drus kernel: [68139.978768] uvcvideo: Found UVC 1.00 device <unnamed> (17ef:480c)
May 13 17:59:40 drus kernel: [68139.982186] uvcvideo 1-1.6:1.0: Entity type for entity Processing 2 was not initialized!
May 13 17:59:40 drus kernel: [68139.982190] uvcvideo 1-1.6:1.0: Entity type for entity Camera 1 was not initialized!
May 13 17:59:40 drus kernel: [68139.982301] input: UVC Camera (17ef:480c): Integra as /devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.6/1-1.6:1.0/input/input28
May 13 17:59:40 drus kernel: [68140.096834] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
May 13 17:59:40 drus kernel: [68140.304219] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
May 13 17:59:40 drus kernel: [68140.308995] IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
May 13 17:59:42 drus kernel: [68141.906389] e1000e: enp0s25 NIC Link is Up 100 Mbps Full Duplex, Flow Control: Rx
May 13 17:59:42 drus kernel: [68141.906395] e1000e 0000:00:19.0 enp0s25: 10/100 speed: disabling TSO
May 13 17:59:42 drus kernel: [68141.906451] IPv6: ADDRCONF(NETDEV_CHANGE): enp0s25: link becomes ready
May 13 18:23:13 drus kernel: [69553.187838] e1000e: enp0s25 NIC Link is Down
May 13 18:23:13 drus kernel: [69553.244821] usb 1-1.1: USB disconnect, device number 40
May 13 18:23:30 drus kernel: [69570.028275] e1000e: enp0s25 NIC Link is Down
May 13 18:23:30 drus kernel: [69570.438056] PM: suspend entry (deep)
May 13 21:08:58 drus kernel: [69570.438059] PM: Syncing filesystems ... done.
May 13 21:08:58 drus kernel: [69570.448041] Freezing user space processes ... (elapsed 0.002 seconds) done.
May 13 21:08:58 drus kernel: [69570.450983] OOM killer disabled.
May 13 21:08:58 drus kernel: [69570.450984] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
May 13 21:08:58 drus kernel: [69570.452250] Suspending console(s) (use no_console_suspend to debug)
May 13 21:08:58 drus kernel: [69570.465451] e1000e: EEE TX LPI TIMER: 00000000
May 13 21:08:58 drus kernel: [69570.475166] sd 0:0:0:0: [sda] Synchronizing SCSI cache
May 13 21:08:58 drus kernel: [69570.476292] sd 0:0:0:0: [sda] Stopping disk
May 13 21:08:58 drus kernel: [69570.579575] ACPI: EC: interrupt blocked
May 13 21:08:58 drus kernel: [69570.600052] ACPI: Preparing to enter system sleep state S3
May 13 21:08:58 drus kernel: [69570.611906] ACPI: EC: event blocked
May 13 21:08:58 drus kernel: [69570.611907] ACPI: EC: EC stopped
May 13 21:08:58 drus kernel: [69570.611908] PM: Saving platform NVS memory
May 13 21:08:58 drus kernel: [69570.612937] Disabling non-boot CPUs ...
May 13 21:08:58 drus kernel: [69570.628686] smpboot: CPU 1 is now offline
May 13 21:08:58 drus kernel: [69570.651492] IRQ 28: no longer affine to CPU2
May 13 21:08:58 drus kernel: [69570.652513] smpboot: CPU 2 is now offline
May 13 21:08:58 drus kernel: [69570.675364] IRQ 19: no longer affine to CPU3
May 13 21:08:58 drus kernel: [69570.675370] IRQ 23: no longer affine to CPU3
May 13 21:08:58 drus kernel: [69570.676382] smpboot: CPU 3 is now offline
May 13 21:08:58 drus kernel: [69570.679238] ACPI: Low-level resume complete
May 13 21:08:58 drus kernel: [69570.679295] ACPI: EC: EC started
May 13 21:08:58 drus kernel: [69570.679296] PM: Restoring platform NVS memory
May 13 21:08:58 drus kernel: [69570.680508] Enabling non-boot CPUs ...
May 13 21:08:58 drus kernel: [69570.680656] x86: Booting SMP configuration:
May 13 21:08:58 drus kernel: [69570.680657] smpboot: Booting Node 0 Processor 1 APIC 0x1
May 13 21:08:58 drus kernel: [69570.683056]  cache: parent cpu1 should not be sleeping
May 13 21:08:58 drus kernel: [69570.684226] CPU1 is up
May 13 21:08:58 drus kernel: [69570.684279] smpboot: Booting Node 0 Processor 2 APIC 0x4
May 13 21:08:58 drus kernel: [69570.686944]  cache: parent cpu2 should not be sleeping
May 13 21:08:58 drus kernel: [69570.688057] CPU2 is up
May 13 21:08:58 drus kernel: [69570.688102] smpboot: Booting Node 0 Processor 3 APIC 0x5
May 13 21:08:58 drus kernel: [69570.690496]  cache: parent cpu3 should not be sleeping
May 13 21:08:58 drus kernel: [69570.691672] CPU3 is up
May 13 21:08:58 drus kernel: [69570.694476] ACPI: Waking up from system sleep state S3
May 13 21:08:58 drus kernel: [69570.714192] ACPI: EC: interrupt unblocked
May 13 21:08:58 drus kernel: [69570.767418] ACPI: EC: event unblocked

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2019-06-01:

Same issue here. Suspend happens before user space is suspended.

On resume, system first continues to suspend, then resumes.

Jun 01 02:18:40 avis systemd[1]: Reached target Sleep.
Jun 01 02:18:40 avis systemd[1]: Starting Suspend...
Jun 01 02:18:40 avis systemd-sleep[27915]: Suspending system...
Jun 01 02:18:40 avis kernel: PM: suspend entry (s2idle)
Jun 01 02:18:40 avis kernel: PM: Syncing filesystems ... done.
Jun 01 12:03:59 avis kernel: Freezing user space processes ... (elapsed 0.215 seconds) done.
Jun 01 12:03:59 avis kernel: OOM killer disabled.
Jun 01 12:03:59 avis kernel: Freezing remaining freezable tasks ... (elapsed 0.002 seconds) done.
Jun 01 12:03:59 avis kernel: printk: Suspending console(s) (use no_console_suspend to debug)
Jun 01 12:03:59 avis kernel: wlp3s0: deauthenticating from cc:ce:1e:89:51:4b by local choice (Reason: 3=DEAUTH_LEAVING)
Jun 01 12:03:59 avis kernel: sd 2:0:0:0: [sda] Synchronizing SCSI cache
Jun 01 12:03:59 avis kernel: sd 2:0:0:0: [sda] Stopping disk
Jun 01 12:03:59 avis kernel: sd 2:0:0:0: [sda] Starting disk
Jun 01 12:03:59 avis kernel: OOM killer enabled.
Jun 01 12:03:59 avis wpa_supplicant[990]: wlp3s0: CTRL-EVENT-SIGNAL-CHANGE above=0 signal=0 noise=9999 txrate=0
Jun 01 12:03:59 avis systemd-logind[982]: Lid opened.
Jun 01 12:03:59 avis wpa_supplicant[990]: wlp3s0: CTRL-EVENT-DISCONNECTED bssid=cc:ce:1e:89:51:4b reason=3 locally_generated=1
Jun 01 12:03:59 avis kernel: Restarting tasks ... done.
Jun 01 12:03:59 avis NetworkManager[979]: <warn> [1559383439.4503] sup-iface[0x56503c5924b0,wlp3s0]: connection disconnected (reason -3)
Jun 01 12:03:59 avis systemd[1]: Started Run anacron jobs.
Jun 01 12:03:59 avis NetworkManager[979]: <info> [1559383439.4557] device (wlp3s0): supplicant interface s

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2019-06-03:

Since I was affected on Ubuntu 19.04 using the generic Kernel (5.0.0-15-generic), I am not sure if "linux-signed-hwe" is the right thing to file this bug against...

I decided to test Mainline 5.1.6 under Ubuntu 19.04, and it's also affected.

I have been looking into kernel/power/suspend.c, and if you trace down (as in: follow the code step by step) that suspend.c, it becomes obvious that it's not suspend.c by itself, as it's impossible to be a timing problem purely in there, as suspend.c *does* do things in order.

I am beginning to think that systemd might be doing things wrong.

Since I could also reproduce this issue with fedora, I will be testing on gentoo without systemd (using openrc) and tell you how this worked out.

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2019-06-03:

OK, so it appears to be within the kernel itself, this is also happening when using openRC.

Revision history for this message

sknzl (sknzl) wrote on 2019-06-03:

Thanks for your research and input!

I initially reported the bug running 'ubuntu-bug linux'. That's why it ended up in 'linux-signed-hwe'.

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2019-06-03:

Fun fact: sometimes "syncing filesystems" is done before the system actually suspends, sometimes it is done after waking the system up:

[...]
Jun 3 21:01:35 avis systemd[1]: Reached target Sleep.
Jun 3 21:01:35 avis systemd[1]: Starting Suspend...
Jun 3 21:01:35 avis systemd-sleep[2281]: Suspending system...
Jun 3 21:01:35 avis kernel: [ 86.149552] PM: suspend entry (s2idle)
------- laptop sleeps here -------
Jun 3 21:01:54 avis kernel: [ 86.149553] PM: Syncing filesystems ... done.
Jun 3 21:01:54 avis kernel: [ 86.163111] Freezing user space processes ... (elapsed 0.002 seconds) done.
Jun 3 21:01:54 avis kernel: [ 86.165328] OOM killer disabled.
Jun 3 21:01:54 avis kernel: [ 86.165329] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[...]

Revision history for this message

Aseem Arora (aseemarora1995) wrote on 2019-07-24:

#11

Facing the same issue elementary OS Juno which is based on Ubuntu 18.04. System suspends before kernel suspends all processes. Significant battery drain occurs.

Revision history for this message

Sascha R. (saschar) wrote on 2019-08-05:

#12

Same issue with Ubuntu 19.04 on Lenovo X1 Extreme (i7 6 core, Nvidia GPU with NVIDIA drivers).
Notebook gets warm/drains battery when in standby.
Log looks like some suspend tasks continue after waking up from standby.

Revision history for this message

Jonathan Polak (jpolak) wrote on 2019-08-19:

#13

Same issue with Ubuntu Mate 19.04 on Lenovo Thinkpad T460s.
Notebook gets warm and drains about 20% of the battery when in standby.

Revision history for this message

Sarowar Alam (sarowar-cse) wrote on 2019-09-24:

#14

Similar issue here with Ubuntu 19.10(5.3.0-10-generic) on Lenovo X1 Extreme Gen2 - i7-9750H, GTX 1650(nvidia-driver-435)

Yanko Malinov (malinov) on 2019-09-28

no longer affects:

debian

Revision history for this message

Yanko Malinov (malinov) wrote on 2019-09-30:

#15

Recently bought an L390 Thinkpad and noticed the battery drain when suspended. Checked the syslog and observed the same issue you've reported. Just tried it out on several other laptops, as well as a desktop lying around in the office. Can confirm that this issue affects all of them. Here's a list:

Lenovo Thinkpad L390 (non-Yoga) running Debian 10.1 (buster) with MATE on kernel 4.19.0-6-amd64
Lenovo Ideapad 500-15ISK running Debian 10.0 (buster) with MATE on kernel 4.19.0-5-amd64
Asus UX310U running Ubuntu 18.04.3 LTS with GNOME on kernel 4.15.0-58-amd64
Desktop with an i7-4790K running Debian 10.1 (buster) with MATE on kernel 4.19.0-6-amd64

I've measured the battery drain rate on the L390 to be around 1-2% every hour of suspend. There is a large thread on the Linux Lenovo forum, where they're discussing a similar issue (battery drain when sleeping) with the X1 Carbon, though they came to the conclusion that the laptop itself doesn't support deep sleep (ACPI S3 sleep), with Lenovo instead opting for s2idle (ACPI S0 sleep). The L390, however, does report it supports S3 sleep, so it's possible the issue is unrelated.

Revision history for this message

Martin Dünkelmann (nc-duenkekl3-deactivatedaccount) wrote on 2019-12-09:

#16

journalctl_from-2019-12-06_18-10_to-2019-12-09_10-25.log Edit (628.3 KiB, text/plain)

Same issue
ThinkPad T460P with Nvidia GTX 940MX and Intel i7-6820HQ
Linux Mint 19.2 Cinnamon (Ubuntu 18.04) with kernel HWE 5.0.0-37-generic
Put in Sleep on Friday evening.
On Sunday in the middle of the day it did go into emergency wakeup because the battery did go empty.
On Monday morning I did boot it with my connected power supply and wondered why I have only 0% battery left.

Revision history for this message

Ahmad Amr (aamr) wrote on 2020-02-02:

#17

Any update on this? I am using Ubuntu 19.10 on my XPS 15 9560 and I am facing this behavior, however, I am not using the signed kernel package, I believe it applies to the mainline as well.

Revision history for this message

MrBidon (joebidon) wrote on 2020-02-04:

#18

I've got a similar issue too. Any workaround available ?

information type:

Public → Public Security

Revision history for this message

Ulan Yisaev (titan4) wrote on 2020-02-17:

#19

I have similar issue on Ubuntu 19.10 + Toshiba R850.

Revision history for this message

jnns (jnns) wrote on 2020-03-26:

#20

Same issue here: S3 enabled only after wake up.

Thinkapd X1 Carbon 2018 with i7-8550U running Ubuntu 19.10 on 5.3.0-42-generic. TLP enabled with default settings.

[...]
Mar 25 22:15:30 x1cbn systemd[1]: Starting TLP suspend/resume...
Mar 25 22:15:30 x1cbn systemd[1]: Started TLP suspend/resume.
Mar 25 22:15:30 x1cbn systemd[1]: Reached target Sleep.
Mar 25 22:15:30 x1cbn systemd[1]: Starting Suspend...
Mar 25 22:15:30 x1cbn systemd-sleep[26401]: Suspending system...
Mar 25 22:15:30 x1cbn kernel: [30261.531474] PM: suspend entry (deep)
Mar 25 22:15:30 x1cbn kernel: [30261.541908] Filesystems sync: 0.010 seconds
Mar 26 09:37:18 x1cbn kernel: [30261.543157] Freezing user space processes ... (elapsed 0.002 seconds) done.
Mar 26 09:37:18 x1cbn kernel: [30261.546086] OOM killer disabled.
Mar 26 09:37:18 x1cbn kernel: [30261.546086] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Mar 26 09:37:18 x1cbn kernel: [30261.547489] printk: Suspending console(s) (use no_console_suspend to debug)
Mar 26 09:37:18 x1cbn kernel: [30261.567980] psmouse serio1: Failed to disable mouse on isa0060/serio1
Mar 26 09:37:18 x1cbn kernel: [30261.846404] ACPI: EC: interrupt blocked
Mar 26 09:37:18 x1cbn kernel: [30261.904539] ACPI: Preparing to enter system sleep state S3
[...]

Revision history for this message

DENNIS OTIENO (dennohxy) wrote on 2020-05-08:

#21

simillar problem on HP EliteBook running Ubuntu 19.04

Timo Jyrinki (timo-jyrinki) on 2020-06-30

Changed in linux (Ubuntu):
status:	New → Confirmed

Revision history for this message

Timo Jyrinki (timo-jyrinki) wrote on 2020-06-30:

#22

I'm no kernel triager but I have a few observations that can help focusing the bug report:

1. Are people manually installing tlp and that causing problems, or do people experience this too with vanilla installation of Ubuntu? (tlp is not part of the default install, and not in the 'main' supported packages, but definitely power management is optimized as is, ie many of the settings tlp would set are the default anyway in Ubuntu)

2. It may be useful to rule out it's not case of bug 1808957, some people here might be here because of it, ie check cat /sys/power/mem_sleep and if it says s2idle, then you've an unfortunate configuration problem, you can add mem_sleep_default=deep to grub config.

Revision history for this message

WinEunuchs2Unix (ricklee518) wrote on 2020-07-03:

#23

Reply to #22 (Timo Jyrinki):

I don't see why `tlp` is being singled out (although I do have it installed since reporting the bug in Ask Ubuntu). I would think the fault is more with `systemd` or perhaps Linux Kernel not understanding how ACPI works properly. (Wild hunch). That said the results of my test you requested:

$ cat /sys/power/mem_sleep
s2idle [deep]

I'm just hoping someone at Canonical or Linux Kernel Team has a laptop and can confirm if their logs match the rest of ours. If they do then dig deeper. If their logs are fine then question the users here on how their platforms are different.

At least that's what I would do if I worked for Canonical or Linux Kernel Team and had access to a laptop. I've read before laptops are extremely rare in the Linux world.

Kai-Heng Feng (kaihengfeng) on 2020-07-09

Changed in linux (Ubuntu):
status:	Confirmed → Incomplete
no longer affects:	linux-signed-hwe (Ubuntu)

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2020-07-09:

#24

Hey has the Status been changed from confirmed to incomplete? Ubuntu -devel is still affected.

no longer affects:

linux-signed-hwe (Ubuntu)

Revision history for this message

Kai-Heng Feng (kaihengfeng) wrote on 2020-07-09:

#25

Because there's no response from the original bug reporter.

This kind of bug is device dependent, please file separate bug if the hardware model is not " Thinkpad L390".

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2020-07-09:

#26

Although this kind of bug /may/ seem device dependent, in this instance it's not.

In this very bug report, the issue hass been confirmed by users of the following devices:
- Thinkpad L390 (twice)
- Thinkpad X201
- Asus Zenbook UX331UN
- Lenovo X1 Extreme
- Lenovo X1 Extreme Gen2
- Thinkapd X1 Carbon 2018
- Lenovo Thinkpad T460s
- Lenovo ThinkPad T460P
- Dell XPS 15 9560
- Toshiba R850
- HP EliteBook

This bug is clearly not device dependent.

Revision history for this message

Kai-Heng Feng (kaihengfeng) wrote on 2020-07-09:

#27

The same behavior usually has different root cause.

So please file separate bug for each model.

Revision history for this message

Florian 'rephlex' Panzer (rephlex) wrote on 2020-07-09:

#28

I bet 500 dollhairs they're being marked as duplicate in two seconds.

Revision history for this message

Roy Sanderson (rasanderson) wrote on 2020-07-17:

#29

Can confirm that this bug is also affecting Dell Latitude 7490 with Ubuntu 18.04 LTS

Revision history for this message

sknzl (sknzl) wrote on 2020-07-17:

#30

Hi, sorry for not replying. I don't own the L390 anymore.
But I can confirm this happened with a vanilla installation and with TLP installed. There was no difference in the behaviour.
I also believe this is not a problem only affecting the L390.

Revision history for this message

sknzl (sknzl) wrote on 2020-07-17:

#31

I suggest that everybody with this problem files a report similar to this one.
It's a bit of work, but probably the only way to give it some weight.

Revision history for this message

Launchpad Janitor (janitor) wrote on 2020-09-16:

#32

[Expired for linux (Ubuntu) because there has been no activity for 60 days.]

Changed in linux (Ubuntu):
status:	Incomplete → Expired

Report a bug

This report contains Public Security information

Everyone can see this security related information.

You are

Subscribing...

Edit bug mail

Other bug subscribers

Bug attachments

Add attachment

Remote bug watches

Bug watches keep track of this bug in other bug trackers.

Ubuntulinux package

Battery drain during sleep. System suspended before kernel suspends all tasks

Bug Description

Other bug subscribers

Bug attachments

Remote bug watches

Ubuntu
linux package