CAM status: SCSI Status Error

[BigDave]

Received this email that was sent at 3:01am this morning.

Code:

freenas.local kernel log messages:
>       (da0:mps0:0:0:0): WRITE(10). CDB: 2a 00 13 8f a9 78 00 00 68 00 length 53248 SMID 450 terminated ioc 804b scsi 0 state c xfer 0
> (da0:mps0:0:0:0): WRITE(10). CDB: 2a 00 13 8f a9 78 00 00 68 00
> (da0:mps0:0:0:0): CAM status: SCSI Status Error
> (da0:mps0:0:0:0): SCSI status: Check Condition
> (da0:mps0:0:0:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
> (da0:mps0:0:0:0): Retrying command (per sense data)
> (da0:mps0:0:0:0): WRITE(10). CDB: 2a 00 88 50 ee b0 00 00 08 00
> (da0:mps0:0:0:0): CAM status: SCSI Status Error
> (da0:mps0:0:0:0): SCSI status: Check Condition
> (da0:mps0:0:0:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
> (da0:mps0:0:0:0): Retrying command (per sense data)

-- End of security output --

I performed a Long Smart Test on drive da0 shortly after reading the email about 5:30am,
here is the result

Code:

login as: root
root@192.168.1.13's password:
Last login: Fri May 13 05:55:58 2016 from 192.168.1.26
FreeBSD 10.3-RELEASE (FreeNAS.amd64) #0 91d0e8e(freebsd10): Mon May  2 10:32:27 PDT 2016

  FreeNAS (c) 2009-2016, The FreeNAS Development Team
  All rights reserved.
  FreeNAS is released under the modified BSD license.

  For more information, documentation, help or support, go here:
  http://freenas.org
Welcome to FreeNAS
[root@freenas] ~# smartctl -a /dev/da0
smartctl 6.4 2015-06-04 r4109 [FreeBSD 10.3-RELEASE amd64] (local build)
Copyright (C) 2002-15, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF INFORMATION SECTION ===
Model Family:  Western Digital Red
Device Model:  WDC WD30EFRX-68EUZN0
Serial Number:  WD-xxxxxxxxxxxx
LU WWN Device Id: 5 0014ee 0ae528ca9
Firmware Version: 80.00A80
User Capacity:  3,000,592,982,016 bytes [3.00 TB]
Sector Sizes:  512 bytes logical, 4096 bytes physical
Rotation Rate:  5400 rpm
Device is:  In smartctl database [for details use: -P show]
ATA Version is:  ACS-2 (minor revision not indicated)
SATA Version is:  SATA 3.0, 6.0 Gb/s (current: 6.0 Gb/s)
Local Time is:  Fri May 13 17:31:26 2016 CDT
SMART support is: Available - device has SMART capability.
SMART support is: Enabled

=== START OF READ SMART DATA SECTION ===
SMART overall-health self-assessment test result: PASSED

General SMART Values:
Offline data collection status:  (0x00) Offline data collection activity
  was never started.
  Auto Offline Data Collection: Disabled.
Self-test execution status:  (  0) The previous self-test routine completed
  without error or no self-test has ever
  been run.
Total time to complete Offline
data collection:  (38640) seconds.
Offline data collection
capabilities:  (0x7b) SMART execute Offline immediate.
  Auto Offline data collection on/off support.
  Suspend Offline collection upon new
  command.
  Offline surface scan supported.
  Self-test supported.
  Conveyance Self-test supported.
  Selective Self-test supported.
SMART capabilities:  (0x0003) Saves SMART data before entering
  power-saving mode.
  Supports SMART auto save timer.
Error logging capability:  (0x01) Error logging supported.
  General Purpose Logging supported.
Short self-test routine
recommended polling time:  (  2) minutes.
Extended self-test routine
recommended polling time:  ( 388) minutes.
Conveyance self-test routine
recommended polling time:  (  5) minutes.
SCT capabilities:  (0x703d) SCT Status supported.
  SCT Error Recovery Control supported.
  SCT Feature Control supported.
  SCT Data Table supported.

SMART Attributes Data Structure revision number: 16
Vendor Specific SMART Attributes with Thresholds:
ID# ATTRIBUTE_NAME  FLAG  VALUE WORST THRESH TYPE  UPDATED  WHEN_FAILED RAW_VALUE
  1 Raw_Read_Error_Rate  0x002f  200  200  051  Pre-fail  Always  -  0
  3 Spin_Up_Time  0x0027  177  177  021  Pre-fail  Always  -  6108
  4 Start_Stop_Count  0x0032  100  100  000  Old_age  Always  -  98
  5 Reallocated_Sector_Ct  0x0033  200  200  140  Pre-fail  Always  -  0
  7 Seek_Error_Rate  0x002e  200  200  000  Old_age  Always  -  0
  9 Power_On_Hours  0x0032  079  079  000  Old_age  Always  -  15893
10 Spin_Retry_Count  0x0032  100  253  000  Old_age  Always  -  0
11 Calibration_Retry_Count 0x0032  100  253  000  Old_age  Always  -  0
12 Power_Cycle_Count  0x0032  100  100  000  Old_age  Always  -  97
192 Power-Off_Retract_Count 0x0032  200  200  000  Old_age  Always  -  69
193 Load_Cycle_Count  0x0032  200  200  000  Old_age  Always  -  28
194 Temperature_Celsius  0x0022  117  107  000  Old_age  Always  -  33
196 Reallocated_Event_Count 0x0032  200  200  000  Old_age  Always  -  0
197 Current_Pending_Sector  0x0032  200  200  000  Old_age  Always  -  0
198 Offline_Uncorrectable  0x0030  100  253  000  Old_age  Offline  -  0
199 UDMA_CRC_Error_Count  0x0032  200  200  000  Old_age  Always  -  0
200 Multi_Zone_Error_Rate  0x0008  200  200  000  Old_age  Offline  -  0

SMART Error Log Version: 1
No Errors Logged

SMART Self-test log structure revision number 1
Num  Test_Description  Status  Remaining  LifeTime(hours)  LBA_of_first_error
# 1  Extended offline  Completed without error  00%  15888  -
# 2  Short offline  Completed without error  00%  15831  -
# 3  Extended offline  Completed without error  00%  15694  -
# 4  Short offline  Completed without error  00%  15592  -
# 5  Short offline  Completed without error  00%  15520  -
# 6  Extended offline  Completed without error  00%  15454  -
# 7  Short offline  Completed without error  00%  15328  -
# 8  Short offline  Completed without error  00%  15136  -
# 9  Extended offline  Completed without error  00%  14999  -
#10  Short offline  Completed without error  00%  14897  -
#11  Short offline  Completed without error  00%  14801  -
#12  Extended offline  Completed without error  00%  14711  -
#13  Short offline  Completed without error  00%  14585  -
#14  Short offline  Completed without error  00%  14394  -
#15  Extended offline  Completed without error  00%  14256  -
#16  Short offline  Completed without error  00%  14155  -
#17  Short offline  Completed without error  00%  14107  -
#18  Extended offline  Completed without error  00%  14017  -
#19  Short offline  Completed without error  00%  13891  -
#20  Short offline  Completed without error  00%  13699  -
#21  Extended offline  Completed without error  00%  13561  -

SMART Selective self-test log data structure revision number 1
SPAN  MIN_LBA  MAX_LBA  CURRENT_TEST_STATUS
  1  0  0  Not_testing
  2  0  0  Not_testing
  3  0  0  Not_testing
  4  0  0  Not_testing
  5  0  0  Not_testing
Selective self-test flags (0x0):
  After scanning selected spans, do NOT read-scan remainder of disk.
If Selective self-test is pending on power-up, resume after 0 minute delay.

[root@freenas] ~#

Log messages

Code:

[root@freenas] ~# cat /var/log/messages
May  9 00:00:00 freenas newsyslog[7234]: logfile turned over due to size>100K
May  9 00:00:00 freenas syslog-ng[1574]: Configuration reload request received, reloading configuration;
May 10 00:00:00 freenas syslog-ng[1574]: Configuration reload request received, reloading configuration;
May 11 00:00:00 freenas syslog-ng[1574]: Configuration reload request received, reloading configuration;
May 12 00:00:00 freenas syslog-ng[1574]: Configuration reload request received, reloading configuration;
May 12 05:06:27 freenas  (da0:mps0:0:0:0): WRITE(10). CDB: 2a 00 13 8f a9 78 00 00 68 00 length 53248 SMID 450 terminated ioc 804b scsi 0 state c xfer 0
May 12 05:06:27 freenas (da0:mps0:0:0:0): WRITE(10). CDB: 2a 00 13 8f a9 78 00 00 68 00
May 12 05:06:27 freenas (da0:mps0:0:0:0): CAM status: SCSI Status Error
May 12 05:06:27 freenas (da0:mps0:0:0:0): SCSI status: Check Condition
May 12 05:06:27 freenas (da0:mps0:0:0:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
May 12 05:06:27 freenas (da0:mps0:0:0:0): Retrying command (per sense data)
May 12 05:06:32 freenas (da0:mps0:0:0:0): WRITE(10). CDB: 2a 00 88 50 ee b0 00 00 08 00
May 12 05:06:32 freenas (da0:mps0:0:0:0): CAM status: SCSI Status Error
May 12 05:06:32 freenas (da0:mps0:0:0:0): SCSI status: Check Condition
May 12 05:06:32 freenas (da0:mps0:0:0:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
May 12 05:06:32 freenas (da0:mps0:0:0:0): Retrying command (per sense data)
May 13 00:00:00 freenas syslog-ng[1574]: Configuration reload request received, reloading configuration;
[root@freenas] ~#

This is the very first error any of my drives has ever had. I'm gonna keep a close eye on this,
but I don't really know how concerned I should be. Everything is backed up so no worries
there. I have two hot swap bays empty so switching the drive to another power and data
connection is only a matter of a shut down/restart.

Case - Mercury S8, CaseLabs
PSU - AURUM S 400 Gold, Fortran (FSP Group)
Motherboard - X9SCM-F, Supermicro
CPU - E3-1220 v2, Intel® Xeon®
Memory - 32GB (8GB x4) DDR3-1600 ECC UDIMM, Crucial
Boot Device - X25-E SSD 32GB SLC, Intel
Hard Drives - Six 3TB WD30EFRX, Western Digital
HBA - M1015 Flashed IT - P20, IBM
NIC - X520-DA1 10Gb, Intel
Vol. Config - Single vDev RAIDz2
FreeNAS-9.10-STABLE-201605021851 (35c85f7)

 

[DrKK]

That's very strange. Perhaps some kind of hiccup with the controller firmware. I would ignore it, and then, if there are no other CAM errors in the next couple of weeks, I'd try to forget about it and have my pulse return to normal. (If anything, no matter how small, is wrong with any of my drives, it will stress me out 24/7).

 

[BigDave]

That's very strange. Perhaps some kind of hiccup with the controller firmware. I would ignore it, and then, if there are no other CAM errors in the next couple of weeks, I'd try to forget about it and have my pulse return to normal. (If anything, no matter how small, is wrong with any of my drives, it will stress me out 24/7).

Pretty much ignoring it for now (no more errors reported). Your right, it was very strange, could be either the controller or
the Kingwin KF-4001-BK internal hot swap racks (2) I have the drives in. The drives have been plugged into those racks for
months and had no issues.
Bought a new Skylake motherboard with a Pentium CPU that's undergoing stress testing right now, as soon as I'm satisfied,
the new stuff will replace my old X9SCM-F and E3-1230 v2 which will become a backup system in the future.
I started up a test machine with the Skylake stuff and got this really strange email, check this out...

Code:

Checking status of 3ware RAID controllers:
Alarms (most recent first)
+++ /var/log/3ware_raid_alarms.today   2016-05-16 05:01.181074156 -0500
@@ -0,0 +1 @@


-- End of daily output --

 

[BigDave]

Just an update;
Today I yanked out the old hardware from my FreeNAS box and installed my new (fully tested)
Skylake hardware. Everything went without a hitch. I got rid of my M1015 HBA and my drives
now are directly on the motherboard's SATA controller.
here's my new list for those of you on mobile browsers (that can't see signatures).

Case - Mercury S8, CaseLabs
PSU - AURUM S 400 Gold, Fortran (FSP Group)
Motherboard - X11SSM-F, Supermicro
CPU - Pentium G4400, Intel
Memory - 16GB (16GB x1) M391A2K43BB1-CPB, Samsung
Boot Device - X25-E SSD 32GB SLC, Intel
Hard Drives - Six 3TB WD30EFRX, Western Digital
NIC - X520-DA1 10Gb, Intel
Vol. Config - Single vDev RAIDz2
FreeNAS-9.10-STABLE-201605240427 (64fcd8e)

I changed to a newer platform for the experience, because I can
and because it's my hobby. As an aside, it was easy on the wallet :)

 

[Ericloewe]

Motherboard - X11SSM-F, Supermicro

What are these going for in the US?

When I got mine, I ended up paying close to 300 bucks, which felt crazy high. 50 bucks were shipping, but it was still the cheapest option.

 

[BigDave]

$210 - $230 (shipping included, listed on google's shopping tab today)

This was my combo order from the 7th of May

[*]1 x SUPERMICRO MBD-X11SSM-F-O Micro ATX Server Motherboard LGA 1151 Intel C236

[*]1 x Intel Pentium G4400 Skylake Dual-Core 3.3 GHz LGA 1151 65W BX80662G4400 Desktop Processor Intel HD Graphics 510

Subtotal $259.98

Tax $0.00

Super Eggsaver Shipping (4-7 bus. days) $1.99

Order Total $261.97

 

[Ericloewe]

That's definitely reasonable.

 

[BigDave]

That's very strange. Perhaps some kind of hiccup with the controller firmware. I would ignore it, and then, if there are no other CAM errors in the next couple of weeks, I'd try to forget about it and have my pulse return to normal. (If anything, no matter how small, is wrong with any of my drives, it will stress me out 24/7).

Code:

May 31 00:00:00 freenas syslog-ng[1593]: Configuration reload request received, reloading configuration;
Jun  1 00:00:00 freenas syslog-ng[1593]: Configuration reload request received, reloading configuration;
Jun  2 00:00:00 freenas syslog-ng[1593]: Configuration reload request received, reloading configuration;
Jun  2 22:03:47 freenas (ada5:ahcich6:0:0:0): WRITE_FPDMA_QUEUED. ACB: 61 68 c0 20 02 40 9b 00 00 00 00 00
Jun  2 22:03:47 freenas (ada5:ahcich6:0:0:0): CAM status: Uncorrectable parity/CRC error
Jun  2 22:03:47 freenas (ada5:ahcich6:0:0:0): Retrying command
Jun  3 00:00:00 freenas syslog-ng[1593]: Configuration reload request received, reloading configuration;
[root@freenas] ~#

Another error :(
I was trying to determine if this error was following a particular drive, but I x'd out the serial number for the
drive in my previous post (shown below)

Code:

snip

[root@freenas] ~# smartctl -a /dev/da0
smartctl 6.4 2015-06-04 r4109 [FreeBSD 10.3-RELEASE amd64] (local build)
Copyright (C) 2002-15, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF INFORMATION SECTION ===
Model Family:  Western Digital Red
Device Model:  WDC WD30EFRX-68EUZN0
Serial Number:  WD-xxxxxxxxxxxx
LU WWN Device Id: 5 0014ee 0ae528ca9
Firmware Version: 80.00A80
User Capacity:  3,000,592,982,016 bytes [3.00 TB]
Sector Sizes:  512 bytes logical, 4096 bytes physical
Rotation Rate:  5400 rpm
snip

The error originally occurred on drive da0, but is now showing as ada5 (since moving pool to SATA ports
on the new motherboard from the old M1015 card). No proof ATM if this is the same HDD or not ;)
I have a new drive en-route from the WD Store (just in case) as a potential replacement.
Having my disks in these Kingwin KF-4001-BK hotswap racks, I guess I could switch the disks around in
different bays and wait to see if the error follows a disk or not.
Any thoughts???

 

[Mirfster]

I know this is an aged post, but are you still seeing these issues? Just trying to correlate some data to see if I can spot a trend...

 

[Glorious1]

I had occasional problems like that a long time ago. Can't remember the details, but shutting down and reseating SATA cables seemed to make it go away.

 

[DrKK]

but I x'd out the serial number for the
drive in my previous post

Just for the record, @BigDave, since we're doing post necromancy, you can do a -qnoserial with your smartctl to suppress the serial number. Just for future reference sir.

 

[BigDave]

I know this is an aged post, but are you still seeing these issues? Just trying to correlate some data to see if I can spot a trend...

After some troubleshooting attempts, the error occurred again on the same drive, so I replaced it. Nothing ever since.

Just for the record, @BigDave, since we're doing post necromancy, you can do a -qnoserial with your smartctl to suppress the serial number. Just for future reference sir.

Thanks for the tip! That saves a few key strokes for sure.

Code:

[root@freenas] ~# smartctl -qnoserial -a /dev/ada5
smartctl 6.5 2016-05-07 r4318 [FreeBSD 10.3-STABLE amd64] (local build)
Copyright (C) 2002-16, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF INFORMATION SECTION ===
Model Family:  Western Digital Red
Device Model:  WDC WD40EFRX-68WT0N0
Firmware Version: 82.00A82
User Capacity:  4,000,787,030,016 bytes [4.00 TB]
Sector Sizes:  512 bytes logical, 4096 bytes physical
Rotation Rate:  5400 rpm
Device is:  In smartctl database [for details use: -P show]
ATA Version is:  ACS-2 (minor revision not indicated)
SATA Version is:  SATA 3.0, 6.0 Gb/s (current: 6.0 Gb/s)
Local Time is:  Sun Oct 16 10:03:51 2016 CDT
SMART support is: Available - device has SMART capability.
SMART support is: Enabled

=== START OF READ SMART DATA SECTION ===
SMART overall-health self-assessment test result: PASSED

General SMART Values:
Offline data collection status:  (0x00) Offline data collection activity
  was never started.
  Auto Offline Data Collection: Disabled.
Self-test execution status:  (  0) The previous self-test routine completed
  without error or no self-test has ever
  been run.
Total time to complete Offline
data collection:  (51420) seconds.
Offline data collection
capabilities:  (0x7b) SMART execute Offline immediate.
  Auto Offline data collection on/off support.
  Suspend Offline collection upon new
  command.
  Offline surface scan supported.
  Self-test supported.
  Conveyance Self-test supported.
  Selective Self-test supported.
SMART capabilities:  (0x0003) Saves SMART data before entering
  power-saving mode.
  Supports SMART auto save timer.
Error logging capability:  (0x01) Error logging supported.
  General Purpose Logging supported.
Short self-test routine
recommended polling time:  (  2) minutes.
Extended self-test routine
recommended polling time:  ( 514) minutes.
Conveyance self-test routine
recommended polling time:  (  5) minutes.
SCT capabilities:  (0x703d) SCT Status supported.
  SCT Error Recovery Control supported.
  SCT Feature Control supported.
  SCT Data Table supported.

 

[Ruff.Hi]

sorry about the thread resurrection ... I got this error last time I ran a long test (late June). My next scheduled long test was last night and the same / similar error was thrown on the same drive.

I also have Kingwin KF-4001-BK drive bays ... but it seems your issue was resolved by replacing the drive.

 

[BigDave]

sorry about the thread resurrection ... I got this error last time I ran a long test (late June). My next scheduled long test was last night and the same / similar error was thrown on the same drive.

I also have Kingwin KF-4001-BK drive bays ... but it seems your issue was resolved by replacing the drive.

I've since moved my hardware into supermicro sc826 chassis, no more kingwin modules for me.