Problem with hard drive lock out

Tue Feb 26 05:08:43 UTC 2008

Craig White wrote:
> On Sun, 2008-02-24 at 12:51 -0500, marklapier at aol.com wrote:
>   
>> Hey All,
>> I'm having a vexing problem with FC8 losing connection to one of my 
>> hard drives. I've had it drop out in a matter of a few minutes. This 
>> time it was working for several days before it quit.
>>
>> I have two drives on my system.  One SATA drive has the system 
>> installed on it.  The other, plugged into the IDE header has data files 
>> on it.  The SATA drive runs fine.  The IDE drive will eventually quit 
>> working.  I can cd onto the drive as long as I don't try to access 
>> something that is not already in the disk cache.  When I try to access 
>> the drive I get this error:
>>
>> [mlapier at mushroom mlapier]$ cd /sdb1
>> [mlapier at mushroom sdb1]$ cd mlapier
>> [mlapier at mushroom mlapier]$ cd mail
>> [mlapier at mushroom mail]$ cd MYDIR
>> [mlapier at mushroom MYDIR]$ ls
>> ls: reading directory .: Input/output error
>>
>> [mlapier at mushroom piano]$ cd /sdb2
>> [mlapier at mushroom sdb2]$ cd piano
>> [mlapier at mushroom piano]$ ls
>> ls: reading directory .: Input/output error
>>
>> There are two partitions mounted thus:
>> drwxrwxrwx 16 root users 4096 2007-12-29 12:56 sdb1
>> drwxrwxrwx 49 root users 4096 2008-01-06 22:48 sdb2
>>
>> [mlapier at mushroom etc]$ cat fstab
>> /dev/VolGroup00/LogVol00 / ext3 defaults > 1 1
>> LABEL=/boot /boot ext3 defaults 1 2
>> tmpfs /dev/shm tmpfs defaults 0 0
>> /dev/sdb1 /sdb1 ext2 defaults 1 2
>> /dev/sdb2 /sdb2 ext2 defaults 1 2
>> devpts /dev/pts devpts gid=5,mode=620 0 0
>> sysfs /sys sysfs defaults 0 0
>> proc /proc proc defaults 0 0
>> /dev/VolGroup00/LogVol01 swap swap defaults > 0 0
>>
>> [mlapier at mushroom etc]$ uname -a
>> Linux mushroom.patch 2.6.23.15-137.fc8 #1 SMP Sun Feb 10 17:03:13 EST 
>> 2008 x86_64 x86_64 x86_64 GNU/Linux
>>
>> I've been running Linux for many years on many boxes but I've never 
>> encountered a situation like this. The only way I've found to restore 
>> operation is a to reboot.
>>
>> Anyone got any clues??
>>     
> ----
> I think the clues are likely to be found in errors in /var/log/messages
> or dmesg
>
> Craig
>
>   

I didn't see much of interest in dmesg output but the /var/log/messages 
file is chock full of references to ata3  Here's a snippet from the 
start of the kernel messages:

Feb 25 10:37:25 mushroom kernel: agpgart: Found an AGP 3.0 compliant 
device at 0000:00:00.0.
Feb 25 10:37:25 mushroom kernel: agpgart: X tried to set rate=x12. 
Setting to AGP3 x8 mode.
Feb 25 10:37:25 mushroom kernel: agpgart: Putting AGP V3 device at 
0000:00:00.0 into 8x mode
Feb 25 10:37:25 mushroom kernel: agpgart: Putting AGP V3 device at 
0000:01:00.0 into 8x mode
Feb 25 10:37:25 mushroom kernel: irq 16: nobody cared (try booting with 
the "irqpoll" option)
Feb 25 10:37:25 mushroom kernel:
Feb 25 10:37:25 mushroom kernel: Call Trace:
Feb 25 10:37:25 mushroom kernel:  <IRQ>  [<ffffffff8106aa4f>] 
__report_bad_irq+0x30/0x72
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8106aca0>] 
note_interrupt+0x20f/0x253
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8106b58c>] 
handle_fasteoi_irq+0xa9/0xd1
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8100e0fc>] do_IRQ+0xf1/0x161
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8100adba>] default_idle+0x0/0x3d
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8100c0e1>] ret_from_intr+0x0/0xa
Feb 25 10:37:25 mushroom kernel:  <EOI>  [<ffffffff812514dd>] 
unix_poll+0x0/0x96
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8100ade3>] 
default_idle+0x29/0x3d
Feb 25 10:37:25 mushroom kernel:  [<ffffffff8100ae8b>] cpu_idle+0x94/0xbc
Feb 25 10:37:25 mushroom kernel:  [<ffffffff81433baa>] 
start_kernel+0x2cf/0x2db
Feb 25 10:37:25 mushroom kernel:  [<ffffffff81433140>] 
_sinittext+0x140/0x144
Feb 25 10:37:25 mushroom kernel:
Feb 25 10:37:25 mushroom kernel: handlers:
Feb 25 10:37:25 mushroom kernel: [<ffffffff8831b35a>] 
(via_driver_irq_handler+0x0/0x183 [via])
Feb 25 10:37:25 mushroom kernel: Disabling IRQ #16
Feb 25 17:04:30 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:04:30 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:04:30 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:04:30 mushroom kernel: ata3: soft resetting port
Feb 25 17:05:00 mushroom kernel: ata3.00: qc timeout (cmd 0xef)
Feb 25 17:05:00 mushroom kernel: ata3.00: failed to IDENTIFY (SPINUP 
failed, err_mask=0x4)
Feb 25 17:05:00 mushroom kernel: ata3.00: revalidation failed (errno=-5)
Feb 25 17:05:00 mushroom kernel: ata3: failed to recover some devices, 
retrying in 5 secs
Feb 25 17:05:05 mushroom kernel: ata3: soft resetting port
Feb 25 17:05:35 mushroom kernel: ata3.00: qc timeout (cmd 0xef)
Feb 25 17:05:35 mushroom kernel: ata3.00: failed to IDENTIFY (SPINUP 
failed, err_mask=0x4)
Feb 25 17:05:35 mushroom kernel: ata3.00: revalidation failed (errno=-5)
Feb 25 17:05:35 mushroom kernel: ata3.00: limiting speed to UDMA/100:PIO3
Feb 25 17:05:35 mushroom kernel: ata3: failed to recover some devices, 
retrying in 5 secs
Feb 25 17:05:40 mushroom kernel: ata3: soft resetting port
Feb 25 17:06:11 mushroom kernel: ata3.00: qc timeout (cmd 0xef)
Feb 25 17:06:11 mushroom kernel: ata3.00: failed to IDENTIFY (SPINUP 
failed, err_mask=0x4)
Feb 25 17:06:11 mushroom kernel: ata3.00: revalidation failed (errno=-5)
Feb 25 17:06:11 mushroom kernel: ata3.00: disabled
Feb 25 17:06:11 mushroom kernel: ata3: failed to recover some devices, 
retrying in 5 secs
Feb 25 17:06:16 mushroom kernel: ata3.01: failed to set xfermode 
(err_mask=0x40)
Feb 25 17:06:16 mushroom kernel: ata3: failed to recover some devices, 
retrying in 5 secs
Feb 25 17:06:21 mushroom kernel: ata3: soft resetting port
Feb 25 17:06:22 mushroom kernel: ata3.01: configured for UDMA/33
Feb 25 17:06:22 mushroom kernel: ata3: EH pending after completion, 
repeating EH (cnt=4)
Feb 25 17:06:22 mushroom kernel: ata3: EH complete
Feb 25 17:06:52 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:06:52 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:06:52 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:06:52 mushroom kernel: ata3: soft resetting port
Feb 25 17:06:52 mushroom kernel: ata3.01: configured for UDMA/33
Feb 25 17:06:52 mushroom kernel: ata3: EH complete
Feb 25 17:07:22 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:07:22 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:07:22 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:07:22 mushroom kernel: ata3: soft resetting port
Feb 25 17:07:23 mushroom kernel: ata3.01: configured for UDMA/33
Feb 25 17:07:23 mushroom kernel: ata3: EH complete
Feb 25 17:07:53 mushroom kernel: ata3.01: limiting speed to UDMA/25:PIO4
Feb 25 17:07:53 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:07:53 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:07:53 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:07:53 mushroom kernel: ata3: soft resetting port
Feb 25 17:07:54 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:07:54 mushroom kernel: ata3: EH complete
Feb 25 17:07:54 mushroom kernel: sd 2:0:0:0: [sdb] READ CAPACITY failed
Feb 25 17:07:54 mushroom kernel: sd 2:0:0:0: [sdb] Result: 
hostbyte=DID_BAD_TARGET driverbyte=DRIVER_OK,SUGGEST_OK
Feb 25 17:07:54 mushroom kernel: sd 2:0:0:0: [sdb] Sense not available.
Feb 25 17:08:24 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:08:24 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:08:24 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:08:24 mushroom kernel: ata3: soft resetting port
Feb 25 17:08:24 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:08:24 mushroom kernel: ata3: EH complete
Feb 25 17:08:24 mushroom kernel: sd 2:0:0:0: [sdb] Write Protect is off
Feb 25 17:08:54 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:08:54 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:08:54 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:08:54 mushroom kernel: ata3: soft resetting port
Feb 25 17:08:55 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:08:55 mushroom kernel: ata3: EH complete
Feb 25 17:08:55 mushroom kernel: sd 2:0:0:0: [sdb] Asking for cache data 
failed
Feb 25 17:08:55 mushroom kernel: sd 2:0:0:0: [sdb] Assuming drive cache: 
write through
Feb 25 17:09:25 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:09:25 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:09:25 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:09:25 mushroom kernel: ata3: soft resetting port
Feb 25 17:09:25 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:09:25 mushroom kernel: ata3: EH complete
Feb 25 17:09:55 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:09:55 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x25 data 8 in
Feb 25 17:09:55 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:09:55 mushroom kernel: ata3: soft resetting port
Feb 25 17:09:56 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:09:56 mushroom kernel: ata3: EH complete
Feb 25 17:10:26 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:10:26 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x0 data 0
Feb 25 17:10:26 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:10:26 mushroom kernel: ata3: soft resetting port
Feb 25 17:10:27 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:10:27 mushroom kernel: ata3: EH complete
Feb 25 17:10:57 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:10:57 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x0 data 0
Feb 25 17:10:57 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:10:57 mushroom kernel: ata3: soft resetting port
Feb 25 17:10:57 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:10:57 mushroom kernel: ata3: EH complete
Feb 25 17:11:27 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:11:27 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x0 data 0
Feb 25 17:11:27 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:11:27 mushroom kernel: ata3: soft resetting port
Feb 25 17:11:28 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:11:28 mushroom kernel: ata3: EH complete
Feb 25 17:11:58 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:11:58 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x0 data 0
Feb 25 17:11:58 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:11:58 mushroom kernel: ata3: soft resetting port
Feb 25 17:11:59 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:11:59 mushroom kernel: ata3: EH complete
Feb 25 17:11:59 mushroom kernel: sr0: CDROM (ioctl) error, command: Test 
Unit Ready 00 00 00 00 00 00
Feb 25 17:11:59 mushroom kernel: sr: Sense Key : Aborted Command 
[current] [descriptor]
Feb 25 17:11:59 mushroom kernel: sr: Add. Sense: No additional sense 
information
Feb 25 17:12:09 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:12:09 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x1e data 0
Feb 25 17:12:09 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:12:09 mushroom kernel: ata3: soft resetting port
Feb 25 17:12:09 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:12:09 mushroom kernel: ata3: EH complete
Feb 25 17:12:19 mushroom kernel: ata3.01: exception Emask 0x0 SAct 0x0 
SErr 0x0 action 0x2 frozen
Feb 25 17:12:19 mushroom kernel: ata3.01: cmd 
a0/00:00:00:00:20/00:00:00:00:00/b0 tag 0 cdb 0x1e data 0
Feb 25 17:12:19 mushroom kernel:          res 
40/00:03:00:00:20/00:00:00:00:00/b0 Emask 0x4 (timeout)
Feb 25 17:12:19 mushroom kernel: ata3: soft resetting port
Feb 25 17:12:20 mushroom kernel: ata3.01: configured for UDMA/25
Feb 25 17:12:20 mushroom kernel: ata3: EH complete