Fail to timeout & sleep on battery power at login screen

[tarverator]

I am running Linux Mint Mate Maya on a ThinkPad X230 laptop. Today I did as I usually do when I was ready to leave the house, which was to close the lid (which usually puts the machine to sleep) and put the laptop into the padded compartment of my shoulder bag and head out. A few hours later, I found the machine in my bag -- not at all sleeping like it was supposed to be, but quite warm with the battery 2/3 discharged and idling at the Login Screen!

I don't know how the machine got into this state, but I find it disturbing that the settings in the Power Manager Preferences (to put the machine to sleep after 30 minutes if inactive and on battery power) seemed not to kick in, evidently because I was at the Login Screen for some reason.

Any ideas?

[tarverator]

I am not sure I have started this thread in the right place, as I thought at first it was a question of finding the correct setting in the Login Screen preferences. Now, I have realized that my laptop refuses to sleep/suspend at all.

Here is what appears in the syslog when I attempt to put the machine into suspend via Menu>Quit>Suspend:

Sep 9 14:52:55 thinx NetworkManager[793]: <info> sleep requested (sleeping: no enabled: yes)
Sep 9 14:52:55 thinx NetworkManager[793]: <info> sleeping or disabling...
Sep 9 14:52:55 thinx NetworkManager[793]: <info> (eth0): now unmanaged
Sep 9 14:52:55 thinx NetworkManager[793]: <info> (eth0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Sep 9 14:52:55 thinx NetworkManager[793]: <info> (wlan0): now unmanaged
Sep 9 14:52:55 thinx NetworkManager[793]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Sep 9 14:52:56 thinx anacron[13563]: Anacron 2.3 started on 2012-09-09
Sep 9 14:52:56 thinx anacron[13563]: Normal exit (0 jobs run)
Sep 9 14:52:56 thinx kernel: [19257.290413] e1000e 0000:00:19.0: BAR 0: set to [mem 0xf2500000-0xf251ffff] (PCI address [0xf2500000-0xf251ffff])
Sep 9 14:52:56 thinx kernel: [19257.290425] e1000e 0000:00:19.0: BAR 1: set to [mem 0xf253b000-0xf253bfff] (PCI address [0xf253b000-0xf253bfff])
Sep 9 14:52:56 thinx kernel: [19257.290435] e1000e 0000:00:19.0: BAR 2: set to [io 0x5080-0x509f] (PCI address [0x5080-0x509f])
Sep 9 14:52:56 thinx kernel: [19257.290464] e1000e 0000:00:19.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 14:52:56 thinx kernel: [19257.290501] e1000e 0000:00:19.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Sep 9 14:52:56 thinx kernel: [19257.290551] e1000e 0000:00:19.0: PME# disabled
Sep 9 14:52:57 thinx kernel: [19258.144954] PM: Syncing filesystems ... done.
Sep 9 14:52:57 thinx kernel: [19258.146988] PM: Preparing system for mem sleep
Sep 9 14:53:00 thinx kernel: [19258.588540] Freezing user space processes ... (elapsed 0.01 seconds) done.
Sep 9 14:53:00 thinx kernel: [19258.604514] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Sep 9 14:53:00 thinx kernel: [19258.620491] PM: Entering mem sleep
Sep 9 14:53:00 thinx kernel: [19258.620544] Suspending console(s) (use no_console_suspend to debug)
Sep 9 14:53:00 thinx kernel: [19258.776294] PM: suspend of drv:psmouse dev:serio2 complete after 155.890 msecs
Sep 9 14:53:00 thinx kernel: [19258.776445] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Sep 9 14:53:00 thinx kernel: [19258.800380] sd 0:0:0:0: [sda] Stopping disk
Sep 9 14:53:00 thinx kernel: [19258.995922] PM: suspend of drv:tpm_tis dev:00:0a complete after 176.314 msecs
Sep 9 14:53:00 thinx kernel: [19258.996214] ehci_hcd 0000:00:1d.0: PCI INT A disabled
Sep 9 14:53:00 thinx kernel: [19258.996252] ehci_hcd 0000:00:1a.0: PCI INT A disabled
Sep 9 14:53:00 thinx kernel: [19258.996702] ACPI handle has no context!
Sep 9 14:53:00 thinx kernel: [19258.997384] sdhci-pci 0000:02:00.0: PCI INT A disabled
Sep 9 14:53:00 thinx kernel: [19259.011807] i915 0000:00:02.0: power state changed by ACPI to D3
Sep 9 14:53:00 thinx kernel: [19259.062206] PM: suspend of drv:sd dev:0:0:0:0 complete after 286.192 msecs
Sep 9 14:53:00 thinx kernel: [19259.062214] PM: suspend of drv:scsi dev:target0:0:0 complete after 286.196 msecs
Sep 9 14:53:00 thinx kernel: [19259.062222] PM: suspend of drv:scsi dev:host0 complete after 242.780 msecs
Sep 9 14:53:00 thinx kernel: [19259.099660] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 103.800 msecs
Sep 9 14:53:00 thinx kernel: [19259.099955] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
Sep 9 14:53:00 thinx kernel: [19259.115643] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.577 msecs
Sep 9 14:53:00 thinx kernel: [19259.451335] PM: suspend of drv:mei dev:0000:00:16.0 complete after 455.292 msecs
Sep 9 14:53:00 thinx kernel: [19259.862853] pci_pm_suspend(): e1000_suspend+0x0/0x50 [e1000e] returns -2
Sep 9 14:53:00 thinx kernel: [19259.862858] pm_op(): pci_pm_suspend+0x0/0x110 returns -2
Sep 9 14:53:00 thinx kernel: [19259.862861] PM: suspend of drv:e1000e dev:0000:00:19.0 complete after 867.885 msecs
Sep 9 14:53:00 thinx kernel: [19259.862865] PM: Device 0000:00:19.0 failed to suspend async: error -2
Sep 9 14:53:00 thinx kernel: [19259.862912] PM: Some devices failed to suspend
Sep 9 14:53:00 thinx kernel: [19259.863017] mei 0000:00:16.0: irq 42 for MSI/MSI-X
Sep 9 14:53:00 thinx kernel: [19259.863058] xhci_hcd 0000:00:14.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.863081] iwlwifi 0000:03:00.0: RF_KILL bit toggled to disable radio.
Sep 9 14:53:00 thinx kernel: [19259.863319] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.863331] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.863339] xhci_hcd 0000:00:14.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.863350] xhci_hcd 0000:00:14.0: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19259.863359] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.863365] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.863368] ehci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 9 14:53:00 thinx kernel: [19259.863371] ehci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Sep 9 14:53:00 thinx kernel: [19259.863375] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19259.863379] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19259.863431] i915 0000:00:02.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.876813] watchdog: only one watchdog can use /dev/watchdog.
Sep 9 14:53:00 thinx kernel: [19259.876820] watchdog: error registering /dev/watchdog (err=-16).
Sep 9 14:53:00 thinx kernel: [19259.876823] mei: unable to register watchdog device.
Sep 9 14:53:00 thinx kernel: [19259.878601] snd_hda_intel 0000:00:1b.0: BAR 0: set to [mem 0xf2530000-0xf2533fff 64bit] (PCI address [0xf2530000-0xf2533fff])
Sep 9 14:53:00 thinx kernel: [19259.878651] i915 0000:00:02.0: BAR 0: set to [mem 0xf0000000-0xf03fffff 64bit] (PCI address [0xf0000000-0xf03fffff])
Sep 9 14:53:00 thinx kernel: [19259.878655] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Sep 9 14:53:00 thinx kernel: [19259.878667] i915 0000:00:02.0: BAR 2: set to [mem 0xe0000000-0xefffffff 64bit pref] (PCI address [0xe0000000-0xefffffff])
Sep 9 14:53:00 thinx kernel: [19259.878677] i915 0000:00:02.0: BAR 4: set to [io 0x5000-0x503f] (PCI address [0x5000-0x503f])
Sep 9 14:53:00 thinx kernel: [19259.878685] i915 0000:00:02.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.878705] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Sep 9 14:53:00 thinx kernel: [19259.878718] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Sep 9 14:53:00 thinx kernel: [19259.878768] sdhci-pci 0000:02:00.0: BAR 0: set to [mem 0xf1d00000-0xf1d000ff] (PCI address [0xf1d00000-0xf1d000ff])
Sep 9 14:53:00 thinx kernel: [19259.878817] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Sep 9 14:53:00 thinx kernel: [19259.878853] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Sep 9 14:53:00 thinx kernel: [19259.878855] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900403, writing 0x900407)
Sep 9 14:53:00 thinx kernel: [19259.878872] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19259.878933] sdhci-pci 0000:02:00.0: restoring config space at offset 0x1 (was 0x100002, writing 0x100006)
Sep 9 14:53:00 thinx kernel: [19259.878951] ahci 0000:00:1f.2: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19259.878966] i915 0000:00:02.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.878973] i915 0000:00:02.0: power state changed by ACPI to D0
Sep 9 14:53:00 thinx kernel: [19259.878983] i915 0000:00:02.0: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19259.878991] sdhci-pci 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 9 14:53:00 thinx kernel: [19259.878999] snd_hda_intel 0000:00:1b.0: irq 43 for MSI/MSI-X
Sep 9 14:53:00 thinx kernel: [19259.894589] sd 0:0:0:0: [sda] Starting disk
Sep 9 14:53:00 thinx kernel: [19259.895120] Extended CMOS year: 2000
Sep 9 14:53:00 thinx kernel: [19259.987384] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 124.119 msecs
Sep 9 14:53:00 thinx kernel: [19259.987428] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 124.183 msecs
Sep 9 14:53:00 thinx kernel: [19259.987431] PM: resume of drv: dev:ep_00 complete after 124.153 msecs
Sep 9 14:53:00 thinx kernel: [19259.987449] PM: resume of drv: dev:ep_81 complete after 124.195 msecs
Sep 9 14:53:00 thinx kernel: [19260.222068] ata5: SATA link down (SStatus 0 SControl 300)
Sep 9 14:53:00 thinx kernel: [19260.222101] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 9 14:53:00 thinx kernel: [19260.223859] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Sep 9 14:53:00 thinx kernel: [19260.223867] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 9 14:53:00 thinx kernel: [19260.230053] ata2: SATA link down (SStatus 0 SControl 300)
Sep 9 14:53:00 thinx kernel: [19260.370738] PM: resume of drv:i915 dev:0000:00:02.0 complete after 508.535 msecs
Sep 9 14:53:00 thinx kernel: [19260.381997] sdhci-pci 0000:02:00.0: setting latency timer to 64
Sep 9 14:53:00 thinx kernel: [19260.382019] PM: resume of drv:sdhci-pci dev:0000:02:00.0 complete after 519.770 msecs
Sep 9 14:53:00 thinx kernel: [19261.508641] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Sep 9 14:53:00 thinx kernel: [19261.508649] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 9 14:53:00 thinx kernel: [19261.511333] ata1.00: configured for UDMA/133
Sep 9 14:53:00 thinx kernel: [19261.558589] PM: resume of drv:sd dev:0:0:0:0 complete after 1697.740 msecs
Sep 9 14:53:00 thinx kernel: [19261.558616] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 1697.746 msecs
Sep 9 14:53:00 thinx kernel: [19261.558645] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 1665.990 msecs
Sep 9 14:53:00 thinx kernel: [19261.559457] PM: resume of devices complete after 1699.068 msecs
Sep 9 14:53:00 thinx kernel: [19261.559509] PM: resume devices took 1.696 seconds
Sep 9 14:53:00 thinx kernel: [19261.565319] PM: Finishing wakeup.
Sep 9 14:53:00 thinx kernel: [19261.565321] Restarting tasks ... done.
Sep 9 14:53:00 thinx kernel: [19261.571805] video LNXVIDEO:00: Restoring backlight state
Sep 9 14:53:00 thinx acpid: client 1269[0:0] has disconnected
Sep 9 14:53:00 thinx acpid: client connected from 1269[0:0]
Sep 9 14:53:00 thinx acpid: 1 client rule loaded
Sep 9 14:53:00 thinx anacron[13907]: Anacron 2.3 started on 2012-09-09
Sep 9 14:53:00 thinx anacron[13907]: Normal exit (0 jobs run)
Sep 9 14:53:01 thinx kernel: [19261.840593] e1000e 0000:00:19.0: PME# enabled
Sep 9 14:53:01 thinx NetworkManager[793]: <info> wake requested (sleeping: yes enabled: yes)
Sep 9 14:53:01 thinx NetworkManager[793]: <info> waking up and re-enabling...
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (eth0): now managed
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (eth0): bringing up device.
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (eth0): deactivating device (reason 'managed') [2]
Sep 9 14:53:01 thinx NetworkManager[793]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Sep 9 14:53:01 thinx NetworkManager[793]: <info> Unmanaged Device found; state CONNECTED forced. (see http://bugs.launchpad.net/bugs/191889)
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (wlan0): now managed
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (wlan0): bringing up device.
Sep 9 14:53:01 thinx NetworkManager[793]: <info> (wlan0): deactivating device (reason 'managed') [2]

[tarverator]

After a reboot, my machine suspends/resumes normally, like so:

Sep 9 15:46:36 thinx NetworkManager[786]: <info> sleep requested (sleeping: no enabled: yes)
Sep 9 15:46:36 thinx NetworkManager[786]: <info> sleeping or disabling...
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (wlan0): now unmanaged
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (wlan0): device state change: unavailable -> unmanaged (reason 'sleeping') [20 10 37]
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (wlan0): cleaning up...
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): now unmanaged
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): device state change: activated -> unmanaged (reason 'sleeping') [100 10 37]
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): deactivating device (reason 'sleeping') [37]
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): canceled DHCP transaction, DHCP client pid 3351
Sep 9 15:46:36 thinx NetworkManager[786]: <info> DNS: starting dnsmasq...
Sep 9 15:46:36 thinx dnsmasq[3355]: exiting on receipt of SIGTERM
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): writing resolv.conf to /sbin/resolvconf
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): cleaning up...
Sep 9 15:46:36 thinx dnsmasq[3595]: started, version 2.59 cache disabled
Sep 9 15:46:36 thinx dnsmasq[3595]: compile time options: IPv6 GNU-getopt DBus i18n DHCP TFTP conntrack IDN
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): taking down device.
Sep 9 15:46:36 thinx dnsmasq[3595]: warning: no upstream servers configured
Sep 9 15:46:36 thinx dbus[736]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Sep 9 15:46:36 thinx NetworkManager[786]: <info> (usb0): carrier now OFF (device state 10)
Sep 9 15:46:36 thinx dbus[736]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Sep 9 15:46:37 thinx anacron[3671]: Anacron 2.3 started on 2012-09-09
Sep 9 15:46:37 thinx anacron[3671]: Normal exit (0 jobs run)
Sep 9 15:46:38 thinx kernel: [ 272.565101] PM: Syncing filesystems ... done.
Sep 9 15:46:38 thinx kernel: [ 272.567114] PM: Preparing system for mem sleep
Sep 9 15:46:46 thinx kernel: [ 273.208923] Freezing user space processes ... (elapsed 0.01 seconds) done.
Sep 9 15:46:46 thinx kernel: [ 273.224705] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done.
Sep 9 15:46:46 thinx kernel: [ 273.240654] PM: Entering mem sleep
Sep 9 15:46:46 thinx kernel: [ 273.240716] Suspending console(s) (use no_console_suspend to debug)
Sep 9 15:46:46 thinx kernel: [ 273.240836] xhci_hcd 0000:00:14.0: WARN Event TRB for slot 1 ep 0 with no TDs queued?
Sep 9 15:46:46 thinx kernel: [ 273.366721] PM: suspend of drv:psmouse dev:serio2 complete after 126.155 msecs
Sep 9 15:46:46 thinx kernel: [ 273.366863] sd 0:0:0:0: [sda] Synchronizing SCSI cache
Sep 9 15:46:46 thinx kernel: [ 273.367225] sd 0:0:0:0: [sda] Stopping disk
Sep 9 15:46:46 thinx kernel: [ 273.603964] PM: suspend of drv:tpm_tis dev:00:0a complete after 189.071 msecs
Sep 9 15:46:46 thinx kernel: [ 273.604224] ehci_hcd 0000:00:1d.0: PCI INT A disabled
Sep 9 15:46:46 thinx kernel: [ 273.604236] ehci_hcd 0000:00:1a.0: PCI INT A disabled
Sep 9 15:46:46 thinx kernel: [ 273.604275] ACPI handle has no context!
Sep 9 15:46:46 thinx kernel: [ 273.605372] sdhci-pci 0000:02:00.0: PCI INT A disabled
Sep 9 15:46:46 thinx kernel: [ 273.614344] PM: suspend of drv:sd dev:0:0:0:0 complete after 247.974 msecs
Sep 9 15:46:46 thinx kernel: [ 273.614433] PM: suspend of drv:scsi dev:target0:0:0 complete after 248.064 msecs
Sep 9 15:46:46 thinx kernel: [ 273.614442] PM: suspend of drv:scsi dev:host0 complete after 199.601 msecs
Sep 9 15:46:46 thinx kernel: [ 273.620076] i915 0000:00:02.0: power state changed by ACPI to D3
Sep 9 15:46:46 thinx kernel: [ 273.708142] snd_hda_intel 0000:00:1b.0: PCI INT A disabled
Sep 9 15:46:46 thinx kernel: [ 273.723681] PM: suspend of drv:snd_hda_intel dev:0000:00:1b.0 complete after 119.692 msecs
Sep 9 15:46:46 thinx kernel: [ 273.723766] PM: suspend of drv: dev:pci0000:00 complete after 119.685 msecs
Sep 9 15:46:46 thinx kernel: [ 273.723774] PM: suspend of devices complete after 483.947 msecs
Sep 9 15:46:46 thinx kernel: [ 273.723775] PM: suspend devices took 0.484 seconds
Sep 9 15:46:46 thinx kernel: [ 273.739792] ehci_hcd 0000:00:1d.0: PME# enabled
Sep 9 15:46:46 thinx kernel: [ 273.739797] ehci_hcd 0000:00:1d.0: wake-up capability enabled by ACPI
Sep 9 15:46:46 thinx kernel: [ 273.755626] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3
Sep 9 15:46:46 thinx kernel: [ 273.755869] ehci_hcd 0000:00:1a.0: PME# enabled
Sep 9 15:46:46 thinx kernel: [ 273.755872] ehci_hcd 0000:00:1a.0: wake-up capability enabled by ACPI
Sep 9 15:46:46 thinx kernel: [ 273.771593] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3
Sep 9 15:46:46 thinx kernel: [ 273.787636] xhci_hcd 0000:00:14.0: PME# enabled
Sep 9 15:46:46 thinx acpid: client 1241[0:0] has disconnected
Sep 9 15:46:46 thinx kernel: [ 273.787640] xhci_hcd 0000:00:14.0: wake-up capability enabled by ACPI
Sep 9 15:46:46 thinx kernel: [ 273.803534] xhci_hcd 0000:00:14.0: power state changed by ACPI to D3
Sep 9 15:46:46 thinx kernel: [ 273.803590] PM: late suspend of devices complete after 79.970 msecs
Sep 9 15:46:46 thinx kernel: [ 273.804040] ACPI: Preparing to enter system sleep state S3
Sep 9 15:46:46 thinx kernel: [ 273.975228] PM: Saving platform NVS memory
Sep 9 15:46:46 thinx kernel: [ 273.975900] Disabling non-boot CPUs ...
Sep 9 15:46:46 thinx kernel: [ 274.079015] CPU 1 is now offline
Sep 9 15:46:46 thinx kernel: [ 274.182760] CPU 2 is now offline
Sep 9 15:46:46 thinx kernel: [ 274.183416] Broke affinity for irq 16
Sep 9 15:46:46 thinx kernel: [ 274.183419] Broke affinity for irq 41
Sep 9 15:46:46 thinx kernel: [ 274.286560] CPU 3 is now offline
Sep 9 15:46:46 thinx kernel: [ 274.286839] Extended CMOS year: 2000
Sep 9 15:46:46 thinx kernel: [ 274.287070] ACPI: Low-level resume complete
Sep 9 15:46:46 thinx kernel: [ 274.287109] PM: Restoring platform NVS memory
Sep 9 15:46:46 thinx kernel: [ 274.287508] Extended CMOS year: 2000
Sep 9 15:46:46 thinx kernel: [ 274.287527] Enabling non-boot CPUs ...
Sep 9 15:46:46 thinx kernel: [ 274.287614] Booting Node 0 Processor 1 APIC 0x1
Sep 9 15:46:46 thinx kernel: [ 274.287616] smpboot cpu 1: start_ip = 99000
Sep 9 15:46:46 thinx kernel: [ 274.297840] Initializing CPU#1
Sep 9 15:46:46 thinx kernel: [ 274.298627] Calibrating delay loop (skipped) already calibrated this CPU
Sep 9 15:46:46 thinx kernel: [ 274.319460] NMI watchdog enabled, takes one hw-pmu counter.
Sep 9 15:46:46 thinx kernel: [ 274.399071] CPU1 is up
Sep 9 15:46:46 thinx kernel: [ 274.399153] Booting Node 0 Processor 2 APIC 0x2
Sep 9 15:46:46 thinx kernel: [ 274.399154] smpboot cpu 2: start_ip = 99000
Sep 9 15:46:46 thinx kernel: [ 274.409352] Initializing CPU#2
Sep 9 15:46:46 thinx kernel: [ 274.410163] Calibrating delay loop (skipped) already calibrated this CPU
Sep 9 15:46:46 thinx kernel: [ 274.431086] NMI watchdog enabled, takes one hw-pmu counter.
Sep 9 15:46:46 thinx kernel: [ 274.510857] CPU2 is up
Sep 9 15:46:46 thinx kernel: [ 274.510922] Booting Node 0 Processor 3 APIC 0x3
Sep 9 15:46:46 thinx kernel: [ 274.510923] smpboot cpu 3: start_ip = 99000
Sep 9 15:46:46 thinx kernel: [ 274.521115] Initializing CPU#3
Sep 9 15:46:46 thinx kernel: [ 274.521931] Calibrating delay loop (skipped) already calibrated this CPU
Sep 9 15:46:46 thinx kernel: [ 274.542915] NMI watchdog enabled, takes one hw-pmu counter.
Sep 9 15:46:46 thinx kernel: [ 274.622706] CPU3 is up
Sep 9 15:46:46 thinx kernel: [ 274.625701] ACPI: Waking up from system sleep state S3
Sep 9 15:46:46 thinx kernel: [ 275.017832] i915 0000:00:02.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.017839] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407)
Sep 9 15:46:46 thinx kernel: [ 275.017858] xhci_hcd 0000:00:14.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.017871] xhci_hcd 0000:00:14.0: restoring config space at offset 0x4 (was 0x4, writing 0xf2520004)
Sep 9 15:46:46 thinx kernel: [ 275.017877] xhci_hcd 0000:00:14.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900402)
Sep 9 15:46:46 thinx kernel: [ 275.017898] xhci_hcd 0000:00:14.0: wake-up capability disabled by ACPI
Sep 9 15:46:46 thinx kernel: [ 275.017901] xhci_hcd 0000:00:14.0: PME# disabled
Sep 9 15:46:46 thinx kernel: [ 275.017904] xhci_hcd 0000:00:14.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.017907] xhci_hcd 0000:00:14.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.017916] mei 0000:00:16.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.017928] mei 0000:00:16.0: restoring config space at offset 0x4 (was 0xfed0a004, writing 0xf2535004)
Sep 9 15:46:46 thinx kernel: [ 275.017947] serial 0000:00:16.3: restoring config space at offset 0xf (was 0x200, writing 0x207)
Sep 9 15:46:46 thinx kernel: [ 275.017959] serial 0000:00:16.3: restoring config space at offset 0x5 (was 0x0, writing 0xf253c000)
Sep 9 15:46:46 thinx kernel: [ 275.017962] serial 0000:00:16.3: restoring config space at offset 0x4 (was 0x1, writing 0x50b1)
Sep 9 15:46:46 thinx kernel: [ 275.017967] serial 0000:00:16.3: restoring config space at offset 0x1 (was 0xb00000, writing 0xb00007)
Sep 9 15:46:46 thinx kernel: [ 275.017985] pci 0000:00:19.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.017995] pci 0000:00:19.0: restoring config space at offset 0x6 (was 0x1, writing 0x5081)
Sep 9 15:46:46 thinx kernel: [ 275.018002] pci 0000:00:19.0: restoring config space at offset 0x1 (was 0x100002, writing 0x100003)
Sep 9 15:46:46 thinx kernel: [ 275.018024] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.018038] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf253a000)
Sep 9 15:46:46 thinx kernel: [ 275.018045] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900002)
Sep 9 15:46:46 thinx kernel: [ 275.018059] ehci_hcd 0000:00:1a.0: wake-up capability disabled by ACPI
Sep 9 15:46:46 thinx kernel: [ 275.018062] ehci_hcd 0000:00:1a.0: PME# disabled
Sep 9 15:46:46 thinx kernel: [ 275.018064] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.018067] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.018079] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Sep 9 15:46:46 thinx kernel: [ 275.018090] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x4 (was 0x4, writing 0xf2530004)
Sep 9 15:46:46 thinx kernel: [ 275.018094] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Sep 9 15:46:46 thinx kernel: [ 275.018099] snd_hda_intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100002)
Sep 9 15:46:46 thinx kernel: [ 275.018129] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.018140] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x10001, writing 0xf0b1f041)
Sep 9 15:46:46 thinx kernel: [ 275.018145] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0x0, writing 0xf240f1d0)
Sep 9 15:46:46 thinx kernel: [ 275.018150] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x20000000, writing 0x20004040)
Sep 9 15:46:46 thinx kernel: [ 275.018160] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810010)
Sep 9 15:46:46 thinx kernel: [ 275.018166] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Sep 9 15:46:46 thinx kernel: [ 275.018214] pcieport 0000:00:1c.1: restoring config space at offset 0xf (was 0x200, writing 0x20a)
Sep 9 15:46:46 thinx kernel: [ 275.018228] pcieport 0000:00:1c.1: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1)
Sep 9 15:46:46 thinx kernel: [ 275.018232] pcieport 0000:00:1c.1: restoring config space at offset 0x8 (was 0x0, writing 0xf1c0f1c0)
Sep 9 15:46:46 thinx kernel: [ 275.018237] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x0, writing 0xf0)
Sep 9 15:46:46 thinx kernel: [ 275.018247] pcieport 0000:00:1c.1: restoring config space at offset 0x3 (was 0x810000, writing 0x810010)
Sep 9 15:46:46 thinx kernel: [ 275.018254] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Sep 9 15:46:46 thinx kernel: [ 275.018293] pcieport 0000:00:1c.2: restoring config space at offset 0xf (was 0x300, writing 0x307)
Sep 9 15:46:46 thinx kernel: [ 275.018305] pcieport 0000:00:1c.2: restoring config space at offset 0x9 (was 0x10001, writing 0xf131f0c1)
Sep 9 15:46:46 thinx kernel: [ 275.018310] pcieport 0000:00:1c.2: restoring config space at offset 0x8 (was 0x0, writing 0xf1b0f140)
Sep 9 15:46:46 thinx kernel: [ 275.018314] pcieport 0000:00:1c.2: restoring config space at offset 0x7 (was 0x20000000, writing 0x20003030)
Sep 9 15:46:46 thinx kernel: [ 275.018324] pcieport 0000:00:1c.2: restoring config space at offset 0x3 (was 0x810000, writing 0x810010)
Sep 9 15:46:46 thinx kernel: [ 275.018331] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100000, writing 0x100007)
Sep 9 15:46:46 thinx kernel: [ 275.018367] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.018381] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2539000)
Sep 9 15:46:46 thinx kernel: [ 275.018388] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900002)
Sep 9 15:46:46 thinx kernel: [ 275.018402] ehci_hcd 0000:00:1d.0: wake-up capability disabled by ACPI
Sep 9 15:46:46 thinx kernel: [ 275.018405] ehci_hcd 0000:00:1d.0: PME# disabled
Sep 9 15:46:46 thinx kernel: [ 275.018407] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.018409] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.018464] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00403, writing 0x2b00407)
Sep 9 15:46:46 thinx kernel: [ 275.018634] sdhci-pci 0000:02:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x10b)
Sep 9 15:46:46 thinx kernel: [ 275.018771] iwlwifi 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a)
Sep 9 15:46:46 thinx kernel: [ 275.018804] iwlwifi 0000:03:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xf1c00004)
Sep 9 15:46:46 thinx kernel: [ 275.018811] iwlwifi 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10)
Sep 9 15:46:46 thinx kernel: [ 275.018820] iwlwifi 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100406)
Sep 9 15:46:46 thinx kernel: [ 275.018941] PM: early resume of devices complete after 1.179 msecs
Sep 9 15:46:46 thinx kernel: [ 275.019037] mei 0000:00:16.0: irq 42 for MSI/MSI-X
Sep 9 15:46:46 thinx kernel: [ 275.019061] iwlwifi 0000:03:00.0: RF_KILL bit toggled to disable radio.
Sep 9 15:46:46 thinx kernel: [ 275.019107] xhci_hcd 0000:00:14.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019357] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019369] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019388] snd_hda_intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
Sep 9 15:46:46 thinx kernel: [ 275.019391] ahci 0000:00:1f.2: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.019393] snd_hda_intel 0000:00:1b.0: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.019613] sdhci-pci 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 9 15:46:46 thinx kernel: [ 275.019616] xhci_hcd 0000:00:14.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019627] xhci_hcd 0000:00:14.0: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.019629] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019631] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019639] ehci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
Sep 9 15:46:46 thinx kernel: [ 275.019641] ehci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
Sep 9 15:46:46 thinx kernel: [ 275.019651] ehci_hcd 0000:00:1a.0: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.019653] ehci_hcd 0000:00:1d.0: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.019660] snd_hda_intel 0000:00:1b.0: irq 45 for MSI/MSI-X
Sep 9 15:46:46 thinx kernel: [ 275.019737] i915 0000:00:02.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019740] i915 0000:00:02.0: power state changed by ACPI to D0
Sep 9 15:46:46 thinx kernel: [ 275.019744] i915 0000:00:02.0: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.033770] sd 0:0:0:0: [sda] Starting disk
Sep 9 15:46:46 thinx kernel: [ 275.050699] Extended CMOS year: 2000
Sep 9 15:46:46 thinx kernel: [ 275.068110] watchdog: only one watchdog can use /dev/watchdog.
Sep 9 15:46:46 thinx kernel: [ 275.068115] watchdog: error registering /dev/watchdog (err=-16).
Sep 9 15:46:46 thinx kernel: [ 275.068117] mei: unable to register watchdog device.
Sep 9 15:46:46 thinx kernel: [ 275.197497] PM: resume of drv: dev:ep_00 complete after 178.454 msecs
Sep 9 15:46:46 thinx kernel: [ 275.197499] PM: resume of drv:hub dev:1-1:1.0 complete after 178.471 msecs
Sep 9 15:46:46 thinx kernel: [ 275.197505] PM: resume of drv: dev:ep_81 complete after 178.476 msecs
Sep 9 15:46:46 thinx kernel: [ 275.269378] usb 1-1.2: reset high-speed USB device number 6 using ehci_hcd
Sep 9 15:46:46 thinx kernel: [ 275.353100] ata2: SATA link down (SStatus 0 SControl 300)
Sep 9 15:46:46 thinx kernel: [ 275.361247] usb 1-1.2: device firmware changed
Sep 9 15:46:46 thinx kernel: [ 275.361620] PM: resume of drv:rndis_host dev:1-1.2:1.0 complete after 342.562 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361622] PM: resume of drv: dev:ep_00 complete after 342.473 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361627] PM: resume of drv:usb dev:1-1.2:1.2 complete after 342.542 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361632] PM: resume of drv:rndis_host dev:1-1.2:1.1 complete after 342.556 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361634] PM: resume of drv: dev:ep_8b complete after 342.547 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361646] PM: resume of drv: dev:ep_0d complete after 342.535 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361648] PM: resume of drv: dev:ep_01 complete after 342.567 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361655] PM: resume of drv: dev:ep_83 complete after 342.594 msecs
Sep 9 15:46:46 thinx kernel: [ 275.361658] PM: resume of drv: dev:ep_82 complete after 342.576 msecs
Sep 9 15:46:46 thinx kernel: [ 275.369065] ata5: SATA link down (SStatus 0 SControl 300)
Sep 9 15:46:46 thinx kernel: [ 275.425877] PM: resume of drv:i915 dev:0000:00:02.0 complete after 407.714 msecs
Sep 9 15:46:46 thinx kernel: [ 275.433143] usb 1-1.6: reset high-speed USB device number 3 using ehci_hcd
Sep 9 15:46:46 thinx kernel: [ 275.520803] sdhci-pci 0000:02:00.0: setting latency timer to 64
Sep 9 15:46:46 thinx kernel: [ 275.520878] PM: resume of drv:sdhci-pci dev:0000:02:00.0 complete after 502.842 msecs
Sep 9 15:46:46 thinx kernel: [ 275.538898] PM: resume of drv: dev:ep_00 complete after 520.232 msecs
Sep 9 15:46:46 thinx kernel: [ 275.538901] PM: resume of drv:uvcvideo dev:1-1.6:1.1 complete after 520.238 msecs
Sep 9 15:46:46 thinx kernel: [ 275.538942] PM: resume of drv:uvcvideo dev:1-1.6:1.0 complete after 520.289 msecs
Sep 9 15:46:46 thinx kernel: [ 275.538959] PM: resume of drv: dev:ep_81 complete after 520.300 msecs
Sep 9 15:46:46 thinx kernel: [ 277.097634] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Sep 9 15:46:46 thinx kernel: [ 277.100393] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Sep 9 15:46:46 thinx kernel: [ 277.100400] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 9 15:46:46 thinx kernel: [ 277.106102] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded
Sep 9 15:46:46 thinx kernel: [ 277.106109] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out
Sep 9 15:46:46 thinx kernel: [ 277.108792] ata1.00: configured for UDMA/133
Sep 9 15:46:46 thinx kernel: [ 277.131983] PM: resume of drv:sd dev:0:0:0:0 complete after 2116.812 msecs
Sep 9 15:46:46 thinx kernel: [ 277.132011] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2085.440 msecs
Sep 9 15:46:46 thinx kernel: [ 277.132064] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 2116.894 msecs
Sep 9 15:46:46 thinx kernel: [ 277.132742] PM: resume of devices complete after 2117.984 msecs
Sep 9 15:46:46 thinx kernel: [ 277.132787] PM: resume devices took 2.116 seconds
Sep 9 15:46:46 thinx kernel: [ 277.132862] PM: Finishing wakeup.
Sep 9 15:46:46 thinx kernel: [ 277.132863] Restarting tasks ... done.
Sep 9 15:46:46 thinx kernel: [ 277.134670] usb 1-1.2: USB disconnect, device number 6
Sep 9 15:46:46 thinx kernel: [ 277.134708] rndis_host 1-1.2:1.0: usb0: unregister 'rndis_host' usb-0000:00:1a.0-1.2, RNDIS device
Sep 9 15:46:46 thinx acpid: client connected from 1241[0:0]
Sep 9 15:46:46 thinx acpid: 1 client rule loaded
Sep 9 15:46:46 thinx kernel: [ 277.161823] video LNXVIDEO:00: Restoring backlight state
Sep 9 15:46:46 thinx NetworkManager[786]: SCPlugin-Ifupdown: devices removed (path: /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/net/usb0, iface: usb0)
Sep 9 15:46:46 thinx anacron[4086]: Anacron 2.3 started on 2012-09-09
Sep 9 15:46:46 thinx anacron[4086]: Normal exit (0 jobs run)
Sep 9 15:46:46 thinx kernel: [ 277.237396] usb 1-1.2: new high-speed USB device number 7 using ehci_hcd
Sep 9 15:46:46 thinx mtp-probe: checking bus 1, device 7: "/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2"
Sep 9 15:46:46 thinx kernel: [ 277.331337] scsi7 : usb-storage 1-1.2:1.0
Sep 9 15:46:46 thinx mtp-probe: bus: 1, device: 7 was not an MTP device
Sep 9 15:46:46 thinx anacron[4245]: Anacron 2.3 started on 2012-09-09
Sep 9 15:46:46 thinx anacron[4245]: Normal exit (0 jobs run)
Sep 9 15:46:47 thinx NetworkManager[786]: <info> wake requested (sleeping: yes enabled: yes)
Sep 9 15:46:47 thinx NetworkManager[786]: <info> waking up and re-enabling...
Sep 9 15:46:47 thinx NetworkManager[786]: <info> (wlan0): now managed
Sep 9 15:46:47 thinx NetworkManager[786]: <info> (wlan0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 9 15:46:47 thinx NetworkManager[786]: <info> (wlan0): bringing up device.
Sep 9 15:46:47 thinx NetworkManager[786]: <info> (wlan0): deactivating device (reason 'managed') [2]
Sep 9 15:46:47 thinx kernel: [ 278.328068] scsi 7:0:0:0: Direct-Access Android UMS Composite 0001 PQ: 0 ANSI: 2
Sep 9 15:46:47 thinx kernel: [ 278.328372] sd 7:0:0:0: Attached scsi generic sg1 type 0
Sep 9 15:46:47 thinx kernel: [ 278.332299] sd 7:0:0:0: [sdb] Attached SCSI removable disk
Sep 9 15:46:49 thinx kernel: [ 280.389610] usb 1-1.2: USB disconnect, device number 7
Sep 9 15:46:49 thinx kernel: [ 280.590897] usb 1-1.2: new high-speed USB device number 8 using ehci_hcd
Sep 9 15:46:50 thinx kernel: [ 280.687325] rndis_host 1-1.2:1.0: usb0: register 'rndis_host' at usb-0000:00:1a.0-1.2, RNDIS device, 3a:f6:f3:0a:75:9d
Sep 9 15:46:50 thinx NetworkManager[786]: SCPlugin-Ifupdown: devices added (path: /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/net/usb0, iface: usb0)
Sep 9 15:46:50 thinx NetworkManager[786]: SCPlugin-Ifupdown: device added (path: /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/net/usb0, iface: usb0): no ifupdown configuration found.
Sep 9 15:46:50 thinx NetworkManager[786]: <warn> failed to allocate link cache: (-10) Operation not supported
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): carrier is OFF
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): new Ethernet device (driver: 'rndis_host' ifindex: 5)
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): exported as /org/freedesktop/NetworkManager/Devices/2
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): now managed
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): bringing up device.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): carrier now ON (device state 20)
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): preparing device.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): deactivating device (reason 'managed') [2]
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Added default wired connection 'Wired connection 1' for /sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.2/1-1.2:1.0/net/usb0
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Auto-activating connection 'Wired connection 1'.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) starting connection 'Wired connection 1'
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 1 of 5 (Device Prepare) scheduled...
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 1 of 5 (Device Prepare) started...
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 2 of 5 (Device Configure) scheduled...
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 1 of 5 (Device Prepare) complete.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 2 of 5 (Device Configure) starting...
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): device state change: prepare -> config (reason 'none') [40 50 0]
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 2 of 5 (Device Configure) successful.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 3 of 5 (IP Configure Start) scheduled.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 2 of 5 (Device Configure) complete.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 3 of 5 (IP Configure Start) started...
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): device state change: config -> ip-config (reason 'none') [50 70 0]
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Beginning DHCPv4 transaction (timeout in 45 seconds)
Sep 9 15:46:50 thinx NetworkManager[786]: <info> dhclient started with pid 4351
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Beginning IP6 addrconf.
Sep 9 15:46:50 thinx NetworkManager[786]: <info> Activation (usb0) Stage 3 of 5 (IP Configure Start) complete.
Sep 9 15:46:50 thinx dhclient: Internet Systems Consortium DHCP Client 4.1-ESV-R4
Sep 9 15:46:50 thinx dhclient: Copyright 2004-2011 Internet Systems Consortium.
Sep 9 15:46:50 thinx dhclient: All rights reserved.
Sep 9 15:46:50 thinx dhclient: For info, please visit https://www.isc.org/software/dhcp/
Sep 9 15:46:50 thinx dhclient:
Sep 9 15:46:50 thinx NetworkManager[786]: <info> (usb0): DHCPv4 state changed nbi -> preinit
Sep 9 15:46:50 thinx dhclient: Listening on LPF/usb0/3a:f6:f3:0a:75:9d
Sep 9 15:46:50 thinx dhclient: Sending on LPF/usb0/3a:f6:f3:0a:75:9d
Sep 9 15:46:50 thinx dhclient: Sending on Socket/fallback
Sep 9 15:46:50 thinx dhclient: DHCPDISCOVER on usb0 to 255.255.255.255 port 67 interval 3
Sep 9 15:46:51 thinx dhclient: DHCPREQUEST of 192.168.42.179 on usb0 to 255.255.255.255 port 67
Sep 9 15:46:51 thinx dhclient: DHCPOFFER of 192.168.42.179 from 192.168.42.129
Sep 9 15:46:51 thinx dhclient: DHCPACK of 192.168.42.179 from 192.168.42.129
Sep 9 15:46:51 thinx dhclient: bound to 192.168.42.179 -- renewal in 1465 seconds.
Sep 9 15:46:51 thinx NetworkManager[786]: <info> (usb0): DHCPv4 state changed preinit -> bound
Sep 9 15:46:51 thinx NetworkManager[786]: <info> address 192.168.42.179
Sep 9 15:46:51 thinx NetworkManager[786]: <info> prefix 24 (255.255.255.0)
Sep 9 15:46:51 thinx NetworkManager[786]: <info> gateway 192.168.42.129
Sep 9 15:46:51 thinx NetworkManager[786]: <info> hostname 'thinx'
Sep 9 15:46:51 thinx NetworkManager[786]: <info> nameserver '192.168.42.129'
Sep 9 15:46:51 thinx NetworkManager[786]: <info> Activation (usb0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
Sep 9 15:46:51 thinx NetworkManager[786]: <info> Activation (usb0) Stage 5 of 5 (IPv4 Commit) started...
Sep 9 15:46:52 thinx dnsmasq[3595]: exiting on receipt of SIGTERM
Sep 9 15:46:52 thinx NetworkManager[786]: <info> DNS: starting dnsmasq...
Sep 9 15:46:52 thinx NetworkManager[786]: <info> (usb0): writing resolv.conf to /sbin/resolvconf
Sep 9 15:46:52 thinx NetworkManager[786]: <info> (usb0): device state change: ip-config -> activated (reason 'none') [70 100 0]
Sep 9 15:46:52 thinx dnsmasq[4357]: started, version 2.59 cache disabled
Sep 9 15:46:52 thinx dnsmasq[4357]: compile time options: IPv6 GNU-getopt DBus i18n DHCP TFTP conntrack IDN
Sep 9 15:46:52 thinx dnsmasq[4357]: using nameserver 192.168.42.129#53
Sep 9 15:46:52 thinx NetworkManager[786]: <info> Policy set 'Wired connection 1' (usb0) as default for IPv4 routing and DNS.
Sep 9 15:46:52 thinx NetworkManager[786]: <info> Activation (usb0) successful, device activated.
Sep 9 15:46:52 thinx dbus[736]: [system] Activating service name='org.freedesktop.nm_dispatcher' (using servicehelper)
Sep 9 15:46:52 thinx NetworkManager[786]: <info> Activation (usb0) Stage 5 of 5 (IPv4 Commit) complete.
Sep 9 15:46:52 thinx dbus[736]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'

[sabotatore]

I have the same problem with my x230. Did you find solution?

I opened bug:
https://bugs.launchpad.net/ubuntu/+sour ... g/1091521/

[tarverator]

Added

Code: Select all

blacklist mei

to /etc/modprobe.d/blacklist.conf