Hard Drive error in logs - OpenSuse 11.2

yangendel · June 29, 2010, 5:28pm

Hello there,
I have brand new Lenovo Thinkserver RS-110 with LSI LSISAS1064e controller with one Hitachi hard drive model HUA721050KLA330 running OpenSuse 11.2 and Zenoss.
The problem - Server gets locked up at times and I see this in /var/log/messages:

un 29 07:47:03 wgh-monitor kernel: [75593.377494] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:16 wgh-monitor kernel: [75666.074859] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:16 wgh-monitor kernel: [75666.074875] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:16 wgh-monitor kernel: [75666.074882] 72 01 00 1d 00 00 00 0e 09 0c 00 00 01 00 00 00
Jun 29 07:48:16 wgh-monitor kernel: [75666.074901] 00 4f 00 c2 00 50
Jun 29 07:48:16 wgh-monitor kernel: [75666.074911] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:16 wgh-monitor smartd[3264]: Device: /dev/sda [SAT], 1 Currently unreadable (pending) sectors
Jun 29 07:48:16 wgh-monitor ata_id[32000]: HDIO_GET_IDENTITY failed for ‘/dev/sda’
Jun 29 07:48:16 wgh-monitor kernel: [75666.633471] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:16 wgh-monitor kernel: [75666.633485] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:16 wgh-monitor kernel: [75666.633493] 72 01 00 1d 00 00 00 0e 09 0c 00 00 00 00 00 00
Jun 29 07:48:16 wgh-monitor kernel: [75666.633511] 00 00 00 00 00 00
Jun 29 07:48:16 wgh-monitor kernel: [75666.633521] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:16 wgh-monitor kernel: [75666.717912] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:16 wgh-monitor kernel: [75666.717924] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:16 wgh-monitor kernel: [75666.717931] 72 01 00 1d 00 00 00 0e 09 0c 00 00 00 00 00 00
Jun 29 07:48:16 wgh-monitor kernel: [75666.717949] 00 00 00 00 00 00
Jun 29 07:48:16 wgh-monitor kernel: [75666.717959] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:22 wgh-monitor kernel: [75671.884514] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:22 wgh-monitor kernel: [75671.884529] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:22 wgh-monitor kernel: [75671.884536] 72 01 00 1d 00 00 00 0e 09 0c 00 00 00 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75671.884555] 00 00 00 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75671.884565] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:22 wgh-monitor kernel: [75671.976051] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:22 wgh-monitor kernel: [75671.976064] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:22 wgh-monitor kernel: [75671.976070] 72 01 00 1d 00 00 00 0e 09 0c 00 00 00 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75671.976089] 00 00 00 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75671.976099] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:22 wgh-monitor kernel: [75671.977528] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:22 wgh-monitor kernel: [75671.977541] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:22 wgh-monitor kernel: [75671.977548] 72 01 00 1d 00 00 00 0e 09 0c 00 00 00 ff 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75671.977567] 00 00 00 00 00 50
Jun 29 07:48:22 wgh-monitor kernel: [75671.977577] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:22 wgh-monitor kernel: [75672.199631] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:22 wgh-monitor kernel: [75672.199643] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:22 wgh-monitor kernel: [75672.199650] 72 01 00 1d 00 00 00 0e 09 0c 00 00 00 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75672.199668] 00 00 00 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75672.199678] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available
Jun 29 07:48:22 wgh-monitor kernel: [75672.332911] sd 4:0:0:0: [sda] Sense Key : Recovered Error [current] [descriptor]
Jun 29 07:48:22 wgh-monitor kernel: [75672.332923] Descriptor sense data with sense descriptors (in hex):
Jun 29 07:48:22 wgh-monitor kernel: [75672.332930] 72 01 00 1d 00 00 00 0e 09 0c 00 00 01 00 00 00
Jun 29 07:48:22 wgh-monitor kernel: [75672.332949] 00 4f 00 c2 00 50
Jun 29 07:48:22 wgh-monitor kernel: [75672.332958] sd 4:0:0:0: [sda] Add. Sense: ATA pass through information available

Do you think it’s the hard drive or something else??
I am new to Linux and any direction on this would be greatly appreciated.

Thanks.

user · June 29, 2010, 6:09pm

yangendel wrote:

>
> Hello there,
> I have brand new Lenovo Thinkserver RS-110 with LSI LSISAS1064e
> controller with one Hitachi hard drive model HUA721050KLA330 running
> OpenSuse 11.2 and Zenoss.
> The problem - Server gets locked up at times and I see this in
> /var/log/messages:
[snip]
> Do you think it’s the hard drive or something else??

I think it’s the harddrive, but you can check it with SMART - just run a
selftest.

> I am new to Linux and any direction on this would be greatly
> appreciated.

You need to install smart, and then run:

smartctl -t long /dev/sda

The results will be available after a while and can be displayed with
smartctl -a /dev/sda.

–
Per Jessen, Zürich (29.1°C)
http://en.opensuse.org/User:Pjessen

yangendel · June 30, 2010, 1:06am

Thanks for the input…
I have smartctl installed and run smartctl -t long /dev/sda.
It told me to wait 143 minutes.
Where can I find test results when test is complete?

ken_yap · June 30, 2010, 1:14am

You have to use smartctl again to view the results.

user · June 30, 2010, 7:41am

yangendel wrote:

>
> Thanks for the input…
> I have smartctl installed and run smartctl -t long /dev/sda.
> It told me to wait 143 minutes.
> Where can I find test results when test is complete?
>

You display them with ‘smartctl -a /dev/sda’ and you’ll see something like this:

SMART Self-test log structure revision number 1
Num Test_Description Status Remaining LifeTime(hours) LBA_of_first_error

1 Short offline Completed without error 00% 19518 -

2 Short offline Completed without error 00% 19494 -

3 Short offline Completed without error 00% 19470 -

4 Extended offline Completed without error 00% 19448 -

5 Short offline Completed without error 00% 19422 -

6 Short offline Completed without error 00% 19398 -

7 Short offline Completed without error 00% 19374 -

8 Short offline Completed without error 00% 19350 -

9 Short offline Completed without error 00% 19326 -

#10 Short offline Completed without error 00% 19302 -
#11 Extended offline Completed without error 00% 19280 -
#12 Short offline Completed without error 00% 19254 -
#13 Short offline Completed without error 00% 19230 -
#14 Short offline Completed without error 00% 19206 -
#15 Short offline Completed without error 00% 19182 -
#16 Short offline Completed without error 00% 19158 -
#17 Short offline Completed without error 00% 19134 -
#18 Extended offline Completed without error 00% 19112 -
#19 Short offline Completed without error 00% 19086 -
#20 Short offline Completed without error 00% 19062 -
#21 Short offline Completed without error 00% 19038 -

(I run the short test every day, the long test on weekends).

–
Per Jessen, Zürich (19.5°C)
http://en.opensuse.org/User:Pjessen

yangendel · June 30, 2010, 8:40am

Thank you all for support!
Here what I got…

Testing has begun.
Please wait 143 minutes for test to complete.
Test will complete after Tue Jun 29 18:22:41 2010

Use smartctl -X to abort test.

wgh-monitor:~ # smartctl -a /dev/sda
smartctl 5.39 2009-08-08 r2872~ [i686-pc-linux-gnu] (openSUSE RPM)
Copyright (C) 2002-9 by Bruce Allen, smartmontools

=== START OF INFORMATION SECTION ===
Device Model: HUA721050KLA330 39M4517 42C0468IBM
Serial Number: GTA460P6GW2ABF
Firmware Version: GK6OABDA
User Capacity: 500,107,862,016 bytes
Device is: Not in smartctl database [for details use: -P showall]
ATA Version is: 7
ATA Standard is: ATA/ATAPI-7 T13 1532D revision 1
Local Time is: Tue Jun 29 23:28:20 2010 PDT
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: (0x85) Offline data collection activity
was aborted by an interrupting command from host.
Auto Offline Data Collection: Enabled.
Self-test execution status: ( 113) The previous self-test completed having
the read element of the test failed.
Total time to complete Offline
data collection: (8579) seconds.
Offline data collection
capabilities: (0x5b) SMART execute Offline immediate.
Auto Offline data collection on/off support.
Suspend Offline collection upon new
command.
Offline surface scan supported.
Self-test supported.
No 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: ( 1) minutes.
Extended self-test routine
recommended polling time: ( 143) minutes.
SCT capabilities: (0x003f) SCT Status 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 0x000b 100 100 016 Pre-fail Always - 0
2 Throughput_Performance 0x0005 130 130 054 Pre-fail Offline - 152
3 Spin_Up_Time 0x0007 141 141 024 Pre-fail Always - 301 (Average 404)
4 Start_Stop_Count 0x0012 100 100 000 Old_age Always - 17
5 Reallocated_Sector_Ct 0x0033 100 100 005 Pre-fail Always - 0
7 Seek_Error_Rate 0x000b 100 100 067 Pre-fail Always - 0
8 Seek_Time_Performance 0x0005 100 100 020 Pre-fail Offline - 0
9 Power_On_Hours 0x0012 100 100 000 Old_age Always - 2155
10 Spin_Retry_Count 0x0013 100 100 060 Pre-fail Always - 0
12 Power_Cycle_Count 0x0032 100 100 000 Old_age Always - 17
192 Power-Off_Retract_Count 0x0032 100 100 000 Old_age Always - 22
193 Load_Cycle_Count 0x0012 100 100 000 Old_age Always - 22
194 Temperature_Celsius 0x0002 157 157 000 Old_age Always - 38 (Lifetime Min/Max 25/51)
196 Reallocated_Event_Count 0x0032 100 100 000 Old_age Always - 0
197 Current_Pending_Sector 0x0022 100 100 000 Old_age Always - 1
198 Offline_Uncorrectable 0x0008 100 100 000 Old_age Offline - 0
199 UDMA_CRC_Error_Count 0x000a 200 200 000 Old_age Always - 0

SMART Error Log Version: 1
ATA Error Count: 14 (device log contains only the most recent five errors)
CR = Command Register [HEX]
FR = Features Register [HEX]
SC = Sector Count Register [HEX]
SN = Sector Number Register [HEX]
CL = Cylinder Low Register [HEX]
CH = Cylinder High Register [HEX]
DH = Device/Head Register [HEX]
DC = Device Command Register [HEX]
ER = Error register [HEX]
ST = Status register [HEX]
Powered_Up_Time is measured from power on, and printed as
DDd+hh:mm:SS.sss where DD=days, hh=hours, mm=minutes,
SS=sec, and sss=millisec. It “wraps” after 49.710 days.

Error 14 occurred at disk power-on lifetime: 2138 hours (89 days + 2 hours)
When the command that caused the error occurred, the device was active or idle.

After command completion occurred, registers were:
ER ST SC SN CL CH DH

40 51 08 09 ec 86 47 Error: UNC 8 sectors at LBA = 0x0786ec09 = 126282761

Commands leading to the command that caused the error were:
CR FR SC SN CL CH DH DC Powered_Up_Time Command/Feature_Name

25 00 08 09 ec 86 40 00 20:05:09.500 READ DMA EXT
35 00 08 91 11 b5 40 00 20:05:09.500 WRITE DMA EXT
25 00 40 39 4e ad 40 00 20:05:09.500 READ DMA EXT
25 00 08 09 ec 86 40 00 20:05:04.500 READ DMA EXT
25 00 08 f9 eb 86 40 00 20:05:04.500 READ DMA EXT

Error 13 occurred at disk power-on lifetime: 2138 hours (89 days + 2 hours)
When the command that caused the error occurred, the device was active or idle.

After command completion occurred, registers were:
ER ST SC SN CL CH DH

40 51 08 09 ec 86 47 Error: UNC 8 sectors at LBA = 0x0786ec09 = 126282761

Commands leading to the command that caused the error were:
CR FR SC SN CL CH DH DC Powered_Up_Time Command/Feature_Name

25 00 08 09 ec 86 40 00 20:05:04.500 READ DMA EXT
25 00 08 f9 eb 86 40 00 20:05:04.500 READ DMA EXT
25 00 08 f1 eb 86 40 00 20:05:04.500 READ DMA EXT
25 00 08 e9 eb 86 40 00 20:05:04.500 READ DMA EXT
25 00 08 e1 eb 86 40 00 20:05:04.500 READ DMA EXT

Error 12 occurred at disk power-on lifetime: 2118 hours (88 days + 6 hours)
When the command that caused the error occurred, the device was active or idle.

After command completion occurred, registers were:
ER ST SC SN CL CH DH

40 51 08 09 ec 86 47 Error: UNC 8 sectors at LBA = 0x0786ec09 = 126282761

Commands leading to the command that caused the error were:
CR FR SC SN CL CH DH DC Powered_Up_Time Command/Feature_Name

25 00 08 09 ec 86 40 00 00:07:16.600 READ DMA EXT
35 00 08 11 49 41 40 00 00:07:16.600 WRITE DMA EXT
35 00 08 e9 48 41 40 00 00:07:16.600 WRITE DMA EXT
35 00 08 a1 45 41 40 00 00:07:16.600 WRITE DMA EXT
35 00 08 e1 45 40 40 00 00:07:16.600 WRITE DMA EXT

Error 11 occurred at disk power-on lifetime: 2118 hours (88 days + 6 hours)
When the command that caused the error occurred, the device was active or idle.

After command completion occurred, registers were:
ER ST SC SN CL CH DH

40 51 08 09 ec 86 47 Error: UNC 8 sectors at LBA = 0x0786ec09 = 126282761

Commands leading to the command that caused the error were:
CR FR SC SN CL CH DH DC Powered_Up_Time Command/Feature_Name

25 00 08 09 ec 86 40 00 00:07:11.600 READ DMA EXT
25 00 08 f9 eb 86 40 00 00:07:11.600 READ DMA EXT
25 00 08 f1 eb 86 40 00 00:07:11.600 READ DMA EXT
25 00 08 e9 eb 86 40 00 00:07:11.600 READ DMA EXT
25 00 08 e1 eb 86 40 00 00:07:11.600 READ DMA EXT

Error 10 occurred at disk power-on lifetime: 2052 hours (85 days + 12 hours)
When the command that caused the error occurred, the device was active or idle.

After command completion occurred, registers were:
ER ST SC SN CL CH DH

40 51 08 09 ec 86 47 Error: UNC 8 sectors at LBA = 0x0786ec09 = 126282761

Commands leading to the command that caused the error were:
CR FR SC SN CL CH DH DC Powered_Up_Time Command/Feature_Name

25 00 08 09 ec 86 40 00 00:07:05.500 READ DMA EXT
35 00 08 66 c0 cc 40 00 00:07:05.500 WRITE DMA EXT
35 00 08 86 79 aa 40 00 00:07:05.500 WRITE DMA EXT
35 00 08 99 35 51 40 00 00:07:05.500 WRITE DMA EXT
35 00 08 29 46 c1 40 00 00:07:05.500 WRITE DMA EXT

SMART Self-test log structure revision number 1
Num Test_Description Status Remaining LifeTime(hours) LBA_of_first_error

1 Extended offline Completed: read failure 10% 2150 931589129

2 Short offline Completed without error 00% 2049 -

3 Short offline Completed without error 00% 2049 -

4 Short offline Completed without error 00% 0 -

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.

user · June 30, 2010, 9:07am

yangendel wrote:

I’ve reduced the smartctl output to the essential:

> Self-test execution status: ( 113) The previous self-test
> completed having the read element of the test failed.

[lots of read errors snipped]

> SMART Self-test log structure revision number 1
> Num Test_Description Status Remaining
> LifeTime(hours) LBA_of_first_error
> # 1 Extended offline Completed: read failure 10% 2150
> 931589129

I would start the warranty proceedings on this drive.

–
Per Jessen, Zürich (21.1°C)
http://en.opensuse.org/User:Pjessen

yangendel · July 1, 2010, 1:02am

I agree, the drive is bad. I have ordered replacement one.
Thanks for your help.

negut · January 3, 2011, 10:16am

The same problem on windows.ATA error 40 at boot sector.
I think that is a too hard decision to change the harddisk .How about the data on the hard disk.
Let try to remake some of his parts(boot sector information,partition table).Keep you informed.

robin_listas · January 3, 2011, 1:33pm

On 2010-06-30 08:46, yangendel wrote:
>
> Thank you all for support!
> Here what I got…

Please remember to use code tags:

View this thread: http://forums.opensuse.org/showthread.php?t=451526

>> 9 Power_On_Hours 0x0012 100 100 000 Old_age Always - 2155

You also said:

> I have brand new Lenovo Thinkserver RS-110 with LSI LSISAS1064e
> controller with one Hitachi hard drive model HUA721050KLA330 running
> OpenSuse 11.2 and Zenoss.

They lied to you, that disk has 2155 hours.

The error can probably, perhaps, be corrected (by rewriting the entire
disk), but that disk is certainly not brand new.

–
Cheers / Saludos,

Carlos E. R.
(from 11.2 x86_64 “Emerald” at Telcontar)