ce (reason=3) [1031608.873286] PM: Syncing filesystems ... done. [1031608.909076] PM: Preparing system for mem sleep [1031608.909080] Freezing user space processes ... (elapsed 0.00 seconds) done. [1031608.910430] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1031608.910501] PM: Entering mem sleep [1031608.910520] Suspending console(s) (use no_console_suspend to debug) [1031609.172863] PM: suspend of drv:psmouse dev:serio2 complete after 220.726 msecs [1031609.173032] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1031609.173159] sd 2:0:0:0: [sda] Stopping disk [1031609.451381] PM: suspend of drv:sd dev:2:0:0:0 complete after 278.356 msecs [1031609.845476] PM: suspend of drv:psmouse dev:serio1 complete after 381.388 msecs [1031610.444054] PM: suspend of drv:atkbd dev:serio0 complete after 598.563 msecs [1031610.481395] ACPI handle has no context! [1031610.481403] sdhci-pci 0000:15:00.2: PCI INT C disabled [1031610.481411] ACPI handle has no context! [1031610.501159] ACPI handle has no context! [1031610.564252] ata2: port disabled. ignoring. [1031610.564346] ata_piix 0000:00:1f.1: PCI INT C disabled [1031610.564380] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1031610.564399] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1031610.564420] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1031610.564440] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1031610.564454] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1031610.668389] HDA Intel 0000:00:1b.0: PCI INT B disabled [1031610.684135] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.653 msecs [1031610.684157] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1031610.684181] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1031610.684202] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1031610.685835] e1000e 0000:00:19.0: PCI INT A disabled [1031610.685846] e1000e 0000:00:19.0: PME# enabled [1031610.685852] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1031610.728204] i915 0000:00:02.0: power state changed by ACPI to D3 [1031610.728516] PM: suspend of devices complete after 1817.637 msecs [1031610.728519] PM: suspend devices took 1.820 seconds [1031610.729045] ricoh-mmc: Suspending. [1031610.729076] ricoh-mmc: Controller is now re-enabled. [1031610.752081] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1031610.760080] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1031610.768079] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1031610.792079] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1031610.820068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1031610.820268] PM: late suspend of devices complete after 91.742 msecs [1031610.860072] ACPI: Preparing to enter system sleep state S3 [1031611.016019] Disabling non-boot CPUs ... [1031611.016042] CPU0 attaching NULL sched-domain. [1031611.016045] CPU1 attaching NULL sched-domain. [1031611.080023] CPU0 attaching NULL sched-domain. [1031611.082440] kvm: disabling virtualization on CPU1 [1031611.184030] CPU 1 is now offline [1031611.184033] SMP alternatives: switching to UP code [1031611.191740] Extended CMOS year: 2000 [1031611.191740] Back to C! [1031611.191740] CPU0: Thermal monitoring enabled (TM2) [1031611.191740] Extended CMOS year: 2000 [1031611.191740] Enabling non-boot CPUs ... [1031611.192044] SMP alternatives: switching to SMP code [1031611.198293] Booting processor 1 APIC 0x1 ip 0x6000 [1031611.191285] Initializing CPU#1 [1031611.191285] CPU: L1 I cache: 32K, L1 D cache: 32K [1031611.191285] CPU: L2 cache: 2048K [1031611.191285] CPU: Physical Processor ID: 0 [1031611.191285] CPU: Processor Core ID: 1 [1031611.191285] CPU1: Thermal monitoring enabled (TM2) [1031611.288113] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1031611.288138] kvm: enabling virtualization on CPU1 [1031611.288198] CPU0 attaching NULL sched-domain. [1031611.316024] CPU0 attaching sched-domain: [1031611.316027] domain 0: span 0-1 level MC [1031611.316030] groups: 0 1 [1031611.316035] CPU1 attaching sched-domain: [1031611.316037] domain 0: span 0-1 level MC [1031611.316039] groups: 1 0 [1031611.316621] CPU1 is up [1031611.317077] ACPI: Waking up from system sleep state S3 [1031611.664257] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1031611.664294] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1031611.664478] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1031611.708068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1031611.716077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1031611.716141] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1031611.724077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1031611.732077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1031611.732157] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1031611.740077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1031611.748077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1031611.748183] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1031611.748318] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1031611.748490] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1031611.748659] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1031611.748836] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1031611.749007] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1031611.756077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1031611.764074] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1031611.764138] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1031611.772077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1031611.780077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1031611.780146] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1031611.788077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1031611.796077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1031611.796141] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1031611.804077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1031611.812077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1031611.812168] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1031611.820077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1031611.828074] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1031611.828155] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1031611.828337] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1031611.828446] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1031611.828783] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1031611.844105] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1031611.844118] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1031611.860102] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1031611.860117] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1031611.860243] ricoh-mmc: Resuming. [1031611.860276] ricoh-mmc: Controller is now disabled. [1031611.860825] PM: early resume of devices complete after 196.705 msecs [1031611.920742] i915 0000:00:02.0: power state changed by ACPI to D0 [1031611.920786] i915 0000:00:02.0: power state changed by ACPI to D0 [1031611.920792] i915 0000:00:02.0: setting latency timer to 64 [1031612.136275] PM: resume of drv:i915 dev:0000:00:02.0 complete after 215.600 msecs [1031612.136326] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1031612.136336] e1000e 0000:00:19.0: setting latency timer to 64 [1031612.136354] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1031612.136363] e1000e 0000:00:19.0: PME# disabled [1031612.136461] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1031612.450174] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.888 msecs [1031612.450188] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1031612.450202] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1031612.450234] usb usb3: root hub lost power or was reset [1031612.450523] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1031612.450779] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1031612.450789] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1031612.450805] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1031612.450843] usb usb4: root hub lost power or was reset [1031612.451086] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1031612.451328] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1031612.451342] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1031612.451358] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1031612.451402] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1031612.451409] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1031612.451439] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1031612.451667] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1031612.451890] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1031612.451900] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1031612.451916] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1031612.451956] usb usb5: root hub lost power or was reset [1031612.451996] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1031612.452035] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1031612.452068] usb usb6: root hub lost power or was reset [1031612.452287] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1031612.452496] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1031612.452506] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1031612.452522] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1031612.452564] usb usb7: root hub lost power or was reset [1031612.452820] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1031612.453047] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1031612.453061] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1031612.453077] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1031612.453110] pci 0000:00:1e.0: setting latency timer to 64 [1031612.453139] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1031612.453151] ata_piix 0000:00:1f.1: setting latency timer to 64 [1031612.453608] ata2: port disabled. ignoring. [1031612.454521] ahci 0000:00:1f.2: setting latency timer to 64 [1031612.592137] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.119 msecs [1031612.616755] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1031612.616759] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1031612.617993] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1031612.618798] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1031612.640405] ata1.00: configured for UDMA/33 [1031612.650140] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1031612.656256] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1031612.656259] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1031612.772141] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1031612.772768] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1031612.772774] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1031612.772780] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1031612.773740] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1031612.773745] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1031612.773751] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1031612.774066] ata3.00: configured for UDMA/133 [1031612.788970] ata3.00: configured for UDMA/133 [1031612.788974] ata3: EH complete [1031612.912134] PM: resume of drv:usb dev:usb1 complete after 254.222 msecs [1031613.188131] PM: resume of drv:usb dev:usb3 complete after 247.946 msecs [1031613.300126] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1031613.505192] PM: resume of drv:usb dev:3-2 complete after 314.256 msecs [1031613.505236] sd 2:0:0:0: [sda] Starting disk [1031613.506311] thinkpad_acpi: ACPI backlight control delay disabled [1031613.509356] PM: resume of devices complete after 1648.425 msecs [1031613.509567] PM: resume devices took 1.648 seconds [1031613.509595] PM: Finishing wakeup. [1031613.509597] Restarting tasks ... done. [1031613.976816] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1031614.032241] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1031614.032869] ADDRCONF(NETDEV_UP): eth0: link is not ready [1031614.133617] Registered led device: iwl-phy0::radio [1031614.133699] Registered led device: iwl-phy0::assoc [1031614.133728] Registered led device: iwl-phy0::RX [1031614.133750] Registered led device: iwl-phy0::TX [1031614.145699] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1031624.524770] wlan0: deauthenticating from 00:12:17:70:25:6e by local choice (reason=3) [1031624.568174] wlan0: direct probe to AP 00:12:17:70:25:6e (try 1) [1031624.769175] wlan0: direct probe to AP 00:12:17:70:25:6e (try 2) [1031624.771335] wlan0: direct probe responded [1031624.771344] wlan0: authenticate with AP 00:12:17:70:25:6e (try 1) [1031624.773486] wlan0: authenticated [1031624.773542] wlan0: associate with AP 00:12:17:70:25:6e (try 1) [1031624.775727] wlan0: RX AssocResp from 00:12:17:70:25:6e (capab=0x1 status=0 aid=7) [1031624.775735] wlan0: associated [1031624.777777] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1031634.864077] wlan0: no IPv6 routers present [1031674.455363] lo: Disabled Privacy Extensions [1032289.577498] lo: Disabled Privacy Extensions [1032793.166402] lo: Disabled Privacy Extensions [1034014.344532] lo: Disabled Privacy Extensions [1035791.187651] lo: Disabled Privacy Extensions [1036303.778114] lo: Disabled Privacy Extensions [1036462.963074] lo: Disabled Privacy Extensions [1036754.108152] lo: Disabled Privacy Extensions [1039894.418916] lo: Disabled Privacy Extensions [1040784.042637] lo: Disabled Privacy Extensions [1041193.119594] lo: Disabled Privacy Extensions [1043598.909991] lo: Disabled Privacy Extensions [1044290.639929] lo: Disabled Privacy Extensions [1046492.128886] lo: Disabled Privacy Extensions [1046864.609121] wlan0: deauthenticating from 00:12:17:70:25:6e by local choice (reason=3) [1046864.853302] PM: Syncing filesystems ... done. [1046864.893091] PM: Preparing system for mem sleep [1046864.893096] Freezing user space processes ... (elapsed 0.00 seconds) done. [1046864.894416] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1046864.894470] PM: Entering mem sleep [1046864.894484] Suspending console(s) (use no_console_suspend to debug) [1046865.158131] PM: suspend of drv:psmouse dev:serio2 complete after 221.993 msecs [1046865.158292] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1046865.158421] sd 2:0:0:0: [sda] Stopping disk [1046865.435810] PM: suspend of drv:sd dev:2:0:0:0 complete after 277.521 msecs [1046865.833095] PM: suspend of drv:psmouse dev:serio1 complete after 385.012 msecs [1046866.432097] PM: suspend of drv:atkbd dev:serio0 complete after 598.989 msecs [1046866.469385] ACPI handle has no context! [1046866.469394] sdhci-pci 0000:15:00.2: PCI INT C disabled [1046866.469402] ACPI handle has no context! [1046866.489157] ACPI handle has no context! [1046866.552245] ata2: port disabled. ignoring. [1046866.552350] ata_piix 0000:00:1f.1: PCI INT C disabled [1046866.552375] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1046866.552394] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1046866.552413] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1046866.552431] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1046866.552445] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1046866.656399] HDA Intel 0000:00:1b.0: PCI INT B disabled [1046866.672138] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.666 msecs [1046866.672160] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1046866.672184] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1046866.672208] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1046866.673850] e1000e 0000:00:19.0: PCI INT A disabled [1046866.673860] e1000e 0000:00:19.0: PME# enabled [1046866.673867] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1046866.716206] i915 0000:00:02.0: power state changed by ACPI to D3 [1046866.716534] PM: suspend of devices complete after 1821.676 msecs [1046866.716537] PM: suspend devices took 1.824 seconds [1046866.717060] ricoh-mmc: Suspending. [1046866.717093] ricoh-mmc: Controller is now re-enabled. [1046866.740085] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1046866.748080] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1046866.756077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1046866.780077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1046866.808068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1046866.808265] PM: late suspend of devices complete after 91.721 msecs [1046866.848071] ACPI: Preparing to enter system sleep state S3 [1046867.004020] Disabling non-boot CPUs ... [1046867.004042] CPU0 attaching NULL sched-domain. [1046867.004046] CPU1 attaching NULL sched-domain. [1046867.068022] CPU0 attaching NULL sched-domain. [1046867.070446] kvm: disabling virtualization on CPU1 [1046867.172035] CPU 1 is now offline [1046867.172038] SMP alternatives: switching to UP code [1046867.179763] Extended CMOS year: 2000 [1046867.179763] Back to C! [1046867.179763] CPU0: Thermal monitoring enabled (TM2) [1046867.179763] Extended CMOS year: 2000 [1046867.179763] Enabling non-boot CPUs ... [1046867.180070] SMP alternatives: switching to SMP code [1046867.186328] Booting processor 1 APIC 0x1 ip 0x6000 [1046867.179323] Initializing CPU#1 [1046867.179323] CPU: L1 I cache: 32K, L1 D cache: 32K [1046867.179323] CPU: L2 cache: 2048K [1046867.179323] CPU: Physical Processor ID: 0 [1046867.179323] CPU: Processor Core ID: 1 [1046867.179323] CPU1: Thermal monitoring enabled (TM2) [1046867.276058] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1046867.276083] kvm: enabling virtualization on CPU1 [1046867.276143] CPU0 attaching NULL sched-domain. [1046867.304024] CPU0 attaching sched-domain: [1046867.304028] domain 0: span 0-1 level MC [1046867.304030] groups: 0 1 [1046867.304035] CPU1 attaching sched-domain: [1046867.304037] domain 0: span 0-1 level MC [1046867.304040] groups: 1 0 [1046867.304623] CPU1 is up [1046867.305077] ACPI: Waking up from system sleep state S3 [1046867.652258] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1046867.652295] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1046867.652485] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1046867.696067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1046867.704077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1046867.704136] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1046867.712077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1046867.720077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1046867.720156] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1046867.728074] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1046867.736077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1046867.736185] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1046867.736315] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1046867.736492] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1046867.736666] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1046867.736837] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1046867.737004] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1046867.744077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1046867.752077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1046867.752136] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1046867.760077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1046867.768078] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1046867.768143] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1046867.776077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1046867.784078] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1046867.784142] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1046867.792077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1046867.800077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1046867.800169] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1046867.808075] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1046867.816077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1046867.816159] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1046867.816347] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1046867.816455] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1046867.816788] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1046867.832106] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1046867.832121] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1046867.848101] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1046867.848116] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1046867.848240] ricoh-mmc: Resuming. [1046867.848273] ricoh-mmc: Controller is now disabled. [1046867.848819] PM: early resume of devices complete after 196.698 msecs [1046867.909032] i915 0000:00:02.0: power state changed by ACPI to D0 [1046867.909085] i915 0000:00:02.0: power state changed by ACPI to D0 [1046867.909091] i915 0000:00:02.0: setting latency timer to 64 [1046868.124277] PM: resume of drv:i915 dev:0000:00:02.0 complete after 215.329 msecs [1046868.124326] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1046868.124334] e1000e 0000:00:19.0: setting latency timer to 64 [1046868.124352] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1046868.124361] e1000e 0000:00:19.0: PME# disabled [1046868.124458] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1046868.438179] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.886 msecs [1046868.438192] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1046868.438206] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1046868.438238] usb usb3: root hub lost power or was reset [1046868.438530] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1046868.438777] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1046868.438787] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1046868.438803] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1046868.438844] usb usb4: root hub lost power or was reset [1046868.439088] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1046868.439332] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1046868.439347] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1046868.439362] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1046868.439404] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1046868.439411] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1046868.439441] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1046868.439660] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1046868.439885] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1046868.439894] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1046868.439910] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1046868.439951] usb usb5: root hub lost power or was reset [1046868.439989] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1046868.439999] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1046868.440064] usb usb6: root hub lost power or was reset [1046868.440311] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1046868.440548] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1046868.440555] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1046868.440568] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1046868.440602] usb usb7: root hub lost power or was reset [1046868.440869] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1046868.441114] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1046868.441129] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1046868.441150] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1046868.441174] pci 0000:00:1e.0: setting latency timer to 64 [1046868.441193] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1046868.441202] ata_piix 0000:00:1f.1: setting latency timer to 64 [1046868.441592] ata2: port disabled. ignoring. [1046868.442507] ahci 0000:00:1f.2: setting latency timer to 64 [1046868.580525] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.522 msecs [1046868.604756] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1046868.604760] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1046868.605954] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1046868.606809] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1046868.628403] ata1.00: configured for UDMA/33 [1046868.638138] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1046868.644249] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1046868.644252] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1046868.760146] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1046868.760772] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1046868.760779] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1046868.760784] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1046868.761747] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1046868.761753] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1046868.761759] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1046868.762074] ata3.00: configured for UDMA/133 [1046868.776963] ata3.00: configured for UDMA/133 [1046868.776967] ata3: EH complete [1046868.900133] PM: resume of drv:usb dev:usb1 complete after 254.228 msecs [1046869.176147] PM: resume of drv:usb dev:usb3 complete after 247.956 msecs [1046869.288134] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1046869.442184] PM: resume of drv:usb dev:3-2 complete after 263.401 msecs [1046869.442228] sd 2:0:0:0: [sda] Starting disk [1046869.443315] thinkpad_acpi: ACPI backlight control delay disabled [1046869.446377] PM: resume of devices complete after 1597.448 msecs [1046869.446586] PM: resume devices took 1.596 seconds [1046869.446614] PM: Finishing wakeup. [1046869.446616] Restarting tasks ... done. [1046869.804829] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1046869.861358] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1046869.862046] ADDRCONF(NETDEV_UP): eth0: link is not ready [1046869.949812] Registered led device: iwl-phy0::radio [1046869.949839] Registered led device: iwl-phy0::assoc [1046869.949860] Registered led device: iwl-phy0::RX [1046869.949882] Registered led device: iwl-phy0::TX [1046869.962568] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1046880.493066] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1046880.511418] wlan0: direct probe to AP 00:22:33:2d:bc:a7 (try 1) [1046880.513661] wlan0: direct probe responded [1046880.513670] wlan0: authenticate with AP 00:22:33:2d:bc:a7 (try 1) [1046880.515809] wlan0: authenticated [1046880.515854] wlan0: associate with AP 00:22:33:2d:bc:a7 (try 1) [1046880.518195] wlan0: RX AssocResp from 00:22:33:2d:bc:a7 (capab=0x411 status=0 aid=3) [1046880.518203] wlan0: associated [1046880.520266] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1046881.407181] lo: Disabled Privacy Extensions [1046891.129013] wlan0: no IPv6 routers present [1047259.634943] lo: Disabled Privacy Extensions [1050169.500132] No probe response from AP 00:22:33:2d:bc:a7 after 500ms, disconnecting. [1050210.550233] wlan0: direct probe to AP 00:22:33:2d:bc:a7 (try 1) [1050210.553125] wlan0: direct probe responded [1050210.553133] wlan0: authenticate with AP 00:22:33:2d:bc:a7 (try 1) [1050210.555003] wlan0: authenticated [1050210.555044] wlan0: associate with AP 00:22:33:2d:bc:a7 (try 1) [1050210.557372] wlan0: RX AssocResp from 00:22:33:2d:bc:a7 (capab=0x411 status=0 aid=1) [1050210.557380] wlan0: associated [1054946.493184] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1054946.709311] PM: Syncing filesystems ... done. [1054946.749094] PM: Preparing system for mem sleep [1054946.749099] Freezing user space processes ... (elapsed 0.00 seconds) done. [1054946.750442] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1054946.750495] PM: Entering mem sleep [1054946.750509] Suspending console(s) (use no_console_suspend to debug) [1054947.022318] PM: suspend of drv:psmouse dev:serio2 complete after 230.165 msecs [1054947.022488] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1054947.022616] sd 2:0:0:0: [sda] Stopping disk [1054947.298631] PM: suspend of drv:sd dev:2:0:0:0 complete after 276.151 msecs [1054947.697343] PM: suspend of drv:psmouse dev:serio1 complete after 385.253 msecs [1054948.296067] PM: suspend of drv:atkbd dev:serio0 complete after 598.713 msecs [1054948.333353] ACPI handle has no context! [1054948.333362] sdhci-pci 0000:15:00.2: PCI INT C disabled [1054948.333370] ACPI handle has no context! [1054948.353118] ACPI handle has no context! [1054948.416180] ata2: port disabled. ignoring. [1054948.416284] ata_piix 0000:00:1f.1: PCI INT C disabled [1054948.416309] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1054948.416328] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1054948.416346] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1054948.416365] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1054948.416379] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1054948.520364] HDA Intel 0000:00:1b.0: PCI INT B disabled [1054948.536086] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.680 msecs [1054948.536102] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1054948.536120] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1054948.536141] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1054948.537769] e1000e 0000:00:19.0: PCI INT A disabled [1054948.537780] e1000e 0000:00:19.0: PME# enabled [1054948.537786] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1054948.580133] i915 0000:00:02.0: power state changed by ACPI to D3 [1054948.580436] PM: suspend of devices complete after 1829.555 msecs [1054948.580439] PM: suspend devices took 1.832 seconds [1054948.580967] ricoh-mmc: Suspending. [1054948.580999] ricoh-mmc: Controller is now re-enabled. [1054948.604085] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1054948.612080] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1054948.620079] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1054948.644076] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1054948.672068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1054948.672265] PM: late suspend of devices complete after 91.820 msecs [1054948.712073] ACPI: Preparing to enter system sleep state S3 [1054948.868019] Disabling non-boot CPUs ... [1054948.868042] CPU0 attaching NULL sched-domain. [1054948.868046] CPU1 attaching NULL sched-domain. [1054948.932023] CPU0 attaching NULL sched-domain. [1054948.934442] kvm: disabling virtualization on CPU1 [1054949.036027] CPU 1 is now offline [1054949.036030] SMP alternatives: switching to UP code [1054949.043819] Extended CMOS year: 2000 [1054949.043819] Back to C! [1054949.043819] CPU0: Thermal monitoring enabled (TM2) [1054949.043819] Extended CMOS year: 2000 [1054949.043819] Enabling non-boot CPUs ... [1054949.044125] SMP alternatives: switching to SMP code [1054949.050385] Booting processor 1 APIC 0x1 ip 0x6000 [1054949.043366] Initializing CPU#1 [1054949.043366] CPU: L1 I cache: 32K, L1 D cache: 32K [1054949.043366] CPU: L2 cache: 2048K [1054949.043366] CPU: Physical Processor ID: 0 [1054949.043366] CPU: Processor Core ID: 1 [1054949.043366] CPU1: Thermal monitoring enabled (TM2) [1054949.140113] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1054949.140138] kvm: enabling virtualization on CPU1 [1054949.140199] CPU0 attaching NULL sched-domain. [1054949.168024] CPU0 attaching sched-domain: [1054949.168027] domain 0: span 0-1 level MC [1054949.168030] groups: 0 1 [1054949.168035] CPU1 attaching sched-domain: [1054949.168037] domain 0: span 0-1 level MC [1054949.168039] groups: 1 0 [1054949.168620] CPU1 is up [1054949.169075] ACPI: Waking up from system sleep state S3 [1054949.560259] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1054949.560295] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1054949.560482] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1054949.604068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1054949.612077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1054949.612139] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1054949.620077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1054949.628077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1054949.628160] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1054949.636077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1054949.644077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1054949.644185] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1054949.644318] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1054949.644495] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1054949.644674] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1054949.644841] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1054949.645010] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1054949.652075] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1054949.660077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1054949.660141] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1054949.668077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1054949.676077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1054949.676144] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1054949.684077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1054949.692077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1054949.692139] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1054949.700077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1054949.708077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1054949.708166] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1054949.716077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1054949.724077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1054949.724160] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1054949.724344] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1054949.724447] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1054949.724780] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1054949.740106] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1054949.740119] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1054949.756100] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1054949.756115] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1054949.756239] ricoh-mmc: Resuming. [1054949.756271] ricoh-mmc: Controller is now disabled. [1054949.756813] PM: early resume of devices complete after 196.693 msecs [1054949.816049] i915 0000:00:02.0: power state changed by ACPI to D0 [1054949.816094] i915 0000:00:02.0: power state changed by ACPI to D0 [1054949.816100] i915 0000:00:02.0: setting latency timer to 64 [1054950.032273] PM: resume of drv:i915 dev:0000:00:02.0 complete after 216.323 msecs [1054950.032324] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1054950.032334] e1000e 0000:00:19.0: setting latency timer to 64 [1054950.032352] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1054950.032361] e1000e 0000:00:19.0: PME# disabled [1054950.032451] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1054950.346192] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.908 msecs [1054950.346206] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1054950.346219] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1054950.346252] usb usb3: root hub lost power or was reset [1054950.346545] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1054950.346783] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1054950.346792] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1054950.346808] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1054950.346847] usb usb4: root hub lost power or was reset [1054950.347093] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1054950.347324] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1054950.347338] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1054950.347354] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1054950.347398] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1054950.347405] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1054950.347435] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1054950.347653] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1054950.347890] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1054950.347899] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1054950.347915] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1054950.347956] usb usb5: root hub lost power or was reset [1054950.347993] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1054950.348036] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1054950.348069] usb usb6: root hub lost power or was reset [1054950.348277] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1054950.348492] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1054950.348502] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1054950.348518] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1054950.348558] usb usb7: root hub lost power or was reset [1054950.348808] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1054950.349057] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1054950.349071] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1054950.349087] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1054950.349117] pci 0000:00:1e.0: setting latency timer to 64 [1054950.349149] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1054950.349160] ata_piix 0000:00:1f.1: setting latency timer to 64 [1054950.349623] ata2: port disabled. ignoring. [1054950.350538] ahci 0000:00:1f.2: setting latency timer to 64 [1054950.488135] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.097 msecs [1054950.512766] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1054950.512769] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1054950.513963] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1054950.514826] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1054950.536405] ata1.00: configured for UDMA/33 [1054950.546146] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1054950.552257] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1054950.552260] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1054950.668140] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1054950.668764] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1054950.668770] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1054950.668776] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1054950.669732] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1054950.669738] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1054950.669744] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1054950.670057] ata3.00: configured for UDMA/133 [1054950.684960] ata3.00: configured for UDMA/133 [1054950.684964] ata3: EH complete [1054950.808134] PM: resume of drv:usb dev:usb1 complete after 254.264 msecs [1054951.084127] PM: resume of drv:usb dev:usb3 complete after 247.949 msecs [1054951.196126] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1054951.345184] PM: resume of drv:usb dev:3-2 complete after 258.630 msecs [1054951.345229] sd 2:0:0:0: [sda] Starting disk [1054951.346311] thinkpad_acpi: ACPI backlight control delay disabled [1054951.349332] PM: resume of devices complete after 1592.410 msecs [1054951.349541] PM: resume devices took 1.592 seconds [1054951.349570] PM: Finishing wakeup. [1054951.349572] Restarting tasks ... done. [1054951.668834] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1054951.725350] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1054951.725967] ADDRCONF(NETDEV_UP): eth0: link is not ready [1054951.813829] Registered led device: iwl-phy0::radio [1054951.813912] Registered led device: iwl-phy0::assoc [1054951.813934] Registered led device: iwl-phy0::RX [1054951.813956] Registered led device: iwl-phy0::TX [1054951.825423] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1054962.324261] wlan0: deauthenticating from 00:12:17:70:25:6e by local choice (reason=3) [1054962.365529] wlan0: direct probe to AP 00:12:17:70:25:6e (try 1) [1054962.564157] wlan0: direct probe to AP 00:12:17:70:25:6e (try 2) [1054962.566258] wlan0: direct probe responded [1054962.566267] wlan0: authenticate with AP 00:12:17:70:25:6e (try 1) [1054962.568459] wlan0: authenticated [1054962.568551] wlan0: associate with AP 00:12:17:70:25:6e (try 1) [1054962.570705] wlan0: RX AssocResp from 00:12:17:70:25:6e (capab=0x401 status=0 aid=4) [1054962.570714] wlan0: associated [1054962.572693] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1054972.896250] wlan0: no IPv6 routers present [1055273.736831] lo: Disabled Privacy Extensions [1055469.993982] lo: Disabled Privacy Extensions [1059218.941616] lo: Disabled Privacy Extensions [1059667.240468] lo: Disabled Privacy Extensions [1061221.626223] lo: Disabled Privacy Extensions [1062264.064429] lo: Disabled Privacy Extensions [1063070.248438] lo: Disabled Privacy Extensions [1066652.519600] lo: Disabled Privacy Extensions [1066654.069875] lo: Disabled Privacy Extensions [1075420.347370] lo: Disabled Privacy Extensions [1077978.998924] lo: Disabled Privacy Extensions [1078219.934553] lo: Disabled Privacy Extensions [1079150.417748] lo: Disabled Privacy Extensions [1079274.122036] lo: Disabled Privacy Extensions [1081166.763310] lo: Disabled Privacy Extensions [1081793.365902] lo: Disabled Privacy Extensions [1082217.329343] wlan0: deauthenticating from 00:12:17:70:25:6e by local choice (reason=3) [1082217.580356] PM: Syncing filesystems ... done. [1082217.625143] PM: Preparing system for mem sleep [1082217.625148] Freezing user space processes ... (elapsed 0.00 seconds) done. [1082217.626617] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1082217.626691] PM: Entering mem sleep [1082217.626710] Suspending console(s) (use no_console_suspend to debug) [1082217.906362] PM: suspend of drv:psmouse dev:serio2 complete after 230.161 msecs [1082217.906543] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1082217.906668] sd 2:0:0:0: [sda] Stopping disk [1082218.183758] PM: suspend of drv:sd dev:2:0:0:0 complete after 277.216 msecs [1082218.582267] PM: suspend of drv:psmouse dev:serio1 complete after 386.128 msecs [1082219.184109] PM: suspend of drv:atkbd dev:serio0 complete after 601.828 msecs [1082219.221396] ACPI handle has no context! [1082219.221405] sdhci-pci 0000:15:00.2: PCI INT C disabled [1082219.221413] ACPI handle has no context! [1082219.241157] ACPI handle has no context! [1082219.304260] ata2: port disabled. ignoring. [1082219.304366] ata_piix 0000:00:1f.1: PCI INT C disabled [1082219.304391] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1082219.304410] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1082219.304429] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1082219.304447] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1082219.304461] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1082219.408400] HDA Intel 0000:00:1b.0: PCI INT B disabled [1082219.424137] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.648 msecs [1082219.424159] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1082219.424182] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1082219.424206] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1082219.425844] e1000e 0000:00:19.0: PCI INT A disabled [1082219.425854] e1000e 0000:00:19.0: PME# enabled [1082219.425861] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1082219.476208] i915 0000:00:02.0: power state changed by ACPI to D3 [1082219.476542] PM: suspend of devices complete after 1849.466 msecs [1082219.476545] PM: suspend devices took 1.852 seconds [1082219.477074] ricoh-mmc: Suspending. [1082219.477107] ricoh-mmc: Controller is now re-enabled. [1082219.500086] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1082219.508078] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1082219.516079] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1082219.540079] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1082219.568070] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1082219.568267] PM: late suspend of devices complete after 91.717 msecs [1082219.608074] ACPI: Preparing to enter system sleep state S3 [1082219.764019] Disabling non-boot CPUs ... [1082219.764042] CPU0 attaching NULL sched-domain. [1082219.764045] CPU1 attaching NULL sched-domain. [1082219.828022] CPU0 attaching NULL sched-domain. [1082219.830439] kvm: disabling virtualization on CPU1 [1082219.932032] CPU 1 is now offline [1082219.932035] SMP alternatives: switching to UP code [1082219.939772] Extended CMOS year: 2000 [1082219.939772] Back to C! [1082219.939772] CPU0: Thermal monitoring enabled (TM2) [1082219.939772] Extended CMOS year: 2000 [1082219.939772] Enabling non-boot CPUs ... [1082219.940076] SMP alternatives: switching to SMP code [1082219.946332] Booting processor 1 APIC 0x1 ip 0x6000 [1082219.939327] Initializing CPU#1 [1082219.939327] CPU: L1 I cache: 32K, L1 D cache: 32K [1082219.939327] CPU: L2 cache: 2048K [1082219.939327] CPU: Physical Processor ID: 0 [1082219.939327] CPU: Processor Core ID: 1 [1082219.939327] CPU1: Thermal monitoring enabled (TM2) [1082220.036058] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1082220.036083] kvm: enabling virtualization on CPU1 [1082220.036199] CPU0 attaching NULL sched-domain. [1082220.064024] CPU0 attaching sched-domain: [1082220.064027] domain 0: span 0-1 level MC [1082220.064029] groups: 0 1 [1082220.064034] CPU1 attaching sched-domain: [1082220.064036] domain 0: span 0-1 level MC [1082220.064039] groups: 1 0 [1082220.064618] CPU1 is up [1082220.065074] ACPI: Waking up from system sleep state S3 [1082220.412254] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1082220.412291] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1082220.412476] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1082220.456068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1082220.464077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1082220.464139] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1082220.472077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1082220.480077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1082220.480160] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1082220.488077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1082220.496077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1082220.496186] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1082220.496316] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1082220.496492] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1082220.496666] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1082220.496833] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1082220.497005] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1082220.504077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1082220.512077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1082220.512142] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1082220.520077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1082220.528074] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1082220.528139] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1082220.536077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1082220.544077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1082220.544141] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1082220.552077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1082220.560077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1082220.560167] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1082220.568076] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1082220.576077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1082220.576155] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1082220.576340] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1082220.576449] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1082220.576790] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1082220.592102] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1082220.592117] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1082220.608100] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1082220.608114] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1082220.608236] ricoh-mmc: Resuming. [1082220.608267] ricoh-mmc: Controller is now disabled. [1082220.608800] PM: early resume of devices complete after 196.679 msecs [1082220.668069] i915 0000:00:02.0: power state changed by ACPI to D0 [1082220.668114] i915 0000:00:02.0: power state changed by ACPI to D0 [1082220.668120] i915 0000:00:02.0: setting latency timer to 64 [1082220.884272] PM: resume of drv:i915 dev:0000:00:02.0 complete after 216.302 msecs [1082220.884323] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1082220.884333] e1000e 0000:00:19.0: setting latency timer to 64 [1082220.884351] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1082220.884361] e1000e 0000:00:19.0: PME# disabled [1082220.884456] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1082221.198194] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.907 msecs [1082221.198208] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1082221.198221] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1082221.198254] usb usb3: root hub lost power or was reset [1082221.198529] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1082221.198757] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1082221.198766] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1082221.198782] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1082221.198830] usb usb4: root hub lost power or was reset [1082221.199073] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1082221.199332] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1082221.199346] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1082221.199362] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1082221.199406] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1082221.199413] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1082221.199443] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1082221.199669] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1082221.199895] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1082221.199904] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1082221.199920] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1082221.199960] usb usb5: root hub lost power or was reset [1082221.200000] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1082221.200042] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1082221.200078] usb usb6: root hub lost power or was reset [1082221.200293] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1082221.200504] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1082221.200514] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1082221.200530] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1082221.200570] usb usb7: root hub lost power or was reset [1082221.200820] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1082221.200993] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1082221.201000] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1082221.201015] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1082221.201036] pci 0000:00:1e.0: setting latency timer to 64 [1082221.201057] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1082221.201067] ata_piix 0000:00:1f.1: setting latency timer to 64 [1082221.201472] ata2: port disabled. ignoring. [1082221.202383] ahci 0000:00:1f.2: setting latency timer to 64 [1082221.340135] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.252 msecs [1082221.364768] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1082221.364771] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1082221.365963] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1082221.366828] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1082221.388394] ata1.00: configured for UDMA/33 [1082221.398143] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1082221.404254] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1082221.404257] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1082221.520147] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1082221.520751] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1082221.520754] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1082221.520758] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1082221.521800] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1082221.521806] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1082221.521811] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1082221.522127] ata3.00: configured for UDMA/133 [1082221.536962] ata3.00: configured for UDMA/133 [1082221.536966] ata3: EH complete [1082221.660129] PM: resume of drv:usb dev:usb1 complete after 254.221 msecs [1082221.936133] PM: resume of drv:usb dev:usb3 complete after 247.945 msecs [1082222.048130] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1082222.201183] PM: resume of drv:usb dev:3-2 complete after 262.348 msecs [1082222.201227] sd 2:0:0:0: [sda] Starting disk [1082222.202304] thinkpad_acpi: ACPI backlight control delay disabled [1082222.205315] PM: resume of devices complete after 1596.406 msecs [1082222.205524] PM: resume devices took 1.596 seconds [1082222.205552] PM: Finishing wakeup. [1082222.205554] Restarting tasks ... done. [1082222.560788] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1082222.616240] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1082222.616929] ADDRCONF(NETDEV_UP): eth0: link is not ready [1082222.705947] Registered led device: iwl-phy0::radio [1082222.705974] Registered led device: iwl-phy0::assoc [1082222.705996] Registered led device: iwl-phy0::RX [1082222.706019] Registered led device: iwl-phy0::TX [1082222.718552] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1082233.216501] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1082233.235149] wlan0: direct probe to AP 00:22:33:2d:bc:a7 (try 1) [1082233.237259] wlan0: direct probe responded [1082233.237263] wlan0: authenticate with AP 00:22:33:2d:bc:a7 (try 1) [1082233.239154] wlan0: authenticated [1082233.239176] wlan0: associate with AP 00:22:33:2d:bc:a7 (try 1) [1082233.241482] wlan0: RX AssocResp from 00:22:33:2d:bc:a7 (capab=0x411 status=0 aid=1) [1082233.241485] wlan0: associated [1082233.242999] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1082243.672254] wlan0: no IPv6 routers present [1082715.366879] lo: Disabled Privacy Extensions [1083124.050747] cfg80211: Found new beacon on frequency: 2472 MHz (Ch 13) on phy0 [1090970.722650] lo: Disabled Privacy Extensions [1102723.952156] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1102724.029353] chromium-browse[29263]: segfault at 0 ip (null) sp ab28db2c error 4 in libgdk-x11-2.0.so.0.2000.1[110000+93000] [1102724.265309] PM: Syncing filesystems ... done. [1102724.301043] PM: Preparing system for mem sleep [1102724.301047] Freezing user space processes ... (elapsed 0.00 seconds) done. [1102724.302423] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1102724.302478] PM: Entering mem sleep [1102724.302492] Suspending console(s) (use no_console_suspend to debug) [1102724.549509] PM: suspend of drv:psmouse dev:serio2 complete after 221.356 msecs [1102724.549678] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1102724.549795] sd 2:0:0:0: [sda] Stopping disk [1102724.825618] PM: suspend of drv:sd dev:2:0:0:0 complete after 275.949 msecs [1102725.224217] PM: suspend of drv:psmouse dev:serio1 complete after 384.124 msecs [1102725.824062] PM: suspend of drv:atkbd dev:serio0 complete after 599.834 msecs [1102725.861354] ACPI handle has no context! [1102725.861363] sdhci-pci 0000:15:00.2: PCI INT C disabled [1102725.861371] ACPI handle has no context! [1102725.881119] ACPI handle has no context! [1102725.944177] ata2: port disabled. ignoring. [1102725.944277] ata_piix 0000:00:1f.1: PCI INT C disabled [1102725.944300] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1102725.944319] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1102725.944337] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1102725.944355] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1102725.944370] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1102726.048341] HDA Intel 0000:00:1b.0: PCI INT B disabled [1102726.064086] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.690 msecs [1102726.064104] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1102726.064122] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1102726.064141] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1102726.065766] e1000e 0000:00:19.0: PCI INT A disabled [1102726.065777] e1000e 0000:00:19.0: PME# enabled [1102726.065784] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1102726.108132] i915 0000:00:02.0: power state changed by ACPI to D3 [1102726.108418] PM: suspend of devices complete after 1805.554 msecs [1102726.108421] PM: suspend devices took 1.808 seconds [1102726.108935] ricoh-mmc: Suspending. [1102726.108965] ricoh-mmc: Controller is now re-enabled. [1102726.132086] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1102726.140082] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1102726.148079] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1102726.172079] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1102726.200067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1102726.200268] PM: late suspend of devices complete after 91.838 msecs [1102726.240072] ACPI: Preparing to enter system sleep state S3 [1102726.396019] Disabling non-boot CPUs ... [1102726.396042] CPU0 attaching NULL sched-domain. [1102726.396045] CPU1 attaching NULL sched-domain. [1102726.460022] CPU0 attaching NULL sched-domain. [1102726.462437] kvm: disabling virtualization on CPU1 [1102726.564029] CPU 1 is now offline [1102726.564032] SMP alternatives: switching to UP code [1102726.571752] Extended CMOS year: 2000 [1102726.571752] Back to C! [1102726.571752] CPU0: Thermal monitoring enabled (TM2) [1102726.571752] Extended CMOS year: 2000 [1102726.571752] Enabling non-boot CPUs ... [1102726.572055] SMP alternatives: switching to SMP code [1102726.578312] Booting processor 1 APIC 0x1 ip 0x6000 [1102726.571307] Initializing CPU#1 [1102726.571307] CPU: L1 I cache: 32K, L1 D cache: 32K [1102726.571307] CPU: L2 cache: 2048K [1102726.571307] CPU: Physical Processor ID: 0 [1102726.571307] CPU: Processor Core ID: 1 [1102726.571307] CPU1: Thermal monitoring enabled (TM2) [1102726.668129] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1102726.668154] kvm: enabling virtualization on CPU1 [1102726.668214] CPU0 attaching NULL sched-domain. [1102726.696024] CPU0 attaching sched-domain: [1102726.696027] domain 0: span 0-1 level MC [1102726.696030] groups: 0 1 [1102726.696035] CPU1 attaching sched-domain: [1102726.696037] domain 0: span 0-1 level MC [1102726.696039] groups: 1 0 [1102726.696621] CPU1 is up [1102726.697083] ACPI: Waking up from system sleep state S3 [1102727.088255] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1102727.088292] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1102727.088481] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1102727.132069] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1102727.140078] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1102727.140139] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1102727.148077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1102727.156077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1102727.156162] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1102727.164077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1102727.172077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1102727.172180] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1102727.172315] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1102727.172464] pcieport 0000:00:1c.1: restoring config space at offset 0x7 (was 0x3030, writing 0x20003030) [1102727.172493] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1102727.172664] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1102727.172837] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1102727.173006] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1102727.180077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1102727.188077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1102727.188141] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1102727.196077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1102727.204077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1102727.204146] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1102727.212077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1102727.220077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1102727.220141] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1102727.228077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1102727.236077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1102727.236167] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1102727.244087] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1102727.252077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1102727.252163] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1102727.252350] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1102727.252458] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1102727.252793] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1102727.268100] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1102727.268114] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1102727.284098] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1102727.284113] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1102727.284239] ricoh-mmc: Resuming. [1102727.284271] ricoh-mmc: Controller is now disabled. [1102727.284806] PM: early resume of devices complete after 196.686 msecs [1102727.343249] i915 0000:00:02.0: power state changed by ACPI to D0 [1102727.343293] i915 0000:00:02.0: power state changed by ACPI to D0 [1102727.343299] i915 0000:00:02.0: setting latency timer to 64 [1102727.556278] PM: resume of drv:i915 dev:0000:00:02.0 complete after 213.098 msecs [1102727.556330] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1102727.556341] e1000e 0000:00:19.0: setting latency timer to 64 [1102727.556358] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1102727.556368] e1000e 0000:00:19.0: PME# disabled [1102727.556463] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1102727.870188] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.897 msecs [1102727.870202] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1102727.870215] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1102727.870248] usb usb3: root hub lost power or was reset [1102727.870537] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1102727.870783] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1102727.870793] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1102727.870809] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1102727.870849] usb usb4: root hub lost power or was reset [1102727.871114] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1102727.871352] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1102727.871366] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1102727.871382] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1102727.871424] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1102727.871431] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1102727.871462] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1102727.871715] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1102727.871947] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1102727.871956] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1102727.871972] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1102727.872014] usb usb5: root hub lost power or was reset [1102727.872074] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1102727.872084] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1102727.872116] usb usb6: root hub lost power or was reset [1102727.872319] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1102727.872528] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1102727.872537] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1102727.872553] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1102727.872595] usb usb7: root hub lost power or was reset [1102727.872853] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1102727.873073] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1102727.873087] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1102727.873103] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1102727.873135] pci 0000:00:1e.0: setting latency timer to 64 [1102727.873165] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1102727.873176] ata_piix 0000:00:1f.1: setting latency timer to 64 [1102727.873635] ata2: port disabled. ignoring. [1102727.874546] ahci 0000:00:1f.2: setting latency timer to 64 [1102728.012129] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.086 msecs [1102728.036760] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1102728.036763] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1102728.037899] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1102728.038723] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1102728.060388] ata1.00: configured for UDMA/33 [1102728.070143] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1102728.076249] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1102728.076252] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1102728.192136] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1102728.192742] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1102728.192746] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1102728.192749] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1102728.193790] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1102728.193796] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1102728.193802] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1102728.194117] ata3.00: configured for UDMA/133 [1102728.208964] ata3.00: configured for UDMA/133 [1102728.208968] ata3: EH complete [1102728.332129] PM: resume of drv:usb dev:usb1 complete after 254.251 msecs [1102728.608135] PM: resume of drv:usb dev:usb3 complete after 247.942 msecs [1102728.720124] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1102728.869182] PM: resume of drv:usb dev:3-2 complete after 258.505 msecs [1102728.869227] sd 2:0:0:0: [sda] Starting disk [1102728.870305] thinkpad_acpi: ACPI backlight control delay disabled [1102728.873324] PM: resume of devices complete after 1588.410 msecs [1102728.873535] PM: resume devices took 1.588 seconds [1102728.873564] PM: Finishing wakeup. [1102728.873566] Restarting tasks ... done. [1102729.252709] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1102729.308253] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1102729.308872] ADDRCONF(NETDEV_UP): eth0: link is not ready [1102729.398240] Registered led device: iwl-phy0::radio [1102729.398316] Registered led device: iwl-phy0::assoc [1102729.398349] Registered led device: iwl-phy0::RX [1102729.398380] Registered led device: iwl-phy0::TX [1102729.410515] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1102732.166276] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1102732.200797] wlan0: direct probe to AP 00:22:33:2d:bc:a7 (try 1) [1102732.202972] wlan0: direct probe responded [1102732.202981] wlan0: authenticate with AP 00:22:33:2d:bc:a7 (try 1) [1102732.204895] wlan0: authenticated [1102732.204935] wlan0: associate with AP 00:22:33:2d:bc:a7 (try 1) [1102732.207388] wlan0: RX AssocResp from 00:22:33:2d:bc:a7 (capab=0x411 status=0 aid=1) [1102732.207396] wlan0: associated [1102732.209491] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1102740.804160] lo: Disabled Privacy Extensions [1102742.828035] wlan0: no IPv6 routers present [1117423.429109] usb 2-2: new high speed USB device using ehci_hcd and address 8 [1117423.561251] usb 2-2: configuration #1 chosen from 1 choice [1117423.562295] scsi10 : SCSI emulation for USB Mass Storage devices [1117423.562634] usb-storage: device found at 8 [1117423.562640] usb-storage: waiting for device to settle before scanning [1117428.560419] usb-storage: device scan complete [1117428.560981] scsi 10:0:0:0: Direct-Access SanDisk Cruzer Pattern 7.01 PQ: 0 ANSI: 0 CCS [1117428.562432] sd 10:0:0:0: Attached scsi generic sg2 type 0 [1117428.565104] sd 10:0:0:0: [sdb] 15695871 512-byte logical blocks: (8.03 GB/7.48 GiB) [1117428.565651] sd 10:0:0:0: [sdb] Write Protect is off [1117428.565660] sd 10:0:0:0: [sdb] Mode Sense: 45 00 00 08 [1117428.565666] sd 10:0:0:0: [sdb] Assuming drive cache: write through [1117428.571144] sd 10:0:0:0: [sdb] Assuming drive cache: write through [1117428.571150] sdb: sdb1 [1117428.573408] sd 10:0:0:0: [sdb] Assuming drive cache: write through [1117428.573414] sd 10:0:0:0: [sdb] Attached SCSI removable disk [1117553.719094] usb 2-2: USB disconnect, address 8 [1117559.288132] usb 2-2: new high speed USB device using ehci_hcd and address 9 [1117559.421229] usb 2-2: configuration #1 chosen from 1 choice [1117559.422346] scsi11 : SCSI emulation for USB Mass Storage devices [1117559.422659] usb-storage: device found at 9 [1117559.422665] usb-storage: waiting for device to settle before scanning [1117564.420415] usb-storage: device scan complete [1117564.420967] scsi 11:0:0:0: Direct-Access SanDisk Cruzer Pattern 7.01 PQ: 0 ANSI: 0 CCS [1117564.426317] sd 11:0:0:0: [sdb] 15695871 512-byte logical blocks: (8.03 GB/7.48 GiB) [1117564.426407] sd 11:0:0:0: Attached scsi generic sg2 type 0 [1117564.428610] sd 11:0:0:0: [sdb] Write Protect is off [1117564.428619] sd 11:0:0:0: [sdb] Mode Sense: 45 00 00 08 [1117564.428625] sd 11:0:0:0: [sdb] Assuming drive cache: write through [1117564.432578] sd 11:0:0:0: [sdb] Assuming drive cache: write through [1117564.432589] sdb: sdb1 [1117564.435395] sd 11:0:0:0: [sdb] Assuming drive cache: write through [1117564.435405] sd 11:0:0:0: [sdb] Attached SCSI removable disk [1118496.091093] usb 2-2: USB disconnect, address 9 [1118497.936111] usb 2-2: new high speed USB device using ehci_hcd and address 10 [1118498.069226] usb 2-2: configuration #1 chosen from 1 choice [1118498.070224] scsi12 : SCSI emulation for USB Mass Storage devices [1118498.070537] usb-storage: device found at 10 [1118498.070543] usb-storage: waiting for device to settle before scanning [1118503.069365] usb-storage: device scan complete [1118503.069959] scsi 12:0:0:0: Direct-Access SanDisk Cruzer Pattern 7.01 PQ: 0 ANSI: 0 CCS [1118503.070991] sd 12:0:0:0: Attached scsi generic sg2 type 0 [1118503.073090] sd 12:0:0:0: [sdb] 15695871 512-byte logical blocks: (8.03 GB/7.48 GiB) [1118503.075312] sd 12:0:0:0: [sdb] Write Protect is off [1118503.075322] sd 12:0:0:0: [sdb] Mode Sense: 45 00 00 08 [1118503.075329] sd 12:0:0:0: [sdb] Assuming drive cache: write through [1118503.078000] sd 12:0:0:0: [sdb] Assuming drive cache: write through [1118503.078011] sdb: sdb1 [1118503.081749] sd 12:0:0:0: [sdb] Assuming drive cache: write through [1118503.081760] sd 12:0:0:0: [sdb] Attached SCSI removable disk [1118724.072119] usb 2-2: USB disconnect, address 10 [1119706.870441] lo: Disabled Privacy Extensions [1121592.361196] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1121592.624270] PM: Syncing filesystems ... done. [1121592.660075] PM: Preparing system for mem sleep [1121592.660080] Freezing user space processes ... (elapsed 0.00 seconds) done. [1121592.661575] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1121592.661638] PM: Entering mem sleep [1121592.661651] Suspending console(s) (use no_console_suspend to debug) [1121592.921247] PM: suspend of drv:psmouse dev:serio2 complete after 217.101 msecs [1121592.921409] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1121592.921538] sd 2:0:0:0: [sda] Stopping disk [1121593.201327] PM: suspend of drv:sd dev:2:0:0:0 complete after 279.921 msecs [1121593.600748] PM: suspend of drv:psmouse dev:serio1 complete after 384.656 msecs [1121594.200073] PM: suspend of drv:atkbd dev:serio0 complete after 599.314 msecs [1121594.237351] ACPI handle has no context! [1121594.237360] sdhci-pci 0000:15:00.2: PCI INT C disabled [1121594.237368] ACPI handle has no context! [1121594.257116] ACPI handle has no context! [1121594.320176] ata2: port disabled. ignoring. [1121594.320276] ata_piix 0000:00:1f.1: PCI INT C disabled [1121594.320302] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1121594.320320] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1121594.320338] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1121594.320357] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1121594.320371] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1121594.424357] HDA Intel 0000:00:1b.0: PCI INT B disabled [1121594.440085] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.691 msecs [1121594.440104] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1121594.440122] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1121594.440141] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1121594.441763] e1000e 0000:00:19.0: PCI INT A disabled [1121594.441774] e1000e 0000:00:19.0: PME# enabled [1121594.441781] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1121594.496136] i915 0000:00:02.0: power state changed by ACPI to D3 [1121594.496456] PM: suspend of devices complete after 1834.432 msecs [1121594.496459] PM: suspend devices took 1.836 seconds [1121594.496985] ricoh-mmc: Suspending. [1121594.497027] ricoh-mmc: Controller is now re-enabled. [1121594.520087] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1121594.528078] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1121594.536079] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1121594.560080] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1121594.588065] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1121594.588264] PM: late suspend of devices complete after 91.796 msecs [1121594.628072] ACPI: Preparing to enter system sleep state S3 [1121594.784019] Disabling non-boot CPUs ... [1121594.784042] CPU0 attaching NULL sched-domain. [1121594.784046] CPU1 attaching NULL sched-domain. [1121594.848022] CPU0 attaching NULL sched-domain. [1121594.850438] kvm: disabling virtualization on CPU1 [1121594.952030] CPU 1 is now offline [1121594.952033] SMP alternatives: switching to UP code [1121594.959644] Extended CMOS year: 2000 [1121594.959644] Back to C! [1121594.959644] CPU0: Thermal monitoring enabled (TM2) [1121594.959644] Extended CMOS year: 2000 [1121594.959644] Enabling non-boot CPUs ... [1121594.959644] SMP alternatives: switching to SMP code [1121594.966243] Booting processor 1 APIC 0x1 ip 0x6000 [1121594.959196] Initializing CPU#1 [1121594.959196] CPU: L1 I cache: 32K, L1 D cache: 32K [1121594.959196] CPU: L2 cache: 2048K [1121594.959196] CPU: Physical Processor ID: 0 [1121594.959196] CPU: Processor Core ID: 1 [1121594.959196] CPU1: Thermal monitoring enabled (TM2) [1121595.056086] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1121595.056110] kvm: enabling virtualization on CPU1 [1121595.056228] CPU0 attaching NULL sched-domain. [1121595.084024] CPU0 attaching sched-domain: [1121595.084027] domain 0: span 0-1 level MC [1121595.084030] groups: 0 1 [1121595.084035] CPU1 attaching sched-domain: [1121595.084037] domain 0: span 0-1 level MC [1121595.084039] groups: 1 0 [1121595.084617] CPU1 is up [1121595.085073] ACPI: Waking up from system sleep state S3 [1121595.476250] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1121595.476286] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1121595.476475] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1121595.520067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1121595.528077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1121595.528141] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1121595.536074] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1121595.544077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1121595.544161] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1121595.552076] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1121595.560077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1121595.560177] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1121595.560314] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1121595.560486] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1121595.560662] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1121595.560833] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1121595.561008] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1121595.568077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1121595.576077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1121595.576141] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1121595.584076] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1121595.592074] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1121595.592144] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1121595.600077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1121595.608077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1121595.608138] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1121595.616077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1121595.624074] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1121595.624163] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1121595.632077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1121595.640076] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1121595.640156] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1121595.640344] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1121595.640449] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1121595.640785] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1121595.656107] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1121595.656122] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1121595.672099] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1121595.672113] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1121595.672239] ricoh-mmc: Resuming. [1121595.672272] ricoh-mmc: Controller is now disabled. [1121595.672808] PM: early resume of devices complete after 196.689 msecs [1121595.731695] i915 0000:00:02.0: power state changed by ACPI to D0 [1121595.731739] i915 0000:00:02.0: power state changed by ACPI to D0 [1121595.731745] i915 0000:00:02.0: setting latency timer to 64 [1121595.944279] PM: resume of drv:i915 dev:0000:00:02.0 complete after 212.650 msecs [1121595.944327] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1121595.944338] e1000e 0000:00:19.0: setting latency timer to 64 [1121595.944355] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1121595.944365] e1000e 0000:00:19.0: PME# disabled [1121595.944458] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1121596.258177] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.888 msecs [1121596.258191] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1121596.258204] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1121596.258237] usb usb3: root hub lost power or was reset [1121596.258539] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1121596.258797] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1121596.258807] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1121596.258827] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1121596.258859] usb usb4: root hub lost power or was reset [1121596.259125] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1121596.259366] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1121596.259380] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1121596.259396] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1121596.259441] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1121596.259448] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1121596.259478] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1121596.259726] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1121596.259951] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1121596.259961] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1121596.259976] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1121596.260021] usb usb5: root hub lost power or was reset [1121596.260081] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1121596.260091] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1121596.260126] usb usb6: root hub lost power or was reset [1121596.260332] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1121596.260539] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1121596.260549] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1121596.260565] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1121596.260607] usb usb7: root hub lost power or was reset [1121596.260866] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1121596.261094] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1121596.261108] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1121596.261124] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1121596.261156] pci 0000:00:1e.0: setting latency timer to 64 [1121596.261186] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1121596.261198] ata_piix 0000:00:1f.1: setting latency timer to 64 [1121596.261653] ata2: port disabled. ignoring. [1121596.262564] ahci 0000:00:1f.2: setting latency timer to 64 [1121596.400130] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.074 msecs [1121596.424763] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1121596.424767] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1121596.425990] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1121596.426804] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1121596.448407] ata1.00: configured for UDMA/33 [1121596.458141] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1121596.464254] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1121596.464257] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1121596.580149] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1121596.580775] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1121596.580781] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1121596.580786] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1121596.581742] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1121596.581748] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1121596.581754] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1121596.582069] ata3.00: configured for UDMA/133 [1121596.597203] ata3.00: configured for UDMA/133 [1121596.597208] ata3: EH complete [1121596.720129] PM: resume of drv:usb dev:usb1 complete after 254.227 msecs [1121596.996129] PM: resume of drv:usb dev:usb3 complete after 247.945 msecs [1121597.108130] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1121597.261180] PM: resume of drv:usb dev:3-2 complete after 262.663 msecs [1121597.261224] sd 2:0:0:0: [sda] Starting disk [1121597.262301] thinkpad_acpi: ACPI backlight control delay disabled [1121597.265321] PM: resume of devices complete after 1592.405 msecs [1121597.265531] PM: resume devices took 1.592 seconds [1121597.265559] PM: Finishing wakeup. [1121597.265561] Restarting tasks ... done. [1121597.732426] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1121597.789146] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1121597.789489] ADDRCONF(NETDEV_UP): eth0: link is not ready [1121597.877598] Registered led device: iwl-phy0::radio [1121597.877885] Registered led device: iwl-phy0::assoc [1121597.878159] Registered led device: iwl-phy0::RX [1121597.878428] Registered led device: iwl-phy0::TX [1121597.888833] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1121650.578151] Registered led device: iwl-phy0::radio [1121650.579244] Registered led device: iwl-phy0::assoc [1121650.579973] Registered led device: iwl-phy0::RX [1121650.581143] Registered led device: iwl-phy0::TX [1121650.593292] wlan0: Trigger new scan to find an IBSS to join [1121652.988209] wlan0: Trigger new scan to find an IBSS to join [1121655.000166] wlan0: Trigger new scan to find an IBSS to join [1121659.000152] wlan0: Trigger new scan to find an IBSS to join [1121659.004238] wlan0: Creating new IBSS network, BSSID a6:04:a5:0b:ab:36 [1121661.216252] wlan0: no IPv6 routers present [1121704.046501] wlan0: Trigger new scan to find an IBSS to join [1121708.000096] wlan0: Trigger new scan to find an IBSS to join [1121708.268156] wlan0: Selected IBSS BSSID a6:04:a5:0b:ab:36 based on configured SSID [1121754.042722] wlan0: Trigger new scan to find an IBSS to join [1121758.000179] wlan0: Trigger new scan to find an IBSS to join [1121762.000284] wlan0: Trigger new scan to find an IBSS to join [1121762.004660] wlan0: Creating new IBSS network, BSSID b6:4a:d4:1a:ec:e5 [1121763.420218] wlan0: Selected IBSS BSSID a6:04:a5:0b:ab:36 based on configured SSID [1121878.633866] cfg80211: Found new beacon on frequency: 5180 MHz (Ch 36) on phy0 [1122051.060141] lo: Disabled Privacy Extensions [1124156.817170] wlan0: Trigger new scan to find an IBSS to join [1124157.077375] PM: Syncing filesystems ... done. [1124157.109143] PM: Preparing system for mem sleep [1124157.109149] Freezing user space processes ... (elapsed 0.00 seconds) done. [1124157.110719] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1124157.110780] PM: Entering mem sleep [1124157.110795] Suspending console(s) (use no_console_suspend to debug) [1124157.361463] PM: suspend of drv:psmouse dev:serio2 complete after 225.251 msecs [1124157.361645] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1124157.361769] sd 2:0:0:0: [sda] Stopping disk [1124157.638900] PM: suspend of drv:sd dev:2:0:0:0 complete after 277.257 msecs [1124158.036374] PM: suspend of drv:psmouse dev:serio1 complete after 384.233 msecs [1124158.636105] PM: suspend of drv:atkbd dev:serio0 complete after 599.717 msecs [1124158.673389] ACPI handle has no context! [1124158.673398] sdhci-pci 0000:15:00.2: PCI INT C disabled [1124158.673406] ACPI handle has no context! [1124158.693158] ACPI handle has no context! [1124158.756256] ata2: port disabled. ignoring. [1124158.756361] ata_piix 0000:00:1f.1: PCI INT C disabled [1124158.756390] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1124158.756409] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1124158.756427] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1124158.756445] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1124158.756459] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1124158.860389] HDA Intel 0000:00:1b.0: PCI INT B disabled [1124158.876136] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.649 msecs [1124158.876157] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1124158.876180] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1124158.876202] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1124158.877843] e1000e 0000:00:19.0: PCI INT A disabled [1124158.877854] e1000e 0000:00:19.0: PME# enabled [1124158.877861] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1124158.924210] i915 0000:00:02.0: power state changed by ACPI to D3 [1124158.924552] PM: suspend of devices complete after 1813.387 msecs [1124158.924555] PM: suspend devices took 1.816 seconds [1124158.925082] ricoh-mmc: Suspending. [1124158.925113] ricoh-mmc: Controller is now re-enabled. [1124158.948086] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1124158.956080] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1124158.964077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1124158.988079] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1124159.016068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1124159.016266] PM: late suspend of devices complete after 91.702 msecs [1124159.056073] ACPI: Preparing to enter system sleep state S3 [1124159.212019] Disabling non-boot CPUs ... [1124159.212042] CPU0 attaching NULL sched-domain. [1124159.212046] CPU1 attaching NULL sched-domain. [1124159.276022] CPU0 attaching NULL sched-domain. [1124159.278440] kvm: disabling virtualization on CPU1 [1124159.380029] CPU 1 is now offline [1124159.380033] SMP alternatives: switching to UP code [1124159.387787] Extended CMOS year: 2000 [1124159.387787] Back to C! [1124159.387787] CPU0: Thermal monitoring enabled (TM2) [1124159.387787] Extended CMOS year: 2000 [1124159.387787] Enabling non-boot CPUs ... [1124159.388098] SMP alternatives: switching to SMP code [1124159.394362] Booting processor 1 APIC 0x1 ip 0x6000 [1124159.387340] Initializing CPU#1 [1124159.387340] CPU: L1 I cache: 32K, L1 D cache: 32K [1124159.387340] CPU: L2 cache: 2048K [1124159.387340] CPU: Physical Processor ID: 0 [1124159.387340] CPU: Processor Core ID: 1 [1124159.387340] CPU1: Thermal monitoring enabled (TM2) [1124159.484086] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1124159.484111] kvm: enabling virtualization on CPU1 [1124159.484171] CPU0 attaching NULL sched-domain. [1124159.512024] CPU0 attaching sched-domain: [1124159.512027] domain 0: span 0-1 level MC [1124159.512030] groups: 0 1 [1124159.512035] CPU1 attaching sched-domain: [1124159.512037] domain 0: span 0-1 level MC [1124159.512039] groups: 1 0 [1124159.512625] CPU1 is up [1124159.513081] ACPI: Waking up from system sleep state S3 [1124159.860254] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1124159.860291] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1124159.860481] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1124159.904068] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1124159.912077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1124159.912134] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1124159.920079] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1124159.928077] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1124159.928157] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1124159.936077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1124159.944077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1124159.944183] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1124159.944318] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1124159.944497] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1124159.944676] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1124159.944847] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1124159.945013] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1124159.952077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1124159.960077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1124159.960141] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1124159.968077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1124159.976077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1124159.976142] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1124159.984077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1124159.992077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1124159.992135] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1124160.000094] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1124160.008077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1124160.008167] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1124160.016077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1124160.024077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1124160.024160] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1124160.024347] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1124160.024458] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1124160.024789] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1124160.040104] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1124160.040119] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1124160.056101] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1124160.056116] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1124160.056240] ricoh-mmc: Resuming. [1124160.056273] ricoh-mmc: Controller is now disabled. [1124160.056813] PM: early resume of devices complete after 196.693 msecs [1124160.118175] i915 0000:00:02.0: power state changed by ACPI to D0 [1124160.118219] i915 0000:00:02.0: power state changed by ACPI to D0 [1124160.118225] i915 0000:00:02.0: setting latency timer to 64 [1124160.332273] PM: resume of drv:i915 dev:0000:00:02.0 complete after 214.167 msecs [1124160.332322] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1124160.332332] e1000e 0000:00:19.0: setting latency timer to 64 [1124160.332350] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1124160.332359] e1000e 0000:00:19.0: PME# disabled [1124160.332454] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1124160.646192] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 313.907 msecs [1124160.646206] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1124160.646217] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1124160.646250] usb usb3: root hub lost power or was reset [1124160.646538] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1124160.646797] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1124160.646807] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1124160.646822] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1124160.646862] usb usb4: root hub lost power or was reset [1124160.647108] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1124160.647370] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1124160.647384] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1124160.647400] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1124160.647440] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1124160.647447] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1124160.647478] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1124160.647693] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1124160.647920] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1124160.647929] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1124160.647945] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1124160.647985] usb usb5: root hub lost power or was reset [1124160.648056] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1124160.648067] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1124160.648099] usb usb6: root hub lost power or was reset [1124160.648304] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1124160.648512] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1124160.648522] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1124160.648538] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1124160.648580] usb usb7: root hub lost power or was reset [1124160.648838] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1124160.649058] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1124160.649071] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1124160.649087] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1124160.649120] pci 0000:00:1e.0: setting latency timer to 64 [1124160.649149] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1124160.649161] ata_piix 0000:00:1f.1: setting latency timer to 64 [1124160.649618] ata2: port disabled. ignoring. [1124160.649636] ahci 0000:00:1f.2: setting latency timer to 64 [1124160.788137] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.482 msecs [1124160.812755] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1124160.812758] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1124160.813954] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1124160.814822] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1124160.836410] ata1.00: configured for UDMA/33 [1124160.846140] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1124160.852251] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1124160.852254] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1124160.968138] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1124160.968758] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1124160.968764] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1124160.968769] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1124160.969731] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1124160.969737] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1124160.969742] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1124160.970058] ata3.00: configured for UDMA/133 [1124160.984965] ata3.00: configured for UDMA/133 [1124160.984970] ata3: EH complete [1124161.108130] PM: resume of drv:usb dev:usb1 complete after 254.249 msecs [1124161.384132] PM: resume of drv:usb dev:usb3 complete after 247.950 msecs [1124161.496123] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1124161.645187] PM: resume of drv:usb dev:3-2 complete after 258.675 msecs [1124161.645231] sd 2:0:0:0: [sda] Starting disk [1124161.646313] thinkpad_acpi: ACPI backlight control delay disabled [1124161.649337] PM: resume of devices complete after 1592.415 msecs [1124161.649546] PM: resume devices took 1.592 seconds [1124161.649575] PM: Finishing wakeup. [1124161.649576] Restarting tasks ... done. [1124162.056572] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1124162.112459] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1124162.113176] ADDRCONF(NETDEV_UP): eth0: link is not ready [1124162.213718] Registered led device: iwl-phy0::radio [1124162.213744] Registered led device: iwl-phy0::assoc [1124162.213805] Registered led device: iwl-phy0::RX [1124162.213826] Registered led device: iwl-phy0::TX [1124162.356963] Registered led device: iwl-phy0::radio [1124162.356990] Registered led device: iwl-phy0::assoc [1124162.357012] Registered led device: iwl-phy0::RX [1124162.357033] Registered led device: iwl-phy0::TX [1124162.372851] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1124172.894525] wlan0: deauthenticating from 00:12:17:70:25:6e by local choice (reason=3) [1124172.929852] wlan0: direct probe to AP 00:12:17:70:25:6e (try 1) [1124172.931875] wlan0: direct probe responded [1124172.931880] wlan0: authenticate with AP 00:12:17:70:25:6e (try 1) [1124172.934078] wlan0: authenticated [1124172.934123] wlan0: associate with AP 00:12:17:70:25:6e (try 1) [1124172.936727] wlan0: RX AssocResp from 00:12:17:70:25:6e (capab=0x1 status=0 aid=5) [1124172.936734] wlan0: associated [1124172.938813] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1124183.684229] wlan0: no IPv6 routers present [1125695.653309] lo: Disabled Privacy Extensions [1130965.083221] lo: Disabled Privacy Extensions [1141421.701642] lo: Disabled Privacy Extensions [1141865.179856] lo: Disabled Privacy Extensions [1142471.522693] lo: Disabled Privacy Extensions [1142504.689447] lo: Disabled Privacy Extensions [1142746.976364] lo: Disabled Privacy Extensions [1142851.105759] lo: Disabled Privacy Extensions [1143652.731982] lo: Disabled Privacy Extensions [1143777.397519] lo: Disabled Privacy Extensions [1146199.341850] lo: Disabled Privacy Extensions [1146381.732697] lo: Disabled Privacy Extensions [1146466.131668] lo: Disabled Privacy Extensions [1146832.145173] wlan0: deauthenticating from 00:12:17:70:25:6e by local choice (reason=3) [1146832.365295] PM: Syncing filesystems ... done. [1146832.389104] PM: Preparing system for mem sleep [1146832.389109] Freezing user space processes ... (elapsed 0.00 seconds) done. [1146832.390643] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [1146832.390704] PM: Entering mem sleep [1146832.390719] Suspending console(s) (use no_console_suspend to debug) [1146832.656195] PM: suspend of drv:psmouse dev:serio2 complete after 224.043 msecs [1146832.656365] sd 2:0:0:0: [sda] Synchronizing SCSI cache [1146832.656487] sd 2:0:0:0: [sda] Stopping disk [1146832.935264] PM: suspend of drv:sd dev:2:0:0:0 complete after 278.901 msecs [1146833.332197] PM: suspend of drv:psmouse dev:serio1 complete after 384.100 msecs [1146833.932063] PM: suspend of drv:atkbd dev:serio0 complete after 599.855 msecs [1146833.969353] ACPI handle has no context! [1146833.969362] sdhci-pci 0000:15:00.2: PCI INT C disabled [1146833.969370] ACPI handle has no context! [1146833.989121] ACPI handle has no context! [1146834.052178] ata2: port disabled. ignoring. [1146834.052281] ata_piix 0000:00:1f.1: PCI INT C disabled [1146834.052307] ehci_hcd 0000:00:1d.7: PCI INT D disabled [1146834.052326] uhci_hcd 0000:00:1d.2: PCI INT C disabled [1146834.052342] uhci_hcd 0000:00:1d.1: PCI INT B disabled [1146834.052360] uhci_hcd 0000:00:1d.0: PCI INT A disabled [1146834.052374] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [1146834.156357] HDA Intel 0000:00:1b.0: PCI INT B disabled [1146834.172086] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.686 msecs [1146834.172105] ehci_hcd 0000:00:1a.7: PCI INT C disabled [1146834.172124] uhci_hcd 0000:00:1a.1: PCI INT B disabled [1146834.172142] uhci_hcd 0000:00:1a.0: PCI INT A disabled [1146834.173764] e1000e 0000:00:19.0: PCI INT A disabled [1146834.173775] e1000e 0000:00:19.0: PME# enabled [1146834.173782] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [1146834.240147] i915 0000:00:02.0: power state changed by ACPI to D3 [1146834.240473] PM: suspend of devices complete after 1849.383 msecs [1146834.240477] PM: suspend devices took 1.852 seconds [1146834.241026] ricoh-mmc: Suspending. [1146834.241050] ricoh-mmc: Controller is now re-enabled. [1146834.264086] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [1146834.272080] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [1146834.280077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [1146834.304079] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [1146834.332071] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [1146834.332274] PM: late suspend of devices complete after 91.791 msecs [1146834.372076] ACPI: Preparing to enter system sleep state S3 [1146834.528019] Disabling non-boot CPUs ... [1146834.528042] CPU0 attaching NULL sched-domain. [1146834.528046] CPU1 attaching NULL sched-domain. [1146834.592023] CPU0 attaching NULL sched-domain. [1146834.594443] kvm: disabling virtualization on CPU1 [1146834.696026] CPU 1 is now offline [1146834.696030] SMP alternatives: switching to UP code [1146834.703798] Extended CMOS year: 2000 [1146834.703798] Back to C! [1146834.703798] CPU0: Thermal monitoring enabled (TM2) [1146834.703798] Extended CMOS year: 2000 [1146834.703798] Enabling non-boot CPUs ... [1146834.704118] SMP alternatives: switching to SMP code [1146834.710387] Booting processor 1 APIC 0x1 ip 0x6000 [1146834.703362] Initializing CPU#1 [1146834.703362] CPU: L1 I cache: 32K, L1 D cache: 32K [1146834.703362] CPU: L2 cache: 2048K [1146834.703362] CPU: Physical Processor ID: 0 [1146834.703362] CPU: Processor Core ID: 1 [1146834.703362] CPU1: Thermal monitoring enabled (TM2) [1146834.800113] CPU1: Intel(R) Core(TM)2 Duo CPU T7100 @ 1.80GHz stepping 0d [1146834.800138] kvm: enabling virtualization on CPU1 [1146834.800199] CPU0 attaching NULL sched-domain. [1146834.828022] CPU0 attaching sched-domain: [1146834.828025] domain 0: span 0-1 level MC [1146834.828028] groups: 0 1 [1146834.828033] CPU1 attaching sched-domain: [1146834.828035] domain 0: span 0-1 level MC [1146834.828037] groups: 1 0 [1146834.828618] CPU1 is up [1146834.829077] ACPI: Waking up from system sleep state S3 [1146835.220259] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [1146835.220296] pci 0000:00:02.1: restoring config space at offset 0x1 (was 0x900000, writing 0x900007) [1146835.220486] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1146835.264069] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1146835.272078] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1146835.272139] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1146835.280075] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1146835.288078] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1146835.288160] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1146835.296077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1146835.304077] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1146835.304183] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [1146835.304315] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1146835.304487] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1146835.304656] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1146835.304827] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1146835.305002] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [1146835.312077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1146835.320077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1146835.320141] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1146835.328077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1146835.336077] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1146835.336147] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1146835.344077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1146835.352077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1146835.352141] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [1146835.360077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1146835.368077] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1146835.368169] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [1146835.376077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1146835.384077] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1146835.384162] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [1146835.384352] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [1146835.384463] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [1146835.384803] iwl3945 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [1146835.400107] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1146835.400119] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1146835.416100] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [1146835.416114] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [1146835.416236] ricoh-mmc: Resuming. [1146835.416269] ricoh-mmc: Controller is now disabled. [1146835.416809] PM: early resume of devices complete after 196.686 msecs [1146835.475415] i915 0000:00:02.0: power state changed by ACPI to D0 [1146835.475460] i915 0000:00:02.0: power state changed by ACPI to D0 [1146835.475466] i915 0000:00:02.0: setting latency timer to 64 [1146835.688281] PM: resume of drv:i915 dev:0000:00:02.0 complete after 212.933 msecs [1146835.688332] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1146835.688343] e1000e 0000:00:19.0: setting latency timer to 64 [1146835.688360] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [1146835.688370] e1000e 0000:00:19.0: PME# disabled [1146835.688467] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1146836.002592] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 314.297 msecs [1146836.002607] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [1146836.002620] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [1146836.002653] usb usb3: root hub lost power or was reset [1146836.002941] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1146836.003178] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [1146836.003188] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [1146836.003203] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [1146836.003247] usb usb4: root hub lost power or was reset [1146836.003501] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1146836.003731] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [1146836.003746] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [1146836.003761] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [1146836.003806] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1146836.003813] HDA Intel 0000:00:1b.0: setting latency timer to 64 [1146836.003843] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [1146836.004082] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1146836.004359] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [1146836.004374] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [1146836.004384] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [1146836.004419] usb usb5: root hub lost power or was reset [1146836.004449] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [1146836.004463] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [1146836.004493] usb usb6: root hub lost power or was reset [1146836.004734] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1146836.004961] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [1146836.004970] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1146836.004986] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [1146836.005029] usb usb7: root hub lost power or was reset [1146836.005307] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1146836.005535] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [1146836.005549] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [1146836.005565] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [1146836.005602] pci 0000:00:1e.0: setting latency timer to 64 [1146836.005621] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [1146836.005630] ata_piix 0000:00:1f.1: setting latency timer to 64 [1146836.006015] ata2: port disabled. ignoring. [1146836.006935] ahci 0000:00:1f.2: setting latency timer to 64 [1146836.144134] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 136.703 msecs [1146836.169126] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [1146836.169130] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [1146836.170000] ata1.00: ACPI cmd e3/00:10:00:00:00:a0 (IDLE) succeeded [1146836.170864] ata1.00: ACPI cmd e3/00:03:00:00:00:a0 (IDLE) succeeded [1146836.192402] ata1.00: configured for UDMA/33 [1146836.202140] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8301000-f83017ff] Max Packet=[2048] IR/IT contexts=[4/4] [1146836.208251] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [1146836.208254] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [1146836.324141] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [1146836.324770] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1146836.324776] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1146836.324782] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1146836.325743] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [1146836.325749] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [1146836.325754] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [1146836.326070] ata3.00: configured for UDMA/133 [1146836.341175] ata3.00: configured for UDMA/133 [1146836.341180] ata3: EH complete [1146836.464136] PM: resume of drv:usb dev:usb1 complete after 254.263 msecs [1146836.740132] PM: resume of drv:usb dev:usb3 complete after 247.945 msecs [1146836.852126] usb 3-2: reset full speed USB device using uhci_hcd and address 2 [1146837.001185] PM: resume of drv:usb dev:3-2 complete after 258.412 msecs [1146837.001229] sd 2:0:0:0: [sda] Starting disk [1146837.002306] thinkpad_acpi: ACPI backlight control delay disabled [1146837.005341] PM: resume of devices complete after 1588.425 msecs [1146837.005550] PM: resume devices took 1.588 seconds [1146837.005578] PM: Finishing wakeup. [1146837.005580] Restarting tasks ... done. [1146837.408751] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1146837.464345] e1000e 0000:00:19.0: irq 29 for MSI/MSI-X [1146837.464982] ADDRCONF(NETDEV_UP): eth0: link is not ready [1146837.551159] Registered led device: iwl-phy0::radio [1146837.551257] Registered led device: iwl-phy0::assoc [1146837.551303] Registered led device: iwl-phy0::RX [1146837.551349] Registered led device: iwl-phy0::TX [1146837.565376] ADDRCONF(NETDEV_UP): wlan0: link is not ready [1146847.902970] wlan0: deauthenticating from 00:22:33:2d:bc:a7 by local choice (reason=3) [1146847.922121] wlan0: direct probe to AP 00:22:33:2d:bc:a7 (try 1) [1146847.924242] wlan0: direct probe responded [1146847.924247] wlan0: authenticate with AP 00:22:33:2d:bc:a7 (try 1) [1146847.926180] wlan0: authenticated [1146847.926209] wlan0: associate with AP 00:22:33:2d:bc:a7 (try 1) [1146847.928778] wlan0: RX AssocResp from 00:22:33:2d:bc:a7 (capab=0x411 status=0 aid=3) [1146847.928784] wlan0: associated [1146847.932372] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [1146858.348041] wlan0: no IPv6 routers present