Arch Linux

olocin

Member

Registered: 2018-01-03

Posts: 171

S3 suspend drains battery of thinkpad

During suspend to RAM with recent kernels, my laptop drains its battery quickly (I can post measures if needed, but it's obviously depleting very quickly).
Here's an excerpt from the journal, notice that I close the lid around 11:41:14 while I resume around 11:42:28.
Is it normal that S3 is only reported at the later time? same for CPUs offline.

Jan 03 11:41:14 ajax systemd-logind[468]: Lid closed.
Jan 03 11:41:14 ajax gnome-shell[722]: Failed to apply DRM plane transform 0: Permission denied
Jan 03 11:41:14 ajax systemd-logind[468]: Suspending...
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.4487] manager: sleep: sleep requested (sleeping: no  enabled: yes)
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.4490] device (wlp4s0): state change: unavailable -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.4495] device (wlp4s0): set-hw-addr: reset MAC address to B8:08:CF:47:4C:F0 (unmanage)
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.4505] manager: NetworkManager state is now ASLEEP
Jan 03 11:41:14 ajax org.gnome.Shell.desktop[722]: Window manager warning: Failed to set power save mode for output eDP-1: Permission denied
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.4533] device (enp0s31f6): state change: activated -> deactivating (reason 'sleeping', sys-iface-state: 'managed')
Jan 03 11:41:14 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:41:14 ajax gnome-shell[722]: Screen lock is locked down, not locking
Jan 03 11:41:14 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:41:14 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:41:14 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:41:14 ajax dbus-daemon[469]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=471 comm="/usr/bin/NetworkManager --no-daemon ")
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.4928] device (enp0s31f6): state change: deactivating -> disconnected (reason 'sleeping', sys-iface-state: 'managed')
Jan 03 11:41:14 ajax systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.5007] dhcp4 (enp0s31f6): canceled DHCP transaction
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.5007] dhcp4 (enp0s31f6): state changed bound -> done
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.5017] dns-mgr: Removing DNS information from /usr/bin/resolvconf
Jan 03 11:41:14 ajax dbus-daemon[469]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 03 11:41:14 ajax systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 03 11:41:14 ajax nm-dispatcher[8504]: req:1 'connectivity-change': new request (1 scripts)
Jan 03 11:41:14 ajax nm-dispatcher[8504]: req:1 'connectivity-change': start running ordered scripts...
Jan 03 11:41:14 ajax nm-dispatcher[8504]: req:2 'down' [enp0s31f6]: new request (1 scripts)
Jan 03 11:41:14 ajax nm-dispatcher[8504]: req:2 'down' [enp0s31f6]: start running ordered scripts...
Jan 03 11:41:14 ajax NetworkManager[471]: <info>  [1514997674.5437] device (enp0s31f6): state change: disconnected -> unmanaged (reason 'sleeping', sys-iface-state: 'managed')
Jan 03 11:41:14 ajax kernel: e1000e: enp0s31f6 NIC Link is Down
Jan 03 11:41:14 ajax systemd[1]: Starting TLP suspend/resume...
Jan 03 11:41:14 ajax systemd[1]: Started TLP suspend/resume.
Jan 03 11:41:14 ajax systemd[1]: Reached target Sleep.
Jan 03 11:41:14 ajax systemd[1]: Starting Suspend...
Jan 03 11:41:14 ajax systemd-sleep[8633]: Suspending system...
Jan 03 11:41:14 ajax kernel: PM: suspend entry (deep)
Jan 03 11:41:14 ajax kernel: PM: Syncing filesystems ... done.
Jan 03 11:42:28 ajax kernel: Freezing user space processes ... (elapsed 0.002 seconds) done.
Jan 03 11:42:28 ajax kernel: OOM killer disabled.
Jan 03 11:42:28 ajax kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Jan 03 11:42:28 ajax kernel: Suspending console(s) (use no_console_suspend to debug)
Jan 03 11:42:28 ajax kernel: e1000e: EEE TX LPI TIMER: 00000011
Jan 03 11:42:28 ajax kernel: ACPI: Preparing to enter system sleep state S3
Jan 03 11:42:28 ajax kernel: ACPI: EC: event blocked
Jan 03 11:42:28 ajax kernel: ACPI: EC: EC stopped
Jan 03 11:42:28 ajax kernel: PM: Saving platform NVS memory
Jan 03 11:42:28 ajax kernel: Disabling non-boot CPUs ...
Jan 03 11:42:28 ajax kernel: smpboot: CPU 1 is now offline
Jan 03 11:42:28 ajax kernel: smpboot: CPU 2 is now offline
Jan 03 11:42:28 ajax kernel: smpboot: CPU 3 is now offline
Jan 03 11:42:28 ajax kernel: ACPI: Low-level resume complete
Jan 03 11:42:28 ajax kernel: ACPI: EC: EC started
Jan 03 11:42:28 ajax kernel: PM: Restoring platform NVS memory
Jan 03 11:42:28 ajax kernel: Enabling non-boot CPUs ...
Jan 03 11:42:28 ajax kernel: x86: Booting SMP configuration:
Jan 03 11:42:28 ajax kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Jan 03 11:42:28 ajax kernel:  cache: parent cpu1 should not be sleeping
Jan 03 11:42:28 ajax kernel: CPU1 is up
Jan 03 11:42:28 ajax kernel: smpboot: Booting Node 0 Processor 2 APIC 0x1
Jan 03 11:42:28 ajax kernel:  cache: parent cpu2 should not be sleeping
Jan 03 11:42:28 ajax kernel: CPU2 is up
Jan 03 11:42:28 ajax kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Jan 03 11:42:28 ajax kernel:  cache: parent cpu3 should not be sleeping
Jan 03 11:42:28 ajax kernel: CPU3 is up
Jan 03 11:42:28 ajax kernel: ACPI: Waking up from system sleep state S3
Jan 03 11:42:28 ajax kernel: ACPI: EC: event unblocked
Jan 03 11:42:28 ajax kernel: [drm] Reducing the compressed framebuffer size. This may lead to less power savings than a non-reduced-size. Try to increase stolen memory size if available in BIOS.
Jan 03 11:42:28 ajax kernel: usb 1-5: reset full-speed USB device number 2 using xhci_hcd
Jan 03 11:42:28 ajax kernel: usb 1-7: reset full-speed USB device number 3 using xhci_hcd
Jan 03 11:42:28 ajax kernel: thinkpad_acpi: EC reports that Thermal Table has changed
Jan 03 11:42:28 ajax kernel: thinkpad_acpi: EC reports that Thermal Table has changed
Jan 03 11:42:28 ajax kernel: usb 1-8: reset high-speed USB device number 4 using xhci_hcd
Jan 03 11:42:28 ajax kernel: psmouse serio1: synaptics: queried max coordinates: x [..5676], y [..4762]
Jan 03 11:42:28 ajax kernel: psmouse serio1: synaptics: queried min coordinates: x [1266..], y [1094..]
Jan 03 11:42:28 ajax kernel: usb 1-7:1.0: rebind failed: -517
Jan 03 11:42:28 ajax kernel: usb 1-7:1.1: rebind failed: -517
Jan 03 11:42:28 ajax kernel: OOM killer enabled.
Jan 03 11:42:28 ajax kernel: Restarting tasks ... 
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014
Jan 03 11:42:28 ajax kernel: done.
Jan 03 11:42:28 ajax kernel: thermal thermal_zone3: failed to read out thermal zone (-61)
Jan 03 11:42:28 ajax systemd-logind[468]: Lid opened.
Jan 03 11:42:28 ajax gnome-shell[722]: Failed to apply DRM plane transform 0: Permission denied
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: Device revision is 5
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: Secure boot is enabled
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: OTP lock is enabled
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: API lock is enabled
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: Debug lock is disabled
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: Minimum firmware build 1 week 10 2014
Jan 03 11:42:28 ajax kernel: Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi
Jan 03 11:42:28 ajax systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Jan 03 11:42:28 ajax systemd[1]: Stopped target Bluetooth.
Jan 03 11:42:28 ajax upowerd[729]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.1
Jan 03 11:42:28 ajax upowerd[729]: unhandled action 'unbind' on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0
Jan 03 11:42:28 ajax upowerd[729]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.1
Jan 03 11:42:28 ajax systemd[1]: Reached target Bluetooth.
Jan 03 11:42:28 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:42:28 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:42:28 ajax gnome-shell[722]: Failed to read EDID from 'eDP-1': No such file or directory
Jan 03 11:42:28 ajax upowerd[729]: unhandled action 'bind' on /sys/devices/pci0000:00/0000:00:14.0/usb1/1-7/1-7:1.0
Jan 03 11:42:28 ajax systemd-sleep[8633]: System resumed.
Jan 03 11:42:28 ajax systemd[1]: Started Suspend.
Jan 03 11:42:28 ajax systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Jan 03 11:42:28 ajax kernel: PM: suspend exit
Jan 03 11:42:28 ajax systemd[1]: Stopped target Sleep.
Jan 03 11:42:28 ajax systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
Jan 03 11:42:28 ajax systemd[1]: Stopping TLP suspend/resume...
Jan 03 11:42:28 ajax systemd[1]: Reached target Suspend.
Jan 03 11:42:28 ajax systemd-logind[468]: Operation 'sleep' finished.
Jan 03 11:42:28 ajax systemd[1]: suspend.target: Unit not needed anymore. Stopping.
Jan 03 11:42:28 ajax systemd[1]: Stopped target Suspend.
Jan 03 11:42:28 ajax NetworkManager[471]: <info>  [1514997748.7534] manager: sleep: wake requested (sleeping: yes  enabled: yes)
Jan 03 11:42:28 ajax NetworkManager[471]: <info>  [1514997748.7535] device (enp0s31f6): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'managed')
Jan 03 11:42:28 ajax kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s31f6: link is not ready
Jan 03 11:42:28 ajax org.gnome.Shell.desktop[722]: Window manager warning: Failed to set power save mode for output eDP-1: Permission denied
Jan 03 11:42:28 ajax kernel: [drm] RC6 on
Jan 03 11:42:28 ajax systemd-udevd[8647]: Process '/usr/bin/tlp auto' failed with exit code 4.
Jan 03 11:42:28 ajax NetworkManager[471]: <info>  [1514997748.9672] device (wlp4s0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'managed')
Jan 03 11:42:28 ajax kernel: IPv6: ADDRCONF(NETDEV_UP): enp0s31f6: link is not ready
Jan 03 11:42:28 ajax NetworkManager[471]: <info>  [1514997748.9700] manager: NetworkManager state is now DISCONNECTED
Jan 03 11:42:28 ajax kernel: IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
Jan 03 11:42:29 ajax systemd[1]: Stopped TLP suspend/resume.
Jan 03 11:42:30 ajax kernel: Bluetooth: hci0: Waiting for firmware download to complete
Jan 03 11:42:30 ajax kernel: Bluetooth: hci0: Firmware loaded in 1594698 usecs
Jan 03 11:42:30 ajax kernel: Bluetooth: hci0: Waiting for device to boot
Jan 03 11:42:30 ajax kernel: Bluetooth: hci0: Device booted in 15557 usecs
Jan 03 11:42:30 ajax kernel: Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
Jan 03 11:42:30 ajax kernel: Bluetooth: hci0: Applying Intel DDC parameters completed
Jan 03 11:42:32 ajax kernel: e1000e: enp0s31f6 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
Jan 03 11:42:32 ajax kernel: IPv6: ADDRCONF(NETDEV_CHANGE): enp0s31f6: link becomes ready
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4248] device (enp0s31f6): carrier: link connected
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4272] device (enp0s31f6): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed')
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4291] policy: auto-activating connection 'ethernet'
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4346] device (enp0s31f6): Activation: starting connection 'ethernet' (f8617ee6-8f3d-4457-b6c4-668172164a72)
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4351] device (enp0s31f6): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4354] manager: NetworkManager state is now CONNECTING
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4398] device (enp0s31f6): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4404] device (enp0s31f6): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Jan 03 11:42:32 ajax NetworkManager[471]: <info>  [1514997752.4407] dhcp4 (enp0s31f6): activation: beginning transaction (timeout in 45 seconds)
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9421] dhcp4 (enp0s31f6):   address 192.168.1.14
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9421] dhcp4 (enp0s31f6):   plen 24
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9421] dhcp4 (enp0s31f6):   expires in 21600 seconds
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9422] dhcp4 (enp0s31f6):   nameserver '192.168.1.1'
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9422] dhcp4 (enp0s31f6):   domain name 'homenet.telecomitalia.it'
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9423] dhcp4 (enp0s31f6):   gateway 192.168.1.1
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9426] dhcp4 (enp0s31f6): state changed unknown -> bound
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9445] device (enp0s31f6): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9471] device (enp0s31f6): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9477] device (enp0s31f6): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9481] manager: NetworkManager state is now CONNECTED_LOCAL
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9546] manager: NetworkManager state is now CONNECTED_SITE
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9547] policy: set 'ethernet' (enp0s31f6) as default for IPv4 routing and DNS
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9548] dns-mgr: Writing DNS information to /usr/bin/resolvconf
Jan 03 11:42:39 ajax NetworkManager[471]: <info>  [1514997759.9987] device (enp0s31f6): Activation: successful, device activated.
Jan 03 11:42:39 ajax dbus-daemon[469]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.2' (uid=0 pid=471 comm="/usr/bin/NetworkManager --no-daemon ")
Jan 03 11:42:40 ajax systemd[1]: Starting Network Manager Script Dispatcher Service...
Jan 03 11:42:40 ajax dbus-daemon[469]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Jan 03 11:42:40 ajax nm-dispatcher[8993]: req:1 'up' [enp0s31f6]: new request (1 scripts)
Jan 03 11:42:40 ajax nm-dispatcher[8993]: req:1 'up' [enp0s31f6]: start running ordered scripts...
Jan 03 11:42:40 ajax systemd[1]: Started Network Manager Script Dispatcher Service.
Jan 03 11:42:41 ajax NetworkManager[471]: <info>  [1514997761.0480] manager: NetworkManager state is now CONNECTED_GLOBAL
Jan 03 11:42:41 ajax nm-dispatcher[8993]: req:2 'connectivity-change': new request (1 scripts)
Jan 03 11:42:41 ajax nm-dispatcher[8993]: req:2 'connectivity-change': start running ordered scripts...

progandy

Member

Registered: 2012-05-17

Posts: 5,193

Re: S3 suspend drains battery of thinkpad

I believe that is normal, the systemd journal seems to rewrite the timestamp for kernel messages. I guess when it gets frozen during the suspend it cannot log the messages and collects them until it is resumed. Then the journal logs them with the time the message was processed. Please check the timestamps directly with "dmesg" or "dmesg -H"

Last edited by progandy (2018-01-03 18:51:30)

---

| alias CUTF='LANG=en_XX.UTF-8@POSIX ' |

olocin

Member

Registered: 2018-01-03

Posts: 171

Re: S3 suspend drains battery of thinkpad

This is the corresponding part from dmesg -H, it seems to add to what you said. I will try to make more precise measurements of battery discharge using tlp stat to support my claim.

[Jan 3 11:41] e1000e: enp0s31f6 NIC Link is Down
[  +0.111008] PM: suspend entry (deep)
[  +0.000001] PM: Syncing filesystems ... done.
[  +0.012428] Freezing user space processes ... (elapsed 0.002 seconds) done.
[  +0.002772] OOM killer disabled.
[  +0.000001] Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
[  +0.001601] Suspending console(s) (use no_console_suspend to debug)
[  +0.198447] e1000e: EEE TX LPI TIMER: 00000011
[  +0.431002] ACPI: Preparing to enter system sleep state S3
[  +0.239239] ACPI: EC: event blocked
[  +0.000001] ACPI: EC: EC stopped
[  +0.000001] PM: Saving platform NVS memory
[  +0.000008] Disabling non-boot CPUs ...
[  +0.028068] smpboot: CPU 1 is now offline
[  +0.019984] smpboot: CPU 2 is now offline
[  +0.029902] smpboot: CPU 3 is now offline
[  +0.003011] ACPI: Low-level resume complete
[  +0.000116] ACPI: EC: EC started
[  +0.000001] PM: Restoring platform NVS memory
[  +0.000802] Enabling non-boot CPUs ...
[  +0.000031] x86: Booting SMP configuration:
[  +0.000001] smpboot: Booting Node 0 Processor 1 APIC 0x2
[  +0.005183]  cache: parent cpu1 should not be sleeping
[  +0.000126] CPU1 is up
[  +0.000034] smpboot: Booting Node 0 Processor 2 APIC 0x1
[  +0.000777]  cache: parent cpu2 should not be sleeping
[  +0.000143] CPU2 is up
[  +0.000018] smpboot: Booting Node 0 Processor 3 APIC 0x3
[  +0.000616]  cache: parent cpu3 should not be sleeping
[  +0.000138] CPU3 is up
[  +0.002993] ACPI: Waking up from system sleep state S3
[  +0.523132] ACPI: EC: event unblocked
[  +0.216520] [drm] Reducing the compressed framebuffer size. This may lead to less power savings than a non-reduced-size. Try to increase stolen memory size if available in BIOS.
[  +0.011994] usb 1-5: reset full-speed USB device number 2 using xhci_hcd
[  +0.264346] usb 1-7: reset full-speed USB device number 3 using xhci_hcd
[  +0.141281] thinkpad_acpi: EC reports that Thermal Table has changed
[  +0.106132] thinkpad_acpi: EC reports that Thermal Table has changed
[  +0.015617] usb 1-8: reset high-speed USB device number 4 using xhci_hcd
[  +0.265319] psmouse serio1: synaptics: queried max coordinates: x [..5676], y [..4762]
[  +0.037902] psmouse serio1: synaptics: queried min coordinates: x [1266..], y [1094..]
[  +0.120471] usb 1-7:1.0: rebind failed: -517
[  +0.000006] usb 1-7:1.1: rebind failed: -517
[  +0.000885] OOM killer enabled.
[  +0.000003] Restarting tasks ... 
[  +0.001068] Bluetooth: hci0: Bootloader revision 0.0 build 2 week 52 2014
[  +0.000415] done.
[  +0.000911] thermal thermal_zone3: failed to read out thermal zone (-61)
[  +0.007625] Bluetooth: hci0: Device revision is 5
[  +0.000001] Bluetooth: hci0: Secure boot is enabled
[  +0.000001] Bluetooth: hci0: OTP lock is enabled
[  +0.000001] Bluetooth: hci0: API lock is enabled
[  +0.000000] Bluetooth: hci0: Debug lock is disabled
[  +0.000001] Bluetooth: hci0: Minimum firmware build 1 week 10 2014
[  +0.000203] Bluetooth: hci0: Found device firmware: intel/ibt-11-5.sfi
[  +0.026056] PM: suspend exit
[  +0.003731] IPv6: ADDRCONF(NETDEV_UP): enp0s31f6: link is not ready
[  +0.057221] [drm] RC6 on
[  +0.154257] IPv6: ADDRCONF(NETDEV_UP): enp0s31f6: link is not ready
[  +0.003910] IPv6: ADDRCONF(NETDEV_UP): wlp4s0: link is not ready
[  +1.377094] Bluetooth: hci0: Waiting for firmware download to complete
[  +0.000600] Bluetooth: hci0: Firmware loaded in 1594698 usecs
[  +0.000191] Bluetooth: hci0: Waiting for device to boot
[  +0.015800] Bluetooth: hci0: Device booted in 15557 usecs
[  +0.000093] Bluetooth: hci0: Found Intel DDC parameters: intel/ibt-11-5.ddc
[  +0.003909] Bluetooth: hci0: Applying Intel DDC parameters completed
[  +2.057167] e1000e: enp0s31f6 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: Rx/Tx
[  +0.000064] IPv6: ADDRCONF(NETDEV_CHANGE): enp0s31f6: link becomes ready

seth

Member

Registered: 2012-09-03

Posts: 51,222

Re: S3 suspend drains battery of thinkpad

1. How does "quickly" relate to the "normal" drain? Same speed, faster, half ..
2. Does it also happen if you do not suspend by closing the lid but eg. "systemctl suspend"? Does the screen turn off?
3. Try to disable TLP.

---

How to upload text · How to boot w/o GUI · Disable Windows Fast-Start! · Fix your xinitrc

olocin

Member

Registered: 2018-01-03

Posts: 171

Re: S3 suspend drains battery of thinkpad

Here's a first measurement: if I suspend on recent kernels by closing the lid, in 9h34m the battery goes from 39.5% to 32.6% according to tlp-stat. I will make a measurement with older kernels and with Windows for reference soon, and also try 2 and 3 as suggested.

olocin

Member

Registered: 2018-01-03

Posts: 171

Re: S3 suspend drains battery of thinkpad

Another measurement with tlp-stat: in 10h, assuming a constant rate, it discharges 0.65% per hour, namely 298 mWh per hour.

Perhaps I was mistaken: are values in this ballpark to be considered reasonable for a thinkpad T460s laptop?

Last edited by olocin (2018-01-18 19:37:54)

seth

Member

Registered: 2012-09-03

Posts: 51,222

Re: S3 suspend drains battery of thinkpad

http://www.thinkwiki.org/wiki/Problem_w … ACPI_sleep supports that value and calls it " fair"
There're also some suggestions to lower the drain (disable WOL etc)

---

How to upload text · How to boot w/o GUI · Disable Windows Fast-Start! · Fix your xinitrc

olocin

Member

Registered: 2018-01-03

Posts: 171

Re: S3 suspend drains battery of thinkpad

Thanks for the ref, indeed they mention a value of 273 mWh, but for a T43, which came out in 2005
I have already disabled WOL, but will keep digging, because I suspect something is still not powering off.

Slithery

Administrator

From: Norfolk, UK

Registered: 2013-12-01

Posts: 5,776

Re: S3 suspend drains battery of thinkpad

Seems fair to me.

Of course there's still something not powering off - your RAM

---

No, it didn't "fix" anything. It just shifted the brokeness one space to the right. - jasonwryan
Closing -- for deletion; Banning -- for muppetry. - jasonwryan

aur - dotfiles

olocin

Member

Registered: 2018-01-03

Posts: 171

Re: S3 suspend drains battery of thinkpad

Another similar measurement yields 307 mWh/h. I wonder how this compares to performance under Windows.