You are not logged in.

#1 2014-02-15 23:15:22

jtheoof
Member
Registered: 2014-01-30
Posts: 23

[SOLVED] Laptop resumes from suspend while lid is still closed

Hi all,

I'm having some weird issue ever since I installed arch on my new laptop (Haswell).
When I put my computer to suspend by closing the lid, all goes well.
But the computer resumes by itself after a while. I have no idea why, WoL is disabled on both ethernet and wifi.

Can someone help figure out what is causing the computer to resume?

For example here is a magic wake up after 1 hour asleep.

Feb 15 00:37:15 jitte systemd[1]: Starting TLP suspend/resume...
Feb 15 00:37:15 jitte tlp[24682]: grep: /sys/devices/platform/dock.?/type: No such file or directory
Feb 15 00:37:15 jitte systemd[1]: Started TLP suspend/resume.
Feb 15 00:37:15 jitte systemd[1]: Starting Sleep.
Feb 15 00:37:15 jitte systemd[1]: Reached target Sleep.
Feb 15 00:37:15 jitte systemd[1]: Starting Suspend...
Feb 15 00:37:15 jitte systemd-sleep[24748]: Suspending system...
Feb 15 00:37:15 jitte kernel: PM: Syncing filesystems ... done.
Feb 15 00:37:15 jitte kernel: PM: Preparing system for mem sleep
Feb 15 00:37:15 jitte kernel: bbswitch: enabling discrete graphics
Feb 15 01:34:26 jitte kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Feb 15 01:34:26 jitte systemd[1]: Time has been changed
Feb 15 01:34:26 jitte kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Feb 15 01:34:26 jitte kernel: PM: Entering mem sleep
Feb 15 01:34:26 jitte kernel: Suspending console(s) (use no_console_suspend to debug)
Feb 15 01:34:26 jitte kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Feb 15 01:34:26 jitte kernel: sd 0:0:0:0: [sda] Stopping disk
Feb 15 01:34:26 jitte kernel: mei_me 0000:00:16.0: suspend
Feb 15 01:34:26 jitte kernel: PM: suspend of devices complete after 220.139 msecs
Feb 15 01:34:26 jitte systemd[607]: Time has been changed
Feb 15 01:34:26 jitte kernel: PM: late suspend of devices complete after 0.115 msecs
Feb 15 01:34:26 jitte kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Feb 15 01:34:26 jitte kernel: PM: noirq suspend of devices complete after 66.764 msecs
Feb 15 01:34:26 jitte kernel: ACPI: Preparing to enter system sleep state S3
Feb 15 01:34:26 jitte kernel: PM: Saving platform NVS memory
Feb 15 01:34:26 jitte kernel: Disabling non-boot CPUs ...
Feb 15 01:34:26 jitte kernel: smpboot: CPU 1 is now offline
Feb 15 01:34:26 jitte kernel: smpboot: CPU 2 is now offline
Feb 15 01:34:26 jitte kernel: smpboot: CPU 3 is now offline
Feb 15 01:34:26 jitte kernel: ACPI: Low-level resume complete
Feb 15 01:34:26 jitte kernel: PM: Restoring platform NVS memory
Feb 15 01:34:26 jitte kernel: Enabling non-boot CPUs ...
Feb 15 01:34:26 jitte kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Feb 15 01:34:26 jitte kernel: Intel pstate controlling: cpu 1
Feb 15 01:34:26 jitte kernel: CPU1 is up
Feb 15 01:34:26 jitte kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Feb 15 01:34:26 jitte kernel: Intel pstate controlling: cpu 2
Feb 15 01:34:26 jitte kernel: CPU2 is up
Feb 15 01:34:26 jitte kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Feb 15 01:34:26 jitte kernel: Intel pstate controlling: cpu 3
Feb 15 01:34:26 jitte kernel: CPU3 is up
Feb 15 01:34:26 jitte kernel: ACPI: Waking up from system sleep state S3
Feb 15 01:34:26 jitte kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Feb 15 01:34:26 jitte kernel: PM: noirq resume of devices complete after 130.807 msecs
Feb 15 01:34:26 jitte kernel: PM: early resume of devices complete after 0.108 msecs
Feb 15 01:34:26 jitte kernel: i915 0000:00:02.0: setting latency timer to 64
Feb 15 01:34:26 jitte kernel: mei_me 0000:00:16.0: irq 57 for MSI/MSI-X
Feb 15 01:34:26 jitte kernel: snd_hda_intel 0000:00:03.0: irq 61 for MSI/MSI-X
Feb 15 01:34:26 jitte kernel: ehci-pci 0000:00:1d.0: setting latency timer to 64
Feb 15 01:34:26 jitte kernel: ahci 0000:00:1f.2: setting latency timer to 64

Last edited by jtheoof (2014-03-16 19:15:02)

Offline

#2 2014-02-15 23:53:18

frank604
Member
From: BC, Canada
Registered: 2011-04-20
Posts: 1,212

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

How are you executing sleep?  Through systemctl?  I've never heard of TLP but it seems you might be using that instead of systemctl to suspend.  Here is TLP's archwiki. https://wiki.archlinux.org/index.php/TLP

Try to stop/disable/remove TLP and let systemctl handle it and see if that works?

Offline

#3 2014-02-16 00:00:37

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Hi Frank,

I don't think TLP is the problem, it was already occurring before I installed it. But I'll try to disable it and put the computer to sleep using

systemctl suspend

. We'll see what happens.
I'm wondering if someone else is having the problem.

Offline

#4 2014-02-24 19:58:23

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Quick update. I removed TLP and every power utils tools. The suspend seemed to work normally for a few days but today I had another magic resume at 17:22.

Feb 24 13:32:04 jitte systemd-logind[276]: Lid closed.
Feb 24 13:32:04 jitte systemd-logind[276]: Suspending...
Feb 24 13:32:04 jitte NetworkManager[273]: <info> sleep requested (sleeping: no  enabled: yes)
Feb 24 13:32:04 jitte NetworkManager[273]: <info> sleeping or disabling...
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (wlp2s0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (wlp2s0): deactivating device (reason 'sleeping') [37]
Feb 24 13:32:04 jitte dhcpcd[13294]: received SIGTERM from PID 273, stopping
Feb 24 13:32:04 jitte dhcpcd[13294]: wlp2s0: removing interface
Feb 24 13:32:04 jitte dhcpcd[13294]: exited
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (wlp2s0): canceled DHCP transaction, DHCP client pid 13294
Feb 24 13:32:04 jitte NetworkManager[273]: <info> Removing DNS information from /usr/bin/resolvconf
Feb 24 13:32:04 jitte kernel: ACPI: \_SB_.PCI0: ACPI_NOTIFY_BUS_CHECK event
Feb 24 13:32:04 jitte kernel: ACPI: \_SB_.PCI0: ACPI_NOTIFY_BUS_CHECK event
Feb 24 13:32:04 jitte kernel: wlp2s0: deauthenticating from 00:24:d4:5f:dc:d8 by local choice (reason=3)
Feb 24 13:32:04 jitte kernel: cfg80211: Calling CRDA to update world regulatory domain
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (wlp2s0): cleaning up...
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (wlp2s0): taking down device.
Feb 24 13:32:04 jitte gnome-session[1672]: Gjs-Message: JS LOG: An active wireless connection, in infrastructure mode, involves no access point?
Feb 24 13:32:04 jitte NetworkManager[273]: <info> NetworkManager state is now ASLEEP
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (enp3s0f1): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (enp3s0f1): cleaning up...
Feb 24 13:32:04 jitte NetworkManager[273]: <info> (enp3s0f1): taking down device.
Feb 24 13:32:04 jitte dbus[278]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Feb 24 13:32:04 jitte systemd[1]: Starting Network Manager Script Dispatcher Service...
Feb 24 13:32:04 jitte dbus[278]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Feb 24 13:32:04 jitte systemd[1]: Started Network Manager Script Dispatcher Service.
Feb 24 13:32:09 jitte systemd-logind[276]: Delay lock is active but inhibitor timeout is reached.
Feb 24 13:32:09 jitte systemd[1]: Starting Sleep.
Feb 24 13:32:09 jitte systemd[1]: Reached target Sleep.
Feb 24 13:32:09 jitte systemd[1]: Starting Suspend...
Feb 24 13:32:09 jitte systemd-sleep[14065]: Suspending system...
Feb 24 13:32:09 jitte kernel: PM: Syncing filesystems ... done.
Feb 24 13:32:09 jitte kernel: PM: Preparing system for mem sleep
Feb 24 13:32:09 jitte kernel: bbswitch: enabling discrete graphics
Feb 24 17:22:59 jitte kernel: Freezing user space processes ... (elapsed 0.003 seconds) done.
Feb 24 17:22:59 jitte systemd[1]: Time has been changed
Feb 24 17:22:59 jitte systemd[1666]: Time has been changed
Feb 24 17:22:59 jitte kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Feb 24 17:22:59 jitte kernel: PM: Entering mem sleep
Feb 24 17:22:59 jitte kernel: Suspending console(s) (use no_console_suspend to debug)
Feb 24 17:22:59 jitte kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Feb 24 17:22:59 jitte kernel: sd 0:0:0:0: [sda] Stopping disk
Feb 24 17:22:59 jitte kernel: PM: suspend of devices complete after 1131.336 msecs
Feb 24 17:22:59 jitte kernel: PM: late suspend of devices complete after 0.555 msecs
Feb 24 17:22:59 jitte kernel: pcieport 0000:00:1c.3: System wakeup enabled by ACPI
Feb 24 17:22:59 jitte kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Feb 24 17:22:59 jitte kernel: PM: noirq suspend of devices complete after 66.779 msecs
Feb 24 17:22:59 jitte kernel: ACPI: Preparing to enter system sleep state S3
Feb 24 17:22:59 jitte kernel: PM: Saving platform NVS memory
Feb 24 17:22:59 jitte kernel: Disabling non-boot CPUs ...
Feb 24 17:22:59 jitte kernel: kvm: disabling virtualization on CPU1
Feb 24 17:22:59 jitte kernel: smpboot: CPU 1 is now offline
Feb 24 17:22:59 jitte kernel: kvm: disabling virtualization on CPU2
Feb 24 17:22:59 jitte kernel: smpboot: CPU 2 is now offline
Feb 24 17:22:59 jitte kernel: kvm: disabling virtualization on CPU3
Feb 24 17:22:59 jitte kernel: smpboot: CPU 3 is now offline
Feb 24 17:22:59 jitte kernel: ACPI: Low-level resume complete
Feb 24 17:22:59 jitte kernel: PM: Restoring platform NVS memory
Feb 24 17:22:59 jitte kernel: Enabling non-boot CPUs ...
Feb 24 17:22:59 jitte kernel: x86: Booting SMP configuration:
Feb 24 17:22:59 jitte kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Feb 24 17:22:59 jitte kernel: kvm: enabling virtualization on CPU1
Feb 24 17:22:59 jitte kernel: Intel pstate controlling: cpu 1
Feb 24 17:22:59 jitte kernel: CPU1 is up
Feb 24 17:22:59 jitte systemd[1666]: Service bluetooth.target is not needed anymore. Stopping.
Feb 24 17:22:59 jitte systemd[1666]: Stopping Bluetooth.
Feb 24 17:22:59 jitte systemd[1666]: Stopped target Bluetooth.
Feb 24 17:22:59 jitte systemd[1]: Service bluetooth.target is not needed anymore. Stopping.
Feb 24 17:22:59 jitte systemd[1]: Stopping Bluetooth.
Feb 24 17:22:59 jitte systemd[1]: Stopped target Bluetooth.
Feb 24 17:22:59 jitte kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Feb 24 17:22:59 jitte kernel: kvm: enabling virtualization on CPU2
Feb 24 17:22:59 jitte kernel: Intel pstate controlling: cpu 2
Feb 24 17:22:59 jitte kernel: CPU2 is up
Feb 24 17:22:59 jitte kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Feb 24 17:22:59 jitte kernel: kvm: enabling virtualization on CPU3
Feb 24 17:22:59 jitte kernel: Intel pstate controlling: cpu 3
Feb 24 17:22:59 jitte kernel: CPU3 is up
Feb 24 17:22:59 jitte kernel: ACPI: Waking up from system sleep state S3
Feb 24 17:22:59 jitte kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Feb 24 17:22:59 jitte kernel: PM: noirq resume of devices complete after 133.662 msecs
Feb 24 17:22:59 jitte kernel: PM: early resume of devices complete after 0.346 msecs
Feb 24 17:22:59 jitte kernel: snd_hda_intel 0000:00:03.0: irq 58 for MSI/MSI-X
Feb 24 17:22:59 jitte kernel: mei_me 0000:00:16.0: irq 62 for MSI/MSI-X
Feb 24 17:22:59 jitte kernel: usb usb1: root hub lost power or was reset
Feb 24 17:22:59 jitte kernel: usb usb2: root hub lost power or was reset
Feb 24 17:22:59 jitte kernel: snd_hda_intel 0000:00:1b.0: irq 61 for MSI/MSI-X
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: irq 63 for MSI/MSI-X
Feb 24 17:22:59 jitte kernel: pcieport 0000:00:1c.3: System wakeup disabled by ACPI
Feb 24 17:22:59 jitte kernel: dpm_run_callback(): pnp_bus_resume+0x0/0xa0 returns -19
Feb 24 17:22:59 jitte kernel: PM: Device 00:07 failed to resume: error -19
Feb 24 17:22:59 jitte kernel: usb 3-1: reset high-speed USB device number 2 using ehci-pci
Feb 24 17:22:59 jitte kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Feb 24 17:22:59 jitte kernel: ata1.00: ACPI cmd ef/10:09:00:00:00:b0 (SET FEATURES) succeeded
Feb 24 17:22:59 jitte kernel: ata1.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device (Stat=0x51 Err=0x04)
Feb 24 17:22:59 jitte kernel: ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
Feb 24 17:22:59 jitte kernel: ata1.00: ACPI cmd ef/10:09:00:00:00:b0 (SET FEATURES) succeeded
Feb 24 17:22:59 jitte kernel: ata1.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device (Stat=0x51 Err=0x04)
Feb 24 17:22:59 jitte kernel: ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
Feb 24 17:22:59 jitte kernel: ata1.00: configured for UDMA/133
Feb 24 17:22:59 jitte kernel: sd 0:0:0:0: [sda] Starting disk
Feb 24 17:22:59 jitte kernel: usb 1-5: reset high-speed USB device number 2 using xhci_hcd
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af174f00
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af174f40
Feb 24 17:22:59 jitte kernel: usb 1-7: reset full-speed USB device number 5 using xhci_hcd
Feb 24 17:22:59 jitte systemd[1666]: Starting Bluetooth.
Feb 24 17:22:59 jitte systemd[1666]: Reached target Bluetooth.
Feb 24 17:22:59 jitte systemd[1]: Starting Bluetooth.
Feb 24 17:22:59 jitte systemd[1]: Reached target Bluetooth.
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88022dc25300
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88022dc25340
Feb 24 17:22:59 jitte kernel: usb 1-7: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
Feb 24 17:22:59 jitte kernel: usb 1-6: reset full-speed USB device number 3 using xhci_hcd
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af143780
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af1437c0
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af143800
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802320b0900
Feb 24 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802320b0940
Feb 24 17:22:59 jitte kernel: PM: resume of devices complete after 1347.427 msecs
Feb 24 17:22:59 jitte kernel: PM: Finishing wakeup.
Feb 24 17:22:59 jitte kernel: Restarting tasks ... 
Feb 24 17:22:59 jitte kernel: iwlwifi 0000:02:00.0: no hotplug settings from platform
Feb 24 17:22:59 jitte kernel: rtsx_pci 0000:03:00.0: no hotplug settings from platform
Feb 24 17:22:59 jitte kernel: r8169 0000:03:00.1: no hotplug settings from platform
Feb 24 17:22:59 jitte kernel: pci 0000:04:00.0: no hotplug settings from platform
Feb 24 17:22:59 jitte kernel: Bluetooth: hci0: read Intel version: 370710018002030d00
Feb 24 17:22:59 jitte kernel: Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.7.10-fw-1.80.2.3.d.bseq
Feb 24 17:22:59 jitte kernel: done.
Feb 24 17:22:59 jitte kernel: video LNXVIDEO:00: Restoring backlight state
Feb 24 17:22:59 jitte kernel: video LNXVIDEO:01: Restoring backlight state
Feb 24 17:22:59 jitte kernel: bbswitch: disabling discrete graphics
Feb 24 17:22:59 jitte kernel: ACPI Warning: \_SB_.PCI0.RP05.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20131218/nsarguments-95)
Feb 24 17:22:59 jitte kernel: [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
Feb 24 17:22:59 jitte kernel: Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
Feb 24 17:22:59 jitte bluetoothd[272]: Failed to set mode: Blocked through rfkill (0x12)
Feb 24 17:22:59 jitte systemd-sleep[14065]: System resumed.
Feb 24 17:22:59 jitte systemd[1]: Started Suspend.
Feb 24 17:22:59 jitte systemd[1]: Requested transaction contradicts existing jobs: File exists
Feb 24 17:22:59 jitte systemd[1]: Service sleep.target is not needed anymore. Stopping.
Feb 24 17:22:59 jitte systemd[1]: Stopping Sleep.
Feb 24 17:22:59 jitte systemd[1]: Stopped target Sleep.
Feb 24 17:22:59 jitte systemd[1]: Reached target Suspend.
Feb 24 17:22:59 jitte systemd-logind[276]: Operation finished.
Feb 24 17:22:59 jitte NetworkManager[273]: <info> wake requested (sleeping: yes  enabled: yes)
Feb 24 17:22:59 jitte NetworkManager[273]: <info> waking up and re-enabling...
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): bringing up device.
Feb 24 17:22:59 jitte kernel: iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S
Feb 24 17:22:59 jitte kernel: iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): preparing device.
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): deactivating device (reason 'managed') [2]
Feb 24 17:22:59 jitte NetworkManager[273]: <info> NetworkManager state is now DISCONNECTED
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): bringing up device.
Feb 24 17:22:59 jitte kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): preparing device.
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): deactivating device (reason 'managed') [2]
Feb 24 17:22:59 jitte kernel: r8169 0000:03:00.1 enp3s0f1: link down
Feb 24 17:22:59 jitte kernel: IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0) supports 5 scan SSIDs
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): supplicant interface state: starting -> ready
Feb 24 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Feb 24 17:22:59 jitte NetworkManager[273]: <warn> Trying to remove a non-existant call id.
Feb 24 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0): supplicant interface state: ready -> disconnected
Feb 24 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0) supports 5 scan SSIDs

Does anyone know what woke the machine up?

Offline

#5 2014-02-25 14:12:21

cmwhelan
Member
Registered: 2014-02-25
Posts: 1

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Are you running "journalctl -a"?  Occasionally some of the output can be hidden from you without that flag.  You could also try "journalctl -am" which sources a few additional log files.

I've been looking into a similar issue on my laptop, one of the problems I had was a device driver misbehaving and raising interrupts which caused the machine to wake up to handle them.  In my case it was the webcam, which I think may have a fault on my laptop so I've just blacklisted it.

Last edited by cmwhelan (2014-02-25 14:22:05)

Offline

#6 2014-02-25 21:21:03

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

So here's the update for today: sudo journalctl -amb.

Feb 25 13:39:00 jitte kernel: ACPI: \_SB_.PCI0: ACPI_NOTIFY_BUS_CHECK event
Feb 25 13:39:00 jitte kernel: ACPI: \_SB_.PCI0: ACPI_NOTIFY_BUS_CHECK event
Feb 25 13:39:00 jitte systemd-logind[276]: Lid closed.
Feb 25 13:39:00 jitte systemd-logind[276]: Suspending...
Feb 25 13:39:00 jitte NetworkManager[273]: <info> sleep requested (sleeping: no  enabled: yes)
Feb 25 13:39:00 jitte NetworkManager[273]: <info> sleeping or disabling...
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (wlp2s0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (wlp2s0): deactivating device (reason 'sleeping') [37]
Feb 25 13:39:00 jitte dhcpcd[22409]: received SIGTERM from PID 273, stopping
Feb 25 13:39:00 jitte dhcpcd[22409]: wlp2s0: removing interface
Feb 25 13:39:00 jitte dhcpcd[22409]: exited
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (wlp2s0): canceled DHCP transaction, DHCP client pid 22409
Feb 25 13:39:00 jitte NetworkManager[273]: <info> Removing DNS information from /usr/bin/resolvconf
Feb 25 13:39:00 jitte kernel: wlp2s0: deauthenticating from 00:24:d4:5f:dc:d8 by local choice (reason=3)
Feb 25 13:39:00 jitte kernel: cfg80211: Calling CRDA to update world regulatory domain
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (wlp2s0): cleaning up...
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (wlp2s0): taking down device.
Feb 25 13:39:00 jitte NetworkManager[273]: <info> NetworkManager state is now ASLEEP
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (enp3s0f1): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (enp3s0f1): cleaning up...
Feb 25 13:39:00 jitte NetworkManager[273]: <info> (enp3s0f1): taking down device.
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte dbus[278]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
Feb 25 13:39:00 jitte systemd[1]: Starting Network Manager Script Dispatcher Service...
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-north".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-east".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-south".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-west".
Feb 25 13:39:00 jitte gnome-session[1672]: Window manager warning: Trying to remove non-existent keybinding "move-focus-cycle".
Feb 25 13:39:00 jitte dbus[278]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Feb 25 13:39:01 jitte systemd[1]: Started Network Manager Script Dispatcher Service.
Feb 25 13:39:01 jitte gnome-session[1672]: Window manager warning: Log level 16: gsignal.c:2593: instance '0x15b1e20' has no handler with id '468541'
Feb 25 13:39:05 jitte systemd-logind[276]: Delay lock is active but inhibitor timeout is reached.
Feb 25 13:39:05 jitte systemd[1]: Starting Sleep.
Feb 25 13:39:05 jitte systemd[1]: Reached target Sleep.
Feb 25 13:39:05 jitte systemd[1]: Starting Suspend...
Feb 25 13:39:05 jitte systemd-sleep[22893]: Suspending system...
Feb 25 13:39:05 jitte kernel: PM: Syncing filesystems ... done.
Feb 25 13:39:05 jitte kernel: PM: Preparing system for mem sleep
Feb 25 13:39:05 jitte kernel: bbswitch: enabling discrete graphics
Feb 25 17:22:59 jitte kernel: Freezing user space processes ... (elapsed 0.002 seconds) done.
Feb 25 17:22:59 jitte systemd[1]: Time has been changed
Feb 25 17:22:59 jitte systemd[1666]: Time has been changed
Feb 25 17:22:59 jitte kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Feb 25 17:22:59 jitte kernel: PM: Entering mem sleep
Feb 25 17:22:59 jitte kernel: Suspending console(s) (use no_console_suspend to debug)
Feb 25 17:22:59 jitte kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Feb 25 17:22:59 jitte kernel: sd 0:0:0:0: [sda] Stopping disk
Feb 25 17:22:59 jitte kernel: PM: suspend of devices complete after 1098.028 msecs
Feb 25 17:22:59 jitte kernel: PM: late suspend of devices complete after 0.504 msecs
Feb 25 17:22:59 jitte kernel: pcieport 0000:00:1c.3: System wakeup enabled by ACPI
Feb 25 17:22:59 jitte kernel: ehci-pci 0000:00:1d.0: System wakeup enabled by ACPI
Feb 25 17:22:59 jitte kernel: PM: noirq suspend of devices complete after 66.728 msecs
Feb 25 17:22:59 jitte kernel: ACPI: Preparing to enter system sleep state S3
Feb 25 17:22:59 jitte kernel: PM: Saving platform NVS memory
Feb 25 17:22:59 jitte kernel: Disabling non-boot CPUs ...
Feb 25 17:22:59 jitte kernel: kvm: disabling virtualization on CPU1
Feb 25 17:22:59 jitte kernel: smpboot: CPU 1 is now offline
Feb 25 17:22:59 jitte kernel: kvm: disabling virtualization on CPU2
Feb 25 17:22:59 jitte kernel: smpboot: CPU 2 is now offline
Feb 25 17:22:59 jitte kernel: kvm: disabling virtualization on CPU3
Feb 25 17:22:59 jitte kernel: smpboot: CPU 3 is now offline
Feb 25 17:22:59 jitte kernel: ACPI: Low-level resume complete
Feb 25 17:22:59 jitte kernel: PM: Restoring platform NVS memory
Feb 25 17:22:59 jitte kernel: Enabling non-boot CPUs ...
Feb 25 17:22:59 jitte kernel: x86: Booting SMP configuration:
Feb 25 17:22:59 jitte kernel: smpboot: Booting Node 0 Processor 1 APIC 0x1
Feb 25 17:22:59 jitte kernel: kvm: enabling virtualization on CPU1
Feb 25 17:22:59 jitte kernel: Intel pstate controlling: cpu 1
Feb 25 17:22:59 jitte kernel: CPU1 is up
Feb 25 17:22:59 jitte kernel: smpboot: Booting Node 0 Processor 2 APIC 0x2
Feb 25 17:22:59 jitte kernel: kvm: enabling virtualization on CPU2
Feb 25 17:22:59 jitte kernel: Intel pstate controlling: cpu 2
Feb 25 17:22:59 jitte kernel: CPU2 is up
Feb 25 17:22:59 jitte kernel: smpboot: Booting Node 0 Processor 3 APIC 0x3
Feb 25 17:22:59 jitte kernel: kvm: enabling virtualization on CPU3
Feb 25 17:22:59 jitte kernel: Intel pstate controlling: cpu 3
Feb 25 17:22:59 jitte kernel: CPU3 is up
Feb 25 17:22:59 jitte kernel: ACPI: Waking up from system sleep state S3
Feb 25 17:22:59 jitte kernel: ehci-pci 0000:00:1d.0: System wakeup disabled by ACPI
Feb 25 17:22:59 jitte kernel: PM: noirq resume of devices complete after 132.766 msecs
Feb 25 17:22:59 jitte systemd[1666]: Service bluetooth.target is not needed anymore. Stopping.
Feb 25 17:22:59 jitte systemd[1]: Service bluetooth.target is not needed anymore. Stopping.
Feb 25 17:22:59 jitte systemd[1666]: Stopping Bluetooth.
Feb 25 17:22:59 jitte systemd[1666]: Stopped target Bluetooth.
Feb 25 17:22:59 jitte systemd[1]: Stopping Bluetooth.
Feb 25 17:22:59 jitte systemd[1]: Stopped target Bluetooth.
Feb 25 17:22:59 jitte systemd[1666]: Starting Bluetooth.
Feb 25 17:22:59 jitte systemd[1666]: Reached target Bluetooth.
Feb 25 17:22:59 jitte systemd[1]: Starting Bluetooth.
Feb 25 17:22:59 jitte systemd[1]: Reached target Bluetooth.
Feb 25 17:22:59 jitte kernel: PM: early resume of devices complete after 0.347 msecs
Feb 25 17:22:59 jitte kernel: hpet1: lost 2539 rtc interrupts
Feb 25 17:22:59 jitte kernel: mei_me 0000:00:16.0: irq 58 for MSI/MSI-X
Feb 25 17:22:59 jitte kernel: usb usb1: root hub lost power or was reset
Feb 25 17:22:59 jitte kernel: usb usb2: root hub lost power or was reset
Feb 25 17:22:59 jitte kernel: snd_hda_intel 0000:00:03.0: irq 61 for MSI/MSI-X
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: irq 62 for MSI/MSI-X
Feb 25 17:22:59 jitte kernel: snd_hda_intel 0000:00:1b.0: irq 63 for MSI/MSI-X
Feb 25 17:22:59 jitte kernel: pcieport 0000:00:1c.3: System wakeup disabled by ACPI
Feb 25 17:22:59 jitte kernel: dpm_run_callback(): pnp_bus_resume+0x0/0xa0 returns -19
Feb 25 17:22:59 jitte kernel: PM: Device 00:07 failed to resume: error -19
Feb 25 17:22:59 jitte kernel: usb 3-1: reset high-speed USB device number 2 using ehci-pci
Feb 25 17:22:59 jitte kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Feb 25 17:22:59 jitte kernel: ata1.00: ACPI cmd ef/10:09:00:00:00:b0 (SET FEATURES) succeeded
Feb 25 17:22:59 jitte kernel: ata1.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device (Stat=0x51 Err=0x04)
Feb 25 17:22:59 jitte kernel: ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
Feb 25 17:22:59 jitte kernel: ata1.00: ACPI cmd ef/10:09:00:00:00:b0 (SET FEATURES) succeeded
Feb 25 17:22:59 jitte kernel: ata1.00: ACPI cmd 00/00:00:00:00:00:a0 (NOP) rejected by device (Stat=0x51 Err=0x04)
Feb 25 17:22:59 jitte kernel: ata1.00: failed to get NCQ Send/Recv Log Emask 0x1
Feb 25 17:22:59 jitte kernel: ata1.00: configured for UDMA/133
Feb 25 17:22:59 jitte kernel: sd 0:0:0:0: [sda] Starting disk
Feb 25 17:22:59 jitte kernel: usb 1-5: reset high-speed USB device number 2 using xhci_hcd
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af174f00
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af174f40
Feb 25 17:22:59 jitte kernel: usb 1-6: reset full-speed USB device number 3 using xhci_hcd
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af143780
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af1437c0
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800af143800
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802320b0900
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8802320b0940
Feb 25 17:22:59 jitte kernel: usb 1-7: reset full-speed USB device number 5 using xhci_hcd
Feb 25 17:22:59 jitte kernel: [drm] Enabling RC6 states: RC6 on, RC6p off, RC6pp off
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88022dc25300
Feb 25 17:22:59 jitte kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff88022dc25340
Feb 25 17:22:59 jitte kernel: usb 1-7: ep 0x2 - rounding interval to 64 microframes, ep desc says 80 microframes
Feb 25 17:22:59 jitte kernel: PM: resume of devices complete after 1355.352 msecs
Feb 25 17:22:59 jitte kernel: PM: Finishing wakeup.
Feb 25 17:22:59 jitte kernel: Restarting tasks ... 
Feb 25 17:22:59 jitte kernel: iwlwifi 0000:02:00.0: no hotplug settings from platform
Feb 25 17:22:59 jitte kernel: rtsx_pci 0000:03:00.0: no hotplug settings from platform
Feb 25 17:22:59 jitte kernel: r8169 0000:03:00.1: no hotplug settings from platform
Feb 25 17:22:59 jitte kernel: pci 0000:04:00.0: no hotplug settings from platform
Feb 25 17:22:59 jitte kernel: done.
Feb 25 17:22:59 jitte kernel: video LNXVIDEO:00: Restoring backlight state
Feb 25 17:22:59 jitte kernel: video LNXVIDEO:01: Restoring backlight state
Feb 25 17:22:59 jitte kernel: bbswitch: disabling discrete graphics
Feb 25 17:22:59 jitte kernel: ACPI Warning: \_SB_.PCI0.RP05.PEGP._DSM: Argument #4 type mismatch - Found [Buffer], ACPI requires [Package] (20131218/nsarguments-95)
Feb 25 17:22:59 jitte kernel: Bluetooth: hci0: read Intel version: 370710018002030d00
Feb 25 17:22:59 jitte kernel: Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.7.10-fw-1.80.2.3.d.bseq
Feb 25 17:22:59 jitte kernel: Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
Feb 25 17:22:59 jitte bluetoothd[272]: Failed to set mode: Blocked through rfkill (0x12)
Feb 25 17:22:59 jitte systemd-sleep[22893]: System resumed.
Feb 25 17:22:59 jitte systemd[1]: Started Suspend.
Feb 25 17:22:59 jitte systemd[1]: Requested transaction contradicts existing jobs: File exists
Feb 25 17:22:59 jitte systemd[1]: Service sleep.target is not needed anymore. Stopping.
Feb 25 17:22:59 jitte systemd[1]: Stopping Sleep.
Feb 25 17:22:59 jitte systemd[1]: Stopped target Sleep.
Feb 25 17:22:59 jitte systemd[1]: Reached target Suspend.
Feb 25 17:22:59 jitte systemd-logind[276]: Operation finished.
Feb 25 17:22:59 jitte NetworkManager[273]: <info> wake requested (sleeping: yes  enabled: yes)
Feb 25 17:22:59 jitte NetworkManager[273]: <info> waking up and re-enabling...
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): bringing up device.
Feb 25 17:22:59 jitte kernel: iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S
Feb 25 17:22:59 jitte kernel: iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S
Feb 25 17:22:59 jitte kernel: IPv6: ADDRCONF(NETDEV_UP): wlp2s0: link is not ready
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): preparing device.
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (wlp2s0): deactivating device (reason 'managed') [2]
Feb 25 17:22:59 jitte NetworkManager[273]: <info> NetworkManager state is now DISCONNECTED
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): bringing up device.
Feb 25 17:22:59 jitte kernel: r8169 0000:03:00.1 enp3s0f1: link down
Feb 25 17:22:59 jitte kernel: IPv6: ADDRCONF(NETDEV_UP): enp3s0f1: link is not ready
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): preparing device.
Feb 25 17:22:59 jitte NetworkManager[273]: <info> (enp3s0f1): deactivating device (reason 'managed') [2]
Feb 25 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0) supports 5 scan SSIDs
Feb 25 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0): supplicant interface state: starting -> ready
Feb 25 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42]
Feb 25 17:23:00 jitte NetworkManager[273]: <warn> Trying to remove a non-existant call id.
Feb 25 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0): supplicant interface state: ready -> disconnected
Feb 25 17:23:00 jitte NetworkManager[273]: <info> (wlp2s0) supports 5 scan SSIDs
Feb 25 17:23:02 jitte kernel: atkbd serio0: Unknown key pressed (translated set 2, code 0x8d on isa0060/serio0).
Feb 25 17:23:02 jitte kernel: atkbd serio0: Use 'setkeycodes e00d <keycode>' to make it known.

What's really odd is that the laptop woke up at the exact same time as yesterday. What could be causing that?

I'm using gnome-shell v3.10.3-1, linux-mainline v3.14rc3-1.

Offline

#7 2014-02-25 21:23:56

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

PS: I will try to follow cmwhelan's advice and blacklist webcam and bluetooth. Let's see what happens. But I'm really curious why the laptop wakes up likes at this exact time two days in a row.

Offline

#8 2014-03-11 00:25:42

indigo
Member
Registered: 2014-03-11
Posts: 5

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Have you ever found out whether blacklisting webcam and/or bluetooth works? I am having a similar issue with my Dell Chromebook (also Haswell), and will try blacklisting the bluetooth module.

Offline

#9 2014-03-11 20:38:00

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

So I deactivated the bluetooth service:

sudo systemctl disable bluetooth.service

And I blacklisted it in /etc/modprobe/blacklist.conf (if you do that do not forget to add this file in FILES section of /etc/mkinitcpio.conf).

cat /etc/modprobe.d/blacklist.conf
blacklist btusb
blacklist rfcomm
blacklist bnep
blacklist bluetooth

The issue is occurring less, but still occurs from time to time, which makes it even harder to debug.

I also disabled uvcvideo module:

sudo modproble -r uvcvideo

No luck. I have no idea what is causing the laptop to resume while lid is still closed.

Last edited by jtheoof (2014-03-11 20:41:03)

Offline

#10 2014-03-12 03:17:54

indigo
Member
Registered: 2014-03-11
Posts: 5

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

All right. I've tried blacklisting btusb but it still wakes up. I'll try to rfkill it tonight, and then I'll try blacklisting the webcam modules.

This is my systemd journal from when it wakes up; nothing too interesting:

Mar 11 09:08:39 kronos kernel: PM: Preparing system for mem sleep
Mar 11 09:08:39 kronos kernel: Freezing user space processes ... (elapsed 0.001 seconds) done.
Mar 11 09:08:39 kronos kernel: Freezing remaining freezable tasks ... (elapsed 0.001 seconds) done.
Mar 11 09:08:39 kronos kernel: PM: Entering mem sleep
Mar 11 09:08:39 kronos kernel: Suspending console(s) (use no_console_suspend to debug)
Mar 11 09:08:39 kronos kernel: wlp1s0: deauthenticating from 58:6d:8f:b2:b1:eb by local choice (reason=3)
Mar 11 09:08:39 kronos kernel: cfg80211: Calling CRDA to update world regulatory domain
Mar 11 09:08:39 kronos kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache
Mar 11 09:08:39 kronos kernel: sd 0:0:0:0: [sda] Stopping disk
Mar 11 09:08:39 kronos kernel: PM: suspend of devices complete after 1190.727 msecs
Mar 11 09:08:39 kronos kernel: PM: late suspend of devices complete after 0.227 msecs
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: System wakeup enabled by ACPI
Mar 11 09:08:39 kronos kernel: PM: noirq suspend of devices complete after 26.679 msecs
Mar 11 09:08:39 kronos kernel: ACPI: Preparing to enter system sleep state S3
Mar 11 09:08:39 kronos kernel: PM: Saving platform NVS memory
Mar 11 09:08:39 kronos kernel: Disabling non-boot CPUs ...
Mar 11 09:08:39 kronos kernel: kvm: disabling virtualization on CPU1
Mar 11 09:08:39 kronos kernel: smpboot: CPU 1 is now offline
Mar 11 09:08:39 kronos kernel: ACPI: Low-level resume complete
Mar 11 09:08:39 kronos kernel: PM: Restoring platform NVS memory
Mar 11 09:08:39 kronos kernel: Enabling non-boot CPUs ...
Mar 11 09:08:39 kronos kernel: x86: Booting SMP configuration:
Mar 11 09:08:39 kronos kernel: smpboot: Booting Node 0 Processor 1 APIC 0x2
Mar 11 09:08:39 kronos kernel: kvm: enabling virtualization on CPU1
Mar 11 09:08:39 kronos kernel: Intel pstate controlling: cpu 1
Mar 11 09:08:39 kronos kernel: CPU1 is up
Mar 11 09:08:39 kronos kernel: ACPI: Waking up from system sleep state S3
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: System wakeup disabled by ACPI
Mar 11 09:08:39 kronos kernel: pci 0000:00:1d.0: Refused to change power state, currently in D3
Mar 11 09:08:39 kronos kernel: PM: noirq resume of devices complete after 166.239 msecs
Mar 11 09:08:39 kronos kernel: PM: early resume of devices complete after 0.149 msecs
Mar 11 09:08:39 kronos kernel: snd_hda_intel 0000:00:03.0: irq 59 for MSI/MSI-X
Mar 11 09:08:39 kronos kernel: snd_hda_intel 0000:00:1b.0: irq 61 for MSI/MSI-X
Mar 11 09:08:39 kronos kernel: ath: phy0: ASPM enabled: 0x43
Mar 11 09:08:39 kronos kernel: usb 1-3: reset high-speed USB device number 2 using xhci_hcd
Mar 11 09:08:39 kronos kernel: ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
Mar 11 09:08:39 kronos kernel: ata1.00: configured for UDMA/133
Mar 11 09:08:39 kronos kernel: sd 0:0:0:0: [sda] Starting disk
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8801759e4b40
Mar 11 09:08:39 kronos kernel: usb 1-4: reset full-speed USB device number 4 using xhci_hcd
Mar 11 09:08:39 kronos kernel: usb 1-4: device firmware changed
Mar 11 09:08:39 kronos kernel: PM: resume of devices complete after 647.882 msecs
Mar 11 09:08:39 kronos kernel: PM: Finishing wakeup.
Mar 11 09:08:39 kronos kernel: Restarting tasks ... done.
Mar 11 09:08:39 kronos kernel: video LNXVIDEO:00: Restoring backlight state
Mar 11 09:08:39 kronos systemd[1]: Time has been changed
Mar 11 09:08:39 kronos systemd[336]: Time has been changed
Mar 11 09:08:39 kronos systemd-sleep[492]: System resumed.
Mar 11 09:08:39 kronos systemd[1]: Started Suspend.
Mar 11 09:08:39 kronos systemd[1]: Service sleep.target is not needed anymore. Stopping.
Mar 11 09:08:39 kronos systemd[1]: Stopping Sleep.
Mar 11 09:08:39 kronos systemd[1]: Stopped target Sleep.
Mar 11 09:08:39 kronos systemd[1]: Starting Suspend.
Mar 11 09:08:39 kronos systemd[1]: Reached target Suspend.
Mar 11 09:08:39 kronos systemd-logind[167]: Operation finished.
Mar 11 09:08:39 kronos systemd-logind[167]: Suspending...
Mar 11 09:08:39 kronos systemd[1]: Starting Sleep.
Mar 11 09:08:39 kronos systemd[1]: Reached target Sleep.
Mar 11 09:08:39 kronos systemd-sleep[545]: Suspending system...
Mar 11 09:08:39 kronos kernel: usb 1-4: USB disconnect, device number 4
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800797decc0
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800797ded40
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800797ded00
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800378ae440
Mar 11 09:08:39 kronos kernel: xhci_hcd 0000:00:14.0: xHCI xhci_drop_endpoint called with disabled ep ffff8800378ae400
Mar 11 09:08:39 kronos dhcpcd[422]: wlp1s0: carrier lost
Mar 11 09:08:39 kronos dhcpcd[422]: wlp1s0: deleting route to 192.168.1.0/24
Mar 11 09:08:39 kronos dhcpcd[422]: wlp1s0: deleting default route via 192.168.1.1

Offline

#11 2014-03-12 04:04:43

frank604
Member
From: BC, Canada
Registered: 2011-04-20
Posts: 1,212

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

On a long shot (just brainstorming and double checking) what is the output for cat /proc/acpi/wakeup ?

Offline

#12 2014-03-12 04:10:42

indigo
Member
Registered: 2014-03-11
Posts: 5

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

This is my /proc/acpi/wakeup:

Device	S-state	  Status   Sysfs node
LID0	  S4	*enabled 
TPAD	  S3	*enabled   pnp:00:00
TSCR	  S3	*enabled  pnp:00:01
HDEF	  S3	*disabled  pci:0000:00:1b.0
EHCI	  S3	*enabled  pci:0000:00:1d.0
XHCI	  S3	*enabled  pci:0000:00:14.0

I'll try disabling everything in here and seeing if that makes any difference tonight.

Offline

#13 2014-03-12 04:14:08

frank604
Member
From: BC, Canada
Registered: 2011-04-20
Posts: 1,212

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Also, what is the output for 'cat /sys/class/rtc/rtc0/wakealarm' ?  the directory might be labeled a little differently but this is your system's "alarm clock" to wake up out of suspend.  Should return with nothing.

My output for the above line is:

┌─[FreedomX /sys/class/rtc/rtc0 ]
└─╼ cat /proc/acpi/wakeup                                                    
Device	S-state	  Status   Sysfs node
P0P2	  S3	*disabled
EC	  S3	*disabled
HDEF	  S3	*disabled  pci:0000:00:1b.0
RP01	  S3	*disabled  pci:0000:00:1c.0
RP02	  S3	*disabled  pci:0000:00:1c.1
RP03	  S3	*disabled  pci:0000:00:1c.2
ARPT	  S4	*disabled  pci:0000:03:00.0
RP05	  S3	*disabled  pci:0000:00:1c.4
RP06	  S3	*disabled  pci:0000:00:1c.5
SPIT	  S3	*disabled
XHC1	  S3	*enabled   pci:0000:00:14.0
ADP1	  S3	*disabled  platform:ACPI0003:00
LID0	  S3	*enabled 

I have only xhc1 and lid0 enabled to allow wakeup.  Which makes sense to me.  I dunno what your 'enabled' devices are though so a google is recommended and hopefully leads to a solution.  Worst case scenario, at least you explore a little more of your system right? big_smile

Looking at your output, look into TPAD      S3    *enabled   pnp:00:00
TSCR      S3    *enabled  pnp:00:01
Since they are "enabled" to wake your system up.  The tpad sounds like a trackpad.   I am not knowledgable in this but only brainstorming hoping someone else hops in to fill in the gap.

Last edited by frank604 (2014-03-12 04:18:07)

Offline

#14 2014-03-12 04:19:38

indigo
Member
Registered: 2014-03-11
Posts: 5

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Yeah, the wakealarm returns nothing. Anyway, I am going to sleep soon... hopefully it doesn't wake up tomorrow morning with these settings! Thanks for all the help. smile

Offline

#15 2014-03-12 12:07:14

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Hi Franck,

Thanks for the feedback.

Here is my output:

cat /proc/acpi/wakeup
Device  S-state   Status   Sysfs node
P0P1      S4    *disabled
GLAN      S4    *disabled
EHC1      S4    *enabled   pci:0000:00:1d.0
EHC2      S4    *disabled
TPD4      S4    *disabled
TPD7      S0    *disabled
TPD8      S0    *disabled
PXSX      S4    *disabled
RP03      S4    *disabled  pci:0000:00:1c.2
PXSX      S4    *disabled  pci:0000:02:00.0
RP04      S3    *disabled  pci:0000:00:1c.3
PXSX      S4    *disabled  pci:0000:03:00.0
PXSX      S4    *disabled
PEG0      S4    *disabled
PEGP      S4    *disabled
PEG1      S4    *disabled
PEG2      S4    *disabled
LID0      S3    *enabled

So it's probably the EHC1 device that's causing the problem.
After some googling, there seems to be issues with this device.
Even though the link is Mac related, the device is likely the same. So it's still probably the bluetooth that's causing issues on my machine.

Do you know how to disable from the wakeup list?

Offline

#16 2014-03-12 12:23:18

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

OK so I disabled it by running:

 # echo 'EHC1' > /proc/acpi/wakeup
 # cat /proc/acpi/wakeup
Device  S-state   Status   Sysfs node
P0P1      S4    *disabled
GLAN      S4    *disabled
EHC1      S4    *disabled  pci:0000:00:1d.0
EHC2      S4    *disabled
TPD4      S4    *disabled
TPD7      S0    *disabled
TPD8      S0    *disabled
PXSX      S4    *disabled
RP03      S4    *disabled  pci:0000:00:1c.2
PXSX      S4    *disabled  pci:0000:02:00.0
RP04      S3    *disabled  pci:0000:00:1c.3
PXSX      S4    *disabled  pci:0000:03:00.0
PXSX      S4    *disabled
PEG0      S4    *disabled
PEGP      S4    *disabled
PEG1      S4    *disabled
PEG2      S4    *disabled
LID0      S3    *enabled

So now, only the LID0 device is allowed to wake up the system. I'll try these settings for a few days, let's see how things turn out.

In the meantime, does anyone know how to make the disabling permanent? (udev rules?).

Offline

#17 2014-03-12 16:42:00

frank604
Member
From: BC, Canada
Registered: 2011-04-20
Posts: 1,212

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

If the wakeup for the device is controlled in bios, then great.  If a acpi event controller can controller, great.  If none of these exist on your system, udev rule.  That is all I can think of, maybe others can add on it but that should get you going.

Remember, the echo <devicename> will only toggle on/off until next reboot/suspend/shutdown.  So to fully test and until you get one of the perm toggles in place, make sure you manually echo so the test is fair. 

Good luck for the next few days!

Offline

#18 2014-03-12 20:52:01

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Thanks for the feedback Franck, I'll probably make a udev rule, since I don't think I can control it from the BIOS and I don't know how to set up a acpi controller.

In case anyone is interested, this the description of the device EHC1 which seems to be causing the issue:

00:1d.0 USB controller: Intel Corporation Lynx Point-LP USB EHCI #1 (rev 04) (prog-if 20 [EH
        Subsystem: Dell Device 05f9
        Control: I/O- Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR-
        Status: Cap+ 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >
        Latency: 0
        Interrupt: pin A routed to IRQ 23
        Region 0: Memory at e361d000 (32-bit, non-prefetchable) [size=1K]
        Capabilities: [50] Power Management version 3
                Flags: PMEClk- DSI- D1- D2- AuxCurrent=375mA PME(D0+,D1-,D2-,D3hot+,D3cold+)
                Status: D0 NoSoftRst+ PME-Enable- DSel=0 DScale=0 PME-
        Capabilities: [58] Debug port: BAR=1 offset=00a0
        Capabilities: [98] PCI Advanced Features
                AFCap: TP+ FLR+
                AFCtrl: FLR-
                AFStatus: TP-
        Kernel driver in use: ehci-pci
        Kernel modules: ehci_pci

I'll keep you posted of my results at the end of the week.

Offline

#19 2014-03-16 18:04:51

indigo
Member
Registered: 2014-03-11
Posts: 5

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Well, it seems that editing /proc/acpi/wakeup worked perfectly. My laptop has not woken up randomly in four days. Thanks!

Offline

#20 2014-03-16 18:09:08

frank604
Member
From: BC, Canada
Registered: 2011-04-20
Posts: 1,212

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Indigo, thanks for the update and great to hera it has been resolved.  Hope the same effect occurred for the OP.

Offline

#21 2014-03-16 18:35:46

jtheoof
Member
Registered: 2014-01-30
Posts: 23

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

Same for me! Laptop sleeps quietly now, no random wakeup since my last post.

For info this is the udev rule that I wrote to deactivate the ehci device:

# cat /etc/udev/rules.d/ehc1.rules
ACTION=="add", KERNEL=="0000:00:1d.0", SUBSYSTEM=="pci", RUN+="/bin/sh -c 'echo EHC1 > /proc/acpi/wakeup'"

There is probably better but it seems to work fine. The device is now always disabled in the list every time I restart my laptop.

Thanks Frank for putting us on the right track.

Offline

#22 2014-03-16 18:48:10

frank604
Member
From: BC, Canada
Registered: 2011-04-20
Posts: 1,212

Re: [SOLVED] Laptop resumes from suspend while lid is still closed

No problemo. Please edit the first post and mark it solved.

Offline

Board footer

Powered by FluxBB