ize = 128 [ 0.401680] NetLabel: protocols = UNLABELED CIPSOv4 [ 0.401680] NetLabel: unlabeled traffic allowed by default [ 0.401680] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0 [ 0.401680] hpet0: 4 comparators, 64-bit 14.318180 MHz counter [ 0.412143] Switching to clocksource tsc [ 0.414253] AppArmor: AppArmor Filesystem Enabled [ 0.414272] pnp: PnP ACPI init [ 0.414291] ACPI: bus type pnp registered [ 0.417202] pnp: PnP ACPI: found 14 devices [ 0.417204] ACPI: ACPI bus type pnp unregistered [ 0.417208] PnPBIOS: Disabled by ACPI PNP [ 0.417220] system 00:01: iomem range 0xfed14000-0xfed19fff has been reserved [ 0.417227] system 00:06: ioport range 0x290-0x297 has been reserved [ 0.417233] system 00:07: ioport range 0x4d0-0x4d1 has been reserved [ 0.417235] system 00:07: ioport range 0x800-0x87f has been reserved [ 0.417238] system 00:07: ioport range 0x480-0x4bf has been reserved [ 0.417241] system 00:07: iomem range 0xfed1c000-0xfed1ffff has been reserved [ 0.417244] system 00:07: iomem range 0xfed20000-0xfed3ffff has been reserved [ 0.417246] system 00:07: iomem range 0xfed50000-0xfed8ffff has been reserved [ 0.417249] system 00:07: iomem range 0xffa00000-0xffafffff has been reserved [ 0.417252] system 00:07: iomem range 0xffb00000-0xffbfffff has been reserved [ 0.417254] system 00:07: iomem range 0xffe00000-0xffefffff has been reserved [ 0.417257] system 00:07: iomem range 0xfff00000-0xfffffffe has been reserved [ 0.417263] system 00:0a: iomem range 0xfec00000-0xfec00fff could not be reserved [ 0.417265] system 00:0a: iomem range 0xfee00000-0xfee00fff has been reserved [ 0.417271] system 00:0c: iomem range 0xe0000000-0xefffffff has been reserved [ 0.417276] system 00:0d: iomem range 0x0-0x9ffff could not be reserved [ 0.417279] system 00:0d: iomem range 0xc0000-0xcffff could not be reserved [ 0.417281] system 00:0d: iomem range 0xe0000-0xfffff could not be reserved [ 0.417284] system 00:0d: iomem range 0x100000-0xcfffffff could not be reserved [ 0.451965] pci 0000:05:02.0: BAR 6: address space collision on of device [0xfebc0000-0xfebdffff] [ 0.451969] pci 0000:05:04.0: BAR 6: address space collision on of device [0xfeba0000-0xfebbffff] [ 0.452007] pci 0000:00:01.0: PCI bridge, secondary bus 0000:01 [ 0.452010] pci 0000:00:01.0: IO window: 0xb000-0xbfff [ 0.452014] pci 0000:00:01.0: MEM window: 0xfa000000-0xfe8fffff [ 0.452017] pci 0000:00:01.0: PREFETCH window: 0x000000d0000000-0x000000dfffffff [ 0.452021] pci 0000:00:1c.0: PCI bridge, secondary bus 0000:04 [ 0.452024] pci 0000:00:1c.0: IO window: 0x1000-0x1fff [ 0.452029] pci 0000:00:1c.0: MEM window: 0xf0000000-0xf03fffff [ 0.452033] pci 0000:00:1c.0: PREFETCH window: 0x000000f8f00000-0x000000f8ffffff [ 0.452038] pci 0000:00:1c.4: PCI bridge, secondary bus 0000:03 [ 0.452041] pci 0000:00:1c.4: IO window: 0xd000-0xdfff [ 0.452046] pci 0000:00:1c.4: MEM window: 0xfea00000-0xfeafffff [ 0.452049] pci 0000:00:1c.4: PREFETCH window: 0x000000f0400000-0x000000f05fffff [ 0.452055] pci 0000:00:1c.5: PCI bridge, secondary bus 0000:02 [ 0.452058] pci 0000:00:1c.5: IO window: 0xc000-0xcfff [ 0.452063] pci 0000:00:1c.5: MEM window: 0xfe900000-0xfe9fffff [ 0.452067] pci 0000:00:1c.5: PREFETCH window: 0x000000f0600000-0x000000f07fffff [ 0.452074] pci 0000:00:1e.0: PCI bridge, secondary bus 0000:05 [ 0.452076] pci 0000:00:1e.0: IO window: 0xe000-0xefff [ 0.452081] pci 0000:00:1e.0: MEM window: 0xfeb00000-0xfebfffff [ 0.452085] pci 0000:00:1e.0: PREFETCH window: 0xf0800000-0xf08fffff [ 0.452097] alloc irq_desc for 16 on node -1 [ 0.452098] alloc kstat_irqs on node -1 [ 0.452104] pci 0000:00:01.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 0.452107] pci 0000:00:01.0: setting latency timer to 64 [ 0.452114] pci 0000:00:1c.0: enabling device (0106 -> 0107) [ 0.452117] alloc irq_desc for 17 on node -1 [ 0.452119] alloc kstat_irqs on node -1 [ 0.452122] pci 0000:00:1c.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 0.452125] pci 0000:00:1c.0: setting latency timer to 64 [ 0.452133] pci 0000:00:1c.4: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 0.452136] pci 0000:00:1c.4: setting latency timer to 64 [ 0.452144] pci 0000:00:1c.5: PCI INT B -> GSI 16 (level, low) -> IRQ 16 [ 0.452147] pci 0000:00:1c.5: setting latency timer to 64 [ 0.452154] pci 0000:00:1e.0: setting latency timer to 64 [ 0.452157] pci_bus 0000:00: resource 0 io: [0x00-0xffff] [ 0.452159] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffffffffffff] [ 0.452161] pci_bus 0000:01: resource 0 io: [0xb000-0xbfff] [ 0.452163] pci_bus 0000:01: resource 1 mem: [0xfa000000-0xfe8fffff] [ 0.452166] pci_bus 0000:01: resource 2 pref mem [0xd0000000-0xdfffffff] [ 0.452168] pci_bus 0000:04: resource 0 io: [0x1000-0x1fff] [ 0.452170] pci_bus 0000:04: resource 1 mem: [0xf0000000-0xf03fffff] [ 0.452172] pci_bus 0000:04: resource 2 pref mem [0xf8f00000-0xf8ffffff] [ 0.452174] pci_bus 0000:03: resource 0 io: [0xd000-0xdfff] [ 0.452176] pci_bus 0000:03: resource 1 mem: [0xfea00000-0xfeafffff] [ 0.452178] pci_bus 0000:03: resource 2 pref mem [0xf0400000-0xf05fffff] [ 0.452181] pci_bus 0000:02: resource 0 io: [0xc000-0xcfff] [ 0.452183] pci_bus 0000:02: resource 1 mem: [0xfe900000-0xfe9fffff] [ 0.452185] pci_bus 0000:02: resource 2 pref mem [0xf0600000-0xf07fffff] [ 0.452187] pci_bus 0000:05: resource 0 io: [0xe000-0xefff] [ 0.452189] pci_bus 0000:05: resource 1 mem: [0xfeb00000-0xfebfffff] [ 0.452191] pci_bus 0000:05: resource 2 pref mem [0xf0800000-0xf08fffff] [ 0.452193] pci_bus 0000:05: resource 3 io: [0x00-0xffff] [ 0.452195] pci_bus 0000:05: resource 4 mem: [0x000000-0xffffffffffffffff] [ 0.452224] NET: Registered protocol family 2 [ 0.452303] IP route cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.452557] TCP established hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.452879] TCP bind hash table entries: 65536 (order: 7, 524288 bytes) [ 0.453020] TCP: Hash tables configured (established 131072 bind 65536) [ 0.453021] TCP reno registered [ 0.453080] NET: Registered protocol family 1 [ 0.453210] pci 0000:01:00.0: Boot video device [ 0.453460] cpufreq-nforce2: No nForce2 chipset. [ 0.453482] Scanning for low memory corruption every 60 seconds [ 0.453564] audit: initializing netlink socket (disabled) [ 0.453572] type=2000 audit(1285888128.451:1): initialized [ 0.462377] highmem bounce pool size: 64 pages [ 0.462383] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.463722] VFS: Disk quotas dquot_6.5.2 [ 0.463773] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 0.464283] fuse init (API version 7.13) [ 0.464377] msgmni has been set to 1613 [ 0.464656] alg: No test for stdrng (krng) [ 0.464726] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253) [ 0.464729] io scheduler noop registered [ 0.464731] io scheduler anticipatory registered [ 0.464733] io scheduler deadline registered [ 0.464768] io scheduler cfq registered (default) [ 0.464898] alloc irq_desc for 24 on node -1 [ 0.464900] alloc kstat_irqs on node -1 [ 0.464906] pcieport 0000:00:01.0: irq 24 for MSI/MSI-X [ 0.464912] pcieport 0000:00:01.0: setting latency timer to 64 [ 0.465000] alloc irq_desc for 25 on node -1 [ 0.465002] alloc kstat_irqs on node -1 [ 0.465008] pcieport 0000:00:1c.0: irq 25 for MSI/MSI-X [ 0.465015] pcieport 0000:00:1c.0: setting latency timer to 64 [ 0.465117] alloc irq_desc for 26 on node -1 [ 0.465119] alloc kstat_irqs on node -1 [ 0.465125] pcieport 0000:00:1c.4: irq 26 for MSI/MSI-X [ 0.465132] pcieport 0000:00:1c.4: setting latency timer to 64 [ 0.465236] alloc irq_desc for 27 on node -1 [ 0.465238] alloc kstat_irqs on node -1 [ 0.465244] pcieport 0000:00:1c.5: irq 27 for MSI/MSI-X [ 0.465251] pcieport 0000:00:1c.5: setting latency timer to 64 [ 0.465330] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.465417] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.465509] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 [ 0.465512] ACPI: Power Button [PWRB] [ 0.465552] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 [ 0.465555] ACPI: Power Button [PWRF] [ 0.466097] ACPI: SSDT cff8e0d0 001D2 (v01 AMI CPU1PM 00000001 INTL 20060113) [ 0.466372] processor LNXCPU:00: registered as cooling_device0 [ 0.466640] ACPI: SSDT cff8e2b0 00143 (v01 AMI CPU2PM 00000001 INTL 20060113) [ 0.466900] processor LNXCPU:01: registered as cooling_device1 [ 0.467166] ACPI: SSDT cff8e400 00143 (v01 AMI CPU3PM 00000001 INTL 20060113) [ 0.467430] processor LNXCPU:02: registered as cooling_device2 [ 0.467697] ACPI: SSDT cff8e550 00143 (v01 AMI CPU4PM 00000001 INTL 20060113) [ 0.467957] processor LNXCPU:03: registered as cooling_device3 [ 0.470645] isapnp: Scanning for PnP cards... [ 0.471821] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.471912] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 0.472276] 00:09: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 0.473238] brd: module loaded [ 0.473644] loop: module loaded [ 0.473715] input: Macintosh mouse button emulation as /devices/virtual/input/input2 [ 0.473810] ata_piix 0000:00:1f.2: version 2.13 [ 0.473823] alloc irq_desc for 22 on node -1 [ 0.473824] alloc kstat_irqs on node -1 [ 0.473829] ata_piix 0000:00:1f.2: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [ 0.473833] ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ] [ 0.473871] ata_piix 0000:00:1f.2: setting latency timer to 64 [ 0.473927] scsi0 : ata_piix [ 0.473994] scsi1 : ata_piix [ 0.475406] ata1: SATA max UDMA/133 cmd 0x9000 ctl 0x8c00 bmdma 0x8480 irq 22 [ 0.475411] ata2: SATA max UDMA/133 cmd 0x8880 ctl 0x8800 bmdma 0x8488 irq 22 [ 0.475431] ata_piix 0000:00:1f.5: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [ 0.475435] ata_piix 0000:00:1f.5: MAP [ P0 -- P1 -- ] [ 0.475466] ata_piix 0000:00:1f.5: setting latency timer to 64 [ 0.475522] scsi2 : ata_piix [ 0.475604] scsi3 : ata_piix [ 0.476629] ata3: SATA max UDMA/133 cmd 0xa000 ctl 0x9c00 bmdma 0x9480 irq 22 [ 0.476633] ata4: SATA max UDMA/133 cmd 0x9880 ctl 0x9800 bmdma 0x9488 irq 22 [ 0.476744] pata_acpi 0000:03:00.1: enabling device (0000 -> 0001) [ 0.476750] pata_acpi 0000:03:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 0.476775] pata_acpi 0000:03:00.1: setting latency timer to 64 [ 0.476787] pata_acpi 0000:03:00.1: PCI INT B disabled [ 0.477048] Fixed MDIO Bus: probed [ 0.477076] PPP generic driver version 2.4.2 [ 0.477104] tun: Universal TUN/TAP device driver, 1.6 [ 0.477105] tun: (C) 1999-2004 Max Krasnyansky [ 0.477179] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 0.477194] alloc irq_desc for 18 on node -1 [ 0.477195] alloc kstat_irqs on node -1 [ 0.477199] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 0.477209] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 0.477212] ehci_hcd 0000:00:1a.7: EHCI Host Controller [ 0.477244] ehci_hcd 0000:00:1a.7: new USB bus registered, assigned bus number 1 [ 0.477266] ehci_hcd 0000:00:1a.7: debug port 1 [ 0.481163] ehci_hcd 0000:00:1a.7: cache line size of 32 is not supported [ 0.481173] ehci_hcd 0000:00:1a.7: irq 18, io mem 0xf9fffc00 [ 0.496046] ehci_hcd 0000:00:1a.7: USB 2.0 started, EHCI 1.00 [ 0.496120] usb usb1: configuration #1 chosen from 1 choice [ 0.496144] hub 1-0:1.0: USB hub found [ 0.496150] hub 1-0:1.0: 6 ports detected [ 0.496200] alloc irq_desc for 23 on node -1 [ 0.496201] alloc kstat_irqs on node -1 [ 0.496205] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 0.496213] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 0.496216] ehci_hcd 0000:00:1d.7: EHCI Host Controller [ 0.496247] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 2 [ 0.496265] ehci_hcd 0000:00:1d.7: debug port 1 [ 0.500147] ehci_hcd 0000:00:1d.7: cache line size of 32 is not supported [ 0.500157] ehci_hcd 0000:00:1d.7: irq 23, io mem 0xf9fff800 [ 0.516047] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00 [ 0.516109] usb usb2: configuration #1 chosen from 1 choice [ 0.516131] hub 2-0:1.0: USB hub found [ 0.516136] hub 2-0:1.0: 6 ports detected [ 0.516184] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 0.516198] uhci_hcd: USB Universal Host Controller Interface driver [ 0.516214] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 0.516219] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 0.516222] uhci_hcd 0000:00:1a.0: UHCI Host Controller [ 0.516248] uhci_hcd 0000:00:1a.0: new USB bus registered, assigned bus number 3 [ 0.516274] uhci_hcd 0000:00:1a.0: irq 16, io base 0x0000a800 [ 0.516348] usb usb3: configuration #1 chosen from 1 choice [ 0.516370] hub 3-0:1.0: USB hub found [ 0.516376] hub 3-0:1.0: 2 ports detected [ 0.516414] alloc irq_desc for 21 on node -1 [ 0.516416] alloc kstat_irqs on node -1 [ 0.516420] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 0.516425] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 0.516427] uhci_hcd 0000:00:1a.1: UHCI Host Controller [ 0.516455] uhci_hcd 0000:00:1a.1: new USB bus registered, assigned bus number 4 [ 0.516482] uhci_hcd 0000:00:1a.1: irq 21, io base 0x0000a880 [ 0.516552] usb usb4: configuration #1 chosen from 1 choice [ 0.516576] hub 4-0:1.0: USB hub found [ 0.516581] hub 4-0:1.0: 2 ports detected [ 0.516617] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 0.516622] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 0.516625] uhci_hcd 0000:00:1a.2: UHCI Host Controller [ 0.516650] uhci_hcd 0000:00:1a.2: new USB bus registered, assigned bus number 5 [ 0.516670] uhci_hcd 0000:00:1a.2: irq 18, io base 0x0000ac00 [ 0.516742] usb usb5: configuration #1 chosen from 1 choice [ 0.516766] hub 5-0:1.0: USB hub found [ 0.516771] hub 5-0:1.0: 2 ports detected [ 0.516809] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 0.516814] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 0.516817] uhci_hcd 0000:00:1d.0: UHCI Host Controller [ 0.516847] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 6 [ 0.516867] uhci_hcd 0000:00:1d.0: irq 23, io base 0x0000a080 [ 0.516947] usb usb6: configuration #1 chosen from 1 choice [ 0.516969] hub 6-0:1.0: USB hub found [ 0.516974] hub 6-0:1.0: 2 ports detected [ 0.517010] alloc irq_desc for 19 on node -1 [ 0.517011] alloc kstat_irqs on node -1 [ 0.517015] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 0.517020] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 0.517022] uhci_hcd 0000:00:1d.1: UHCI Host Controller [ 0.517048] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 7 [ 0.517074] uhci_hcd 0000:00:1d.1: irq 19, io base 0x0000a400 [ 0.517150] usb usb7: configuration #1 chosen from 1 choice [ 0.517172] hub 7-0:1.0: USB hub found [ 0.517178] hub 7-0:1.0: 2 ports detected [ 0.517213] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 0.517218] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 0.517221] uhci_hcd 0000:00:1d.2: UHCI Host Controller [ 0.517252] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 8 [ 0.517272] uhci_hcd 0000:00:1d.2: irq 18, io base 0x0000a480 [ 0.517344] usb usb8: configuration #1 chosen from 1 choice [ 0.517366] hub 8-0:1.0: USB hub found [ 0.517371] hub 8-0:1.0: 2 ports detected [ 0.517455] PNP: PS/2 Controller [PNP0303:PS2K] at 0x60,0x64 irq 1 [ 0.517457] PNP: PS/2 appears to have AUX port disabled, if this is incorrect please boot with i8042.nopnp [ 0.517953] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 0.518015] mice: PS/2 mouse device common for all mice [ 0.518101] rtc_cmos 00:03: RTC can wake from S4 [ 0.518129] rtc_cmos 00:03: rtc core: registered rtc_cmos as rtc0 [ 0.518151] rtc0: alarms up to one month, y3k, 114 bytes nvram, hpet irqs [ 0.518233] device-mapper: uevent: version 1.0.3 [ 0.518338] device-mapper: ioctl: 4.15.0-ioctl (2009-04-01) initialised: dm-devel@redhat.com [ 0.518453] device-mapper: multipath: version 1.1.0 loaded [ 0.518455] device-mapper: multipath round-robin: version 1.0.0 loaded [ 0.518553] EISA: Probing bus 0 at eisa.0 [ 0.518558] Cannot allocate resource for EISA slot 1 [ 0.518575] Cannot allocate resource for EISA slot 8 [ 0.518577] EISA: Detected 0 cards. [ 0.518730] cpuidle: using governor ladder [ 0.518732] cpuidle: using governor menu [ 0.519109] TCP cubic registered [ 0.519239] NET: Registered protocol family 10 [ 0.519645] lo: Disabled Privacy Extensions [ 0.519938] NET: Registered protocol family 17 [ 0.520657] Using IPI No-Shortcut mode [ 0.520716] PM: Resume from disk failed. [ 0.520725] registered taskstats version 1 [ 0.521158] Magic number: 10:778:151 [ 0.521167] bdi 1:3: hash matches [ 0.521213] rtc_cmos 00:03: setting system clock to 2010-09-30 23:08:49 UTC (1285888129) [ 0.521215] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found [ 0.521217] EDD information not available. [ 0.609783] Freeing initrd memory: 12205k freed [ 0.807984] ata4: SATA link down (SStatus 0 SControl 300) [ 0.818523] ata3: SATA link down (SStatus 0 SControl 300) [ 0.823593] isapnp: No Plug & Play device found [ 0.884360] usb 2-2: new high speed USB device using ehci_hcd and address 3 [ 1.024045] usb 2-2: configuration #1 chosen from 1 choice [ 1.136030] usb 2-3: new high speed USB device using ehci_hcd and address 4 [ 1.284082] ata1.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1.284097] ata1.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1.284281] ata2.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 1.284293] ata2.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 1.308565] ata2.00: ATA-8: ST3500320AS, SD1A, max UDMA/133 [ 1.308569] ata2.00: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32) [ 1.308616] ata2.01: ATAPI: LITE-ON DVDRW LH-20A1S, 9L09, max UDMA/100 [ 1.324616] ata2.00: configured for UDMA/133 [ 1.326464] ata1.00: ATA-8: ST31000528AS, CC34, max UDMA/133 [ 1.326468] ata1.00: 1953525168 sectors, multi 16: LBA48 NCQ (depth 0/32) [ 1.356251] ata2.01: configured for UDMA/100 [ 1.366882] ata1.01: ATA-7: ST3500630AS, 3.AAK, max UDMA/133 [ 1.366885] ata1.01: 976773168 sectors, multi 16: LBA48 NCQ (depth 0/32) [ 1.396081] usb 2-3: configuration #1 chosen from 1 choice [ 1.423279] ata1.00: configured for UDMA/133 [ 1.466863] ata1.01: configured for UDMA/133 [ 1.467017] scsi 0:0:0:0: Direct-Access ATA ST31000528AS CC34 PQ: 0 ANSI: 5 [ 1.467184] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 1.467202] sd 0:0:0:0: [sda] 1953525168 512-byte logical blocks: (1.00 TB/931 GiB) [ 1.467246] sd 0:0:0:0: [sda] Write Protect is off [ 1.467249] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 [ 1.467270] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.467291] scsi 0:0:1:0: Direct-Access ATA ST3500630AS 3.AA PQ: 0 ANSI: 5 [ 1.467402] sd 0:0:1:0: Attached scsi generic sg1 type 0 [ 1.467439] sd 0:0:1:0: [sdb] 976773168 512-byte logical blocks: (500 GB/465 GiB) [ 1.467442] sda: [ 1.467569] scsi 1:0:0:0: Direct-Access ATA ST3500320AS SD1A PQ: 0 ANSI: 5 [ 1.467676] sd 1:0:0:0: Attached scsi generic sg2 type 0 [ 1.468877] sd 1:0:0:0: [sdc] 976773168 512-byte logical blocks: (500 GB/465 GiB) [ 1.468885] scsi 1:0:1:0: CD-ROM LITE-ON DVDRW LH-20A1S 9L09 PQ: 0 ANSI: 5 [ 1.468937] sd 1:0:0:0: [sdc] Write Protect is off [ 1.468941] sd 1:0:0:0: [sdc] Mode Sense: 00 3a 00 00 [ 1.470294] sd 1:0:0:0: [sdc] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.471969] sda1 [ 1.472002] sd 0:0:1:0: [sdb] Write Protect is off [ 1.472006] sd 0:0:1:0: [sdb] Mode Sense: 00 3a 00 00 [ 1.472037] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.472334] sdb: [ 1.472356] sd 0:0:0:0: [sda] Attached SCSI disk [ 1.474470] sr0: scsi3-mmc drive: 48x/48x writer dvd-ram cd/rw xa/form2 cdda tray [ 1.474474] Uniform CD-ROM driver Revision: 3.20 [ 1.474542] sdc: [ 1.474594] sr 1:0:1:0: Attached scsi CD-ROM sr0 [ 1.474650] sr 1:0:1:0: Attached scsi generic sg3 type 5 [ 1.501392] ldm_parse_tocblock(): Cannot find TOCBLOCK, database may be corrupt. [ 1.501398] ldm_parse_tocblock(): Cannot find TOCBLOCK, database may be corrupt. [ 1.506532] sdb1 sdb2 < sdb5 > [ 1.528000] sd 0:0:1:0: [sdb] Attached SCSI disk [ 1.529093] [LDM] sdc1 [ 1.529570] sd 1:0:0:0: [sdc] Attached SCSI disk [ 1.529607] Freeing unused kernel memory: 672k freed [ 1.529911] Write protecting the kernel text: 4836k [ 1.530011] Write protecting the kernel read-only data: 1880k [ 1.547231] udev: starting version 151 [ 1.564023] usb 2-5: new high speed USB device using ehci_hcd and address 6 [ 1.583050] pata_jmicron 0000:03:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 1.583089] pata_jmicron 0000:03:00.1: setting latency timer to 64 [ 1.585629] sky2 driver version 1.25 [ 1.585659] sky2 0000:02:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 1.585670] sky2 0000:02:00.0: setting latency timer to 64 [ 1.585698] sky2 0000:02:00.0: Yukon-2 EC Ultra chip revision 3 [ 1.585784] alloc irq_desc for 28 on node -1 [ 1.585786] alloc kstat_irqs on node -1 [ 1.585798] sky2 0000:02:00.0: irq 28 for MSI/MSI-X [ 1.586505] sky2 eth0: addr 00:1b:fc:66:ba:27 [ 1.587966] scsi4 : pata_jmicron [ 1.591413] scsi5 : pata_jmicron [ 1.592145] ata5: PATA max UDMA/100 cmd 0xdc00 ctl 0xd880 bmdma 0xd400 irq 17 [ 1.592148] ata6: PATA max UDMA/100 cmd 0xd800 ctl 0xd480 bmdma 0xd408 irq 17 [ 1.592302] ahci 0000:03:00.0: version 3.0 [ 1.592322] ahci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1.609068] ahci 0000:03:00.0: AHCI 0001.0000 32 slots 2 ports 3 Gbps 0x3 impl SATA mode [ 1.609072] ahci 0000:03:00.0: flags: 64bit ncq pm led clo pmp pio slum part [ 1.609078] ahci 0000:03:00.0: setting latency timer to 64 [ 1.610511] Linux agpgart interface v0.103 [ 1.613260] skge 0000:05:02.0: PCI INT A -> GSI 18 (level, low) -> IRQ 18 [ 1.613299] skge 1.13 addr 0xfebfc000 irq 18 chip Yukon-Lite rev 9 [ 1.614020] skge eth1: addr 00:1e:58:48:86:c0 [ 1.615791] scsi6 : ahci [ 1.615876] scsi7 : ahci [ 1.615998] ata7: SATA max UDMA/133 abar m8192@0xfeafe000 port 0xfeafe100 irq 16 [ 1.616035] ata8: SATA max UDMA/133 abar m8192@0xfeafe000 port 0xfeafe180 irq 16 [ 1.700553] usb 2-5: configuration #1 chosen from 1 choice [ 1.700751] hub 2-5:1.0: USB hub found [ 1.700832] hub 2-5:1.0: 2 ports detected [ 1.940048] ata8: SATA link down (SStatus 0 SControl 300) [ 1.941049] ata7: SATA link down (SStatus 0 SControl 300) [ 1.941068] usb 6-1: new low speed USB device using uhci_hcd and address 2 [ 1.959552] ohci1394 0000:05:03.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19 [ 2.014077] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[19] MMIO=[febfb000-febfb7ff] Max Packet=[2048] IR/IT contexts=[8/8] [ 2.021151] r8169 Gigabit Ethernet driver 2.3LK-NAPI loaded [ 2.021175] r8169 0000:05:04.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 2.021193] r8169 0000:05:04.0: no PCI Express capability [ 2.021876] eth2: RTL8169sc/8110sc at 0xf832ec00, 00:1b:fc:66:b2:b6, XID 18000000 IRQ 16 [ 2.057685] vesafb: framebuffer at 0xfb000000, mapped to 0xf8400000, using 3072k, total 3072k [ 2.057688] vesafb: mode is 1024x768x32, linelength=4096, pages=0 [ 2.057689] vesafb: scrolling: redraw [ 2.057692] vesafb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 2.057741] fb0: VESA VGA frame buffer device [ 2.061949] Console: switching to colour frame buffer device 128x48 [ 2.222970] usb 6-1: configuration #1 chosen from 1 choice [ 2.245665] usbcore: registered new interface driver hiddev [ 2.295125] input: Chicony Saitek Eclipse Keyboard as /devices/pci0000:00/0000:00:1d.0/usb6/6-1/6-1:1.0/input/input3 [ 2.295193] generic-usb 0003:06A3:8020.0001: input,hidraw0: USB HID v1.11 Keyboard [Chicony Saitek Eclipse Keyboard] on usb-0000:00:1d.0-1/input0 [ 2.406982] input: Chicony Saitek Eclipse Keyboard as /devices/pci0000:00/0000:00:1d.0/usb6/6-1/6-1:1.1/input/input4 [ 2.407098] generic-usb 0003:06A3:8020.0002: input,hiddev96,hidraw1: USB HID v1.11 Device [Chicony Saitek Eclipse Keyboard] on usb-0000:00:1d.0-1/input1 [ 2.407125] usbcore: registered new interface driver usbhid [ 2.407127] usbhid: v2.6:USB HID core driver [ 2.449898] EXT4-fs (sdb1): mounted filesystem with ordered data mode [ 2.468060] usb 7-2: new low speed USB device using uhci_hcd and address 2 [ 2.649198] usb 7-2: configuration #1 chosen from 1 choice [ 2.676355] input: Logitech USB Receiver as /devices/pci0000:00/0000:00:1d.1/usb7/7-2/7-2:1.0/input/input5 [ 2.676444] generic-usb 0003:046D:C51B.0003: input,hidraw2: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-0000:00:1d.1-2/input0 [ 2.690171] generic-usb 0003:046D:C51B.0004: hiddev97,hidraw3: USB HID v1.11 Device [Logitech USB Receiver] on usb-0000:00:1d.1-2/input1 [ 2.777195] usb 2-5.1: new high speed USB device using ehci_hcd and address 7 [ 2.872518] usb 2-5.1: configuration #1 chosen from 1 choice [ 2.872629] hub 2-5.1:1.0: USB hub found [ 2.872692] hub 2-5.1:1.0: 4 ports detected [ 3.144205] usb 2-5.1.1: new high speed USB device using ehci_hcd and address 8 [ 3.293218] ieee1394: Host added: ID:BUS[0-00:1023] GUID[0011d800014f55a4] [ 3.326057] usb 2-5.1.1: configuration #1 chosen from 1 choice [ 3.412191] usb 2-5.1.2: new full speed USB device using ehci_hcd and address 9 [ 3.505801] usb 2-5.1.2: configuration #1 chosen from 1 choice [ 4.474221] Adding 9831740k swap on /dev/sdb5. Priority:-1 extents:1 across:9831740k [ 5.248352] udev: starting version 151 [ 7.055476] type=1505 audit(1285913336.030:2): operation="profile_load" pid=619 name="/sbin/dhclient3" [ 7.056055] type=1505 audit(1285913336.030:3): operation="profile_load" pid=619 name="/usr/lib/NetworkManager/nm-dhcp-client.action" [ 7.056363] type=1505 audit(1285913336.034:4): operation="profile_load" pid=619 name="/usr/lib/connman/scripts/dhclient-script" [ 7.339390] type=1505 audit(1285913336.314:5): operation="profile_load" pid=633 name="/usr/sbin/ntpd" [ 9.173005] Initializing USB Mass Storage driver... [ 9.173323] scsi8 : SCSI emulation for USB Mass Storage devices [ 9.173742] usb-storage: device found at 8 [ 9.173756] usb-storage: waiting for device to settle before scanning [ 9.176090] scsi9 : SCSI emulation for USB Mass Storage devices [ 9.176195] usbcore: registered new interface driver usb-storage [ 9.176207] USB Mass Storage support registered. [ 9.177233] usb-storage: device found at 9 [ 9.177235] usb-storage: waiting for device to settle before scanning [ 9.223669] Linux video capture interface: v2.00 [ 9.229814] uvcvideo: Found UVC 1.00 device (046d:0990) [ 9.246153] input: UVC Camera (046d:0990) as /devices/pci0000:00/0000:00:1d.7/usb2/2-3/2-3:1.0/input/input6 [ 9.246256] usbcore: registered new interface driver uvcvideo [ 9.246259] USB Video Class driver (v0.1.0) [ 9.691565] lp: driver loaded but no devices found [ 9.863518] vga16fb: initializing [ 9.863522] vga16fb: mapped to 0xc00a0000 [ 9.863526] vga16fb: not registering due to another framebuffer present [ 10.938169] nvidia: module license 'NVIDIA' taints kernel. [ 10.938173] Disabling lock debugging due to kernel taint [ 11.991058] nvidia 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 11.991068] nvidia 0000:01:00.0: setting latency timer to 64 [ 11.991072] vgaarb: device changed decodes: PCI:0000:01:00.0,olddecodes=io+mem,decodes=none:owns=io+mem [ 11.991190] NVRM: loading NVIDIA UNIX x86 Kernel Module 260.19.06 Mon Sep 13 06:35:06 PDT 2010 [ 12.090410] RPC: Registered udp transport module. [ 12.090413] RPC: Registered tcp transport module. [ 12.090415] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 12.101086] usblp0: USB Bidirectional printer dev 3 if 0 alt 0 proto 2 vid 0x04B8 pid 0x0007 [ 12.103976] usblp1: USB Bidirectional printer dev 9 if 0 alt 0 proto 2 vid 0x04F9 pid 0x01AB [ 12.104015] usbcore: registered new interface driver usblp [ 12.291702] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 12.291725] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 12.327172] usbcore: registered new interface driver snd-usb-audio [ 12.907229] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/input/input7 [ 13.423975] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). [ 14.177520] usb-storage: device scan complete [ 14.178382] usb-storage: device scan complete [ 14.182518] scsi 8:0:0:0: Direct-Access Generic Flash HS-CF 5.39 PQ: 0 ANSI: 0 [ 14.185759] scsi 8:0:0:1: Direct-Access Generic Flash HS-COMBO 5.39 PQ: 0 ANSI: 0 [ 14.186009] scsi 9:0:0:0: Direct-Access Brother MFC-240C 1.00 PQ: 0 ANSI: 2 [ 14.186547] sd 9:0:0:0: Attached scsi generic sg4 type 0 [ 14.186836] sd 8:0:0:0: Attached scsi generic sg5 type 0 [ 14.189959] sd 8:0:0:1: Attached scsi generic sg6 type 0 [ 14.202608] sd 8:0:0:1: [sdf] Attached SCSI removable disk [ 14.230117] sd 8:0:0:0: [sde] Attached SCSI removable disk [ 14.285620] sd 9:0:0:0: [sdd] Attached SCSI removable disk [ 15.268361] type=1505 audit(1285913344.247:6): operation="profile_load" pid=1045 name="/usr/share/gdm/guest-session/Xsession" [ 15.319675] type=1505 audit(1285913344.295:7): operation="profile_replace" pid=1046 name="/sbin/dhclient3" [ 15.320254] type=1505 audit(1285913344.295:8): operation="profile_replace" pid=1046 name="/usr/lib/NetworkManager/nm-dhcp-client.action" [ 15.320591] type=1505 audit(1285913344.299:9): operation="profile_replace" pid=1046 name="/usr/lib/connman/scripts/dhclient-script" [ 15.531569] type=1505 audit(1285913344.507:10): operation="profile_load" pid=1047 name="/usr/bin/evince" [ 15.538899] type=1505 audit(1285913344.515:11): operation="profile_load" pid=1047 name="/usr/bin/evince-previewer" [ 15.543558] type=1505 audit(1285913344.519:12): operation="profile_load" pid=1047 name="/usr/bin/evince-thumbnailer" [ 15.570058] type=1505 audit(1285913344.547:13): operation="profile_load" pid=1049 name="/usr/bin/freshclam" [ 15.577786] type=1505 audit(1285913344.555:14): operation="profile_load" pid=1050 name="/usr/sbin/clamd" [ 15.594610] type=1505 audit(1285913344.571:15): operation="profile_load" pid=1052 name="/usr/lib/cups/backend/cups-pdf" [ 17.148777] sky2 eth0: enabling interface [ 17.148940] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 17.162489] skge eth1: enabling interface [ 17.165684] ADDRCONF(NETDEV_UP): eth1: link is not ready [ 17.166993] r8169: eth2: link down [ 17.167155] ADDRCONF(NETDEV_UP): eth2: link is not ready [ 19.509678] skge eth1: Link is up at 1000 Mbps, full duplex, flow control both [ 19.509840] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 19.781561] ata2.00: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x6 frozen [ 19.781567] ata2.00: failed command: SET FEATURES [ 19.781574] ata2.00: cmd ef/05:fe:00:00:00/00:00:00:00:00/40 tag 0 [ 19.781575] res 40/00:01:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout) [ 19.781579] ata2.00: status: { DRDY } [ 19.781588] ata2.00: hard resetting link [ 20.104037] ata2.01: hard resetting link [ 20.580107] ata2.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 20.580122] ata2.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 20.631447] ata2.00: configured for UDMA/133 [ 20.660255] ata2.01: configured for UDMA/100 [ 20.660970] ata2: EH complete [ 30.160335] eth1: no IPv6 routers present [ 34.641386] vboxdrv: Trying to deactivate the NMI watchdog permanently... [ 34.641389] vboxdrv: Successfully done. [ 34.641391] vboxdrv: Found 4 processor cores. [ 34.641521] vboxdrv: fAsync=0 offMin=0x4da offMax=0x3d6b [ 34.641834] vboxdrv: TSC mode is 'synchronous', kernel timer mode is 'normal'. [ 34.641836] vboxdrv: Successfully loaded version 3.1.8 (interface 0x00100001). [ 48.986193] ppdev: user-space parallel port driver [ 53.734766] usb 2-5.1.2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [ 53.734795] usb 2-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [ 56.044166] usb 2-5.1.2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [ 56.044221] usb 2-2: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [ 9330.160017] sky2 eth0: disabling interface [ 9330.400012] skge eth1: disabling interface [ 9330.610483] sky2 eth0: enabling interface [ 9330.610721] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 9330.636522] sky2 eth0: disabling interface [ 9330.644407] sky2 eth0: enabling interface [ 9330.644660] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 9330.708521] sky2 eth0: disabling interface [ 9330.716042] sky2 eth0: enabling interface [ 9330.716275] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 9333.913905] PM: Syncing filesystems ... done. [ 9333.918046] PM: Preparing system for mem sleep [ 9333.918049] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 9333.919966] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 9333.920027] PM: Entering mem sleep [ 9333.920038] Suspending console(s) (use no_console_suspend to debug) [ 9334.028064] sd 1:0:0:0: [sdc] Synchronizing SCSI cache [ 9334.028379] sd 1:0:0:0: [sdc] Stopping disk [ 9334.028979] sd 0:0:1:0: [sdb] Synchronizing SCSI cache [ 9334.062927] sd 0:0:1:0: [sdb] Stopping disk [ 9334.300060] PM: suspend of drv:sd dev:0:0:1:0 complete after 271.080 msecs [ 9334.300071] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 9334.300244] sd 0:0:0:0: [sda] Stopping disk [ 9334.833531] PM: suspend of drv:sd dev:0:0:0:0 complete after 533.461 msecs [ 9334.864535] ACPI handle has no context! [ 9334.865086] serial 00:09: disabled [ 9334.869134] ACPI handle has no context! [ 9334.884727] skge 0000:05:02.0: PME# enabled [ 9334.884734] pci 0000:00:1e.0: wake-up capability enabled by ACPI [ 9334.900024] sky2 eth0: disabling interface [ 9334.901432] ACPI handle has no context! [ 9334.901438] ACPI handle has no context! [ 9334.916165] pata_jmicron 0000:03:00.1: PCI INT B disabled [ 9334.964143] ahci 0000:03:00.0: PCI INT A disabled [ 9335.002813] ata_piix 0000:00:1f.5: PCI INT B disabled [ 9335.016136] ata_piix 0000:00:1f.2: PCI INT B disabled [ 9335.032039] ehci_hcd 0000:00:1d.7: PCI INT A disabled [ 9335.032048] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 9335.032055] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 9335.032062] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 9335.136115] HDA Intel 0000:00:1b.0: PCI INT A disabled [ 9335.136156] ACPI handle has no context! [ 9335.152030] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.953 msecs [ 9335.152038] ehci_hcd 0000:00:1a.7: PCI INT C disabled [ 9335.152046] uhci_hcd 0000:00:1a.2: PCI INT C disabled [ 9335.152053] uhci_hcd 0000:00:1a.1: PCI INT B disabled [ 9335.152061] uhci_hcd 0000:00:1a.0: PCI INT A disabled [ 9335.152139] PM: suspend of devices complete after 1231.709 msecs [ 9335.152142] PM: suspend devices took 1.232 seconds [ 9335.152261] r8169 0000:05:04.0: PME# enabled [ 9335.152264] pci 0000:00:1e.0: wake-up capability enabled by ACPI [ 9335.200141] PM: late suspend of devices complete after 47.994 msecs [ 9335.200652] ACPI: Preparing to enter system sleep state S3 [ 9335.217028] Disabling non-boot CPUs ... [ 9335.217052] CPU0 attaching NULL sched-domain. [ 9335.217056] CPU1 attaching NULL sched-domain. [ 9335.217059] CPU2 attaching NULL sched-domain. [ 9335.217062] CPU3 attaching NULL sched-domain. [ 9335.281031] CPU0 attaching NULL sched-domain. [ 9335.282222] Breaking affinity for irq 18 [ 9335.283352] CPU 1 is now offline [ 9335.286307] CPU 2 is now offline [ 9335.287996] Breaking affinity for irq 16 [ 9335.288009] Breaking affinity for irq 22 [ 9335.288012] Breaking affinity for irq 23 [ 9335.392029] CPU 3 is now offline [ 9335.392031] SMP alternatives: switching to UP code [ 9335.398894] Back to C! [ 9335.398894] CPU0: Thermal monitoring enabled (TM2) [ 9335.398894] Enabling non-boot CPUs ... [ 9335.398894] SMP alternatives: switching to SMP code [ 9335.405574] Booting processor 1 APIC 0x1 ip 0x6000 [ 9335.283501] Initializing CPU#1 [ 9335.283501] CPU: L1 I cache: 32K, L1 D cache: 32K [ 9335.283501] CPU: L2 cache: 4096K [ 9335.283501] CPU: Physical Processor ID: 0 [ 9335.283501] CPU: Processor Core ID: 1 [ 9335.283501] CPU1: Thermal monitoring enabled (TM2) [ 9335.496105] CPU1: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [ 9335.496112] checking TSC synchronization [CPU#0 -> CPU#1]: passed. [ 9335.516199] CPU0 attaching NULL sched-domain. [ 9335.544052] CPU0 attaching sched-domain: [ 9335.544056] domain 0: span 0-1 level MC [ 9335.544059] groups: 0 1 [ 9335.544064] CPU1 attaching sched-domain: [ 9335.544066] domain 0: span 0-1 level MC [ 9335.544069] groups: 1 0 [ 9335.548018] CPU1 is up [ 9335.548246] Booting processor 2 APIC 0x2 ip 0x6000 [ 9335.286484] Initializing CPU#2 [ 9335.286484] CPU: L1 I cache: 32K, L1 D cache: 32K [ 9335.286484] CPU: L2 cache: 4096K [ 9335.286484] CPU: Physical Processor ID: 0 [ 9335.286484] CPU: Processor Core ID: 2 [ 9335.286484] CPU2: Thermal monitoring enabled (TM2) [ 9335.636093] CPU2: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [ 9335.636101] checking TSC synchronization [CPU#0 -> CPU#2]: passed. [ 9335.656147] CPU0 attaching NULL sched-domain. [ 9335.656150] CPU1 attaching NULL sched-domain. [ 9335.708053] CPU0 attaching sched-domain: [ 9335.708055] domain 0: span 0-1 level MC [ 9335.708058] groups: 0 1 [ 9335.708062] domain 1: span 0-2 level CPU [ 9335.708065] groups: 0-1 (cpu_power = 2048) 2 [ 9335.708071] CPU1 attaching sched-domain: [ 9335.708074] domain 0: span 0-1 level MC [ 9335.708076] groups: 1 0 [ 9335.708080] domain 1: span 0-2 level CPU [ 9335.708082] groups: 0-1 (cpu_power = 2048) 2 [ 9335.708088] CPU2 attaching sched-domain: [ 9335.708090] domain 0: span 0-2 level CPU [ 9335.708093] groups: 2 0-1 (cpu_power = 2048) [ 9335.708831] CPU2 is up [ 9335.709268] Booting processor 3 APIC 0x3 ip 0x6000 [ 9335.398626] Initializing CPU#3 [ 9335.398626] CPU: L1 I cache: 32K, L1 D cache: 32K [ 9335.398626] CPU: L2 cache: 4096K [ 9335.398626] CPU: Physical Processor ID: 0 [ 9335.398626] CPU: Processor Core ID: 3 [ 9335.398626] CPU3: Thermal monitoring enabled (TM2) [ 9335.800093] CPU3: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [ 9335.800103] checking TSC synchronization [CPU#0 -> CPU#3]: passed. [ 9335.820170] CPU0 attaching NULL sched-domain. [ 9335.820172] CPU1 attaching NULL sched-domain. [ 9335.820175] CPU2 attaching NULL sched-domain. [ 9335.868054] CPU0 attaching sched-domain: [ 9335.868057] domain 0: span 0-1 level MC [ 9335.868060] groups: 0 1 [ 9335.868064] domain 1: span 0-3 level CPU [ 9335.868067] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [ 9335.868074] CPU1 attaching sched-domain: [ 9335.868076] domain 0: span 0-1 level MC [ 9335.868078] groups: 1 0 [ 9335.868082] domain 1: span 0-3 level CPU [ 9335.868085] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [ 9335.868091] CPU2 attaching sched-domain: [ 9335.868093] domain 0: span 2-3 level MC [ 9335.868096] groups: 2 3 [ 9335.868100] domain 1: span 0-3 level CPU [ 9335.868102] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [ 9335.868109] CPU3 attaching sched-domain: [ 9335.868111] domain 0: span 2-3 level MC [ 9335.868113] groups: 3 2 [ 9335.868117] domain 1: span 0-3 level CPU [ 9335.868119] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [ 9335.869045] CPU3 is up [ 9335.870827] ACPI: Waking up from system sleep state S3 [ 9335.871630] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [ 9335.871641] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 9335.871675] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 9335.871703] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 9335.871730] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 9335.871764] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 9335.871800] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [ 9335.871820] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [ 9335.871829] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xf030f000) [ 9335.871833] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0x1010) [ 9335.871841] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [ 9335.871871] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [ 9335.871879] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf051f041) [ 9335.871888] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 9335.871918] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x2020b) [ 9335.871926] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf071f061) [ 9335.871936] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 9335.871975] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 9335.872002] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 9335.872047] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 9335.872081] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [ 9335.872100] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [ 9335.872108] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf081f081) [ 9335.872230] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe8e0000, writing 0x0) [ 9335.872240] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0) [ 9335.872275] ahci 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 9335.872284] ahci 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfeae0000) [ 9335.872292] ahci 0000:03:00.0: restoring config space at offset 0x9 (was 0x0, writing 0xfeafe000) [ 9335.872298] ahci 0000:03:00.0: restoring config space at offset 0x8 (was 0x1, writing 0x0) [ 9335.872303] ahci 0000:03:00.0: restoring config space at offset 0x7 (was 0x1, writing 0x0) [ 9335.872308] ahci 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x0) [ 9335.872313] ahci 0000:03:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x0) [ 9335.872318] ahci 0000:03:00.0: restoring config space at offset 0x4 (was 0x1, writing 0x0) [ 9335.872323] ahci 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x800008) [ 9335.872329] ahci 0000:03:00.0: restoring config space at offset 0x2 (was 0x1018503, writing 0x1060103) [ 9335.872334] ahci 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [ 9335.872382] pata_jmicron 0000:03:00.1: restoring config space at offset 0x8 (was 0x1, writing 0xd401) [ 9335.872387] pata_jmicron 0000:03:00.1: restoring config space at offset 0x7 (was 0x1, writing 0xd481) [ 9335.872392] pata_jmicron 0000:03:00.1: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [ 9335.872398] pata_jmicron 0000:03:00.1: restoring config space at offset 0x5 (was 0x1, writing 0xd881) [ 9335.872403] pata_jmicron 0000:03:00.1: restoring config space at offset 0x4 (was 0x1, writing 0xdc01) [ 9335.872411] pata_jmicron 0000:03:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100005) [ 9335.872445] sky2 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [ 9335.872452] sky2 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [ 9335.872464] sky2 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [ 9335.872471] sky2 0000:02:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [ 9335.872475] sky2 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [ 9335.872482] sky2 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [ 9335.872526] skge 0000:05:02.0: restoring config space at offset 0xf (was 0x1f170100, writing 0x1f170105) [ 9335.872532] skge 0000:05:02.0: restoring config space at offset 0xc (was 0x0, writing 0xfebc0000) [ 9335.872543] skge 0000:05:02.0: restoring config space at offset 0x5 (was 0x1, writing 0xe801) [ 9335.872547] skge 0000:05:02.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfc000) [ 9335.872552] skge 0000:05:02.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [ 9335.872557] skge 0000:05:02.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [ 9335.872575] ohci1394 0000:05:03.0: restoring config space at offset 0xf (was 0x180c0100, writing 0x180c010e) [ 9335.872590] ohci1394 0000:05:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfb000) [ 9335.872595] ohci1394 0000:05:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [ 9335.872600] ohci1394 0000:05:03.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900016) [ 9335.872616] r8169 0000:05:04.0: restoring config space at offset 0xf (was 0x40200100, writing 0x4020010b) [ 9335.872622] r8169 0000:05:04.0: restoring config space at offset 0xc (was 0x0, writing 0xfeba0000) [ 9335.872633] r8169 0000:05:04.0: restoring config space at offset 0x5 (was 0x0, writing 0xfebfac00) [ 9335.872637] r8169 0000:05:04.0: restoring config space at offset 0x4 (was 0x1, writing 0xe401) [ 9335.872642] r8169 0000:05:04.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [ 9335.872647] r8169 0000:05:04.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [ 9335.872708] PM: early resume of devices complete after 1.115 msecs [ 9335.872781] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 9335.872787] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [ 9335.872806] usb usb3: root hub lost power or was reset [ 9335.872821] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [ 9335.872826] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [ 9335.872843] usb usb4: root hub lost power or was reset [ 9335.872857] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 9335.872862] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [ 9335.872879] usb usb5: root hub lost power or was reset [ 9335.872894] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 9335.872899] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [ 9335.872906] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 9335.872911] HDA Intel 0000:00:1b.0: setting latency timer to 64 [ 9336.312194] PM: resume of drv:HDA Intel dev:0000:00:1b.0 complete after 439.286 msecs [ 9336.312211] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 9336.312217] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 9336.312249] usb usb6: root hub lost power or was reset [ 9336.312273] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [ 9336.312278] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 9336.312296] usb usb7: root hub lost power or was reset [ 9336.312318] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 9336.312323] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 9336.312342] usb usb8: root hub lost power or was reset [ 9336.312359] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [ 9336.312364] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 9336.312375] pci 0000:00:1e.0: setting latency timer to 64 [ 9336.312382] ata_piix 0000:00:1f.2: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [ 9336.312385] ata_piix 0000:00:1f.2: setting latency timer to 64 [ 9336.317346] ata_piix 0000:00:1f.5: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [ 9336.317350] ata_piix 0000:00:1f.5: setting latency timer to 64 [ 9336.827021] ata3: SATA link down (SStatus 0 SControl 300) [ 9336.838056] ata4: SATA link down (SStatus 0 SControl 300) [ 9342.288131] ata2.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 9342.288158] ata2.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 9342.312526] ata2.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [ 9342.312529] ata2.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [ 9342.312532] ata2.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [ 9342.336304] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [ 9342.336307] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [ 9342.336648] ata2.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [ 9342.336652] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [ 9342.344029] ata1.00: link is slow to respond, please be patient (ready=0) [ 9342.368728] ata2.00: configured for UDMA/133 [ 9342.390968] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 6071.622 msecs [ 9342.391011] ahci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 9342.391018] ahci 0000:03:00.0: setting latency timer to 64 [ 9342.400249] ata2.01: configured for UDMA/100 [ 9342.404224] pata_jmicron 0000:03:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 9342.404231] pata_jmicron 0000:03:00.1: setting latency timer to 64 [ 9342.407750] sky2 eth0: enabling interface [ 9342.407768] pci 0000:00:1e.0: wake-up capability disabled by ACPI [ 9342.407772] skge 0000:05:02.0: PME# disabled [ 9342.424593] ata2.00: configured for UDMA/133 [ 9342.456255] ata2.01: configured for UDMA/100 [ 9342.456258] ata2: EH complete [ 9342.462041] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[19] MMIO=[febfb000-febfb7ff] Max Packet=[2048] IR/IT contexts=[8/8] [ 9342.471057] pci 0000:00:1e.0: wake-up capability disabled by ACPI [ 9342.471062] r8169 0000:05:04.0: PME# disabled [ 9342.472197] serial 00:09: activated [ 9342.724046] ata8: SATA link down (SStatus 0 SControl 300) [ 9342.724075] ata7: SATA link down (SStatus 0 SControl 300) [ 9342.800028] PM: resume of drv:usb dev:usb6 complete after 247.968 msecs [ 9343.048029] PM: resume of drv:usb dev:usb7 complete after 247.992 msecs [ 9343.153597] sd 0:0:0:0: [sda] Starting disk [ 9344.473071] ata1.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [ 9344.473085] ata1.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 9344.545208] ata1.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [ 9344.545213] ata1.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [ 9344.545312] ata1.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded [ 9344.545317] ata1.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [ 9344.592214] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [ 9344.592218] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [ 9344.592398] ata1.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [ 9344.592402] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [ 9344.682279] ata1.00: configured for UDMA/133 [ 9344.734864] ata1.01: configured for UDMA/133 [ 9344.882277] ata1.00: configured for UDMA/133 [ 9344.926444] ata1.01: configured for UDMA/133 [ 9344.926447] ata1: EH complete [ 9344.939049] PM: resume of drv:sd dev:0:0:0:0 complete after 1785.453 msecs [ 9344.939058] sd 0:0:1:0: [sdb] Starting disk [ 9344.966046] sd 1:0:0:0: [sdc] Starting disk [ 9345.296029] usb 6-1: reset low speed USB device using uhci_hcd and address 2 [ 9345.674015] PM: resume of drv:usb dev:6-1 complete after 637.555 msecs [ 9345.932027] usb 7-2: reset low speed USB device using uhci_hcd and address 2 [ 9346.247015] PM: resume of drv:usb dev:7-2 complete after 572.961 msecs [ 9346.388388] PM: resume of devices complete after 10515.655 msecs [ 9346.388677] PM: resume devices took 10.516 seconds [ 9346.388679] ------------[ cut here ]------------ [ 9346.388687] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [ 9346.388690] Hardware name: P5K Deluxe [ 9346.388703] Component: resume devices, time: 10516 [ 9346.388704] Modules linked in: binfmt_misc ppdev vboxnetadp vboxnetflt vboxdrv nfsd exportfs nfs lockd nfs_acl auth_rpcgss snd_hda_codec_analog snd_usb_audio snd_hda_intel snd_seq_dummy snd_usb_lib snd_seq_oss usblp snd_hda_codec sunrpc snd_seq_midi nvidia(P) snd_seq_midi_event snd_rawmidi snd_seq snd_pcm_oss vga16fb snd_seq_device snd_mixer_oss vgastate asus_atk0110 serio_raw snd_pcm snd_hwdep snd_timer uvcvideo videodev snd_page_alloc snd usb_storage soundcore lp v4l1_compat parport usbhid hid fbcon tileblit font bitblit softcursor vesafb r8169 intel_agp ohci1394 skge mii agpgart ieee1394 sky2 pata_jmicron ahci [ 9346.388743] Pid: 14721, comm: pm-suspend Tainted: P 2.6.32-25-generic-pae #44-Ubuntu [ 9346.388745] Call Trace: [ 9346.388750] [] warn_slowpath_common+0x72/0xa0 [ 9346.388753] [] ? suspend_test_finish+0x89/0x90 [ 9346.388756] [] ? suspend_test_finish+0x89/0x90 [ 9346.388758] [] warn_slowpath_fmt+0x2b/0x30 [ 9346.388761] [] suspend_test_finish+0x89/0x90 [ 9346.388764] [] suspend_devices_and_enter+0xa1/0xd0 [ 9346.388768] [] ? printk+0x1d/0x25 [ 9346.388770] [] enter_state+0xbd/0xf0 [ 9346.388773] [] state_store+0x75/0xc0 [ 9346.388776] [] ? state_store+0x0/0xc0 [ 9346.388779] [] kobj_attr_store+0x20/0x30 [ 9346.388782] [] sysfs_write_file+0x95/0x100 [ 9346.388786] [] vfs_write+0xa2/0x1a0 [ 9346.388788] [] ? sysfs_write_file+0x0/0x100 [ 9346.388791] [] ? do_page_fault+0x160/0x3a0 [ 9346.388794] [] sys_write+0x42/0x70 [ 9346.388797] [] sysenter_do_call+0x12/0x28 [ 9346.388799] ---[ end trace f9239b39a855995b ]--- [ 9346.388835] PM: Finishing wakeup. [ 9346.388837] Restarting tasks ... done. [ 9348.862509] skge eth1: enabling interface [ 9348.865904] ADDRCONF(NETDEV_UP): eth1: link is not ready [ 9348.867102] r8169: eth2: link down [ 9348.867332] ADDRCONF(NETDEV_UP): eth2: link is not ready [ 9351.218600] skge eth1: Link is up at 1000 Mbps, full duplex, flow control both [ 9351.218824] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 9351.289029] sky2 eth0: disabling interface [ 9351.296832] sky2 eth0: enabling interface [ 9351.297067] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 9361.736009] eth1: no IPv6 routers present [17340.095238] lo: Disabled Privacy Extensions [45881.388016] sky2 eth0: disabling interface [45881.620022] skge eth1: disabling interface [45881.858548] sky2 eth0: enabling interface [45881.858808] ADDRCONF(NETDEV_UP): eth0: link is not ready [45881.880526] sky2 eth0: disabling interface [45881.888250] sky2 eth0: enabling interface [45881.888515] ADDRCONF(NETDEV_UP): eth0: link is not ready [45881.960535] sky2 eth0: disabling interface [45881.968403] sky2 eth0: enabling interface [45881.968668] ADDRCONF(NETDEV_UP): eth0: link is not ready [45885.103919] PM: Syncing filesystems ... done. [45885.115195] PM: Preparing system for mem sleep [45885.115198] Freezing user space processes ... (elapsed 0.00 seconds) done. [45885.116447] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [45885.116503] PM: Entering mem sleep [45885.116514] Suspending console(s) (use no_console_suspend to debug) [45885.228073] sd 1:0:0:0: [sdc] Synchronizing SCSI cache [45885.228392] sd 1:0:0:0: [sdc] Stopping disk [45885.228990] sd 0:0:1:0: [sdb] Synchronizing SCSI cache [45885.255330] sd 0:0:1:0: [sdb] Stopping disk [45885.387129] PM: suspend of drv:sd dev:0:0:1:0 complete after 158.139 msecs [45885.387137] sd 0:0:0:0: [sda] Synchronizing SCSI cache [45885.387313] sd 0:0:0:0: [sda] Stopping disk [45885.912439] PM: suspend of drv:sd dev:0:0:0:0 complete after 525.301 msecs [45885.944457] ACPI handle has no context! [45885.944943] serial 00:09: disabled [45885.948992] ACPI handle has no context! [45885.964726] skge 0000:05:02.0: PME# enabled [45885.964733] pci 0000:00:1e.0: wake-up capability enabled by ACPI [45885.980030] sky2 eth0: disabling interface [45885.981442] ACPI handle has no context! [45885.981448] ACPI handle has no context! [45885.996166] pata_jmicron 0000:03:00.1: PCI INT B disabled [45886.044147] ahci 0000:03:00.0: PCI INT A disabled [45886.082829] ata_piix 0000:00:1f.5: PCI INT B disabled [45886.112102] ata_piix 0000:00:1f.2: PCI INT B disabled [45886.128039] ehci_hcd 0000:00:1d.7: PCI INT A disabled [45886.128048] uhci_hcd 0000:00:1d.2: PCI INT C disabled [45886.128056] uhci_hcd 0000:00:1d.1: PCI INT B disabled [45886.128063] uhci_hcd 0000:00:1d.0: PCI INT A disabled [45886.232084] HDA Intel 0000:00:1b.0: PCI INT A disabled [45886.232125] ACPI handle has no context! [45886.248032] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.953 msecs [45886.248039] ehci_hcd 0000:00:1a.7: PCI INT C disabled [45886.248047] uhci_hcd 0000:00:1a.2: PCI INT C disabled [45886.248054] uhci_hcd 0000:00:1a.1: PCI INT B disabled [45886.248062] uhci_hcd 0000:00:1a.0: PCI INT A disabled [45886.248140] PM: suspend of devices complete after 1131.222 msecs [45886.248144] PM: suspend devices took 1.136 seconds [45886.248263] r8169 0000:05:04.0: PME# enabled [45886.248266] pci 0000:00:1e.0: wake-up capability enabled by ACPI [45886.296158] PM: late suspend of devices complete after 48.010 msecs [45886.296670] ACPI: Preparing to enter system sleep state S3 [45886.313028] Disabling non-boot CPUs ... [45886.313054] CPU0 attaching NULL sched-domain. [45886.313058] CPU1 attaching NULL sched-domain. [45886.313061] CPU2 attaching NULL sched-domain. [45886.313064] CPU3 attaching NULL sched-domain. [45886.377031] CPU0 attaching NULL sched-domain. [45886.379336] CPU 1 is now offline [45886.382296] CPU 2 is now offline [45886.383975] Breaking affinity for irq 22 [45886.383979] Breaking affinity for irq 23 [45886.488028] CPU 3 is now offline [45886.488031] SMP alternatives: switching to UP code [45886.494859] Back to C! [45886.494859] CPU0: Thermal monitoring enabled (TM2) [45886.494859] Enabling non-boot CPUs ... [45886.494859] SMP alternatives: switching to SMP code [45886.501541] Booting processor 1 APIC 0x1 ip 0x6000 [45886.379535] Initializing CPU#1 [45886.379535] CPU: L1 I cache: 32K, L1 D cache: 32K [45886.379535] CPU: L2 cache: 4096K [45886.379535] CPU: Physical Processor ID: 0 [45886.379535] CPU: Processor Core ID: 1 [45886.379535] CPU1: Thermal monitoring enabled (TM2) [45886.592069] CPU1: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [45886.592076] checking TSC synchronization [CPU#0 -> CPU#1]: passed. [45886.612156] CPU0 attaching NULL sched-domain. [45886.640053] CPU0 attaching sched-domain: [45886.640056] domain 0: span 0-1 level MC [45886.640059] groups: 0 1 [45886.640064] CPU1 attaching sched-domain: [45886.640067] domain 0: span 0-1 level MC [45886.640069] groups: 1 0 [45886.644020] CPU1 is up [45886.644246] Booting processor 2 APIC 0x2 ip 0x6000 [45886.382460] Initializing CPU#2 [45886.382460] CPU: L1 I cache: 32K, L1 D cache: 32K [45886.382460] CPU: L2 cache: 4096K [45886.382460] CPU: Physical Processor ID: 0 [45886.382460] CPU: Processor Core ID: 2 [45886.382460] CPU2: Thermal monitoring enabled (TM2) [45886.732093] CPU2: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [45886.732101] checking TSC synchronization [CPU#0 -> CPU#2]: passed. [45886.752145] CPU0 attaching NULL sched-domain. [45886.752148] CPU1 attaching NULL sched-domain. [45886.804053] CPU0 attaching sched-domain: [45886.804055] domain 0: span 0-1 level MC [45886.804058] groups: 0 1 [45886.804063] domain 1: span 0-2 level CPU [45886.804065] groups: 0-1 (cpu_power = 2048) 2 [45886.804072] CPU1 attaching sched-domain: [45886.804074] domain 0: span 0-1 level MC [45886.804077] groups: 1 0 [45886.804080] domain 1: span 0-2 level CPU [45886.804083] groups: 0-1 (cpu_power = 2048) 2 [45886.804089] CPU2 attaching sched-domain: [45886.804091] domain 0: span 0-2 level CPU [45886.804093] groups: 2 0-1 (cpu_power = 2048) [45886.804828] CPU2 is up [45886.805267] Booting processor 3 APIC 0x3 ip 0x6000 [45886.494580] Initializing CPU#3 [45886.494580] CPU: L1 I cache: 32K, L1 D cache: 32K [45886.494580] CPU: L2 cache: 4096K [45886.494580] CPU: Physical Processor ID: 0 [45886.494580] CPU: Processor Core ID: 3 [45886.494580] CPU3: Thermal monitoring enabled (TM2) [45886.896093] CPU3: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [45886.896102] checking TSC synchronization [CPU#0 -> CPU#3]: passed. [45886.916164] CPU0 attaching NULL sched-domain. [45886.916167] CPU1 attaching NULL sched-domain. [45886.916170] CPU2 attaching NULL sched-domain. [45886.964054] CPU0 attaching sched-domain: [45886.964057] domain 0: span 0-1 level MC [45886.964059] groups: 0 1 [45886.964064] domain 1: span 0-3 level CPU [45886.964066] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [45886.964073] CPU1 attaching sched-domain: [45886.964076] domain 0: span 0-1 level MC [45886.964078] groups: 1 0 [45886.964082] domain 1: span 0-3 level CPU [45886.964085] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [45886.964091] CPU2 attaching sched-domain: [45886.964093] domain 0: span 2-3 level MC [45886.964096] groups: 2 3 [45886.964100] domain 1: span 0-3 level CPU [45886.964102] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [45886.964109] CPU3 attaching sched-domain: [45886.964111] domain 0: span 2-3 level MC [45886.964113] groups: 3 2 [45886.964117] domain 1: span 0-3 level CPU [45886.964120] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [45886.965049] CPU3 is up [45886.966830] ACPI: Waking up from system sleep state S3 [45886.967636] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [45886.967646] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [45886.967680] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [45886.967708] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [45886.967735] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [45886.967769] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [45886.967806] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [45886.967826] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [45886.967835] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xf030f000) [45886.967839] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0x20001010) [45886.967847] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [45886.967876] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [45886.967884] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf051f041) [45886.967894] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [45886.967923] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x2020b) [45886.967931] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf071f061) [45886.967941] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [45886.967980] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [45886.968025] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [45886.968053] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [45886.968087] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [45886.968105] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [45886.968114] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf081f081) [45886.968236] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe8e0000, writing 0x0) [45886.968246] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0) [45886.968281] ahci 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [45886.968290] ahci 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfeae0000) [45886.968299] ahci 0000:03:00.0: restoring config space at offset 0x9 (was 0x0, writing 0xfeafe000) [45886.968304] ahci 0000:03:00.0: restoring config space at offset 0x8 (was 0x1, writing 0x0) [45886.968309] ahci 0000:03:00.0: restoring config space at offset 0x7 (was 0x1, writing 0x0) [45886.968314] ahci 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x0) [45886.968319] ahci 0000:03:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x0) [45886.968324] ahci 0000:03:00.0: restoring config space at offset 0x4 (was 0x1, writing 0x0) [45886.968329] ahci 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x800008) [45886.968335] ahci 0000:03:00.0: restoring config space at offset 0x2 (was 0x1018503, writing 0x1060103) [45886.968340] ahci 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [45886.968388] pata_jmicron 0000:03:00.1: restoring config space at offset 0x8 (was 0x1, writing 0xd401) [45886.968393] pata_jmicron 0000:03:00.1: restoring config space at offset 0x7 (was 0x1, writing 0xd481) [45886.968398] pata_jmicron 0000:03:00.1: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [45886.968403] pata_jmicron 0000:03:00.1: restoring config space at offset 0x5 (was 0x1, writing 0xd881) [45886.968409] pata_jmicron 0000:03:00.1: restoring config space at offset 0x4 (was 0x1, writing 0xdc01) [45886.968417] pata_jmicron 0000:03:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100005) [45886.968450] sky2 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [45886.968458] sky2 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [45886.968470] sky2 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [45886.968476] sky2 0000:02:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [45886.968481] sky2 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [45886.968487] sky2 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [45886.968532] skge 0000:05:02.0: restoring config space at offset 0xf (was 0x1f170100, writing 0x1f170105) [45886.968538] skge 0000:05:02.0: restoring config space at offset 0xc (was 0x0, writing 0xfebc0000) [45886.968549] skge 0000:05:02.0: restoring config space at offset 0x5 (was 0x1, writing 0xe801) [45886.968553] skge 0000:05:02.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfc000) [45886.968558] skge 0000:05:02.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [45886.968563] skge 0000:05:02.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [45886.968581] ohci1394 0000:05:03.0: restoring config space at offset 0xf (was 0x180c0100, writing 0x180c010e) [45886.968596] ohci1394 0000:05:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfb000) [45886.968600] ohci1394 0000:05:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [45886.968606] ohci1394 0000:05:03.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900016) [45886.968622] r8169 0000:05:04.0: restoring config space at offset 0xf (was 0x40200100, writing 0x4020010b) [45886.968628] r8169 0000:05:04.0: restoring config space at offset 0xc (was 0x0, writing 0xfeba0000) [45886.968639] r8169 0000:05:04.0: restoring config space at offset 0x5 (was 0x0, writing 0xfebfac00) [45886.968643] r8169 0000:05:04.0: restoring config space at offset 0x4 (was 0x1, writing 0xe401) [45886.968648] r8169 0000:05:04.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [45886.968653] r8169 0000:05:04.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [45886.968714] PM: early resume of devices complete after 1.116 msecs [45886.968787] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [45886.968792] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [45886.968811] usb usb3: root hub lost power or was reset [45886.968826] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [45886.968831] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [45886.968848] usb usb4: root hub lost power or was reset [45886.968863] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [45886.968867] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [45886.968885] usb usb5: root hub lost power or was reset [45886.968899] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [45886.968904] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [45886.968912] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [45886.968916] HDA Intel 0000:00:1b.0: setting latency timer to 64 [45887.408194] PM: resume of drv:HDA Intel dev:0000:00:1b.0 complete after 439.281 msecs [45887.408211] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [45887.408217] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [45887.408249] usb usb6: root hub lost power or was reset [45887.408274] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [45887.408278] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [45887.408296] usb usb7: root hub lost power or was reset [45887.408318] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [45887.408325] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [45887.408343] usb usb8: root hub lost power or was reset [45887.408359] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [45887.408364] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [45887.408375] pci 0000:00:1e.0: setting latency timer to 64 [45887.408382] ata_piix 0000:00:1f.2: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [45887.408388] ata_piix 0000:00:1f.2: setting latency timer to 64 [45887.413350] ata_piix 0000:00:1f.5: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [45887.413353] ata_piix 0000:00:1f.5: setting latency timer to 64 [45887.827013] ata3: SATA link down (SStatus 0 SControl 300) [45887.837985] ata4: SATA link down (SStatus 0 SControl 300) [45893.344099] ata2.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [45893.344118] ata2.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [45893.344253] ata1.00: link is slow to respond, please be patient (ready=0) [45893.368526] ata2.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [45893.368529] ata2.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [45893.368532] ata2.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [45893.392522] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [45893.392524] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [45893.392880] ata2.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [45893.392885] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [45893.424687] ata2.00: configured for UDMA/133 [45893.460372] ata2.01: configured for UDMA/100 [45893.492723] ata2.00: configured for UDMA/133 [45893.494221] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 6078.862 msecs [45893.494265] ahci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [45893.494272] ahci 0000:03:00.0: setting latency timer to 64 [45893.508226] pata_jmicron 0000:03:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [45893.508233] pata_jmicron 0000:03:00.1: setting latency timer to 64 [45893.511746] sky2 eth0: enabling interface [45893.511764] pci 0000:00:1e.0: wake-up capability disabled by ACPI [45893.511768] skge 0000:05:02.0: PME# disabled [45893.524252] ata2.01: configured for UDMA/100 [45893.524255] ata2: EH complete [45893.566030] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[19] MMIO=[febfb000-febfb7ff] Max Packet=[2048] IR/IT contexts=[8/8] [45893.575050] pci 0000:00:1e.0: wake-up capability disabled by ACPI [45893.575054] r8169 0000:05:04.0: PME# disabled [45893.576194] serial 00:09: activated [45893.828059] ata8: SATA link down (SStatus 0 SControl 300) [45893.828062] ata7: SATA link down (SStatus 0 SControl 300) [45893.904071] PM: resume of drv:usb dev:usb6 complete after 248.009 msecs [45894.152067] PM: resume of drv:usb dev:usb7 complete after 247.987 msecs [45895.024093] ata1.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [45895.024106] ata1.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [45895.128210] ata1.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [45895.128214] ata1.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [45895.128313] ata1.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded [45895.128318] ata1.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [45895.176214] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [45895.176218] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [45895.176396] ata1.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [45895.176400] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [45895.266279] ata1.00: configured for UDMA/133 [45895.321182] ata1.01: configured for UDMA/133 [45895.466276] ata1.00: configured for UDMA/133 [45895.512763] ata1.01: configured for UDMA/133 [45895.512766] ata1: EH complete [45899.312030] usb 2-2: reset high speed USB device using ehci_hcd and address 3 [45899.444667] usblp 2-2:1.0: no reset_resume for driver usblp? [45899.444789] usblp0: removed [45899.444794] PM: resume of drv:usb dev:2-2 complete after 5292.299 msecs [45899.501552] sd 0:0:0:0: [sda] Starting disk [45899.531636] sd 0:0:1:0: [sdb] Starting disk [45899.550614] sd 1:0:0:0: [sdc] Starting disk [45899.876028] usb 6-1: reset low speed USB device using uhci_hcd and address 2 [45900.254006] PM: resume of drv:usb dev:6-1 complete after 637.450 msecs [45900.512032] usb 7-2: reset low speed USB device using uhci_hcd and address 2 [45900.827000] PM: resume of drv:usb dev:7-2 complete after 572.956 msecs [45900.968358] PM: resume of devices complete after 13999.619 msecs [45900.970832] usblp0: USB Bidirectional printer dev 3 if 0 alt 0 proto 2 vid 0x04B8 pid 0x0007 [45900.971032] PM: resume devices took 14.000 seconds [45900.971033] ------------[ cut here ]------------ [45900.971040] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [45900.971042] Hardware name: P5K Deluxe [45900.971043] Component: resume devices, time: 14000 [45900.971045] Modules linked in: binfmt_misc ppdev vboxnetadp vboxnetflt vboxdrv nfsd exportfs nfs lockd nfs_acl auth_rpcgss snd_hda_codec_analog snd_usb_audio snd_hda_intel snd_seq_dummy snd_usb_lib snd_seq_oss usblp snd_hda_codec sunrpc snd_seq_midi nvidia(P) snd_seq_midi_event snd_rawmidi snd_seq snd_pcm_oss vga16fb snd_seq_device snd_mixer_oss vgastate asus_atk0110 serio_raw snd_pcm snd_hwdep snd_timer uvcvideo videodev snd_page_alloc snd usb_storage soundcore lp v4l1_compat parport usbhid hid fbcon tileblit font bitblit softcursor vesafb r8169 intel_agp ohci1394 skge mii agpgart ieee1394 sky2 pata_jmicron ahci [45900.971083] Pid: 30079, comm: pm-suspend Tainted: P W 2.6.32-25-generic-pae #44-Ubuntu [45900.971085] Call Trace: [45900.971090] [] warn_slowpath_common+0x72/0xa0 [45900.971093] [] ? suspend_test_finish+0x89/0x90 [45900.971096] [] ? suspend_test_finish+0x89/0x90 [45900.971098] [] warn_slowpath_fmt+0x2b/0x30 [45900.971101] [] suspend_test_finish+0x89/0x90 [45900.971104] [] suspend_devices_and_enter+0xa1/0xd0 [45900.971108] [] ? printk+0x1d/0x25 [45900.971111] [] enter_state+0xbd/0xf0 [45900.971113] [] state_store+0x75/0xc0 [45900.971116] [] ? state_store+0x0/0xc0 [45900.971119] [] kobj_attr_store+0x20/0x30 [45900.971122] [] sysfs_write_file+0x95/0x100 [45900.971126] [] vfs_write+0xa2/0x1a0 [45900.971128] [] ? sysfs_write_file+0x0/0x100 [45900.971131] [] ? do_page_fault+0x160/0x3a0 [45900.971134] [] sys_write+0x42/0x70 [45900.971137] [] sysenter_do_call+0x12/0x28 [45900.971139] ---[ end trace f9239b39a855995c ]--- [45900.971177] PM: Finishing wakeup. [45900.971178] Restarting tasks ... done. [45904.017148] skge eth1: enabling interface [45904.020565] ADDRCONF(NETDEV_UP): eth1: link is not ready [45904.021504] r8169: eth2: link down [45904.021657] ADDRCONF(NETDEV_UP): eth2: link is not ready [45906.398515] skge eth1: Link is up at 1000 Mbps, full duplex, flow control both [45906.398752] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [45906.508529] sky2 eth0: disabling interface [45906.516321] sky2 eth0: enabling interface [45906.516596] ADDRCONF(NETDEV_UP): eth0: link is not ready [45916.888008] eth1: no IPv6 routers present [45985.138277] lo: Disabled Privacy Extensions [54560.157011] sky2 eth0: disabling interface [54560.389029] skge eth1: disabling interface [54560.558535] sky2 eth0: enabling interface [54560.558781] ADDRCONF(NETDEV_UP): eth0: link is not ready [54560.604526] sky2 eth0: disabling interface [54560.612044] sky2 eth0: enabling interface [54560.612277] ADDRCONF(NETDEV_UP): eth0: link is not ready [54560.740526] sky2 eth0: disabling interface [54560.748267] sky2 eth0: enabling interface [54560.748519] ADDRCONF(NETDEV_UP): eth0: link is not ready [54563.821359] PM: Syncing filesystems ... done. [54563.831290] PM: Preparing system for mem sleep [54563.831293] Freezing user space processes ... (elapsed 0.00 seconds) done. [54563.832581] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [54563.832637] PM: Entering mem sleep [54563.832648] Suspending console(s) (use no_console_suspend to debug) [54563.944051] sd 1:0:0:0: [sdc] Synchronizing SCSI cache [54563.944380] sd 1:0:0:0: [sdc] Stopping disk [54563.944975] sd 0:0:1:0: [sdb] Synchronizing SCSI cache [54563.973660] sd 0:0:1:0: [sdb] Stopping disk [54564.186807] PM: suspend of drv:sd dev:0:0:1:0 complete after 241.831 msecs [54564.186815] sd 0:0:0:0: [sda] Synchronizing SCSI cache [54564.187627] sd 0:0:0:0: [sda] Stopping disk [54564.710700] PM: suspend of drv:sd dev:0:0:0:0 complete after 523.884 msecs [54564.740456] ACPI handle has no context! [54564.740945] serial 00:09: disabled [54564.745004] ACPI handle has no context! [54564.760727] skge 0000:05:02.0: PME# enabled [54564.760734] pci 0000:00:1e.0: wake-up capability enabled by ACPI [54564.776022] sky2 eth0: disabling interface [54564.777432] ACPI handle has no context! [54564.777437] ACPI handle has no context! [54564.792166] pata_jmicron 0000:03:00.1: PCI INT B disabled [54564.840148] ahci 0000:03:00.0: PCI INT A disabled [54564.878874] ata_piix 0000:00:1f.5: PCI INT B disabled [54564.908103] ata_piix 0000:00:1f.2: PCI INT B disabled [54564.924039] ehci_hcd 0000:00:1d.7: PCI INT A disabled [54564.924047] uhci_hcd 0000:00:1d.2: PCI INT C disabled [54564.924055] uhci_hcd 0000:00:1d.1: PCI INT B disabled [54564.924063] uhci_hcd 0000:00:1d.0: PCI INT A disabled [54565.028103] HDA Intel 0000:00:1b.0: PCI INT A disabled [54565.028144] ACPI handle has no context! [54565.044030] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.952 msecs [54565.044038] ehci_hcd 0000:00:1a.7: PCI INT C disabled [54565.044046] uhci_hcd 0000:00:1a.2: PCI INT C disabled [54565.044053] uhci_hcd 0000:00:1a.1: PCI INT B disabled [54565.044061] uhci_hcd 0000:00:1a.0: PCI INT A disabled [54565.044139] PM: suspend of devices complete after 1211.095 msecs [54565.044143] PM: suspend devices took 1.212 seconds [54565.044261] r8169 0000:05:04.0: PME# enabled [54565.044264] pci 0000:00:1e.0: wake-up capability enabled by ACPI [54565.092158] PM: late suspend of devices complete after 48.010 msecs [54565.092669] ACPI: Preparing to enter system sleep state S3 [54565.109028] Disabling non-boot CPUs ... [54565.109053] CPU0 attaching NULL sched-domain. [54565.109057] CPU1 attaching NULL sched-domain. [54565.109060] CPU2 attaching NULL sched-domain. [54565.109063] CPU3 attaching NULL sched-domain. [54565.173031] CPU0 attaching NULL sched-domain. [54565.175338] CPU 1 is now offline [54565.178341] CPU 2 is now offline [54565.180002] Breaking affinity for irq 19 [54565.180008] Breaking affinity for irq 22 [54565.180011] Breaking affinity for irq 23 [54565.284028] CPU 3 is now offline [54565.284031] SMP alternatives: switching to UP code [54565.290888] Back to C! [54565.290888] CPU0: Thermal monitoring enabled (TM2) [54565.290888] Enabling non-boot CPUs ... [54565.290888] SMP alternatives: switching to SMP code [54565.297566] Booting processor 1 APIC 0x1 ip 0x6000 [54565.175563] Initializing CPU#1 [54565.175563] CPU: L1 I cache: 32K, L1 D cache: 32K [54565.175563] CPU: L2 cache: 4096K [54565.175563] CPU: Physical Processor ID: 0 [54565.175563] CPU: Processor Core ID: 1 [54565.175563] CPU1: Thermal monitoring enabled (TM2) [54565.388096] CPU1: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [54565.388103] checking TSC synchronization [CPU#0 -> CPU#1]: passed. [54565.408186] CPU0 attaching NULL sched-domain. [54565.436053] CPU0 attaching sched-domain: [54565.436056] domain 0: span 0-1 level MC [54565.436059] groups: 0 1 [54565.436064] CPU1 attaching sched-domain: [54565.436067] domain 0: span 0-1 level MC [54565.436069] groups: 1 0 [54565.440019] CPU1 is up [54565.440243] Booting processor 2 APIC 0x2 ip 0x6000 [54565.178475] Initializing CPU#2 [54565.178475] CPU: L1 I cache: 32K, L1 D cache: 32K [54565.178475] CPU: L2 cache: 4096K [54565.178475] CPU: Physical Processor ID: 0 [54565.178475] CPU: Processor Core ID: 2 [54565.178475] CPU2: Thermal monitoring enabled (TM2) [54565.528088] CPU2: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [54565.528097] checking TSC synchronization [CPU#0 -> CPU#2]: passed. [54565.548137] CPU0 attaching NULL sched-domain. [54565.548140] CPU1 attaching NULL sched-domain. [54565.600053] CPU0 attaching sched-domain: [54565.600055] domain 0: span 0-1 level MC [54565.600058] groups: 0 1 [54565.600063] domain 1: span 0-2 level CPU [54565.600065] groups: 0-1 (cpu_power = 2048) 2 [54565.600072] CPU1 attaching sched-domain: [54565.600074] domain 0: span 0-1 level MC [54565.600077] groups: 1 0 [54565.600080] domain 1: span 0-2 level CPU [54565.600083] groups: 0-1 (cpu_power = 2048) 2 [54565.600089] CPU2 attaching sched-domain: [54565.600091] domain 0: span 0-2 level CPU [54565.600093] groups: 2 0-1 (cpu_power = 2048) [54565.600830] CPU2 is up [54565.601263] Booting processor 3 APIC 0x3 ip 0x6000 [54565.290593] Initializing CPU#3 [54565.290593] CPU: L1 I cache: 32K, L1 D cache: 32K [54565.290593] CPU: L2 cache: 4096K [54565.290593] CPU: Physical Processor ID: 0 [54565.290593] CPU: Processor Core ID: 3 [54565.290593] CPU3: Thermal monitoring enabled (TM2) [54565.692088] CPU3: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [54565.692097] checking TSC synchronization [CPU#0 -> CPU#3]: passed. [54565.712159] CPU0 attaching NULL sched-domain. [54565.712162] CPU1 attaching NULL sched-domain. [54565.712164] CPU2 attaching NULL sched-domain. [54565.760054] CPU0 attaching sched-domain: [54565.760057] domain 0: span 0-1 level MC [54565.760060] groups: 0 1 [54565.760064] domain 1: span 0-3 level CPU [54565.760067] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [54565.760074] CPU1 attaching sched-domain: [54565.760076] domain 0: span 0-1 level MC [54565.760079] groups: 1 0 [54565.760083] domain 1: span 0-3 level CPU [54565.760085] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [54565.760092] CPU2 attaching sched-domain: [54565.760094] domain 0: span 2-3 level MC [54565.760097] groups: 2 3 [54565.760101] domain 1: span 0-3 level CPU [54565.760103] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [54565.760110] CPU3 attaching sched-domain: [54565.760112] domain 0: span 2-3 level MC [54565.760114] groups: 3 2 [54565.760118] domain 1: span 0-3 level CPU [54565.760121] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [54565.761046] CPU3 is up [54565.762830] ACPI: Waking up from system sleep state S3 [54565.763634] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [54565.763645] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [54565.763679] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [54565.763706] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [54565.763734] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [54565.763768] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [54565.763804] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [54565.763824] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [54565.763833] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xf030f000) [54565.763837] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0x1010) [54565.763845] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [54565.763874] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [54565.763883] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf051f041) [54565.763892] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [54565.763922] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x2020b) [54565.763930] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf071f061) [54565.763939] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [54565.763979] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [54565.764024] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [54565.764051] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [54565.764085] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [54565.764104] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [54565.764112] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf081f081) [54565.764235] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe8e0000, writing 0x0) [54565.764244] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0) [54565.764280] ahci 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [54565.764288] ahci 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfeae0000) [54565.764297] ahci 0000:03:00.0: restoring config space at offset 0x9 (was 0x0, writing 0xfeafe000) [54565.764302] ahci 0000:03:00.0: restoring config space at offset 0x8 (was 0x1, writing 0x0) [54565.764308] ahci 0000:03:00.0: restoring config space at offset 0x7 (was 0x1, writing 0x0) [54565.764313] ahci 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x0) [54565.764318] ahci 0000:03:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x0) [54565.764323] ahci 0000:03:00.0: restoring config space at offset 0x4 (was 0x1, writing 0x0) [54565.764328] ahci 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x800008) [54565.764333] ahci 0000:03:00.0: restoring config space at offset 0x2 (was 0x1018503, writing 0x1060103) [54565.764339] ahci 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [54565.764387] pata_jmicron 0000:03:00.1: restoring config space at offset 0x8 (was 0x1, writing 0xd401) [54565.764392] pata_jmicron 0000:03:00.1: restoring config space at offset 0x7 (was 0x1, writing 0xd481) [54565.764397] pata_jmicron 0000:03:00.1: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [54565.764402] pata_jmicron 0000:03:00.1: restoring config space at offset 0x5 (was 0x1, writing 0xd881) [54565.764407] pata_jmicron 0000:03:00.1: restoring config space at offset 0x4 (was 0x1, writing 0xdc01) [54565.764416] pata_jmicron 0000:03:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100005) [54565.764449] sky2 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [54565.764457] sky2 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [54565.764469] sky2 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [54565.764475] sky2 0000:02:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [54565.764480] sky2 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [54565.764486] sky2 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [54565.764531] skge 0000:05:02.0: restoring config space at offset 0xf (was 0x1f170100, writing 0x1f170105) [54565.764537] skge 0000:05:02.0: restoring config space at offset 0xc (was 0x0, writing 0xfebc0000) [54565.764548] skge 0000:05:02.0: restoring config space at offset 0x5 (was 0x1, writing 0xe801) [54565.764552] skge 0000:05:02.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfc000) [54565.764556] skge 0000:05:02.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [54565.764562] skge 0000:05:02.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [54565.764580] ohci1394 0000:05:03.0: restoring config space at offset 0xf (was 0x180c0100, writing 0x180c010e) [54565.764595] ohci1394 0000:05:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfb000) [54565.764599] ohci1394 0000:05:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [54565.764604] ohci1394 0000:05:03.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900016) [54565.764621] r8169 0000:05:04.0: restoring config space at offset 0xf (was 0x40200100, writing 0x4020010b) [54565.764627] r8169 0000:05:04.0: restoring config space at offset 0xc (was 0x0, writing 0xfeba0000) [54565.764638] r8169 0000:05:04.0: restoring config space at offset 0x5 (was 0x0, writing 0xfebfac00) [54565.764642] r8169 0000:05:04.0: restoring config space at offset 0x4 (was 0x1, writing 0xe401) [54565.764646] r8169 0000:05:04.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [54565.764652] r8169 0000:05:04.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [54565.764714] PM: early resume of devices complete after 1.116 msecs [54565.764787] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [54565.764792] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [54565.764811] usb usb3: root hub lost power or was reset [54565.764826] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [54565.764831] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [54565.764848] usb usb4: root hub lost power or was reset [54565.764863] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [54565.764867] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [54565.764885] usb usb5: root hub lost power or was reset [54565.764899] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [54565.764904] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [54565.764912] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [54565.764916] HDA Intel 0000:00:1b.0: setting latency timer to 64 [54566.204194] PM: resume of drv:HDA Intel dev:0000:00:1b.0 complete after 439.281 msecs [54566.204212] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [54566.204218] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [54566.204250] usb usb6: root hub lost power or was reset [54566.204274] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [54566.204278] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [54566.204296] usb usb7: root hub lost power or was reset [54566.204319] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [54566.204325] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [54566.204343] usb usb8: root hub lost power or was reset [54566.204359] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [54566.204364] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [54566.204375] pci 0000:00:1e.0: setting latency timer to 64 [54566.204382] ata_piix 0000:00:1f.2: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [54566.204385] ata_piix 0000:00:1f.2: setting latency timer to 64 [54566.209349] ata_piix 0000:00:1f.5: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [54566.209353] ata_piix 0000:00:1f.5: setting latency timer to 64 [54566.731547] ata4: SATA link down (SStatus 0 SControl 300) [54566.742588] ata3: SATA link down (SStatus 0 SControl 300) [54572.192130] ata2.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [54572.192156] ata2.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [54572.216313] ata2.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [54572.216316] ata2.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [54572.216318] ata2.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [54572.240329] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [54572.240332] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [54572.240674] ata2.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [54572.240679] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [54572.248029] ata1.00: link is slow to respond, please be patient (ready=0) [54572.272724] ata2.00: configured for UDMA/133 [54572.281630] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 6070.277 msecs [54572.281673] ahci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [54572.281680] ahci 0000:03:00.0: setting latency timer to 64 [54572.296134] pata_jmicron 0000:03:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [54572.296141] pata_jmicron 0000:03:00.1: setting latency timer to 64 [54572.299676] sky2 eth0: enabling interface [54572.299694] pci 0000:00:1e.0: wake-up capability disabled by ACPI [54572.299698] skge 0000:05:02.0: PME# disabled [54572.304265] ata2.01: configured for UDMA/100 [54572.328602] ata2.00: configured for UDMA/133 [54572.354044] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[19] MMIO=[febfb000-febfb7ff] Max Packet=[2048] IR/IT contexts=[8/8] [54572.360700] ata2.01: configured for UDMA/100 [54572.360702] ata2: EH complete [54572.363069] pci 0000:00:1e.0: wake-up capability disabled by ACPI [54572.363073] r8169 0000:05:04.0: PME# disabled [54572.364232] serial 00:09: activated [54572.616046] ata8: SATA link down (SStatus 0 SControl 300) [54572.616075] ata7: SATA link down (SStatus 0 SControl 300) [54572.692050] PM: resume of drv:usb dev:usb6 complete after 247.989 msecs [54572.940067] PM: resume of drv:usb dev:usb7 complete after 248.008 msecs [54573.045617] sd 0:0:0:0: [sda] Starting disk [54574.209071] ata1.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [54574.209084] ata1.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [54574.281208] ata1.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [54574.281213] ata1.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [54574.281317] ata1.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded [54574.281322] ata1.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [54574.328221] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [54574.328226] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [54574.328409] ata1.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [54574.328413] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [54574.418296] ata1.00: configured for UDMA/133 [54574.470225] ata1.01: configured for UDMA/133 [54574.618295] ata1.00: configured for UDMA/133 [54574.661810] ata1.01: configured for UDMA/133 [54574.661813] ata1: EH complete [54574.683891] PM: resume of drv:sd dev:0:0:0:0 complete after 1638.274 msecs [54574.683901] sd 0:0:1:0: [sdb] Starting disk [54574.701392] sd 1:0:0:0: [sdc] Starting disk [54575.028031] usb 6-1: reset low speed USB device using uhci_hcd and address 2 [54575.406035] PM: resume of drv:usb dev:6-1 complete after 637.551 msecs [54575.664028] usb 7-2: reset low speed USB device using uhci_hcd and address 2 [54575.979012] PM: resume of drv:usb dev:7-2 complete after 572.938 msecs [54576.120285] PM: resume of devices complete after 10355.547 msecs [54576.120496] PM: resume devices took 10.356 seconds [54576.120497] ------------[ cut here ]------------ [54576.120504] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [54576.120506] Hardware name: P5K Deluxe [54576.120507] Component: resume devices, time: 10356 [54576.120509] Modules linked in: binfmt_misc ppdev vboxnetadp vboxnetflt vboxdrv nfsd exportfs nfs lockd nfs_acl auth_rpcgss snd_hda_codec_analog snd_usb_audio snd_hda_intel snd_seq_dummy snd_usb_lib snd_seq_oss usblp snd_hda_codec sunrpc snd_seq_midi nvidia(P) snd_seq_midi_event snd_rawmidi snd_seq snd_pcm_oss vga16fb snd_seq_device snd_mixer_oss vgastate asus_atk0110 serio_raw snd_pcm snd_hwdep snd_timer uvcvideo videodev snd_page_alloc snd usb_storage soundcore lp v4l1_compat parport usbhid hid fbcon tileblit font bitblit softcursor vesafb r8169 intel_agp ohci1394 skge mii agpgart ieee1394 sky2 pata_jmicron ahci [54576.120547] Pid: 11010, comm: pm-suspend Tainted: P W 2.6.32-25-generic-pae #44-Ubuntu [54576.120549] Call Trace: [54576.120554] [] warn_slowpath_common+0x72/0xa0 [54576.120557] [] ? suspend_test_finish+0x89/0x90 [54576.120560] [] ? suspend_test_finish+0x89/0x90 [54576.120563] [] warn_slowpath_fmt+0x2b/0x30 [54576.120565] [] suspend_test_finish+0x89/0x90 [54576.120568] [] suspend_devices_and_enter+0xa1/0xd0 [54576.120572] [] ? printk+0x1d/0x25 [54576.120575] [] enter_state+0xbd/0xf0 [54576.120578] [] state_store+0x75/0xc0 [54576.120581] [] ? state_store+0x0/0xc0 [54576.120584] [] kobj_attr_store+0x20/0x30 [54576.120587] [] sysfs_write_file+0x95/0x100 [54576.120591] [] vfs_write+0xa2/0x1a0 [54576.120593] [] ? sysfs_write_file+0x0/0x100 [54576.120596] [] ? do_page_fault+0x160/0x3a0 [54576.120599] [] sys_write+0x42/0x70 [54576.120602] [] sysenter_do_call+0x12/0x28 [54576.120604] ---[ end trace f9239b39a855995d ]--- [54576.120641] PM: Finishing wakeup. [54576.120642] Restarting tasks ... done. [54579.545902] skge eth1: enabling interface [54579.549278] ADDRCONF(NETDEV_UP): eth1: link is not ready [54579.550412] r8169: eth2: link down [54579.550642] ADDRCONF(NETDEV_UP): eth2: link is not ready [54581.681536] sky2 eth0: disabling interface [54581.689295] sky2 eth0: enabling interface [54581.689615] ADDRCONF(NETDEV_UP): eth0: link is not ready [54582.223414] skge eth1: Link is up at 1000 Mbps, full duplex, flow control both [54582.223640] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [54592.808004] eth1: no IPv6 routers present [65390.696482] lo: Disabled Privacy Extensions [74169.693515] sky2 eth0: disabling interface [74169.929635] skge eth1: disabling interface [74170.570061] sky2 eth0: enabling interface [74170.570307] ADDRCONF(NETDEV_UP): eth0: link is not ready [74170.612520] sky2 eth0: disabling interface [74170.620176] sky2 eth0: enabling interface [74170.620411] ADDRCONF(NETDEV_UP): eth0: link is not ready [74170.704526] sky2 eth0: disabling interface [74170.712274] sky2 eth0: enabling interface [74170.712529] ADDRCONF(NETDEV_UP): eth0: link is not ready [74173.521779] PM: Syncing filesystems ... done. [74173.534607] PM: Preparing system for mem sleep [74173.534610] Freezing user space processes ... (elapsed 0.00 seconds) done. [74173.537020] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [74173.537084] PM: Entering mem sleep [74173.537095] Suspending console(s) (use no_console_suspend to debug) [74173.644083] sd 1:0:0:0: [sdc] Synchronizing SCSI cache [74173.644403] sd 1:0:0:0: [sdc] Stopping disk [74173.644990] sd 0:0:1:0: [sdb] Synchronizing SCSI cache [74173.906428] sd 0:0:1:0: [sdb] Stopping disk [74173.906583] PM: suspend of drv:sd dev:0:0:1:0 complete after 261.593 msecs [74173.906591] sd 0:0:0:0: [sda] Synchronizing SCSI cache [74173.906770] sd 0:0:0:0: [sda] Stopping disk [74174.441601] PM: suspend of drv:sd dev:0:0:0:0 complete after 535.010 msecs [74174.472460] ACPI handle has no context! [74174.472943] serial 00:09: disabled [74174.476990] ACPI handle has no context! [74174.492735] skge 0000:05:02.0: PME# enabled [74174.492742] pci 0000:00:1e.0: wake-up capability enabled by ACPI [74174.508023] sky2 eth0: disabling interface [74174.509429] ACPI handle has no context! [74174.509435] ACPI handle has no context! [74174.524144] pata_jmicron 0000:03:00.1: PCI INT B disabled [74174.572132] ahci 0000:03:00.0: PCI INT A disabled [74174.610841] ata_piix 0000:00:1f.5: PCI INT B disabled [74174.624151] ata_piix 0000:00:1f.2: PCI INT B disabled [74174.640039] ehci_hcd 0000:00:1d.7: PCI INT A disabled [74174.640048] uhci_hcd 0000:00:1d.2: PCI INT C disabled [74174.640056] uhci_hcd 0000:00:1d.1: PCI INT B disabled [74174.640063] uhci_hcd 0000:00:1d.0: PCI INT A disabled [74174.744094] HDA Intel 0000:00:1b.0: PCI INT A disabled [74174.744135] ACPI handle has no context! [74174.760031] PM: suspend of drv:HDA Intel dev:0000:00:1b.0 complete after 119.953 msecs [74174.760039] ehci_hcd 0000:00:1a.7: PCI INT C disabled [74174.760046] uhci_hcd 0000:00:1a.2: PCI INT C disabled [74174.760054] uhci_hcd 0000:00:1a.1: PCI INT B disabled [74174.760061] uhci_hcd 0000:00:1a.0: PCI INT A disabled [74174.760140] PM: suspend of devices complete after 1222.656 msecs [74174.760143] PM: suspend devices took 1.224 seconds [74174.760263] r8169 0000:05:04.0: PME# enabled [74174.760266] pci 0000:00:1e.0: wake-up capability enabled by ACPI [74174.808158] PM: late suspend of devices complete after 48.010 msecs [74174.808670] ACPI: Preparing to enter system sleep state S3 [74174.824029] Disabling non-boot CPUs ... [74174.824053] CPU0 attaching NULL sched-domain. [74174.824057] CPU1 attaching NULL sched-domain. [74174.824060] CPU2 attaching NULL sched-domain. [74174.824063] CPU3 attaching NULL sched-domain. [74174.888030] CPU0 attaching NULL sched-domain. [74174.890315] CPU 1 is now offline [74174.893257] CPU 2 is now offline [74174.894919] Breaking affinity for irq 19 [74174.894928] Breaking affinity for irq 23 [74175.000039] CPU 3 is now offline [74175.000042] SMP alternatives: switching to UP code [74175.006912] Back to C! [74175.006912] CPU0: Thermal monitoring enabled (TM2) [74175.006912] Enabling non-boot CPUs ... [74175.006912] SMP alternatives: switching to SMP code [74175.013626] Booting processor 1 APIC 0x1 ip 0x6000 [74174.890467] Initializing CPU#1 [74174.890467] CPU: L1 I cache: 32K, L1 D cache: 32K [74174.890467] CPU: L2 cache: 4096K [74174.890467] CPU: Physical Processor ID: 0 [74174.890467] CPU: Processor Core ID: 1 [74174.890467] CPU1: Thermal monitoring enabled (TM2) [74175.104055] CPU1: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [74175.104062] checking TSC synchronization [CPU#0 -> CPU#1]: passed. [74175.124151] CPU0 attaching NULL sched-domain. [74175.152052] CPU0 attaching sched-domain: [74175.152055] domain 0: span 0-1 level MC [74175.152058] groups: 0 1 [74175.152064] CPU1 attaching sched-domain: [74175.152066] domain 0: span 0-1 level MC [74175.152069] groups: 1 0 [74175.156018] CPU1 is up [74175.156244] Booting processor 2 APIC 0x2 ip 0x6000 [74174.893398] Initializing CPU#2 [74174.893398] CPU: L1 I cache: 32K, L1 D cache: 32K [74174.893398] CPU: L2 cache: 4096K [74174.893398] CPU: Physical Processor ID: 0 [74174.893398] CPU: Processor Core ID: 2 [74174.893398] CPU2: Thermal monitoring enabled (TM2) [74175.244090] CPU2: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [74175.244098] checking TSC synchronization [CPU#0 -> CPU#2]: passed. [74175.264143] CPU0 attaching NULL sched-domain. [74175.264146] CPU1 attaching NULL sched-domain. [74175.316053] CPU0 attaching sched-domain: [74175.316055] domain 0: span 0-1 level MC [74175.316058] groups: 0 1 [74175.316063] domain 1: span 0-2 level CPU [74175.316065] groups: 0-1 (cpu_power = 2048) 2 [74175.316072] CPU1 attaching sched-domain: [74175.316074] domain 0: span 0-1 level MC [74175.316076] groups: 1 0 [74175.316080] domain 1: span 0-2 level CPU [74175.316083] groups: 0-1 (cpu_power = 2048) 2 [74175.316089] CPU2 attaching sched-domain: [74175.316091] domain 0: span 0-2 level CPU [74175.316093] groups: 2 0-1 (cpu_power = 2048) [74175.316829] CPU2 is up [74175.317267] Booting processor 3 APIC 0x3 ip 0x6000 [74175.006633] Initializing CPU#3 [74175.006633] CPU: L1 I cache: 32K, L1 D cache: 32K [74175.006633] CPU: L2 cache: 4096K [74175.006633] CPU: Physical Processor ID: 0 [74175.006633] CPU: Processor Core ID: 3 [74175.006633] CPU3: Thermal monitoring enabled (TM2) [74175.408093] CPU3: Intel(R) Core(TM)2 Quad CPU @ 2.40GHz stepping 07 [74175.408103] checking TSC synchronization [CPU#0 -> CPU#3]: passed. [74175.428176] CPU0 attaching NULL sched-domain. [74175.428179] CPU1 attaching NULL sched-domain. [74175.428182] CPU2 attaching NULL sched-domain. [74175.476054] CPU0 attaching sched-domain: [74175.476057] domain 0: span 0-1 level MC [74175.476060] groups: 0 1 [74175.476064] domain 1: span 0-3 level CPU [74175.476066] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [74175.476074] CPU1 attaching sched-domain: [74175.476076] domain 0: span 0-1 level MC [74175.476078] groups: 1 0 [74175.476082] domain 1: span 0-3 level CPU [74175.476085] groups: 0-1 (cpu_power = 2048) 2-3 (cpu_power = 2048) [74175.476091] CPU2 attaching sched-domain: [74175.476094] domain 0: span 2-3 level MC [74175.476096] groups: 2 3 [74175.476100] domain 1: span 0-3 level CPU [74175.476102] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [74175.476109] CPU3 attaching sched-domain: [74175.476111] domain 0: span 2-3 level MC [74175.476114] groups: 3 2 [74175.476118] domain 1: span 0-3 level CPU [74175.476120] groups: 2-3 (cpu_power = 2048) 0-1 (cpu_power = 2048) [74175.477048] CPU3 is up [74175.478830] ACPI: Waking up from system sleep state S3 [74175.479635] pcieport 0000:00:01.0: restoring config space at offset 0xf (was 0xa0100, writing 0xa010b) [74175.479646] pcieport 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [74175.479680] uhci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [74175.479708] uhci_hcd 0000:00:1a.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [74175.479735] uhci_hcd 0000:00:1a.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [74175.479769] ehci_hcd 0000:00:1a.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [74175.479806] HDA Intel 0000:00:1b.0: restoring config space at offset 0x1 (was 0x100006, writing 0x100002) [74175.479826] pcieport 0000:00:1c.0: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [74175.479835] pcieport 0000:00:1c.0: restoring config space at offset 0x8 (was 0xfff0, writing 0xf030f000) [74175.479839] pcieport 0000:00:1c.0: restoring config space at offset 0x7 (was 0x200000f0, writing 0x20001010) [74175.479847] pcieport 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100507) [74175.479876] pcieport 0000:00:1c.4: restoring config space at offset 0xf (was 0x20100, writing 0x2010a) [74175.479884] pcieport 0000:00:1c.4: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf051f041) [74175.479894] pcieport 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [74175.479923] pcieport 0000:00:1c.5: restoring config space at offset 0xf (was 0x20200, writing 0x2020b) [74175.479931] pcieport 0000:00:1c.5: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf071f061) [74175.479941] pcieport 0000:00:1c.5: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [74175.479980] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [74175.480026] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [74175.480053] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [74175.480087] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900006, writing 0x2900002) [74175.480106] pci 0000:00:1e.0: restoring config space at offset 0xf (was 0x20000, writing 0x200ff) [74175.480114] pci 0000:00:1e.0: restoring config space at offset 0x9 (was 0x1fff1, writing 0xf081f081) [74175.480237] nvidia 0000:01:00.0: restoring config space at offset 0xc (was 0xfe8e0000, writing 0x0) [74175.480246] nvidia 0000:01:00.0: restoring config space at offset 0x3 (was 0x8, writing 0x0) [74175.480282] ahci 0000:03:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [74175.480290] ahci 0000:03:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfeae0000) [74175.480299] ahci 0000:03:00.0: restoring config space at offset 0x9 (was 0x0, writing 0xfeafe000) [74175.480304] ahci 0000:03:00.0: restoring config space at offset 0x8 (was 0x1, writing 0x0) [74175.480309] ahci 0000:03:00.0: restoring config space at offset 0x7 (was 0x1, writing 0x0) [74175.480314] ahci 0000:03:00.0: restoring config space at offset 0x6 (was 0x1, writing 0x0) [74175.480320] ahci 0000:03:00.0: restoring config space at offset 0x5 (was 0x1, writing 0x0) [74175.480325] ahci 0000:03:00.0: restoring config space at offset 0x4 (was 0x1, writing 0x0) [74175.480330] ahci 0000:03:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x800008) [74175.480335] ahci 0000:03:00.0: restoring config space at offset 0x2 (was 0x1018503, writing 0x1060103) [74175.480341] ahci 0000:03:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100007) [74175.480388] pata_jmicron 0000:03:00.1: restoring config space at offset 0x8 (was 0x1, writing 0xd401) [74175.480394] pata_jmicron 0000:03:00.1: restoring config space at offset 0x7 (was 0x1, writing 0xd481) [74175.480399] pata_jmicron 0000:03:00.1: restoring config space at offset 0x6 (was 0x1, writing 0xd801) [74175.480404] pata_jmicron 0000:03:00.1: restoring config space at offset 0x5 (was 0x1, writing 0xd881) [74175.480409] pata_jmicron 0000:03:00.1: restoring config space at offset 0x4 (was 0x1, writing 0xdc01) [74175.480418] pata_jmicron 0000:03:00.1: restoring config space at offset 0x1 (was 0x100000, writing 0x100005) [74175.480451] sky2 0000:02:00.0: restoring config space at offset 0xf (was 0x100, writing 0x10a) [74175.480459] sky2 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfe9c0000) [74175.480471] sky2 0000:02:00.0: restoring config space at offset 0x6 (was 0x1, writing 0xc801) [74175.480477] sky2 0000:02:00.0: restoring config space at offset 0x4 (was 0x4, writing 0xfe9fc004) [74175.480482] sky2 0000:02:00.0: restoring config space at offset 0x3 (was 0x0, writing 0x8) [74175.480488] sky2 0000:02:00.0: restoring config space at offset 0x1 (was 0x100000, writing 0x100407) [74175.480533] skge 0000:05:02.0: restoring config space at offset 0xf (was 0x1f170100, writing 0x1f170105) [74175.480539] skge 0000:05:02.0: restoring config space at offset 0xc (was 0x0, writing 0xfebc0000) [74175.480550] skge 0000:05:02.0: restoring config space at offset 0x5 (was 0x1, writing 0xe801) [74175.480554] skge 0000:05:02.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfc000) [74175.480559] skge 0000:05:02.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [74175.480564] skge 0000:05:02.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [74175.480582] ohci1394 0000:05:03.0: restoring config space at offset 0xf (was 0x180c0100, writing 0x180c010e) [74175.480597] ohci1394 0000:05:03.0: restoring config space at offset 0x4 (was 0x0, writing 0xfebfb000) [74175.480602] ohci1394 0000:05:03.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [74175.480607] ohci1394 0000:05:03.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900016) [74175.480623] r8169 0000:05:04.0: restoring config space at offset 0xf (was 0x40200100, writing 0x4020010b) [74175.480630] r8169 0000:05:04.0: restoring config space at offset 0xc (was 0x0, writing 0xfeba0000) [74175.480640] r8169 0000:05:04.0: restoring config space at offset 0x5 (was 0x0, writing 0xfebfac00) [74175.480645] r8169 0000:05:04.0: restoring config space at offset 0x4 (was 0x1, writing 0xe401) [74175.480649] r8169 0000:05:04.0: restoring config space at offset 0x3 (was 0x0, writing 0x4008) [74175.480654] r8169 0000:05:04.0: restoring config space at offset 0x1 (was 0x2b00000, writing 0x2b00017) [74175.480716] PM: early resume of devices complete after 1.117 msecs [74175.480788] uhci_hcd 0000:00:1a.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [74175.480793] uhci_hcd 0000:00:1a.0: setting latency timer to 64 [74175.480812] usb usb3: root hub lost power or was reset [74175.480828] uhci_hcd 0000:00:1a.1: PCI INT B -> GSI 21 (level, low) -> IRQ 21 [74175.480832] uhci_hcd 0000:00:1a.1: setting latency timer to 64 [74175.480850] usb usb4: root hub lost power or was reset [74175.480864] uhci_hcd 0000:00:1a.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [74175.480869] uhci_hcd 0000:00:1a.2: setting latency timer to 64 [74175.480886] usb usb5: root hub lost power or was reset [74175.480901] ehci_hcd 0000:00:1a.7: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [74175.480905] ehci_hcd 0000:00:1a.7: setting latency timer to 64 [74175.480913] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [74175.480918] HDA Intel 0000:00:1b.0: setting latency timer to 64 [74175.920194] PM: resume of drv:HDA Intel dev:0000:00:1b.0 complete after 439.279 msecs [74175.920211] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [74175.920217] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [74175.920249] usb usb6: root hub lost power or was reset [74175.920274] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19 [74175.920278] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [74175.920296] usb usb7: root hub lost power or was reset [74175.920319] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [74175.920325] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [74175.920343] usb usb8: root hub lost power or was reset [74175.920359] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23 [74175.920364] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [74175.920375] pci 0000:00:1e.0: setting latency timer to 64 [74175.920382] ata_piix 0000:00:1f.2: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [74175.920388] ata_piix 0000:00:1f.2: setting latency timer to 64 [74175.925357] ata_piix 0000:00:1f.5: PCI INT B -> GSI 22 (level, low) -> IRQ 22 [74175.925361] ata_piix 0000:00:1f.5: setting latency timer to 64 [74176.311473] ata4: SATA link down (SStatus 0 SControl 300) [74176.322462] ata3: SATA link down (SStatus 0 SControl 300) [74181.820030] ata1.00: link is slow to respond, please be patient (ready=0) [74181.828029] ata2.00: link is slow to respond, please be patient (ready=0) [74181.884130] ata2.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [74181.884158] ata2.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [74181.908528] ata2.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [74181.908531] ata2.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [74181.908534] ata2.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [74181.916523] ata2.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [74181.916526] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [74181.916878] ata2.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [74181.916882] ata2.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [74181.948649] ata2.00: configured for UDMA/133 [74181.984374] ata2.01: configured for UDMA/100 [74181.998942] PM: resume of drv:nvidia dev:0000:01:00.0 complete after 6071.577 msecs [74181.998985] ahci 0000:03:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [74181.998992] ahci 0000:03:00.0: setting latency timer to 64 [74182.012132] pata_jmicron 0000:03:00.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [74182.012140] pata_jmicron 0000:03:00.1: setting latency timer to 64 [74182.012655] ata2.00: configured for UDMA/133 [74182.015339] sky2 eth0: enabling interface [74182.015495] pci 0000:00:1e.0: wake-up capability disabled by ACPI [74182.015500] skge 0000:05:02.0: PME# disabled [74182.044255] ata2.01: configured for UDMA/100 [74182.044258] ata2: EH complete [74182.070162] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[19] MMIO=[febfb000-febfb7ff] Max Packet=[2048] IR/IT contexts=[8/8] [74182.079181] pci 0000:00:1e.0: wake-up capability disabled by ACPI [74182.079185] r8169 0000:05:04.0: PME# disabled [74182.080351] serial 00:09: activated [74182.332047] ata7: SATA link down (SStatus 0 SControl 300) [74182.332076] ata8: SATA link down (SStatus 0 SControl 300) [74182.408071] PM: resume of drv:usb dev:usb6 complete after 248.008 msecs [74182.656029] PM: resume of drv:usb dev:usb7 complete after 247.950 msecs [74183.948068] ata1.00: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [74183.948082] ata1.01: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [74184.044210] ata1.01: ACPI cmd ef/03:45:00:00:00:b0 (SET FEATURES) filtered out [74184.044215] ata1.01: ACPI cmd ef/03:0c:00:00:00:b0 (SET FEATURES) filtered out [74184.044323] ata1.01: ACPI cmd c6/00:10:00:00:00:b0 (SET MULTIPLE MODE) succeeded [74184.044328] ata1.01: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [74184.092214] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 (SET FEATURES) filtered out [74184.092218] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 (SET FEATURES) filtered out [74184.092397] ata1.00: ACPI cmd c6/00:10:00:00:00:a0 (SET MULTIPLE MODE) succeeded [74184.092401] ata1.00: ACPI cmd f5/00:00:00:00:00:00 (SECURITY FREEZE LOCK) filtered out [74184.182277] ata1.00: configured for UDMA/133 [74184.234258] ata1.01: configured for UDMA/133 [74184.382276] ata1.00: configured for UDMA/133 [74184.425844] ata1.01: configured for UDMA/133 [74184.425847] ata1: EH complete [74187.816019] usb 2-2: reset high speed USB device using ehci_hcd and address 3 [74187.948690] usblp 2-2:1.0: no reset_resume for driver usblp? [74187.948805] usblp0: removed [74187.948810] PM: resume of drv:usb dev:2-2 complete after 5292.352 msecs [74188.004826] sd 0:0:0:0: [sda] Starting disk [74188.022625] sd 0:0:1:0: [sdb] Starting disk [74188.038874] sd 1:0:0:0: [sdc] Starting disk [74188.392030] usb 6-1: reset low speed USB device using uhci_hcd and address 2 [74188.765986] PM: resume of drv:usb dev:6-1 complete after 633.530 msecs [74189.024051] usb 7-2: reset low speed USB device using uhci_hcd and address 2 [74189.338990] PM: resume of drv:usb dev:7-2 complete after 572.966 msecs [74189.480381] PM: resume of devices complete after 13999.640 msecs [74189.482856] usblp0: USB Bidirectional printer dev 3 if 0 alt 0 proto 2 vid 0x04B8 pid 0x0007 [74189.483055] PM: resume devices took 14.000 seconds [74189.483057] ------------[ cut here ]------------ [74189.483063] WARNING: at /build/buildd/linux-2.6.32/kernel/power/suspend_test.c:53 suspend_test_finish+0x89/0x90() [74189.483065] Hardware name: P5K Deluxe [74189.483067] Component: resume devices, time: 14000 [74189.483068] Modules linked in: binfmt_misc ppdev vboxnetadp vboxnetflt vboxdrv nfsd exportfs nfs lockd nfs_acl auth_rpcgss snd_hda_codec_analog snd_usb_audio snd_hda_intel snd_seq_dummy snd_usb_lib snd_seq_oss usblp snd_hda_codec sunrpc snd_seq_midi nvidia(P) snd_seq_midi_event snd_rawmidi snd_seq snd_pcm_oss vga16fb snd_seq_device snd_mixer_oss vgastate asus_atk0110 serio_raw snd_pcm snd_hwdep snd_timer uvcvideo videodev snd_page_alloc snd usb_storage soundcore lp v4l1_compat parport usbhid hid fbcon tileblit font bitblit softcursor vesafb r8169 intel_agp ohci1394 skge mii agpgart ieee1394 sky2 pata_jmicron ahci [74189.483107] Pid: 4837, comm: pm-suspend Tainted: P W 2.6.32-25-generic-pae #44-Ubuntu [74189.483109] Call Trace: [74189.483113] [] warn_slowpath_common+0x72/0xa0 [74189.483116] [] ? suspend_test_finish+0x89/0x90 [74189.483119] [] ? suspend_test_finish+0x89/0x90 [74189.483122] [] warn_slowpath_fmt+0x2b/0x30 [74189.483124] [] suspend_test_finish+0x89/0x90 [74189.483127] [] suspend_devices_and_enter+0xa1/0xd0 [74189.483131] [] ? printk+0x1d/0x25 [74189.483133] [] enter_state+0xbd/0xf0 [74189.483136] [] state_store+0x75/0xc0 [74189.483139] [] ? state_store+0x0/0xc0 [74189.483142] [] kobj_attr_store+0x20/0x30 [74189.483145] [] sysfs_write_file+0x95/0x100 [74189.483148] [] vfs_write+0xa2/0x1a0 [74189.483151] [] ? sysfs_write_file+0x0/0x100 [74189.483154] [] ? do_page_fault+0x160/0x3a0 [74189.483157] [] sys_write+0x42/0x70 [74189.483160] [] sysenter_do_call+0x12/0x28 [74189.483162] ---[ end trace f9239b39a855995e ]--- [74189.483200] PM: Finishing wakeup. [74189.483201] Restarting tasks ... done. [74192.695002] skge eth1: enabling interface [74192.698398] ADDRCONF(NETDEV_UP): eth1: link is not ready [74192.699588] r8169: eth2: link down [74192.699822] ADDRCONF(NETDEV_UP): eth2: link is not ready [74194.881523] sky2 eth0: disabling interface [74194.889057] sky2 eth0: enabling interface [74194.889291] ADDRCONF(NETDEV_UP): eth0: link is not ready [74195.005518] skge eth1: Link is up at 1000 Mbps, full duplex, flow control both [74195.005746] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [74205.684006] eth1: no IPv6 routers present [74403.965868] lo: Disabled Privacy Extensions [74542.002078] __ratelimit: 21 callbacks suppressed [74542.002081] type=1505 audit(1286130357.201:23): operation="profile_replace" pid=9298 name="/usr/bin/freshclam" [74544.312335] type=1505 audit(1286130359.510:24): operation="profile_replace" pid=9927 name="/usr/sbin/clamd"