Drives drop out, then reconnect during zpool scrub

ajgnet

Explorer
Joined
Jun 16, 2020
Messages
65
Having a strange issue on my zpool. If I initiate a scrub, around 15% completion, a random drive drops out then reconnects and ZFS begins to resilver. I've tried replacing the drives with new ones, and swapped controllers, but the issue still occurs. I'm not sure what to do next? There are no reported smart errors.

Code:
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1678 loginfo 31110d00
mpr0: (da1:mpr0:0:1:0): READ(16). CDB: 88 00 00 00 00 05 ef e1 8e d0 00 00 00 60 00 00
Controller reported scsi ioc terminated tgt 1 SMID 1544 loginfo 31110d00
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1032 loginfo 31110d00
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1142 loginfo 31110d00
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1059 loginfo 31110d00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d4 c8 00 00 98 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d4 28 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d5 60 00 03 18 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
(da1:mpr0:0:1:0): READ(16). CDB: 88 00 00 00 00 05 ef e1 8a 80 00 00 00 98 00 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 488 loginfo 31170000
mpr0: (da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d4 28 00 00 a0 00
Controller reported scsi ioc terminated tgt 1 SMID 629 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1083 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1197 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 2056 loginfo 31170000
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d4 c8 00 00 98 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d5 60 00 03 18 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
(da1:mpr0:0:1:0): READ(16). CDB: 88 00 00 00 00 05 ef e1 8a 80 00 00 00 98 00 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
(da1:mpr0:0:1:0): READ(16). CDB: 88 00 00 00 00 05 ef e1 8e d0 00 00 00 60 00 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1112 loginfo 31170000
mpr0: (da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d4 28 00 00 a0 00
Controller reported scsi ioc terminated tgt 1 SMID 1326 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 802 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1629 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1410 loginfo 31170000
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d4 c8 00 00 98 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a 0e d5 60 00 03 18 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
(da1:mpr0:0:1:0): READ(16). CDB: 88 00 00 00 00 05 ef e1 8a 80 00 00 00 98 00 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
(da1:mpr0:0:1:0): READ(16). CDB: 88 00 00 00 00 05 ef e1 8e d0 00 00 00 60 00 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
mpr0: mprsas_prepare_remove: Sending reset for target ID 1
da1 at mpr0 bus 0 scbus9 target 1 lun 0
da1: <ATA ST16000NM001G-2K SN03>  s/n ZL297N6W detached
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1974 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 2030 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 535 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1678 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 237 loginfo 31130000 departing
mpr0: No pending commands: starting remove_device
mpr0: clearing target 1 handle 0x001c
mpr0: At enclosure level 0, slot 1, connector name (    )
mpr0: Unfreezing devq for target ID 1
(da1:mpr0:0:1:0): Periph destroyed
mpr0: Found device <881<SataDev,Direct>,End Device> <6.0Gbps> handle<0x001c> enclosureHandle<0x0001> slot 1
mpr0: At enclosure level 0 and connector name (    )
da1 at mpr0 bus 0 scbus9 target 1 lun 0
da1: <ATA ST16000NM001G-2K SN03> Fixed Direct Access SPC-4 SCSI device
da1: Serial Number ZL297N6W
da1: 600.000MB/s transfers
da1: Command Queueing enabled
da1: 15259648MB (31251759104 512 byte sectors)
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 959 loginfo 31110d00
mpr0: (da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 65 70 00 00 a0 00
Controller reported scsi ioc terminated tgt 1 SMID 510 loginfo 31110d00
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1722 loginfo 31110d00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 66 10 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 64 d0 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 3 more tries remain
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 930 loginfo 31170000
mpr0: (da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 64 d0 00 00 a0 00
Controller reported scsi ioc terminated tgt 1 SMID 280 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1330 loginfo 31170000
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 65 70 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 66 10 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 2 more tries remain
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1670 loginfo 31170000
mpr0: (da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 64 d0 00 00 a0 00
Controller reported scsi ioc terminated tgt 1 SMID 940 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1737 loginfo 31170000
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1334 loginfo 31170000
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 65 70 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
(da1:mpr0:0:1:0): READ(10). CDB: 28 00 2a aa 66 10 00 00 a0 00
(da1:mpr0:0:1:0): CAM status: CCB request completed with an error
(da1:mpr0:0:1:0): Retrying command, 1 more tries remain
mpr0: mprsas_prepare_remove: Sending reset for target ID 1
da1 at mpr0 bus 0 scbus9 target 1 lun 0
da1: <ATA ST16000NM001G-2K SN03>  s/n ZL297N6W detached
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 746 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 130 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1449 loginfo 31130000 departing
mpr0: Controller reported scsi ioc terminated tgt 1 SMID 1746 loginfo 31130000 departing
mpr0: No pending commands: starting remove_device
mpr0: clearing target 1 handle 0x001c
mpr0: At enclosure level 0, slot 1, connector name (    )
mpr0: Unfreezing devq for target ID 1
(da1:mpr0:0:1:0): Periph destroyed
mpr0: Found device <881<SataDev,Direct>,End Device> <6.0Gbps> handle<0x001c> enclosureHandle<0x0001> slot 1
mpr0: At enclosure level 0 and connector name (    )
da1 at mpr0 bus 0 scbus9 target 1 lun 0
da1: <ATA ST16000NM001G-2K SN03> Fixed Direct Access SPC-4 SCSI device
da1: Serial Number ZL297N6W
da1: 600.000MB/s transfers
da1: Command Queueing enabled
da1: 15259648MB (31251759104 512 byte sectors)



Code:
errors: No known data errors

  pool: terapool
 state: ONLINE
  scan: resilvered 216M in 00:01:25 with 0 errors on Sun Mar 14 13:03:36 2021
config:

    NAME                                            STATE     READ WRITE CKSUM
    terapool                                        ONLINE       0     0     0
      raidz2-0                                      ONLINE       0     0     0
        gptid/295eaade-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/330a28cd-ebce-dc4d-a68d-a373239c0ac5  ONLINE       0     0     0
        gptid/2290e748-ff5a-b54b-bb34-c306ea21e8fa  ONLINE       0     0     0
        gptid/141da3e0-ff50-4f9e-b0ff-156574350b3c  ONLINE       0     0     0
        gptid/2fe281ae-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/30621220-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/337369ad-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/482fcedf-0716-41b6-9c0b-d7e8db8444a5  ONLINE       0     0     0
        gptid/34487cb9-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/35beca93-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/3725335a-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/39d17383-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/3a55cf88-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/3ba25374-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/3c9a1e04-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
      raidz2-3                                      ONLINE       0     0     0
        gptid/d7a546dd-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/b6b95789-baac-4b33-b69f-8b5b01b60c5d  ONLINE       0     0     0
        gptid/de8f5473-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/dfa46229-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/dba95b5c-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e0e414d1-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e2f620e3-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e2e746c7-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e5c7d0c7-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e723e2e9-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e65e915e-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/e64f9c2d-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/ea523ac8-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/eade446f-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
        gptid/ec017d8a-eec5-11ea-93c6-3cecef6e7f4c  ONLINE       0     0     0
    special
      mirror-1                                      ONLINE       0     0     0
        gptid/54ce329f-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/54d84cd3-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
      mirror-2                                      ONLINE       0     0     0
        gptid/60c6091b-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
        gptid/60c06ae1-ebf1-11ea-994d-3cecef6bac28  ONLINE       0     0     0
    logs
      nvd4p1                                        ONLINE       0     0     0
    cache
      nvd5p1                                        ONLINE       0     0     0
      nvd3p1                                        ONLINE       0     0     0
      nvd6p1                                        ONLINE       0     0     0
      nvd2p1                                        ONLINE       0     0     0

errors: No known data errors


Here is smartctl -a /dev/da1 (the drive that dropped out)
Code:
root@teracube[/mnt/datapool/adam]# smartctl -a /dev/da1
smartctl 7.1 2019-12-30 r5022 [FreeBSD 12.2-RELEASE-p3 amd64] (local build)
Copyright (C) 2002-19, Bruce Allen, Christian Franke, www.smartmontools.org

=== START OF INFORMATION SECTION ===
Device Model:     ST16000NM001G-2KK103
Serial Number:    ZL297N6W
LU WWN Device Id: 5 000c50 0c7409f95
Firmware Version: SN03
User Capacity:    16,000,900,661,248 bytes [16.0 TB]
Sector Sizes:     512 bytes logical, 4096 bytes physical
Rotation Rate:    7200 rpm
Form Factor:      3.5 inches
Device is:        Not in smartctl database [for details use: -P showall]
ATA Version is:   ACS-4 (minor revision not indicated)
SATA Version is:  SATA 3.3, 6.0 Gb/s (current: 6.0 Gb/s)
Local Time is:    Sun Mar 14 13:08:53 2021 EDT
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:         (  567) 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:      (1456) minutes.
Conveyance self-test routine
recommended polling time:      (   2) 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: 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   081   064   044    Pre-fail  Always       -       118866536
  3 Spin_Up_Time            0x0003   094   093   000    Pre-fail  Always       -       0
  4 Start_Stop_Count        0x0032   100   100   020    Old_age   Always       -       11
  5 Reallocated_Sector_Ct   0x0033   100   100   010    Pre-fail  Always       -       0
  7 Seek_Error_Rate         0x000f   083   060   045    Pre-fail  Always       -       193090397
  9 Power_On_Hours          0x0032   099   099   000    Old_age   Always       -       1680
 10 Spin_Retry_Count        0x0013   100   100   097    Pre-fail  Always       -       0
 12 Power_Cycle_Count       0x0032   100   100   020    Old_age   Always       -       11
 18 Unknown_Attribute       0x000b   100   100   050    Pre-fail  Always       -       0
187 Reported_Uncorrect      0x0032   100   100   000    Old_age   Always       -       0
188 Command_Timeout         0x0032   100   099   000    Old_age   Always       -       1
190 Airflow_Temperature_Cel 0x0022   058   051   040    Old_age   Always       -       42 (Min/Max 42/43)
192 Power-Off_Retract_Count 0x0032   100   100   000    Old_age   Always       -       10
193 Load_Cycle_Count        0x0032   100   100   000    Old_age   Always       -       98
194 Temperature_Celsius     0x0022   042   049   000    Old_age   Always       -       42 (0 22 0 0 0)
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
200 Multi_Zone_Error_Rate   0x0023   100   100   001    Pre-fail  Always       -       0
240 Head_Flying_Hours       0x0000   100   253   000    Old_age   Offline      -       1675 (47 5 0)
241 Total_LBAs_Written      0x0000   100   253   000    Old_age   Offline      -       49014624841
242 Total_LBAs_Read         0x0000   100   253   000    Old_age   Offline      -       327664310264

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  Short offline       Completed without error       00%      1669         -
# 2  Short offline       Completed without error       00%      1644         -
# 3  Short offline       Completed without error       00%      1621         -
# 4  Short offline       Completed without error       00%      1597         -
# 5  Short offline       Completed without error       00%      1573         -
# 6  Short offline       Completed without error       00%      1549         -

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.
 

SweetAndLow

Sweet'NASty
Joined
Nov 6, 2013
Messages
6,421
Hardware info? Read the forum rules before posting this help create a post that is worth while and you will get better help.

I'm interested in what power supply you have and if you're pulling to much power.
 

ajgnet

Explorer
Joined
Jun 16, 2020
Messages
65
Hardware info? I'm interested in what power supply you have and if you're pulling to much power.
Motherboard: SuperMicro X11SPL
RAM: 512 GB ECC
HDDs: 30x 16TB SEAGATE EXOS, 7200rpm SATA
4x 4GB Samsung 860 SSDs
4x 8TB Sabrent NVMEs
Controllers: 2x LSI 9305-24i
Configuration: 2x 15-wide RAIDz2, 4x SSDs in metadata vdev raidz mirror, 4x 8TB NVMEs for cache
Network Card: Intel X540 10G
Power Supply: 850W ATX Standard Supply
 

SweetAndLow

Sweet'NASty
Joined
Nov 6, 2013
Messages
6,421
Motherboard: SuperMicro X11SPL
RAM: 512 GB ECC
HDDs: 30x 16TB SEAGATE EXOS, 7200rpm SATA
4x 4GB Samsung 860 SSDs
4x 8TB Sabrent NVMEs
Controllers: 2x LSI 9305-24i
Configuration: 2x 15-wide RAIDz2, 4x SSDs in metadata vdev raidz mirror, 4x 8TB NVMEs for cache
Network Card: Intel X540 10G
Power Supply: 850W ATX Standard Supply
Since you seemed to have tried everything else. My guess is going to be power supply. For comparison my 24 disks system using 5400rpm drives has dual 1200w power supplies.
 

ajgnet

Explorer
Joined
Jun 16, 2020
Messages
65
After a lot of head aches, I resolved this issue. It was the firmware running on one of my Seagate Exos 16TB drives (ST16000NM001G-2KK103). Several of the drives were running SN02 firmware and needed to be updated to SN03. After updating all drives using the Seagate utility, the scrub completed.

I tried replacing the HBAs (tried Adaptec & LSI), changing around the firmware on the HBAs, swapping the backplane, swapping the power supply, swapping the cables .. nothing worked. Then I tried updating the firmware on the drives.
 
Top