:00:1f.0: quirk: region 1180-11bf claimed by ICH6 GPIO [ 47.928877] type=1503 audit(1257056489.124:26): operation="open" pid=1376 parent=1375 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 48.368024] eth1: no IPv6 routers present [ 48.446158] type=1503 audit(1257056489.640:27): operation="open" pid=1409 parent=1408 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 48.865860] type=1503 audit(1257056490.060:28): operation="open" pid=1527 parent=1416 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 48.892023] eth0: no IPv6 routers present [ 49.702512] type=1503 audit(1257056490.896:29): operation="open" pid=1533 parent=1532 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 50.717539] type=1503 audit(1257056491.912:30): operation="open" pid=1547 parent=1546 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 51.844721] type=1503 audit(1257056493.040:31): operation="open" pid=1563 parent=1562 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 51.943560] type=1503 audit(1257056493.136:32): operation="open" pid=1574 parent=1573 profile="/usr/sbin/mysqld" requested_mask="r::" denied_mask="r::" fsuid=0 ouid=0 name="/sys/devices/system/cpu/" [ 58.435655] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 58.435659] Bluetooth: BNEP filters: protocol multicast [ 58.743358] Bridge firewalling registered [ 64.208611] hci_cmd_task: hci0 command tx timeout [ 1103.128158] type=1503 audit(1257057544.324:33): operation="exec" pid=4356 parent=4354 profile="/usr/bin/evince" requested_mask="::x" denied_mask="::x" fsuid=1000 ouid=0 name="/bin/dash" [ 1103.128713] type=1503 audit(1257057544.324:34): operation="exec" pid=4357 parent=4354 profile="/usr/bin/evince" requested_mask="::x" denied_mask="::x" fsuid=1000 ouid=0 name="/bin/dash" [ 1110.803534] type=1503 audit(1257057551.996:35): operation="exec" pid=4363 parent=4361 profile="/usr/bin/evince" requested_mask="::x" denied_mask="::x" fsuid=1000 ouid=0 name="/bin/dash" [ 1110.805059] type=1503 audit(1257057552.000:36): operation="exec" pid=4364 parent=4361 profile="/usr/bin/evince" requested_mask="::x" denied_mask="::x" fsuid=1000 ouid=0 name="/bin/dash" [ 1634.893286] type=1503 audit(1257058076.088:37): operation="exec" pid=5362 parent=5360 profile="/usr/bin/evince" requested_mask="::x" denied_mask="::x" fsuid=1000 ouid=0 name="/bin/dash" [ 1634.893861] type=1503 audit(1257058076.088:38): operation="exec" pid=5363 parent=5360 profile="/usr/bin/evince" requested_mask="::x" denied_mask="::x" fsuid=1000 ouid=0 name="/bin/dash" [ 1972.727580] scim-bridge[2531]: segfault at c ip 0016835c sp bfb43458 error 4 in libscim-1.0.so.8.2.4[110000+bd000] [ 5293.520274] scim-bridge[5693]: segfault at c ip 001b035c sp bfc4ef48 error 4 in libscim-1.0.so.8.2.4[158000+bd000] [ 5754.264097] usb 4-1: USB disconnect, address 2 [ 5754.264731] btusb_intr_complete: hci0 urb f424cd00 failed to resubmit (19) [ 5754.264745] btusb_bulk_complete: hci0 urb f424cc80 failed to resubmit (19) [ 5754.265727] btusb_bulk_complete: hci0 urb f424c580 failed to resubmit (19) [ 5754.265873] btusb_send_frame: hci0 urb e98a1d00 submission failed [ 5754.808473] tg3 0000:02:00.0: PME# enabled [ 5755.380374] [drm] Num pipes: 1 [ 5759.353986] PM: Syncing filesystems ... done. [ 5759.370634] PM: Preparing system for mem sleep [ 5759.370639] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 5759.372260] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 5759.372311] PM: Entering mem sleep [ 5759.372327] Suspending console(s) (use no_console_suspend to debug) [ 5759.624076] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 5759.624249] sd 0:0:0:0: [sda] Stopping disk [ 5761.076496] nsc-ircc 00:0c: disabled [ 5761.076756] parport_pc 00:0b: disabled [ 5761.077031] serial 00:0a: disabled [ 5761.077103] eth1: Going into suspend... [ 5761.077820] ipw2200 0000:04:02.0: PCI INT A disabled [ 5761.077828] ACPI handle has no context! [ 5761.097065] ACPI handle has no context! [ 5761.112107] pci 0000:01:00.0: PCI INT A disabled [ 5761.128258] Intel ICH 0000:00:1e.2: PCI INT A disabled [ 5761.128298] ehci_hcd 0000:00:1d.7: PCI INT D disabled [ 5761.128305] uhci_hcd 0000:00:1d.3: PCI INT D disabled [ 5761.128311] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 5761.128318] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 5761.128325] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 5761.128356] PM: suspend devices took 1.756 seconds [ 5761.128650] ehci_hcd 0000:00:1d.7: PME# disabled [ 5761.264052] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [ 5761.360059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [ 5761.456059] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [ 5761.600055] ACPI: Preparing to enter system sleep state S3 [ 5762.272152] Disabling non-boot CPUs ... [ 5762.272206] Extended CMOS year: 2000 [ 5762.272206] Back to C! [ 5762.272206] CPU0: Thermal LVT vector (0xfa) already installed [ 5762.272206] Extended CMOS year: 2000 [ 5762.272206] Force enabled HPET at resume [ 5762.272206] ACPI: Waking up from system sleep state S3 [ 5763.448136] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 5763.448175] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x7 (was 0xf0, writing 0x200000f0) [ 5763.448188] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 5763.448250] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 5763.616051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 5763.616057] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 5763.616071] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [ 5763.616084] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 5763.648059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [ 5763.648065] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [ 5763.648078] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [ 5763.648092] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 5763.648103] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [ 5763.648116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [ 5763.648129] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 5763.648140] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 5763.648154] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [ 5763.648167] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 5763.648186] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 5763.648205] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [ 5763.648213] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [ 5763.648231] ehci_hcd 0000:00:1d.7: PME# disabled [ 5763.648291] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [ 5763.648329] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 5763.648399] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [ 5763.648504] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xecf30000) [ 5763.648531] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [ 5763.804051] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [ 5763.804060] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 5763.868406] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 5763.868413] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 5763.868437] usb usb2: root hub lost power or was reset [ 5763.868455] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 5763.868461] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 5763.868482] usb usb3: root hub lost power or was reset [ 5763.868510] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 5763.868516] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 5763.868537] usb usb4: root hub lost power or was reset [ 5763.868554] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 5763.868560] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [ 5763.868583] usb usb5: root hub lost power or was reset [ 5763.868600] ehci_hcd 0000:00:1d.7: PME# disabled [ 5763.868605] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 5763.868611] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 5763.868638] pci 0000:00:1e.0: setting latency timer to 64 [ 5763.868644] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 5763.868651] Intel ICH 0000:00:1e.2: setting latency timer to 64 [ 5764.881813] pci 0000:00:1e.3: PME# disabled [ 5764.881823] ata_piix 0000:00:1f.2: setting latency timer to 64 [ 5764.882115] pci 0000:01:00.0: PME# disabled [ 5764.882120] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 5764.882125] pci 0000:01:00.0: setting latency timer to 64 [ 5764.938072] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [ 5764.947116] eth1: Coming out of suspend... [ 5764.947124] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [ 5764.947752] serial 00:0a: activated [ 5764.976134] parport_pc 00:0b: activated [ 5764.976818] nsc-ircc 00:0c: activated [ 5765.084541] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [ 5765.084545] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 5765.085490] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [ 5765.086474] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [ 5765.093409] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [ 5765.093412] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [ 5765.093415] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [ 5765.093419] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 5765.093846] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [ 5765.108567] ata2.00: configured for UDMA/33 [ 5765.116782] ata1.00: configured for UDMA/100 [ 5765.140796] ata1.00: configured for UDMA/100 [ 5765.140799] ata1: EH complete [ 5765.541894] sd 0:0:0:0: [sda] Starting disk [ 5768.604020] [ 5768.604022] floppy driver state [ 5768.604023] ------------------- [ 5768.604027] now=1367151 last interrupt=4294902225 diff=1432222 last called handler=f80a2500 [ 5768.604029] timeout_message=lock fdc [ 5768.604030] last output bytes: [ 5768.604032] 18 80 4294892646 [ 5768.604034] 8 80 4294892646 [ 5768.604036] 8 80 4294892646 [ 5768.604038] 8 80 4294892646 [ 5768.604039] 8 80 4294892646 [ 5768.604041] 12 80 4294902046 [ 5768.604043] 0 90 4294902046 [ 5768.604044] 13 80 4294902046 [ 5768.604046] 0 90 4294902046 [ 5768.604048] 1a 90 4294902046 [ 5768.604050] 0 90 4294902046 [ 5768.604051] 3 80 4294902046 [ 5768.604053] c1 90 4294902046 [ 5768.604055] 10 90 4294902046 [ 5768.604056] 7 80 4294902046 [ 5768.604058] 0 90 4294902046 [ 5768.604060] 8 81 4294902133 [ 5768.604061] 7 80 4294902138 [ 5768.604063] 0 90 4294902138 [ 5768.604065] 8 81 4294902225 [ 5768.604066] last result at 4294902225 [ 5768.604068] last redo_fd_request at 4294902226 [ 5768.604069] 70 0 [ 5768.604078] status=0 [ 5768.604079] fdc_busy=1 [ 5768.604081] do_floppy=f809e770 [ 5768.604082] cont=f80a7904 [ 5768.604084] current_req=(null) [ 5768.604086] command_status=-1 [ 5768.604087] [ 5768.604091] floppy0: floppy timeout called [ 5768.716029] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [ 5769.013753] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [ 5769.013756] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [ 5769.272067] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [ 5769.847877] PM: resume devices took 6.040 seconds [ 5769.847879] ------------[ cut here ]------------ [ 5769.847887] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [ 5769.847890] Hardware name: 18494WU [ 5769.847891] Component: resume devices [ 5769.847893] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [ 5769.847937] Pid: 6682, comm: pm-suspend Not tainted 2.6.31-14-generic #48-Ubuntu [ 5769.847940] Call Trace: [ 5769.847947] [] warn_slowpath_common+0x6d/0xa0 [ 5769.847951] [] ? suspend_test_finish+0x80/0x90 [ 5769.847954] [] ? suspend_test_finish+0x80/0x90 [ 5769.847958] [] warn_slowpath_fmt+0x26/0x30 [ 5769.847962] [] suspend_test_finish+0x80/0x90 [ 5769.847965] [] suspend_devices_and_enter+0x9f/0xd0 [ 5769.847971] [] ? printk+0x18/0x1c [ 5769.847974] [] enter_state+0xb9/0xf0 [ 5769.847977] [] state_store+0x6d/0xb0 [ 5769.847981] [] ? state_store+0x0/0xb0 [ 5769.847986] [] kobj_attr_store+0x20/0x30 [ 5769.847991] [] sysfs_write_file+0x90/0x100 [ 5769.847996] [] vfs_write+0x9a/0x190 [ 5769.847999] [] ? sysfs_write_file+0x0/0x100 [ 5769.848013] [] ? do_page_fault+0x19b/0x380 [ 5769.848016] [] sys_write+0x3d/0x70 [ 5769.848020] [] syscall_call+0x7/0xb [ 5769.848023] ---[ end trace 181a8deae270bcfa ]--- [ 5769.848059] PM: Finishing wakeup. [ 5769.848061] Restarting tasks ... done. [ 5769.849744] hub 1-0:1.0: over-current change on port 3 [ 5769.952050] hub 3-0:1.0: over-current change on port 1 [ 5770.056031] hub 3-0:1.0: over-current change on port 2 [ 5771.167176] tg3 0000:02:00.0: PME# disabled [ 5771.339514] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 5771.340485] ADDRCONF(NETDEV_UP): eth1: link is not ready [ 5771.358195] hub 1-0:1.0: over-current change on port 4 [ 5772.004071] usb 4-1: new full speed USB device using uhci_hcd and address 3 [ 5772.221258] usb 4-1: configuration #1 chosen from 1 choice [ 5772.452182] [drm] Loading R300 Microcode [ 5772.452228] [drm] Num pipes: 1 [ 5772.689011] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 5772.973449] tg3: eth0: Link is up at 100 Mbps, full duplex. [ 5772.973458] tg3: eth0: Flow control is on for TX and on for RX. [ 5772.975473] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 5777.777444] hci_cmd_task: hci0 command tx timeout [ 5782.952060] eth1: no IPv6 routers present [ 5783.572019] eth0: no IPv6 routers present [ 7529.448104] usb 4-1: USB disconnect, address 3 [ 7529.448321] btusb_intr_complete: hci0 urb f3361100 failed to resubmit (19) [ 7529.448334] btusb_bulk_complete: hci0 urb f3361e00 failed to resubmit (19) [ 7529.449319] btusb_bulk_complete: hci0 urb f3361200 failed to resubmit (19) [ 7529.449489] btusb_send_frame: hci0 urb e9be5a00 submission failed [ 7529.904496] tg3 0000:02:00.0: PME# enabled [ 7530.309344] [drm] Num pipes: 1 [ 7532.023061] PM: Syncing filesystems ... done. [ 7532.038953] PM: Preparing system for mem sleep [ 7532.038959] Freezing user space processes ... (elapsed 0.00 seconds) done. [ 7532.040632] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [ 7532.040684] PM: Entering mem sleep [ 7532.040700] Suspending console(s) (use no_console_suspend to debug) [ 7532.292075] sd 0:0:0:0: [sda] Synchronizing SCSI cache [ 7532.333170] sd 0:0:0:0: [sda] Stopping disk [ 7533.788701] nsc-ircc 00:0c: disabled [ 7533.788962] parport_pc 00:0b: disabled [ 7533.789236] serial 00:0a: disabled [ 7533.789308] eth1: Going into suspend... [ 7533.790022] ipw2200 0000:04:02.0: PCI INT A disabled [ 7533.790030] ACPI handle has no context! [ 7533.809065] ACPI handle has no context! [ 7533.824106] pci 0000:01:00.0: PCI INT A disabled [ 7533.840261] Intel ICH 0000:00:1e.2: PCI INT A disabled [ 7533.840300] ehci_hcd 0000:00:1d.7: PCI INT D disabled [ 7533.840307] uhci_hcd 0000:00:1d.3: PCI INT D disabled [ 7533.840314] uhci_hcd 0000:00:1d.2: PCI INT C disabled [ 7533.840320] uhci_hcd 0000:00:1d.1: PCI INT B disabled [ 7533.840327] uhci_hcd 0000:00:1d.0: PCI INT A disabled [ 7533.840359] PM: suspend devices took 1.800 seconds [ 7533.840652] ehci_hcd 0000:00:1d.7: PME# disabled [ 7533.888062] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [ 7533.920059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [ 7534.024051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [ 7534.168055] ACPI: Preparing to enter system sleep state S3 [ 7534.824154] Disabling non-boot CPUs ... [ 7534.824208] Extended CMOS year: 2000 [ 7534.824208] Back to C! [ 7534.824208] CPU0: Thermal LVT vector (0xfa) already installed [ 7534.824208] Extended CMOS year: 2000 [ 7534.824208] Force enabled HPET at resume [ 7534.824208] ACPI: Waking up from system sleep state S3 [ 7535.984135] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7535.984182] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7535.984244] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [ 7536.160051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [ 7536.160057] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [ 7536.160070] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [ 7536.160084] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 7536.192059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [ 7536.192064] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [ 7536.192078] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [ 7536.192091] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 7536.192102] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [ 7536.192116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [ 7536.192129] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 7536.192140] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 7536.192153] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [ 7536.192166] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [ 7536.192185] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [ 7536.192204] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [ 7536.192213] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [ 7536.192230] ehci_hcd 0000:00:1d.7: PME# disabled [ 7536.192290] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [ 7536.192327] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [ 7536.192397] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [ 7536.192503] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xecf30000) [ 7536.192529] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [ 7536.348051] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [ 7536.348059] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [ 7536.412291] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 7536.412298] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [ 7536.412321] usb usb2: root hub lost power or was reset [ 7536.412341] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [ 7536.412347] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [ 7536.412370] usb usb3: root hub lost power or was reset [ 7536.412397] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [ 7536.412403] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [ 7536.412424] usb usb4: root hub lost power or was reset [ 7536.412442] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 7536.412448] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [ 7536.412470] usb usb5: root hub lost power or was reset [ 7536.412489] ehci_hcd 0000:00:1d.7: PME# disabled [ 7536.412493] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [ 7536.412500] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [ 7536.412526] pci 0000:00:1e.0: setting latency timer to 64 [ 7536.412533] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [ 7536.412539] Intel ICH 0000:00:1e.2: setting latency timer to 64 [ 7537.425809] pci 0000:00:1e.3: PME# disabled [ 7537.425819] ata_piix 0000:00:1f.2: setting latency timer to 64 [ 7537.426110] pci 0000:01:00.0: PME# disabled [ 7537.426115] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [ 7537.426120] pci 0000:01:00.0: setting latency timer to 64 [ 7537.482073] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [ 7537.491115] eth1: Coming out of suspend... [ 7537.491123] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [ 7537.491754] serial 00:0a: activated [ 7537.519880] parport_pc 00:0b: activated [ 7537.520584] nsc-ircc 00:0c: activated [ 7537.628539] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [ 7537.628543] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 7537.629488] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [ 7537.630476] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [ 7537.637382] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [ 7537.637386] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [ 7537.637389] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [ 7537.637393] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [ 7537.637824] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [ 7537.652589] ata2.00: configured for UDMA/33 [ 7537.660764] ata1.00: configured for UDMA/100 [ 7537.684783] ata1.00: configured for UDMA/100 [ 7537.684786] ata1: EH complete [ 7538.086221] sd 0:0:0:0: [sda] Starting disk [ 7541.156056] [ 7541.156057] floppy driver state [ 7541.156059] ------------------- [ 7541.156062] now=1810289 last interrupt=4294902225 diff=1875360 last called handler=f80a2500 [ 7541.156064] timeout_message=lock fdc [ 7541.156065] last output bytes: [ 7541.156068] 18 80 4294892646 [ 7541.156069] 8 80 4294892646 [ 7541.156071] 8 80 4294892646 [ 7541.156073] 8 80 4294892646 [ 7541.156074] 8 80 4294892646 [ 7541.156076] 12 80 4294902046 [ 7541.156078] 0 90 4294902046 [ 7541.156080] 13 80 4294902046 [ 7541.156081] 0 90 4294902046 [ 7541.156083] 1a 90 4294902046 [ 7541.156084] 0 90 4294902046 [ 7541.156086] 3 80 4294902046 [ 7541.156088] c1 90 4294902046 [ 7541.156090] 10 90 4294902046 [ 7541.156091] 7 80 4294902046 [ 7541.156093] 0 90 4294902046 [ 7541.156095] 8 81 4294902133 [ 7541.156096] 7 80 4294902138 [ 7541.156098] 0 90 4294902138 [ 7541.156099] 8 81 4294902225 [ 7541.156101] last result at 4294902225 [ 7541.156103] last redo_fd_request at 1367151 [ 7541.156104] 70 0 [ 7541.156113] status=0 [ 7541.156114] fdc_busy=1 [ 7541.156116] do_floppy=f809e770 [ 7541.156117] cont=f80a7904 [ 7541.156119] current_req=(null) [ 7541.156120] command_status=-1 [ 7541.156122] [ 7541.156125] floppy0: floppy timeout called [ 7541.268069] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [ 7541.566042] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [ 7541.566045] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [ 7541.824027] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [ 7542.398927] PM: resume devices took 6.048 seconds [ 7542.398930] ------------[ cut here ]------------ [ 7542.398938] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [ 7542.398940] Hardware name: 18494WU [ 7542.398942] Component: resume devices [ 7542.398944] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [ 7542.398988] Pid: 7955, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [ 7542.398991] Call Trace: [ 7542.398998] [] warn_slowpath_common+0x6d/0xa0 [ 7542.399002] [] ? suspend_test_finish+0x80/0x90 [ 7542.399005] [] ? suspend_test_finish+0x80/0x90 [ 7542.399009] [] warn_slowpath_fmt+0x26/0x30 [ 7542.399013] [] suspend_test_finish+0x80/0x90 [ 7542.399017] [] suspend_devices_and_enter+0x9f/0xd0 [ 7542.399022] [] ? printk+0x18/0x1c [ 7542.399026] [] enter_state+0xb9/0xf0 [ 7542.399029] [] state_store+0x6d/0xb0 [ 7542.399032] [] ? state_store+0x0/0xb0 [ 7542.399037] [] kobj_attr_store+0x20/0x30 [ 7542.399042] [] sysfs_write_file+0x90/0x100 [ 7542.399047] [] vfs_write+0x9a/0x190 [ 7542.399050] [] ? sysfs_write_file+0x0/0x100 [ 7542.399055] [] ? do_page_fault+0x19b/0x380 [ 7542.399058] [] sys_write+0x3d/0x70 [ 7542.399062] [] syscall_call+0x7/0xb [ 7542.399064] ---[ end trace 181a8deae270bcfb ]--- [ 7542.399100] PM: Finishing wakeup. [ 7542.399102] Restarting tasks ... done. [ 7542.400821] hub 1-0:1.0: over-current change on port 3 [ 7542.504126] hub 3-0:1.0: over-current change on port 1 [ 7542.608035] hub 3-0:1.0: over-current change on port 2 [ 7543.587368] tg3 0000:02:00.0: PME# disabled [ 7543.760426] ADDRCONF(NETDEV_UP): eth0: link is not ready [ 7543.761336] ADDRCONF(NETDEV_UP): eth1: link is not ready [ 7543.785488] hub 1-0:1.0: over-current change on port 4 [ 7544.432224] usb 4-1: new full speed USB device using uhci_hcd and address 4 [ 7544.704266] usb 4-1: configuration #1 chosen from 1 choice [ 7544.863780] [drm] Loading R300 Microcode [ 7544.863825] [drm] Num pipes: 1 [ 7544.885749] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [ 7545.372691] tg3: eth0: Link is up at 100 Mbps, full duplex. [ 7545.372700] tg3: eth0: Flow control is on for TX and on for RX. [ 7545.373175] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 7549.933082] hci_cmd_task: hci0 command tx timeout [ 7555.684021] eth0: no IPv6 routers present [ 7555.696014] eth1: no IPv6 routers present [14144.848110] usb 4-1: USB disconnect, address 4 [14144.848606] btusb_intr_complete: hci0 urb f25d8b80 failed to resubmit (19) [14144.848620] btusb_bulk_complete: hci0 urb f25d8680 failed to resubmit (19) [14144.849605] btusb_bulk_complete: hci0 urb f25d8180 failed to resubmit (19) [14144.849776] btusb_send_frame: hci0 urb d34c3c00 submission failed [14145.328457] tg3 0000:02:00.0: PME# enabled [14145.744364] [drm] Num pipes: 1 [14147.575487] PM: Syncing filesystems ... done. [14147.590899] PM: Preparing system for mem sleep [14147.590904] Freezing user space processes ... (elapsed 0.00 seconds) done. [14147.592584] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [14147.592635] PM: Entering mem sleep [14147.592652] Suspending console(s) (use no_console_suspend to debug) [14147.840038] sd 0:0:0:0: [sda] Synchronizing SCSI cache [14147.840154] sd 0:0:0:0: [sda] Stopping disk [14149.292471] nsc-ircc 00:0c: disabled [14149.292731] parport_pc 00:0b: disabled [14149.293006] serial 00:0a: disabled [14149.293078] eth1: Going into suspend... [14149.293799] ipw2200 0000:04:02.0: PCI INT A disabled [14149.293807] ACPI handle has no context! [14149.313068] ACPI handle has no context! [14149.328107] pci 0000:01:00.0: PCI INT A disabled [14149.344252] Intel ICH 0000:00:1e.2: PCI INT A disabled [14149.344292] ehci_hcd 0000:00:1d.7: PCI INT D disabled [14149.344299] uhci_hcd 0000:00:1d.3: PCI INT D disabled [14149.344305] uhci_hcd 0000:00:1d.2: PCI INT C disabled [14149.344312] uhci_hcd 0000:00:1d.1: PCI INT B disabled [14149.344319] uhci_hcd 0000:00:1d.0: PCI INT A disabled [14149.344350] PM: suspend devices took 1.752 seconds [14149.344643] ehci_hcd 0000:00:1d.7: PME# disabled [14149.392062] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [14149.424059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [14149.536051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [14149.680055] ACPI: Preparing to enter system sleep state S3 [14150.352152] Disabling non-boot CPUs ... [14150.352205] Extended CMOS year: 2000 [14150.352205] Back to C! [14150.352205] CPU0: Thermal LVT vector (0xfa) already installed [14150.352205] Extended CMOS year: 2000 [14150.352205] Force enabled HPET at resume [14150.352205] ACPI: Waking up from system sleep state S3 [14151.512135] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14151.512182] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14151.512245] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [14151.688051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [14151.688057] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [14151.688071] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [14151.688084] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [14151.720059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [14151.720065] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [14151.720078] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [14151.720091] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [14151.720102] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [14151.720116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [14151.720129] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [14151.720139] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [14151.720153] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [14151.720166] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [14151.720185] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [14151.720204] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [14151.720212] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [14151.720229] ehci_hcd 0000:00:1d.7: PME# disabled [14151.720290] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [14151.720327] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [14151.720396] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [14151.720503] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xecf30000) [14151.720529] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [14151.876051] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [14151.876059] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [14151.940783] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [14151.940790] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [14151.940813] usb usb2: root hub lost power or was reset [14151.940832] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [14151.940838] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [14151.940861] usb usb3: root hub lost power or was reset [14151.940887] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [14151.940894] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [14151.940916] usb usb4: root hub lost power or was reset [14151.940933] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [14151.940939] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [14151.940960] usb usb5: root hub lost power or was reset [14151.940978] ehci_hcd 0000:00:1d.7: PME# disabled [14151.940983] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [14151.940989] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [14151.941015] pci 0000:00:1e.0: setting latency timer to 64 [14151.941022] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [14151.941028] Intel ICH 0000:00:1e.2: setting latency timer to 64 [14152.953795] pci 0000:00:1e.3: PME# disabled [14152.953805] ata_piix 0000:00:1f.2: setting latency timer to 64 [14152.954097] pci 0000:01:00.0: PME# disabled [14152.954102] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [14152.954107] pci 0000:01:00.0: setting latency timer to 64 [14153.010069] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [14153.019183] eth1: Coming out of suspend... [14153.019191] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [14153.019820] serial 00:0a: activated [14153.020443] parport_pc 00:0b: activated [14153.021126] nsc-ircc 00:0c: activated [14153.156483] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [14153.156487] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [14153.157493] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [14153.158477] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [14153.180540] ata2.00: configured for UDMA/33 [14153.196745] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [14153.196749] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [14153.196752] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [14153.196756] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [14153.197206] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [14153.220784] ata1.00: configured for UDMA/100 [14153.244776] ata1.00: configured for UDMA/100 [14153.244779] ata1: EH complete [14153.689799] sd 0:0:0:0: [sda] Starting disk [14156.708021] [14156.708022] floppy driver state [14156.708024] ------------------- [14156.708027] now=3464177 last interrupt=4294902225 diff=3529248 last called handler=f80a2500 [14156.708029] timeout_message=lock fdc [14156.708030] last output bytes: [14156.708032] 18 80 4294892646 [14156.708034] 8 80 4294892646 [14156.708036] 8 80 4294892646 [14156.708038] 8 80 4294892646 [14156.708039] 8 80 4294892646 [14156.708041] 12 80 4294902046 [14156.708043] 0 90 4294902046 [14156.708045] 13 80 4294902046 [14156.708046] 0 90 4294902046 [14156.708048] 1a 90 4294902046 [14156.708050] 0 90 4294902046 [14156.708051] 3 80 4294902046 [14156.708053] c1 90 4294902046 [14156.708055] 10 90 4294902046 [14156.708056] 7 80 4294902046 [14156.708058] 0 90 4294902046 [14156.708060] 8 81 4294902133 [14156.708061] 7 80 4294902138 [14156.708063] 0 90 4294902138 [14156.708065] 8 81 4294902225 [14156.708066] last result at 4294902225 [14156.708068] last redo_fd_request at 1810289 [14156.708070] 70 0 [14156.708078] status=0 [14156.708080] fdc_busy=1 [14156.708081] do_floppy=f809e770 [14156.708083] cont=f80a7904 [14156.708085] current_req=(null) [14156.708086] command_status=-1 [14156.708087] [14156.708091] floppy0: floppy timeout called [14156.820029] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [14157.118188] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [14157.118190] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [14157.376063] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [14157.949696] PM: resume devices took 6.072 seconds [14157.949698] ------------[ cut here ]------------ [14157.949706] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [14157.949709] Hardware name: 18494WU [14157.949711] Component: resume devices [14157.949712] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [14157.949757] Pid: 8859, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [14157.949760] Call Trace: [14157.949767] [] warn_slowpath_common+0x6d/0xa0 [14157.949771] [] ? suspend_test_finish+0x80/0x90 [14157.949775] [] ? suspend_test_finish+0x80/0x90 [14157.949778] [] warn_slowpath_fmt+0x26/0x30 [14157.949782] [] suspend_test_finish+0x80/0x90 [14157.949786] [] suspend_devices_and_enter+0x9f/0xd0 [14157.949791] [] ? printk+0x18/0x1c [14157.949795] [] enter_state+0xb9/0xf0 [14157.949798] [] state_store+0x6d/0xb0 [14157.949801] [] ? state_store+0x0/0xb0 [14157.949806] [] kobj_attr_store+0x20/0x30 [14157.949811] [] sysfs_write_file+0x90/0x100 [14157.949816] [] vfs_write+0x9a/0x190 [14157.949819] [] ? sysfs_write_file+0x0/0x100 [14157.949824] [] ? do_page_fault+0x19b/0x380 [14157.949827] [] sys_write+0x3d/0x70 [14157.949831] [] syscall_call+0x7/0xb [14157.949833] ---[ end trace 181a8deae270bcfc ]--- [14157.949869] PM: Finishing wakeup. [14157.949871] Restarting tasks ... done. [14157.951566] hub 1-0:1.0: over-current change on port 3 [14158.052056] hub 3-0:1.0: over-current change on port 1 [14158.160114] hub 3-0:1.0: over-current change on port 2 [14159.132406] tg3 0000:02:00.0: PME# disabled [14159.305295] ADDRCONF(NETDEV_UP): eth0: link is not ready [14159.306221] ADDRCONF(NETDEV_UP): eth1: link is not ready [14159.327113] hub 1-0:1.0: over-current change on port 4 [14159.908046] usb 4-1: new full speed USB device using uhci_hcd and address 5 [14160.123467] usb 4-1: configuration #1 chosen from 1 choice [14160.360694] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [14160.405086] [drm] Loading R300 Microcode [14160.405128] [drm] Num pipes: 1 [14160.917664] tg3: eth0: Link is up at 100 Mbps, full duplex. [14160.917668] tg3: eth0: Flow control is on for TX and on for RX. [14160.917879] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [14165.348836] hci_cmd_task: hci0 command tx timeout [14170.692064] eth1: no IPv6 routers present [14170.932069] eth0: no IPv6 routers present [25009.976105] usb 4-1: USB disconnect, address 5 [25009.976499] btusb_intr_complete: hci0 urb f4223b80 failed to resubmit (19) [25009.976512] btusb_bulk_complete: hci0 urb f689a100 failed to resubmit (19) [25009.977498] btusb_bulk_complete: hci0 urb f4223980 failed to resubmit (19) [25009.977682] btusb_send_frame: hci0 urb d349e980 submission failed [25010.332500] tg3 0000:02:00.0: PME# enabled [25010.725997] [drm] Num pipes: 1 [25014.778222] PM: Syncing filesystems ... done. [25014.803506] PM: Preparing system for mem sleep [25014.803512] Freezing user space processes ... (elapsed 0.00 seconds) done. [25014.805194] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [25014.805249] PM: Entering mem sleep [25014.805264] Suspending console(s) (use no_console_suspend to debug) [25015.052038] sd 0:0:0:0: [sda] Synchronizing SCSI cache [25015.052247] sd 0:0:0:0: [sda] Stopping disk [25016.580503] nsc-ircc 00:0c: disabled [25016.580763] parport_pc 00:0b: disabled [25016.581037] serial 00:0a: disabled [25016.581109] eth1: Going into suspend... [25016.583499] ipw2200 0000:04:02.0: PCI INT A disabled [25016.583507] ACPI handle has no context! [25016.601064] ACPI handle has no context! [25016.616106] pci 0000:01:00.0: PCI INT A disabled [25016.632261] Intel ICH 0000:00:1e.2: PCI INT A disabled [25016.632300] ehci_hcd 0000:00:1d.7: PCI INT D disabled [25016.632307] uhci_hcd 0000:00:1d.3: PCI INT D disabled [25016.632314] uhci_hcd 0000:00:1d.2: PCI INT C disabled [25016.632320] uhci_hcd 0000:00:1d.1: PCI INT B disabled [25016.632327] uhci_hcd 0000:00:1d.0: PCI INT A disabled [25016.632359] PM: suspend devices took 1.828 seconds [25016.632651] ehci_hcd 0000:00:1d.7: PME# disabled [25016.680062] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [25016.712059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [25016.816051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [25016.960055] ACPI: Preparing to enter system sleep state S3 [25017.632153] Disabling non-boot CPUs ... [25017.632206] Extended CMOS year: 2000 [25017.632206] Back to C! [25017.632206] CPU0: Thermal LVT vector (0xfa) already installed [25017.632206] Extended CMOS year: 2000 [25017.632206] Force enabled HPET at resume [25017.632206] ACPI: Waking up from system sleep state S3 [25018.784135] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [25018.784183] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [25018.784245] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [25018.952051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [25018.952057] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [25018.952071] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [25018.952084] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25018.984059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [25018.984065] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [25018.984079] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [25018.984092] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25018.984103] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [25018.984116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [25018.984130] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25018.984140] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [25018.984154] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [25018.984167] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25018.984186] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [25018.984204] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [25018.984213] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [25018.984230] ehci_hcd 0000:00:1d.7: PME# disabled [25018.984291] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [25018.984328] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [25018.984397] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [25018.984503] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xecf30000) [25018.984530] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [25019.140051] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [25019.140060] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [25019.204368] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [25019.204375] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [25019.204399] usb usb2: root hub lost power or was reset [25019.204418] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [25019.204424] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [25019.204445] usb usb3: root hub lost power or was reset [25019.204473] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [25019.204480] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [25019.204502] usb usb4: root hub lost power or was reset [25019.204519] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [25019.204525] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [25019.204547] usb usb5: root hub lost power or was reset [25019.204565] ehci_hcd 0000:00:1d.7: PME# disabled [25019.204570] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [25019.204576] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [25019.204601] pci 0000:00:1e.0: setting latency timer to 64 [25019.204608] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [25019.204615] Intel ICH 0000:00:1e.2: setting latency timer to 64 [25020.217799] pci 0000:00:1e.3: PME# disabled [25020.217809] ata_piix 0000:00:1f.2: setting latency timer to 64 [25020.218102] pci 0000:01:00.0: PME# disabled [25020.218108] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [25020.218112] pci 0000:01:00.0: setting latency timer to 64 [25020.274072] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [25020.283185] eth1: Coming out of suspend... [25020.283193] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [25020.283821] serial 00:0a: activated [25020.284442] parport_pc 00:0b: activated [25020.285125] nsc-ircc 00:0c: activated [25020.420483] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [25020.420487] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [25020.421520] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [25020.422523] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [25020.444669] ata2.00: configured for UDMA/33 [25020.445208] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [25020.445211] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [25020.445215] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [25020.445218] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [25020.445684] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [25020.468774] ata1.00: configured for UDMA/100 [25020.492783] ata1.00: configured for UDMA/100 [25020.492786] ata1: EH complete [25020.810203] sd 0:0:0:0: [sda] Starting disk [25023.868052] [25023.868054] floppy driver state [25023.868055] ------------------- [25023.868058] now=6180967 last interrupt=4294902225 diff=6246038 last called handler=f80a2500 [25023.868060] timeout_message=lock fdc [25023.868062] last output bytes: [25023.868064] 18 80 4294892646 [25023.868066] 8 80 4294892646 [25023.868068] 8 80 4294892646 [25023.868069] 8 80 4294892646 [25023.868071] 8 80 4294892646 [25023.868073] 12 80 4294902046 [25023.868074] 0 90 4294902046 [25023.868076] 13 80 4294902046 [25023.868078] 0 90 4294902046 [25023.868079] 1a 90 4294902046 [25023.868081] 0 90 4294902046 [25023.868083] 3 80 4294902046 [25023.868084] c1 90 4294902046 [25023.868086] 10 90 4294902046 [25023.868088] 7 80 4294902046 [25023.868090] 0 90 4294902046 [25023.868091] 8 81 4294902133 [25023.868093] 7 80 4294902138 [25023.868095] 0 90 4294902138 [25023.868096] 8 81 4294902225 [25023.868098] last result at 4294902225 [25023.868099] last redo_fd_request at 3464177 [25023.868101] 70 0 [25023.868110] status=0 [25023.868111] fdc_busy=1 [25023.868113] do_floppy=f809e770 [25023.868114] cont=f80a7904 [25023.868116] current_req=(null) [25023.868117] command_status=-1 [25023.868119] [25023.868122] floppy0: floppy timeout called [25023.980030] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [25024.277683] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [25024.277685] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [25024.536066] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [25025.111941] PM: resume devices took 5.968 seconds [25025.111943] ------------[ cut here ]------------ [25025.111952] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [25025.111954] Hardware name: 18494WU [25025.111956] Component: resume devices [25025.111958] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [25025.112015] Pid: 10286, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [25025.112017] Call Trace: [25025.112024] [] warn_slowpath_common+0x6d/0xa0 [25025.112028] [] ? suspend_test_finish+0x80/0x90 [25025.112032] [] ? suspend_test_finish+0x80/0x90 [25025.112036] [] warn_slowpath_fmt+0x26/0x30 [25025.112039] [] suspend_test_finish+0x80/0x90 [25025.112043] [] suspend_devices_and_enter+0x9f/0xd0 [25025.112049] [] ? printk+0x18/0x1c [25025.112052] [] enter_state+0xb9/0xf0 [25025.112055] [] state_store+0x6d/0xb0 [25025.112059] [] ? state_store+0x0/0xb0 [25025.112063] [] kobj_attr_store+0x20/0x30 [25025.112069] [] sysfs_write_file+0x90/0x100 [25025.112073] [] vfs_write+0x9a/0x190 [25025.112077] [] ? sysfs_write_file+0x0/0x100 [25025.112081] [] ? do_page_fault+0x19b/0x380 [25025.112084] [] sys_write+0x3d/0x70 [25025.112088] [] syscall_call+0x7/0xb [25025.112091] ---[ end trace 181a8deae270bcfd ]--- [25025.112128] PM: Finishing wakeup. [25025.112129] Restarting tasks ... done. [25025.113861] hub 1-0:1.0: over-current change on port 3 [25025.216034] hub 3-0:1.0: over-current change on port 1 [25025.320458] hub 3-0:1.0: over-current change on port 2 [25026.309752] tg3 0000:02:00.0: PME# disabled [25026.481976] ADDRCONF(NETDEV_UP): eth0: link is not ready [25026.482877] ADDRCONF(NETDEV_UP): eth1: link is not ready [25026.501044] hub 1-0:1.0: over-current change on port 4 [25027.148364] usb 4-1: new full speed USB device using uhci_hcd and address 6 [25027.360267] usb 4-1: configuration #1 chosen from 1 choice [25027.584981] [drm] Loading R300 Microcode [25027.585026] [drm] Num pipes: 1 [25027.983886] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [25028.115986] tg3: eth0: Link is up at 100 Mbps, full duplex. [25028.115990] tg3: eth0: Flow control is on for TX and on for RX. [25028.116943] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [25032.597864] hci_cmd_task: hci0 command tx timeout [25038.176024] eth0: no IPv6 routers present [25038.264023] eth1: no IPv6 routers present [25581.242367] usb 4-1: USB disconnect, address 6 [25581.242493] btusb_intr_complete: hci0 urb d34c3c00 failed to resubmit (19) [25581.242501] btusb_bulk_complete: hci0 urb d34c3d80 failed to resubmit (19) [25581.243503] btusb_bulk_complete: hci0 urb d34c3f00 failed to resubmit (19) [25581.243614] btusb_send_frame: hci0 urb d35ef000 submission failed [25581.436497] tg3 0000:02:00.0: PME# enabled [25581.848631] [drm] Num pipes: 1 [25583.724095] PM: Syncing filesystems ... done. [25583.737812] PM: Preparing system for mem sleep [25583.737817] Freezing user space processes ... (elapsed 0.00 seconds) done. [25583.739825] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [25583.739873] PM: Entering mem sleep [25583.739888] Suspending console(s) (use no_console_suspend to debug) [25583.988075] sd 0:0:0:0: [sda] Synchronizing SCSI cache [25583.992969] sd 0:0:0:0: [sda] Stopping disk [25585.480577] nsc-ircc 00:0c: disabled [25585.480838] parport_pc 00:0b: disabled [25585.481113] serial 00:0a: disabled [25585.481185] eth1: Going into suspend... [25585.481906] ipw2200 0000:04:02.0: PCI INT A disabled [25585.481914] ACPI handle has no context! [25585.501059] ACPI handle has no context! [25585.516106] pci 0000:01:00.0: PCI INT A disabled [25585.532260] Intel ICH 0000:00:1e.2: PCI INT A disabled [25585.532299] ehci_hcd 0000:00:1d.7: PCI INT D disabled [25585.532306] uhci_hcd 0000:00:1d.3: PCI INT D disabled [25585.532313] uhci_hcd 0000:00:1d.2: PCI INT C disabled [25585.532319] uhci_hcd 0000:00:1d.1: PCI INT B disabled [25585.532326] uhci_hcd 0000:00:1d.0: PCI INT A disabled [25585.532357] PM: suspend devices took 1.796 seconds [25585.532650] ehci_hcd 0000:00:1d.7: PME# disabled [25585.588058] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [25585.620059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [25585.724051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [25585.868055] ACPI: Preparing to enter system sleep state S3 [25586.532153] Disabling non-boot CPUs ... [25586.532207] Extended CMOS year: 2000 [25586.532207] Back to C! [25586.532207] CPU0: Thermal LVT vector (0xfa) already installed [25586.532207] Extended CMOS year: 2000 [25586.532207] Force enabled HPET at resume [25586.532207] ACPI: Waking up from system sleep state S3 [25587.692135] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [25587.692182] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [25587.692244] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [25587.860052] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [25587.860058] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [25587.860071] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [25587.860085] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25587.892059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [25587.892065] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [25587.892078] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [25587.892092] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25587.892103] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [25587.892116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [25587.892130] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25587.892140] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [25587.892154] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [25587.892167] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [25587.892186] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [25587.892205] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [25587.892213] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [25587.892231] ehci_hcd 0000:00:1d.7: PME# disabled [25587.892291] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [25587.892328] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [25587.892398] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [25587.892504] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xacf20000) [25587.892531] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [25588.048050] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [25588.048059] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [25588.120456] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [25588.120464] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [25588.120487] usb usb2: root hub lost power or was reset [25588.120505] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [25588.120511] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [25588.120532] usb usb3: root hub lost power or was reset [25588.120560] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [25588.120566] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [25588.120589] usb usb4: root hub lost power or was reset [25588.120605] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [25588.120612] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [25588.120635] usb usb5: root hub lost power or was reset [25588.120652] ehci_hcd 0000:00:1d.7: PME# disabled [25588.120657] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [25588.120663] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [25588.120690] pci 0000:00:1e.0: setting latency timer to 64 [25588.120697] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [25588.120703] Intel ICH 0000:00:1e.2: setting latency timer to 64 [25589.133804] pci 0000:00:1e.3: PME# disabled [25589.133814] ata_piix 0000:00:1f.2: setting latency timer to 64 [25589.134105] pci 0000:01:00.0: PME# disabled [25589.134111] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [25589.134115] pci 0000:01:00.0: setting latency timer to 64 [25589.190071] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [25589.199111] eth1: Coming out of suspend... [25589.199119] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [25589.199747] serial 00:0a: activated [25589.227914] parport_pc 00:0b: activated [25589.228614] nsc-ircc 00:0c: activated [25589.336538] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [25589.336542] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [25589.337485] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [25589.338468] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [25589.345375] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [25589.345379] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [25589.345382] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [25589.345386] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [25589.345821] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [25589.360590] ata2.00: configured for UDMA/33 [25589.368764] ata1.00: configured for UDMA/100 [25589.392779] ata1.00: configured for UDMA/100 [25589.392782] ata1: EH complete [25589.937849] sd 0:0:0:0: [sda] Starting disk [25592.964020] [25592.964021] floppy driver state [25592.964023] ------------------- [25592.964026] now=6323241 last interrupt=4294902225 diff=6388312 last called handler=f80a2500 [25592.964028] timeout_message=lock fdc [25592.964030] last output bytes: [25592.964032] 18 80 4294892646 [25592.964034] 8 80 4294892646 [25592.964035] 8 80 4294892646 [25592.964037] 8 80 4294892646 [25592.964039] 8 80 4294892646 [25592.964040] 12 80 4294902046 [25592.964042] 0 90 4294902046 [25592.964044] 13 80 4294902046 [25592.964045] 0 90 4294902046 [25592.964047] 1a 90 4294902046 [25592.964049] 0 90 4294902046 [25592.964051] 3 80 4294902046 [25592.964052] c1 90 4294902046 [25592.964054] 10 90 4294902046 [25592.964056] 7 80 4294902046 [25592.964057] 0 90 4294902046 [25592.964059] 8 81 4294902133 [25592.964061] 7 80 4294902138 [25592.964062] 0 90 4294902138 [25592.964064] 8 81 4294902225 [25592.964066] last result at 4294902225 [25592.964067] last redo_fd_request at 6180967 [25592.964069] 70 0 [25592.964078] status=0 [25592.964079] fdc_busy=1 [25592.964080] do_floppy=f809e770 [25592.964082] cont=f80a7904 [25592.964084] current_req=(null) [25592.964085] command_status=-1 [25592.964086] [25592.964090] floppy0: floppy timeout called [25593.076028] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [25593.373051] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [25593.373054] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [25593.632027] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [25594.206306] PM: resume devices took 6.156 seconds [25594.206308] ------------[ cut here ]------------ [25594.206316] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [25594.206319] Hardware name: 18494WU [25594.206321] Component: resume devices [25594.206322] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [25594.206367] Pid: 10966, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [25594.206369] Call Trace: [25594.206376] [] warn_slowpath_common+0x6d/0xa0 [25594.206380] [] ? suspend_test_finish+0x80/0x90 [25594.206384] [] ? suspend_test_finish+0x80/0x90 [25594.206388] [] warn_slowpath_fmt+0x26/0x30 [25594.206391] [] suspend_test_finish+0x80/0x90 [25594.206395] [] suspend_devices_and_enter+0x9f/0xd0 [25594.206400] [] ? printk+0x18/0x1c [25594.206404] [] enter_state+0xb9/0xf0 [25594.206407] [] state_store+0x6d/0xb0 [25594.206410] [] ? state_store+0x0/0xb0 [25594.206415] [] kobj_attr_store+0x20/0x30 [25594.206421] [] sysfs_write_file+0x90/0x100 [25594.206425] [] vfs_write+0x9a/0x190 [25594.206429] [] ? sysfs_write_file+0x0/0x100 [25594.206433] [] ? do_page_fault+0x19b/0x380 [25594.206436] [] sys_write+0x3d/0x70 [25594.206440] [] syscall_call+0x7/0xb [25594.206443] ---[ end trace 181a8deae270bcfe ]--- [25594.206479] PM: Finishing wakeup. [25594.206481] Restarting tasks ... done. [25594.208185] hub 1-0:1.0: over-current change on port 3 [25594.312051] hub 3-0:1.0: over-current change on port 1 [25594.418107] hub 3-0:1.0: over-current change on port 2 [25595.515604] tg3 0000:02:00.0: PME# disabled [25595.687957] ADDRCONF(NETDEV_UP): eth0: link is not ready [25595.688877] ADDRCONF(NETDEV_UP): eth1: link is not ready [25595.704608] hub 1-0:1.0: over-current change on port 4 [25596.288048] usb 4-1: new full speed USB device using uhci_hcd and address 7 [25596.505443] usb 4-1: configuration #1 chosen from 1 choice [25596.788402] [drm] Loading R300 Microcode [25596.788446] [drm] Num pipes: 1 [25596.927258] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [25597.300886] tg3: eth0: Link is up at 100 Mbps, full duplex. [25597.300890] tg3: eth0: Flow control is on for TX and on for RX. [25597.301099] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [25601.735609] hci_cmd_task: hci0 command tx timeout [25607.400069] eth1: no IPv6 routers present [25607.728065] eth0: no IPv6 routers present [27516.760079] usb 4-1: USB disconnect, address 7 [27516.760615] btusb_intr_complete: hci0 urb e9bccd80 failed to resubmit (19) [27516.760625] btusb_bulk_complete: hci0 urb e9bcc700 failed to resubmit (19) [27516.761613] btusb_bulk_complete: hci0 urb e9bcc380 failed to resubmit (19) [27516.761751] btusb_send_frame: hci0 urb f64fdd80 submission failed [27517.280505] tg3 0000:02:00.0: PME# enabled [27517.645137] [drm] Num pipes: 1 [27519.764838] PM: Syncing filesystems ... done. [27519.780154] PM: Preparing system for mem sleep [27519.780160] Freezing user space processes ... (elapsed 0.00 seconds) done. [27519.781744] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [27519.781796] PM: Entering mem sleep [27519.781812] Suspending console(s) (use no_console_suspend to debug) [27520.036075] sd 0:0:0:0: [sda] Synchronizing SCSI cache [27520.036245] sd 0:0:0:0: [sda] Stopping disk [27521.484519] nsc-ircc 00:0c: disabled [27521.484780] parport_pc 00:0b: disabled [27521.485052] serial 00:0a: disabled [27521.485124] eth1: Going into suspend... [27521.489196] ipw2200 0000:04:02.0: PCI INT A disabled [27521.489204] ACPI handle has no context! [27521.509061] ACPI handle has no context! [27521.524107] pci 0000:01:00.0: PCI INT A disabled [27521.540247] Intel ICH 0000:00:1e.2: PCI INT A disabled [27521.540286] ehci_hcd 0000:00:1d.7: PCI INT D disabled [27521.540294] uhci_hcd 0000:00:1d.3: PCI INT D disabled [27521.540300] uhci_hcd 0000:00:1d.2: PCI INT C disabled [27521.540307] uhci_hcd 0000:00:1d.1: PCI INT B disabled [27521.540313] uhci_hcd 0000:00:1d.0: PCI INT A disabled [27521.540345] PM: suspend devices took 1.760 seconds [27521.540636] ehci_hcd 0000:00:1d.7: PME# disabled [27521.588062] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [27521.620059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [27521.724046] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [27521.868055] ACPI: Preparing to enter system sleep state S3 [27522.532152] Disabling non-boot CPUs ... [27522.532206] Extended CMOS year: 2000 [27522.532206] Back to C! [27522.532206] CPU0: Thermal LVT vector (0xfa) already installed [27522.532206] Extended CMOS year: 2000 [27522.532206] Force enabled HPET at resume [27522.532206] ACPI: Waking up from system sleep state S3 [27523.700135] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27523.700183] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27523.700245] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [27523.868052] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [27523.868058] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [27523.868071] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [27523.868084] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [27523.900059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [27523.900065] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [27523.900078] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [27523.900092] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [27523.900103] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [27523.900116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [27523.900129] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [27523.900140] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [27523.900153] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [27523.900167] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [27523.900185] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [27523.900204] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [27523.900212] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [27523.900230] ehci_hcd 0000:00:1d.7: PME# disabled [27523.900290] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [27523.900327] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [27523.900397] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [27523.900503] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xecf30000) [27523.900529] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [27524.056051] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [27524.056059] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [27524.120014] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [27524.120021] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [27524.120045] usb usb2: root hub lost power or was reset [27524.120064] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [27524.120070] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [27524.120093] usb usb3: root hub lost power or was reset [27524.120119] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [27524.120125] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [27524.120148] usb usb4: root hub lost power or was reset [27524.120165] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [27524.120171] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [27524.120193] usb usb5: root hub lost power or was reset [27524.120210] ehci_hcd 0000:00:1d.7: PME# disabled [27524.120215] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [27524.120221] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [27524.120247] pci 0000:00:1e.0: setting latency timer to 64 [27524.120254] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [27524.120261] Intel ICH 0000:00:1e.2: setting latency timer to 64 [27525.133797] pci 0000:00:1e.3: PME# disabled [27525.133807] ata_piix 0000:00:1f.2: setting latency timer to 64 [27525.134101] pci 0000:01:00.0: PME# disabled [27525.134107] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [27525.134111] pci 0000:01:00.0: setting latency timer to 64 [27525.190071] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [27525.199185] eth1: Coming out of suspend... [27525.199194] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [27525.199820] serial 00:0a: activated [27525.200443] parport_pc 00:0b: activated [27525.201126] nsc-ircc 00:0c: activated [27525.336483] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [27525.336487] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [27525.337486] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [27525.338469] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [27525.360537] ata2.00: configured for UDMA/33 [27525.376773] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [27525.376776] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [27525.376780] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [27525.376783] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [27525.377233] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [27525.400741] ata1.00: configured for UDMA/100 [27525.424727] ata1.00: configured for UDMA/100 [27525.424730] ata1: EH complete [27525.869798] sd 0:0:0:0: [sda] Starting disk [27528.888021] [27528.888022] floppy driver state [27528.888024] ------------------- [27528.888027] now=6807222 last interrupt=4294902225 diff=6872293 last called handler=f80a2500 [27528.888029] timeout_message=lock fdc [27528.888031] last output bytes: [27528.888033] 18 80 4294892646 [27528.888035] 8 80 4294892646 [27528.888036] 8 80 4294892646 [27528.888038] 8 80 4294892646 [27528.888040] 8 80 4294892646 [27528.888041] 12 80 4294902046 [27528.888043] 0 90 4294902046 [27528.888045] 13 80 4294902046 [27528.888046] 0 90 4294902046 [27528.888048] 1a 90 4294902046 [27528.888050] 0 90 4294902046 [27528.888052] 3 80 4294902046 [27528.888053] c1 90 4294902046 [27528.888055] 10 90 4294902046 [27528.888057] 7 80 4294902046 [27528.888058] 0 90 4294902046 [27528.888060] 8 81 4294902133 [27528.888062] 7 80 4294902138 [27528.888063] 0 90 4294902138 [27528.888065] 8 81 4294902225 [27528.888067] last result at 4294902225 [27528.888068] last redo_fd_request at 6323241 [27528.888070] 70 0 [27528.888079] status=0 [27528.888080] fdc_busy=1 [27528.888081] do_floppy=f809e770 [27528.888083] cont=f80a7904 [27528.888085] current_req=(null) [27528.888086] command_status=-1 [27528.888087] [27528.888091] floppy0: floppy timeout called [27529.000069] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [27529.297808] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [27529.297810] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [27529.556064] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [27530.131062] PM: resume devices took 6.072 seconds [27530.131064] ------------[ cut here ]------------ [27530.131072] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [27530.131075] Hardware name: 18494WU [27530.131076] Component: resume devices [27530.131078] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [27530.131123] Pid: 11761, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [27530.131125] Call Trace: [27530.131132] [] warn_slowpath_common+0x6d/0xa0 [27530.131136] [] ? suspend_test_finish+0x80/0x90 [27530.131140] [] ? suspend_test_finish+0x80/0x90 [27530.131144] [] warn_slowpath_fmt+0x26/0x30 [27530.131147] [] suspend_test_finish+0x80/0x90 [27530.131151] [] suspend_devices_and_enter+0x9f/0xd0 [27530.131156] [] ? printk+0x18/0x1c [27530.131160] [] enter_state+0xb9/0xf0 [27530.131163] [] state_store+0x6d/0xb0 [27530.131166] [] ? state_store+0x0/0xb0 [27530.131171] [] kobj_attr_store+0x20/0x30 [27530.131177] [] sysfs_write_file+0x90/0x100 [27530.131182] [] vfs_write+0x9a/0x190 [27530.131185] [] ? sysfs_write_file+0x0/0x100 [27530.131189] [] ? do_page_fault+0x19b/0x380 [27530.131193] [] sys_write+0x3d/0x70 [27530.131196] [] syscall_call+0x7/0xb [27530.131199] ---[ end trace 181a8deae270bcff ]--- [27530.131235] PM: Finishing wakeup. [27530.131237] Restarting tasks ... done. [27530.132924] hub 1-0:1.0: over-current change on port 3 [27530.236038] hub 3-0:1.0: over-current change on port 1 [27530.340047] hub 3-0:1.0: over-current change on port 2 [27531.355478] tg3 0000:02:00.0: PME# disabled [27531.527650] ADDRCONF(NETDEV_UP): eth0: link is not ready [27531.528610] ADDRCONF(NETDEV_UP): eth1: link is not ready [27531.548458] hub 1-0:1.0: over-current change on port 4 [27532.132056] usb 4-1: new full speed USB device using uhci_hcd and address 8 [27532.349462] usb 4-1: configuration #1 chosen from 1 choice [27532.629662] [drm] Loading R300 Microcode [27532.629707] [drm] Num pipes: 1 [27532.908776] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [27533.161728] tg3: eth0: Link is up at 100 Mbps, full duplex. [27533.161736] tg3: eth0: Flow control is on for TX and on for RX. [27533.162219] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [27537.586677] hci_cmd_task: hci0 command tx timeout [27543.000061] eth1: no IPv6 routers present [27543.944029] eth0: no IPv6 routers present [32363.672110] usb 4-1: USB disconnect, address 8 [32363.672208] btusb_intr_complete: hci0 urb f65b2900 failed to resubmit (19) [32363.672221] btusb_bulk_complete: hci0 urb f65b2100 failed to resubmit (19) [32363.673207] btusb_bulk_complete: hci0 urb f65b2e80 failed to resubmit (19) [32363.673377] btusb_send_frame: hci0 urb f258e780 submission failed [32364.144437] tg3 0000:02:00.0: PME# enabled [32364.495191] [drm] Num pipes: 1 [32374.442390] PM: Syncing filesystems ... done. [32374.456807] PM: Preparing system for mem sleep [32374.456813] Freezing user space processes ... (elapsed 0.00 seconds) done. [32374.458444] Freezing remaining freezable tasks ... (elapsed 0.00 seconds) done. [32374.458500] PM: Entering mem sleep [32374.458516] Suspending console(s) (use no_console_suspend to debug) [32374.704076] sd 0:0:0:0: [sda] Synchronizing SCSI cache [32374.730017] sd 0:0:0:0: [sda] Stopping disk [32376.180462] nsc-ircc 00:0c: disabled [32376.180721] parport_pc 00:0b: disabled [32376.180995] serial 00:0a: disabled [32376.181067] eth1: Going into suspend... [32376.182052] ipw2200 0000:04:02.0: PCI INT A disabled [32376.182060] ACPI handle has no context! [32376.201062] ACPI handle has no context! [32376.216107] pci 0000:01:00.0: PCI INT A disabled [32376.232247] Intel ICH 0000:00:1e.2: PCI INT A disabled [32376.232287] ehci_hcd 0000:00:1d.7: PCI INT D disabled [32376.232294] uhci_hcd 0000:00:1d.3: PCI INT D disabled [32376.232301] uhci_hcd 0000:00:1d.2: PCI INT C disabled [32376.232307] uhci_hcd 0000:00:1d.1: PCI INT B disabled [32376.232314] uhci_hcd 0000:00:1d.0: PCI INT A disabled [32376.232345] PM: suspend devices took 1.776 seconds [32376.232639] ehci_hcd 0000:00:1d.7: PME# disabled [32376.280063] ehci_hcd 0000:00:1d.7: power state changed by ACPI to D3 [32376.320060] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D3 [32376.424051] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [32376.568055] ACPI: Preparing to enter system sleep state S3 [32377.224153] Disabling non-boot CPUs ... [32377.224206] Extended CMOS year: 2000 [32377.224206] Back to C! [32377.224206] CPU0: Thermal LVT vector (0xfa) already installed [32377.224206] Extended CMOS year: 2000 [32377.224206] Force enabled HPET at resume [32377.224206] ACPI: Waking up from system sleep state S3 [32378.376135] pcieport-driver 0000:00:01.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [32378.376182] pcieport-driver 0000:00:1c.0: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [32378.376244] pcieport-driver 0000:00:1c.2: restoring config space at offset 0x1 (was 0x100107, writing 0x100507) [32378.544052] uhci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [32378.544058] uhci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x100, writing 0x10b) [32378.544071] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x8 (was 0x1, writing 0x1801) [32378.544085] uhci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [32378.576059] uhci_hcd 0000:00:1d.1: power state changed by ACPI to D0 [32378.576065] uhci_hcd 0000:00:1d.1: restoring config space at offset 0xf (was 0x200, writing 0x20b) [32378.576078] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x8 (was 0x1, writing 0x1821) [32378.576091] uhci_hcd 0000:00:1d.1: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [32378.576103] uhci_hcd 0000:00:1d.2: restoring config space at offset 0xf (was 0x300, writing 0x30b) [32378.576116] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x8 (was 0x1, writing 0x1841) [32378.576129] uhci_hcd 0000:00:1d.2: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [32378.576140] uhci_hcd 0000:00:1d.3: restoring config space at offset 0xf (was 0x400, writing 0x40b) [32378.576153] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x8 (was 0x1, writing 0x1861) [32378.576167] uhci_hcd 0000:00:1d.3: restoring config space at offset 0x1 (was 0x2800000, writing 0x2800001) [32378.576186] ehci_hcd 0000:00:1d.7: restoring config space at offset 0xf (was 0x400, writing 0x40b) [32378.576205] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x4 (was 0x0, writing 0xa8000000) [32378.576213] ehci_hcd 0000:00:1d.7: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [32378.576231] ehci_hcd 0000:00:1d.7: PME# disabled [32378.576291] Intel ICH 0000:00:1e.2: restoring config space at offset 0x1 (was 0x2900007, writing 0x2900003) [32378.576328] pci 0000:00:1e.3: restoring config space at offset 0x1 (was 0x2900005, writing 0x2900001) [32378.576398] ata_piix 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00005, writing 0x2b80005) [32378.576504] tg3 0000:02:00.0: restoring config space at offset 0xc (was 0x0, writing 0xecf30000) [32378.576531] tg3 0000:02:00.0: restoring config space at offset 0x1 (was 0x100102, writing 0x100106) [32378.732051] ohci1394 0000:04:00.1: restoring config space at offset 0x3 (was 0x800000, writing 0x804000) [32378.732060] ohci1394 0000:04:00.1: restoring config space at offset 0x1 (was 0x2100000, writing 0x2100106) [32378.796706] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [32378.796713] uhci_hcd 0000:00:1d.0: setting latency timer to 64 [32378.796736] usb usb2: root hub lost power or was reset [32378.796755] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [32378.796761] uhci_hcd 0000:00:1d.1: setting latency timer to 64 [32378.796783] usb usb3: root hub lost power or was reset [32378.796810] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18 [32378.796816] uhci_hcd 0000:00:1d.2: setting latency timer to 64 [32378.796837] usb usb4: root hub lost power or was reset [32378.796854] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [32378.796860] uhci_hcd 0000:00:1d.3: setting latency timer to 64 [32378.796882] usb usb5: root hub lost power or was reset [32378.796900] ehci_hcd 0000:00:1d.7: PME# disabled [32378.796905] ehci_hcd 0000:00:1d.7: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [32378.796911] ehci_hcd 0000:00:1d.7: setting latency timer to 64 [32378.796938] pci 0000:00:1e.0: setting latency timer to 64 [32378.796944] Intel ICH 0000:00:1e.2: PCI INT A -> GSI 22 (level, low) -> IRQ 22 [32378.796951] Intel ICH 0000:00:1e.2: setting latency timer to 64 [32379.809792] pci 0000:00:1e.3: PME# disabled [32379.809802] ata_piix 0000:00:1f.2: setting latency timer to 64 [32379.810091] pci 0000:01:00.0: PME# disabled [32379.810097] pci 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16 [32379.810101] pci 0000:01:00.0: setting latency timer to 64 [32379.866072] ohci1394: fw-host0: OHCI-1394 1.0 (PCI): IRQ=[17] MMIO=[b1000000-b10007ff] Max Packet=[2048] IR/IT contexts=[4/4] [32379.875122] eth1: Coming out of suspend... [32379.875130] ipw2200 0000:04:02.0: PCI INT A -> GSI 21 (level, low) -> IRQ 21 [32379.875759] serial 00:0a: activated [32379.876383] parport_pc 00:0b: activated [32379.877069] nsc-ircc 00:0c: activated [32380.012485] ata2.00: ACPI cmd ef/03:42:00:00:00:a0 filtered out [32380.012489] ata2.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [32380.013495] ata2.00: ACPI cmd e3/00:1f:00:00:00:a0 succeeded [32380.014522] ata2.00: ACPI cmd e3/00:02:00:00:00:a0 succeeded [32380.036419] ata2.00: configured for UDMA/33 [32380.116770] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 succeeded [32380.116773] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 filtered out [32380.116777] ata1.00: ACPI cmd ef/03:45:00:00:00:a0 filtered out [32380.116780] ata1.00: ACPI cmd ef/03:0c:00:00:00:a0 filtered out [32380.117230] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 succeeded [32380.140792] ata1.00: configured for UDMA/100 [32380.164793] ata1.00: configured for UDMA/100 [32380.164796] ata1: EH complete [32380.402153] sd 0:0:0:0: [sda] Starting disk [32383.452020] [32383.452022] floppy driver state [32383.452023] ------------------- [32383.452026] now=8020863 last interrupt=4294902225 diff=8085934 last called handler=f80a2500 [32383.452029] timeout_message=lock fdc [32383.452030] last output bytes: [32383.452032] 18 80 4294892646 [32383.452034] 8 80 4294892646 [32383.452036] 8 80 4294892646 [32383.452037] 8 80 4294892646 [32383.452039] 8 80 4294892646 [32383.452041] 12 80 4294902046 [32383.452042] 0 90 4294902046 [32383.452044] 13 80 4294902046 [32383.452046] 0 90 4294902046 [32383.452047] 1a 90 4294902046 [32383.452049] 0 90 4294902046 [32383.452051] 3 80 4294902046 [32383.452052] c1 90 4294902046 [32383.452054] 10 90 4294902046 [32383.452056] 7 80 4294902046 [32383.452058] 0 90 4294902046 [32383.452059] 8 81 4294902133 [32383.452061] 7 80 4294902138 [32383.452063] 0 90 4294902138 [32383.452064] 8 81 4294902225 [32383.452066] last result at 4294902225 [32383.452067] last redo_fd_request at 6807222 [32383.452069] 70 0 [32383.452078] status=0 [32383.452079] fdc_busy=1 [32383.452081] do_floppy=f809e770 [32383.452082] cont=f80a7904 [32383.452084] current_req=(null) [32383.452085] command_status=-1 [32383.452087] [32383.452090] floppy0: floppy timeout called [32383.564069] usb 1-3: reset high speed USB device using ehci_hcd and address 2 [32383.861810] snd-usb-audio 1-3:1.2: no reset_resume for driver snd-usb-audio? [32383.861813] snd-usb-audio 1-3:1.3: no reset_resume for driver snd-usb-audio? [32384.120067] usb 3-2: reset low speed USB device using uhci_hcd and address 2 [32384.460198] PM: resume devices took 5.728 seconds [32384.460200] ------------[ cut here ]------------ [32384.460209] WARNING: at /build/buildd/linux-2.6.31/kernel/power/suspend_test.c:52 suspend_test_finish+0x80/0x90() [32384.460211] Hardware name: 18494WU [32384.460213] Component: resume devices [32384.460214] Modules linked in: binfmt_misc bridge stp bnep snd_intel8x0 snd_ac97_codec ac97_bus snd_usb_audio snd_usb_lib snd_hwdep snd_pcm_oss snd_seq_dummy snd_mixer_oss snd_pcm snd_seq_oss snd_seq_midi pcmcia snd_rawmidi uvcvideo ipw2200 snd_seq_midi_event sbp2 yenta_socket snd_seq iptable_filter ppdev joydev libipw snd_timer nsc_ircc rsrc_nonstatic snd_seq_device videodev ip_tables parport_pc lp lib80211 snd snd_page_alloc pcmcia_core irda soundcore psmouse btusb v4l1_compat x_tables parport crc_ccitt serio_raw thinkpad_acpi led_class nvram usbhid ohci1394 ieee1394 tg3 radeon ttm drm i2c_algo_bit video output floppy intel_agp agpgart [32384.460260] Pid: 12868, comm: pm-suspend Tainted: G W 2.6.31-14-generic #48-Ubuntu [32384.460262] Call Trace: [32384.460269] [] warn_slowpath_common+0x6d/0xa0 [32384.460273] [] ? suspend_test_finish+0x80/0x90 [32384.460277] [] ? suspend_test_finish+0x80/0x90 [32384.460281] [] warn_slowpath_fmt+0x26/0x30 [32384.460284] [] suspend_test_finish+0x80/0x90 [32384.460288] [] suspend_devices_and_enter+0x9f/0xd0 [32384.460293] [] ? printk+0x18/0x1c [32384.460297] [] enter_state+0xb9/0xf0 [32384.460300] [] state_store+0x6d/0xb0 [32384.460303] [] ? state_store+0x0/0xb0 [32384.460308] [] kobj_attr_store+0x20/0x30 [32384.460314] [] sysfs_write_file+0x90/0x100 [32384.460318] [] vfs_write+0x9a/0x190 [32384.460322] [] ? sysfs_write_file+0x0/0x100 [32384.460326] [] ? do_page_fault+0x19b/0x380 [32384.460329] [] sys_write+0x3d/0x70 [32384.460333] [] syscall_call+0x7/0xb [32384.460336] ---[ end trace 181a8deae270bd00 ]--- [32384.460372] PM: Finishing wakeup. [32384.460373] Restarting tasks ... done. [32384.462061] hub 1-0:1.0: over-current change on port 3 [32384.568071] hub 3-0:1.0: over-current change on port 1 [32384.677038] hub 3-0:1.0: over-current change on port 2 [32385.608310] tg3 0000:02:00.0: PME# disabled [32385.780575] ADDRCONF(NETDEV_UP): eth0: link is not ready [32385.781484] ADDRCONF(NETDEV_UP): eth1: link is not ready [32385.803462] hub 1-0:1.0: over-current change on port 4 [32386.504049] usb 4-1: new full speed USB device using uhci_hcd and address 9 [32386.728270] usb 4-1: configuration #1 chosen from 1 choice [32386.882655] [drm] Loading R300 Microcode [32386.882700] [drm] Num pipes: 1 [32387.393639] tg3: eth0: Link is up at 100 Mbps, full duplex. [32387.393647] tg3: eth0: Flow control is on for TX and on for RX. [32387.394125] ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [32387.599982] ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready [32388.101290] psmouse serio2: ID: 10 00 64 [32391.602788] IBM TrackPoint firmware: 0x0e, buttons: 3/3 [32391.880119] input: TPPS/2 IBM TrackPoint as /devices/platform/i8042/serio1/serio2/input/input11 [32391.955267] hci_cmd_task: hci0 command tx timeout [32397.616066] eth0: no IPv6 routers present [32398.576029] eth1: no IPv6 routers present