[ 18.694116] vboxdrv: fAsync=1 offMin=0x56e09 offMax=0x56e09 [ 18.694298] vboxdrv: TSC mode is 'asynchronous', kernel timer mode is 'normal'. [ 18.694301] vboxdrv: Successfully loaded version 4.1.4 (interface 0x00190000). [ 18.905011] vboxpci: IOMMU not found (not registered) [ 18.979899] Bluetooth: Core ver 2.16 [ 18.979944] NET: Registered protocol family 31 [ 18.979944] Bluetooth: HCI device and connection manager initialized [ 18.979948] Bluetooth: HCI socket layer initialized [ 18.979950] Bluetooth: L2CAP socket layer initialized [ 18.980472] Bluetooth: SCO socket layer initialized [ 18.982723] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 18.982727] Bluetooth: BNEP filters: protocol multicast [ 18.990386] Bluetooth: RFCOMM TTY layer initialized [ 18.990393] Bluetooth: RFCOMM socket layer initialized [ 18.990395] Bluetooth: RFCOMM ver 1.11 [ 19.175873] vesafb: mode is 1280x1024x32, linelength=5120, pages=0 [ 19.175877] vesafb: scrolling: redraw [ 19.175880] vesafb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 19.177426] vesafb: framebuffer at 0xd0000000, mapped to 0xffffc90012880000, using 5120k, total 5120k [ 19.177703] Console: switching to colour frame buffer device 160x64 [ 19.177736] fb0: VESA VGA frame buffer device [ 19.211139] ppdev: user-space parallel port driver [ 19.867279] r8169 0000:02:00.0: eth0: link up [ 19.867830] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 20.392599] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,user_xattr,commit=0 [ 21.678144] RPC: Registered named UNIX socket transport module. [ 21.678149] RPC: Registered udp transport module. [ 21.678151] RPC: Registered tcp transport module. [ 21.678153] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 21.680467] FS-Cache: Loaded [ 21.690012] FS-Cache: Netfs 'nfs' registered for caching [ 22.323783] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,user_xattr,commit=0 [ 30.720018] eth0: no IPv6 routers present [ 6862.455692] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,user_xattr,commit=0 [ 6865.168791] PM: Syncing filesystems ... done. [ 6865.221059] PM: Preparing system for mem sleep [ 6865.221073] Freezing user space processes ... (elapsed 0.01 seconds) done. [ 6865.236071] Freezing remaining freezable tasks ... (elapsed 0.01 seconds) done. [ 6865.252061] PM: Entering mem sleep [ 6865.252093] Suspending console(s) (use no_console_suspend to debug) [ 6865.252880] sd 1:0:0:0: [sdb] Synchronizing SCSI cache [ 6865.252977] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 6865.253052] sd 0:0:0:0: [sda] Stopping disk [ 6865.254095] serial 00:06: disabled [ 6865.257869] ohci_hcd 0000:00:14.5: PCI INT C disabled [ 6865.258002] ehci_hcd 0000:00:13.2: PCI INT B disabled [ 6865.258018] ohci_hcd 0000:00:13.1: PCI INT A disabled [ 6865.258033] ohci_hcd 0000:00:13.0: PCI INT A disabled [ 6865.264992] sd 1:0:0:0: [sdb] Stopping disk [ 6865.268582] ohci_hcd 0000:00:12.0: PCI INT A disabled [ 6865.268625] ohci_hcd 0000:00:12.1: PCI INT A disabled [ 6865.284037] ehci_hcd 0000:00:12.2: PCI INT B disabled [ 6865.625325] PM: suspend of drv:sd dev:0:0:0:0 complete after 372.350 msecs [ 6865.625336] PM: suspend of drv:scsi dev:target0:0:0 complete after 372.316 msecs [ 6865.625354] PM: suspend of drv:scsi dev:host0 complete after 372.151 msecs [ 6865.720047] HDA Intel 0000:00:14.2: PCI INT A disabled [ 6865.721230] PM: suspend of drv:sd dev:1:0:0:0 complete after 468.372 msecs [ 6865.721239] PM: suspend of drv:scsi dev:target1:0:0 complete after 468.308 msecs [ 6865.721258] PM: suspend of drv:scsi dev:host1 complete after 468.092 msecs [ 6865.721434] ahci 0000:00:11.0: PCI INT A disabled [ 6865.721438] PM: suspend of drv:ahci dev:0000:00:11.0 complete after 463.323 msecs [ 6865.722550] PM: suspend of drv:nvidia dev:0000:01:00.0 complete after 468.220 msecs [ 6865.722572] PM: suspend of drv:pcieport dev:0000:00:02.0 complete after 464.416 msecs [ 6865.736021] PM: suspend of drv:HDA Intel dev:0000:00:14.2 complete after 478.133 msecs [ 6865.736046] PM: suspend of drv: dev:pci0000:00 complete after 477.853 msecs [ 6865.736060] PM: suspend of devices complete after 483.616 msecs [ 6865.736063] PM: suspend devices took 0.484 seconds [ 6865.736774] r8169 0000:02:00.0: PME# enabled [ 6865.736784] pcieport 0000:00:06.0: wake-up capability enabled by ACPI [ 6865.784198] PM: late suspend of devices complete after 48.131 msecs [ 6865.784265] ACPI: Preparing to enter system sleep state S3 [ 6865.784512] PM: Saving platform NVS memory [ 6865.784828] Disabling non-boot CPUs ... [ 6865.888035] CPU 1 is now offline [ 6865.888359] ACPI: Low-level resume complete [ 6865.888359] PM: Restoring platform NVS memory [ 6865.888359] PCI-DMA: Resuming GART IOMMU [ 6865.888359] PCI-DMA: Restoring GART aperture settings [ 6865.888359] Enabling non-boot CPUs ... [ 6865.888359] Booting Node 0 Processor 1 APIC 0x1 [ 6865.888359] smpboot cpu 1: start_ip = 9a000 [ 6865.976517] CPU1 is up [ 6865.976802] ACPI: Waking up from system sleep state S3 [ 6865.980030] Switched to NOHz mode on CPU #1 [ 6866.016289] pcieport 0000:00:02.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 6866.016323] pcieport 0000:00:06.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 6866.016350] ahci 0000:00:11.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 6866.016370] ahci 0000:00:11.0: restoring config space at offset 0x2 (was 0x1018f00, writing 0x1060100) [ 6866.016392] ahci 0000:00:11.0: set SATA to AHCI mode [ 6866.032020] ehci_hcd 0000:00:12.2: BAR 0: set to [mem 0xfbfff000-0xfbfff0ff] (PCI address [0xfbfff000-0xfbfff0ff]) [ 6866.032051] ehci_hcd 0000:00:12.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00112) [ 6866.048021] ehci_hcd 0000:00:13.2: BAR 0: set to [mem 0xfbffa800-0xfbffa8ff] (PCI address [0xfbffa800-0xfbffa8ff]) [ 6866.048051] ehci_hcd 0000:00:13.2: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00112) [ 6866.048120] pata_atiixp 0000:00:14.1: restoring config space at offset 0x3 (was 0x0, writing 0x4000) [ 6866.048160] HDA Intel 0000:00:14.2: restoring config space at offset 0x1 (was 0x4100006, writing 0x4100002) [ 6866.048284] nvidia 0000:01:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [ 6866.048293] nvidia 0000:01:00.0: restoring config space at offset 0x9 (was 0x1, writing 0xd801) [ 6866.048298] nvidia 0000:01:00.0: restoring config space at offset 0x7 (was 0x4, writing 0xfc000004) [ 6866.048304] nvidia 0000:01:00.0: restoring config space at offset 0x5 (was 0xc, writing 0xd000000c) [ 6866.048308] nvidia 0000:01:00.0: restoring config space at offset 0x4 (was 0x0, writing 0xfd000000) [ 6866.048314] nvidia 0000:01:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [ 6866.048351] r8169 0000:02:00.0: restoring config space at offset 0xf (was 0x1ff, writing 0x10a) [ 6866.048358] r8169 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfebc0000) [ 6866.048367] r8169 0000:02:00.0: restoring config space at offset 0x8 (was 0xc, writing 0xfaff000c) [ 6866.048373] r8169 0000:02:00.0: restoring config space at offset 0x6 (was 0x4, writing 0xfebff004) [ 6866.048379] r8169 0000:02:00.0: restoring config space at offset 0x4 (was 0x1, writing 0xe801) [ 6866.048384] r8169 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x10) [ 6866.048390] r8169 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100507) [ 6866.049020] PM: early resume of devices complete after 32.879 msecs [ 6866.049124] ahci 0000:00:11.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 6866.049170] ohci_hcd 0000:00:12.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 6866.049196] ohci_hcd 0000:00:12.1: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 6866.049203] ehci_hcd 0000:00:12.2: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 6866.049289] ohci_hcd 0000:00:13.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 6866.049317] ohci_hcd 0000:00:13.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 6866.049374] ehci_hcd 0000:00:13.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 6866.049398] HDA Intel 0000:00:14.2: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 6866.049423] ohci_hcd 0000:00:14.5: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 6866.050525] pcieport 0000:00:06.0: wake-up capability disabled by ACPI [ 6866.050531] r8169 0000:02:00.0: PME# disabled [ 6866.055170] serial 00:06: activated [ 6866.056524] sd 0:0:0:0: [sda] Starting disk [ 6866.056597] sd 1:0:0:0: [sdb] Starting disk [ 6866.268031] PM: resume of drv:hub dev:4-0:1.0 complete after 211.920 msecs [ 6866.268039] PM: resume of drv: dev:ep_00 complete after 211.894 msecs [ 6866.268047] PM: resume of drv: dev:ep_81 complete after 211.921 msecs [ 6866.332055] PM: resume of drv:hub dev:1-0:1.0 complete after 276.109 msecs [ 6866.332065] PM: resume of drv: dev:ep_00 complete after 276.093 msecs [ 6866.332101] PM: resume of drv: dev:ep_81 complete after 276.142 msecs [ 6866.368071] ata4: SATA link down (SStatus 0 SControl 300) [ 6866.368142] ata3: SATA link down (SStatus 0 SControl 300) [ 6866.444033] usb 1-5: reset high speed USB device number 3 using ehci_hcd [ 6866.496071] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 446.622 msecs [ 6866.725153] PM: resume of drv:usb-storage dev:1-5:1.0 complete after 668.707 msecs [ 6866.725165] PM: resume of drv: dev:ep_00 complete after 668.666 msecs [ 6866.725173] PM: resume of drv: dev:ep_01 complete after 668.708 msecs [ 6866.725190] PM: resume of drv: dev:ep_82 complete after 668.707 msecs [ 6866.725193] PM: resume of drv:scsi dev:host6 complete after 668.447 msecs [ 6866.725207] PM: resume of drv:scsi_host dev:host6 complete after 668.444 msecs [ 6866.725215] PM: resume of drv:scsi dev:target6:0:0 complete after 668.361 msecs [ 6866.725238] PM: resume of drv:sd dev:6:0:0:0 complete after 668.365 msecs [ 6866.725249] PM: resume of drv:sd dev:6:0:0:3 complete after 668.268 msecs [ 6866.725254] PM: resume of drv:scsi_device dev:6:0:0:0 complete after 668.362 msecs [ 6866.725260] PM: resume of drv:sd dev:6:0:0:2 complete after 668.316 msecs [ 6866.725265] PM: resume of drv:scsi_device dev:6:0:0:3 complete after 668.261 msecs [ 6866.725271] PM: resume of drv:sd dev:6:0:0:1 complete after 668.364 msecs [ 6866.725275] PM: resume of drv:scsi_device dev:6:0:0:2 complete after 668.310 msecs [ 6866.725284] PM: resume of drv:scsi_device dev:6:0:0:1 complete after 668.357 msecs [ 6866.844493] ata6.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [ 6866.844497] ata6.01: ACPI cmd ef/03:42:00:00:00:b0 (SET FEATURES) filtered out [ 6866.844501] ata6.01: ACPI cmd f5/00:00:00:00:00:b0 (SECURITY FREEZE LOCK) filtered out [ 6866.860030] usb 4-3: reset low speed USB device number 2 using ohci_hcd [ 6866.868494] ata6.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [ 6866.868498] ata6.00: ACPI cmd ef/03:42:00:00:00:a0 (SET FEATURES) filtered out [ 6866.868501] ata6.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [ 6866.900427] ata6.00: configured for UDMA/33 [ 6866.948426] ata6.01: configured for UDMA/33 [ 6867.173099] PM: resume of drv:usbhid dev:4-3:1.0 complete after 1116.301 msecs [ 6867.173108] PM: resume of drv: dev:ep_00 complete after 1116.273 msecs [ 6867.173113] PM: resume of drv: dev:ep_81 complete after 1116.297 msecs [ 6867.448026] usb 3-1: reset low speed USB device number 2 using ohci_hcd [ 6867.763144] PM: resume of drv:usbhid dev:3-1:1.0 complete after 1706.471 msecs [ 6867.763153] PM: resume of drv: dev:ep_00 complete after 1706.442 msecs [ 6867.763158] PM: resume of drv: dev:ep_81 complete after 1706.465 msecs [ 6869.080011] [ 6869.080012] floppy driver state [ 6869.080013] ------------------- [ 6869.080028] now=4296609566 last interrupt=4294892623 diff=1716943 last called handler=reset_interrupt [ 6869.080030] timeout_message=lock fdc [ 6869.080031] last output bytes: [ 6869.080033] 8 80 4294892618 [ 6869.080034] 8 80 4294892618 [ 6869.080036] 8 80 4294892618 [ 6869.080037] 8 80 4294892622 [ 6869.080039] 8 80 4294892622 [ 6869.080040] 8 80 4294892622 [ 6869.080042] 8 80 4294892622 [ 6869.080043] e 80 4294892622 [ 6869.080045] 13 80 4294892622 [ 6869.080046] 0 90 4294892622 [ 6869.080048] 1a 90 4294892622 [ 6869.080049] 0 90 4294892622 [ 6869.080050] 12 90 4294892622 [ 6869.080052] 0 90 4294892622 [ 6869.080053] 14 90 4294892622 [ 6869.080055] 18 80 4294892622 [ 6869.080056] 8 80 4294892623 [ 6869.080057] 8 80 4294892623 [ 6869.080059] 8 80 4294892623 [ 6869.080060] 8 80 4294892623 [ 6869.080061] last result at 4294892623 [ 6869.080063] last redo_fd_request at 4294892623 [ 6869.080072] status=0 [ 6869.080073] fdc_busy=1 [ 6869.080077] do_floppy=reset_interrupt [ 6869.080078] cont=ffffffffa003bf20 [ 6869.080080] current_req= (null) [ 6869.080081] command_status=-1 [ 6869.080082] [ 6869.080085] floppy0: floppy timeout called [ 6869.080098] PM: resume of drv:floppy dev:floppy.0 complete after 3007.587 msecs [ 6871.244017] ata1: softreset failed (device not ready) [ 6871.244020] ata1: applying SB600 PMP SRST workaround and retrying [ 6871.356023] ata2: softreset failed (device not ready) [ 6871.356026] ata2: applying SB600 PMP SRST workaround and retrying [ 6871.416027] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 6871.437369] ata1.00: configured for UDMA/133 [ 6871.461332] PM: resume of drv:sd dev:0:0:0:0 complete after 5404.810 msecs [ 6871.461345] PM: resume of drv:scsi_device dev:0:0:0:0 complete after 5404.775 msecs [ 6871.461352] PM: resume of drv:scsi_disk dev:0:0:0:0 complete after 2381.242 msecs [ 6871.528035] ata2: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 6871.550574] ata2.00: configured for UDMA/133 [ 6871.568614] PM: resume of drv:sd dev:1:0:0:0 complete after 5512.016 msecs [ 6871.568630] PM: resume of drv:scsi_device dev:1:0:0:0 complete after 5512.002 msecs [ 6871.568637] PM: resume of drv:scsi_disk dev:1:0:0:0 complete after 107.269 msecs [ 6871.568922] PM: resume of devices complete after 5519.865 msecs [ 6871.569053] PM: resume devices took 5.520 seconds [ 6871.569100] PM: Finishing wakeup. [ 6871.569101] Restarting tasks ... done. [ 6874.454095] EXT4-fs (sdb1): re-mounted. Opts: errors=remount-ro,user_xattr,commit=0 [ 6874.518893] r8169 0000:02:00.0: eth0: link down [ 6874.518917] r8169 0000:02:00.0: eth0: link down [ 6874.520955] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 6876.153849] r8169 0000:02:00.0: eth0: link up [ 6876.154441] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 6886.976028] eth0: no IPv6 routers present [ 7935.783911] exe (4904): /proc/4904/oom_adj is deprecated, please use /proc/4904/oom_score_adj instead. [18519.932232] audit_printk_skb: 21 callbacks suppressed [18519.932241] type=1400 audit(1319737936.285:19): apparmor="STATUS" operation="profile_replace" name="/usr/lib/cups/backend/cups-pdf" pid=7094 comm="apparmor_parser" [18519.933591] type=1400 audit(1319737936.285:20): apparmor="STATUS" operation="profile_replace" name="/usr/sbin/cupsd" pid=7094 comm="apparmor_parser"