Ok, I have been fighting this Brightstor/Compaq problem for months now and am about ready to quit my job and work for 7-11. Here are the details:
Server: Compaq DL-380 G2
Library: Compaq StorageWorks MSL5026 w/ 2 drives
Media: Maxell and Quantum 110/220 SDLT-1
OS: Windows 2000 SP4 all patches applied
Backup Software: Brightstor 9 with all patches and device support updates applied
Library firmware is 423, drive firmware is 75, the latest.
I have contacted CA and HP on this matter and each points to the other as the problem so they are useless. What happens is the classic media errors, which occur at random across old and new tapes. Sometimes they happen, others they don't, even on the same tapes. EC's are UNRECOVERABLE DATA ERROR, MEDIA MISCOMPARE, HARDWARE ERROR, CONTROLLER FAILURE, etc. Half of my backups are passing every day, the other half fail every day. The backups passing have hundred of GB's backed up, which indicates to me the drives are working. Any of the backups can fail, not just the same ones, so it doesn't seem to care what magazine each tape is in. Here is a recent tape log, while I as attempting an erase:
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:05 ABSL:2020 SCSICommandRetry: NT Error that can be retried. CMD:[10], Error 1117
[2632] ABSL:2020 NT Error that can be retried. CMD:16 Retry count 0
[2632] ABSL:2020 NT Error 1117 - The request could not be performed because of an I/O device error.
[2632] Retry[1] for ABSL[2020] Command 10
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2536] ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2536] _ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2536] ClientGetGroupStatus: ownerName = SYSTEM
[2536] CheckGroupStatus(): No group name given
[2448] Calling DisConnectFromTape()
[2448] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] ABSL:2020 CMD:10 Returning status[2]
[2632] 03/18 13:04:08 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <WRITE FILEMARK >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 06 00 00 00 00 16 00 00 00 00 29
[2632] EX SENSE ABSL:2020 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Unit Attention [06]
[2632] EX SENSE ABSL:2020 Diagnostic Failure On Component ASCQ: NN (80H-FFH) [29, 04]
[2632] Unit Attention occurred during Write Filemark
[2632] ABSL:2020 (SCSI WRITE FILE MARK)
[2632] (06) *** Unit Attention ***
[2632] UNKNOWN ERROR (29 04)
[2632] SEVERITY UNKNOWN
[2632] LDN:3 Long Erase Failed
[2632] 03/18 13:04:08 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:08 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:18 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:18 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:28 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:28 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2536] Total Jobs ACTIVE 5
[2536] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2448] ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2448] _ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2448] ClientGetGroupStatus: ownerName = SYSTEM
[2448] CheckGroupStatus(): No group name given
[568] Calling DisConnectFromTape()
[568] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:38 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:38 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[1316] Total Jobs ACTIVE 5
[1316] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2536] ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2536] _ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2536] ClientGetGroupStatus: ownerName = SYSTEM
[2536] CheckGroupStatus(): No group name given
[2448] Calling DisConnectFromTape()
[2448] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:48 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:48 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:58 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:58 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:08 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:08 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2536] Total Jobs ACTIVE 5
[2536] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2448] ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2448] _ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2448] ClientGetGroupStatus: ownerName = SYSTEM
[2448] CheckGroupStatus(): No group name given
[568] Calling DisConnectFromTape()
[568] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:18 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:18 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[1316] Total Jobs ACTIVE 5
[1316] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2536] ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2536] _ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2536] ClientGetGroupStatus: ownerName = SYSTEM
[2536] CheckGroupStatus(): No group name given
[2448] Calling DisConnectFromTape()
[2448] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:28 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:28 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:38 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:38 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:48 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:48 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] ...in ChgReturnTapeToSlot
[2632] LDN:3 Returning tape to Slot :23.
[2632] ...in ChgDriveElementStatus
[2632] ...in ChgReadElementStatus
[2632] LDN:1 Read Elem Stat:Starting Element [481] Buffer size [128] # of Element [1]
[2632] 03/18 13:05:48 ABSL:2000 [READ ELEMENT STAT ] b8 04 01 e1 00 01 00 00 00 80
[2632] ABSL:2000 CMD:b8 Returning status[2]
[2632] 03/18 13:05:48 ABSL:2000 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 80
[2632] <READ ELEMENT STAT >, Sense Data as Follows:
[2632] SENSE ABSL:2000 70 00 06 00 00 00 00 14 00 00 00 00 29
[2632] EX SENSE ABSL:2000 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2000 Unit Attention [06]
[2632] EX SENSE ABSL:2000 Log Parameter Changed [29, 02]
[2632] ABSL:2000 (SCSI READ ELEMENT STATUS)
[2632] (06) *** Unit Attention ***
[2632] UNKNOWN ERROR (29 02)
[2632] SEVERITY UNKNOWN
[2632] ...in ChgMoveMedium
[2632] LDN:1 ChgMoveMedium(), from address:481 to address:55 Flip:0 FM:0.
[2632] LDN:1 Moving Tape from Library Drive LDN:3 to Slot:23
[2632] LDN:1 Leaving tape in drive, from slot address:55
[2632] ...in SaveChangerConfiguration
[2632] Successfully Flushed data to Changer Config file.
[2632] Successfully Flushed data to Changer Config file.
[2632] Successfully Flushed data to Changer Config file.
[2632] Successfully Flushed data to Changer Config file.
[1544] LDN:3 Already erased tape in drive, skipping.
[1544] ...in WaitForAllThreads
[1544] ...in FormatLockUnLockAllSlotGroups
[1544] ClientGetNumberOfGroups: Invalid job handle passed in !
[1544] ...in ChgGetOriginalHomeSlot
[1544] LDN:1 ChgOriginalHomeSlot(): LDN:3
[1544] LDN:1 ChgOriginalHomeSlot(): offset in TID:1 LDN:3 homeSlot:23 Invert:0
[1544] ClientGetNumberOfGroups: Invalid job handle passed in !
[1544] ...in ChgReturnChangerDrive
[1544] LDN:1 Return Library Drive Associated Group:[]
[1544] LDN:1 Return Library Drive Set drive LDN:3 with Group:[]
[1544] LDN:1 Unlock LDN:3 Total number of free drives 2
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[568] CheckIfAChangerDriveFree() tapeName:[*] RandomID:[0000] Seq:[0]
[568] CheckIfAChangerDriveFree: Free Drive:2 Reserved Drive:0
[568] CheckIfAChangerDriveFree: Have at least one Free Drive, Free Drive:2 Reserved Drive:0
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[1072] Total Jobs ACTIVE 5
[1072] CREATEJOBHANDLE [00CBEFE4]: JobType - 8, ServerType - Remote, Client - ASMGR@TABLET1
[1072] Total Jobs ACTIVE 6
[1072] CREATEJOBHANDLE [00CBF308]: JobType - 8, ServerType - Remote, Client - ASMGR@W103534
[1544] Calling DisConnectFromTape()
[1544] DestroyHandle [00CBF308]: Active Jobs 5
[1544] Calling DisConnectFromTape()
[1544] DestroyHandle [00CBEFE4]: Active Jobs 4
I have disabled the Remote Storage Service, disabled the tape drive and library in device manager per other suggestions here, tried new media, applied all patches, changed SCSI cable and host adapter, performed diags on the library, all to no avail. What sounds like the culprit here?
Server: Compaq DL-380 G2
Library: Compaq StorageWorks MSL5026 w/ 2 drives
Media: Maxell and Quantum 110/220 SDLT-1
OS: Windows 2000 SP4 all patches applied
Backup Software: Brightstor 9 with all patches and device support updates applied
Library firmware is 423, drive firmware is 75, the latest.
I have contacted CA and HP on this matter and each points to the other as the problem so they are useless. What happens is the classic media errors, which occur at random across old and new tapes. Sometimes they happen, others they don't, even on the same tapes. EC's are UNRECOVERABLE DATA ERROR, MEDIA MISCOMPARE, HARDWARE ERROR, CONTROLLER FAILURE, etc. Half of my backups are passing every day, the other half fail every day. The backups passing have hundred of GB's backed up, which indicates to me the drives are working. Any of the backups can fail, not just the same ones, so it doesn't seem to care what magazine each tape is in. Here is a recent tape log, while I as attempting an erase:
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:05 ABSL:2020 SCSICommandRetry: NT Error that can be retried. CMD:[10], Error 1117
[2632] ABSL:2020 NT Error that can be retried. CMD:16 Retry count 0
[2632] ABSL:2020 NT Error 1117 - The request could not be performed because of an I/O device error.
[2632] Retry[1] for ABSL[2020] Command 10
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2536] ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2536] _ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2536] ClientGetGroupStatus: ownerName = SYSTEM
[2536] CheckGroupStatus(): No group name given
[2448] Calling DisConnectFromTape()
[2448] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] ABSL:2020 CMD:10 Returning status[2]
[2632] 03/18 13:04:08 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <WRITE FILEMARK >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 06 00 00 00 00 16 00 00 00 00 29
[2632] EX SENSE ABSL:2020 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Unit Attention [06]
[2632] EX SENSE ABSL:2020 Diagnostic Failure On Component ASCQ: NN (80H-FFH) [29, 04]
[2632] Unit Attention occurred during Write Filemark
[2632] ABSL:2020 (SCSI WRITE FILE MARK)
[2632] (06) *** Unit Attention ***
[2632] UNKNOWN ERROR (29 04)
[2632] SEVERITY UNKNOWN
[2632] LDN:3 Long Erase Failed
[2632] 03/18 13:04:08 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:08 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:18 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:18 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:28 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:28 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2536] Total Jobs ACTIVE 5
[2536] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2448] ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2448] _ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2448] ClientGetGroupStatus: ownerName = SYSTEM
[2448] CheckGroupStatus(): No group name given
[568] Calling DisConnectFromTape()
[568] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:38 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:38 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[1316] Total Jobs ACTIVE 5
[1316] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2536] ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2536] _ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2536] ClientGetGroupStatus: ownerName = SYSTEM
[2536] CheckGroupStatus(): No group name given
[2448] Calling DisConnectFromTape()
[2448] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:48 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:48 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:04:58 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:04:58 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:08 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:08 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2536] Total Jobs ACTIVE 5
[2536] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2448] ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2448] _ClientGetGroupStatus() is called.
[2448] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2448] ClientGetGroupStatus: ownerName = SYSTEM
[2448] CheckGroupStatus(): No group name given
[568] Calling DisConnectFromTape()
[568] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:18 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:18 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[1316] Total Jobs ACTIVE 5
[1316] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[2536] ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[2536] _ClientGetGroupStatus() is called.
[2536] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[2536] ClientGetGroupStatus: ownerName = SYSTEM
[2536] CheckGroupStatus(): No group name given
[2448] Calling DisConnectFromTape()
[2448] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:28 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:28 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[568] Total Jobs ACTIVE 5
[568] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[1316] ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[1316] _ClientGetGroupStatus() is called.
[1316] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[1316] ClientGetGroupStatus: ownerName = SYSTEM
[1316] CheckGroupStatus(): No group name given
[2536] Calling DisConnectFromTape()
[2536] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:38 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:38 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] LDN:3 TapeRewind() error, attempting to rewind again.
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[2632] 03/18 13:05:48 ABSL:2020 [REWIND ] 01 00 00 00 00 00 00 00 00 00
[2632] ABSL:2020 CMD:1 Returning status[2]
[2632] 03/18 13:05:48 ABSL:2020 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
[2632] <REWIND >, Sense Data as Follows:
[2632] SENSE ABSL:2020 70 00 02 00 00 00 00 16 00 00 00 00 04
[2632] EX SENSE ABSL:2020 01 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2020 Not Ready [02]
[2632] EX SENSE ABSL:2020 Logical Unit Is In Process Of Becoming Ready [04, 01]
[2632] ...in ChgReturnTapeToSlot
[2632] LDN:3 Returning tape to Slot :23.
[2632] ...in ChgDriveElementStatus
[2632] ...in ChgReadElementStatus
[2632] LDN:1 Read Elem Stat:Starting Element [481] Buffer size [128] # of Element [1]
[2632] 03/18 13:05:48 ABSL:2000 [READ ELEMENT STAT ] b8 04 01 e1 00 01 00 00 00 80
[2632] ABSL:2000 CMD:b8 Returning status[2]
[2632] 03/18 13:05:48 ABSL:2000 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 80
[2632] <READ ELEMENT STAT >, Sense Data as Follows:
[2632] SENSE ABSL:2000 70 00 06 00 00 00 00 14 00 00 00 00 29
[2632] EX SENSE ABSL:2000 02 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[2632] SENSE ABSL:2000 Unit Attention [06]
[2632] EX SENSE ABSL:2000 Log Parameter Changed [29, 02]
[2632] ABSL:2000 (SCSI READ ELEMENT STATUS)
[2632] (06) *** Unit Attention ***
[2632] UNKNOWN ERROR (29 02)
[2632] SEVERITY UNKNOWN
[2632] ...in ChgMoveMedium
[2632] LDN:1 ChgMoveMedium(), from address:481 to address:55 Flip:0 FM:0.
[2632] LDN:1 Moving Tape from Library Drive LDN:3 to Slot:23
[2632] LDN:1 Leaving tape in drive, from slot address:55
[2632] ...in SaveChangerConfiguration
[2632] Successfully Flushed data to Changer Config file.
[2632] Successfully Flushed data to Changer Config file.
[2632] Successfully Flushed data to Changer Config file.
[2632] Successfully Flushed data to Changer Config file.
[1544] LDN:3 Already erased tape in drive, skipping.
[1544] ...in WaitForAllThreads
[1544] ...in FormatLockUnLockAllSlotGroups
[1544] ClientGetNumberOfGroups: Invalid job handle passed in !
[1544] ...in ChgGetOriginalHomeSlot
[1544] LDN:1 ChgOriginalHomeSlot(): LDN:3
[1544] LDN:1 ChgOriginalHomeSlot(): offset in TID:1 LDN:3 homeSlot:23 Invert:0
[1544] ClientGetNumberOfGroups: Invalid job handle passed in !
[1544] ...in ChgReturnChangerDrive
[1544] LDN:1 Return Library Drive Associated Group:[]
[1544] LDN:1 Return Library Drive Set drive LDN:3 with Group:[]
[1544] LDN:1 Unlock LDN:3 Total number of free drives 2
[2448] Total Jobs ACTIVE 5
[2448] CREATEJOBHANDLE [00CBEFE4]: JobType - 2, ServerType - Local, Client - SYSTEM
[568] ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus:gbPrimaryServer == FALSE. call _ClientGetGroupStatus()
[568] _ClientGetGroupStatus() is called.
[568] ClientGetGroupStatus JobType:45 tapeName:[*] RID:[0000] Seq:[0] Group:[]
[568] ClientGetGroupStatus: ownerName = SYSTEM
[568] CheckGroupStatus(): No group name given
[568] CheckIfAChangerDriveFree() tapeName:[*] RandomID:[0000] Seq:[0]
[568] CheckIfAChangerDriveFree: Free Drive:2 Reserved Drive:0
[568] CheckIfAChangerDriveFree: Have at least one Free Drive, Free Drive:2 Reserved Drive:0
[1316] Calling DisConnectFromTape()
[1316] DestroyHandle [00CBEFE4]: Active Jobs 4
[1072] Total Jobs ACTIVE 5
[1072] CREATEJOBHANDLE [00CBEFE4]: JobType - 8, ServerType - Remote, Client - ASMGR@TABLET1
[1072] Total Jobs ACTIVE 6
[1072] CREATEJOBHANDLE [00CBF308]: JobType - 8, ServerType - Remote, Client - ASMGR@W103534
[1544] Calling DisConnectFromTape()
[1544] DestroyHandle [00CBF308]: Active Jobs 5
[1544] Calling DisConnectFromTape()
[1544] DestroyHandle [00CBEFE4]: Active Jobs 4
I have disabled the Remote Storage Service, disabled the tape drive and library in device manager per other suggestions here, tried new media, applied all patches, changed SCSI cable and host adapter, performed diags on the library, all to no avail. What sounds like the culprit here?