[ 51.028020] eth1: no IPv6 routers present [ 63.317905] warning: `jsvc' uses 32-bit capabilities (legacy support in use) [ 111.812454] lib80211_crypt: registered algorithm 'TKIP' [ 858.667745] type=1503 audit(1256178605.194:26): operation="open" pid=2803 parent=2801 profile="/usr/sbin/mysqld-akonadi" requested_mask="::r" denied_mask="::r" fsuid=1000 ouid=0 name="/sys/devices/system/cpu/" [ 1096.116490] tg3 0000:02:00.0: PME# enabled [ 1096.681558] [drm] Num pipes: 1 [ 1099.619043] PM: Syncing filesystems ... done. [ 1099.629892] PM: Preparing system for mem sleep [ 1099.629896] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 1099.631386] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 1099.631432] PM: Entering mem sleep [ 1099.631448] Suspending console(s) (use no_console_suspend to debug) [ 1099.884072] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 1099.884238] sd 0:0:0:0: [sda] Stopping disk [ 1101.392457] nsc-ircc 00:0c: disabled [ 1101.392695] parport_pc 00:0b: disabled [ 1101.392944] serial 00:0a: disabled [ 1101.393010] eth1: Going into suspend... [ 1101.393740] ipw2200 0000:0b:02.0: PCI INT A disabled [ 1101.393748] ACPI handle has no context! [ 1101.408107] pci 0000:01:00.0: PCI INT A disabled [ 1101.424244] Intel ICH 0000:00:1e.2: PCI INT A disabled [ 1101.424283] ehci_hcd 0000:00:1d.7: PCI INT D disabled [ 1101.424290] uhci_hcd 0000:00:1d.3: PCI INT D disabled [ 1101.424296] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 1101.424302] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 1101.424309] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 1101.424340] PM: suspend devices took 1.796 seconds [ 1101.424625] ehci_hcd 0000:00:1d.7: PME# disabled [ 1101.544046] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [ 1101.640052] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [ 1101.744052] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [ 1101.888050] ACPI: Preparing to enter system sleep state S3 [ 1102.544143] Disabling non-boot CPUs ... [ 1102.544195] Extended CMOS year: 2000 [ 1102.544195] Back to C! [ 1102.544195] CPU0: Thermal LVT vector (0xfa) already installed [ 1102.544195] Extended CMOS year: 2000 [ 1102.544195] Force enabled HPET at resume [ 1102.544195] ACPI: Waking up from system sleep state S3 [ 1103.784123] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 1103.784161] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x200000f0) [ 1103.784173] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 1103.784234] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 1103.952046] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 1103.952051] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 1103.952064] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [ 1103.952077] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 1103.984053] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [ 1103.984058] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [ 1103.984071] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [ 1103.984084] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 1103.984095] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [ 1103.984108] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [ 1103.984120] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 1103.984130] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 1103.984143] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [ 1103.984156] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 1103.984174] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 1103.984192] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xb0000000) [ 1103.984200] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [ 1103.984216] ehci_hcd 0000:00:1d.7: PME# disabled [ 1103.984275] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [ 1103.984312] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 1103.984380] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [ 1103.984484] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0x7cff0000) [ 1103.984510] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [ 1104.211866] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1104.211873] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 1104.211897] usb usb2: root hub lost power or was reset [ 1104.211915] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 1104.211921] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 1104.211944] usb usb3: root hub lost power or was reset [ 1104.211971] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 1104.211976] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 1104.211999] usb usb4: root hub lost power or was reset [ 1104.212032] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 1104.212038] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [ 1104.212060] usb usb5: root hub lost power or was reset [ 1104.212077] ehci_hcd 0000:00:1d.7: PME# disabled [ 1104.212081] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 1104.212087] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 1104.212112] pci 0000:00:1e.0: setting latency timer to 64 [ 1104.212119] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 1104.212125] Intel ICH 0000:00:1e.2: setting latency timer to 64 [ 1105.225914] pci 0000:00:1e.3: PME# disabled [ 1105.225923] ata_piix 0000:00:1f.2: setting latency timer to 64 [ 1105.226188] pci 0000:01:00.0: PME# disabled [ 1105.226193] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 1105.226197] pci 0000:01:00.0: setting latency timer to 64 [ 1105.226202] eth1: Coming out of suspend... [ 1105.226208] ipw2200 0000:0b:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [ 1105.226745] serial 00:0a: activated [ 1105.227302] parport_pc 00:0b: activated [ 1105.227951] nsc-ircc 00:0c: activated [ 1105.408965] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [ 1105.408968] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [ 1105.408971] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [ 1105.408974] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 1105.409524] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [ 1105.432977] ata1.00: configured for UDMA/100 [ 1105.448661] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [ 1105.448664] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 1105.449194] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [ 1105.449726] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [ 1105.457606] ata1.00: configured for UDMA/100 [ 1105.457609] ata1: EH complete [ 1105.472551] ata2.00: configured for UDMA/33 [ 1106.003503] sd 0:0:0:0: [sda] Starting disk [ 1109.024155] [ 1109.024157] floppy driver state [ 1109.024158] ------------------- [ 1109.024161] now=202256 last interrupt=4294901996 diff=267556 last called handler=f809e500 [ 1109.024163] timeout_message=lock fdc [ 1109.024164] last output bytes: [ 1109.024166] 18 80 4294892627 [ 1109.024167] 8 80 4294892627 [ 1109.024169] 8 80 4294892627 [ 1109.024170] 8 80 4294892627 [ 1109.024172] 8 80 4294892627 [ 1109.024173] 12 80 4294901817 [ 1109.024175] 0 90 4294901817 [ 1109.024176] 13 80 4294901817 [ 1109.024177] 0 90 4294901817 [ 1109.024179] 1a 90 4294901817 [ 1109.024180] 0 90 4294901817 [ 1109.024182] 3 80 4294901817 [ 1109.024183] c1 90 4294901817 [ 1109.024185] 10 90 4294901817 [ 1109.024186] 7 80 4294901817 [ 1109.024188] 0 90 4294901817 [ 1109.024189] 8 81 4294901904 [ 1109.024190] 7 80 4294901909 [ 1109.024192] 0 90 4294901909 [ 1109.024193] 8 81 4294901996 [ 1109.024195] last result at 4294901996 [ 1109.024196] last redo_fd_request at 4294901996 [ 1109.024197] 70 0 [ 1109.024206] status=0 [ 1109.024207] fdc_busy=1 [ 1109.024208] do_floppy=f809a770 [ 1109.024209] cont=f80a3904 [ 1109.024211] current_req=(null) [ 1109.024212] command_status=-1 [ 1109.024213] [ 1109.024216] floppy0: floppy timeout called [ 1109.136162] usb 4-2: reset full speed USB device using uhci_hcd and address 2 [ 1109.287025] hdaps: initial latch check good (0x03). [ 1110.712562] PM: resume devices took 6.588 seconds [ 1110.712563] ------------[ cut here ]------------ [ 1110.712571] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [ 1110.712573] Hardware name: 2668PEG [ 1110.712575] Component: resume devices [ 1110.712576] Modules linked in: michael_mic arc4 ecb lib80211_crypt_tkip binfmt_misc clip atm ppp_async snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq pcmcia snd_timer hdaps iptable_filter input_polldev snd_seq_device ppdev joydev ipw2200 thinkpad_acpi yenta_socket ip_tables lp snd parport_pc libipw rsrc_nonstatic led_class x_tables nsc_ircc psmouse soundcore lib80211 parport pcmcia_core serio_raw nvram irda snd_page_alloc crc_ccitt dm_raid45 xor usbhid radeon ttm drm i2c_algo_bit tg3 video output floppy intel_agp agpgart [ 1110.712615] Pid: 2998, comm: pm-suspend Not tainted 2.6.31-14-generic #48-Ubuntu [ 1110.712617] Call Trace: [ 1110.712624] [] warn_slowpath_common+0x6d/0xa0 [ 1110.712627] [] ? suspend_test_finish+0x80/0x90 [ 1110.712630] [] ? suspend_test_finish+0x80/0x90 [ 1110.712634] [] warn_slowpath_fmt+0x26/0x30 [ 1110.712637] [] suspend_test_finish+0x80/0x90 [ 1110.712640] [] suspend_devices_and_enter+0x9f/0xd0 [ 1110.712645] [] ? printk+0x18/0x1c [ 1110.712648] [] enter_state+0xb9/0xf0 [ 1110.712651] [] state_store+0x6d/0xb0 [ 1110.712654] [] ? state_store+0x0/0xb0 [ 1110.712658] [] kobj_attr_store+0x20/0x30 [ 1110.712663] [] sysfs_write_file+0x90/0x100 [ 1110.712667] [] vfs_write+0x9a/0x190 [ 1110.712670] [] ? sysfs_write_file+0x0/0x100 [ 1110.712674] [] ? do_page_fault+0x19b/0x380 [ 1110.712677] [] sys_write+0x3d/0x70 [ 1110.712680] [] syscall_call+0x7/0xb [ 1110.712683] ---[ end trace e22d8301b99e9385 ]--- [ 1110.712713] PM: Finishing wakeup. [ 1110.712715] Restarting tasks ... [ 1110.713458] hub 1-0:1.0: over-current change on port 3 [ 1110.748070] done. [ 1110.824592] hub 1-0:1.0: over-current change on port 4 [ 1110.928085] hub 3-0:1.0: over-current change on port 1 [ 1111.036301] hub 3-0:1.0: over-current change on port 2 [ 1111.143988] usb 3-2: USB disconnect, address 2 [ 1111.733861] tg3 0000:02:00.0: PME# disabled [ 1111.915392] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 1111.916181] ADDRCONF(NETDEV_UP): eth1: link is not ready [ 1113.015703] [drm] Loading R300 Microcode [ 1113.015747] [drm] Num pipes: 1 [ 1122.107309] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 1133.084122] eth1: no IPv6 routers present [ 4971.316609] tg3 0000:02:00.0: PME# enabled [ 4971.838876] [drm] Num pipes: 1 [ 4973.630944] PM: Syncing filesystems ... done. [ 4973.643471] PM: Preparing system for mem sleep [ 4973.643477] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 4973.645193] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 4973.645240] PM: Entering mem sleep [ 4973.645257] Suspending console(s) (use no_console_suspend to debug) [ 4973.880175] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 4973.887063] sd 0:0:0:0: [sda] Stopping disk [ 4975.356459] nsc-ircc 00:0c: disabled [ 4975.356695] parport_pc 00:0b: disabled [ 4975.356945] serial 00:0a: disabled [ 4975.357010] eth1: Going into suspend... [ 4975.357726] ipw2200 0000:0b:02.0: PCI INT A disabled [ 4975.357734] ACPI handle has no context! [ 4975.372106] pci 0000:01:00.0: PCI INT A disabled [ 4975.388365] Intel ICH 0000:00:1e.2: PCI INT A disabled [ 4975.388404] ehci_hcd 0000:00:1d.7: PCI INT D disabled [ 4975.388411] uhci_hcd 0000:00:1d.3: PCI INT D disabled [ 4975.388417] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 4975.388424] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 4975.388430] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 4975.388460] PM: suspend devices took 1.744 seconds [ 4975.388745] ehci_hcd 0000:00:1d.7: PME# disabled [ 4975.444055] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [ 4975.476053] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [ 4975.580046] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [ 4975.732049] ACPI: Preparing to enter system sleep state S3 [ 4976.396141] Disabling non-boot CPUs ... [ 4976.396193] Extended CMOS year: 2000 [ 4976.396193] Back to C! [ 4976.396193] CPU0: Thermal LVT vector (0xfa) already installed [ 4976.396193] Extended CMOS year: 2000 [ 4976.396193] Force enabled HPET at resume [ 4976.396193] ACPI: Waking up from system sleep state S3 [ 4977.636123] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 4977.636169] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 4977.636230] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 4977.804047] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 4977.804052] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 4977.804065] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [ 4977.804078] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 4977.836053] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [ 4977.836058] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [ 4977.836071] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [ 4977.836084] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 4977.836094] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [ 4977.836107] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [ 4977.836120] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 4977.836130] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 4977.836143] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [ 4977.836155] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 4977.836173] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 4977.836191] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xb0000000) [ 4977.836199] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [ 4977.836215] ehci_hcd 0000:00:1d.7: PME# disabled [ 4977.836275] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [ 4977.836311] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 4977.836380] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [ 4977.836483] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xfcff0000) [ 4977.836509] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [ 4978.061255] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 4978.061261] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 4978.061284] usb usb2: root hub lost power or was reset [ 4978.061302] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 4978.061308] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 4978.061330] usb usb3: root hub lost power or was reset [ 4978.061356] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 4978.061362] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 4978.061384] usb usb4: root hub lost power or was reset [ 4978.061409] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 4978.061415] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [ 4978.061435] usb usb5: root hub lost power or was reset [ 4978.061452] ehci_hcd 0000:00:1d.7: PME# disabled [ 4978.061456] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 4978.061462] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 4978.061487] pci 0000:00:1e.0: setting latency timer to 64 [ 4978.061493] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 4978.061499] Intel ICH 0000:00:1e.2: setting latency timer to 64 [ 4979.073808] pci 0000:00:1e.3: PME# disabled [ 4979.073818] ata_piix 0000:00:1f.2: setting latency timer to 64 [ 4979.074080] pci 0000:01:00.0: PME# disabled [ 4979.074085] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 4979.074089] pci 0000:01:00.0: setting latency timer to 64 [ 4979.074094] eth1: Coming out of suspend... [ 4979.074100] ipw2200 0000:0b:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [ 4979.074640] serial 00:0a: activated [ 4979.075198] parport_pc 00:0b: activated [ 4979.075847] nsc-ircc 00:0c: activated [ 4979.256768] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [ 4979.256772] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [ 4979.256775] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [ 4979.256778] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 4979.257224] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [ 4979.280777] ata1.00: configured for UDMA/100 [ 4979.296562] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [ 4979.296565] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 4979.296979] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [ 4979.297413] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [ 4979.305403] ata1.00: configured for UDMA/100 [ 4979.305405] ata1: EH complete [ 4979.320450] ata2.00: configured for UDMA/33 [ 4979.601787] sd 0:0:0:0: [sda] Starting disk [ 4982.652015] [ 4982.652017] floppy driver state [ 4982.652018] ------------------- [ 4982.652021] now=1170663 last interrupt=4294901996 diff=1235963 last called handler=f809e500 [ 4982.652023] timeout_message=lock fdc [ 4982.652024] last output bytes: [ 4982.652026] 18 80 4294892627 [ 4982.652028] 8 80 4294892627 [ 4982.652029] 8 80 4294892627 [ 4982.652031] 8 80 4294892627 [ 4982.652032] 8 80 4294892627 [ 4982.652034] 12 80 4294901817 [ 4982.652035] 0 90 4294901817 [ 4982.652036] 13 80 4294901817 [ 4982.652038] 0 90 4294901817 [ 4982.652039] 1a 90 4294901817 [ 4982.652041] 0 90 4294901817 [ 4982.652042] 3 80 4294901817 [ 4982.652044] c1 90 4294901817 [ 4982.652045] 10 90 4294901817 [ 4982.652047] 7 80 4294901817 [ 4982.652048] 0 90 4294901817 [ 4982.652050] 8 81 4294901904 [ 4982.652051] 7 80 4294901909 [ 4982.652053] 0 90 4294901909 [ 4982.652054] 8 81 4294901996 [ 4982.652055] last result at 4294901996 [ 4982.652057] last redo_fd_request at 202256 [ 4982.652058] 70 0 [ 4982.652067] status=0 [ 4982.652068] fdc_busy=1 [ 4982.652069] do_floppy=f809a770 [ 4982.652070] cont=f80a3904 [ 4982.652072] current_req=(null) [ 4982.652073] command_status=-1 [ 4982.652074] [ 4982.652078] floppy0: floppy timeout called [ 4982.764063] usb 4-2: reset full speed USB device using uhci_hcd and address 2 [ 4982.915179] hdaps: initial latch check good (0x03). [ 4984.548583] PM: resume devices took 6.572 seconds [ 4984.548584] ------------[ cut here ]------------ [ 4984.548592] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [ 4984.548595] Hardware name: 2668PEG [ 4984.548596] Component: resume devices [ 4984.548597] Modules linked in: michael_mic arc4 ecb lib80211_crypt_tkip binfmt_misc clip atm ppp_async snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq pcmcia snd_timer hdaps iptable_filter input_polldev snd_seq_device ppdev joydev ipw2200 thinkpad_acpi yenta_socket ip_tables lp snd parport_pc libipw rsrc_nonstatic led_class x_tables nsc_ircc psmouse soundcore lib80211 parport pcmcia_core serio_raw nvram irda snd_page_alloc crc_ccitt dm_raid45 xor usbhid radeon ttm drm i2c_algo_bit tg3 video output floppy intel_agp agpgart [ 4984.548637] Pid: 4295, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [ 4984.548639] Call Trace: [ 4984.548645] [] warn_slowpath_common+0x6d/0xa0 [ 4984.548649] [] ? suspend_test_finish+0x80/0x90 [ 4984.548652] [] ? suspend_test_finish+0x80/0x90 [ 4984.548655] [] warn_slowpath_fmt+0x26/0x30 [ 4984.548658] [] suspend_test_finish+0x80/0x90 [ 4984.548662] [] suspend_devices_and_enter+0x9f/0xd0 [ 4984.548667] [] ? printk+0x18/0x1c [ 4984.548670] [] enter_state+0xb9/0xf0 [ 4984.548672] [] state_store+0x6d/0xb0 [ 4984.548675] [] ? state_store+0x0/0xb0 [ 4984.548680] [] kobj_attr_store+0x20/0x30 [ 4984.548684] [] sysfs_write_file+0x90/0x100 [ 4984.548689] [] vfs_write+0x9a/0x190 [ 4984.548692] [] ? sysfs_write_file+0x0/0x100 [ 4984.548695] [] ? do_page_fault+0x19b/0x380 [ 4984.548698] [] sys_write+0x3d/0x70 [ 4984.548702] [] syscall_call+0x7/0xb [ 4984.548704] ---[ end trace e22d8301b99e9386 ]--- [ 4984.548734] PM: Finishing wakeup. [ 4984.548736] Restarting tasks ... [ 4984.549532] hub 1-0:1.0: over-current change on port 3 [ 4984.604314] done. [ 4984.652239] hub 1-0:1.0: over-current change on port 4 [ 4984.812102] hub 3-0:1.0: over-current change on port 1 [ 4984.916053] hub 3-0:1.0: over-current change on port 2 [ 4985.264447] usb 3-2: new low speed USB device using uhci_hcd and address 3 [ 4985.448197] usb 3-2: configuration #1 chosen from 1 choice [ 4985.468507] input: Logitech USB-PS/2 Optical Mouse as /devices/pci0000:00/0000:00:1d.1/usb3/3-2/3-2:1.0/input/input11 [ 4985.468603] generic-usb 0003:046D:C03E.0002: input,hidraw0: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:1d.1-2/input0 [ 4985.678745] tg3 0000:02:00.0: PME# disabled [ 4985.861105] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 4985.861920] ADDRCONF(NETDEV_UP): eth1: link is not ready [ 4986.963981] [drm] Loading R300 Microcode [ 4986.964045] [drm] Num pipes: 1 [ 4988.393100] psmouse serio2: ID: 10 00 64 [ 4988.723232] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 4991.889493] IBM TrackPoint firmware: 0x0e, buttons: 3/3 [ 4992.112890] input: TPPS/2 IBM TrackPoint as /devices/platform/i8042/serio1/serio2/input/input12 [ 4998.940029] eth1: no IPv6 routers present [12715.028059] usb 1-3: new high speed USB device using ehci_hcd and address 5 [12715.161313] usb 1-3: configuration #1 chosen from 1 choice [12715.707273] usblp0: USB Bidirectional printer dev 5 if 0 alt 0 proto 2 vid 0x03F0 pid 0x2B17 [12715.707311] usbcore: registered new interface driver usblp [12716.388670] usb 1-3: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [12719.164718] usblp0: removed [12855.435848] usb 1-3: USB disconnect, address 5 [13105.220253] usb 1-3: new high speed USB device using ehci_hcd and address 6 [13105.372797] usb 1-3: configuration #1 chosen from 1 choice [13105.382638] usblp0: USB Bidirectional printer dev 6 if 0 alt 0 proto 2 vid 0x03F0 pid 0x2B17 [13106.536397] usb 1-3: usbfs: interface 0 claimed by usblp while 'usb' sets config #1 [13108.576487] usblp0: removed [17784.904434] pcmcia_socket pcmcia_socket0: pccard: PCMCIA card inserted into slot 0 [17784.904448] pcmcia_socket pcmcia_socket0: cs: memory probe 0xd0000000-0xd7ffffff: excluding 0xd0000000-0xd7ffffff [17784.904491] pcmcia_socket pcmcia_socket0: cs: memory probe 0xb4000000-0xbfffffff: excluding 0xb4000000-0xb47fffff 0xb8000000-0xbbffffff [17784.910556] pcmcia 0.0: pcmcia: registering new device pcmcia0.0 [17785.030353] pcmcia 0.0: firmware: requesting SW_8xx_SER.cis [17785.124265] 0.0: ttyS2 at I/O 0x3e8 (irq = 4) is a 16550A [20012.776099] usb 3-2: USB disconnect, address 3 [20013.680063] usb 1-3: USB disconnect, address 6 [20013.852490] tg3 0000:02:00.0: PME# enabled [20014.234022] [drm] Num pipes: 1 [20016.227495] PM: Syncing filesystems ... done. [20016.239076] PM: Preparing system for mem sleep [20016.239082] Freezing user space processes ... (elapsed 0.00 seconds) done. [20016.240873] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [20016.240922] PM: Entering mem sleep [20016.240938] Suspending console(s) (use no_console_suspend to debug) [20016.476038] sd 0:0:0:0: [sda] Synchronizing SCSI cache [20016.476149] sd 0:0:0:0: [sda] Stopping disk [20017.944355] nsc-ircc 00:0c: disabled [20017.944594] parport_pc 00:0b: disabled [20017.944844] serial 00:0a: disabled [20017.944909] eth1: Going into suspend... [20017.945626] ipw2200 0000:0b:02.0: PCI INT A disabled [20017.945633] ACPI handle has no context! [20017.960106] pci 0000:01:00.0: PCI INT A disabled [20017.976245] Intel ICH 0000:00:1e.2: PCI INT A disabled [20017.976284] ehci_hcd 0000:00:1d.7: PCI INT D disabled [20017.976291] uhci_hcd 0000:00:1d.3: PCI INT D disabled [20017.976297] uhci_hcd 0000:00:1d.2: PCI INT C disabled [20017.976304] uhci_hcd 0000:00:1d.1: PCI INT B disabled [20017.976310] uhci_hcd 0000:00:1d.0: PCI INT A disabled [20017.976341] PM: suspend devices took 1.736 seconds [20017.976625] ehci_hcd 0000:00:1d.7: PME# disabled [20018.024056] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [20018.056053] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [20018.168045] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [20018.312050] ACPI: Preparing to enter system sleep state S3 [20018.976144] Disabling non-boot CPUs ... [20018.976195] Extended CMOS year: 2000 [20018.976195] Back to C! [20018.976195] CPU0: Thermal LVT vector (0xfa) already installed [20018.976195] Extended CMOS year: 2000 [20018.976195] Force enabled HPET at resume [20018.976195] ACPI: Waking up from system sleep state S3 [20020.216124] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [20020.216170] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [20020.216231] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [20020.384046] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [20020.384052] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [20020.384065] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [20020.384078] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [20020.416053] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [20020.416058] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [20020.416071] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [20020.416084] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [20020.416094] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [20020.416107] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [20020.416120] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [20020.416130] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [20020.416143] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [20020.416156] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [20020.416174] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [20020.416192] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xb0000000) [20020.416200] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [20020.416216] ehci_hcd 0000:00:1d.7: PME# disabled [20020.416276] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [20020.416313] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [20020.416381] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [20020.416485] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0x7cff0000) [20020.416511] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [20021.610190] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [20021.610196] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [20021.610219] usb usb2: root hub lost power or was reset [20021.610236] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [20021.610242] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [20021.610264] usb usb3: root hub lost power or was reset [20021.610291] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [20021.610297] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [20021.610318] usb usb4: root hub lost power or was reset [20021.610342] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [20021.610348] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [20021.610369] usb usb5: root hub lost power or was reset [20021.610387] ehci_hcd 0000:00:1d.7: PME# disabled [20021.610391] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [20021.610397] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [20021.610423] pci 0000:00:1e.0: setting latency timer to 64 [20021.610429] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [20021.610435] Intel ICH 0000:00:1e.2: setting latency timer to 64 [20022.621912] pci 0000:00:1e.3: PME# disabled [20022.621922] ata_piix 0000:00:1f.2: setting latency timer to 64 [20022.622184] pci 0000:01:00.0: PME# disabled [20022.622189] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [20022.622194] pci 0000:01:00.0: setting latency timer to 64 [20022.622199] eth1: Coming out of suspend... [20022.622204] ipw2200 0000:0b:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [20022.622744] serial 00:0a: activated [20022.623304] parport_pc 00:0b: activated [20022.623956] nsc-ircc 00:0c: activated [20022.804986] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [20022.804990] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [20022.804993] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [20022.804996] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [20022.805523] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [20022.828968] ata1.00: configured for UDMA/100 [20022.844678] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [20022.844681] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [20022.845215] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [20022.845750] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [20022.853636] ata1.00: configured for UDMA/100 [20022.853638] ata1: EH complete [20022.868565] ata2.00: configured for UDMA/33 [20023.149891] sd 0:0:0:0: [sda] Starting disk [20026.168015] [20026.168017] floppy driver state [20026.168018] ------------------- [20026.168021] now=4931542 last interrupt=4294901996 diff=4996842 last called handler=f809e500 [20026.168023] timeout_message=lock fdc [20026.168024] last output bytes: [20026.168026] 18 80 4294892627 [20026.168028] 8 80 4294892627 [20026.168029] 8 80 4294892627 [20026.168031] 8 80 4294892627 [20026.168032] 8 80 4294892627 [20026.168034] 12 80 4294901817 [20026.168035] 0 90 4294901817 [20026.168037] 13 80 4294901817 [20026.168038] 0 90 4294901817 [20026.168040] 1a 90 4294901817 [20026.168041] 0 90 4294901817 [20026.168042] 3 80 4294901817 [20026.168044] c1 90 4294901817 [20026.168045] 10 90 4294901817 [20026.168047] 7 80 4294901817 [20026.168048] 0 90 4294901817 [20026.168050] 8 81 4294901904 [20026.168051] 7 80 4294901909 [20026.168053] 0 90 4294901909 [20026.168054] 8 81 4294901996 [20026.168056] last result at 4294901996 [20026.168057] last redo_fd_request at 1170663 [20026.168058] 70 0 [20026.168067] status=0 [20026.168068] fdc_busy=1 [20026.168069] do_floppy=f809a770 [20026.168071] cont=f80a3904 [20026.168072] current_req=(null) [20026.168074] command_status=-1 [20026.168075] [20026.168078] floppy0: floppy timeout called [20026.280163] usb 4-2: reset full speed USB device using uhci_hcd and address 2 [20026.431071] hdaps: initial latch check good (0x03). [20027.080353] PM: resume devices took 5.536 seconds [20027.080355] ------------[ cut here ]------------ [20027.080363] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [20027.080365] Hardware name: 2668PEG [20027.080367] Component: resume devices [20027.080368] Modules linked in: serial_cs usblp michael_mic arc4 ecb lib80211_crypt_tkip binfmt_misc clip atm ppp_async snd_intel8x0 snd_ac97_codec ac97_bus snd_pcm_oss snd_mixer_oss snd_pcm snd_seq_dummy snd_seq_oss snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq pcmcia snd_timer hdaps iptable_filter input_polldev snd_seq_device ppdev joydev ipw2200 thinkpad_acpi yenta_socket ip_tables lp snd parport_pc libipw rsrc_nonstatic led_class x_tables nsc_ircc psmouse soundcore lib80211 parport pcmcia_core serio_raw nvram irda snd_page_alloc crc_ccitt dm_raid45 xor usbhid radeon ttm drm i2c_algo_bit tg3 video output floppy intel_agp agpgart [20027.080408] Pid: 7025, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [20027.080410] Call Trace: [20027.080417] [] warn_slowpath_common+0x6d/0xa0 [20027.080421] [] ? suspend_test_finish+0x80/0x90 [20027.080424] [] ? suspend_test_finish+0x80/0x90 [20027.080427] [] warn_slowpath_fmt+0x26/0x30 [20027.080430] [] suspend_test_finish+0x80/0x90 [20027.080434] [] suspend_devices_and_enter+0x9f/0xd0 [20027.080438] [] ? printk+0x18/0x1c [20027.080441] [] enter_state+0xb9/0xf0 [20027.080444] [] state_store+0x6d/0xb0 [20027.080447] [] ? state_store+0x0/0xb0 [20027.080451] [] kobj_attr_store+0x20/0x30 [20027.080456] [] sysfs_write_file+0x90/0x100 [20027.080460] [] vfs_write+0x9a/0x190 [20027.080463] [] ? sysfs_write_file+0x0/0x100 [20027.080467] [] ? do_page_fault+0x19b/0x380 [20027.080470] [] sys_write+0x3d/0x70 [20027.080473] [] syscall_call+0x7/0xb [20027.080476] ---[ end trace e22d8301b99e9387 ]--- [20027.080506] PM: Finishing wakeup. [20027.080507] Restarting tasks ... done. [20027.081864] hub 1-0:1.0: over-current change on port 3 [20027.184032] hub 1-0:1.0: over-current change on port 4 [20027.288069] hub 3-0:1.0: over-current change on port 1 [20027.392041] hub 3-0:1.0: over-current change on port 2 [20028.278341] tg3 0000:02:00.0: PME# disabled [20028.462883] ADDRCONF(NETDEV_UP): eth0: link is not ready [20028.463720] ADDRCONF(NETDEV_UP): eth1: link is not ready [20029.563322] [drm] Loading R300 Microcode [20029.563366] [drm] Num pipes: 1 [20029.946162] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [20040.912034] eth1: no IPv6 routers present