Bug 379591 - SATA exception with reduced disk IO afterwards
SATA exception with reduced disk IO afterwards
Status: CLOSED CURRENTRELEASE
Product: Red Hat Enterprise Linux 5
Classification: Red Hat
Component: kernel-xen (Show other bugs)
5.1
i386 Linux
low Severity low
: ---
: ---
Assigned To: Xen Maintainance List
Martin Jenner
:
Depends On:
Blocks:
  Show dependency treegraph
 
Reported: 2007-11-13 04:31 EST by Joachim Schröder
Modified: 2009-01-11 12:46 EST (History)
1 user (show)

See Also:
Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of:
Environment:
Last Closed: 2009-01-11 12:46:25 EST
Type: ---
Regression: ---
Mount Type: ---
Documentation: ---
CRM:
Verified Versions:
Category: ---
oVirt Team: ---
RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: ---


Attachments (Terms of Use)

  None (edit)
Description Joachim Schröder 2007-11-13 04:31:57 EST
Description of problem:
Using RHEL 5.1 (2.6.18-53.el5xen) I experience SATA errors while running a 
resync on a two-1TB harddisks md raid:
--- snip---
Nov 12 23:28:01 tiger kernel: ata2.00: exception Emask 0x2 SAct 0x3f00 SErr 
0x0 action 0x2 frozen
Nov 12 23:28:01 tiger kernel: ata2.00: (spurious completions during NCQ 
issue=0x0 SAct=0x3f00 FIS=005040a1:00000080)
Nov 12 23:28:01 tiger kernel: ata2.00: cmd 61/d8:40:d0:cb:45/03:00:15:00:00/40 
tag 8 cdb 0x0 data 503808 out
Nov 12 23:28:01 tiger kernel:          res 50/00:b8:f0:eb:45/00:01:15:00:00/40 
Emask 0x2 (HSM violation)
Nov 12 23:28:01 tiger kernel: ata2.00: cmd 61/00:48:e8:df:45/04:00:15:00:00/40 
tag 9 cdb 0x0 data 524288 out
Nov 12 23:28:01 tiger kernel:          res 50/00:b8:f0:eb:45/00:01:15:00:00/40 
Emask 0x2 (HSM violation)
Nov 12 23:28:01 tiger kernel: ata2.00: cmd 61/00:50:e8:e3:45/04:00:15:00:00/40 
tag 10 cdb 0x0 data 524288 out
Nov 12 23:28:01 tiger kernel:          res 50/00:b8:f0:eb:45/00:01:15:00:00/40 
Emask 0x2 (HSM violation)
Nov 12 23:28:01 tiger kernel: ata2.00: cmd 61/08:58:e8:e7:45/00:00:15:00:00/40 
tag 11 cdb 0x0 data 4096 out
Nov 12 23:28:01 tiger kernel:          res 50/00:b8:f0:eb:45/00:01:15:00:00/40 
Emask 0x2 (HSM violation)
Nov 12 23:28:01 tiger kernel: ata2.00: cmd 61/00:60:f0:e7:45/04:00:15:00:00/40 
tag 12 cdb 0x0 data 524288 out
Nov 12 23:28:01 tiger kernel:          res 50/00:b8:f0:eb:45/00:01:15:00:00/40 
Emask 0x2 (HSM violation)
Nov 12 23:28:01 tiger kernel: ata2.00: cmd 61/b8:68:f0:eb:45/01:00:15:00:00/40 
tag 13 cdb 0x0 data 225280 out
Nov 12 23:28:01 tiger kernel:          res 50/00:b8:f0:eb:45/00:01:15:00:00/40 
Emask 0x2 (HSM violation)
Nov 12 23:28:01 tiger kernel: ata2: soft resetting port
Nov 12 23:28:01 tiger kernel: ata2: SATA link up 3.0 Gbps (SStatus 123 
SControl 300)
Nov 12 23:28:01 tiger kernel: ata2.00: configured for UDMA/133
Nov 12 23:28:01 tiger kernel: ata2: EH complete
Nov 12 23:28:01 tiger kernel: SCSI device sdb: 1953525168 512-byte hdwr 
sectors (1000205 MB)
Nov 12 23:28:01 tiger kernel: sdb: Write Protect is off
Nov 12 23:28:01 tiger kernel: SCSI device sdb: drive cache: write back
Nov 13 05:01:36 tiger kernel: ata1.00: exception Emask 0x2 SAct 0x707ffa SErr 
0x0 action 0x2 frozen
Nov 13 05:01:36 tiger kernel: ata1.00: (spurious completions during NCQ 
issue=0x0 SAct=0x707ffa FIS=005040a1:00000004)
Nov 13 05:01:36 tiger kernel: ata1.00: cmd 61/80:08:a8:2d:33/00:00:46:00:00/40 
tag 1 cdb 0x0 data 65536 out
Nov 13 05:01:36 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:36 tiger kernel: ata1.00: cmd 61/00:18:a8:27:33/04:00:46:00:00/40 
tag 3 cdb 0x0 data 524288 out
Nov 13 05:01:36 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:36 tiger kernel: ata1.00: cmd 61/00:20:a8:2b:33/01:00:46:00:00/40 
tag 4 cdb 0x0 data 131072 out
Nov 13 05:01:36 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:36 tiger kernel: ata1.00: cmd 61/80:28:a8:2c:33/00:00:46:00:00/40 
tag 5 cdb 0x0 data 65536 out
Nov 13 05:01:36 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:36 tiger kernel: ata1.00: cmd 61/80:30:a8:2e:33/00:00:46:00:00/40 
tag 6 cdb 0x0 data 65536 out
Nov 13 05:01:36 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:36 tiger kernel: ata1.00: cmd 61/80:38:a8:2f:33/00:00:46:00:00/40 
tag 7 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:40:a8:25:33/00:00:46:00:00/40 
tag 8 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:48:28:2d:33/00:00:46:00:00/40 
tag 9 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:50:28:2e:33/00:00:46:00:00/40 
tag 10 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:58:28:2f:33/00:00:46:00:00/40 
tag 11 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/00:60:28:30:33/01:00:46:00:00/40 
tag 12 cdb 0x0 data 131072 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:68:28:31:33/00:00:46:00:00/40 
tag 13 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:70:a8:31:33/00:00:46:00:00/40 
tag 14 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:a0:28:26:33/00:00:46:00:00/40 
tag 20 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:a8:a8:26:33/00:00:46:00:00/40 
tag 21 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1.00: cmd 61/80:b0:28:27:33/00:00:46:00:00/40 
tag 22 cdb 0x0 data 65536 out
Nov 13 05:01:38 tiger kernel:          res 50/00:80:a8:31:33/00:00:46:00:00/40 
Emask 0x2 (HSM violation)
Nov 13 05:01:38 tiger kernel: ata1: soft resetting port
Nov 13 05:01:38 tiger kernel: ata1: SATA link up 3.0 Gbps (SStatus 123 
SControl 300)
Nov 13 05:01:38 tiger kernel: ata1.00: configured for UDMA/133
Nov 13 05:01:38 tiger kernel: ata1: EH complete
Nov 13 05:01:38 tiger kernel: SCSI device sda: 1953525168 512-byte hdwr 
sectors (1000205 MB)
Nov 13 05:01:38 tiger kernel: sda: Write Protect is off
Nov 13 05:01:38 tiger kernel: SCSI device sda: drive cache: write back

after those errors the resync still runs, but at about half of the usual 
speed.
--- snap ---

How reproducible:
run a md resync or other disk-IO on SATA.
Comment 1 Rik van Riel 2009-01-11 12:46:25 EST
I believe these got fixed with the SATA rebase in RHEL 5.2.

Note You need to log in before you can comment on or make changes to this bug.