Possible drive failure in progress?

djdwosk97 · Feb 10, 2016

I tried backing up a few files (<1GB) to my NAS but each time I got an read/write error. The issue is only occurring on my Raid1 array and I'm having no issues copying the same data to another drive on my NAS -- leading me to believe one or both of my RAID1 drives are failing although I haven't gotten any reports from a failed smart test (and I am running them regularly).

Here's the SMART output for the first drive in the array (it's a WD Green that I ran without WDIDLE for years, and thus the load cycle count is dangerously high): (Yes, I know I should be replacing the drive/s even if they aren't failing right now).

Code:

[root@freenas ~]# smartctl -a -q noserial /dev/ada0 |less
smartctl 6.3 2014-07-26 r3976 [FreeBSD 9.3-RELEASE-p13 amd64] (local build)
Copyright (C) 2002-14, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF INFORMATION SECTION ===
Model Family: Western Digital Caviar Green (AF)
Device Model: WDC WD10EARS-00MVWB0
Firmware Version: 51.0AB51
User Capacity: 1,000,204,886,016 bytes [1.00 TB]
Sector Size: 512 bytes logical/physical
Device is: In smartctl database [for details use: -P show]
ATA Version is: ATA8-ACS (minor revision not indicated)
SATA Version is: SATA 2.6, 3.0 Gb/s
Local Time is: Wed Feb 10 17:20:49 2016 EST
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: (0x82) Offline data collection activity
was completed without error.
Auto Offline Data Collection: Enabled.
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: (17880) 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: ( 175) minutes.
Conveyance self-test routine
recommended polling time: ( 5) minutes.
SCT capabilities: (0x3035) 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 0x002f 200 200 051 Pre-fail Always - 21
3 Spin_Up_Time 0x0027 171 163 021 Pre-fail Always - 6408
4 Start_Stop_Count 0x0032 088 088 000 Old_age Always - 12645
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 056 056 000 Old_age Always - 32673
10 Spin_Retry_Count 0x0032 100 100 000 Old_age Always - 0
11 Calibration_Retry_Count 0x0032 100 100 000 Old_age Always - 0
12 Power_Cycle_Count 0x0032 100 100 000 Old_age Always - 122
192 Power-Off_Retract_Count 0x0032 200 200 000 Old_age Always - 72
193 Load_Cycle_Count 0x0032 001 001 000 Old_age Always - 1237504
194 Temperature_Celsius 0x0022 120 089 000 Old_age Always - 30
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 200 200 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 - 9

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% 32615 -
# 2 Short offline Completed without error 00% 32539 -
# 3 Extended offline Completed without error 00% 32340 -
# 4 Short offline Completed without error 00% 32264 -
# 5 Short offline Completed without error 00% 32096 -
# 6 Extended offline Completed without error 00% 32004 -
# 7 Short offline Completed without error 00% 31929 -
# 8 Extended offline Completed without error 00% 31764 -
# 9 Extended offline Completed without error 00% 31597 -
#10 Extended offline Completed without error 00% 31429 -
#11 Extended offline Completed without error 00% 31262 -
#12 Extended offline Completed without error 00% 31094 -
#13 Extended offline Completed without error 00% 31046 -
#14 Extended offline Completed without error 00% 30878 -
#15 Extended offline Completed without error 00% 30710 -
#16 Extended offline Completed without error 00% 30375 -
#17 Extended offline Completed without error 00% 30302 -
#18 Extended offline Completed without error 00% 30134 -
#19 Extended offline Completed without error 00% 29966 -
#20 Extended offline Completed without error 00% 29799 -
#21 Extended offline Completed without error 00% 29700 -

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.

(END)

Drive #2:

Code:

[root@freenas ~]# smartctl -a -q noserial /dev/ada1 |less
smartctl 6.3 2014-07-26 r3976 [FreeBSD 9.3-RELEASE-p13 amd64] (local build)
Copyright (C) 2002-14, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF INFORMATION SECTION ===
Model Family: Seagate Barracuda 7200.12
Device Model: ST31000528AS
Firmware Version: CC38
User Capacity: 1,000,204,886,016 bytes [1.00 TB]
Sector Size: 512 bytes logical/physical
Rotation Rate: 7200 rpm
Device is: In smartctl database [for details use: -P show]
ATA Version is: ATA8-ACS T13/1699-D revision 4
SATA Version is: SATA 2.6, 3.0 Gb/s
Local Time is: Wed Feb 10 17:32:09 2016 EST

==> WARNING: A firmware update for this drive may be available,
see the following Seagate web pages:
http://knowledge.seagate.com/articles/en_US/FAQ/207931en
http://knowledge.seagate.com/articles/en_US/FAQ/213891en

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
See vendor-specific Attribute list for marginal Attributes.

General SMART Values:
Offline data collection status: (0x82) Offline data collection activity
was completed without error.
Auto Offline Data Collection: Enabled.
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: ( 609) 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: ( 1) minutes.
Extended self-test routine
recommended polling time: ( 184) minutes.
Conveyance self-test routine
recommended polling time: ( 2) minutes.
SCT capabilities: (0x103f) SCT Status supported.
SCT Error Recovery Control supported.
SCT Feature Control supported.
SCT Data Table supported.

SMART Attributes Data Structure revision number: 10
Vendor Specific SMART Attributes with Thresholds:
ID# ATTRIBUTE_NAME FLAG VALUE WORST THRESH TYPE UPDATED WHEN_FAILED RAW_VALUE
1 Raw_Read_Error_Rate 0x000f 117 099 006 Pre-fail Always - 127251839
3 Spin_Up_Time 0x0003 095 094 000 Pre-fail Always - 0
4 Start_Stop_Count 0x0032 100 100 020 Old_age Always - 595
5 Reallocated_Sector_Ct 0x0033 100 100 036 Pre-fail Always - 0
7 Seek_Error_Rate 0x000f 062 055 030 Pre-fail Always - 98813702107
9 Power_On_Hours 0x0032 090 090 000 Old_age Always - 9032
10 Spin_Retry_Count 0x0013 100 100 097 Pre-fail Always - 0
12 Power_Cycle_Count 0x0032 100 100 020 Old_age Always - 131
183 Runtime_Bad_Block 0x0032 100 100 000 Old_age Always - 0
184 End-to-End_Error 0x0032 100 100 099 Old_age Always - 0
187 Reported_Uncorrect 0x0032 100 100 000 Old_age Always - 0
188 Command_Timeout 0x0032 100 099 000 Old_age Always - 51540393996
189 High_Fly_Writes 0x003a 098 098 000 Old_age Always - 2
190 Airflow_Temperature_Cel 0x0022 071 043 045 Old_age Always In_the_past 29 (0 5 32 24 0)
194 Temperature_Celsius 0x0022 029 057 000 Old_age Always - 29 (0 15 0 0 0)
195 Hardware_ECC_Recovered 0x001a 032 024 000 Old_age Always - 127251839
197 Current_Pending_Sector 0x0012 100 100 000 Old_age Always - 0
198 Offline_Uncorrectable 0x0010 100 100 000 Old_age Offline - 0
199 UDMA_CRC_Error_Count 0x003e 200 200 000 Old_age Always - 0
240 Head_Flying_Hours 0x0000 100 253 000 Old_age Offline - 5908 (173 188 0)
241 Total_LBAs_Written 0x0000 100 253 000 Old_age Offline - 365777625
242 Total_LBAs_Read 0x0000 100 253 000 Old_age Offline - 2419108026

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% 8973 -
# 2 Short offline Completed without error 00% 8897 -
# 3 Extended offline Completed without error 00% 8697 -
# 4 Short offline Completed without error 00% 8622 -
# 5 Short offline Completed without error 00% 8454 -
# 6 Extended offline Completed without error 00% 8362 -
# 7 Short offline Completed without error 00% 8286 -
# 8 Extended offline Completed without error 00% 8121 -
# 9 Extended offline Completed without error 00% 7954 -
#10 Extended offline Completed without error 00% 7786 -
#11 Extended offline Completed without error 00% 7618 -
#12 Extended offline Completed without error 00% 7449 -
#13 Extended offline Completed without error 00% 7401 -
#14 Extended offline Completed without error 00% 7233 -
#15 Extended offline Completed without error 00% 7065 -
#16 Extended offline Interrupted (host reset) 00% 6897 -
#17 Extended offline Completed without error 00% 6729 -
#18 Extended offline Completed without error 00% 6656 -
#19 Extended offline Completed without error 00% 6488 -
#20 Extended offline Completed without error 00% 6320 -
#21 Extended offline Completed without error 00% 6152 -

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.

(END)

Bidule0hm · Feb 10, 2016

I don't see anything wrong ;)

Waow, 1.2M LCC, that must be the record here :)

djdwosk97 · Feb 10, 2016

Bidule0hm said:
I don't see anything wrong ;)

Waow, 1.2 M LCC, that must be the record here :)

Any idea what else would cause a file to consistently fail to copy (but have no issues copying to a different drive on the NAS?)

Yeah, I was using it in a MyBook Live for a few years before ripping it out and throwing it in my NAS.

Bidule0hm · Feb 10, 2016

Permissions maybe?

djdwosk97 · Feb 10, 2016

Bidule0hm said:
Permissions maybe?

Nope. Plus I've never had an issue with the drives before (and I haven't changed anything).

Of the three different files I tried copying all copied a bit before failing (10-40%~).

dlavigne · Feb 10, 2016

Post the output of ifconfig. Also, anything in /var/log/messages when the copy fails?

djdwosk97 · Feb 10, 2016

dlavigne said:
Post the output of ifconfig. Also, anything in /var/log/messages when the copy fails?

Code:

[root@freenas ~]# ifconfig
em0: flags=8c02<BROADCAST,OACTIVE,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=4219b<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,VLAN_HWCSUM,TSO4,WOL_MAGIC,VLAN_HWTSO>
ether 00:25:90:d8:5e:b3
nd6 options=9<PERFORMNUD,IFDISABLED>
media: Ethernet autoselect
status: no carrier
em1: flags=8843<UP,BROADCAST,RUNNING,SIMPLEX,MULTICAST> metric 0 mtu 1500
options=4219b<RXCSUM,TXCSUM,VLAN_MTU,VLAN_HWTAGGING,VLAN_HWCSUM,TSO4,WOL_MAGIC,VLAN_HWTSO>
ether 00:25:90:d8:5e:b2
inet 192.168.20.15 netmask 0xffffff00 broadcast 192.168.20.255
inet 192.168.20.16 netmask 0xffffff00 broadcast 192.168.20.255
nd6 options=9<PERFORMNUD,IFDISABLED>
media: Ethernet autoselect (1000baseT <full-duplex>)
status: active
ipfw0: flags=8801<UP,SIMPLEX,MULTICAST> metric 0 mtu 65536
nd6 options=9<PERFORMNUD,IFDISABLED>
lo0: flags=8049<UP,LOOPBACK,RUNNING,MULTICAST> metric 0 mtu 16384
options=600003<RXCSUM,TXCSUM,RXCSUM_IPV6,TXCSUM_IPV6>
inet6 ::1 prefixlen 128
inet6 fe80::1%lo0 prefixlen 64 scopeid 0x6
inet 127.0.0.1 netmask 0xff000000
nd6 options=21<PERFORMNUD,AUTO_LINKLOCAL>
[root@freenas ~]#

How would I access /var/log?

dlavigne · Feb 11, 2016

Type more /var/log/messages from the CLI and use the spacebar to scroll through it.

djdwosk97 · Feb 13, 2016

dlavigne said:
Type more /var/log/messages from the CLI and use the spacebar to scroll through it.

Code:

Feb 10 00:00:00 freenas syslog-ng[1918]: Configuration reload request received, reloading configuration;
Feb 10 07:34:50 freenas winbindd[2837]: STATUS=daemon 'winbindd' finished starting up and ready to serve connectionssam_rids_to_na
mes: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528-39914409
Feb 10 07:34:52 freenas smbd[62121]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsFailed to fetch recor
d!
Feb 10 07:34:56 freenas winbindd[2837]: [2016/02/10 07:34:56.397107, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 07:34:56 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 09:21:48 freenas smbd[66541]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsmatchname: host name/
name mismatch: 192.168.20.229 != (NULL)
Feb 10 09:21:48 freenas smbd[66541]: [2016/02/10 09:21:48.132708, 0] ../source3/lib/util_sock.c:1199(get_remote_hostname)
Feb 10 09:21:48 freenas smbd[66541]: matchname failed on 192.168.20.229
Feb 10 09:21:48 freenas winbindd[2837]: [2016/02/10 09:21:48.191595, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 09:21:48 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 13:05:19 freenas smbd[74584]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsmatchname: host name/
name mismatch: 192.168.20.229 != (NULL)
Feb 10 13:05:19 freenas smbd[74584]: [2016/02/10 13:05:19.535021, 0] ../source3/lib/util_sock.c:1199(get_remote_hostname)
Feb 10 13:05:19 freenas smbd[74584]: matchname failed on 192.168.20.229
Feb 10 13:05:19 freenas winbindd[2837]: [2016/02/10 13:05:19.590797, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 13:05:19 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:46:20 freenas smbd[83643]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsFailed to fetch recor
d!
Feb 10 16:47:55 freenas winbindd[2837]: [2016/02/10 16:47:55.104058, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 16:47:55 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:47:56 freenas smbd[83706]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsFailed to fetch recor
d!
Feb 10 16:47:58 freenas winbindd[2837]: [2016/02/10 16:47:58.817358, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 16:47:58 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:55:48 freenas winbindd[2837]: [2016/02/10 16:55:48.975397, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 16:55:48 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:56:22 freenas winbindd[2837]: [2016/02/10 16:56:22.022224, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 16:56:22 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:56:22 freenas smbd[84845]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsdaniels-rmbp (ipv4:19
2.168.20.211:63024) closed connection to service Photos
Feb 10 16:57:37 freenas smbd[84874]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsdaniels-rmbp (ipv4:19
2.168.20.211:63030) closed connection to service Photos
Feb 10 16:57:37 freenas winbindd[2837]: [2016/02/10 16:57:37.891971, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 16:57:37 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:57:37 freenas smbd[84899]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsdaniels-rmbp (ipv4:19
2.168.20.211:63059) closed connection to service Photos
Feb 10 16:57:37 freenas winbindd[2837]: [2016/02/10 16:57:37.976008, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 16:57:37 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 16:57:37 freenas smbd[84900]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsFailed to fetch recor
d!
Feb 10 17:08:21 freenas winbindd[2837]: [2016/02/10 17:08:21.190505, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 17:08:21 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409
Feb 10 17:08:22 freenas smbd[85928]: STATUS=daemon 'smbd' finished starting up and ready to serve connectionsFailed to fetch recor
d!
Feb 10 17:08:25 freenas winbindd[2837]: [2016/02/10 17:08:25.078979, 0] ../source3/winbindd/winbindd_samr.c:769(sam_rids_to_names)
Feb 10 17:08:25 freenas winbindd[2837]: sam_rids_to_names: possible deadlock - trying to lookup SID S-1-5-21-1575378786-3262691528
-39914409

Is there something wrong with my permissions maybe? (looking through the log I seem to get one or two of these a day with a bunch on the 10th -- the day I was trying to copy stuff up to the drives I thought were failing).

Here are my permissions for all of the datasets (I'm using CIFS and AFP).

DrKK · Feb 14, 2016

He's got a very high Hardware ECC correct number in the SMART data...

Important Announcement for The TrueNAS Community.

Possible drive failure in progress?

djdwosk97

Patron

Bidule0hm

Server Electronics Sorcerer

djdwosk97

Patron

Bidule0hm

Server Electronics Sorcerer

djdwosk97

Patron

dlavigne

Guest

djdwosk97

Patron

dlavigne

Guest

djdwosk97

Patron

DrKK

FreeNAS Generalissimo

Similar threads