My ubuntu-running X201 thinkpad (running the latest Ubuntu), has recently developed a problem after updating.
When I wake the machine (by opening the lid) it wakes up just long enough to show the lock screen, then it goes back to sleep. I can then wake it up by pressing the power button and everything behaves normally until I put it to sleep again (by closing the lid), and I have the same issue waking it up again. This has turned out to be a frustrating thing to search for, since there is a long and sordid history of sleep/resume problems with Ubuntu. Further complicating issues, I'm running XFCE, with Xmonad as the window manager and xfce4-power-manager. Does anyone have debugging suggestions? I've included a bit from my syslog showing the end of the first wake, and the subsequent sleep, but it doesn't look very informative to me. Thanks! Rogan Here's a snippit from syslog: Mar 19 08:00:55 escher kernel: [48053.687387] PM: Finishing wakeup. Mar 19 08:00:55 escher acpid: client 1200[0:0] has disconnected Mar 19 08:00:55 escher kernel: [48053.687388] Restarting tasks ... done. Mar 19 08:00:55 escher kernel: [48053.693468] video LNXVIDEO:00: Restoring backlight state Mar 19 08:00:55 escher acpid: client connected from 1200[0:0] Mar 19 08:00:55 escher acpid: 1 client rule loaded Mar 19 08:00:56 escher anacron[23861]: Anacron 2.3 started on 2013-03-19 Mar 19 08:00:56 escher anacron[23861]: Will run job `cron.daily' in 5 min. Mar 19 08:00:56 escher anacron[23861]: Jobs will be executed sequentially Mar 19 08:00:56 escher NetworkManager[931]: <info> wake requested (sleeping: yes enabled: yes) Mar 19 08:00:56 escher NetworkManager[931]: <info> waking up and re-enabling... Mar 19 08:00:56 escher NetworkManager[931]: <info> (eth0): now managed Mar 19 08:00:56 escher NetworkManager[931]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Mar 19 08:00:56 escher NetworkManager[931]: <info> (eth0): bringing up device. Mar 19 08:00:56 escher kernel: [48054.197765] e1000e 0000:00:19.0: irq 42 for MSI/MSI-X Mar 19 08:00:56 escher NetworkManager[931]: <info> (eth0): preparing device. Mar 19 08:00:56 escher NetworkManager[931]: <info> (eth0): deactivating device (reason 'managed') [2] Mar 19 08:00:56 escher NetworkManager[931]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Mar 19 08:00:56 escher NetworkManager[931]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889) Mar 19 08:00:56 escher kernel: [48054.300773] e1000e 0000:00:19.0: irq 42 for MSI/MSI-X Mar 19 08:00:56 escher kernel: [48054.302493] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Mar 19 08:00:56 escher kernel: [48054.303836] iwlwifi 0000:02:00.0: L1 Enabled; Disabling L0S Mar 19 08:00:56 escher kernel: [48054.304030] iwlwifi 0000:02:00.0: Radio type=0x1-0x3-0x1 Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): now managed Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2] Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): bringing up device. Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): preparing device. Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): deactivating device (reason 'managed') [2] Mar 19 08:00:56 escher kernel: [48054.419874] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0) supports 5 scan SSIDs Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): supplicant interface state: starting -> ready Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): device state change: unavailable -> disconnected (reason 'supplicant-available') [20 30 42] Mar 19 08:00:56 escher NetworkManager[931]: <warn> Trying to remove a non-existant call id. Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0): supplicant interface state: ready -> inactive Mar 19 08:00:56 escher NetworkManager[931]: <info> (wlan0) supports 5 scan SSIDs Mar 19 08:00:57 escher NetworkManager[931]: <info> VPN service 'openvpn' disappeared Mar 19 08:00:58 escher NetworkManager[931]: <info> sleep requested (sleeping: no enabled: yes) Mar 19 08:00:58 escher NetworkManager[931]: <info> sleeping or disabling... Mar 19 08:00:58 escher NetworkManager[931]: <info> (eth0): now unmanaged Mar 19 08:00:58 escher NetworkManager[931]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37] Mar 19 08:00:58 escher NetworkManager[931]: <info> (eth0): cleaning up... Mar 19 08:00:58 escher NetworkManager[931]: <info> (eth0): taking down device. Mar 19 08:00:58 escher NetworkManager[931]: <info> (wlan0): now unmanaged Mar 19 08:00:58 escher NetworkManager[931]: <info> (wlan0): device state change: disconnected -> unmanaged (reason 'sleeping') [30 10 37] Mar 19 08:00:58 escher NetworkManager[931]: <info> (wlan0): cleaning up... Mar 19 08:00:58 escher NetworkManager[931]: <info> (wlan0): taking down device. Mar 19 08:01:02 escher kernel: [48059.509615] PM: Syncing filesystems ... done. Mar 19 08:01:02 escher kernel: [48059.923320] PM: Preparing system for mem sleep Mar 19 08:01:09 escher kernel: [48059.938816] Freezing user space processes ... (elapsed 0.01 seconds) done. Mar 19 08:01:09 escher kernel: [48059.954729] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. Mar 19 08:01:09 escher kernel: [48059.970684] PM: Entering mem sleep Mar 19 08:01:09 escher kernel: [48059.970767] Suspending console(s) (use no_console_suspend to debug) Mar 19 08:01:09 escher kernel: [48060.151712] PM: suspend of drv:psmouse dev:serio2 complete after 181.239 msecs Mar 19 08:01:09 escher kernel: [48060.151942] sd 0:0:0:0: [sda] Synchronizing SCSI cache Mar 19 08:01:09 escher kernel: [48060.241586] sd 0:0:0:0: [sda] Stopping disk Mar 19 08:01:09 escher kernel: [48060.393763] ACPI handle has no context! Mar 19 08:01:09 escher kernel: [48060.417600] e1000e 0000:00:19.0: wake-up capability enabled by ACPI Mar 19 08:01:09 escher kernel: [48060.425400] i915 0000:00:02.0: power state changed by ACPI to D3 Mar 19 08:01:09 escher kernel: [48060.616922] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 223.815 msecs Mar 19 08:01:09 escher kernel: [48061.238992] PM: suspend of drv:sd dev:0:0:0:0 complete after 1089.779 msecs Mar 19 08:01:09 escher kernel: [48061.239012] PM: suspend of drv:scsi dev:target0:0:0 complete after 1089.797 msecs Mar 19 08:01:09 escher kernel: [48061.239069] PM: suspend of drv:scsi dev:host0 complete after 1036.891 msecs Mar 19 08:01:09 escher kernel: [48061.239300] PM: suspend of drv: dev:ata1 complete after 1037.083 msecs Mar 19 08:01:09 escher kernel: [48061.255392] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 863.898 msecs Mar 19 08:01:09 escher kernel: [48061.255451] PM: suspend of drv: dev:pci0000:00 complete after 863.905 msecs Mar 19 08:01:09 escher kernel: [48061.255510] PM: suspend of devices complete after 1287.832 msecs Mar 19 08:01:09 escher kernel: [48061.255513] PM: suspend devices took 1.288 seconds Mar 19 08:01:09 escher kernel: [48061.255671] PM: late suspend of devices complete after 0.157 msecs Mar 19 08:01:09 escher kernel: [48061.271477] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI Mar 19 08:01:09 escher kernel: [48061.287230] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3 Mar 19 08:01:09 escher kernel: [48061.287430] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI Mar 19 08:01:09 escher kernel: [48061.303191] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3 Mar 19 08:01:09 escher kernel: [48061.319253] PM: noirq suspend of devices complete after 63.735 msecs Mar 19 08:01:09 escher kernel: [48061.319457] ACPI: Preparing to enter system sleep state S3 Mar 19 08:01:09 escher kernel: [48061.490761] PM: Saving platform NVS memory Mar 19 08:01:09 escher kernel: [48061.493730] Disabling non-boot CPUs ... _______________________________________________ PLUG mailing list [email protected] http://lists.pdxlinux.org/mailman/listinfo/plug
