You are not logged in.

#1 2016-11-27 13:18:22

Und
Member
Registered: 2012-01-02
Posts: 7

Trouble with delayed hibernation

I have followed the instructions in the wiki to enable the provided suspend-to-hibernate systemd service. However, when the service wakes the laptop to hibernate it after the specified interval, another suspend is triggered before the system has a chance to hibernate. I have attached the relevant part of my systemd log. Just wondering if anyone has faced a similar problem and can advise on what to do. Thanks.

Nov 27 12:59:44 e7450-arch systemd[1]: Starting Delayed hibernation trigger...
Nov 27 12:59:44 e7450-arch systemd[1]: Starting TLP suspend/resume...
Nov 27 12:59:44 e7450-arch sh[8532]: alarm set for 1480251644
Nov 27 12:59:44 e7450-arch systemd[1]: Started Delayed hibernation trigger.
Nov 27 12:59:45 e7450-arch systemd[1]: Started TLP suspend/resume.
Nov 27 12:59:45 e7450-arch systemd[1]: Reached target Sleep.
Nov 27 12:59:45 e7450-arch systemd[1]: Starting Suspend...
Nov 27 12:59:45 e7450-arch systemd-sleep[8600]: Suspending system...
Nov 27 13:00:47 e7450-arch kernel: PM: Syncing filesystems ... done.
Nov 27 13:00:47 e7450-arch kernel: PM: Preparing system for sleep (mem)
Nov 27 13:00:47 e7450-arch kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Nov 27 13:00:47 e7450-arch kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Nov 27 13:00:47 e7450-arch kernel: PM: Suspending system (mem)
Nov 27 13:00:47 e7450-arch kernel: Suspending console(s) (use no_console_suspend to debug)
Nov 27 13:00:47 e7450-arch kernel: sd 1:0:0:0: [sda] Synchronizing SCSI cache
Nov 27 13:00:47 e7450-arch kernel: sd 1:0:0:0: [sda] Stopping disk
Nov 27 13:00:47 e7450-arch kernel: e1000e: EEE TX LPI TIMER: 00000011
Nov 27 13:00:47 e7450-arch kernel: PM: suspend of devices complete after 200.181 msecs
Nov 27 13:00:47 e7450-arch kernel: PM: late suspend of devices complete after 19.843 msecs
Nov 27 13:00:47 e7450-arch kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: e1000e 0000:00:19.0: System wakeup enabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: PM: noirq suspend of devices complete after 92.181 msecs
Nov 27 13:00:47 e7450-arch kernel: ACPI: Preparing to enter system sleep state S3
Nov 27 13:00:47 e7450-arch kernel: ACPI : EC: EC stopped
Nov 27 13:00:47 e7450-arch kernel: PM: Saving platform NVS memory
Nov 27 13:00:47 e7450-arch kernel: Disabling non-boot CPUs ...
Nov 27 13:00:47 e7450-arch kernel: smpboot: CPU 1 is now offline
Nov 27 13:00:47 e7450-arch kernel: smpboot: CPU 2 is now offline
Nov 27 13:00:47 e7450-arch kernel: smpboot: CPU 3 is now offline
Nov 27 13:00:47 e7450-arch kernel: ACPI: Low-level resume complete
Nov 27 13:00:47 e7450-arch kernel: ACPI : EC: EC started
Nov 27 13:00:47 e7450-arch kernel: PM: Restoring platform NVS memory
Nov 27 13:00:47 e7450-arch kernel: microcode: CPU0 microcode updated early to revision 0x24, date = 2016-04-29
Nov 27 13:00:47 e7450-arch kernel: Enabling non-boot CPUs ...
Nov 27 13:00:47 e7450-arch kernel: x86: Booting SMP configuration:
Nov 27 13:00:47 e7450-arch kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Nov 27 13:00:47 e7450-arch kernel: microcode: CPU1 microcode updated early to revision 0x24, date = 2016-04-29
Nov 27 13:00:47 e7450-arch kernel:  cache: parent cpu1 should not be sleeping
Nov 27 13:00:47 e7450-arch kernel: CPU1 is up
Nov 27 13:00:47 e7450-arch kernel: smpboot: Booting Node 0 Processor 2 APIC 0x1
Nov 27 13:00:47 e7450-arch kernel:  cache: parent cpu2 should not be sleeping
Nov 27 13:00:47 e7450-arch kernel: CPU2 is up
Nov 27 13:00:47 e7450-arch kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Nov 27 13:00:47 e7450-arch kernel:  cache: parent cpu3 should not be sleeping
Nov 27 13:00:47 e7450-arch kernel: CPU3 is up
Nov 27 13:00:47 e7450-arch kernel: ACPI: Waking up from system sleep state S3
Nov 27 13:00:47 e7450-arch kernel: ACPI Error: Cannot release Mutex [PATM], not acquired (20150930/exmutex-376)
Nov 27 13:00:47 e7450-arch kernel: ACPI Error: Method parse/execution failed [\_SB.PCI0.LPCB.ECDV._Q66] (Node ffff880215cbeeb0), AE_AML_MUTEX_NOT_ACQUIRED (20150930/psparse-542)
Nov 27 13:00:47 e7450-arch kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: PM: noirq resume of devices complete after 61.172 msecs
Nov 27 13:00:47 e7450-arch kernel: PM: early resume of devices complete after 6.255 msecs
Nov 27 13:00:47 e7450-arch kernel: usb usb2: root hub lost power or was reset
Nov 27 13:00:47 e7450-arch kernel: usb usb3: root hub lost power or was reset
Nov 27 13:00:47 e7450-arch kernel: sd 1:0:0:0: [sda] Starting disk
Nov 27 13:00:47 e7450-arch kernel: e1000e 0000:00:19.0: System wakeup disabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: rtc_cmos 00:01: System wakeup disabled by ACPI
Nov 27 13:00:47 e7450-arch kernel: usb 1-1: reset high-speed USB device number 2 using ehci-pci
Nov 27 13:00:47 e7450-arch kernel: ata1: SATA link down (SStatus 0 SControl 300)
Nov 27 13:00:47 e7450-arch kernel: ata2: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Nov 27 13:00:47 e7450-arch kernel: ata2.00: supports DRM functions and may not be fully accessible
Nov 27 13:00:47 e7450-arch kernel: ata2.00: disabling queued TRIM support
Nov 27 13:00:47 e7450-arch kernel: ata2.00: supports DRM functions and may not be fully accessible
Nov 27 13:00:47 e7450-arch kernel: ata2.00: disabling queued TRIM support
Nov 27 13:00:47 e7450-arch kernel: ata2.00: configured for UDMA/133
Nov 27 13:00:47 e7450-arch kernel: usb 1-1.3: reset full-speed USB device number 3 using ehci-pci
Nov 27 13:00:47 e7450-arch kernel: usb 1-1.7: reset full-speed USB device number 5 using ehci-pci
Nov 27 13:00:47 e7450-arch kernel: usb 1-1.6: reset high-speed USB device number 4 using ehci-pci
Nov 27 13:00:47 e7450-arch kernel: PM: resume of devices complete after 1233.837 msecs
Nov 27 13:00:47 e7450-arch kernel: PM: Finishing wakeup.
Nov 27 13:00:47 e7450-arch kernel: Restarting tasks ... 
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:01: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:02: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:03: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: done.
Nov 27 13:00:47 e7450-arch systemd[1]: Time has been changed
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:01: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:02: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:03: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: Bluetooth: hci0: read Intel version: 370810011003110e00
Nov 27 13:00:47 e7450-arch kernel: Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.8.10-fw-1.10.3.11.e.bseq
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:01: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:02: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: pci_bus 0000:03: Allocating resources
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch kernel: acpi PNP0401:00: Already enumerated
Nov 27 13:00:47 e7450-arch kernel: acpi PNP0501:00: Still not present
Nov 27 13:00:47 e7450-arch kernel: i915 0000:00:02.0: BAR 6: [??? 0x00000000 flags 0x2] has bogus alignment
Nov 27 13:00:47 e7450-arch systemd[818]: Time has been changed
Nov 27 13:00:47 e7450-arch systemd[537]: Time has been changed
Nov 27 13:00:47 e7450-arch systemd-sleep[8600]: System resumed.
Nov 27 13:00:47 e7450-arch systemd[1]: Starting Load/Save RF Kill Switch Status...
Nov 27 13:00:47 e7450-arch systemd[1]: Started Suspend.
Nov 27 13:00:47 e7450-arch systemd[1]: sleep.target: Unit not needed anymore. Stopping.
Nov 27 13:00:47 e7450-arch systemd[1]: bluetooth.target: Unit not needed anymore. Stopping.
Nov 27 13:00:47 e7450-arch systemd[1]: Stopped target Bluetooth.
Nov 27 13:00:47 e7450-arch systemd[1]: Stopped target Sleep.
Nov 27 13:00:47 e7450-arch systemd[1]: suspend-to-hibernate.service: Unit not needed anymore. Stopping.
Nov 27 13:00:47 e7450-arch systemd[1]: tlp-sleep.service: Unit not needed anymore. Stopping.
Nov 27 13:00:47 e7450-arch systemd[1]: Stopping TLP suspend/resume...
Nov 27 13:00:47 e7450-arch systemd[1]: Stopping Delayed hibernation trigger...
Nov 27 13:00:47 e7450-arch systemd[1]: Started Load/Save RF Kill Switch Status.
Nov 27 13:00:47 e7450-arch sh[8717]: hibernate triggered
Nov 27 13:00:47 e7450-arch sh[8717]: Failed to hibernate system via logind: There's already a shutdown or sleep operation in progress
Nov 27 13:00:47 e7450-arch systemd[1]: Stopped Delayed hibernation trigger.
Nov 27 13:00:47 e7450-arch systemd[1]: Reached target Suspend.
Nov 27 13:00:47 e7450-arch systemd[1]: suspend.target: Unit is bound to inactive unit systemd-suspend.service. Stopping, too.
Nov 27 13:00:47 e7450-arch systemd[1]: Stopped target Suspend.

Offline

Board footer

Powered by FluxBB