lkml.org 
[lkml]   [2010]   [Mar]   [9]   [last100]   RSS Feed
Views: [wrap][no wrap]   [headers]  [forward] 
 
Messages in this thread
/
Date
From
SubjectRe: [git patches] libata updates for 2.6.34

Jeff,
this is a new machine, so I don't know when it started, but it was
running a couple of Fedora 2.6.31/32 kernels for a while with no trouble.
So I _think_ it's recent.

I'd guess it's due to commit 27943620cb ("libata: implement spurious irq
handling for SFF and apply it to piix"), in fact.

With current -git I got a 30 second pause, and it was accompanied with
this kernel log:

Mar 9 12:51:05 i5 kernel: [ 7.040194] ata4: clearing spurious IRQ
Mar 9 12:51:05 i5 kernel: [ 37.978933] ata4: lost interrupt (Status 0x50)
Mar 9 12:51:05 i5 kernel: [ 37.978948] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 frozen
Mar 9 12:51:05 i5 kernel: [ 37.978951] ata4.01: failed command: READ DMA
Mar 9 12:51:05 i5 kernel: [ 37.978954] ata4.01: cmd c8/00:08:ef:44:47/00:00:00:00:00/f0 tag 0 dma 4096 in
Mar 9 12:51:05 i5 kernel: [ 37.978955] res 40/00:00:00:00:00/00:00:00:00:00/00 Emask 0x4 (timeout)
Mar 9 12:51:05 i5 kernel: [ 37.978957] ata4.01: status: { DRDY }
Mar 9 12:51:05 i5 kernel: [ 37.978963] ata4.00: hard resetting link
Mar 9 12:51:05 i5 kernel: [ 38.306451] ata4.01: hard resetting link
Mar 9 12:51:05 i5 kernel: [ 38.785773] ata4.00: SATA link down (SStatus 0 SControl 300)
Mar 9 12:51:05 i5 kernel: [ 38.785787] ata4.01: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 9 12:51:05 i5 kernel: [ 38.809900] ata4.01: configured for UDMA/133
Mar 9 12:51:05 i5 kernel: [ 38.809903] ata4.01: device reported invalid CHS sector 0
Mar 9 12:51:05 i5 kernel: [ 38.809907] ata4: EH complete
and notice how it first complains about a spurious interrupt, and then
thirty seconds later the timeout for a _lost_ interrupt happens. Looks
like a race condition in SATA irq handling.

This is a Core i5 with intel controller and intel SSD:

Mar 9 12:51:05 i5 kernel: [ 1.431579] ata_piix 0000:00:1f.2: PCI INT D -> GSI 21 (level, low) -> IRQ 21
Mar 9 12:51:05 i5 kernel: [ 1.431607] ata_piix 0000:00:1f.2: MAP [ P0 P2 P1 P3 ]
Mar 9 12:51:05 i5 kernel: [ 1.431698] scsi3 : ata_piix
Mar 9 12:51:05 i5 kernel: [ 1.431760] scsi4 : ata_piix
Mar 9 12:51:05 i5 kernel: [ 1.432800] ata4: SATA max UDMA/133 cmd 0x9880 ctl 0x9800 bmdma 0x9080 irq 21
Mar 9 12:51:05 i5 kernel: [ 1.432829] ata5: SATA max UDMA/133 cmd 0x9480 ctl 0x9400 bmdma 0x9088 irq 21
...
Mar 9 12:51:05 i5 kernel: [ 2.236210] ata4.00: SATA link down (SStatus 0 SControl 300)
Mar 9 12:51:05 i5 kernel: [ 2.237724] ata4.01: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 9 12:51:05 i5 kernel: [ 2.256250] ata4.01: ATA-7: INTEL SSDSA2M160G2GC, 2CV102HA, max UDMA/133
Mar 9 12:51:05 i5 kernel: [ 2.257747] ata4.01: 312581808 sectors, multi 16: LBA48 NCQ (depth 0/32)
Mar 9 12:51:05 i5 kernel: [ 2.268256] ata4.01: configured for UDMA/133
yeah, yeah, I realize it's ata_piix, and I need to go into the BIOS and
turn it into AHCI mode, but I thought I'd make a report about this before
I do anything else.

The 30 second pause was annoying, but then half an hour later it just hung
entirely, with the disk light blinking at 1Hz. Which I assume is related.

Oh, just happened again:

Mar 9 13:12:29 i5 kernel: [ 540.453163] ata4: clearing spurious IRQ
Mar 9 13:12:59 i5 kernel: [ 571.133725] ata4: lost interrupt (Status 0x50)
Mar 9 13:12:59 i5 kernel: [ 571.133749] ata4.01: exception Emask 0x0 SAct 0x0 SErr 0x0 action 0x0 frozen
Mar 9 13:12:59 i5 kernel: [ 571.133755] ata4.01: failed command: READ DMA
Mar 9 13:12:59 i5 kernel: [ 571.133764] ata4.01: cmd c8/00:08:c7:9a:47/00:00:00:00:00/f5 tag 0 dma 4096 in
Mar 9 13:12:59 i5 kernel: [ 571.133766] res 40/00:00:00:4f:c2/00:00:00:00:00/10 Emask 0x4 (timeout)
Mar 9 13:12:59 i5 kernel: [ 571.133771] ata4.01: status: { DRDY }
Mar 9 13:12:59 i5 kernel: [ 571.133783] ata4.00: hard resetting link
Mar 9 13:13:00 i5 kernel: [ 571.453215] ata4.01: hard resetting link
Mar 9 13:13:00 i5 kernel: [ 571.928512] ata4.00: SATA link down (SStatus 0 SControl 300)
Mar 9 13:13:00 i5 kernel: [ 571.928526] ata4.01: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
Mar 9 13:13:00 i5 kernel: [ 571.952699] ata4.01: configured for UDMA/133
Mar 9 13:13:00 i5 kernel: [ 571.952707] ata4.01: device reported invalid CHS sector 0
Mar 9 13:13:00 i5 kernel: [ 571.952716] ata4: EH complete
so I really think PIIX is screwed up in current -git. And it really must
be fairly recent, since none of this happened with the Fedora kernels, and
it's clearly not a one-time event.

In fact, grepping for this, it's happened at least four times since I
upgraded the machine to 2.6.34-rc1-00005-g522dba7.
Linus


\
 
 \ /
  Last update: 2010-03-09 22:23    [from the cache]
©2003-2011 Jasper Spaans