Server Crash - Scary Output log after scrub

David3D · Apr 25, 2016

Hi guys,

Firstly...Specs:

- Build: FreeNAS-9.3-STABLE-201602031011 (Pending updates / which I don't want to do right now)
- Processor: x2 Intel(R) Xeon(R) CPU L5410 @ 2.33GHz
- Memory: 40928MB ECC
- Media: 12x 2TB Red PRO drives (All within temperature spec/no SMART issues)
- Drives connected from SATA backplane directly in to board.
- All drives are brand new and were burned in successfully a while ago.

It seems I've wrongfully blamed CrashPlan for randomly crashing my server every now and again. I got an email telling me FreeNAS was performing a scrub and then the usual "security run output" log I get every day.

This is a fairly new 9.3 installation. I'm tempted to reinstall.

First security run output:

Code:

SERVER.WORKGROUP changes in mounted filesystems:
0a1,9
> /mnt/PRIMARY/ACCOUNTS /mnt/PRIMARY/jails/crashplan_1/media/Accounts nullfs    ro              0 0
> /mnt/PRIMARY/INTERNAL /mnt/PRIMARY/jails/crashplan_1/media/Internal nullfs    ro              0 0
> /mnt/PRIMARY/QUOTES   /mnt/PRIMARY/jails/crashplan_1/media/Quotes nullfs      ro              0 0
> /mnt/PRIMARY/RETAIL   /mnt/PRIMARY/jails/crashplan_1/media/Retail nullfs      ro              0 0
> /mnt/PRIMARY/SG-SERVER        /mnt/PRIMARY/jails/crashplan_1/media/SG Files nullfs    ro              0 0
> /mnt/PRIMARY/SWAP     /mnt/PRIMARY/jails/crashplan_1/media/Swap nullfs        ro              0 0
> /mnt/PRIMARY/TRADE    /mnt/PRIMARY/jails/crashplan_1/media/Trade nullfs       ro              0 0
> /mnt/SECONDARY/BACKUP/I.T /mnt/PRIMARY/jails/crashplan_1/media/IT Docs nullfs ro              0 0
> /mnt/SECONDARY/BACKUP/Portable Disk Backups /mnt/PRIMARY/jails/crashplan_1/media/Portable Disk Backups nullfs ro              0 0
22a32
> devfs                 /mnt/PRIMARY/jails/crashplan_1/dev devfs        rw,multilabel   0 0
24a35
> procfs                        /mnt/PRIMARY/jails/crashplan_1/proc procfs      rw              0 0

SERVER.WORKGROUP kernel log messages:
> lapic5: Forcing LINT1 to edge trigger
> SMP: AP CPU #5 Launched!
> lapic2: Forcing LINT1 to edge trigger
> SMP: AP CPU #2 Launched!
> lapic3: Forcing LINT1 to edge trigger
> SMP: AP CPU #3 Launched!
> lapic7: Forcing LINT1 to edge trigger
> SMP: AP CPU #7 Launched!
> Timecounter "TSC-low" frequency 1163775669 Hz quality 1000
> vboxdrv: fAsync=0 offMin=0x738 offMax=0x738e
> em1: promiscuous mode enabled
> epair0a: Ethernet address: 02:fb:1a:00:0f:0a
> epair0b: Ethernet address: 02:fb:1a:00:10:0b
> epair0a: promiscuous mode enabled

-- End of security output --

Security run output after scrub:

Code:

SERVER.WORKGROUP kernel log messages:
> (da10:mpt0:0:21:0): READ(10). CDB: 28 00 0f 6b ac c8 00 00 50 00
> (da10:mpt0:0:21:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da10:mpt0:0:21:0): Retrying command
> (da6:mpt0:0:17:0): READ(10). CDB: 28 00 0f 6b ac d0 00 00 48 00
> (da6:mpt0:0:17:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da6:mpt0:0:17:0): Retrying command
> (da9:mpt0:0:20:0): READ(10). CDB: 28 00 0f 6b ac c8 00 00 50 00
> (da9:mpt0:0:20:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da9:mpt0:0:20:0): Retrying command
> (da7:mpt0:0:18:0): READ(10). CDB: 28 00 0f 6b ad 20 00 00 f8 00
> (da7:mpt0:0:18:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da7:mpt0:0:18:0): Retrying command
> (da10:mpt0:0:21:0): READ(10). CDB: 28 00 0f 6b ad 18 00 01 00 00
> (da10:mpt0:0:21:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da10:mpt0:0:21:0): Retrying command
> (da0:mpt0:0:11:0): READ(10). CDB: 28 00 11 f0 1e 78 00 01 00 00
> (da0:mpt0:0:11:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da0:mpt0:0:11:0): Retrying command
> (da11:mpt0:0:22:0): READ(10). CDB: 28 00 0f 6b ab c8 00 01 00 00
> (da11:mpt0:0:22:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da11:mpt0:0:22:0): Retrying command
> (da1:mpt0:0:12:0): READ(10). CDB: 28 00 11 f0 1d 70 00 01 00 00
> (da1:mpt0:0:12:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da1:mpt0:0:12:0): Retrying command
> (da9:mpt0:0:20:0): READ(10). CDB: 28 00 0f 6b ad 18 00 01 00 00
> (da9:mpt0:0:20:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da9:mpt0:0:20:0): Retrying command
> (da6:mpt0:0:17:0): READ(10). CDB: 28 00 0f 6b ad 20 00 00 f8 00
> (da6:mpt0:0:17:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da6:mpt0:0:17:0): Retrying command
> (da7:mpt0:0:18:0): READ(10). CDB: 28 00 00 40 02 90 00 00 10 00
> (da7:mpt0:0:18:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da7:mpt0:0:18:0): Retrying command
> (da7:mpt0:0:18:0): READ(10). CDB: 28 00 e8 e0 84 90 00 00 10 00
> (da7:mpt0:0:18:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da7:mpt0:0:18:0): Retrying command
> (da7:mpt0:0:18:0): READ(10). CDB: 28 00 e8 e0 86 90 00 00 10 00
> (da7:mpt0:0:18:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da7:mpt0:0:18:0): Retrying command
> (da1:mpt0:0:12:0): READ(10). CDB: 28 00 11 f0 1f 70 00 01 00 00
> (da1:mpt0:0:12:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da1:mpt0:0:12:0): Retrying command
> (da2:mpt0:0:13:0): READ(10). CDB: 28 00 11 f0 1d 70 00 01 00 00
> (da2:mpt0:0:13:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da2:mpt0:0:13:0): Retrying command
> (da6:mpt0:0:17:0): READ(10). CDB: 28 00 0f 6b ae 18 00 00 e0 00
> (da6:mpt0:0:17:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da6:mpt0:0:17:0): Retrying command
> (da7:mpt0:0:18:0): READ(10). CDB: 28 00 0f 6b af 88 00 00 e0 00
> (da7:mpt0:0:18:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da7:mpt0:0:18:0): Retrying command
> (da10:mpt0:0:21:0): READ(10). CDB: 28 00 0f 6b ae 18 00 01 00 00
> (da10:mpt0:0:21:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da10:mpt0:0:21:0): Retrying command
> (da9:mpt0:0:20:0): READ(10). CDB: 28 00 0f 6b ae 18 00 01 00 00
> (da9:mpt0:0:20:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da9:mpt0:0:20:0): Retrying command
> (da6:mpt0:0:17:0): READ(10). CDB: 28 00 0f 6b ae f8 00 01 00 00
> (da6:mpt0:0:17:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da6:mpt0:0:17:0): Retrying command
> (da10:mpt0:0:21:0): READ(10). CDB: 28 00 0f 6b af 18 00 01 00 00
> (da10:mpt0:0:21:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da10:mpt0:0:21:0): Retrying command
> (da9:mpt0:0:20:0): READ(10). CDB: 28 00 0f 6b af 18 00 01 00 00
> (da9:mpt0:0:20:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da9:mpt0:0:20:0): Retrying command
> (da1:mpt0:0:12:0): READ(10). CDB: 28 00 11 f0 1d 70 00 01 00 00
> (da1:mpt0:0:12:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da1:mpt0:0:12:0): Retrying command
> (da5:mpt0:0:16:0): READ(10). CDB: 28 00 11 f0 22 30 00 00 40 00
> (da5:mpt0:0:16:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da5:mpt0:0:16:0): Retrying command
> (da4:mpt0:0:15:0): READ(10). CDB: 28 00 11 f0 22 30 00 00 40 00
> (da4:mpt0:0:15:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da4:mpt0:0:15:0): Retrying command
> (da5:mpt0:0:16:0): READ(10). CDB: 28 00 11 f0 22 b0 00 00 40 00
> (da5:mpt0:0:16:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da5:mpt0:0:16:0): Retrying command
> (da11:mpt0:0:22:0): READ(10). CDB: 28 00 0f 6b ae a8 00 01 00 00
> (da11:mpt0:0:22:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da11:mpt0:0:22:0): Retrying command
> (da4:mpt0:0:15:0): READ(10). CDB: 28 00 11 f0 22 b0 00 00 40 00
> (da4:mpt0:0:15:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da4:mpt0:0:15:0): Retrying command
> (da2:mpt0:0:13:0): READ(10). CDB: 28 00 11 f0 1e 70 00 01 00 00
> (da2:mpt0:0:13:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da2:mpt0:0:13:0): Retrying command
> (da11:mpt0:0:22:0): READ(10). CDB: 28 00 0f 6b af a8 00 01 00 00
> (da11:mpt0:0:22:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da11:mpt0:0:22:0): Retrying command
> (da3:mpt0:0:14:0): READ(10). CDB: 28 00 11 f0 1e 70 00 01 00 00
> (da3:mpt0:0:14:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da3:mpt0:0:14:0): Retrying command
> (da7:mpt0:0:18:0): READ(10). CDB: 28 00 0f 6b b0 68 00 00 70 00
> (da7:mpt0:0:18:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da7:mpt0:0:18:0): Retrying command
> (da10:mpt0:0:21:0): READ(10). CDB: 28 00 0f 6b ae 18 00 01 00 00
> (da10:mpt0:0:21:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da10:mpt0:0:21:0): Retrying command
> (da9:mpt0:0:20:0): READ(10). CDB: 28 00 0f 6b ae 18 00 01 00 00
> (da9:mpt0:0:20:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da9:mpt0:0:20:0): Retrying command
> (da6:mpt0:0:17:0): READ(10). CDB: 28 00 0f 6b ae f8 00 01 00 00
> (da6:mpt0:0:17:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da6:mpt0:0:17:0): Retrying command
> (da10:mpt0:0:21:0): READ(10). CDB: 28 00 0f 6b af 18 00 01 00 00
> (da10:mpt0:0:21:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da10:mpt0:0:21:0): Retrying command
> (da5:mpt0:0:16:0): READ(10). CDB: 28 00 11 f0 22 30 00 00 40 00
> (da5:mpt0:0:16:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da5:mpt0:0:16:0): Retrying command
> (da9:mpt0:0:20:0): READ(10). CDB: 28 00 0f 6b af 18 00 01 00 00
> (da9:mpt0:0:20:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da9:mpt0:0:20:0): Retrying command
> (da5:mpt0:0:16:0): READ(10). CDB: 28 00 11 f0 22 b0 00 00 40 00
> (da5:mpt0:0:16:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da5:mpt0:0:16:0): Retrying command
> (da4:mpt0:0:15:0): READ(10). CDB: 28 00 11 f0 22 30 00 00 40 00
> (da4:mpt0:0:15:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da4:mpt0:0:15:0): Retrying command
> (da4:mpt0:0:15:0): READ(10). CDB: 28 00 11 f0 22 b0 00 00 40 00
> (da4:mpt0:0:15:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da4:mpt0:0:15:0): Retrying command
> (da1:mpt0:0:12:0): READ(10). CDB: 28 00 11 f0 1d 70 00 01 00 00
> (da1:mpt0:0:12:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da1:mpt0:0:12:0): Retrying command
> (da11:mpt0:0:22:0): READ(10). CDB: 28 00 0f 6b ae a8 00 01 00 00
> (da11:mpt0:0:22:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da11:mpt0:0:22:0): Retrying command
> (da11:mpt0:0:22:0): READ(10). CDB: 28 00 0f 6b af a8 00 01 00 00
> (da11:mpt0:0:22:0): CAM status: Unrecoverable Host Bus Adapter Error
> (da11:mpt0:0:22:0): Retrying command
> (da0:mpt0:0:11:0): READ(10). CDB: 28 00 11 f0 21 78 00 00 c0 00
> (da0:mpt0:0:11:0): CAM status: CCB request terminated by the host
> (da0:mpt0:0:11:0): Retrying command
> (da0:mpt0:0:11:0): READ(10). CDB: 28 00 11 f0 20 78 00 01 00 00
> (da0:mpt0:0:11:0): CAM status: CCB request terminated by the host
> (da0:mpt0:0:11:0): Retrying command
> (da2:mpt0:0:13:0): READ(10). CDB: 28 00 11 f0 1e 70 00 01 00 00
> (da2:mpt0:0:13:0): CAM status: CCB request terminated by the host
> (da2:mpt0:0:13:0): Retrying command
> (da2:mpt0:0:13:0): READ(10). CDB: 28 00 11 f0 1d 70 00 01 00 00
> (da2:mpt0:0:13:0): CAM status: CCB request terminated by the host
> (da2:mpt0:0:13:0): Retrying command
> (da8:mpt0:0:19:0): READ(10). CDB: 28 00 0f 6b ac a8 00 00 70 00
> (da8:mpt0:0:19:0): CAM status: CCB request terminated by the host
> (da8:mpt0:0:19:0): Retrying command
> (da8:mpt0:0:19:0): READ(10). CDB: 28 00 0f 6b ac 60 00 00 48 00
> (da8:mpt0:0:19:0): CAM status: CCB request terminated by the host
> (da8:mpt0:0:19:0): Retrying command
> (da0:mpt0:0:11:0): READ(10). CDB: 28 00 11 f0 21 78 00 00 c0 00
> (da0:mpt0:0:11:0): CAM status: SCSI Status Error
> (da0:mpt0:0:11:0): SCSI status: Check Condition
> (da0:mpt0:0:11:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
> (da0:mpt0:0:11:0): Retrying command (per sense data)
> (da2:mpt0:0:13:0): READ(10). CDB: 28 00 11 f0 1d 70 00 01 00 00
> (da2:mpt0:0:13:0): CAM status: SCSI Status Error
> (da2:mpt0:0:13:0): SCSI status: Check Condition
> (da2:mpt0:0:13:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
> (da2:mpt0:0:13:0): Retrying command (per sense data)
> (da8:mpt0:0:19:0): READ(10). CDB: 28 00 0f 6b ac 60 00 00 48 00
> (da8:mpt0:0:19:0): CAM status: SCSI Status Error
> (da8:mpt0:0:19:0): SCSI status: Check Condition
> (da8:mpt0:0:19:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset, or bus device reset occurred)
> (da8:mpt0:0:19:0): Retrying command (per sense data)

-- End of security output --

Security run output the next day:

Code:

SERVER.WORKGROUP changes in mounted filesystems:
1,9d0
< /mnt/PRIMARY/ACCOUNTS /mnt/PRIMARY/jails/crashplan_1/media/Accounts nullfs    ro              0 0
< /mnt/PRIMARY/INTERNAL /mnt/PRIMARY/jails/crashplan_1/media/Internal nullfs    ro              0 0
< /mnt/PRIMARY/QUOTES   /mnt/PRIMARY/jails/crashplan_1/media/Quotes nullfs      ro              0 0
< /mnt/PRIMARY/RETAIL   /mnt/PRIMARY/jails/crashplan_1/media/Retail nullfs      ro              0 0
< /mnt/PRIMARY/SG-SERVER        /mnt/PRIMARY/jails/crashplan_1/media/SG Files nullfs    ro              0 0
< /mnt/PRIMARY/SWAP     /mnt/PRIMARY/jails/crashplan_1/media/Swap nullfs        ro              0 0
< /mnt/PRIMARY/TRADE    /mnt/PRIMARY/jails/crashplan_1/media/Trade nullfs       ro              0 0
< /mnt/SECONDARY/BACKUP/I.T /mnt/PRIMARY/jails/crashplan_1/media/IT Docs nullfs ro              0 0
< /mnt/SECONDARY/BACKUP/Portable Disk Backups /mnt/PRIMARY/jails/crashplan_1/media/Portable Disk Backups nullfs ro              0 0
27d17
< PRIMARY/jails/crashplan_1 /mnt/PRIMARY/jails/crashplan_1 zfs  rw,nfsv4acls    0 0
32d21
< devfs                 /mnt/PRIMARY/jails/crashplan_1/dev devfs        rw,multilabel   0 0
35d23
< procfs                        /mnt/PRIMARY/jails/crashplan_1/proc procfs      rw              0 0

SERVER.WORKGROUP kernel log messages:
> lapic6: Forcing LINT1 to edge trigger
> SMP: AP CPU #6 Launched!
> lapic2: Forcing LINT1 to edge trigger
> SMP: AP CPU #2 Launched!
> lapic4: Forcing LINT1 to edge trigger
> SMP: AP CPU #4 Launched!
> lapic5: Forcing LINT1 to edge trigger
> SMP: AP CPU #5 Launched!
> Timecounter "TSC-low" frequency 1163772599 Hz quality 1000
> vboxdrv: fAsync=0 offMin=0x3fe offMax=0xc709
> bridge0: link state changed to UP
> epair0a: Ethernet address: 02:0a:61:00:0f:0a
> epair0b: Ethernet address: 02:0a:61:00:10:0b
> em1: link state changed to DOWN
> epair0a: link state changed to DOWN
> epair0b: link state changed to DOWN
> em1: link state changed to DOWN
> em1: promiscuous mode disabled
> bridge0: link state changed to DOWN
> em1: link state changed to UP
> ifa_del_loopback_route: deletion failed
> Freed UMA keg (udp_inpcb) was not empty (60 items).  Lost 6 pages of memory.
> Freed UMA keg (udpcb) was not empty (840 items).  Lost 5 pages of memory.
> Freed UMA keg (tcptw) was not empty (150 items).  Lost 3 pages of memory.
> Freed UMA keg (tcp_inpcb) was not empty (50 items).  Lost 5 pages of memory.
> Freed UMA keg (tcpcb) was not empty (20 items).  Lost 5 pages of memory.
> hhook_vnet_uninit: hhook_head type=1, id=1 cleanup required
> hhook_vnet_uninit: hhook_head type=1, id=0 cleanup required

-- End of security output --

Snapshots have been spamming my log, so it's been hard to nail down exactly what's happening. I disabled snapshots and it seems the box has remained up for two days.

Any advice from here? I need a little help decoding these logs, but a quick google might suggest SATA cables could be to blame.

It's worth noting, all pools are saying they are healthy and good to go.

Thank you in advance! :)

Ericloewe · Apr 25, 2016

mpt? You're using LSI SAS1 stuff? That's unusual.

At first glance, it doesn't seem like it's a problem with the physical interface between HBA and drives, but SMART data from a few representative drives would help interpret what is going on.

David3D · Apr 25, 2016

Ericloewe said:
mpt? You're using LSI SAS1 stuff? That's unusual..

I didn't choose the hardware. Was told specifically it needed to work with FreeNAS and was told it would be fine. I hope they were right!
It's an IBM HS-1235E Xyratex.

I'll work on getting the SMART data. I have it set to run the short test every day and the long twice per month if I remember correctly. Is there a particular way I should output and upload the data?

Ericloewe · Apr 25, 2016

David3D said:
I'll work on getting the SMART data. I have it set to run the short test every day and the long twice per month if I remember correctly. Is there a particular way I should output and upload the data?

Just grab the output of smartctl -a /dev/dawhatever and copy/paste it directly into CODE tags on the forum or onto pastebin.

David3D said:
I didn't choose the hardware. Was told specifically it needed to work with FreeNAS and was told it would be fine. I hope they were right!
It's an IBM HS-1235E Xyratex.

It should work, but you're limited to 2TB drives, due to hardware limitations in LSI's stuff.

David3D · Apr 25, 2016

Ericloewe said:
Just grab the output of smartctl -a /dev/dawhatever and copy/paste it directly into CODE tags on the forum or onto pastebin.

It should work, but you're limited to 2TB drives, due to hardware limitations in LSI's stuff.

Output of da0:

Code:

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%      1384         -
# 2  Short offline       Completed without error       00%      1306         -
# 3  Short offline       Completed without error       00%      1138         -
# 4  Extended offline    Interrupted (host reset)      40%      1046         -
# 5  Short offline       Completed without error       00%       970         -
# 6  Short offline       Completed without error       00%       730         -
# 7  Extended offline    Interrupted (host reset)      50%       638         -
# 8  Short offline       Completed without error       00%       562         -
# 9  Short offline       Completed without error       00%       399         -
#10  Extended offline    Completed without error       00%       313         -
#11  Short offline       Completed without error       00%       232         -
#12  Extended offline    Completed without error       00%        12         -
                                                                              
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.

The rest are practically the same. If SMART is failing, I should be getting emails, right? It looks like it might not be getting through extended tests. Perhaps this is causing the crash? I wondered why they seemed to happen at a particular time.

Ericloewe · Apr 25, 2016

David3D said:

Output of da0:

Code:

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%      1384         -
# 2  Short offline       Completed without error       00%      1306         -
# 3  Short offline       Completed without error       00%      1138         -
# 4  Extended offline    Interrupted (host reset)      40%      1046         -
# 5  Short offline       Completed without error       00%       970         -
# 6  Short offline       Completed without error       00%       730         -
# 7  Extended offline    Interrupted (host reset)      50%       638         -
# 8  Short offline       Completed without error       00%       562         -
# 9  Short offline       Completed without error       00%       399         -
#10  Extended offline    Completed without error       00%       313         -
#11  Short offline       Completed without error       00%       232         -
#12  Extended offline    Completed without error       00%        12         -
                                                                             
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.

The rest are practically the same. If SMART is failing, I should be getting emails, right? It looks like it might not be getting through extended tests. Perhaps this is causing the crash? I wondered why they seemed to happen at a particular time.

Did you use the -a flag? That output is missing the most interesting part.

David3D · Apr 25, 2016

Ericloewe said:
Did you use the -a flag? That output is missing the most interesting part.

Sorry, went in to my SSH client and ran the same thing. Apparently the output in the shell only shows half the story.

Code:

=== START OF INFORMATION SECTION ===
Model Family:     Western Digital Red Pro
Device Model:     WDC WD2001FFSX-68JNUN0
Serial Number:    WD-WMC5C0D86HAH
LU WWN Device Id: 5 0014ee 004234f2f
Firmware Version: 81.00A81
User Capacity:    2,000,398,934,016 bytes [2.00 TB]
Sector Sizes:     512 bytes logical, 4096 bytes physical
Rotation Rate:    7200 rpm
Device is:        In smartctl database [for details use: -P show]
ATA Version is:   ATA8-ACS (minor revision not indicated)
SATA Version is:  SATA 3.0, 6.0 Gb/s (current: 3.0 Gb/s)
Local Time is:    Tue Apr 26 10:49:29 2016 NZST
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:                (23100) 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:        ( 252) minutes.
Conveyance self-test routine
recommended polling time:        (   5) minutes.
SCT capabilities:              (0x70bd) 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   164   164   021    Pre-fail  Always       -       6783
  4 Start_Stop_Count        0x0032   100   100   000    Old_age   Always       -       18
  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   098   098   000    Old_age   Always       -       1464
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       -       18
16 Unknown_Attribute       0x0022   032   168   000    Old_age   Always       -       568541628981
183 Runtime_Bad_Block       0x0032   100   100   000    Old_age   Always       -       0
192 Power-Off_Retract_Count 0x0032   200   200   000    Old_age   Always       -       16
193 Load_Cycle_Count        0x0032   200   200   000    Old_age   Always       -       3
194 Temperature_Celsius     0x0022   124   115   000    Old_age   Always       -       26
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%      1384         -
# 2  Short offline       Completed without error       00%      1306         -
# 3  Short offline       Completed without error       00%      1138         -
# 4  Extended offline    Interrupted (host reset)      40%      1046         -
# 5  Short offline       Completed without error       00%       970         -
# 6  Short offline       Completed without error       00%       730         -
# 7  Extended offline    Interrupted (host reset)      50%       638         -
# 8  Short offline       Completed without error       00%       562         -
# 9  Short offline       Completed without error       00%       399         -
#10  Extended offline    Completed without error       00%       313         -
#11  Short offline       Completed without error       00%       232         -
#12  Extended offline    Completed without error       00%        12         -

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.

Output for da1:

Code:

=== START OF INFORMATION SECTION ===
Model Family:     Western Digital Red Pro
Device Model:     WDC WD2001FFSX-68JNUN0
Serial Number:    WD-WMC5C0D44XXJ
LU WWN Device Id: 5 0014ee 05978689a
Firmware Version: 81.00A81
User Capacity:    2,000,398,934,016 bytes [2.00 TB]
Sector Sizes:     512 bytes logical, 4096 bytes physical
Rotation Rate:    7200 rpm
Device is:        In smartctl database [for details use: -P show]
ATA Version is:   ATA8-ACS (minor revision not indicated)
SATA Version is:  SATA 3.0, 6.0 Gb/s (current: 3.0 Gb/s)
Local Time is:    Tue Apr 26 10:53:05 2016 NZST
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:                (23100) 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:        ( 252) minutes.
Conveyance self-test routine
recommended polling time:        (   5) minutes.
SCT capabilities:              (0x70bd) 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   167   167   021    Pre-fail  Always       -       6625
  4 Start_Stop_Count        0x0032   100   100   000    Old_age   Always       -       18
  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   098   098   000    Old_age   Always       -       1464
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       -       18
16 Unknown_Attribute       0x0022   033   167   000    Old_age   Always       -       594134475453
183 Runtime_Bad_Block       0x0032   100   100   000    Old_age   Always       -       0
192 Power-Off_Retract_Count 0x0032   200   200   000    Old_age   Always       -       16
193 Load_Cycle_Count        0x0032   200   200   000    Old_age   Always       -       3
194 Temperature_Celsius     0x0022   124   115   000    Old_age   Always       -       26
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%      1384         -
# 2  Short offline       Completed without error       00%      1306         -
# 3  Short offline       Completed without error       00%      1138         -
# 4  Extended offline    Interrupted (host reset)      40%      1046         -
# 5  Short offline       Completed without error       00%       970         -
# 6  Short offline       Completed without error       00%       730         -
# 7  Extended offline    Interrupted (host reset)      50%       638         -
# 8  Short offline       Completed without error       00%       562         -
# 9  Short offline       Completed without error       00%       399         -
#10  Extended offline    Completed without error       00%       313         -
#11  Short offline       Completed without error       00%       232         -

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.

Output for da2:

Code:

=== START OF INFORMATION SECTION ===
Model Family:     Western Digital Red Pro
Device Model:     WDC WD2001FFSX-68JNUN0
Serial Number:    WD-WMC5C0D226VP
LU WWN Device Id: 5 0014ee 0aece14c4
Firmware Version: 81.00A81
User Capacity:    2,000,398,934,016 bytes [2.00 TB]
Sector Sizes:     512 bytes logical, 4096 bytes physical
Rotation Rate:    7200 rpm
Device is:        In smartctl database [for details use: -P show]
ATA Version is:   ATA8-ACS (minor revision not indicated)
SATA Version is:  SATA 3.0, 6.0 Gb/s (current: 3.0 Gb/s)
Local Time is:    Tue Apr 26 10:56:32 2016 NZST
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:                (23100) 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:        ( 252) minutes.
Conveyance self-test routine
recommended polling time:        (   5) minutes.
SCT capabilities:              (0x70bd) 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   167   167   021    Pre-fail  Always       -       6650
  4 Start_Stop_Count        0x0032   100   100   000    Old_age   Always       -       18
  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   098   098   000    Old_age   Always       -       1464
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       -       18
16 Unknown_Attribute       0x0022   036   164   000    Old_age   Always       -       649916713105
183 Runtime_Bad_Block       0x0032   100   100   000    Old_age   Always       -       0
192 Power-Off_Retract_Count 0x0032   200   200   000    Old_age   Always       -       16
193 Load_Cycle_Count        0x0032   200   200   000    Old_age   Always       -       3
194 Temperature_Celsius     0x0022   124   115   000    Old_age   Always       -       26
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%      1384         -
# 2  Short offline       Completed without error       00%      1306         -
# 3  Short offline       Completed without error       00%      1138         -
# 4  Extended offline    Interrupted (host reset)      40%      1046         -
# 5  Short offline       Completed without error       00%       970         -
# 6  Short offline       Completed without error       00%       730         -
# 7  Extended offline    Interrupted (host reset)      50%       638         -
# 8  Short offline       Completed without error       00%       562         -
# 9  Short offline       Completed without error       00%       399         -
#10  Extended offline    Completed without error       00%       313         -
#11  Short offline       Completed without error       00%       232         -

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.

Seems to be a trend through the rest of them. Let me know if this is enough or you need all 12 disks. I hope "Pre-fail" and "old_age" don't mean what I think they mean.

Ericloewe · Apr 25, 2016

David3D said:
I hope "Pre-fail" and "old_age" don't mean what I think they mean.

They just indicate the failure mode each stat tracks, don't worry.

However, as I thought, it doesn't look like a cable issue at all. The drives all report zero UDMA errors, suggesting that the problem is in the controller.

It could be some weird incompatibility (LSI SAS1 stuff is essentially obsolete and the Red Pros are a newer model) or plain physical malfunction.

David3D · Apr 25, 2016

Ericloewe said:
They just indicate the failure mode each stat tracks, don't worry.

However, as I thought, it doesn't look like a cable issue at all. The drives all report zero UDMA errors, suggesting that the problem is in the controller.

It could be some weird incompatibility (LSI SAS1 stuff is essentially obsolete and the Red Pros are a newer model) or plain physical malfunction.

That's not cool. Only until recently have I been getting these errors. Unless a recent update has caused the issue, it's almost certainly hardware failure.
Is it at all possible to replace the backplane with something more modern? I didn't think SAS backplanes were backwards compatible with SATA hard disks.

Ericloewe · Apr 25, 2016

David3D said:
That's not cool. Only until recently have I been getting these errors. Unless a recent update has caused the issue, it's almost certainly hardware failure.
Is it at all possible to replace the backplane with something more modern? I didn't think SAS backplanes were backwards compatible with SATA hard disks.

A new SAS controller is easily added, if enough PCI-e connectivity exists.
The backplane may be harder - it depends on what the manufacturer has available for that chassis.

But first, I'd investigate driver and firmware version mismatches.

IIRC, you need the P21 firmware on the SAS controller (check the mpt driver version and flash the same firmware version). Also, investigate firmware updates for the backplane.

David3D · Apr 25, 2016

Ericloewe said:
A new SAS controller is easily added, if enough PCI-e connectivity exists.
The backplane may be harder - it depends on what the manufacturer has available for that chassis.

But first, I'd investigate driver and firmware version mismatches.

IIRC, you need the P21 firmware on the SAS controller (check the mpt driver version and flash the same firmware version). Also, investigate firmware updates for the backplane.

Thanks for all your help!

I can't find anything to do with Xyratex on the internet let alone firmware stuff. I'll contact the company that sent the unit to me and explain the issue from there. I don't want to call it hardware failure yet either, but I haven't done any major updating and all other reports have come up clean. Only recently has the box been crashing. Perhaps a fresh OS install might be in order from a fresh image.

I'm guessing I'll find firmware versions in the BIOS, so I'll have to wait until I can get direct access to the server tonight.

To be continued!

David3D · Apr 25, 2016

EDIT: Just found this thread: https://forums.freenas.org/index.php?threads/locking-up-periodically-and-gaps-in-report-info.16782/

I found it by searching for my motherboard in Google. A lot of what people are reporting in this thread is alarmingly similar to what I am experiencing. According to them, updates don't fix it. I shall continue reading...it's a long thread!

My SAS expander is apparently an Intel FMCSASX. Motherboard is an Intel S5000PSL. Neither of which I can find in the supported hardware list. Now I've got a feeling I won't be able to solve this problem.

I'll dig around for motherboard firmware and try and update it, then continue testing. If that fails, I'll try another USB drive and a fresh OS install.

David3D · May 25, 2016

Just an update. I know it's been a while, but it is hard troubleshooting a production server that other people need working during the day. The fact the problem pops up is over a few days does not help when troubleshooting.

I tried flashing the firmware on the HBA, but it continued crashing. At this point I decided to replace the boot thumb drive with a brand new thumb drive and upgraded to 9.10.

After a few "Oh crap" moments after uploading my previous settings, the OS is up and running and the server has been operating for about 15 hours now. The maximum the server ever ran for in its lifetime previous to the reinstall was 7 days, although it was quite rare, so when I hit 8 days, I'll be a happy man.

If it crashes after a few days, I'll be replacing SATA cables for giggles and after that, seeking out replacement parts and/or a different SAS controller.

Thanks for the help! I only spotted this through strange email behavior and random messages from workstations. Otherwise, I would never have known it was crashing all the time. I'll be checking the "uptime" graphs a lot closer from now on.

Fingers crossed!

David3D · May 31, 2016

For the purposes of closure and for community members with issues similar to mine, I can confirm that the issue was faulty boot media.

I upgraded the firmware on the HBA to no avail. I upgraded to 9.10 to no avail and finally, I decided to do a fresh install of 9.10 on a brand new thumb drive, then import my settings. I can report that the server has been up for a record 6 days and 21 hours without any gaps in graphs or sudden soft reboots.

I'll update again if anything changes. But for now, this is SOLVED! :) Can't work out how to change the title though.

Thanks to Eric for deciphering the logs for me and putting me on the right track.

Important Announcement for the TrueNAS Community.

Server Crash - Scary Output log after scrub

David3D

Explorer

Ericloewe

Server Wrangler

David3D

Explorer

Ericloewe

Server Wrangler

David3D

Explorer

Ericloewe

Server Wrangler

David3D

Explorer

Ericloewe

Server Wrangler

David3D

Explorer

Ericloewe

Server Wrangler

David3D

Explorer

David3D

Explorer

David3D

Explorer

David3D

Explorer

Similar threads

Important Announcement for the TrueNAS Community.

Server Crash - Scary Output log after scrub

Explorer

Server Wrangler

Explorer

Server Wrangler

Explorer

Server Wrangler

Explorer

Server Wrangler

Explorer

Server Wrangler

Explorer

Explorer

Explorer

Explorer

Important Announcement for the TrueNAS Community.

Related topics on forums.truenas.com for thread: "Server Crash - Scary Output log after scrub"

Similar threads