[ 4777.138931] PM: Syncing filesystems ... done. [ 4777.192678] PM: Preparing system for mem sleep [ 4777.193120] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 4777.208091] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 4777.224070] PM: Entering mem sleep [ 4777.224137] Suspending console(s) (use no_console_suspend to debug) [ 4777.224707] sd 3:0:0:0: [sdf] Synchronizing SCSI cache [ 4777.224960] sd 3:0:0:0: [sdf] Stopping disk [ 4777.225087] sd 2:0:0:0: [sde] Synchronizing SCSI cache [ 4777.225246] sd 2:0:0:0: [sde] Stopping disk [ 4777.225382] sd 1:0:0:0: [sdd] Synchronizing SCSI cache [ 4777.225531] sd 1:0:0:0: [sdd] Stopping disk [ 4777.225724] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 4777.225888] sd 0:0:0:0: [sda] Stopping disk [ 4777.226321] parport_pc 00:09: disabled [ 4777.227061] serial 00:07: disabled [ 4777.227521] serial 00:06: disabled [ 4777.228108] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 4777.228135] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 4777.228153] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 4777.228240] pciehp 0000:00:1c.0:pcie04: pciehp_suspend ENTRY [ 4777.228275] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 4777.228293] uhci_hcd 0000:00:1a.2: PCI INT D disabled [ 4777.228312] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 4777.228332] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 4777.228405] pciehp 0000:00:1c.5:pcie04: pciehp_suspend ENTRY [ 4777.256042] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 4777.458744] PM: suspend of drv:sd dev:3:0:0:0 complete after 234.051 msecs [ 4777.458777] PM: suspend of drv:scsi dev:target3:0:0 complete after 233.827 msecs [ 4777.458803] PM: suspend of drv:scsi dev:host3 complete after 232.572 msecs [ 4777.470734] PM: suspend of drv:sd dev:2:0:0:0 complete after 245.652 msecs [ 4777.470763] PM: suspend of drv:scsi dev:target2:0:0 complete after 245.555 msecs [ 4777.470805] PM: suspend of drv:scsi dev:host2 complete after 244.468 msecs [ 4777.936805] PM: suspend of drv:sd dev:1:0:0:0 complete after 711.431 msecs [ 4777.936834] PM: suspend of drv:scsi dev:target1:0:0 complete after 711.311 msecs [ 4777.936858] PM: suspend of drv:scsi dev:host1 complete after 710.406 msecs [ 4777.948000] PM: suspend of drv:sd dev:0:0:0:0 complete after 722.281 msecs [ 4777.948057] PM: suspend of drv:scsi dev:target0:0:0 complete after 722.126 msecs [ 4777.948108] PM: suspend of drv:scsi dev:host0 complete after 721.552 msecs [ 4777.964066] PM: suspend of drv:ahci dev:0000:00:1f.2 complete after 736.166 msecs [ 4777.964108] PM: suspend of drv: dev:pci0000:00 complete after 735.746 msecs [ 4777.964132] PM: suspend of devices complete after 739.558 msecs [ 4777.964138] PM: suspend devices took 0.740 seconds [ 4778.120107] PM: late suspend of drv:sky2 dev:0000:02:00.0 complete after 155.672 msecs [ 4778.152194] PM: late suspend of devices complete after 188.046 msecs [ 4778.152235] ACPI: Preparing to enter system sleep state S3 [ 4778.153037] PM: Saving platform NVS memory [ 4778.153577] Disabling non-boot CPUs ... [ 4778.155268] CPU 1 is now offline [ 4778.155911] Extended CMOS year: 2000 [ 4778.155911] ACPI: Low-level resume complete [ 4778.155911] PM: Restoring platform NVS memory [ 4778.155911] Extended CMOS year: 2000 [ 4778.155911] Enabling non-boot CPUs ... [ 4778.156266] Booting Node 0 Processor 1 APIC 0x1 [ 4778.156272] smpboot cpu 1: start_ip = 99000 [ 4778.268019] Switched to NOHz mode on CPU #1 [ 4778.268145] CPU1 is up [ 4778.268707] ACPI: Waking up from system sleep state S3 [ 4778.269348] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [ 4778.269396] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4778.269435] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4778.269473] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4778.269519] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 4778.269552] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x60100, writing 0x60105) [ 4778.269565] pcieport 0000:00:1c.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0x7fc17fb1) [ 4778.269574] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0x7fa07f90) [ 4778.269582] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0x1010) [ 4778.269594] pcieport 0000:00:1c.0: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [ 4778.269603] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100104, writing 0x100507) [ 4778.269646] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x60200, writing 0x6020a) [ 4778.269660] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0x7f817f71) [ 4778.269673] pcieport 0000:00:1c.5: restoring config space at offset 0x3 (was 0x810000, writing 0x810008) [ 4778.269682] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 4778.269736] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4778.269774] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4778.269813] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4778.269857] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 4778.269886] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x60000, writing 0x600ff) [ 4778.269974] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [ 4778.270054] sky2 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x105) [ 4778.270067] sky2 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfebc0000) [ 4778.270084] sky2 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xe801) [ 4778.270095] sky2 0000:02:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfebfc004) [ 4778.270104] sky2 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [ 4778.270115] sky2 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [ 4778.270373] PM: early resume of devices complete after 1.107 msecs [ 4778.270527] i915 0000:00:02.0: setting latency timer to 64 [ 4778.270837] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 4778.270849] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 4778.270878] usb usb3: root hub lost power or was reset [ 4778.270906] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 4778.270916] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 4778.270943] usb usb4: root hub lost power or was reset [ 4778.270966] uhci_hcd 0000:00:1a.2: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 4778.270977] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 4778.271002] usb usb5: root hub lost power or was reset [ 4778.271027] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4778.271038] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 4778.271104] pciehp 0000:00:1c.0:pcie04: pciehp_resume ENTRY [ 4778.271128] pciehp 0000:00:1c.5:pcie04: pciehp_resume ENTRY [ 4778.271147] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 4778.271158] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 4778.271184] usb usb6: root hub lost power or was reset [ 4778.271207] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 4778.271217] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 4778.271243] usb usb7: root hub lost power or was reset [ 4778.271266] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4778.271277] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 4778.271302] usb usb8: root hub lost power or was reset [ 4778.271326] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 4778.271337] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 4778.271417] pci 0000:00:1e.0: setting latency timer to 64 [ 4778.271447] ahci 0000:00:1f.2: setting latency timer to 64 [ 4778.276846] serial 00:06: activated [ 4778.279729] serial 00:07: activated [ 4778.283715] parport_pc 00:09: activated [ 4778.285351] sd 0:0:0:0: [sda] Starting disk [ 4778.285831] sd 1:0:0:0: [sdd] Starting disk [ 4778.285967] sd 2:0:0:0: [sde] Starting disk [ 4778.286097] sd 3:0:0:0: [sdf] Starting disk [ 4778.287190] Extended CMOS year: 2000 [ 4778.392569] No ACPI video bus found [ 4778.392583] PM: resume of drv:i915 dev:0000:00:02.0 complete after 122.074 msecs [ 4778.400094] PM: resume of drv:hub dev:4-0:1.0 complete after 116.576 msecs [ 4778.400102] PM: resume of drv:hub dev:5-0:1.0 complete after 116.491 msecs [ 4778.400122] PM: resume of drv: dev:ep_00 complete after 116.558 msecs [ 4778.400130] PM: resume of drv: dev:ep_00 complete after 116.391 msecs [ 4778.400138] PM: resume of drv: dev:ep_81 complete after 116.587 msecs [ 4778.400153] PM: resume of drv: dev:ep_81 complete after 116.539 msecs [ 4778.400183] PM: resume of drv:hub dev:6-0:1.0 complete after 116.309 msecs [ 4778.400226] PM: resume of drv: dev:ep_81 complete after 116.295 msecs [ 4778.400233] PM: resume of drv: dev:ep_00 complete after 116.241 msecs [ 4778.400327] PM: resume of drv: dev:ep_00 complete after 116.844 msecs [ 4778.400336] PM: resume of drv:hub dev:3-0:1.0 complete after 116.881 msecs [ 4778.400361] PM: resume of drv: dev:ep_81 complete after 116.902 msecs [ 4778.404097] PM: resume of drv:hub dev:8-0:1.0 complete after 119.682 msecs [ 4778.404105] PM: resume of drv: dev:ep_00 complete after 119.587 msecs [ 4778.404119] PM: resume of drv: dev:ep_81 complete after 119.656 msecs [ 4778.404161] PM: resume of drv:hub dev:7-0:1.0 complete after 119.961 msecs [ 4778.404195] PM: resume of drv: dev:ep_00 complete after 119.896 msecs [ 4778.404208] PM: resume of drv: dev:ep_81 complete after 119.960 msecs [ 4778.412064] PM: resume of drv:hub dev:2-0:1.0 complete after 128.745 msecs [ 4778.412122] PM: resume of drv: dev:ep_00 complete after 128.730 msecs [ 4778.412139] PM: resume of drv: dev:ep_81 complete after 128.758 msecs [ 4778.460243] PM: resume of drv:usb-storage dev:2-3:1.0 complete after 175.323 msecs [ 4778.460270] PM: resume of drv: dev:ep_00 complete after 175.192 msecs [ 4778.460278] PM: resume of drv: dev:ep_01 complete after 175.297 msecs [ 4778.460318] PM: resume of drv: dev:ep_82 complete after 175.291 msecs [ 4778.460350] PM: resume of drv:scsi dev:host7 complete after 175.112 msecs [ 4778.460400] PM: resume of drv:scsi_host dev:host7 complete after 175.110 msecs [ 4778.460408] PM: resume of drv:scsi dev:target7:0:0 complete after 174.911 msecs [ 4778.460445] PM: resume of drv:sd dev:7:0:0:0 complete after 174.898 msecs [ 4778.460468] PM: resume of drv:scsi_device dev:7:0:0:0 complete after 174.859 msecs [ 4778.524075] usb 2-1: reset high speed USB device number 2 using ehci_hcd [ 4778.600041] ata5: SATA link down (SStatus 0 SControl 300) [ 4778.608037] ata6: SATA link down (SStatus 0 SControl 300) [ 4778.658638] PM: resume of drv:usb-storage dev:2-1:1.0 complete after 374.017 msecs [ 4778.658647] PM: resume of drv: dev:ep_00 complete after 373.853 msecs [ 4778.658668] PM: resume of drv: dev:ep_81 complete after 373.996 msecs [ 4778.658690] PM: resume of drv:scsi dev:host6 complete after 373.562 msecs [ 4778.658724] PM: resume of drv: dev:ep_02 complete after 373.996 msecs [ 4778.658738] PM: resume of drv:scsi dev:target6:0:0 complete after 373.079 msecs [ 4778.658757] PM: resume of drv:scsi_host dev:host6 complete after 373.577 msecs [ 4778.658771] PM: resume of drv:sd dev:6:0:0:0 complete after 373.058 msecs [ 4778.658797] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 373.030 msecs [ 4781.112232] sky2 0000:02:00.0: eth0: Link is up at 1000 Mbps, full duplex, flow control rx [ 4788.284027] ata1: softreset failed (device not ready) [ 4788.292025] ata2: softreset failed (device not ready) [ 4788.308023] ata4: softreset failed (device not ready) [ 4788.316024] ata3: softreset failed (device not ready) [ 4789.288031] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 4789.319196] ata2.00: configured for UDMA/133 [ 4789.336033] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 4789.341676] PM: resume of drv:sd dev:1:0:0:0 complete after 11055.845 msecs [ 4789.341708] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 11055.797 msecs [ 4789.366431] ata1.00: configured for UDMA/133 [ 4789.389842] PM: resume of drv:sd dev:0:0:0:0 complete after 11104.496 msecs [ 4789.389876] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 11104.437 msecs [ 4789.389891] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 11102.636 msecs [ 4791.040030] ata4: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 4791.048028] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 4791.208369] ata3.00: configured for UDMA/133 [ 4791.266368] PM: resume of drv:sd dev:2:0:0:0 complete after 12980.399 msecs [ 4791.266403] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 12980.359 msecs [ 4791.266417] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 1876.493 msecs [ 4791.401170] ata4.00: configured for UDMA/133 [ 4791.457094] PM: resume of drv:sd dev:3:0:0:0 complete after 13170.994 msecs [ 4791.457128] PM: resume of drv:scsi_device dev:3:0:0:0 complete after 13170.948 msecs [ 4791.457141] PM: resume of drv:scsi_disk dev:3:0:0:0 complete after 190.711 msecs [ 4791.457206] PM: resume of devices complete after 13186.775 msecs [ 4791.457460] PM: resume devices took 13.188 seconds [ 4791.457464] ------------[ cut here ]------------ [ 4791.457476] WARNING: at /build/buildd/linux-3.0.0/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 4791.457481] Hardware name: Aspire easyStore H341 [ 4791.457485] Component: resume devices, time: 13188 [ 4791.457489] Modules linked in: nfsd nfs lockd fscache auth_rpcgss nfs_acl sunrpc ppdev psmouse serio_raw parport_pc i915 drm_kms_helper drm i2c_algo_bit video dme1737 hwmon_vid coretemp lp parport raid10 raid456 async_pq async_xor xor async_memcpy async_raid6_recov usb_storage uas ahci libahci sky2 floppy raid6_pq async_tx raid1 raid0 multipath linear [ 4791.457549] Pid: 2691, comm: pm-suspend Not tainted 3.0.0-14-server #23-Ubuntu [ 4791.457553] Call Trace: [ 4791.457564] [] warn_slowpath_common+0x7f/0xc0 [ 4791.457572] [] warn_slowpath_fmt+0x46/0x50 [ 4791.457580] [] suspend_test_finish+0x86/0x90 [ 4791.457587] [] suspend_devices_and_enter+0xca/0x1b0 [ 4791.457594] [] enter_state+0x11d/0x150 [ 4791.457601] [] state_store+0xb7/0xf0 [ 4791.457610] [] kobj_attr_store+0xf/0x30 [ 4791.457619] [] sysfs_write_file+0xe2/0x170 [ 4791.457628] [] vfs_write+0xb3/0x180 [ 4791.457635] [] sys_write+0x4a/0x90 [ 4791.457644] [] system_call_fastpath+0x16/0x1b [ 4791.457649] ---[ end trace 09f7f5cfbc9814e3 ]--- [ 4791.457768] PM: Finishing wakeup. [ 4791.457772] Restarting tasks ... done. [ 4791.987007] sd 7:0:0:0: [sdb] Media Changed [ 4791.987020] sd 7:0:0:0: [sdb] Result: hostbyte=DID_OK driverbyte=DRIVER_SENSE [ 4791.987032] sd 7:0:0:0: [sdb] Sense Key : Unit Attention [current] [ 4791.987046] Info fld=0x0 [ 4791.987053] sd 7:0:0:0: [sdb] Add. Sense: Not ready to ready change, medium may have changed [ 4791.987069] sd 7:0:0:0: [sdb] CDB: Read(10): 28 00 00 0a 97 00 00 00 f0 00 [ 4791.987099] end_request: I/O error, dev sdb, sector 694016 [ 4791.988165] init: upstart-udev-bridge main process (345) killed by BUS signal [ 4791.988279] init: upstart-udev-bridge main process ended, respawning [ 4791.989053] init: idmapd main process (722) killed by BUS signal [ 4791.989165] init: idmapd main process ended, respawning [ 4792.011675] Buffer I/O error on device sdb1, logical block 140045 [ 4792.011801] Buffer I/O error on device sdb1, logical block 140046 [ 4792.011909] Buffer I/O error on device sdb1, logical block 140047 [ 4792.012024] Buffer I/O error on device sdb1, logical block 140048 [ 4792.012135] Buffer I/O error on device sdb1, logical block 140049 [ 4792.012242] Buffer I/O error on device sdb1, logical block 140050 [ 4792.012286] Buffer I/O error on device sdb1, logical block 140051 [ 4792.012286] EXT4-fs warning (device sdb1): ext4_end_bio:258: I/O error writing to inode 1958 (offset 1286144 size 28672 starting block 140308) [ 4792.012693] Buffer I/O error on device sdb1, logical block 173614 [ 4792.012834] Buffer I/O error on device sdb1, logical block 173615 [ 4792.012972] Buffer I/O error on device sdb1, logical block 173616 [ 4792.013107] Buffer I/O error on device sdb1, logical block 173617 [ 4792.013241] Buffer I/O error on device sdb1, logical block 173618 [ 4792.013374] Buffer I/O error on device sdb1, logical block 173619 [ 4792.013506] Buffer I/O error on device sdb1, logical block 173620 [ 4792.013647] EXT4-fs warning (device sdb1): ext4_end_bio:258: I/O error writing to inode 2239 (offset 1204224 size 28672 starting block 173877) [ 4792.013732] Buffer I/O error on device sdb1, logical block 167410 [ 4792.013875] EXT4-fs warning (device sdb1): ext4_end_bio:258: I/O error writing to inode 19872 (offset 73728 size 4096 starting block 167667) [ 4792.013940] Buffer I/O error on device sdb1, logical block 376578 [ 4792.014082] EXT4-fs warning (device sdb1): ext4_end_bio:258: I/O error writing to inode 2194 (offset 32768 size 4096 starting block 376835) [ 4792.014211] Buffer I/O error on device sdb1, logical block 396316 [ 4792.014357] EXT4-fs warning (device sdb1): ext4_end_bio:258: I/O error writing to inode 2246 (offset 16384 size 4096 starting block 396573) [ 4792.014402] Buffer I/O error on device sdb1, logical block 405180 [ 4792.014541] EXT4-fs warning (device sdb1): ext4_end_bio:258: I/O error writing to inode 2246 (offset 20480 size 4096 starting block 405437) [ 4792.015075] JBD2: Detected IO errors while flushing file data on sdb1-8 [ 4792.015146] Aborting journal on device sdb1-8. [ 4792.015297] JBD2: I/O error detected when updating journal superblock for sdb1-8. [ 4792.018341] EXT4-fs error (device sdb1): ext4_journal_start_sb:296: Detected aborted journal [ 4792.021291] EXT4-fs (sdb1): Remounting filesystem read-only [ 4792.024074] EXT4-fs (sdb1): previous I/O error to superblock detected [ 4792.028055] journal commit I/O error [ 4792.033269] EXT4-fs (sdb1): previous I/O error to superblock detected [ 4792.035287] init: rsyslog main process (743) killed by BUS signal [ 4792.035387] init: rsyslog main process ended, respawning [ 4792.036116] EXT4-fs error (device sdb1): ext4_remount:4324: Abort forced by user [ 4792.044589] init: rsyslog main process (3118) killed by SEGV signal [ 4792.044695] init: rsyslog main process ended, respawning [ 4792.048464] EXT4-fs (dm-0): re-mounted. Opts: errors=remount-ro,commit=0 [ 4792.104776] EXT4-fs (dm-1): re-mounted. Opts: errors=remount-ro,commit=0