Arcserve v11 with service pack

[michaelcoulter]

Backup keeps failing after about 4%

using v11 with sp1 on a win2k server with sp4 to a Compaq SuperDLT1 firmware 4b4b

Error is :

E6304 Unexpected SCSI Sense Code ==> JobID[42], Device[4], ABSL[5 0 6 0], SN[PMC43Y0128 ], CMD[ah], [Synchronous Data Transfer Error], ASC[1b], ASCQ[00]

followed by :

E3719 Unable to write to media. (MEDIA=26/11/04 12:36 [S/N: ], EC=COMMAND ABORTED)

Tape log :

11/26 12:38:00 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:00 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:00 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:01 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:01 [0d94] =>ABSL:5060 <WRITE> - Returning status[2]
11/26 12:38:01 [0d94] Sense Data allready returned.
11/26 12:38:01 [0d94] =>ABSL:5060 [REQUEST SENSE ] 03 00 00 00 40 00 00 00 00 00
11/26 12:38:01 [0d94] <WRITE >, Sense Data as Follows:
11/26 12:38:01 [0d94] SENSE ABSL:5060 70 00 0b 00 00 00 00 16 00 00 07 84 1b
11/26 12:38:01 [0d94] EX SENSE ABSL:5060 00 00 00 00 00 00 01 ba 00 00 22 f4 01 98 4c e0
11/26 12:38:01 [0d94] SENSE ABSL:5060 Command Aborted [0b]
11/26 12:38:01 [0d94] EX SENSE ABSL:5060 Synchronous Data Transfer Error [1b, 00]
11/26 12:38:01 [0d94] =>ABSL:5060 WriteDaemon thread suspended. DaemonStatus[ad]
11/26 12:38:01 [0404] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:01 [0c88] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:01 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0404] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0c88] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0404] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0c88] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0404] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0c88] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0c10] GetFreeBuffers : Req [32], Allocated [32]
11/26 12:38:02 [0404] GetFreeBuffers : Req [32], Allocated [4]
11/26 12:38:07 [0c88] SENSE ABSL:5060 70 00 00 00 00 00 00 16 00 00 00 00 00
11/26 12:38:07 [0c88] EX SENSE ABSL:5060 00 00 00 00 00 00 01 ba 00 00 22 f4 01 98 4c e0
11/26 12:38:07 [0c88] DRV:4 Tape Remaining Capacity :107033472 KB
11/26 12:38:07 [0c10] =>ABSL:5060 [LOG SENSE ] 4d 00 72 00 00 00 00 00 58 00 tt[5]
11/26 12:38:07 [0c10] DRV:4 ClientGetCompressionRatio: 2.060000
11/26 12:38:07 [0404] ...in DisConnectFromTape for JobID[42]
11/26 12:38:07 [0404] DRV:[4] DisConnectFromTape leaveTapeAtEnd[0]
11/26 12:38:07 [0404] DRV:4 [COMPAQ SuperDLT1 4B4B] Log Sense Not Supported.
11/26 12:38:07 [0404] Drive[4] Usage Time for this job 00000079
11/26 12:38:07 [0404] DB: DBUpdateTape: Tapename[26/11/04 12:36]
11/26 12:38:07 [0404] DB: Updating tape info in database.
11/26 12:38:07 [0404] DB: Updating tapeinfo in database[00000001]: START
11/26 12:38:07 [0404] DB: IMPERSONATE
11/26 12:38:07 [0404] DB: REVERT
11/26 12:38:07 [0404] DB: Finished Updating tapeinfo in database: END
11/26 12:38:07 [0404] Accumulative Drive[4] Usage reported by DB 00000079
11/26 12:38:07 [0404] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:07 [0404] ClientDisConnectFromTape: Skipping Drive Operations because Device not Ready... EC=0
11/26 12:38:07 [0c88] -- GetXCB called with hJob[01191B60] JobID[42] Type[9250] CBNo[0] Data[00146F50] --
11/26 12:38:07 [0c88] GetXCB 9250: Device[0] not a changer...
11/26 12:38:07 [0404] GRP: ...in DisConnectFromGroup for JobID[42]
11/26 12:38:07 [0404] GRP: Calling ClientDisConnectFromTape for JobID[42] Group[GROUP0]
11/26 12:38:07 [0404] ...in DisConnectFromTape for JobID[42]
11/26 12:38:07 [0404] GRP: ...in UnLockGroupEnum for JobID[42] Group[GROUP0], but Job does NOT have Lock!
11/26 12:38:07 [0404] Attempting to UnLock device 4
11/26 12:38:07 [0404] Successfully UnLocked device 4
11/26 12:38:07 [0404] GetGCBHandleFromGroupName(): GroupName [GROUP0]
11/26 12:38:07 [0404] GRP: UnLocking Group [GROUP0]
11/26 12:38:07 [0c88] DestroyJobHandle[01191B60]
11/26 12:38:07 [0c88] Ending Session in DestroyJobHandle for JobID[42]
11/26 12:38:07 [0d94] =>ABSL:5060 WriteDaemon thread resumed.
11/26 12:38:07 [0c88] ...in DisConnectFromTape for JobID[42]
11/26 12:38:07 [0c88] Destroy JobID[42]: Active Jobs now: [4] -- jID[42], mID[0], jTKN[0], dcb[4]
11/26 12:38:08 [043c] Successfully Locked device 4
11/26 12:38:08 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:08 [043c] Attempting to UnLock device 4
11/26 12:38:08 [043c] Successfully UnLocked device 4
11/26 12:38:14 [043c] Successfully Locked device 4
11/26 12:38:14 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:14 [043c] Attempting to UnLock device 4
11/26 12:38:14 [043c] Successfully UnLocked device 4
11/26 12:38:20 [043c] Successfully Locked device 4
11/26 12:38:20 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:20 [043c] Attempting to UnLock device 4
11/26 12:38:20 [043c] Successfully UnLocked device 4
11/26 12:38:26 [043c] Successfully Locked device 4
11/26 12:38:26 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:26 [043c] Attempting to UnLock device 4
11/26 12:38:26 [043c] Successfully UnLocked device 4
11/26 12:38:32 [043c] Successfully Locked device 4
11/26 12:38:32 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:32 [043c] Attempting to UnLock device 4
11/26 12:38:32 [043c] Successfully UnLocked device 4
11/26 12:38:38 [043c] Successfully Locked device 4
11/26 12:38:38 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:38 [043c] Attempting to UnLock device 4
11/26 12:38:38 [043c] Successfully UnLocked device 4
11/26 12:38:44 [043c] Successfully Locked device 4
11/26 12:38:44 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:44 [043c] Attempting to UnLock device 4
11/26 12:38:44 [043c] Successfully UnLocked device 4
11/26 12:38:50 [043c] Successfully Locked device 4
11/26 12:38:50 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:50 [043c] Attempting to UnLock device 4
11/26 12:38:50 [043c] Successfully UnLocked device 4
11/26 12:38:56 [043c] Successfully Locked device 4
11/26 12:38:56 [043c] =>ABSL:5060 [TEST UNIT READY ] 00 00 00 00 00 00 00 00 58 00 tt[5]
11/26 12:38:56 [043c] Attempting to UnLock device 4
11/26 12:38:56 [043c] Successfully UnLocked device 4
11/26 12:39:02 [043c] Successfully Locked device 4

Tape drive has been replaced 5 times so far - No Windows drivers installed for tape device and brand new media used (straight out of packet)

any help would be apreciated

thanks
in advance

 

[davidmichel]

That's a new one on me. First off I'd say to check the SCSI controller configuration but Compaq in their great wisdom does not let the user make configuration changes to their SCSI controlers.

Ok OS Tape Device driver is out, but how about the Compaq Storage Agent? It can also cause a problem.

Check Control Panel, look for Compaq Management Agents. When open there will be a right and left window. SCSI Information should be listed in the right side, which means it will not be collected.

Install the 11.1 update along with the latest Tape Engine update.

If there are any other devices on the bus try putting it on it's own.

 

[Notleks]

Sorry to append to your thread but I am having exactly the same issue and error messages. At least yours gets to 4%, mine stops at 1%.

HP DL380 G4 W2K SP4 Brightstor r11.1 build 3060 backing up to an HP Ultrium 460 drive.

I have disabled the device in device manager and restarted server.

I am in the process of removing scsi devices from the list of active management agents.

Will let you know how I get on.