:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [ 4979.529727] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x40100106) [ 4979.545078] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [ 4979.545090] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [ 4979.561085] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [ 4979.561099] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [ 4979.561205] ricoh-mmc: Resuming. [ 4979.561231] ricoh-mmc: Controller is now disabled. [ 4979.561752] PM: early resume of devices complete after 196.649 msecs [ 4979.607677] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 4979.607683] e1000e 0000:00:19.0: setting latency timer to 64 [ 4979.607692] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [ 4979.607703] e1000e 0000:00:19.0: PME# disabled [ 4979.607772] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [ 4979.922241] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 314.591 msecs [ 4979.922252] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 4979.922268] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 4979.922296] usb usb3: root hub lost power or was reset [ 4979.922513] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 4979.922731] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 4979.922739] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 4979.922747] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 4979.922779] usb usb4: root hub lost power or was reset [ 4979.922978] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 4979.923227] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 4979.923238] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [ 4979.923246] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 4979.923290] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 4979.923310] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 4979.923342] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [ 4979.923541] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 4979.923760] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 4979.923767] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 4979.923773] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 4979.923795] usb usb5: root hub lost power or was reset [ 4979.923822] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 4979.923837] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 4979.923863] usb usb6: root hub lost power or was reset [ 4979.924115] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 4979.924298] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 4979.924304] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4979.924311] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 4979.924336] usb usb7: root hub lost power or was reset [ 4979.924567] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 4979.924769] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 4979.924779] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 4979.924791] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 4979.924827] pci 0000:00:1e.0: setting latency timer to 64 [ 4979.924855] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [ 4979.924865] ata_piix 0000:00:1f.1: setting latency timer to 64 [ 4979.924905] ahci 0000:00:1f.2: setting latency timer to 64 [ 4979.925231] ata2: port disabled. ignoring. [ 4980.089074] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [ 4980.089081] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [ 4980.090856] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [ 4980.092352] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [ 4980.116891] ata1.00: configured for UDMA/33 [ 4980.244116] ata5: SATA link down (SStatus 0 SControl 300) [ 4980.412112] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 4980.422346] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [ 4980.422353] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [ 4980.422625] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [ 4980.422631] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [ 4980.425961] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [ 4980.425970] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [ 4980.426217] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [ 4980.426227] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [ 4980.427723] ata3.00: configured for UDMA/133 [ 4980.443375] ata3.00: configured for UDMA/133 [ 4980.443381] ata3: EH complete [ 4981.253338] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1328.281 msecs [ 4981.392060] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 138.535 msecs [ 4981.450080] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [ 4981.456226] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4981.456231] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [ 4981.744130] PM: resume of drv:usb dev:usb1 complete after 251.251 msecs [ 4981.996119] PM: resume of drv:usb dev:usb2 complete after 251.964 msecs [ 4982.244122] PM: resume of drv:usb dev:usb3 complete after 247.974 msecs [ 4982.492116] PM: resume of drv:usb dev:usb6 complete after 247.918 msecs [ 4982.494705] sd 2:0:0:0: [sda] Starting disk [ 4982.620129] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [ 4982.774174] PM: resume of drv:usb dev:3-2 complete after 263.395 msecs [ 4982.884130] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [ 4983.045178] PM: resume of drv:usb dev:6-2 complete after 270.973 msecs [ 4983.046448] thinkpad_acpi: ACPI backlight control delay disabled [ 4983.160187] usb 2-2: reset high speed USB device using ehci_hcd and address 3 [ 4983.293730] PM: resume of drv:usb dev:2-2 complete after 244.487 msecs [ 4983.293801] PM: resume of devices complete after 3732.006 msecs [ 4983.294020] PM: resume devices took 3.732 seconds [ 4983.294045] PM: Finishing wakeup. [ 4983.294046] Restarting tasks ... done. [ 4983.534095] mmc0: new SDHC card at address 894a [ 4983.535845] mmcblk0: mmc0:894a SD04G 3.69 GiB [ 4983.535906] mmcblk0: p1 [ 4984.017626] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [ 4984.073605] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [ 4984.073882] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 4984.299339] Registered led device: iwl-phy0::radio [ 4984.299588] Registered led device: iwl-phy0::assoc [ 4984.299798] Registered led device: iwl-phy0::RX [ 4984.299996] Registered led device: iwl-phy0::TX [ 4984.342884] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 4985.725051] usb 3-1: new full speed USB device using uhci_hcd and address 4 [ 4985.898332] usb 3-1: configuration #1 chosen from 1 choice [ 7157.696149] usb 3-1: USB disconnect, address 4 [ 7157.696443] btusb_intr_complete: hci0 urb ef53bb80 failed to resubmit (19) [ 7157.696465] btusb_bulk_complete: hci0 urb ef53bc80 failed to resubmit (19) [ 7157.697447] btusb_bulk_complete: hci0 urb ef53bc00 failed to resubmit (19) [ 7157.697501] btusb_send_frame: hci0 urb f1e5f080 submission failed [ 7161.482026] PM: Syncing filesystems ... done. [ 7161.491350] PM: Preparing system for mem sleep [ 7161.491371] mmc0: card 894a removed [ 7161.518042] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 7161.518801] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 7161.518868] PM: Entering mem sleep [ 7161.518879] Suspending console(s) (use no_console_suspend to debug) [ 7161.740018] PM: suspend of drv:psmouse dev:serio2 complete after 206.896 msecs [ 7161.820108] sd 2:0:0:0: [sda] Synchronizing SCSI cache [ 7161.820193] sd 2:0:0:0: [sda] Stopping disk [ 7162.302818] PM: suspend of drv:sd dev:2:0:0:0 complete after 482.711 msecs [ 7162.688641] PM: suspend of drv:psmouse dev:serio1 complete after 385.774 msecs [ 7163.292083] PM: suspend of drv:atkbd dev:serio0 complete after 603.415 msecs [ 7163.341396] ACPI handle has no context! [ 7163.341408] sdhci-pci 0000:15:00.2: PCI INT C disabled [ 7163.341421] ACPI handle has no context! [ 7163.361202] ACPI handle has no context! [ 7165.429383] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 2037.271 msecs [ 7165.476328] ata_piix 0000:00:1f.1: PCI INT C disabled [ 7165.476356] ehci_hcd 0000:00:1d.7: PCI INT D disabled [ 7165.476377] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 7165.476403] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 7165.476423] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 7165.476438] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [ 7165.580458] HDA Intel 0000:00:1b.0: PCI INT B disabled [ 7165.596103] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.636 msecs [ 7165.596113] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 7165.596129] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 7165.596146] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 7165.597763] e1000e 0000:00:19.0: PCI INT A disabled [ 7165.597771] e1000e 0000:00:19.0: PME# enabled [ 7165.597778] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [ 7165.612368] PM: suspend of devices complete after 4093.167 msecs [ 7165.612371] PM: suspend devices took 4.096 seconds [ 7165.612889] ricoh-mmc: Suspending. [ 7165.612915] ricoh-mmc: Controller is now re-enabled. [ 7165.637081] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [ 7165.645070] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [ 7165.653068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [ 7165.677065] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [ 7165.705059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [ 7165.705258] PM: late suspend of devices complete after 92.881 msecs [ 7165.745060] ACPI: Preparing to enter system sleep state S3 [ 7165.885215] Disabling non-boot CPUs ... [ 7165.885229] CPU0 attaching NULL sched-domain. [ 7165.885233] CPU1 attaching NULL sched-domain. [ 7165.933018] CPU0 attaching NULL sched-domain. [ 7166.036027] CPU 1 is now offline [ 7166.036030] SMP alternatives: switching to UP code [ 7166.041627] Extended CMOS year: 2000 [ 7166.041627] Back to C! [ 7166.041627] CPU0: Thermal monitoring enabled (TM2) [ 7166.041627] Extended CMOS year: 2000 [ 7166.041627] Enabling non-boot CPUs ... [ 7166.041627] SMP alternatives: switching to SMP code [ 7166.046982] Booting processor 1 APIC 0x1 ip 0x6000 [ 7166.041627] Initializing CPU#1 [ 7166.041627] CPU: L1 I cache: 32K, L1 D cache: 32K [ 7166.041627] CPU: L2 cache: 4096K [ 7166.041627] CPU: Physical Processor ID: 0 [ 7166.041627] CPU: Processor Core ID: 1 [ 7166.041627] CPU1: Thermal monitoring enabled (TM2) [ 7166.136121] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b [ 7166.136177] CPU0 attaching NULL sched-domain. [ 7166.164020] CPU0 attaching sched-domain: [ 7166.164023] domain 0: span 0-1 level MC [ 7166.164025] groups: 0 1 [ 7166.164029] CPU1 attaching sched-domain: [ 7166.164030] domain 0: span 0-1 level MC [ 7166.164032] groups: 1 0 [ 7166.180168] CPU1 is up [ 7166.180736] ACPI: Waking up from system sleep state S3 [ 7166.561235] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7166.561419] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [ 7166.605059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 7166.613067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 7166.613127] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [ 7166.621067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 7166.629067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 7166.629143] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 7166.637067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 7166.645066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 7166.645164] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [ 7166.645289] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7166.645454] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7166.645610] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7166.645773] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7166.645939] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7166.653067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7166.661067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7166.661128] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [ 7166.669067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7166.677066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7166.677129] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [ 7166.685067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 7166.693066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 7166.693119] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [ 7166.701066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 7166.709066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 7166.709155] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [ 7166.717067] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 7166.725066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 7166.725145] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [ 7166.725324] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [ 7166.725425] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [ 7166.725611] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [ 7166.725764] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [ 7166.740088] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [ 7166.740102] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [ 7166.756090] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [ 7166.756102] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [ 7166.756211] ricoh-mmc: Resuming. [ 7166.756241] ricoh-mmc: Controller is now disabled. [ 7166.756775] PM: early resume of devices complete after 195.674 msecs [ 7166.805607] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 7166.805615] e1000e 0000:00:19.0: setting latency timer to 64 [ 7166.805623] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [ 7166.805636] e1000e 0000:00:19.0: PME# disabled [ 7166.805707] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [ 7167.118119] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 312.540 msecs [ 7167.118129] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [ 7167.118144] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 7167.118171] usb usb3: root hub lost power or was reset [ 7167.118386] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 7167.118597] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [ 7167.118602] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 7167.118610] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 7167.118642] usb usb4: root hub lost power or was reset [ 7167.118841] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 7167.119029] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [ 7167.119036] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [ 7167.119046] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 7167.119096] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 7167.119106] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 7167.119150] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [ 7167.119347] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7167.119526] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7167.119532] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 7167.119543] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 7167.119566] usb usb5: root hub lost power or was reset [ 7167.119592] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 7167.119609] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 7167.119634] usb usb6: root hub lost power or was reset [ 7167.119846] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 7167.120087] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [ 7167.120093] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 7167.120103] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 7167.120127] usb usb7: root hub lost power or was reset [ 7167.120349] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 7167.120581] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [ 7167.120593] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 7167.120614] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 7167.120648] pci 0000:00:1e.0: setting latency timer to 64 [ 7167.120672] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [ 7167.120679] ata_piix 0000:00:1f.1: setting latency timer to 64 [ 7167.120723] ahci 0000:00:1f.2: setting latency timer to 64 [ 7167.121068] ata2: port disabled. ignoring. [ 7167.285625] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [ 7167.285629] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [ 7167.286707] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [ 7167.288210] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [ 7167.309419] ata1.00: configured for UDMA/33 [ 7167.437096] ata5: SATA link down (SStatus 0 SControl 300) [ 7167.549080] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 7167.560940] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [ 7167.560943] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [ 7167.561233] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [ 7167.561235] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [ 7167.564396] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [ 7167.564399] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [ 7167.564658] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [ 7167.564661] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [ 7167.566177] ata3.00: configured for UDMA/133 [ 7167.584328] ata3.00: configured for UDMA/133 [ 7167.584330] ata3: EH complete [ 7168.060466] irq 18: nobody cared (try booting with the "irqpoll" option) [ 7168.060469] Pid: 22299, comm: pm-suspend Tainted: P 2.6.32-31-generic #61-Ubuntu [ 7168.060471] Call Trace: [ 7168.060478] [] ? printk+0x1d/0x25 [ 7168.060482] [] __report_bad_irq+0x2c/0x90 [ 7168.060484] [] ? handle_IRQ_event+0x54/0x150 [ 7168.060486] [] note_interrupt+0x150/0x190 [ 7168.060489] [] handle_fasteoi_irq+0xac/0xd0 [ 7168.060492] [] handle_irq+0x1d/0x30 [ 7168.060494] [] do_IRQ+0x4c/0xc0 [ 7168.060497] [] ? do_IRQ+0x55/0xc0 [ 7168.060499] [] common_interrupt+0x30/0x40 [ 7168.060610] [] ? os_io_read_byte+0xc/0x10 [nvidia] [ 7168.060703] [] _nv000016rm+0x11/0x18 [nvidia] [ 7168.060808] [] ? _nv000217rm+0xe/0x21 [nvidia] [ 7168.060908] [] ? _nv000310rm+0x7c/0x9a [nvidia] [ 7168.061000] [] ? _nv004710rm+0x196/0x1d1 [nvidia] [ 7168.061106] [] ? _nv004034rm+0x93/0x2c3 [nvidia] [ 7168.061211] [] ? _nv002965rm+0x28a/0x4c0 [nvidia] [ 7168.061316] [] ? _nv003101rm+0x3a6/0x5d4 [nvidia] [ 7168.061421] [] ? _nv002960rm+0x4e/0x54 [nvidia] [ 7168.061525] [] ? _nv002971rm+0x1cc/0x1e4 [nvidia] [ 7168.061630] [] ? rm_power_management+0x1f6/0x286 [nvidia] [ 7168.061719] [] ? nv_power_management+0xef/0x200 [nvidia] [ 7168.061729] [] ? ktime_get+0x6f/0x110 [ 7168.061812] [] ? nv_kern_resume+0x14/0x20 [nvidia] [ 7168.061815] [] ? pci_legacy_resume+0x33/0x60 [ 7168.061817] [] ? pci_pm_resume+0x6f/0xa0 [ 7168.061821] [] ? pm_op+0xb6/0x180 [ 7168.061824] [] ? down+0x29/0x40 [ 7168.061827] [] ? device_resume+0x4c/0x150 [ 7168.061829] [] ? kobject_get+0x12/0x20 [ 7168.061832] [] ? dpm_resume+0xb1/0x140 [ 7168.061835] [] ? dpm_resume_end+0x17/0x30 [ 7168.061838] [] ? suspend_devices_and_enter+0x97/0xd0 [ 7168.061840] [] ? printk+0x1d/0x25 [ 7168.061842] [] ? enter_state+0xbd/0xf0 [ 7168.061845] [] ? state_store+0x75/0xc0 [ 7168.061847] [] ? state_store+0x0/0xc0 [ 7168.061850] [] ? kobj_attr_store+0x20/0x30 [ 7168.061853] [] ? sysfs_write_file+0x95/0x100 [ 7168.061856] [] ? vfs_write+0xa2/0x1a0 [ 7168.061858] [] ? sysfs_write_file+0x0/0x100 [ 7168.061861] [] ? do_page_fault+0x160/0x3a0 [ 7168.061863] [] ? sys_write+0x42/0x70 [ 7168.061865] [] ? syscall_call+0x7/0xb [ 7168.061868] [] ? do_debug+0x130/0x160 [ 7168.061869] handlers: [ 7168.061870] [] (usb_hcd_irq+0x0/0x80) [ 7168.061873] Disabling IRQ #18 [ 7168.375845] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1254.975 msecs [ 7168.513150] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.152 msecs [ 7168.570163] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [ 7168.576181] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 7168.576184] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [ 7168.865146] PM: resume of drv:usb dev:usb1 complete after 251.246 msecs [ 7169.117143] PM: resume of drv:usb dev:usb2 complete after 251.968 msecs [ 7169.365148] PM: resume of drv:usb dev:usb3 complete after 247.975 msecs [ 7169.613145] PM: resume of drv:usb dev:usb6 complete after 247.918 msecs [ 7169.615609] sd 2:0:0:0: [sda] Starting disk [ 7169.748133] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [ 7169.953197] PM: resume of drv:usb dev:3-2 complete after 314.986 msecs [ 7170.120132] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [ 7170.276210] PM: resume of drv:usb dev:6-2 complete after 322.982 msecs [ 7170.277495] thinkpad_acpi: ACPI backlight control delay disabled [ 7170.280259] PM: resume of devices complete after 3523.439 msecs [ 7170.280430] PM: resume devices took 3.524 seconds [ 7170.280465] PM: Finishing wakeup. [ 7170.280466] Restarting tasks ... [ 7170.280516] usb 2-2: USB disconnect, address 3 [ 7170.315374] done. [ 7170.608618] mmc0: new SDHC card at address 894a [ 7170.608783] mmcblk0: mmc0:894a SD04G 3.69 GiB [ 7170.608839] mmcblk0: p1 [ 7171.021291] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [ 7171.077116] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [ 7171.077422] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 7171.294026] Registered led device: iwl-phy0::radio [ 7171.294880] Registered led device: iwl-phy0::assoc [ 7171.295388] Registered led device: iwl-phy0::RX [ 7171.295878] Registered led device: iwl-phy0::TX [ 7171.334979] ADDRCONF(NETDEV_UP): wlan0: link is not ready [ 7172.680080] usb 3-1: new full speed USB device using uhci_hcd and address 5 [ 7172.851452] usb 3-1: configuration #1 chosen from 1 choice [ 7224.278382] wlan0: deauthenticating from 02:c8:40:2a:4f:b0 by local choice (reason=3) [ 7224.286678] wlan0: direct probe to AP 02:c8:40:2a:4d:80 (try 1) [ 7224.290344] wlan0: direct probe responded [ 7224.290354] wlan0: authenticate with AP 02:c8:40:2a:4d:80 (try 1) [ 7224.292452] wlan0: authenticated [ 7224.292495] wlan0: associate with AP 02:c8:40:2a:4d:80 (try 1) [ 7224.343855] wlan0: RX AssocResp from 02:c8:40:2a:4d:80 (capab=0x1 status=0 aid=7) [ 7224.343863] wlan0: associated [ 7224.365890] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [ 7224.366017] cfg80211: Calling CRDA for country: DE [ 7224.373100] cfg80211: Received country IE: [ 7224.373104] cfg80211: Regulatory domain: DE [ 7224.373105] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [ 7224.373108] (2402000 KHz - 2494000 KHz @ 40000 KHz), (10000 mBi, 10000 mBm) [ 7224.373110] cfg80211: CRDA thinks this should applied: [ 7224.373111] cfg80211: Regulatory domain: DE [ 7224.373113] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [ 7224.373115] (2400000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm) [ 7224.373118] (5150000 KHz - 5350000 KHz @ 40000 KHz), (N/A, 2000 mBm) [ 7224.373120] (5470000 KHz - 5725000 KHz @ 40000 KHz), (N/A, 2698 mBm) [ 7224.373122] cfg80211: We intersect both of these and get: [ 7224.373123] cfg80211: Regulatory domain: 98 [ 7224.373125] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [ 7224.373127] (2402000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm) [ 7224.373132] cfg80211: Leaving channel 5180 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373134] cfg80211: Leaving channel 5200 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373137] cfg80211: Leaving channel 5220 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373139] cfg80211: Leaving channel 5240 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373141] cfg80211: Leaving channel 5260 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373143] cfg80211: Leaving channel 5280 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373146] cfg80211: Leaving channel 5300 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373148] cfg80211: Leaving channel 5320 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373150] cfg80211: Leaving channel 5500 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373152] cfg80211: Leaving channel 5520 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373155] cfg80211: Leaving channel 5540 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373157] cfg80211: Leaving channel 5560 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373159] cfg80211: Leaving channel 5580 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373161] cfg80211: Leaving channel 5600 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373164] cfg80211: Leaving channel 5620 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373166] cfg80211: Leaving channel 5640 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373168] cfg80211: Leaving channel 5660 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373170] cfg80211: Leaving channel 5680 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373172] cfg80211: Leaving channel 5700 MHz intact on phy0 - no rule found in band on Country IE [ 7224.373175] cfg80211: Current regulatory domain updated by AP to: DE [ 7224.373177] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) [ 7224.373180] (2402000 KHz - 2483500 KHz @ 40000 KHz), (N/A, 2000 mBm) [ 7234.864259] wlan0: no IPv6 routers present [ 7945.500305] No probe response from AP 02:c8:40:2a:4d:80 after 500ms, disconnecting. [ 7947.897806] wlan0: direct probe to AP 02:c8:40:35:b4:60 (try 1) [ 7947.897968] wlan0: deauthenticating from 02:c8:40:35:b4:60 by local choice (reason=3) [ 7947.897992] wlan0: deauthenticating from 02:c8:40:35:b4:60 by local choice (reason=3) [ 7947.907319] wlan0: direct probe to AP 02:c8:40:35:9e:80 (try 1) [ 7947.911383] wlan0: direct probe responded [ 7947.911387] wlan0: authenticate with AP 02:c8:40:35:9e:80 (try 1) [ 7947.913402] wlan0: authenticated [ 7947.913429] wlan0: associate with AP 02:c8:40:35:9e:80 (try 1) [ 7947.918855] wlan0: RX AssocResp from 02:c8:40:35:9e:80 (capab=0x1 status=0 aid=11) [ 7947.918859] wlan0: associated [ 7981.500244] No probe response from AP 02:c8:40:35:9e:80 after 500ms, disconnecting. [ 7983.889867] wlan0: deauthenticating from 02:c8:40:35:9e:80 by local choice (reason=3) [ 7983.898062] wlan0: direct probe to AP 02:c8:40:2a:4d:80 (try 1) [ 7983.900957] wlan0: direct probe responded [ 7983.900967] wlan0: authenticate with AP 02:c8:40:2a:4d:80 (try 1) [ 7983.903190] wlan0: authenticated [ 7983.903233] wlan0: associate with AP 02:c8:40:2a:4d:80 (try 1) [ 7983.957514] wlan0: RX AssocResp from 02:c8:40:2a:4d:80 (capab=0x1 status=0 aid=7) [ 7983.957519] wlan0: associated [ 8009.500282] No probe response from AP 02:c8:40:2a:4d:80 after 500ms, disconnecting. [ 8011.925571] wlan0: direct probe to AP 02:c8:40:2a:12:a0 (try 1) [ 8011.932866] wlan0: direct probe responded [ 8011.932872] wlan0: authenticate with AP 02:c8:40:2a:12:a0 (try 1) [ 8011.936321] wlan0: authenticated [ 8011.936339] wlan0: associate with AP 02:c8:40:2a:12:a0 (try 1) [ 8011.942427] wlan0: RX AssocResp from 02:c8:40:2a:12:a0 (capab=0x1 status=0 aid=2) [ 8011.942431] wlan0: associated [ 8025.781317] wlan0: deauthenticating from 02:c8:40:2a:12:a0 by local choice (reason=3) [ 8025.797178] wlan0: deauthenticating from 02:c8:40:2a:12:a0 by local choice (reason=3) [ 8025.804896] wlan0: direct probe to AP 02:c8:40:2a:7a:e0 (try 1) [ 8026.004319] wlan0: direct probe to AP 02:c8:40:2a:7a:e0 (try 2) [ 8026.013701] wlan0: direct probe responded [ 8026.013712] wlan0: authenticate with AP 02:c8:40:2a:7a:e0 (try 1) [ 8026.016003] wlan0: authenticated [ 8026.016130] wlan0: associate with AP 02:c8:40:2a:7a:e0 (try 1) [ 8026.041627] wlan0: RX AssocResp from 02:c8:40:2a:7a:e0 (capab=0x1 status=0 aid=20) [ 8026.041635] wlan0: associated [ 8054.500323] No probe response from AP 02:c8:40:2a:7a:e0 after 500ms, disconnecting. [ 8056.857407] wlan0: direct probe to AP 02:c8:40:2a:83:60 (try 1) [ 8056.860066] wlan0: direct probe responded [ 8056.860070] wlan0: authenticate with AP 02:c8:40:2a:83:60 (try 1) [ 8056.862417] wlan0: authenticated [ 8056.862435] wlan0: associate with AP 02:c8:40:2a:83:60 (try 1) [ 8056.875765] wlan0: RX AssocResp from 02:c8:40:2a:83:60 (capab=0x1 status=0 aid=2) [ 8056.875770] wlan0: associated [ 8084.500303] No probe response from AP 02:c8:40:2a:83:60 after 500ms, disconnecting. [ 8086.929036] wlan0: direct probe to AP 02:c8:40:2a:d7:30 (try 1) [ 8086.931802] wlan0: direct probe responded [ 8086.931806] wlan0: authenticate with AP 02:c8:40:2a:d7:30 (try 1) [ 8086.933944] wlan0: authenticated [ 8086.933979] wlan0: associate with AP 02:c8:40:2a:d7:30 (try 1) [ 8086.948639] wlan0: RX AssocResp from 02:c8:40:2a:d7:30 (capab=0x1 status=0 aid=9) [ 8086.948644] wlan0: associated [ 8104.500321] No probe response from AP 02:c8:40:2a:d7:30 after 500ms, disconnecting. [ 8106.879946] wlan0: direct probe to AP 02:c8:40:2a:76:50 (try 1) [ 8106.883120] wlan0: direct probe responded [ 8106.883123] wlan0: authenticate with AP 02:c8:40:2a:76:50 (try 1) [ 8106.885481] wlan0: authenticated [ 8106.885543] wlan0: associate with AP 02:c8:40:2a:76:50 (try 1) [ 8106.891283] wlan0: RX AssocResp from 02:c8:40:2a:76:50 (capab=0x1 status=0 aid=3) [ 8106.891288] wlan0: associated [10085.248160] usb 2-2: new high speed USB device using ehci_hcd and address 4 [10086.303471] usb 2-2: configuration #1 chosen from 1 choice [10086.303931] scsi6 : SCSI emulation for USB Mass Storage devices [10086.304363] usb-storage: device found at 4 [10086.304369] usb-storage: waiting for device to settle before scanning [10091.304409] usb-storage: device scan complete [10091.305574] scsi 6:0:0:0: Direct-Access General USB Flash Disk 1100 PQ: 0 ANSI: 0 CCS [10091.310347] sd 6:0:0:0: Attached scsi generic sg2 type 0 [10091.314863] sd 6:0:0:0: [sdb] 7831552 512-byte logical blocks: (4.00 GB/3.73 GiB) [10091.315734] sd 6:0:0:0: [sdb] Write Protect is off [10091.315742] sd 6:0:0:0: [sdb] Mode Sense: 43 00 00 00 [10091.315748] sd 6:0:0:0: [sdb] Assuming drive cache: write through [10091.321352] sd 6:0:0:0: [sdb] Assuming drive cache: write through [10091.321363] sdb: sdb1 [10091.326110] sd 6:0:0:0: [sdb] Assuming drive cache: write through [10091.326120] sd 6:0:0:0: [sdb] Attached SCSI removable disk [11391.450483] usb 2-2: USB disconnect, address 4 [13515.836169] usb 2-2: new high speed USB device using ehci_hcd and address 5 [13515.970658] usb 2-2: configuration #1 chosen from 1 choice [13515.972672] scsi7 : SCSI emulation for USB Mass Storage devices [13515.973459] usb-storage: device found at 5 [13515.973465] usb-storage: waiting for device to settle before scanning [13515.973572] scsi8 : SCSI emulation for USB Mass Storage devices [13515.973917] usb-storage: device found at 5 [13515.973922] usb-storage: waiting for device to settle before scanning [13516.702711] usb 2-2: USB disconnect, address 5 [13521.716093] usb 2-2: new high speed USB device using ehci_hcd and address 6 [13521.851034] usb 2-2: configuration #1 chosen from 1 choice [13521.858136] scsi13 : SCSI emulation for USB Mass Storage devices [13521.858649] usb-storage: device found at 6 [13521.858655] usb-storage: waiting for device to settle before scanning [13521.870339] scsi14 : SCSI emulation for USB Mass Storage devices [13521.873000] usb-storage: device found at 6 [13521.873066] usb-storage: waiting for device to settle before scanning [13521.917283] usbcore: registered new interface driver usbserial [13521.917800] USB Serial support registered for generic [13521.918431] usbcore: registered new interface driver usbserial_generic [13521.918437] usbserial: USB Serial Driver core [13521.934380] USB Serial support registered for GSM modem (1-port) [13521.935249] option 2-2:1.0: GSM modem (1-port) converter detected [13521.935626] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB0 [13521.935656] option 2-2:1.1: GSM modem (1-port) converter detected [13521.935978] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB1 [13521.936070] option 2-2:1.2: GSM modem (1-port) converter detected [13521.936348] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB2 [13521.936376] option 2-2:1.3: GSM modem (1-port) converter detected [13521.936667] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB3 [13521.936735] usbcore: registered new interface driver option [13521.936740] option: v0.7.2:USB Driver for GSM modems [13526.858086] usb-storage: device scan complete [13526.860526] scsi 13:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2 [13526.870710] sr1: scsi-1 drive [13526.872569] sr 13:0:0:0: Attached scsi CD-ROM sr1 [13526.875445] usb-storage: device scan complete [13526.876483] scsi 14:0:0:0: Direct-Access HUAWEI SD Storage 2.31 PQ: 0 ANSI: 2 [13526.879038] sr 13:0:0:0: Attached scsi generic sg2 type 5 [13526.881881] sd 14:0:0:0: Attached scsi generic sg3 type 0 [13526.890463] sd 14:0:0:0: [sdb] Attached SCSI removable disk [13539.279960] ISO 9660 Extensions: Microsoft Joliet Level 1 [13539.295737] ISOFS: changing to secondary root [13548.465861] PPP BSD Compression module registered [13548.496142] PPP Deflate Compression module registered [13606.277048] wlan0: deauthenticating from 02:c8:40:2a:76:50 by local choice (reason=3) [13606.326153] wlan0: deauthenticating from 02:c8:40:2a:76:50 by local choice (reason=3) [13606.366431] wlan0: direct probe to AP 02:c8:40:2a:76:50 (try 1) [13606.369803] wlan0: direct probe responded [13606.369809] wlan0: authenticate with AP 02:c8:40:2a:76:50 (try 1) [13606.371952] wlan0: authenticated [13606.371974] wlan0: associate with AP 02:c8:40:2a:76:50 (try 1) [13606.402592] wlan0: RX AssocResp from 02:c8:40:2a:76:50 (capab=0x1 status=0 aid=3) [13606.402596] wlan0: associated [13609.968052] wlan0: deauthenticating from 02:c8:40:2a:76:50 by local choice (reason=3) [14212.748111] CE: hpet increasing min_delta_ns to 50624 nsec [14356.041769] usb 2-2: USB disconnect, address 6 [14356.042043] option: option_instat_callback: error -108 [14356.042442] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 [14356.042476] option 2-2:1.0: device disconnected [14356.042752] option: option_instat_callback: error -108 [14356.042913] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 [14356.042957] option 2-2:1.1: device disconnected [14356.046939] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2 [14356.046985] option 2-2:1.2: device disconnected [14356.047294] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3 [14356.047342] option 2-2:1.3: device disconnected [14364.081179] usb 3-1: USB disconnect, address 5 [14364.082077] btusb_intr_complete: hci0 urb f1bc7180 failed to resubmit (19) [14364.082100] btusb_bulk_complete: hci0 urb f1bc7000 failed to resubmit (19) [14364.083107] btusb_bulk_complete: hci0 urb f1290c80 failed to resubmit (19) [14364.083161] btusb_send_frame: hci0 urb f1bc3f80 submission failed [14368.766372] PM: Syncing filesystems ... done. [14368.944653] PM: Preparing system for mem sleep [14368.944671] mmc0: card 894a removed [14368.970742] Freezing user space processes ... (elapsed 0.09 seconds) done. [14369.068163] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [14369.068237] PM: Entering mem sleep [14369.068248] Suspending console(s) (use no_console_suspend to debug) [14369.297530] PM: suspend of drv:psmouse dev:serio2 complete after 228.926 msecs [14369.376086] sd 2:0:0:0: [sda] Synchronizing SCSI cache [14369.376191] sd 2:0:0:0: [sda] Stopping disk [14369.873924] PM: suspend of drv:sd dev:2:0:0:0 complete after 497.839 msecs [14370.259762] PM: suspend of drv:psmouse dev:serio1 complete after 385.787 msecs [14370.864066] PM: suspend of drv:atkbd dev:serio0 complete after 604.279 msecs [14370.913398] ACPI handle has no context! [14370.913410] sdhci-pci 0000:15:00.2: PCI INT C disabled [14370.913423] ACPI handle has no context! [14370.933189] ACPI handle has no context! [14372.527854] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 1563.745 msecs [14372.572294] ata_piix 0000:00:1f.1: PCI INT C disabled [14372.572324] ehci_hcd 0000:00:1d.7: PCI INT D disabled [14372.572341] uhci_hcd 0000:00:1d.2: PCI INT C disabled [14372.572357] uhci_hcd 0000:00:1d.1: PCI INT B disabled [14372.572380] uhci_hcd 0000:00:1d.0: PCI INT A disabled [14372.572395] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [14372.676439] HDA Intel 0000:00:1b.0: PCI INT B disabled [14372.692457] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 120.031 msecs [14372.692475] ehci_hcd 0000:00:1a.7: PCI INT C disabled [14372.692492] uhci_hcd 0000:00:1a.1: PCI INT B disabled [14372.692507] uhci_hcd 0000:00:1a.0: PCI INT A disabled [14372.694113] e1000e 0000:00:19.0: PCI INT A disabled [14372.694121] e1000e 0000:00:19.0: PME# enabled [14372.694129] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [14372.708391] PM: suspend of devices complete after 3639.827 msecs [14372.708394] PM: suspend devices took 3.644 seconds [14372.708881] ricoh-mmc: Suspending. [14372.708908] ricoh-mmc: Controller is now re-enabled. [14372.733078] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [14372.741068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [14372.749067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [14372.773071] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [14372.801058] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [14372.801256] PM: late suspend of devices complete after 92.856 msecs [14372.841062] ACPI: Preparing to enter system sleep state S3 [14372.981224] Disabling non-boot CPUs ... [14372.981237] CPU0 attaching NULL sched-domain. [14372.981240] CPU1 attaching NULL sched-domain. [14373.029018] CPU0 attaching NULL sched-domain. [14373.132035] CPU 1 is now offline [14373.132037] SMP alternatives: switching to UP code [14373.137692] Extended CMOS year: 2000 [14373.137692] Back to C! [14373.137692] CPU0: Thermal monitoring enabled (TM2) [14373.137692] Extended CMOS year: 2000 [14373.137692] Enabling non-boot CPUs ... [14373.137692] SMP alternatives: switching to SMP code [14373.143070] Booting processor 1 APIC 0x1 ip 0x6000 [14373.137692] Initializing CPU#1 [14373.137692] CPU: L1 I cache: 32K, L1 D cache: 32K [14373.137692] CPU: L2 cache: 4096K [14373.137692] CPU: Physical Processor ID: 0 [14373.137692] CPU: Processor Core ID: 1 [14373.137692] CPU1: Thermal monitoring enabled (TM2) [14373.232112] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b [14373.232171] CPU0 attaching NULL sched-domain. [14373.260022] CPU0 attaching sched-domain: [14373.260024] domain 0: span 0-1 level MC [14373.260026] groups: 0 1 [14373.260030] CPU1 attaching sched-domain: [14373.260032] domain 0: span 0-1 level MC [14373.260034] groups: 1 0 [14373.292162] CPU1 is up [14373.292728] ACPI: Waking up from system sleep state S3 [14373.625234] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14373.625422] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [14373.669059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [14373.677067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [14373.677125] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [14373.685067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [14373.693067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [14373.693141] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [14373.701067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [14373.709067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [14373.709166] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [14373.709290] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14373.709444] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14373.709614] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14373.709770] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14373.709931] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14373.717067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14373.725066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14373.725129] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [14373.733066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14373.741066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14373.741129] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [14373.749067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [14373.757067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [14373.757122] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [14373.765067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [14373.773066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [14373.773153] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [14373.781067] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [14373.789066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [14373.789137] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [14373.789288] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [14373.789384] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [14373.789549] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [14373.789702] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [14373.804085] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [14373.804097] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [14373.820089] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [14373.820103] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [14373.820214] ricoh-mmc: Resuming. [14373.820240] ricoh-mmc: Controller is now disabled. [14373.820754] PM: early resume of devices complete after 195.656 msecs [14373.867600] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [14373.867608] e1000e 0000:00:19.0: setting latency timer to 64 [14373.867616] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [14373.867630] e1000e 0000:00:19.0: PME# disabled [14373.867690] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [14374.182135] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 314.565 msecs [14374.182146] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [14374.182162] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [14374.182187] usb usb3: root hub lost power or was reset [14374.182411] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [14374.182664] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [14374.182669] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [14374.182679] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [14374.182703] usb usb4: root hub lost power or was reset [14374.182910] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [14374.183148] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [14374.183155] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [14374.183166] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [14374.183216] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [14374.183225] HDA Intel 0000:00:1b.0: setting latency timer to 64 [14374.183263] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [14374.183465] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14374.183635] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14374.183643] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [14374.183658] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [14374.183683] usb usb5: root hub lost power or was reset [14374.183709] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [14374.183726] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [14374.183752] usb usb6: root hub lost power or was reset [14374.183963] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [14374.184143] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [14374.184149] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [14374.184156] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [14374.184185] usb usb7: root hub lost power or was reset [14374.184404] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [14374.184631] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [14374.184639] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [14374.184651] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [14374.184711] pci 0000:00:1e.0: setting latency timer to 64 [14374.184733] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [14374.184747] ata_piix 0000:00:1f.1: setting latency timer to 64 [14374.184788] ahci 0000:00:1f.2: setting latency timer to 64 [14374.185124] ata2: port disabled. ignoring. [14374.349503] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [14374.349506] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [14374.350589] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [14374.352152] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [14374.377425] ata1.00: configured for UDMA/33 [14374.505079] ata5: SATA link down (SStatus 0 SControl 300) [14374.617080] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [14374.630640] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [14374.630644] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [14374.630931] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [14374.630934] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [14374.634130] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [14374.634133] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [14374.634398] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [14374.634401] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [14374.635900] ata3.00: configured for UDMA/133 [14374.652365] ata3.00: configured for UDMA/133 [14374.652368] ata3: EH complete [14375.118905] irq 18: nobody cared (try booting with the "irqpoll" option) [14375.118909] Pid: 24852, comm: pm-suspend Tainted: P 2.6.32-31-generic #61-Ubuntu [14375.118911] Call Trace: [14375.118918] [] ? printk+0x1d/0x25 [14375.118921] [] __report_bad_irq+0x2c/0x90 [14375.118924] [] ? handle_IRQ_event+0x54/0x150 [14375.118926] [] note_interrupt+0x150/0x190 [14375.118929] [] handle_fasteoi_irq+0xac/0xd0 [14375.118932] [] handle_irq+0x1d/0x30 [14375.118934] [] do_IRQ+0x4c/0xc0 [14375.118937] [] ? irq_exit+0x39/0x70 [14375.118939] [] common_interrupt+0x30/0x40 [14375.119053] [] ? os_io_read_byte+0xc/0x10 [nvidia] [14375.119147] [] _nv000016rm+0x11/0x18 [nvidia] [14375.119255] [] ? _nv000217rm+0xe/0x21 [nvidia] [14375.119356] [] ? _nv000310rm+0x7c/0x9a [nvidia] [14375.119450] [] ? _nv004710rm+0x196/0x1d1 [nvidia] [14375.119558] [] ? _nv004034rm+0x93/0x2c3 [nvidia] [14375.119665] [] ? _nv002965rm+0x28a/0x4c0 [nvidia] [14375.119772] [] ? _nv003101rm+0x3a6/0x5d4 [nvidia] [14375.119879] [] ? _nv002960rm+0x4e/0x54 [nvidia] [14375.119985] [] ? _nv002971rm+0x1cc/0x1e4 [nvidia] [14375.120019] [] ? rm_power_management+0x1f6/0x286 [nvidia] [14375.120019] [] ? nv_power_management+0xef/0x200 [nvidia] [14375.120019] [] ? ktime_get+0x6f/0x110 [14375.120019] [] ? nv_kern_resume+0x14/0x20 [nvidia] [14375.120019] [] ? pci_legacy_resume+0x33/0x60 [14375.120019] [] ? pci_pm_resume+0x6f/0xa0 [14375.120019] [] ? pm_op+0xb6/0x180 [14375.120019] [] ? down+0x29/0x40 [14375.120019] [] ? may_link+0x4b/0x110 [14375.120019] [] ? device_resume+0x4c/0x150 [14375.120019] [] ? kobject_get+0x12/0x20 [14375.120019] [] ? dpm_resume+0xb1/0x140 [14375.120019] [] ? dpm_resume_end+0x17/0x30 [14375.120019] [] ? suspend_devices_and_enter+0x97/0xd0 [14375.120019] [] ? printk+0x1d/0x25 [14375.120019] [] ? enter_state+0xbd/0xf0 [14375.120019] [] ? state_store+0x75/0xc0 [14375.120019] [] ? state_store+0x0/0xc0 [14375.120019] [] ? kobj_attr_store+0x20/0x30 [14375.120019] [] ? sysfs_write_file+0x95/0x100 [14375.120019] [] ? vfs_write+0xa2/0x1a0 [14375.120019] [] ? sysfs_write_file+0x0/0x100 [14375.120019] [] ? do_page_fault+0x160/0x3a0 [14375.120019] [] ? sys_write+0x42/0x70 [14375.120019] [] ? syscall_call+0x7/0xb [14375.120019] [] ? do_debug+0x130/0x160 [14375.120019] handlers: [14375.120019] [] (usb_hcd_irq+0x0/0x80) [14375.120019] Disabling IRQ #18 [14375.411163] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1226.233 msecs [14375.548091] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 136.770 msecs [14375.606120] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [14375.612134] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [14375.612137] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [14375.900091] PM: resume of drv:usb dev:usb1 complete after 251.274 msecs [14376.152091] PM: resume of drv:usb dev:usb2 complete after 251.979 msecs [14376.400098] PM: resume of drv:usb dev:usb3 complete after 247.992 msecs [14376.648101] PM: resume of drv:usb dev:usb6 complete after 247.947 msecs [14376.650395] sd 2:0:0:0: [sda] Starting disk [14376.784076] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [14376.989148] PM: resume of drv:usb dev:3-2 complete after 314.594 msecs [14377.100080] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [14377.256154] PM: resume of drv:usb dev:6-2 complete after 266.985 msecs [14377.257179] thinkpad_acpi: ACPI backlight control delay disabled [14377.259657] PM: resume of devices complete after 3438.860 msecs [14377.259821] PM: resume devices took 3.436 seconds [14377.259856] PM: Finishing wakeup. [14377.259857] Restarting tasks ... done. [14377.491796] mmc0: new SDHC card at address 894a [14377.499458] mmcblk0: mmc0:894a SD04G 3.69 GiB [14377.499527] mmcblk0: p1 [14378.114244] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [14378.169160] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [14378.169452] ADDRCONF(NETDEV_UP): eth0: link is not ready [14378.391367] Registered led device: iwl-phy0::radio [14378.391768] Registered led device: iwl-phy0::assoc [14378.391988] Registered led device: iwl-phy0::RX [14378.392444] Registered led device: iwl-phy0::TX [14378.438960] ADDRCONF(NETDEV_UP): wlan0: link is not ready [14379.217113] usb 3-1: new full speed USB device using uhci_hcd and address 6 [14379.391252] usb 3-1: configuration #1 chosen from 1 choice [14385.721797] wlan0: deauthenticating from 02:c8:40:2a:76:50 by local choice (reason=3) [14385.760993] wlan0: direct probe to AP 02:c8:40:2a:76:50 (try 1) [14385.764187] wlan0: direct probe responded [14385.764190] wlan0: authenticate with AP 02:c8:40:2a:76:50 (try 1) [14385.766416] wlan0: authenticated [14385.766433] wlan0: associate with AP 02:c8:40:2a:76:50 (try 1) [14385.778966] wlan0: RX AssocResp from 02:c8:40:2a:76:50 (capab=0x1 status=0 aid=3) [14385.778969] wlan0: associated [14385.798767] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [14396.528089] wlan0: no IPv6 routers present [17181.360192] usb 3-1: USB disconnect, address 6 [17181.360496] btusb_intr_complete: hci0 urb ed103580 failed to resubmit (19) [17181.360520] btusb_bulk_complete: hci0 urb ed103080 failed to resubmit (19) [17181.361477] btusb_bulk_complete: hci0 urb f3bbef80 failed to resubmit (19) [17181.361908] btusb_send_frame: hci0 urb ec5efa00 submission failed [17182.269175] wlan0: deauthenticating from 02:c8:40:2a:76:50 by local choice (reason=3) [17186.169021] PM: Syncing filesystems ... done. [17186.373218] PM: Preparing system for mem sleep [17186.373261] mmc0: card 894a removed [17186.391831] Freezing user space processes ... (elapsed 0.00 seconds) done. [17186.393051] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [17186.393109] PM: Entering mem sleep [17186.393120] Suspending console(s) (use no_console_suspend to debug) [17186.605712] PM: suspend of drv:psmouse dev:serio2 complete after 212.231 msecs [17186.680170] sd 2:0:0:0: [sda] Synchronizing SCSI cache [17186.680257] sd 2:0:0:0: [sda] Stopping disk [17187.165150] PM: suspend of drv:sd dev:2:0:0:0 complete after 484.982 msecs [17187.548449] PM: suspend of drv:psmouse dev:serio1 complete after 383.234 msecs [17188.152131] PM: suspend of drv:atkbd dev:serio0 complete after 603.648 msecs [17188.201470] ACPI handle has no context! [17188.201482] sdhci-pci 0000:15:00.2: PCI INT C disabled [17188.201491] ACPI handle has no context! [17188.221248] ACPI handle has no context! [17189.816308] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 1564.139 msecs [17189.864425] ata_piix 0000:00:1f.1: PCI INT C disabled [17189.864462] ehci_hcd 0000:00:1d.7: PCI INT D disabled [17189.864481] uhci_hcd 0000:00:1d.2: PCI INT C disabled [17189.864501] uhci_hcd 0000:00:1d.1: PCI INT B disabled [17189.864521] uhci_hcd 0000:00:1d.0: PCI INT A disabled [17189.864538] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [17189.968403] HDA Intel 0000:00:1b.0: PCI INT B disabled [17189.984144] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.579 msecs [17189.984166] ehci_hcd 0000:00:1a.7: PCI INT C disabled [17189.984190] uhci_hcd 0000:00:1a.1: PCI INT B disabled [17189.984211] uhci_hcd 0000:00:1a.0: PCI INT A disabled [17189.985849] e1000e 0000:00:19.0: PCI INT A disabled [17189.985859] e1000e 0000:00:19.0: PME# enabled [17189.985867] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [17190.000497] PM: suspend of devices complete after 3607.055 msecs [17190.000500] PM: suspend devices took 3.608 seconds [17190.000995] ricoh-mmc: Suspending. [17190.001021] ricoh-mmc: Controller is now re-enabled. [17190.024079] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [17190.032067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [17190.040076] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [17190.064069] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [17190.092059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [17190.092258] PM: late suspend of devices complete after 91.753 msecs [17190.132067] ACPI: Preparing to enter system sleep state S3 [17190.272220] Disabling non-boot CPUs ... [17190.272236] CPU0 attaching NULL sched-domain. [17190.272239] CPU1 attaching NULL sched-domain. [17190.320018] CPU0 attaching NULL sched-domain. [17190.424029] CPU 1 is now offline [17190.424032] SMP alternatives: switching to UP code [17190.429731] Extended CMOS year: 2000 [17190.429731] Back to C! [17190.429731] CPU0: Thermal monitoring enabled (TM2) [17190.429731] Extended CMOS year: 2000 [17190.429731] Enabling non-boot CPUs ... [17190.429731] SMP alternatives: switching to SMP code [17190.435100] Booting processor 1 APIC 0x1 ip 0x6000 [17190.429681] Initializing CPU#1 [17190.429681] CPU: L1 I cache: 32K, L1 D cache: 32K [17190.429681] CPU: L2 cache: 4096K [17190.429681] CPU: Physical Processor ID: 0 [17190.429681] CPU: Processor Core ID: 1 [17190.429681] CPU1: Thermal monitoring enabled (TM2) [17190.524052] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b [17190.524114] CPU0 attaching NULL sched-domain. [17190.552022] CPU0 attaching sched-domain: [17190.552025] domain 0: span 0-1 level MC [17190.552027] groups: 0 1 [17190.552031] CPU1 attaching sched-domain: [17190.552033] domain 0: span 0-1 level MC [17190.552035] groups: 1 0 [17190.568166] CPU1 is up [17190.568731] ACPI: Waking up from system sleep state S3 [17190.901234] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [17190.901421] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [17190.945059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [17190.953067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [17190.953127] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [17190.961067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [17190.969067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [17190.969149] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [17190.977067] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [17190.985066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [17190.985167] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [17190.985298] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [17190.985454] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [17190.985617] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [17190.985775] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [17190.985930] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [17190.993067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [17191.001066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [17191.001128] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [17191.009065] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [17191.017064] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [17191.017131] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [17191.025067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [17191.033063] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [17191.033121] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [17191.041067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [17191.049067] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [17191.049154] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [17191.057067] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [17191.065066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [17191.065147] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [17191.065310] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [17191.065401] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [17191.065584] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [17191.065725] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [17191.080090] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [17191.080102] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [17191.096090] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [17191.096102] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [17191.096209] ricoh-mmc: Resuming. [17191.096235] ricoh-mmc: Controller is now disabled. [17191.096755] PM: early resume of devices complete after 195.656 msecs [17191.143364] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [17191.143371] e1000e 0000:00:19.0: setting latency timer to 64 [17191.143378] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [17191.143383] e1000e 0000:00:19.0: PME# disabled [17191.143447] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [17191.458087] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 314.742 msecs [17191.458097] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [17191.458112] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [17191.458141] usb usb3: root hub lost power or was reset [17191.458372] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [17191.458569] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [17191.458575] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [17191.458583] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [17191.458611] usb usb4: root hub lost power or was reset [17191.458821] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [17191.459015] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [17191.459025] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [17191.459040] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [17191.459072] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [17191.459080] HDA Intel 0000:00:1b.0: setting latency timer to 64 [17191.459115] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [17191.459325] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [17191.459516] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [17191.459521] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [17191.459529] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [17191.459552] usb usb5: root hub lost power or was reset [17191.459576] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [17191.459592] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [17191.459615] usb usb6: root hub lost power or was reset [17191.459831] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [17191.460074] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [17191.460080] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [17191.460088] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [17191.460121] usb usb7: root hub lost power or was reset [17191.460335] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [17191.460543] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [17191.460554] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [17191.460564] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [17191.460612] pci 0000:00:1e.0: setting latency timer to 64 [17191.460640] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [17191.460649] ata_piix 0000:00:1f.1: setting latency timer to 64 [17191.460696] ahci 0000:00:1f.2: setting latency timer to 64 [17191.461027] ata2: port disabled. ignoring. [17191.625488] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [17191.625492] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [17191.626581] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [17191.628089] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [17191.653423] ata1.00: configured for UDMA/33 [17191.781098] ata5: SATA link down (SStatus 0 SControl 300) [17191.949077] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [17191.960544] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [17191.960548] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [17191.960816] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [17191.960819] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [17191.964039] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [17191.964042] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [17191.964302] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [17191.964305] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [17191.965814] ata3.00: configured for UDMA/133 [17191.984366] ata3.00: configured for UDMA/133 [17191.984369] ata3: EH complete [17192.386280] irq 18: nobody cared (try booting with the "irqpoll" option) [17192.386284] Pid: 25865, comm: pm-suspend Tainted: P 2.6.32-31-generic #61-Ubuntu [17192.386286] Call Trace: [17192.386292] [] ? printk+0x1d/0x25 [17192.386297] [] __report_bad_irq+0x2c/0x90 [17192.386299] [] ? handle_IRQ_event+0x54/0x150 [17192.386301] [] note_interrupt+0x150/0x190 [17192.386304] [] handle_fasteoi_irq+0xac/0xd0 [17192.386307] [] handle_irq+0x1d/0x30 [17192.386310] [] do_IRQ+0x4c/0xc0 [17192.386312] [] common_interrupt+0x30/0x40 [17192.386426] [] ? os_io_read_byte+0xc/0x10 [nvidia] [17192.386520] [] _nv000016rm+0x11/0x18 [nvidia] [17192.386628] [] ? _nv000217rm+0xe/0x21 [nvidia] [17192.386729] [] ? _nv000310rm+0x7c/0x9a [nvidia] [17192.386823] [] ? _nv004710rm+0x196/0x1d1 [nvidia] [17192.386931] [] ? _nv004034rm+0x93/0x2c3 [nvidia] [17192.387037] [] ? _nv002965rm+0x28a/0x4c0 [nvidia] [17192.387144] [] ? _nv003101rm+0x3a6/0x5d4 [nvidia] [17192.387250] [] ? _nv002960rm+0x4e/0x54 [nvidia] [17192.387356] [] ? _nv002971rm+0x1cc/0x1e4 [nvidia] [17192.387463] [] ? rm_power_management+0x1f6/0x286 [nvidia] [17192.387554] [] ? nv_power_management+0xef/0x200 [nvidia] [17192.387645] [] ? nv_kern_resume+0x14/0x20 [nvidia] [17192.387648] [] ? pci_legacy_resume+0x33/0x60 [17192.387651] [] ? pci_pm_resume+0x6f/0xa0 [17192.387654] [] ? pm_op+0xb6/0x180 [17192.387658] [] ? down+0x29/0x40 [17192.387660] [] ? device_resume+0x4c/0x150 [17192.387663] [] ? kobject_get+0x12/0x20 [17192.387666] [] ? dpm_resume+0xb1/0x140 [17192.387668] [] ? dpm_resume_end+0x17/0x30 [17192.387672] [] ? suspend_devices_and_enter+0x97/0xd0 [17192.387674] [] ? printk+0x1d/0x25 [17192.387676] [] ? enter_state+0xbd/0xf0 [17192.387679] [] ? state_store+0x75/0xc0 [17192.387681] [] ? state_store+0x0/0xc0 [17192.387684] [] ? kobj_attr_store+0x20/0x30 [17192.387687] [] ? sysfs_write_file+0x95/0x100 [17192.387690] [] ? vfs_write+0xa2/0x1a0 [17192.387692] [] ? sysfs_write_file+0x0/0x100 [17192.387695] [] ? do_page_fault+0x160/0x3a0 [17192.387697] [] ? sys_write+0x42/0x70 [17192.387699] [] ? syscall_call+0x7/0xb [17192.387702] [] ? do_debug+0x130/0x160 [17192.387703] handlers: [17192.387704] [] (usb_hcd_irq+0x0/0x80) [17192.387708] Disabling IRQ #18 [17192.687113] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1226.275 msecs [17192.825147] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 137.867 msecs [17192.883160] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [17192.889207] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [17192.889210] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [17193.177148] PM: resume of drv:usb dev:usb1 complete after 251.258 msecs [17193.429149] PM: resume of drv:usb dev:usb2 complete after 251.973 msecs [17193.677151] PM: resume of drv:usb dev:usb3 complete after 247.984 msecs [17193.925150] PM: resume of drv:usb dev:usb6 complete after 247.916 msecs [17193.927825] sd 2:0:0:0: [sda] Starting disk [17194.060133] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [17194.265201] PM: resume of drv:usb dev:3-2 complete after 316.216 msecs [17194.376128] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [17194.532205] PM: resume of drv:usb dev:6-2 complete after 266.976 msecs [17194.533488] thinkpad_acpi: ACPI backlight control delay disabled [17194.536211] PM: resume of devices complete after 3439.413 msecs [17194.536376] PM: resume devices took 3.440 seconds [17194.536411] PM: Finishing wakeup. [17194.536412] Restarting tasks ... done. [17194.793102] mmc0: new SDHC card at address 894a [17194.797195] mmcblk0: mmc0:894a SD04G 3.69 GiB [17194.797271] mmcblk0: p1 [17195.497322] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [17195.553092] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [17195.553424] ADDRCONF(NETDEV_UP): eth0: link is not ready [17195.779373] Registered led device: iwl-phy0::radio [17195.780809] Registered led device: iwl-phy0::assoc [17195.782240] Registered led device: iwl-phy0::RX [17195.783651] Registered led device: iwl-phy0::TX [17195.813217] ADDRCONF(NETDEV_UP): wlan0: link is not ready [17196.481082] usb 3-1: new full speed USB device using uhci_hcd and address 7 [17196.655480] usb 3-1: configuration #1 chosen from 1 choice [17220.236185] usb 2-2: new high speed USB device using ehci_hcd and address 7 [17220.371644] usb 2-2: configuration #1 chosen from 1 choice [17220.375087] scsi15 : SCSI emulation for USB Mass Storage devices [17220.377630] usb-storage: device found at 7 [17220.377636] usb-storage: waiting for device to settle before scanning [17220.377708] scsi16 : SCSI emulation for USB Mass Storage devices [17220.378070] usb-storage: device found at 7 [17220.378075] usb-storage: waiting for device to settle before scanning [17220.909191] usb 2-2: USB disconnect, address 7 [17225.109173] usb 2-2: new high speed USB device using ehci_hcd and address 8 [17225.244753] usb 2-2: configuration #1 chosen from 1 choice [17225.247635] option 2-2:1.0: GSM modem (1-port) converter detected [17225.247852] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB0 [17225.248307] option 2-2:1.1: GSM modem (1-port) converter detected [17225.248489] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB1 [17225.248913] option 2-2:1.2: GSM modem (1-port) converter detected [17225.249087] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB2 [17225.249493] option 2-2:1.3: GSM modem (1-port) converter detected [17225.249635] usb 2-2: GSM modem (1-port) converter now attached to ttyUSB3 [17225.253759] scsi21 : SCSI emulation for USB Mass Storage devices [17225.254347] usb-storage: device found at 8 [17225.254353] usb-storage: waiting for device to settle before scanning [17225.255116] scsi22 : SCSI emulation for USB Mass Storage devices [17225.255492] usb-storage: device found at 8 [17225.255497] usb-storage: waiting for device to settle before scanning [17230.253533] usb-storage: device scan complete [17230.253727] usb-storage: device scan complete [17230.255104] scsi 21:0:0:0: CD-ROM HUAWEI Mass Storage 2.31 PQ: 0 ANSI: 2 [17230.255458] scsi 22:0:0:0: Direct-Access HUAWEI SD Storage 2.31 PQ: 0 ANSI: 2 [17230.263184] sr1: scsi-1 drive [17230.263460] sr 21:0:0:0: Attached scsi CD-ROM sr1 [17230.263633] sr 21:0:0:0: Attached scsi generic sg2 type 5 [17230.266910] sd 22:0:0:0: Attached scsi generic sg3 type 0 [17230.272911] sd 22:0:0:0: [sdb] Attached SCSI removable disk [17242.787017] ISO 9660 Extensions: Microsoft Joliet Level 1 [17242.788282] ISOFS: changing to secondary root [17715.637267] usb 2-2: USB disconnect, address 8 [17715.637582] option: option_instat_callback: error -108 [17715.638022] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 [17715.638058] option 2-2:1.0: device disconnected [17715.638325] option: option_instat_callback: error -108 [17715.638485] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 [17715.638529] option 2-2:1.1: device disconnected [17715.638996] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2 [17715.639115] option 2-2:1.2: device disconnected [17715.643894] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3 [17715.643953] option 2-2:1.3: device disconnected [19496.723934] cfg80211: Found new beacon on frequency: 2467 MHz (Ch 12) on phy0 [20516.996787] cfg80211: Found new beacon on frequency: 2472 MHz (Ch 13) on phy0 [26975.625375] usb 3-1: USB disconnect, address 7 [26975.625439] btusb_bulk_complete: hci0 urb f3bbaa80 failed to resubmit (19) [26975.626529] btusb_intr_complete: hci0 urb ed05ef00 failed to resubmit (19) [26975.626562] btusb_bulk_complete: hci0 urb f3bba500 failed to resubmit (19) [26975.626885] btusb_send_frame: hci0 urb f7098080 submission failed [26981.620628] PM: Syncing filesystems ... done. [26981.807693] PM: Preparing system for mem sleep [26981.807729] mmc0: card 894a removed [26981.841640] Freezing user space processes ... (elapsed 0.00 seconds) done. [26981.843985] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [26981.844121] PM: Entering mem sleep [26981.844144] Suspending console(s) (use no_console_suspend to debug) [26982.072345] PM: suspend of drv:psmouse dev:serio2 complete after 227.410 msecs [26982.152394] sd 2:0:0:0: [sda] Synchronizing SCSI cache [26982.152554] sd 2:0:0:0: [sda] Stopping disk [26982.638889] PM: suspend of drv:sd dev:2:0:0:0 complete after 486.502 msecs [26983.028489] PM: suspend of drv:psmouse dev:serio1 complete after 389.456 msecs [26983.632305] PM: suspend of drv:atkbd dev:serio0 complete after 603.756 msecs [26983.681790] ACPI handle has no context! [26983.681812] sdhci-pci 0000:15:00.2: PCI INT C disabled [26983.681831] ACPI handle has no context! [26983.701320] ACPI handle has no context! [26986.243585] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 2511.391 msecs [26986.288580] ata_piix 0000:00:1f.1: PCI INT C disabled [26986.288631] ehci_hcd 0000:00:1d.7: PCI INT D disabled [26986.288660] uhci_hcd 0000:00:1d.2: PCI INT C disabled [26986.288687] uhci_hcd 0000:00:1d.1: PCI INT B disabled [26986.288713] uhci_hcd 0000:00:1d.0: PCI INT A disabled [26986.288741] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [26986.392575] HDA Intel 0000:00:1b.0: PCI INT B disabled [26986.408217] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.427 msecs [26986.408244] ehci_hcd 0000:00:1a.7: PCI INT C disabled [26986.408271] uhci_hcd 0000:00:1a.1: PCI INT B disabled [26986.408299] uhci_hcd 0000:00:1a.0: PCI INT A disabled [26986.409985] e1000e 0000:00:19.0: PCI INT A disabled [26986.410002] e1000e 0000:00:19.0: PME# enabled [26986.410022] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [26986.425941] PM: suspend of devices complete after 4581.099 msecs [26986.425948] PM: suspend devices took 4.580 seconds [26986.426780] ricoh-mmc: Suspending. [26986.426824] ricoh-mmc: Controller is now re-enabled. [26986.449178] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [26986.457159] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [26986.465157] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [26986.489160] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [26986.517141] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [26986.517461] PM: late suspend of devices complete after 91.502 msecs [26986.557143] ACPI: Preparing to enter system sleep state S3 [26986.697458] Disabling non-boot CPUs ... [26986.697485] CPU0 attaching NULL sched-domain. [26986.697493] CPU1 attaching NULL sched-domain. [26986.745037] CPU0 attaching NULL sched-domain. [26986.848056] CPU 1 is now offline [26986.848061] SMP alternatives: switching to UP code [26986.863927] Extended CMOS year: 2000 [26986.863927] Back to C! [26986.863927] CPU0: Thermal monitoring enabled (TM2) [26986.863927] Extended CMOS year: 2000 [26986.863927] Enabling non-boot CPUs ... [26986.864226] SMP alternatives: switching to SMP code [26986.869380] Booting processor 1 APIC 0x1 ip 0x6000 [26986.863927] Initializing CPU#1 [26986.863927] CPU: L1 I cache: 32K, L1 D cache: 32K [26986.863927] CPU: L2 cache: 4096K [26986.863927] CPU: Physical Processor ID: 0 [26986.863927] CPU: Processor Core ID: 1 [26986.863927] CPU1: Thermal monitoring enabled (TM2) [26986.960097] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b [26986.960153] CPU0 attaching NULL sched-domain. [26986.988122] CPU0 attaching sched-domain: [26986.988124] domain 0: span 0-1 level MC [26986.988126] groups: 0 1 [26986.988130] CPU1 attaching sched-domain: [26986.988132] domain 0: span 0-1 level MC [26986.988134] groups: 1 0 [26987.020162] CPU1 is up [26987.020728] ACPI: Waking up from system sleep state S3 [26987.397236] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26987.397417] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [26987.441059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [26987.449066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [26987.449120] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [26987.457066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [26987.465066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [26987.465127] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [26987.473066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [26987.481066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [26987.481161] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [26987.481270] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26987.481411] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26987.481559] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26987.481705] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26987.481860] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [26987.489066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [26987.497066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [26987.497121] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [26987.505066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [26987.513066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [26987.513121] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [26987.521066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [26987.529066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [26987.529120] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [26987.537066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [26987.545065] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [26987.545142] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [26987.553066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [26987.561065] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [26987.561129] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [26987.561274] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [26987.561350] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [26987.561522] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [26987.561674] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [26987.577079] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [26987.577093] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [26987.593080] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [26987.593092] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [26987.593203] ricoh-mmc: Resuming. [26987.593232] ricoh-mmc: Controller is now disabled. [26987.593739] PM: early resume of devices complete after 196.634 msecs [26987.639094] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [26987.639100] e1000e 0000:00:19.0: setting latency timer to 64 [26987.639107] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [26987.639114] e1000e 0000:00:19.0: PME# disabled [26987.639189] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [26987.954104] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 315.039 msecs [26987.954114] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [26987.954121] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [26987.954148] usb usb3: root hub lost power or was reset [26987.954374] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [26987.954573] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [26987.954578] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [26987.954588] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [26987.954613] usb usb4: root hub lost power or was reset [26987.954818] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [26987.955009] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [26987.955017] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [26987.955025] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [26987.955064] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [26987.955074] HDA Intel 0000:00:1b.0: setting latency timer to 64 [26987.955118] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [26987.955322] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [26987.955498] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [26987.955504] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [26987.955510] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [26987.955534] usb usb5: root hub lost power or was reset [26987.955559] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [26987.955578] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [26987.955603] usb usb6: root hub lost power or was reset [26987.955833] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [26987.956071] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [26987.956079] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [26987.956087] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [26987.956121] usb usb7: root hub lost power or was reset [26987.956342] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [26987.956574] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [26987.956586] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [26987.956596] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [26987.956653] pci 0000:00:1e.0: setting latency timer to 64 [26987.956674] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [26987.956683] ata_piix 0000:00:1f.1: setting latency timer to 64 [26987.956729] ahci 0000:00:1f.2: setting latency timer to 64 [26987.957047] ata2: port disabled. ignoring. [26988.121141] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [26988.121148] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [26988.122894] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [26988.124384] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [26988.149216] ata1.00: configured for UDMA/33 [26988.276115] ata5: SATA link down (SStatus 0 SControl 300) [26988.388102] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [26988.400510] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [26988.400517] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [26988.400819] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [26988.400822] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [26988.404146] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [26988.404155] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [26988.404409] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [26988.404416] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [26988.405924] ata3.00: configured for UDMA/133 [26988.423364] ata3.00: configured for UDMA/133 [26988.423371] ata3: EH complete [26989.284072] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1327.197 msecs [26989.424041] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 139.793 msecs [26989.482084] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [26989.488230] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [26989.488235] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [26989.776074] PM: resume of drv:usb dev:usb1 complete after 251.325 msecs [26990.028080] PM: resume of drv:usb dev:usb2 complete after 251.978 msecs [26990.276078] PM: resume of drv:usb dev:usb3 complete after 247.983 msecs [26990.524048] PM: resume of drv:usb dev:usb6 complete after 247.913 msecs [26990.526845] sd 2:0:0:0: [sda] Starting disk [26990.656065] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [26990.805136] PM: resume of drv:usb dev:3-2 complete after 260.685 msecs [26990.972062] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [26991.128123] PM: resume of drv:usb dev:6-2 complete after 322.967 msecs [26991.129109] thinkpad_acpi: ACPI backlight control delay disabled [26991.131565] PM: resume of devices complete after 3537.786 msecs [26991.131731] PM: resume devices took 3.536 seconds [26991.131755] PM: Finishing wakeup. [26991.131756] Restarting tasks ... done. [26991.378776] mmc0: new SDHC card at address 894a [26991.381517] mmcblk0: mmc0:894a SD04G 3.69 GiB [26991.381585] mmcblk0: p1 [26992.003407] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [26992.057960] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [26992.058253] ADDRCONF(NETDEV_UP): eth0: link is not ready [26992.279850] Registered led device: iwl-phy0::radio [26992.280285] Registered led device: iwl-phy0::assoc [26992.280502] Registered led device: iwl-phy0::RX [26992.280705] Registered led device: iwl-phy0::TX [26992.320302] ADDRCONF(NETDEV_UP): wlan0: link is not ready [26992.977148] usb 3-1: new full speed USB device using uhci_hcd and address 8 [26993.900263] usb 3-1: configuration #1 chosen from 1 choice [26999.631199] wlan0: deauthenticating from 02:c8:40:2a:6f:00 by local choice (reason=3) [26999.673085] wlan0: direct probe to AP 02:c8:40:2a:6f:00 (try 1) [26999.676260] wlan0: direct probe responded [26999.676263] wlan0: authenticate with AP 02:c8:40:2a:6f:00 (try 1) [26999.678839] wlan0: authenticated [26999.678857] wlan0: associate with AP 02:c8:40:2a:6f:00 (try 1) [26999.688889] wlan0: RX AssocResp from 02:c8:40:2a:6f:00 (capab=0x1 status=0 aid=17) [26999.688892] wlan0: associated [26999.709197] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [27010.669112] wlan0: no IPv6 routers present [27117.308125] usb 2-1: new high speed USB device using ehci_hcd and address 9 [27117.442636] usb 2-1: configuration #1 chosen from 1 choice [27117.443233] scsi23 : SCSI emulation for USB Mass Storage devices [27117.443607] usb-storage: device found at 9 [27117.443613] usb-storage: waiting for device to settle before scanning [27122.441582] usb-storage: device scan complete [27122.443974] scsi 23:0:0:0: Direct-Access TOSHIBA MK1652GSX 0002 PQ: 0 ANSI: 0 [27122.449910] sd 23:0:0:0: Attached scsi generic sg2 type 0 [27122.455272] sd 23:0:0:0: [sdb] 312581808 512-byte logical blocks: (160 GB/149 GiB) [27122.456531] sd 23:0:0:0: [sdb] Write Protect is off [27122.456539] sd 23:0:0:0: [sdb] Mode Sense: 03 00 00 00 [27122.456545] sd 23:0:0:0: [sdb] Assuming drive cache: write through [27122.458535] sd 23:0:0:0: [sdb] Assuming drive cache: write through [27122.458546] sdb: sdb1 [27122.474782] sd 23:0:0:0: [sdb] Assuming drive cache: write through [27122.474793] sd 23:0:0:0: [sdb] Attached SCSI disk [27296.185187] wlan0: deauthenticating from 02:c8:40:2a:6f:00 by local choice (reason=3) [27296.199987] wlan0: direct probe to AP 02:c8:40:2a:6f:00 (try 1) [27296.200057] wlan0: deauthenticating from 02:c8:40:2a:6f:00 by local choice (reason=3) [27296.207683] wlan0: direct probe to AP 02:c8:40:2a:6d:e0 (try 1) [27296.224158] wlan0: direct probe responded [27296.224167] wlan0: authenticate with AP 02:c8:40:2a:6d:e0 (try 1) [27296.227748] wlan0: authenticated [27296.227785] wlan0: associate with AP 02:c8:40:2a:6d:e0 (try 1) [27296.247684] wlan0: RX AssocResp from 02:c8:40:2a:6d:e0 (capab=0x1 status=0 aid=34) [27296.247687] wlan0: associated [27367.464103] usb 3-1: USB disconnect, address 8 [27367.464163] btusb_bulk_complete: hci0 urb f311c300 failed to resubmit (19) [27367.465155] btusb_intr_complete: hci0 urb df674980 failed to resubmit (19) [27367.465178] btusb_bulk_complete: hci0 urb df674300 failed to resubmit (19) [27367.465576] btusb_send_frame: hci0 urb f1a64280 submission failed [27368.353170] wlan0: deauthenticating from 02:c8:40:2a:6d:e0 by local choice (reason=3) [27372.577660] PM: Syncing filesystems ... done. [27372.719224] PM: Preparing system for mem sleep [27372.719248] mmc0: card 894a removed [27372.745699] Freezing user space processes ... (elapsed 0.00 seconds) done. [27372.746698] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [27372.746765] PM: Entering mem sleep [27372.746777] Suspending console(s) (use no_console_suspend to debug) [27372.972044] PM: suspend of drv:psmouse dev:serio2 complete after 210.897 msecs [27373.048113] sd 2:0:0:0: [sda] Synchronizing SCSI cache [27373.048195] sd 2:0:0:0: [sda] Stopping disk [27373.527679] PM: suspend of drv:sd dev:2:0:0:0 complete after 479.568 msecs [27373.907719] PM: suspend of drv:psmouse dev:serio1 complete after 379.988 msecs [27374.512083] PM: suspend of drv:atkbd dev:serio0 complete after 604.338 msecs [27374.561394] ACPI handle has no context! [27374.561407] sdhci-pci 0000:15:00.2: PCI INT C disabled [27374.561417] ACPI handle has no context! [27374.581194] ACPI handle has no context! [27376.998470] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 2386.361 msecs [27377.044318] ata_piix 0000:00:1f.1: PCI INT C disabled [27377.044346] ehci_hcd 0000:00:1d.7: PCI INT D disabled [27377.044365] uhci_hcd 0000:00:1d.2: PCI INT C disabled [27377.044389] uhci_hcd 0000:00:1d.1: PCI INT B disabled [27377.044409] uhci_hcd 0000:00:1d.0: PCI INT A disabled [27377.044423] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [27377.148347] HDA Intel 0000:00:1b.0: PCI INT B disabled [27377.164083] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.629 msecs [27377.164100] ehci_hcd 0000:00:1a.7: PCI INT C disabled [27377.164117] uhci_hcd 0000:00:1a.1: PCI INT B disabled [27377.164134] uhci_hcd 0000:00:1a.0: PCI INT A disabled [27377.165750] e1000e 0000:00:19.0: PCI INT A disabled [27377.165761] e1000e 0000:00:19.0: PME# enabled [27377.165768] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [27377.180360] PM: suspend of devices complete after 4433.255 msecs [27377.180363] PM: suspend devices took 4.436 seconds [27377.180876] ricoh-mmc: Suspending. [27377.180902] ricoh-mmc: Controller is now re-enabled. [27377.204078] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [27377.212078] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [27377.220068] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [27377.244068] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [27377.272059] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [27377.272254] PM: late suspend of devices complete after 91.886 msecs [27377.312062] ACPI: Preparing to enter system sleep state S3 [27377.452217] Disabling non-boot CPUs ... [27377.452233] CPU0 attaching NULL sched-domain. [27377.452236] CPU1 attaching NULL sched-domain. [27377.500020] CPU0 attaching NULL sched-domain. [27377.604028] CPU 1 is now offline [27377.604030] SMP alternatives: switching to UP code [27377.609679] Extended CMOS year: 2000 [27377.609679] Back to C! [27377.609679] CPU0: Thermal monitoring enabled (TM2) [27377.609679] Extended CMOS year: 2000 [27377.609679] Enabling non-boot CPUs ... [27377.609679] SMP alternatives: switching to SMP code [27377.615052] Booting processor 1 APIC 0x1 ip 0x6000 [27377.609630] Initializing CPU#1 [27377.609630] CPU: L1 I cache: 32K, L1 D cache: 32K [27377.609630] CPU: L2 cache: 4096K [27377.609630] CPU: Physical Processor ID: 0 [27377.609630] CPU: Processor Core ID: 1 [27377.609630] CPU1: Thermal monitoring enabled (TM2) [27377.704097] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b [27377.704150] CPU0 attaching NULL sched-domain. [27377.732023] CPU0 attaching sched-domain: [27377.732026] domain 0: span 0-1 level MC [27377.732028] groups: 0 1 [27377.732032] CPU1 attaching sched-domain: [27377.732034] domain 0: span 0-1 level MC [27377.732036] groups: 1 0 [27377.764166] CPU1 is up [27377.764733] ACPI: Waking up from system sleep state S3 [27378.097234] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27378.097413] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [27378.141058] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [27378.149066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [27378.149125] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [27378.157066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [27378.165066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [27378.165140] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [27378.173066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [27378.181066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [27378.181155] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [27378.181272] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27378.181424] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27378.181579] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27378.181729] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27378.181878] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27378.189066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27378.197066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27378.197124] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [27378.205066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27378.213066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27378.213127] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [27378.221066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [27378.229066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [27378.229124] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [27378.237066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [27378.245065] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [27378.245141] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [27378.253066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [27378.261067] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [27378.261142] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [27378.261300] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [27378.261382] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [27378.261556] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [27378.261704] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [27378.277084] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [27378.277096] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [27378.293080] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [27378.293092] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [27378.293197] ricoh-mmc: Resuming. [27378.293227] ricoh-mmc: Controller is now disabled. [27378.293750] PM: early resume of devices complete after 196.647 msecs [27378.340109] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [27378.340118] e1000e 0000:00:19.0: setting latency timer to 64 [27378.340125] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [27378.340140] e1000e 0000:00:19.0: PME# disabled [27378.340191] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [27378.654118] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 314.036 msecs [27378.654126] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [27378.654134] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [27378.654158] usb usb3: root hub lost power or was reset [27378.654369] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [27378.654564] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [27378.654570] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [27378.654578] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [27378.654600] usb usb4: root hub lost power or was reset [27378.654807] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [27378.654996] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [27378.655005] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [27378.655013] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [27378.655053] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [27378.655062] HDA Intel 0000:00:1b.0: setting latency timer to 64 [27378.655104] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [27378.655299] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27378.655488] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27378.655494] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [27378.655501] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [27378.655528] usb usb5: root hub lost power or was reset [27378.655554] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [27378.655570] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [27378.655594] usb usb6: root hub lost power or was reset [27378.655798] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [27378.655968] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [27378.655974] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [27378.655982] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [27378.656014] usb usb7: root hub lost power or was reset [27378.656257] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [27378.656458] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [27378.656467] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [27378.656477] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [27378.656519] pci 0000:00:1e.0: setting latency timer to 64 [27378.656542] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [27378.656550] ata_piix 0000:00:1f.1: setting latency timer to 64 [27378.656591] ahci 0000:00:1f.2: setting latency timer to 64 [27378.656906] ata2: port disabled. ignoring. [27378.821106] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [27378.821113] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [27378.822883] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [27378.824412] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [27378.848902] ata1.00: configured for UDMA/33 [27378.976129] ata5: SATA link down (SStatus 0 SControl 300) [27379.144118] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [27379.148308] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [27379.148315] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [27379.148589] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [27379.148597] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [27379.151954] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [27379.151960] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [27379.152225] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [27379.152232] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [27379.153729] ata3.00: configured for UDMA/133 [27379.171339] ata3.00: configured for UDMA/133 [27379.171345] ata3: EH complete [27379.984472] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1327.730 msecs [27380.124051] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 139.401 msecs [27380.182086] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [27380.188235] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [27380.188240] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [27380.476082] PM: resume of drv:usb dev:usb1 complete after 251.299 msecs [27380.728082] PM: resume of drv:usb dev:usb2 complete after 251.980 msecs [27380.976068] PM: resume of drv:usb dev:usb3 complete after 247.967 msecs [27381.224050] PM: resume of drv:usb dev:usb6 complete after 247.928 msecs [27381.226418] sd 2:0:0:0: [sda] Starting disk [27381.356031] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [27381.561124] PM: resume of drv:usb dev:3-2 complete after 313.329 msecs [27381.672059] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [27381.832112] PM: resume of drv:usb dev:6-2 complete after 270.967 msecs [27381.833101] thinkpad_acpi: ACPI backlight control delay disabled [27381.944105] usb 2-1: reset high speed USB device using ehci_hcd and address 9 [27382.077809] PM: resume of drv:usb dev:2-1 complete after 242.243 msecs [27382.077867] PM: resume of devices complete after 3784.076 msecs [27382.078049] PM: resume devices took 3.784 seconds [27382.078073] PM: Finishing wakeup. [27382.078075] Restarting tasks ... done. [27382.341470] mmc0: new SDHC card at address 894a [27382.343189] mmcblk0: mmc0:894a SD04G 3.69 GiB [27382.343252] mmcblk0: p1 [27382.730474] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [27382.785914] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [27382.786211] ADDRCONF(NETDEV_UP): eth0: link is not ready [27383.019994] Registered led device: iwl-phy0::radio [27383.020745] Registered led device: iwl-phy0::assoc [27383.021252] Registered led device: iwl-phy0::RX [27383.021749] Registered led device: iwl-phy0::TX [27383.064289] ADDRCONF(NETDEV_UP): wlan0: link is not ready [27384.600474] usb 3-1: new full speed USB device using uhci_hcd and address 9 [27384.775374] usb 3-1: configuration #1 chosen from 1 choice [27390.400917] wlan0: deauthenticating from 02:c8:40:2a:6d:e0 by local choice (reason=3) [27390.449340] wlan0: direct probe to AP 02:c8:40:2a:6f:00 (try 1) [27390.452226] wlan0: direct probe responded [27390.452230] wlan0: authenticate with AP 02:c8:40:2a:6f:00 (try 1) [27390.454602] wlan0: authenticated [27390.454619] wlan0: associate with AP 02:c8:40:2a:6f:00 (try 1) [27390.462068] wlan0: RX AssocResp from 02:c8:40:2a:6f:00 (capab=0x1 status=0 aid=17) [27390.462072] wlan0: associated [27390.482469] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [27400.920074] wlan0: no IPv6 routers present [37744.036164] usb 2-1: USB disconnect, address 9 [37749.488214] usb 3-1: USB disconnect, address 9 [37749.488413] btusb_bulk_complete: hci0 urb f30e8900 failed to resubmit (19) [37749.489431] btusb_intr_complete: hci0 urb f30e8680 failed to resubmit (19) [37749.489451] btusb_bulk_complete: hci0 urb f30e8500 failed to resubmit (19) [37749.489504] btusb_send_frame: hci0 urb ec6d6580 submission failed [37750.468126] wlan0: deauthenticating from 02:c8:40:2a:6f:00 by local choice (reason=3) [37754.810239] PM: Syncing filesystems ... done. [37754.976438] PM: Preparing system for mem sleep [37754.976462] mmc0: card 894a removed [37754.991325] Freezing user space processes ... (elapsed 0.00 seconds) done. [37754.992557] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [37754.992609] PM: Entering mem sleep [37754.992621] Suspending console(s) (use no_console_suspend to debug) [37755.215175] PM: suspend of drv:psmouse dev:serio2 complete after 222.183 msecs [37755.292110] sd 2:0:0:0: [sda] Synchronizing SCSI cache [37755.292196] sd 2:0:0:0: [sda] Stopping disk [37755.779656] PM: suspend of drv:sd dev:2:0:0:0 complete after 487.547 msecs [37756.164725] PM: suspend of drv:psmouse dev:serio1 complete after 385.010 msecs [37756.768081] PM: suspend of drv:atkbd dev:serio0 complete after 603.334 msecs [37756.817395] ACPI handle has no context! [37756.817407] sdhci-pci 0000:15:00.2: PCI INT C disabled [37756.817420] ACPI handle has no context! [37756.837192] ACPI handle has no context! [37759.253777] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 2385.666 msecs [37759.300327] ata_piix 0000:00:1f.1: PCI INT C disabled [37759.300354] ehci_hcd 0000:00:1d.7: PCI INT D disabled [37759.300375] uhci_hcd 0000:00:1d.2: PCI INT C disabled [37759.300397] uhci_hcd 0000:00:1d.1: PCI INT B disabled [37759.300415] uhci_hcd 0000:00:1d.0: PCI INT A disabled [37759.300432] pciehp 0000:00:1c.3:pcie04: pciehp_suspend ENTRY [37759.404801] HDA Intel 0000:00:1b.0: PCI INT B disabled [37759.420095] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.632 msecs [37759.420106] ehci_hcd 0000:00:1a.7: PCI INT C disabled [37759.420125] uhci_hcd 0000:00:1a.1: PCI INT B disabled [37759.420143] uhci_hcd 0000:00:1a.0: PCI INT A disabled [37759.421755] e1000e 0000:00:19.0: PCI INT A disabled [37759.421765] e1000e 0000:00:19.0: PME# enabled [37759.421772] e1000e 0000:00:19.0: wake-up capability enabled by ACPI [37759.436391] PM: suspend of devices complete after 4443.446 msecs [37759.436394] PM: suspend devices took 4.448 seconds [37759.436889] ricoh-mmc: Suspending. [37759.436915] ricoh-mmc: Controller is now re-enabled. [37759.461080] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [37759.469068] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D3 [37759.477067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [37759.501068] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D3 [37759.529058] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D3 [37759.529253] PM: late suspend of devices complete after 92.853 msecs [37759.569064] ACPI: Preparing to enter system sleep state S3 [37759.709225] Disabling non-boot CPUs ... [37759.709239] CPU0 attaching NULL sched-domain. [37759.709242] CPU1 attaching NULL sched-domain. [37759.757017] CPU0 attaching NULL sched-domain. [37759.860029] CPU 1 is now offline [37759.860031] SMP alternatives: switching to UP code [37759.865734] Extended CMOS year: 2000 [37759.865734] Back to C! [37759.865734] CPU0: Thermal monitoring enabled (TM2) [37759.865734] Extended CMOS year: 2000 [37759.865734] Enabling non-boot CPUs ... [37759.865734] SMP alternatives: switching to SMP code [37759.871126] Booting processor 1 APIC 0x1 ip 0x6000 [37759.865734] Initializing CPU#1 [37759.865734] CPU: L1 I cache: 32K, L1 D cache: 32K [37759.865734] CPU: L2 cache: 4096K [37759.865734] CPU: Physical Processor ID: 0 [37759.865734] CPU: Processor Core ID: 1 [37759.865734] CPU1: Thermal monitoring enabled (TM2) [37759.960080] CPU1: Intel(R) Core(TM)2 Duo CPU T7500 @ 2.20GHz stepping 0b [37759.960139] CPU0 attaching NULL sched-domain. [37759.988022] CPU0 attaching sched-domain: [37759.988025] domain 0: span 0-1 level MC [37759.988027] groups: 0 1 [37759.988031] CPU1 attaching sched-domain: [37759.988032] domain 0: span 0-1 level MC [37759.988034] groups: 1 0 [37760.020163] CPU1 is up [37760.020729] ACPI: Waking up from system sleep state S3 [37760.205146] ACPI: \_SB_.GDCK - docking [37762.472543] ACPI Exception: AE_TIME, Returned by Handler for [EmbeddedControl] (20090903/evregion-424) [37762.472717] ACPI Error (psparse-0537): Method parse/execution failed [\_SB_.PCI0.LPC_.EC__.LED_] (Node f70175d0), AE_TIME [37762.472754] ACPI Error (psparse-0537): Method parse/execution failed [\_SI_._SST] (Node f701f738), AE_TIME [37762.472803] ACPI Exception: AE_TIME, During Method _SST (20090903/hwsleep-629) [37762.472947] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [37762.473176] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [37762.537060] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [37762.545067] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [37762.545125] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [37762.553066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [37762.561066] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [37762.561142] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [37762.569066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [37762.577066] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [37762.577159] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100102) [37762.577289] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [37762.577440] pcieport 0000:00:1c.1: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [37762.577593] pcieport 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [37762.577749] pcieport 0000:00:1c.3: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [37762.577905] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [37762.585067] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [37762.593066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [37762.593116] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [37762.601066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [37762.609066] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [37762.609123] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [37762.617066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [37762.625066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [37762.625126] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800005, writing 0x2800001) [37762.633066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [37762.641066] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [37762.641156] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900106, writing 0x2900102) [37762.649066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [37762.657066] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [37762.657143] pci 0000:00:1e.0: restoring config space at offset 0x1 (was 0x100005, writing 0x100007) [37762.657311] ata_piix 0000:00:1f.1: restoring config space at offset 0x1 (was 0x2800005, writing 0x2880005) [37762.657411] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [37762.657585] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x10, writing 0x0) [37762.657734] iwlagn 0000:03:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [37762.673075] ohci1394 0000:15:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [37762.673089] ohci1394 0000:15:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [37762.689090] sdhci-pci 0000:15:00.2: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [37762.689104] sdhci-pci 0000:15:00.2: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100006) [37762.689223] ricoh-mmc: Resuming. [37762.689251] ricoh-mmc: Controller is now disabled. [37762.689770] PM: early resume of devices complete after 216.961 msecs [37762.689772] acpi PNP0501:00: parent device:00 should not be sleeping [37762.690995] acpi PNP0400:00: parent device:00 should not be sleeping [37762.735323] e1000e 0000:00:19.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [37762.735334] e1000e 0000:00:19.0: setting latency timer to 64 [37762.735341] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [37762.735349] e1000e 0000:00:19.0: PME# disabled [37762.735425] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [37763.051088] PM: resume of drv:e1000e dev:0000:00:19.0 complete after 315.786 msecs [37763.051097] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [37763.051106] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [37763.051135] usb usb3: root hub lost power or was reset [37763.051349] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [37763.051565] uhci_hcd 0000:00:1a.1: power state changed by ACPI to D0 [37763.051570] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [37763.051578] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [37763.051606] usb usb4: root hub lost power or was reset [37763.051812] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [37763.051990] ehci_hcd 0000:00:1a.7: power state changed by ACPI to D0 [37763.051999] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 22 (level, low) -> IRQ 22 [37763.052024] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [37763.052063] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [37763.052078] HDA Intel 0000:00:1b.0: setting latency timer to 64 [37763.052129] pciehp 0000:00:1c.3:pcie04: pciehp_resume ENTRY [37763.052316] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [37763.052488] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [37763.052494] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [37763.052501] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [37763.052539] usb usb5: root hub lost power or was reset [37763.052565] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [37763.052584] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [37763.052610] usb usb6: root hub lost power or was reset [37763.052847] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [37763.053062] uhci_hcd 0000:00:1d.2: power state changed by ACPI to D0 [37763.053068] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [37763.053076] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [37763.053113] usb usb7: root hub lost power or was reset [37763.053339] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [37763.053555] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D0 [37763.053563] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [37763.053573] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [37763.053613] pci 0000:00:1e.0: setting latency timer to 64 [37763.053641] ata_piix 0000:00:1f.1: PCI INT C -> GSI 16 (level, low) -> IRQ 16 [37763.053649] ata_piix 0000:00:1f.1: setting latency timer to 64 [37763.053691] ahci 0000:00:1f.2: setting latency timer to 64 [37763.054010] ata2: port disabled. ignoring. [37763.217084] ata1.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [37763.217091] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [37763.217593] ata1.00: ACPI cmd e3/00:1f:00:00:00:a0 (IDLE) succeeded [37763.218595] ata1.00: ACPI cmd e3/00:02:00:00:00:a0 (IDLE) succeeded [37763.240872] ata1.00: configured for UDMA/33 [37763.372127] ata5: SATA link down (SStatus 0 SControl 300) [37763.372246] ata3: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [37763.382713] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [37763.382724] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [37763.383009] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [37763.383012] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [37763.386358] ata3.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [37763.386365] ata3.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [37763.386618] ata3.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [37763.386626] ata3.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [37763.388128] ata3.00: configured for UDMA/133 [37763.407422] ata3.00: configured for UDMA/133 [37763.407430] ata3: EH complete [37764.381109] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 1327.275 msecs [37764.520067] PM: resume of drv:yenta_cardbus dev:0000:15:00.0 complete after 138.779 msecs [37764.578077] ohci1394: fw-host0: OHCI-1394 1.1 (PCI): IRQ=[17] MMIO=[f8101000-f81017ff] Max Packet=[2048] IR/IT contexts=[4/4] [37764.584223] sdhci-pci 0000:15:00.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [37764.584228] sdhci-pci 0000:15:00.2: Will use DMA mode even though HW doesn't fully claim to support it. [37764.872087] PM: resume of drv:usb dev:usb1 complete after 251.335 msecs [37765.124087] PM: resume of drv:usb dev:usb2 complete after 251.983 msecs [37765.372077] PM: resume of drv:usb dev:usb3 complete after 247.971 msecs [37765.620050] PM: resume of drv:usb dev:usb6 complete after 247.922 msecs [37765.622358] sd 2:0:0:0: [sda] Starting disk [37765.760059] usb 3-2: reset full speed USB device using uhci_hcd and address 3 [37765.909137] PM: resume of drv:usb dev:3-2 complete after 260.260 msecs [37766.076045] usb 6-2: reset full speed USB device using uhci_hcd and address 2 [37766.288126] PM: resume of drv:usb dev:6-2 complete after 378.968 msecs [37766.289122] thinkpad_acpi: ACPI backlight control delay disabled [37766.291598] PM: resume of devices complete after 3601.784 msecs [37766.291763] PM: resume devices took 3.600 seconds [37766.291788] PM: Finishing wakeup. [37766.291790] Restarting tasks ... done. [37766.401040] usb 1-4: new high speed USB device using ehci_hcd and address 10 [37766.533531] usb 1-4: configuration #1 chosen from 1 choice [37766.533704] hub 1-4:1.0: USB hub found [37766.533793] hub 1-4:1.0: 4 ports detected [37766.559584] mmc0: new SDHC card at address 894a [37766.569036] mmcblk0: mmc0:894a SD04G 3.69 GiB [37766.569108] mmcblk0: p1 [37767.654786] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [37767.710632] e1000e 0000:00:19.0: irq 30 for MSI/MSI-X [37767.710923] ADDRCONF(NETDEV_UP): eth0: link is not ready [37767.934006] Registered led device: iwl-phy0::radio [37767.936784] Registered led device: iwl-phy0::assoc [37767.936857] Registered led device: iwl-phy0::RX [37767.937392] Registered led device: iwl-phy0::TX [37767.975946] ADDRCONF(NETDEV_UP): wlan0: link is not ready [37769.569148] usb 3-1: new full speed USB device using uhci_hcd and address 10 [37769.742244] usb 3-1: configuration #1 chosen from 1 choice [38010.813156] usb 4-1: new low speed USB device using uhci_hcd and address 2 [38010.990346] usb 4-1: configuration #1 chosen from 1 choice [38011.127549] usbcore: registered new interface driver hiddev [38011.143743] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.0/input/input10 [38011.143992] generic-usb 0003:046D:C521.0001: input,hidraw0: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:00:1a.1-1/input0 [38011.174408] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1a.1/usb4/4-1/4-1:1.1/input/input11 [38011.174706] generic-usb 0003:046D:C521.0002: input,hiddev96,hidraw1: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:1a.1-1/input1 [38011.174765] usbcore: registered new interface driver usbhid [38011.174773] usbhid: v2.6:USB HID core driver [38097.560610] wlan0: deauthenticating from 00:19:cb:ca:5f:52 by local choice (reason=3) [38097.610116] wlan0: direct probe to AP 00:19:cb:ca:5f:52 (try 1) [38097.612854] wlan0: direct probe responded [38097.612862] wlan0: authenticate with AP 00:19:cb:ca:5f:52 (try 1) [38097.614508] wlan0: authenticated [38097.614582] wlan0: associate with AP 00:19:cb:ca:5f:52 (try 1) [38097.617495] wlan0: RX AssocResp from 00:19:cb:ca:5f:52 (capab=0x431 status=0 aid=1) [38097.617502] wlan0: associated [38097.638634] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready [38100.798506] padlock: VIA PadLock not detected. [38108.220086] wlan0: no IPv6 routers present [38349.801617] padlock: VIA PadLock Hash Engine not detected. [38349.810646] PPP MPPE Compression module registered