[ 43.183866] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 46.691434] EXT4-fs (sdb5): re-mounted. Opts: errors=remount-ro,commit=0 [ 50.840406] hda-intel: IRQ timing workaround is activated for card #1. Suggest a bigger bdl_pos_adj. [ 52.278349] EXT4-fs (sdb5): re-mounted. Opts: errors=remount-ro,commit=0 [ 53.290114] eth0: no IPv6 routers present [ 59.442766] Intel AES-NI instructions are not detected. [ 59.654307] padlock_aes: VIA PadLock not detected. [ 1397.376706] SGI XFS with ACLs, security attributes, realtime, large block/inode numbers, no debug enabled [ 1397.377331] SGI XFS Quota Management subsystem [ 1397.397461] JFS: nTxBlock = 8192, nTxLock = 65536 [ 1397.446225] NTFS driver 2.1.30 [Flags: R/O MODULE]. [ 1397.477484] QNX4 filesystem 0.2.3 registered. [ 1397.530843] Btrfs loaded [ 1398.398015] EXT3-fs: barriers not enabled [ 1398.403624] kjournald starting. Commit interval 5 seconds [ 1398.403742] EXT3-fs (sda3): mounted filesystem with ordered data mode [ 1398.752484] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 1401.479949] EXT4-fs (sdb7): mounted filesystem with ordered data mode. Opts: (null) [ 1402.625857] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 1406.822500] audit_printk_skb: 24 callbacks suppressed [ 1406.822504] type=1400 audit(1302124592.891:20): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient" pid=19786 comm="apparmor_parser" [ 1406.822616] type=1400 audit(1302124592.891:21): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=19786 comm="apparmor_parser" [ 1406.822691] type=1400 audit(1302124592.891:22): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=19786 comm="apparmor_parser" [ 1408.729570] <30>udev[19810]: converting old udev database [ 1408.764521] <30>udev[19811]: starting version 167 [ 1458.522837] type=1400 audit(1302124644.591:23): apparmor="STATUS" operation="profile_replace" name="/usr/lib/cups/backend/cups-pdf" pid=20421 comm="apparmor_parser" [ 1458.523121] type=1400 audit(1302124644.591:24): apparmor="STATUS" operation="profile_replace" name="/usr/sbin/cupsd" pid=20421 comm="apparmor_parser" [ 1458.595060] type=1400 audit(1302124644.661:25): apparmor="STATUS" operation="profile_replace" name="/usr/lib/cups/backend/cups-pdf" pid=20457 comm="apparmor_parser" [ 1458.595827] type=1400 audit(1302124644.661:26): apparmor="STATUS" operation="profile_replace" name="/usr/sbin/cupsd" pid=20457 comm="apparmor_parser" [ 1492.252860] EXT3-fs: barriers not enabled [ 1492.258320] kjournald starting. Commit interval 5 seconds [ 1492.258446] EXT3-fs (sda3): mounted filesystem with ordered data mode [ 1492.418229] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 1494.722386] EXT4-fs (sdb7): mounted filesystem with ordered data mode. Opts: (null) [ 1495.950003] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null) [ 1675.827103] EXT4-fs (sdb5): re-mounted. Opts: errors=remount-ro,commit=0 [ 1677.386697] PM: Syncing filesystems ... done. [ 1677.410338] PM: Preparing system for mem sleep [ 1677.650526] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 1677.670154] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 1677.690140] PM: Entering mem sleep [ 1677.690156] Suspending console(s) (use no_console_suspend to debug) [ 1677.690651] sd 5:0:0:0: [sdb] Synchronizing SCSI cache [ 1677.690765] sd 2:0:0:0: [sda] Synchronizing SCSI cache [ 1677.690871] sd 2:0:0:0: [sda] Stopping disk [ 1677.690964] sd 5:0:0:0: [sdb] Stopping disk [ 1677.691771] parport_pc 00:0a: disabled [ 1677.691832] serial 00:09: disabled [ 1677.692067] ohci_hcd 0000:00:14.5: PCI INT C disabled [ 1677.692098] pata_sil680 0000:03:07.0: PCI INT A disabled [ 1677.692333] ehci_hcd 0000:00:13.2: PCI INT B disabled [ 1677.692349] ohci_hcd 0000:00:13.1: PCI INT A disabled [ 1677.692365] ohci_hcd 0000:00:13.0: PCI INT A disabled [ 1677.692495] ohci_hcd 0000:00:12.0: PCI INT A disabled [ 1677.710127] ohci_hcd 0000:00:12.1: PCI INT A disabled [ 1677.750160] ehci_hcd 0000:00:12.2: PCI INT B disabled [ 1677.800157] HDA Intel 0000:01:00.1: PCI INT B disabled [ 1677.800188] ACPI handle has no context! [ 1677.800268] HDA Intel 0000:00:14.2: PCI INT A disabled [ 1677.820124] PM: suspend of drv:HDA Intel dev:0000:01:00.1 complete after 128.190 msecs [ 1677.820134] PM: suspend of drv:HDA Intel dev:0000:00:14.2 complete after 127.950 msecs [ 1678.095575] PM: suspend of drv:sd dev:2:0:0:0 complete after 404.813 msecs [ 1678.095595] PM: suspend of drv:scsi dev:target2:0:0 complete after 404.786 msecs [ 1678.095613] PM: suspend of drv:scsi dev:host2 complete after 404.489 msecs [ 1678.095803] ahci 0000:00:11.0: PCI INT A disabled [ 1678.095807] PM: suspend of drv:ahci dev:0000:00:11.0 complete after 403.303 msecs [ 1678.424512] [drm] Disabling audio support [ 1678.540150] PM: suspend of drv:radeon dev:0000:01:00.0 complete after 848.181 msecs [ 1678.540168] PM: suspend of drv:pcieport dev:0000:00:02.0 complete after 847.599 msecs [ 1678.540204] PM: suspend of drv: dev:pci0000:00 complete after 847.583 msecs [ 1678.540218] PM: suspend of devices complete after 849.757 msecs [ 1678.540221] PM: suspend devices took 0.850 seconds [ 1678.540879] r8169 0000:02:00.0: PME# enabled [ 1678.540892] pcieport 0000:00:0a.0: wake-up capability enabled by ACPI [ 1678.600315] PM: late suspend of devices complete after 60.090 msecs [ 1678.600575] ACPI: Preparing to enter system sleep state S3 [ 1678.600801] PM: Saving platform NVS memory [ 1678.600824] Disabling non-boot CPUs ... [ 1678.650479] Broke affinity for irq 43 [ 1678.760129] CPU 1 is now offline [ 1679.040121] CPU 2 is now offline [ 1679.220113] CPU 3 is now offline [ 1679.220319] Back to C! [ 1679.220319] PM: Restoring platform NVS memory [ 1679.220319] PCI-DMA: Resuming GART IOMMU [ 1679.220319] PCI-DMA: Restoring GART aperture settings [ 1679.220319] Enabling non-boot CPUs ... [ 1679.220319] Booting Node 0 Processor 1 APIC 0x1 [ 1678.651500] Switch to broadcast mode on CPU1 [ 1679.400589] CPU1 is up [ 1679.400733] Booting Node 0 Processor 2 APIC 0x2 [ 1679.410026] Switched to NOHz mode on CPU #1 [ 1678.931430] Switch to broadcast mode on CPU2 [ 1679.590125] Switched to NOHz mode on CPU #2 [ 1679.630497] CPU2 is up [ 1679.630636] Booting Node 0 Processor 3 APIC 0x3 [ 1679.111325] Switch to broadcast mode on CPU3 [ 1679.820124] Switched to NOHz mode on CPU #3 [ 1679.880550] CPU3 is up [ 1679.881431] ACPI: Waking up from system sleep state S3 [ 1679.881682] pci 0000:00:00.0: restoring config space at offset 0x7 (was 0x0, writing 0xe0000004) [ 1679.881686] pci 0000:00:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x2000) [ 1679.881706] pcieport 0000:00:02.0: restoring config space at offset 0x7 (was 0x2000e1e1, writing 0xe1e1) [ 1679.881711] pcieport 0000:00:02.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1679.881736] pcieport 0000:00:0a.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1679.881758] ahci 0000:00:11.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 1679.881775] ahci 0000:00:11.0: restoring config space at offset 0x2 (was 0x1018f00, writing 0x1060100) [ 1679.881794] ahci 0000:00:11.0: set SATA to AHCI mode [ 1679.900133] ehci_hcd 0000:00:12.2: BAR 0: set to [mem 0xfe02c000-0xfe02c0ff] (PCI address [0xfe02c000-0xfe02c0ff]) [ 1679.900159] ehci_hcd 0000:00:12.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00012) [ 1679.920119] ehci_hcd 0000:00:13.2: BAR 0: set to [mem 0xfe029000-0xfe0290ff] (PCI address [0xfe029000-0xfe0290ff]) [ 1679.920144] ehci_hcd 0000:00:13.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00012) [ 1679.920221] HDA Intel 0000:00:14.2: restoring config space at offset 0xf (was 0x100, writing 0x3) [ 1679.920237] HDA Intel 0000:00:14.2: restoring config space at offset 0x3 (was 0x0, writing 0x2010) [ 1679.920376] radeon 0000:01:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1679.920414] HDA Intel 0000:01:00.1: restoring config space at offset 0xf (was 0x2ff, writing 0x20b) [ 1679.920427] HDA Intel 0000:01:00.1: restoring config space at offset 0x4 (was 0x4, writing 0xfdffc004) [ 1679.920431] HDA Intel 0000:01:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x800010) [ 1679.920436] HDA Intel 0000:01:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) [ 1679.920477] r8169 0000:02:00.0: restoring config space at offset 0x1 (was 0x100007, writing 0x100407) [ 1679.921126] PM: early resume of devices complete after 39.556 msecs [ 1679.921367] ohci_hcd 0000:00:12.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1679.921395] ohci_hcd 0000:00:12.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1679.921464] radeon 0000:01:00.0: setting latency timer to 64 [ 1679.921472] HDA Intel 0000:01:00.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 1679.921485] HDA Intel 0000:01:00.1: setting latency timer to 64 [ 1679.921588] HDA Intel 0000:01:00.1: irq 44 for MSI/MSI-X [ 1679.930060] ehci_hcd 0000:00:12.2: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 1679.930286] ohci_hcd 0000:00:13.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 1679.930405] ohci_hcd 0000:00:13.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 1679.930495] ehci_hcd 0000:00:13.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 1679.930643] ohci_hcd 0000:00:14.5: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 1679.930699] pata_sil680 0000:03:07.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [ 1679.930771] ahci 0000:00:11.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 1679.931017] sil680: 133MHz clock. [ 1679.931143] pcieport 0000:00:0a.0: wake-up capability disabled by ACPI [ 1679.931193] r8169 0000:02:00.0: PME# disabled [ 1679.931890] sd 5:0:0:0: [sdb] Starting disk [ 1679.931991] sd 2:0:0:0: [sda] Starting disk [ 1679.934703] HDA Intel 0000:00:14.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1679.936195] serial 00:09: activated [ 1679.937624] parport_pc 00:0a: activated [ 1679.987358] radeon 0000:01:00.0: WB enabled [ 1680.030071] PM: resume of drv:usb dev:usb1 complete after 108.594 msecs [ 1680.030085] PM: resume of drv:hub dev:1-0:1.0 complete after 108.602 msecs [ 1680.030097] PM: resume of drv: dev:ep_00 complete after 108.605 msecs [ 1680.030100] PM: resume of drv: dev:ep_81 complete after 108.613 msecs [ 1680.033550] [drm] ring test succeeded in 1 usecs [ 1680.033568] [drm] ib test succeeded in 0 usecs [ 1680.033570] [drm] Enabling audio support [ 1680.060124] PM: resume of drv:usb dev:usb4 complete after 138.099 msecs [ 1680.060147] PM: resume of drv:hub dev:4-0:1.0 complete after 138.084 msecs [ 1680.060155] PM: resume of drv: dev:ep_00 complete after 138.014 msecs [ 1680.060165] PM: resume of drv: dev:ep_81 complete after 138.059 msecs [ 1680.070132] PM: resume of drv:usb dev:usb7 complete after 147.624 msecs [ 1680.070140] PM: resume of drv:usb dev:usb5 complete after 147.958 msecs [ 1680.070147] PM: resume of drv:usb dev:usb6 complete after 147.804 msecs [ 1680.070151] PM: resume of drv:hub dev:7-0:1.0 complete after 147.598 msecs [ 1680.070162] PM: resume of drv:hub dev:6-0:1.0 complete after 147.775 msecs [ 1680.070165] PM: resume of drv:usb dev:usb3 complete after 148.337 msecs [ 1680.070167] PM: resume of drv: dev:ep_00 complete after 147.860 msecs [ 1680.070170] PM: resume of drv: dev:ep_00 complete after 147.524 msecs [ 1680.070172] PM: resume of drv: dev:ep_00 complete after 147.707 msecs [ 1680.070175] PM: resume of drv: dev:ep_81 complete after 147.748 msecs [ 1680.070177] PM: resume of drv:hub dev:5-0:1.0 complete after 147.955 msecs [ 1680.070180] PM: resume of drv: dev:ep_81 complete after 147.586 msecs [ 1680.070183] PM: resume of drv: dev:ep_00 complete after 148.203 msecs [ 1680.070185] PM: resume of drv:hub dev:3-0:1.0 complete after 148.302 msecs [ 1680.070198] PM: resume of drv: dev:ep_81 complete after 147.933 msecs [ 1680.070218] PM: resume of drv: dev:ep_81 complete after 148.291 msecs [ 1680.103705] PM: resume of drv:usb dev:1-3 complete after 180.225 msecs [ 1680.103721] PM: resume of drv:hub dev:1-3:1.0 complete after 180.199 msecs [ 1680.103731] PM: resume of drv: dev:ep_00 complete after 180.130 msecs [ 1680.103739] PM: resume of drv: dev:ep_81 complete after 180.178 msecs [ 1680.124152] PM: resume of drv:usb dev:4-1 complete after 200.392 msecs [ 1680.124167] PM: resume of drv: dev:ep_00 complete after 200.192 msecs [ 1680.124169] PM: resume of drv:usbhid dev:4-1:1.0 complete after 200.363 msecs [ 1680.124178] PM: resume of drv:usbhid dev:4-1:1.1 complete after 200.286 msecs [ 1680.124187] PM: resume of drv: dev:ep_81 complete after 200.337 msecs [ 1680.124190] PM: resume of drv: dev:ep_82 complete after 200.253 msecs [ 1680.161902] PM: resume of drv:usb dev:1-3.1 complete after 237.887 msecs [ 1680.161917] PM: resume of drv: dev:ep_00 complete after 237.777 msecs [ 1680.161919] PM: resume of drv:usbhid dev:1-3.1:1.0 complete after 237.865 msecs [ 1680.161929] PM: resume of drv: dev:ep_81 complete after 237.831 msecs [ 1680.280139] ata4: SATA link down (SStatus 0 SControl 300) [ 1680.280171] ata5: SATA link down (SStatus 0 SControl 300) [ 1680.350455] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [ 1680.350458] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [ 1680.390454] ata2.00: configured for UDMA/100 [ 1681.710176] PM: resume of drv:radeon dev:0000:01:00.0 complete after 1788.727 msecs [ 1682.970108] [ 1682.970109] floppy driver state [ 1682.970110] ------------------- [ 1682.970125] now=4295105593 last interrupt=4294937548 diff=168045 last called handler=reset_interrupt [ 1682.970126] timeout_message=lock fdc [ 1682.970127] last output bytes: [ 1682.970129] 8 80 4294937546 [ 1682.970130] 8 80 4294937546 [ 1682.970131] 8 80 4294937546 [ 1682.970132] 8 80 4294937548 [ 1682.970133] 8 80 4294937548 [ 1682.970134] 8 80 4294937548 [ 1682.970135] 8 80 4294937548 [ 1682.970136] e 80 4294937548 [ 1682.970137] 13 80 4294937548 [ 1682.970139] 0 90 4294937548 [ 1682.970140] 1a 90 4294937548 [ 1682.970141] 0 90 4294937548 [ 1682.970142] 12 90 4294937548 [ 1682.970143] 0 90 4294937548 [ 1682.970144] 14 90 4294937548 [ 1682.970145] 18 80 4294937548 [ 1682.970146] 8 80 4294937548 [ 1682.970147] 8 80 4294937548 [ 1682.970148] 8 80 4294937548 [ 1682.970149] 8 80 4294937548 [ 1682.970150] last result at 4294937548 [ 1682.970151] last redo_fd_request at 4294937548 [ 1682.970159] status=0 [ 1682.970160] fdc_busy=1 [ 1682.970163] do_floppy=reset_interrupt [ 1682.970164] cont=ffffffffa009ab80 [ 1682.970166] current_req= (null) [ 1682.970167] command_status=-1 [ 1682.970167] [ 1682.970170] floppy0: floppy timeout called [ 1682.970185] PM: resume of drv:floppy dev:floppy.0 complete after 3007.250 msecs [ 1685.520137] ata3: softreset failed (device not ready) [ 1685.520139] ata3: applying SB600 PMP SRST workaround and retrying [ 1685.720148] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1685.724616] ata3.00: n_sectors mismatch 781422768 != 781420655 [ 1685.724618] ata3.00: old n_sectors matches native, probably late HPA lock, will try to unlock HPA [ 1685.724620] ata3.00: revalidation failed (errno=-5) [ 1690.910127] ata6: softreset failed (device not ready) [ 1690.910130] ata6: applying SB600 PMP SRST workaround and retrying [ 1691.090148] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1691.122331] ata6.00: n_sectors mismatch 2930277168 != 2930275055 [ 1691.122332] ata6.00: old n_sectors matches native, probably late HPA lock, will try to unlock HPA [ 1691.122334] ata6.00: revalidation failed (errno=-5) [ 1696.250134] ata3: softreset failed (device not ready) [ 1696.250137] ata3: applying SB600 PMP SRST workaround and retrying [ 1696.450154] ata3: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1696.463470] ata3.00: configured for UDMA/133 [ 1696.501513] PM: resume of drv:sd dev:2:0:0:0 complete after 16577.293 msecs [ 1696.501528] PM: resume of drv:scsi_device dev:2:0:0:0 complete after 16577.267 msecs [ 1696.501532] PM: resume of drv:scsi_disk dev:2:0:0:0 complete after 13531.268 msecs [ 1696.640121] ata6: softreset failed (device not ready) [ 1696.640123] ata6: applying SB600 PMP SRST workaround and retrying [ 1696.840125] ata6: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1696.999927] ata6.00: configured for UDMA/133 [ 1697.059373] PM: resume of drv:sd dev:5:0:0:0 complete after 17134.902 msecs [ 1697.059388] PM: resume of drv:scsi_device dev:5:0:0:0 complete after 17134.874 msecs [ 1697.059392] PM: resume of drv:scsi_disk dev:5:0:0:0 complete after 557.846 msecs [ 1697.059543] PM: resume of devices complete after 17138.357 msecs [ 1697.059738] PM: resume devices took 17.130 seconds [ 1697.059739] ------------[ cut here ]------------ [ 1697.059744] WARNING: at /build/buildd/linux-2.6.38/kernel/power/suspend_test.c:53 suspend_test_finish+0x86/0x90() [ 1697.059746] Hardware name: GA-MA78G-DS3H [ 1697.059747] Component: resume devices, time: 17130 [ 1697.059748] Modules linked in: nls_iso8859_1 nls_cp437 btrfs zlib_deflate libcrc32c ufs qnx4 hfsplus hfs minix ntfs vfat msdos fat jfs xfs exportfs reiserfs cryptd aes_x86_64 aes_generic binfmt_misc dm_crypt snd_hda_codec_hdmi snd_hda_codec_realtek snd_seq_midi snd_hda_intel snd_rawmidi snd_hda_codec snd_seq_midi_event snd_hwdep snd_seq snd_pcm snd_seq_device snd_timer sp5100_tco edac_core i2c_piix4 ppdev psmouse snd serio_raw soundcore snd_page_alloc parport_pc k10temp edac_mce_amd lp parport radeon ttm drm_kms_helper usbhid hid floppy pata_sil680 drm ahci pata_atiixp r8169 libahci i2c_algo_bit [ 1697.059777] Pid: 31082, comm: pm-suspend Not tainted 2.6.38-7-generic #39-Ubuntu [ 1697.059779] Call Trace: [ 1697.059785] [] ? warn_slowpath_common+0x7f/0xc0 [ 1697.059788] [] ? warn_slowpath_fmt+0x46/0x50 [ 1697.059790] [] ? suspend_test_finish+0x86/0x90 [ 1697.059792] [] ? suspend_devices_and_enter+0xac/0x170 [ 1697.059794] [] ? enter_state+0x120/0x150 [ 1697.059796] [] ? state_store+0xc6/0x100 [ 1697.059800] [] ? kobj_attr_store+0x17/0x20 [ 1697.059803] [] ? sysfs_write_file+0xde/0x160 [ 1697.059807] [] ? vfs_write+0xc6/0x180 [ 1697.059809] [] ? sys_write+0x51/0x90 [ 1697.059812] [] ? system_call_fastpath+0x16/0x1b [ 1697.059813] ---[ end trace 74364d4ad18d67ed ]--- [ 1697.060105] PM: Finishing wakeup. [ 1697.060106] Restarting tasks ... done. [ 1697.123519] [drm:drm_mode_getfb] *ERROR* invalid framebuffer id [ 1697.230219] r8169 0000:02:00.0: eth0: link down [ 1697.230233] r8169 0000:02:00.0: eth0: link down [ 1697.231000] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1698.077120] EXT4-fs (sdb5): re-mounted. Opts: errors=remount-ro,commit=0 [ 1698.844038] r8169 0000:02:00.0: eth0: link up [ 1698.844770] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 1708.920125] eth0: no IPv6 routers present