Return-Path: Received: (majordomo@vger.kernel.org) by vger.kernel.org via listexpand id S1753583Ab0LFQHq (ORCPT ); Mon, 6 Dec 2010 11:07:46 -0500 Received: from bombadil.infradead.org ([18.85.46.34]:46199 "EHLO bombadil.infradead.org" rhost-flags-OK-OK-OK-OK) by vger.kernel.org with ESMTP id S1751942Ab0LFQHo (ORCPT ); Mon, 6 Dec 2010 11:07:44 -0500 Date: Mon, 6 Dec 2010 11:07:28 -0500 From: Kyle McMartin To: Kyle McMartin Cc: Tejun Heo , Jeff Garzik , Andrew Morton , Linus Torvalds , linux-ide@vger.kernel.org, LKML Subject: Re: [git patches] libata updates for 2.6.37 Message-ID: <20101206160728.GP25668@bombadil.infradead.org> References: <20101130210938.GF25668@bombadil.infradead.org> <4CF62EE0.7040607@kernel.org> <20101201124445.GG25668@bombadil.infradead.org> <4CF6595A.6010104@kernel.org> <20101201155026.GH25668@bombadil.infradead.org> <20101201200907.GI25668@bombadil.infradead.org> <4CF77225.3040904@kernel.org> <20101202212346.GK25668@bombadil.infradead.org> <4CF8C204.8010904@kernel.org> <20101203115902.GL25668@bombadil.infradead.org> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="s2ZSL+KKDSLx8OML" Content-Disposition: inline In-Reply-To: <20101203115902.GL25668@bombadil.infradead.org> User-Agent: Mutt/1.5.21 (2010-09-15) Sender: linux-kernel-owner@vger.kernel.org List-ID: X-Mailing-List: linux-kernel@vger.kernel.org Content-Length: 126286 Lines: 1949 --s2ZSL+KKDSLx8OML Content-Type: text/plain; charset=us-ascii Content-Disposition: inline On Fri, Dec 03, 2010 at 06:59:02AM -0500, Kyle McMartin wrote: > On Fri, Dec 03, 2010 at 11:10:12AM +0100, Tejun Heo wrote: > > > This gets triggered a bunch on boot-up: > > > > Yeah, that's expected. Can you please do the usual testing routine > > and trigger the EH giving up message and then attach the full kernel > > log? > > > OK, attached is a couple days worth of suspend/resume, hopefully that turns up what you're looking for. regards, Kyle --s2ZSL+KKDSLx8OML Content-Type: text/plain; charset=us-ascii Content-Disposition: attachment; filename="dmesg.dreadnought-2010-12-06.txt" nted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45717.620218] Call Trace: [45717.620221] [] ata_eh_set_pending+0x3e/0x85 [45717.620223] [] ata_port_schedule_eh+0x43/0x50 [45717.620225] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45717.620228] [] ata_host_request_pm+0xcb/0x122 [45717.620230] [] ata_host_suspend+0x2e/0x42 [45717.620233] [] ata_pci_device_suspend+0x2d/0x4b [45717.620235] [] ahci_pci_device_suspend+0x74/0x7b [45717.620238] [] pci_legacy_suspend+0x3a/0xbd [45717.620240] [] pci_pm_suspend+0x4c/0xf5 [45717.620242] [] ? need_resched+0x23/0x2d [45717.620244] [] pm_op+0x8b/0x149 [45717.620247] [] __device_suspend+0x12e/0x19e [45717.620249] [] async_suspend+0x21/0x62 [45717.620251] [] async_run_entry_fn+0x9e/0x12b [45717.620253] [] ? async_run_entry_fn+0x0/0x12b [45717.620256] [] process_one_work+0x18c/0x285 [45717.620258] [] worker_thread+0x104/0x1a4 [45717.620260] [] ? worker_thread+0x0/0x1a4 [45717.620262] [] kthread+0x82/0x8a [45717.620264] [] kernel_thread_helper+0x4/0x10 [45717.620267] [] ? kthread+0x0/0x8a [45717.620269] [] ? kernel_thread_helper+0x0/0x10 [45717.620311] ata6: XXX SET EH PENDING, fastdrain=1 [45717.620317] Pid: 14697, comm: kworker/u:5 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45717.620320] Call Trace: [45717.620326] [] ata_eh_set_pending+0x3e/0x85 [45717.620331] [] ata_port_schedule_eh+0x43/0x50 [45717.620337] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45717.620342] [] ata_host_request_pm+0xcb/0x122 [45717.620348] [] ata_host_suspend+0x2e/0x42 [45717.620354] [] ata_pci_device_suspend+0x2d/0x4b [45717.620359] [] ahci_pci_device_suspend+0x74/0x7b [45717.620365] [] pci_legacy_suspend+0x3a/0xbd [45717.620371] [] pci_pm_suspend+0x4c/0xf5 [45717.620376] [] ? need_resched+0x23/0x2d [45717.620381] [] pm_op+0x8b/0x149 [45717.620386] [] __device_suspend+0x12e/0x19e [45717.620391] [] async_suspend+0x21/0x62 [45717.620396] [] async_run_entry_fn+0x9e/0x12b [45717.620401] [] ? async_run_entry_fn+0x0/0x12b [45717.620407] [] process_one_work+0x18c/0x285 [45717.620412] [] worker_thread+0x104/0x1a4 [45717.620417] [] ? worker_thread+0x0/0x1a4 [45717.620422] [] kthread+0x82/0x8a [45717.620427] [] kernel_thread_helper+0x4/0x10 [45717.620433] [] ? kthread+0x0/0x8a [45717.620438] [] ? kernel_thread_helper+0x0/0x10 [45717.631130] PM: suspend of devices complete after 606.817 msecs [45717.649957] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D3 [45717.668943] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D3 [45717.669054] PM: late suspend of devices complete after 37.940 msecs [45717.669257] ACPI: Preparing to enter system sleep state S3 [45717.850865] PM: Saving platform NVS memory [45717.988665] Disabling non-boot CPUs ... [45717.991228] CPU 1 is now offline [45718.009183] CPU 2 is now offline [45718.015853] Broke affinity for irq 12 [45718.016896] CPU 3 is now offline [45718.016898] SMP alternatives: switching to UP code [45718.019763] Extended CMOS year: 2000 [45718.020126] Back to C! [45718.020130] PM: Restoring platform NVS memory [45718.167211] microcode: CPU0 updated to revision 0xc, date = 2010-06-10 [45718.167534] Extended CMOS year: 2000 [45718.167591] Enabling non-boot CPUs ... [45718.167686] SMP alternatives: switching to SMP code [45718.170250] Booting Node 0 Processor 1 APIC 0x1 [45718.260684] NMI watchdog enabled, takes one hw-pmu counter. [45718.261112] microcode: CPU1 updated to revision 0xc, date = 2010-06-10 [45718.277487] CPU1 is up [45718.277607] Booting Node 0 Processor 2 APIC 0x4 [45718.368562] NMI watchdog enabled, takes one hw-pmu counter. [45718.369005] microcode: CPU2 updated to revision 0xc, date = 2010-06-10 [45718.384397] CPU2 is up [45718.384564] Booting Node 0 Processor 3 APIC 0x5 [45718.475652] NMI watchdog enabled, takes one hw-pmu counter. [45718.476460] microcode: CPU3 updated to revision 0xc, date = 2010-06-10 [45718.492526] CPU3 is up [45718.494488] ACPI: Waking up from system sleep state S3 [45718.820466] thinkpad_acpi: EC reports that Thermal Table has changed [45718.869272] i915 0000:00:02.0: restoring config space at offset 0x1 (was 0x900007, writing 0x900407) [45718.869391] ehci_hcd 0000:00:1a.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [45718.869414] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728000) [45718.869423] ehci_hcd 0000:00:1a.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [45718.917124] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [45718.949089] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [45718.949283] pci 0000:00:1c.4: restoring config space at offset 0xf (was 0x100, writing 0x4010b) [45718.949297] pci 0000:00:1c.4: restoring config space at offset 0x9 (was 0x10001, writing 0x1fff1) [45718.949303] pci 0000:00:1c.4: restoring config space at offset 0x8 (was 0x0, writing 0xf240f240) [45718.949309] pci 0000:00:1c.4: restoring config space at offset 0x7 (was 0x0, writing 0xf0) [45718.949319] pci 0000:00:1c.4: restoring config space at offset 0x3 (was 0x810000, writing 0x810010) [45718.949326] pci 0000:00:1c.4: restoring config space at offset 0x1 (was 0x100000, writing 0x100107) [45718.949361] ehci_hcd 0000:00:1d.0: restoring config space at offset 0xf (was 0x400, writing 0x40b) [45718.949381] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x4 (was 0x0, writing 0xf2728400) [45718.949390] ehci_hcd 0000:00:1d.0: restoring config space at offset 0x1 (was 0x2900000, writing 0x2900102) [45718.973076] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [45718.997062] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [45718.997187] ahci 0000:00:1f.2: restoring config space at offset 0x1 (was 0x2b00007, writing 0x2b00407) [45718.997260] pci 0000:00:1f.6: restoring config space at offset 0xf (was 0x400, writing 0x40b) [45718.997284] pci 0000:00:1f.6: restoring config space at offset 0x1 (was 0x100000, writing 0x100002) [45718.997371] iwlagn 0000:02:00.0: restoring config space at offset 0x1 (was 0x100106, writing 0x100506) [45718.997526] PM: early resume of devices complete after 128.437 msecs [45718.997791] i915 0000:00:02.0: power state changed by ACPI to D0 [45718.997811] i915 0000:00:02.0: power state changed by ACPI to D0 [45718.997823] e1000e 0000:00:19.0: wake-up capability disabled by ACPI [45718.997827] i915 0000:00:02.0: setting latency timer to 64 [45718.997831] e1000e 0000:00:19.0: PME# disabled [45718.997900] HDA Intel 0000:00:1b.0: PCI INT B -> GSI 17 (level, low) -> IRQ 17 [45718.997910] HDA Intel 0000:00:1b.0: setting latency timer to 64 [45718.997991] HDA Intel 0000:00:1b.0: irq 44 for MSI/MSI-X [45718.998008] e1000e 0000:00:19.0: irq 45 for MSI/MSI-X [45718.998056] pci 0000:00:1c.0: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [45718.998062] pci 0000:00:1c.0: setting latency timer to 64 [45718.998073] pci 0000:00:1c.3: PCI INT D -> GSI 23 (level, low) -> IRQ 23 [45718.998080] pci 0000:00:1c.3: setting latency timer to 64 [45718.998090] pci 0000:00:1c.4: PCI INT A -> GSI 20 (level, low) -> IRQ 20 [45718.998096] pci 0000:00:1c.4: setting latency timer to 64 [45718.998148] pci 0000:00:1e.0: setting latency timer to 64 [45718.998169] ahci 0000:00:1f.2: setting latency timer to 64 [45718.998212] ata1: XXX SET EH PENDING, fastdrain=1 [45718.998215] Pid: 14725, comm: kworker/u:15 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45718.998218] Call Trace: [45718.998228] [] ata_eh_set_pending+0x3e/0x85 [45718.998233] [] ata_port_schedule_eh+0x43/0x50 [45718.998240] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45718.998245] [] ata_host_request_pm+0xcb/0x122 [45718.998249] [] ata_host_resume+0x25/0x37 [45718.998254] [] ahci_pci_device_resume+0x58/0x66 [45718.998262] [] pci_legacy_resume+0x38/0x47 [45718.998266] [] pci_pm_resume+0x57/0x8b [45718.998272] [] pm_op+0xa9/0x149 [45718.998276] [] device_resume+0x9d/0x1b6 [45718.998279] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45718.998283] [] async_resume+0x21/0x62 [45718.998290] [] async_run_entry_fn+0x9e/0x12b [45718.998294] [] ? async_run_entry_fn+0x0/0x12b [45718.998300] [] process_one_work+0x18c/0x285 [45718.998305] [] worker_thread+0x104/0x1a4 [45718.998308] [] ? worker_thread+0x0/0x1a4 [45718.998312] [] kthread+0x82/0x8a [45718.998318] [] kernel_thread_helper+0x4/0x10 [45718.998322] [] ? kthread+0x0/0x8a [45718.998326] [] ? kernel_thread_helper+0x0/0x10 [45718.998334] ata2: XXX SET EH PENDING, fastdrain=1 [45718.998337] Pid: 14725, comm: kworker/u:15 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45718.998339] Call Trace: [45718.998343] [] ata_eh_set_pending+0x3e/0x85 [45718.998347] [] ata_port_schedule_eh+0x43/0x50 [45718.998351] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45718.998355] [] ata_host_request_pm+0xcb/0x122 [45718.998359] [] ata_host_resume+0x25/0x37 [45718.998363] [] ahci_pci_device_resume+0x58/0x66 [45718.998367] [] pci_legacy_resume+0x38/0x47 [45718.998371] [] pci_pm_resume+0x57/0x8b [45718.998375] [] pm_op+0xa9/0x149 [45718.998379] [] device_resume+0x9d/0x1b6 [45718.998382] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45718.998386] [] async_resume+0x21/0x62 [45718.998390] [] async_run_entry_fn+0x9e/0x12b [45718.998394] [] ? async_run_entry_fn+0x0/0x12b [45718.998398] [] process_one_work+0x18c/0x285 [45718.998402] [] worker_thread+0x104/0x1a4 [45718.998406] [] ? worker_thread+0x0/0x1a4 [45718.998409] [] kthread+0x82/0x8a [45718.998413] [] kernel_thread_helper+0x4/0x10 [45718.998417] [] ? kthread+0x0/0x8a [45718.998421] [] ? kernel_thread_helper+0x0/0x10 [45718.998427] ata3: XXX SET EH PENDING, fastdrain=1 [45718.998430] Pid: 14725, comm: kworker/u:15 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45718.998432] Call Trace: [45718.998435] [] ata_eh_set_pending+0x3e/0x85 [45718.998439] [] ata_port_schedule_eh+0x43/0x50 [45718.998443] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45718.998448] [] ata_host_request_pm+0xcb/0x122 [45718.998452] [] ata_host_resume+0x25/0x37 [45718.998455] [] ahci_pci_device_resume+0x58/0x66 [45718.998460] [] pci_legacy_resume+0x38/0x47 [45718.998464] [] pci_pm_resume+0x57/0x8b [45718.998467] [] pm_op+0xa9/0x149 [45718.998471] [] device_resume+0x9d/0x1b6 [45718.998475] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45718.998479] [] async_resume+0x21/0x62 [45718.998483] [] async_run_entry_fn+0x9e/0x12b [45718.998486] [] ? async_run_entry_fn+0x0/0x12b [45718.998491] [] process_one_work+0x18c/0x285 [45718.998494] [] worker_thread+0x104/0x1a4 [45718.998498] [] ? worker_thread+0x0/0x1a4 [45718.998501] [] kthread+0x82/0x8a [45718.998505] [] kernel_thread_helper+0x4/0x10 [45718.998509] [] ? kthread+0x0/0x8a [45718.998513] [] ? kernel_thread_helper+0x0/0x10 [45718.998549] ata4: XXX SET EH PENDING, fastdrain=1 [45718.998552] Pid: 14725, comm: kworker/u:15 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45718.998554] Call Trace: [45718.998557] [] ata_eh_set_pending+0x3e/0x85 [45718.998561] [] ata_port_schedule_eh+0x43/0x50 [45718.998565] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45718.998570] [] ata_host_request_pm+0xcb/0x122 [45718.998574] [] ata_host_resume+0x25/0x37 [45718.998578] [] ahci_pci_device_resume+0x58/0x66 [45718.998582] [] pci_legacy_resume+0x38/0x47 [45718.998586] [] pci_pm_resume+0x57/0x8b [45718.998590] [] pm_op+0xa9/0x149 [45718.998593] [] device_resume+0x9d/0x1b6 [45718.998597] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45718.998601] [] async_resume+0x21/0x62 [45718.998605] [] async_run_entry_fn+0x9e/0x12b [45718.998609] [] ? async_run_entry_fn+0x0/0x12b [45718.998613] [] process_one_work+0x18c/0x285 [45718.998617] [] worker_thread+0x104/0x1a4 [45718.998620] [] ? worker_thread+0x0/0x1a4 [45718.998624] [] kthread+0x82/0x8a [45718.998628] [] kernel_thread_helper+0x4/0x10 [45718.998632] [] ? kthread+0x0/0x8a [45718.998636] [] ? kernel_thread_helper+0x0/0x10 [45718.998642] ata5: XXX SET EH PENDING, fastdrain=1 [45718.998645] Pid: 14725, comm: kworker/u:15 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45718.998648] Call Trace: [45718.998651] [] ata_eh_set_pending+0x3e/0x85 [45718.998655] [] ata_port_schedule_eh+0x43/0x50 [45718.998659] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45718.998663] [] ata_host_request_pm+0xcb/0x122 [45718.998668] [] ata_host_resume+0x25/0x37 [45718.998671] [] ahci_pci_device_resume+0x58/0x66 [45718.998676] [] pci_legacy_resume+0x38/0x47 [45718.998680] [] pci_pm_resume+0x57/0x8b [45718.998683] [] pm_op+0xa9/0x149 [45718.998687] [] device_resume+0x9d/0x1b6 [45718.998691] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45718.998695] [] async_resume+0x21/0x62 [45718.998699] [] async_run_entry_fn+0x9e/0x12b [45718.998702] [] ? async_run_entry_fn+0x0/0x12b [45718.998707] [] process_one_work+0x18c/0x285 [45718.998710] [] worker_thread+0x104/0x1a4 [45718.998714] [] ? worker_thread+0x0/0x1a4 [45718.998717] [] kthread+0x82/0x8a [45718.998721] [] kernel_thread_helper+0x4/0x10 [45718.998725] [] ? kthread+0x0/0x8a [45718.998729] [] ? kernel_thread_helper+0x0/0x10 [45718.998736] ata6: XXX SET EH PENDING, fastdrain=1 [45718.998739] Pid: 14725, comm: kworker/u:15 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45718.998741] Call Trace: [45718.998745] [] ata_eh_set_pending+0x3e/0x85 [45718.998749] [] ata_port_schedule_eh+0x43/0x50 [45718.998752] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45718.998757] [] ata_host_request_pm+0xcb/0x122 [45718.998761] [] ata_host_resume+0x25/0x37 [45718.998765] [] ahci_pci_device_resume+0x58/0x66 [45718.998769] [] pci_legacy_resume+0x38/0x47 [45718.998773] [] pci_pm_resume+0x57/0x8b [45718.998776] [] pm_op+0xa9/0x149 [45718.998780] [] device_resume+0x9d/0x1b6 [45718.998784] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45718.998788] [] async_resume+0x21/0x62 [45718.998792] [] async_run_entry_fn+0x9e/0x12b [45718.998796] [] ? async_run_entry_fn+0x0/0x12b [45718.998800] [] process_one_work+0x18c/0x285 [45718.998803] [] worker_thread+0x104/0x1a4 [45718.998807] [] ? worker_thread+0x0/0x1a4 [45718.998810] [] kthread+0x82/0x8a [45718.998815] [] kernel_thread_helper+0x4/0x10 [45718.998819] [] ? kthread+0x0/0x8a [45718.998822] [] ? kernel_thread_helper+0x0/0x10 [45718.999106] sd 0:0:0:0: [sda] Starting disk [45719.000928] ata1: XXX SET EH PENDING, fastdrain=0 [45719.000932] Pid: 14696, comm: kworker/u:4 Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45719.000935] Call Trace: [45719.000937] [] ata_eh_set_pending+0x3e/0x85 [45719.000947] [] ata_do_link_abort+0x44/0x94 [45719.000951] [] ata_port_abort+0x10/0x12 [45719.000955] [] ata_port_freeze+0x40/0x45 [45719.000960] [] ahci_interrupt+0x51e/0x5a1 [45719.000968] [] ? run_timer_softirq+0x28f/0x29e [45719.000973] [] ? sched_clock_cpu+0x42/0xc6 [45719.000981] [] handle_IRQ_event+0x58/0x122 [45719.000988] [] ? apic_write+0x16/0x18 [45719.000994] [] handle_edge_irq+0xfa/0x145 [45719.000999] [] handle_irq+0x88/0x90 [45719.001004] [] do_IRQ+0x58/0xb0 [45719.001009] [] ret_from_intr+0x0/0x11 [45719.001012] [] ? i965_write_entry+0x0/0x2c [45719.001023] [] ? intel_fake_agp_configure+0xdb/0x105 [45719.001028] [] agp_intel_resume+0x1c/0x23 [45719.001032] [] pci_legacy_resume+0x38/0x47 [45719.001037] [] pci_pm_resume+0x57/0x8b [45719.001041] [] pm_op+0xa9/0x149 [45719.001045] [] device_resume+0x9d/0x1b6 [45719.001049] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45719.001054] [] async_resume+0x21/0x62 [45719.001058] [] async_run_entry_fn+0x9e/0x12b [45719.001063] [] ? async_run_entry_fn+0x0/0x12b [45719.001068] [] process_one_work+0x18c/0x285 [45719.001072] [] worker_thread+0x104/0x1a4 [45719.001076] [] ? worker_thread+0x0/0x1a4 [45719.001080] [] kthread+0x82/0x8a [45719.001085] [] kernel_thread_helper+0x4/0x10 [45719.001089] [] ? kthread+0x0/0x8a [45719.001093] [] ? kernel_thread_helper+0x0/0x10 [45719.018960] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [45719.018964] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [45719.023336] ehci_hcd 0000:00:1a.0: power state changed by ACPI to D0 [45719.023511] ehci_hcd 0000:00:1a.0: PCI INT D -> GSI 23 (level, low) -> IRQ 23 [45719.023528] ehci_hcd 0000:00:1a.0: setting latency timer to 64 [45719.024977] ehci_hcd 0000:00:1d.0: power state changed by ACPI to D0 [45719.024991] ehci_hcd 0000:00:1d.0: PCI INT D -> GSI 19 (level, low) -> IRQ 19 [45719.024998] ehci_hcd 0000:00:1d.0: setting latency timer to 64 [45719.255976] usb 1-1.3: reset full speed USB device using ehci_hcd and address 3 [45719.303816] ata5: SATA link down (SStatus 0 SControl 300) [45719.307848] ata6: SATA link down (SStatus 0 SControl 300) [45719.309811] ata2: SATA link down (SStatus 0 SControl 300) [45720.588158] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300) [45720.594379] ata1: XXX SET EH PENDING, fastdrain=1 [45720.594385] Pid: 0, comm: swapper Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45720.594389] Call Trace: [45720.594391] [] ata_eh_set_pending+0x3e/0x85 [45720.594409] [] ata_port_schedule_eh+0x43/0x50 [45720.594417] [] ata_qc_complete+0xd2/0x152 [45720.594423] [] ata_qc_complete_multiple+0xc6/0xcb [45720.594431] [] ahci_interrupt+0x4fd/0x5a1 [45720.594439] [] ? tick_dev_program_event+0x36/0xf4 [45720.594448] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45720.594454] [] ? unlock_hrtimer_base.clone.16+0x11/0x13 [45720.594463] [] handle_IRQ_event+0x58/0x122 [45720.594473] [] ? apic_write+0x16/0x18 [45720.594480] [] handle_edge_irq+0xfa/0x145 [45720.594488] [] handle_irq+0x88/0x90 [45720.594494] [] do_IRQ+0x58/0xb0 [45720.594500] [] ret_from_intr+0x0/0x11 [45720.594506] [] ? intel_idle+0x115/0x13d [45720.594515] [] ? intel_idle+0xf4/0x13d [45720.594521] [] cpuidle_idle_call+0x9e/0x104 [45720.594524] [] cpu_idle+0xae/0x105 [45720.594530] [] rest_init+0x72/0x74 [45720.594534] [] start_kernel+0x3e0/0x3eb [45720.594539] [] x86_64_start_reservations+0xb6/0xba [45720.594543] [] x86_64_start_kernel+0x106/0x115 [45720.594576] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [45720.594580] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [45720.594882] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [45720.594889] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [45720.597752] ata1.00: ACPI cmd ef/02:00:00:00:00:a0 (SET FEATURES) succeeded [45720.597760] ata1.00: ACPI cmd f5/00:00:00:00:00:a0 (SECURITY FREEZE LOCK) filtered out [45720.598063] ata1.00: ACPI cmd ef/5f:00:00:00:00:a0 (SET FEATURES) succeeded [45720.598069] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out [45720.599385] ata1.00: configured for UDMA/100 [45720.613150] ata1.00: configured for UDMA/100 [45720.613156] ata1: EH complete [45720.641205] PM: resume of devices complete after 1644.504 msecs [45720.642987] PM: Finishing wakeup. [45720.642990] Restarting tasks ... [45720.643653] usb 1-1.4: USB disconnect, address 9 [45720.644069] done. [45720.644077] video LNXVIDEO:00: Restoring backlight state [45720.837513] e1000e 0000:00:19.0: irq 45 for MSI/MSI-X [45720.888013] e1000e 0000:00:19.0: irq 45 for MSI/MSI-X [45720.890421] ADDRCONF(NETDEV_UP): eth0: link is not ready [45720.917389] ata1: XXX SET EH PENDING, fastdrain=0 [45720.917398] Pid: 0, comm: swapper Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45720.917402] Call Trace: [45720.917405] [] ata_eh_set_pending+0x3e/0x85 [45720.917424] [] ata_do_link_abort+0x44/0x94 [45720.917430] [] ata_port_abort+0x10/0x12 [45720.917437] [] ahci_interrupt+0x467/0x5a1 [45720.917446] [] ? scsi_finish_command+0xe5/0xee [45720.917456] [] handle_IRQ_event+0x58/0x122 [45720.917466] [] ? apic_write+0x16/0x18 [45720.917473] [] handle_edge_irq+0xfa/0x145 [45720.917482] [] handle_irq+0x88/0x90 [45720.917489] [] do_IRQ+0x58/0xb0 [45720.917497] [] ret_from_intr+0x0/0x11 [45720.917500] [] ? intel_idle+0x115/0x13d [45720.917513] [] ? intel_idle+0xf4/0x13d [45720.917523] [] cpuidle_idle_call+0x9e/0x104 [45720.917529] [] cpu_idle+0xae/0x105 [45720.917536] [] rest_init+0x72/0x74 [45720.917543] [] start_kernel+0x3e0/0x3eb [45720.917552] [] x86_64_start_reservations+0xb6/0xba [45720.917558] [] x86_64_start_kernel+0x106/0x115 [45720.918625] ata1: XXX SET EH PENDING, fastdrain=1 [45720.918631] Pid: 33, comm: kseriod Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45720.918635] Call Trace: [45720.918637] [] ata_eh_set_pending+0x3e/0x85 [45720.918650] [] ata_port_schedule_eh+0x43/0x50 [45720.918658] [] ata_qc_complete+0xd2/0x152 [45720.918665] [] ata_qc_complete_multiple+0xc6/0xcb [45720.918672] [] ahci_interrupt+0x4fd/0x5a1 [45720.918680] [] ? i8042_interrupt+0x288/0x29a [45720.918688] [] handle_IRQ_event+0x58/0x122 [45720.918695] [] ? apic_write+0x16/0x18 [45720.918702] [] handle_edge_irq+0xfa/0x145 [45720.918708] [] handle_irq+0x88/0x90 [45720.918714] [] do_IRQ+0x58/0xb0 [45720.918720] [] ret_from_intr+0x0/0x11 [45720.918723] [] ? del_timer_sync+0x21/0x26 [45720.918738] [] ? schedule_timeout+0xb3/0xe3 [45720.918745] [] ? process_timeout+0x0/0x10 [45720.918752] [] ps2_sendbyte+0xbd/0xfd [45720.918760] [] ? autoremove_wake_function+0x0/0x39 [45720.918767] [] __ps2_command+0x10c/0x33f [45720.918772] [] ? mutex_lock+0x29/0x50 [45720.918778] [] ? mutex_lock+0x29/0x50 [45720.918784] [] ps2_command+0x2c/0x41 [45720.918792] [] psmouse_sliced_command+0x5f/0x7f [45720.918799] [] synaptics_pt_write+0x39/0x61 [45720.918805] [] ps2_sendbyte+0x50/0xfd [45720.918814] [] ? autoremove_wake_function+0x0/0x39 [45720.918824] [] ? finish_wait+0x66/0x6e [45720.918833] [] __ps2_command+0xe4/0x33f [45720.918842] [] ? need_resched+0x23/0x2d [45720.918851] [] ? _cond_resched+0xe/0x22 [45720.918859] [] ? mutex_lock+0x29/0x50 [45720.918866] [] ps2_command+0x2c/0x41 [45720.918872] [] trackpoint_read+0x42/0x51 [45720.918877] [] trackpoint_sync+0x51/0x1f8 [45720.918883] [] trackpoint_reconnect+0x29/0x34 [45720.918889] [] psmouse_reconnect+0xa5/0x135 [45720.918895] [] serio_reconnect_port+0x42/0x6e [45720.918901] [] serio_thread+0x10f/0x2dc [45720.918908] [] ? autoremove_wake_function+0x0/0x39 [45720.918914] [] ? _raw_spin_unlock_irqrestore+0x17/0x19 [45720.918920] [] ? serio_thread+0x0/0x2dc [45720.918926] [] kthread+0x82/0x8a [45720.918933] [] kernel_thread_helper+0x4/0x10 [45720.918939] [] ? kthread+0x0/0x8a [45720.918945] [] ? kernel_thread_helper+0x0/0x10 [45720.921568] ata1.00: configured for UDMA/100 [45720.921575] ata1: EH complete [45720.921997] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA [45721.014959] ADDRCONF(NETDEV_UP): wlan0: link is not ready [45721.148470] EXT4-fs (dm-0): re-mounted. Opts: commit=600 [45721.157299] EXT4-fs (sda1): re-mounted. Opts: commit=600 [45721.161023] EXT4-fs (dm-2): re-mounted. Opts: commit=600 [45721.204459] ata1: XXX SET EH PENDING, fastdrain=1 [45721.204469] Pid: 15426, comm: sata_alpm Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.204475] Call Trace: [45721.204491] [] ata_eh_set_pending+0x3e/0x85 [45721.204500] [] ata_port_schedule_eh+0x43/0x50 [45721.204512] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45721.204520] [] ata_scsi_lpm_store+0x89/0xa5 [45721.204528] [] dev_attr_store+0x20/0x22 [45721.204540] [] sysfs_write_file+0x114/0x150 [45721.204548] [] vfs_write+0xac/0xff [45721.204554] [] sys_write+0x4a/0x6e [45721.204562] [] system_call_fastpath+0x16/0x1b [45721.204962] ata2: XXX SET EH PENDING, fastdrain=1 [45721.204970] Pid: 15426, comm: sata_alpm Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.204975] Call Trace: [45721.204984] [] ata_eh_set_pending+0x3e/0x85 [45721.204993] [] ata_port_schedule_eh+0x43/0x50 [45721.205002] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45721.205011] [] ata_scsi_lpm_store+0x89/0xa5 [45721.205019] [] dev_attr_store+0x20/0x22 [45721.205028] [] sysfs_write_file+0x114/0x150 [45721.205038] [] vfs_write+0xac/0xff [45721.205046] [] sys_write+0x4a/0x6e [45721.205054] [] system_call_fastpath+0x16/0x1b [45721.205296] ata3: XXX SET EH PENDING, fastdrain=1 [45721.205302] Pid: 15426, comm: sata_alpm Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.205305] Call Trace: [45721.205313] [] ata_eh_set_pending+0x3e/0x85 [45721.205319] [] ata_port_schedule_eh+0x43/0x50 [45721.205326] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45721.205331] [] ata_scsi_lpm_store+0x89/0xa5 [45721.205337] [] dev_attr_store+0x20/0x22 [45721.205343] [] sysfs_write_file+0x114/0x150 [45721.205350] [] vfs_write+0xac/0xff [45721.205356] [] sys_write+0x4a/0x6e [45721.205362] [] system_call_fastpath+0x16/0x1b [45721.205562] ata4: XXX SET EH PENDING, fastdrain=1 [45721.205568] Pid: 15426, comm: sata_alpm Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.205572] Call Trace: [45721.205579] [] ata_eh_set_pending+0x3e/0x85 [45721.205585] [] ata_port_schedule_eh+0x43/0x50 [45721.205591] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45721.205597] [] ata_scsi_lpm_store+0x89/0xa5 [45721.205602] [] dev_attr_store+0x20/0x22 [45721.205609] [] sysfs_write_file+0x114/0x150 [45721.205615] [] vfs_write+0xac/0xff [45721.205621] [] sys_write+0x4a/0x6e [45721.205627] [] system_call_fastpath+0x16/0x1b [45721.205953] ata5: XXX SET EH PENDING, fastdrain=1 [45721.205961] Pid: 15426, comm: sata_alpm Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.205966] Call Trace: [45721.205976] [] ata_eh_set_pending+0x3e/0x85 [45721.205985] [] ata_port_schedule_eh+0x43/0x50 [45721.205994] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45721.206002] [] ata_scsi_lpm_store+0x89/0xa5 [45721.206010] [] dev_attr_store+0x20/0x22 [45721.206019] [] sysfs_write_file+0x114/0x150 [45721.206029] [] vfs_write+0xac/0xff [45721.206037] [] sys_write+0x4a/0x6e [45721.206045] [] system_call_fastpath+0x16/0x1b [45721.206374] ata6: XXX SET EH PENDING, fastdrain=1 [45721.206383] Pid: 15426, comm: sata_alpm Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.206389] Call Trace: [45721.206399] [] ata_eh_set_pending+0x3e/0x85 [45721.206409] [] ata_port_schedule_eh+0x43/0x50 [45721.206418] [] ? _raw_spin_lock_irqsave+0x12/0x2f [45721.206427] [] ata_scsi_lpm_store+0x89/0xa5 [45721.206437] [] dev_attr_store+0x20/0x22 [45721.206446] [] sysfs_write_file+0x114/0x150 [45721.206457] [] vfs_write+0xac/0xff [45721.206466] [] sys_write+0x4a/0x6e [45721.206475] [] system_call_fastpath+0x16/0x1b [45721.248855] usb 1-1.4: new full speed USB device using ehci_hcd and address 10 [45721.338217] usb 1-1.4: New USB device found, idVendor=0a5c, idProduct=217f [45721.338225] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 [45721.338231] usb 1-1.4: Product: Broadcom Bluetooth Device [45721.338235] usb 1-1.4: Manufacturer: Broadcom Corp [45721.338238] usb 1-1.4: SerialNumber: C417FEF2CB38 [45721.367639] ata1: XXX SET EH PENDING, fastdrain=0 [45721.367648] Pid: 0, comm: swapper Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.367652] Call Trace: [45721.367655] [] ata_eh_set_pending+0x3e/0x85 [45721.367673] [] ata_do_link_abort+0x44/0x94 [45721.367680] [] ata_port_abort+0x10/0x12 [45721.367687] [] ahci_interrupt+0x467/0x5a1 [45721.367697] [] handle_IRQ_event+0x58/0x122 [45721.367707] [] ? apic_write+0x16/0x18 [45721.367715] [] handle_edge_irq+0xfa/0x145 [45721.367723] [] handle_irq+0x88/0x90 [45721.367730] [] do_IRQ+0x58/0xb0 [45721.367738] [] ret_from_intr+0x0/0x11 [45721.367741] [] ? intel_idle+0x115/0x13d [45721.367755] [] ? intel_idle+0xf4/0x13d [45721.367764] [] cpuidle_idle_call+0x9e/0x104 [45721.367770] [] cpu_idle+0xae/0x105 [45721.367778] [] rest_init+0x72/0x74 [45721.367784] [] start_kernel+0x3e0/0x3eb [45721.367793] [] x86_64_start_reservations+0xb6/0xba [45721.367799] [] x86_64_start_kernel+0x106/0x115 [45721.386484] ata1: XXX SET EH PENDING, fastdrain=1 [45721.386489] Pid: 0, comm: swapper Not tainted 2.6.37-0.rc4.git1.2.fc15.x86_64 #1 [45721.386492] Call Trace: [45721.386495] [] ata_eh_set_pending+0x3e/0x85 [45721.386506] [] ata_port_schedule_eh+0x43/0x50 [45721.386514] [] ata_qc_complete+0xd2/0x152 [45721.386519] [] ata_qc_complete_multiple+0xc6/0xcb [45721.386525] [] ahci_interrupt+0x4fd/0x5a1 [45721.386532] [] ? scsi_finish_command+0xe5/0xee [45721.386539] [] ? sched_clock+0x9/0xd [45721.386545] [] handle_IRQ_event+0x58/0x122 [45721.386551] [] ? apic_write+0x16/0x18 [45721.386556] [] handle_edge_irq+0xfa/0x145 [45721.386562] [] handle_irq+0x88/0x90 [45721.386566] [] do_IRQ+0x58/0xb0 [45721.386572] [] ret_from_intr+0x0/0x11 [45721.386574] [] ? intel_idle+0x115/0x13d [45721.386584] [] ? intel_idle+0xf4/0x13d [45721.386590] [] cpuidle_idle_call+0x9e/0x104 [45721.386594] [] cpu_idle+0xae/0x105 [45721.386600] [] rest_init+0x72/0x74 [45721.386604] [] start_kernel+0x3e0/0x3eb [45721.386611] [