Solved

Brightstor 11.5 hangs when accessing tape

Posted on 2006-06-30
9
3,819 Views
Last Modified: 2007-11-27
Hi Experts - hope you can help me recover a single folder from SDLT II tape.  We stopped using Brightstor 11.5 because we were moving to an enterprise (Legato Networker) solution.  Anyway, a user accidently erased a folder on a server share which might be saved to one of the old SDLT II tapes previously backed up by Brightstor.  The original Windows 2003 Std server is now hooked up to the Networker backup system and the original Brightstor software has been removed along with the SDLT tape drive.  Here is my problem: I took a little bit older server with the proper SCSI connection on it and installed the old tape drive on it along with Brightstor 11.5 for Windows (licensed).  Brightstor works fine until I try to access the tape.  Brightstor itself is hung, not the server.  I tried a solution posted by Expert "papushka" on Nov. 30, 2005 for a similar problem, but it didn't work for me.  The CA website hasn't been any help and our support has expired.  If anyone has any hints, I'd appreciate some input.  Also, we couldn't keep the old tape drive on the original server because the Networker software conflicted with either the Brightstor software of the tape drive.  So, putting it back on to try to recover this one folder might not fly with our IS Manager.
0
Comment
Question by:alendozo
  • 4
  • 4
9 Comments
 
LVL 30

Expert Comment

by:callrs
ID: 17022120
Wow. Helps to divide your thoughts into paragraphs ;)
And, if you can, give link to papushka's solution so we don't recommend the same thing.
0
 
LVL 6

Expert Comment

by:kbens0n
ID: 17023739
Does NOT sound good... if ALL tape operations seem fine (backup/compare/restore) with any -other- cartridge and this server configuration.

What error(s) are thrown up in the server's Application event log? What is shown in ARCserve's "tape.log" when this problem occurs? The activity log would probably show nothing, since tape operations don't commence... do they?

If ONLY this tape has that symptom it may be questionable as to whether orignal backup was pristine. Elsewise, the tape might have been overwritten or corrupted (magnetically/physically).
0
 

Author Comment

by:alendozo
ID: 17033449
Sorry to all Experts for the extreme run-on paragraph, but I was stressing quite a bit at the time.  I'll do better next time.

Here is 'papushka's' solution:
"You could try the below and see if this stops arcserve from freezing

1)Stop all the arcserve services (Run Cstop.bat from the x:\Program files\CA\Brightstror Arcserve Backup )
2)Under the brightstor Arcserve Backup folder you will have a Temp folder, delete the files from there.
3) Also under the folder you will have a DATABASE folder, delete the 'r' files from that folder
4) start the services by running the cstart.bat from the abobe folder"

The Application Event logs are pretty vanilla, with reference to Source: Application Hang, Event ID: 1002 and this as the description: "Hanging application BrightStorMgr.exe, version 11.5.3884.0, hang module hungapp, version 0.0.0.0, hang address 0x00000000."

I'll look at the "tape.log" more carefully now that I'm not being pulled in 20 different directions at once, for a change.  I'll also try to use a new SDLT II tape and create a test backup, then I'll attempt a restore.
0
 

Author Comment

by:alendozo
ID: 17034103
I tried using a fresh tape to create a test backup.  The first thing I did was format the tape.  It hung but eventually ran the job.  Below is the part of "Tape.log" concerning today's tasks (sorry that it is so long):

2006/07/03 15:02:36 [1354]         DRV:1 Got SCSI Unit Attention, Go test drive again with Unit Attention
2006/07/03 15:02:36 [1354] DB:: DB_GetEntryPoints:: pfASDBConnectToDBServer failed...Disconnect error = 0x1
2006/07/03 15:02:36 [1354] DB: Disconnected from Database Server.
2006/07/03 15:02:36 [1354] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/03 15:24:24 [1354] DB: Database Library not ready.
2006/07/03 15:24:24 [1354] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/03 15:24:51 [0704] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/03 15:27:14 [175c] USE DEFAULT PATH = C:\Program Files\CA\BrightStor ARCserve Backup\LOG FOR LOGGING
2006/07/03 15:27:14 [175c]
2006/07/03 15:27:14 [175c] ============ TAPE ENGINE SUMMARY LOG ====================
2006/07/03 15:27:14 [175c] Product Info: [CA Inc.] [BrightStor ARCserve Backup] Ver[11.5]
2006/07/03 15:27:14 [175c] Tapeeng Info: Date[Aug  9 2005] Time[15:37:15]
2006/07/03 15:27:14 [175c] Debug Info  : Level[1] Read/Write/TestUnitReady Logging[OFF]
2006/07/03 15:27:14 [175c] =========================================================
2006/07/03 15:27:14 [175c] Client Set DebugLevel: 1
2006/07/03 15:27:14 [175c] FindTapeDriveCategory: inquirydata->VendorID: QUANTUM SDLT600         2323
2006/07/03 15:27:14 [175c] Loading Changer.dll: Date[Aug  9 2005] Time[13:43:10]
2006/07/03 15:27:16 [175c] TapeDrive[1] ABSL:2050 <SDLT600         2323> S/N[RB0451AMC05123  ]
2006/07/03 15:27:16 [175c] ABSL = 0 0 1 0, adapterTransferSize = 131072
2006/07/03 15:27:16 [175c] ABSL = 2 0 5 0, adapterTransferSize = 1044480
2006/07/03 15:27:16 [175c] ABSL = 4 0 0 0, adapterTransferSize = 69632
2006/07/03 15:27:16 [175c] ABSL = 4 0 1 0, adapterTransferSize = 69632
2006/07/03 15:27:16 [175c] ABSL = 4 1 0 0, adapterTransferSize = 69632
2006/07/03 15:27:21 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:26 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:31 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:36 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:41 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:46 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:51 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:27:56 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:01 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:06 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:11 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:16 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:21 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:26 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:31 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:36 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:41 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:46 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:51 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:28:56 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:29:01 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:29:06 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:29:11 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:29:16 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/03 15:29:21 [175c] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.

If you need any more info, I'll do my best to dig it up for you.
0
Enabling OSINT in Activity Based Intelligence

Activity based intelligence (ABI) requires access to all available sources of data. Recorded Future allows analysts to observe structured data on the open, deep, and dark web.

 
LVL 6

Expert Comment

by:kbens0n
ID: 17034377
Check the BrightStor log directory for exception logs (\Program Files\Computer Associates\ARCserve\log\excptnxx.log). The first few lines of each will show the Date/Time it occurred and the associated STACK Trace info.
Maybe that will show some clarity to your problems.

Unfortunately the debug level was set at the "summary" level  for "tape.log" and there's really no details to analyze
See this line (above):
( Debug Info  : Level[1] Read/Write/TestUnitReady Logging[OFF] )

You will want to set this level higher:
Open ARCserve Server Admin found in the ARCserve Program Group
-Select <Admin/Configuration>
-Select the <Tape Engine> tab
-Stop the Tape Engine
-Wait for the Tape Engine to finish shutting down
-Set Message Level to <Detail>
-Start the Tape Engine
-Wait for the Tape Engine to finish initializing
-Go back and re-attempt any failing operation(s).

Even more detail can be achieved, but this involves editing the registry (Registry changes require reboot to take effect):
-Run REGEDT32.EXE to set the debug level:
-Look Under HKEY_LOCAL_MACHINE\SOFTWARE\Cheyenne\ARCserve\CurrentVersion\TapeEngine\Debug\ (may have changed to BrightStor)
-Highlight key named <Level> of type REG_DWORD value is 1. (fshown in the right-hand pane).
-From the Edit menu, select DWORD.
-Leave Base at Hex, and below DATA, enter one of the following values:.
   1 is the summary (Summary)
   2 is the normal debug (Detail)
  10 is the highest (This mode will record -ALL- information... -EVEN- communication between engines)
-Go back and re-attempt any failing operation(s).
(Using the higher modes will log increasing amounts of detail to the log. Make sure to have enough disk space on the volume)
-Re-run REGEDT32.EXE to set the debug level back, when finished.

 
0
 

Author Comment

by:alendozo
ID: 17055349
kbens0n -

I wasn't able to find an exception log, might be due to the version I'm running (BrightStor 11.5).  I was able to change the Message Level to "Detail" and received the text below (sorry again for the length of the text file):

2006/07/06 10:45:31 [112c] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 10:55:16 [1120] Registering protocolSeq ncalrpc, EndPoint tapeeng_ncalrpc
2006/07/06 10:55:16 [1120] Registering protocolSeq ncacn_np, EndPoint \pipe\tapeeng_rpc
2006/07/06 10:55:16 [1120] Registering protocolSeq ncacn_ip_tcp, EndPoint 6502
2006/07/06 10:55:16 [1758] -----------START LOGGING---------

2006/07/06 10:55:16 [1758] Loading Server and Device List

2006/07/06 10:55:16 [1758] Unable to get the NAS Server list (RC= 12)

2006/07/06 10:55:16 [1758] Virtual Libraries Manager NOT Installed
2006/07/06 10:55:16 [1758] Mux_Init successfully launched.
2006/07/06 10:55:16 [1758] Mux_Device_Init successfully launched.
2006/07/06 10:55:16 [1758] Failed to get Tape log path. MS=0
2006/07/06 10:55:16 [1758] USE DEFAULT PATH = C:\Program Files\CA\BrightStor ARCserve Backup\LOG FOR LOGGING
2006/07/06 10:55:16 [1758] Successfully logon with User[ale] Domain[BACKUPTEST]
2006/07/06 10:55:16 [1758]  !!!  SAN Option is not Installed  !!!
2006/07/06 10:55:16 [1758]
2006/07/06 10:55:16 [1758] ============ TAPE ENGINE DETAIL LOG ====================
2006/07/06 10:55:16 [1758] OEM                              : CA Inc.
2006/07/06 10:55:16 [1758] Name                             : BrightStor ARCserve Backup
2006/07/06 10:55:16 [1758] Major Version                    : 11
2006/07/06 10:55:16 [1758] Minor Version                    : 5
2006/07/06 10:55:16 [1758] Date                             : Aug  9 2005
2006/07/06 10:55:16 [1758] Time                             : 15:37:15
2006/07/06 10:55:16 [1758] Description                      : 11.5 Release of Tape Engine
2006/07/06 10:55:16 [1758] Debug Level                      : 2
2006/07/06 10:55:16 [1758] Read/Write/TestUnitReady Logging : OFF
2006/07/06 10:55:16 [1758] =========================================================
2006/07/06 10:55:16 [1758] Client Set DebugLevel: 2
2006/07/06 10:55:16 [1758] ...Scanning for Removable Media Devices...
2006/07/06 10:55:16 [1758] Scanning the system for devices
2006/07/06 10:55:16 [1758] Found 5 adapters
2006/07/06 10:55:16 [1758] Scanning for devices at \Scsi Port 0
2006/07/06 10:55:16 [1758]   LDN:0 on ABSL:0010 [COMPAQ  CDR-8435        0013]
2006/07/06 10:55:16 [1758] Scanning for devices at \Scsi Port 1
2006/07/06 10:55:16 [1758] Scanning for devices at \Scsi Port 2
2006/07/06 10:55:16 [1758]   LDN:1 on ABSL:2050 [QUANTUM SDLT600         2323]
2006/07/06 10:55:16 [1758] Scanning for devices at \Scsi Port 3
2006/07/06 10:55:16 [1758] Scanning for devices at \Scsi Port 4
2006/07/06 10:55:16 [1758]   LDN:2 on ABSL:4000 [Compaq  Disk Array      1.02]
2006/07/06 10:55:16 [1758]   LDN:3 on ABSL:4010 [Compaq  Disk Array      1.02]
2006/07/06 10:55:16 [1758]   LDN:4 on ABSL:4100 [COMPAQ  SCSI COMMUNICATEDA02]
2006/07/06 10:55:16 [1758] BCB size = 1416
2006/07/06 10:55:16 [1758] DCB size = 6528
2006/07/06 10:55:16 [1758] JCB size = 118784
2006/07/06 10:55:16 [1758] GCB size = 250368
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:16 [1758] Info[board-0,bus-0,scsiid-1,lun-0,type-258,COMPAQ  CDR-8435        0013]
2006/07/06 10:55:16 [1758] ABSL[0010] DeviceType[258] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 10:55:16 [1758] ABSL[0010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:16 [1758] ABSL[0010] muxSeekType[2] stagingPath[]
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:16 [1758] Info[board-2,bus-0,scsiid-5,lun-0,type-5,QUANTUM SDLT600         2323]
2006/07/06 10:55:16 [1758] ABSL[2050] DeviceType[5] Inquiry_String[QUANTUM] SerialNumber[RB0451AMC05123  ]
2006/07/06 10:55:16 [1758] ABSL[2050] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:16 [1758] ABSL[2050] muxSeekType[0] stagingPath[]
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:16 [1758] Info[board-4,bus-0,scsiid-0,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 10:55:16 [1758] ABSL[4000] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 10:55:16 [1758] ABSL[4000] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:16 [1758] ABSL[4000] muxSeekType[2] stagingPath[]
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:16 [1758] Info[board-4,bus-0,scsiid-1,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 10:55:16 [1758] ABSL[4010] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 10:55:16 [1758] ABSL[4010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:16 [1758] ABSL[4010] muxSeekType[2] stagingPath[]
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:16 [1758] Info[board-4,bus-1,scsiid-0,lun-0,type-32768,COMPAQ  SCSI COMMUNICATEDA02]
2006/07/06 10:55:16 [1758] ABSL[4100] DeviceType[32768] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 10:55:16 [1758] ABSL[4100] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:16 [1758] ABSL[4100] muxSeekType[2] stagingPath[]
2006/07/06 10:55:16 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:16 [1758] MicrosoftiScsiInitiator: ScsiPort [0] device name []
2006/07/06 10:55:16 [1758] MicrosoftiScsiInitiator: ScsiPort [1] device name []
2006/07/06 10:55:16 [1758] MicrosoftiScsiInitiator: ScsiPort [2] device name []
2006/07/06 10:55:16 [1758] MicrosoftiScsiInitiator: ScsiPort [3] device name []
2006/07/06 10:55:16 [1758] MicrosoftiScsiInitiator: ScsiPort [4] device name []
2006/07/06 10:55:16 [1758] Detected a total of 5 SCSI Board
2006/07/06 10:55:16 [1758] 5 local devices detected by the media engine

2006/07/06 10:55:16 [1758] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 10:55:16 [1758] TapeAlert registry setting successful
2006/07/06 10:55:16 [1758] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 10:55:16 [1758] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 10:55:16 [1758] ABSL:2050: Successfully open Tape handle.
2006/07/06 10:55:16 [1758] ABSL:2050: Tape IOCTL_STORAGE_MCN_CONTROL Disabled.
2006/07/06 10:55:16 [1758] LDN:1 Mark claimed tape device as unclaimed!!!
2006/07/06 10:55:16 [1758] FindTapeDriveCategory: inquirydata->VendorID: QUANTUM SDLT600         2323
2006/07/06 10:55:16 [1758] Device Support INI found.
2006/07/06 10:55:16 [1758] FindTapeDriveCategory: Drive: [QUANTUM SDLT600         2323] matches...
2006/07/06 10:55:16 [1758] FindTapeDriveCategory: Entry: [QUANTUM SDLT            ] in Tapelist
2006/07/06 10:55:16 [1758] =>ABSL:2050 [MODE SENSE      ] 1a 00 0f 00 1c 00 00 00 00 00 tt[5]
2006/07/06 10:55:16 [1758]    MS Page[0f] ABSL:2050      1b 87 10 08 4a 00 00 00 00 01 00 00
2006/07/06 10:55:16 [1758]                ABSL:2050      0f 0e c0 80 00 00 00 10 00 00 00 10
2006/07/06 10:55:16 [1758]         DRV:1 Compression Enabled
2006/07/06 10:55:16 [1758] =>ABSL:2050 [INQUIRY         ] 12 00 00 00 40 00 00 00 00 00 tt[1]
2006/07/06 10:55:16 [1758] ---------------INQUIRY HEX DUMP----------------
2006/07/06 10:55:16 [1758] Hexdump Starts --------------------
2006/07/06 10:55:16 [1758] 01 80 04 12 3d 00 01 30 51 55 41 4e 54 55 4d 20   ....=..0QUANTUM
2006/07/06 10:55:16 [1758] 53 44 4c 54 36 30 30 20 20 20 20 20 20 20 20 20   SDLT600        
2006/07/06 10:55:16 [1758] 32 33 32 33 c1 23 00 23 01 04 01 01 00 00 00 00   2323.#.#........
2006/07/06 10:55:16 [1758] 00 00 00 00 41 30 32 20 0c 00 00 40 0b 1c 02 60   ....A02 ...@...`
2006/07/06 10:55:16 [1758]
2006/07/06 10:55:16 [1758] =>ABSL:2050 [INQUIRY         ] 12 01 80 00 40 00 00 00 00 00 tt[1]
2006/07/06 10:55:16 [1758] ---------------INQUIRY HEX DUMP----------------
2006/07/06 10:55:16 [1758] Hexdump Starts --------------------
2006/07/06 10:55:16 [1758] 01 80 00 10 52 42 30 34 35 31 41 4d 43 30 35 31   ....RB0451AMC051
2006/07/06 10:55:16 [1758] 32 33 20 20 36 30 30 20 20 20 20 20 20 20 20 20   23  600        
2006/07/06 10:55:16 [1758] 32 33 32 33 c1 23 00 23 01 04 01 01 00 00 00 00   2323.#.#........
2006/07/06 10:55:16 [1758] 00 00 00 00 41 30 32 20 0c 00 00 40 0b 1c 02 60   ....A02 ...@...`
2006/07/06 10:55:16 [1758]
2006/07/06 10:55:16 [1758] LDN:1 - *Serial Number [RB0451AMC05123  ] Found*
2006/07/06 10:55:16 [1758] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 10:55:16 [1758] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 10:55:16 [1758] fsdev: Windows 2003 or greater detected...
2006/07/06 10:55:16 [1758] fsdev:InitRemovableMediaDevices() : gBTOFSDoNotUseImpersonation = [0]
2006/07/06 10:55:16 [1758] fsdev:InitRemovableMediaDevices() : Looking for Removable Media Devices.....
2006/07/06 10:55:16 [1758] fsdev:InitRemovableMediaDevices() : Number of Removable Media Devices Found:[ZERO]
2006/07/06 10:55:16 [1758] Detected a total of 5 SCSI Devices
2006/07/06 10:55:16 [1758] DB: Database initialization thread created TID[1058]
2006/07/06 10:55:16 [1758] Initializing changer(s)
2006/07/06 10:55:16 [1758] Loading Changer.dll: Date[Aug  9 2005] Time[13:43:10]
2006/07/06 10:55:16 [1758] Waiting for [0] Library(s) to initialize!
2006/07/06 10:55:16 [1758] SaveChangerConfigurationThread created. ThreadID[AAC]
2006/07/06 10:55:16 [1758] Done Initializing changer(s)
2006/07/06 10:55:16 [1758] InitializeGroupTables: Found Group[GROUP0] Type[TAPE_GROUP]
2006/07/06 10:55:16 [1758] InitializeGroupTables:  Found Group[GROUP0] Drive[0] Device[1]
2006/07/06 10:55:16 [1758] FSD: FSD_InitThresholdControl(), initialized successfully.
2006/07/06 10:55:16 [1758] ClientActivateTapesvr: ExecuteQueuedDBCommands(). RemoteDB or Central DB not used.  
2006/07/06 10:55:16 [1758] DB: ExecuteQueuedDBCommands()
2006/07/06 10:55:17 [0aac] Start SaveChangerConfigurationThread
2006/07/06 10:55:17 [0aac] SaveChangerConfigurationThread: All Libraries have been initialized
2006/07/06 10:55:17 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 10:55:17 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 10:55:17 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 10:55:17 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 10:55:17 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 10:55:17 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 10:55:17 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 10:55:18 [1758] DB: No Queued DB Commands
2006/07/06 10:55:18 [1758] Home directory:[C:\Program Files\CA\BrightStor ARCserve Backup]
2006/07/06 10:55:18 [1758] HostServer    :[BACKUPTEST]
2006/07/06 10:55:18 [1758] ASWriteToASLog: NOTE[62817] [Tape Engine is Started.]
2006/07/06 10:55:19 [1758] TapeDrive[1] ABSL:2050 <SDLT600         2323> S/N[RB0451AMC05123  ]
2006/07/06 10:55:19 [1758] Loading Media Server
2006/07/06 10:55:19 [1758] Started MediaSvr process.
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:19 [1758] Info[board-0,bus-0,scsiid-1,lun-0,type-258,COMPAQ  CDR-8435        0013]
2006/07/06 10:55:19 [1758] ABSL[0010] DeviceType[258] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 10:55:19 [1758] ABSL[0010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:19 [1758] ABSL[0010] muxSeekType[2] stagingPath[]
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:19 [1758] Info[board-2,bus-0,scsiid-5,lun-0,type-5,QUANTUM SDLT600         2323]
2006/07/06 10:55:19 [1758] ABSL[2050] DeviceType[5] Inquiry_String[QUANTUM] SerialNumber[RB0451AMC05123  ]
2006/07/06 10:55:19 [1758] ABSL[2050] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:19 [1758] ABSL[2050] muxSeekType[2] stagingPath[]
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:19 [1758] Info[board-4,bus-0,scsiid-0,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 10:55:19 [1758] ABSL[4000] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 10:55:19 [1758] ABSL[4000] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:19 [1758] ABSL[4000] muxSeekType[2] stagingPath[]
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:19 [1758] Info[board-4,bus-0,scsiid-1,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 10:55:19 [1758] ABSL[4010] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 10:55:19 [1758] ABSL[4010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:19 [1758] ABSL[4010] muxSeekType[2] stagingPath[]
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:19 [1758] Info[board-4,bus-1,scsiid-0,lun-0,type-32768,COMPAQ  SCSI COMMUNICATEDA02]
2006/07/06 10:55:19 [1758] ABSL[4100] DeviceType[32768] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 10:55:19 [1758] ABSL[4100] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:19 [1758] ABSL[4100] muxSeekType[2] stagingPath[]
2006/07/06 10:55:19 [1758] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:19 [1758] ---------- SetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 10:55:19 [1758] ABSL[0010] DeviceType[258] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 10:55:19 [1758] ABSL[0010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 10:55:19 [1758] ABSL[0010] muxSeekType[2] stagingPath[]
2006/07/06 10:55:19 [1758] ---------- SetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 10:55:19 [1758] ABSL = 0 0 1 0, adapterTransferSize = 131072
2006/07/06 10:55:19 [1758] ABSL = 2 0 5 0, adapterTransferSize = 1044480
2006/07/06 10:55:19 [1758] ABSL = 4 0 0 0, adapterTransferSize = 69632
2006/07/06 10:55:19 [1758] ABSL = 4 0 1 0, adapterTransferSize = 69632
2006/07/06 10:55:19 [1758] ABSL = 4 1 0 0, adapterTransferSize = 69632
2006/07/06 10:55:24 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:29 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:34 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:39 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:44 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:49 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:54 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:55:59 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:04 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:09 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:14 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:19 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 10:56:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 10:56:24 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:29 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:34 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:39 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:44 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:49 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:54 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:56:59 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:57:04 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:57:09 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:57:14 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:57:19 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:57:24 [1758] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 10:57:24 [1758] -Total Jobs now ACTIVE: [1]-
2006/07/06 10:57:24 [1758] CREATEJOBHANDLE[00D55898] JobID[21976] Generated for new Job Queue Handle
2006/07/06 10:57:24 [1758] CREATEJOBHANDLE[00D55898] JobType:[64][Tape Engine ScanDeamon]
2006/07/06 10:57:24 [1758] CREATEJOBHANDLE[00D55898] Client:[Main Job]
2006/07/06 10:57:24 [1758] CREATEJOBHANDLE[00D55898] Connection:[Local]
2006/07/06 10:57:24 [1758] ---------------------- Host Adapter 0 Details------------------
2006/07/06 10:57:24 [1758] Host Adapter Vendor Name   : Generic
2006/07/06 10:57:24 [1758] Host Adapter Name          : atapi
2006/07/06 10:57:24 [1758] Host Adapter No            : 0
2006/07/06 10:57:24 [1758] Host ScsiId                : 255
2006/07/06 10:57:24 [1758] IRQ Level                  : ffffh
2006/07/06 10:57:24 [1758] Max Transfer Length        : 131072 Bytes, 0 MB
2006/07/06 10:57:24 [1758] Max Physical Pages         : 32
2006/07/06 10:57:24 [1758] Max Transfer Length 2      : 131072 Bytes, 0 MB
2006/07/06 10:57:24 [1758] ----------------------------------------------------------------
2006/07/06 10:57:24 [1758] ---------------------- Host Adapter 1 Details------------------
2006/07/06 10:57:24 [1758] Host Adapter Vendor Name   : Generic
2006/07/06 10:57:24 [1758] Host Adapter Name          : atapi
2006/07/06 10:57:24 [1758] Host Adapter No            : 1
2006/07/06 10:57:24 [1758] Host ScsiId                : 255
2006/07/06 10:57:24 [1758] IRQ Level                  : ffffh
2006/07/06 10:57:24 [1758] Max Transfer Length        : 131072 Bytes, 0 MB
2006/07/06 10:57:24 [1758] Max Physical Pages         : 32
2006/07/06 10:57:24 [1758] Max Transfer Length 2      : 131072 Bytes, 0 MB
2006/07/06 10:57:24 [1758] ----------------------------------------------------------------
2006/07/06 10:57:24 [1758] ---------------------- Host Adapter 2 Details------------------
2006/07/06 10:57:24 [1758] Host Adapter Vendor Name   : Compaq Inc.
2006/07/06 10:57:24 [1758] Host Adapter Name          : cpq32fs2
2006/07/06 10:57:24 [1758] Host Adapter No            : 2
2006/07/06 10:57:24 [1758] Host ScsiId                : 255
2006/07/06 10:57:24 [1758] IRQ Level                  : 13h
2006/07/06 10:57:24 [1758] Max Transfer Length        : 285212655 Bytes, 271 MB
2006/07/06 10:57:24 [1758] Max Physical Pages         : 255
2006/07/06 10:57:24 [1758] Max Transfer Length 2      : 1044480 Bytes, 0 MB
2006/07/06 10:57:24 [1758] ----------------------------------------------------------------
2006/07/06 10:57:24 [1758] ---------------------- Host Adapter 3 Details------------------
2006/07/06 10:57:24 [1758] Host Adapter Vendor Name   : Compaq Inc.
2006/07/06 10:57:24 [1758] Host Adapter Name          : cpq32fs2
2006/07/06 10:57:24 [1758] Host Adapter No            : 3
2006/07/06 10:57:24 [1758] Host ScsiId                : 255
2006/07/06 10:57:24 [1758] IRQ Level                  : 12h
2006/07/06 10:57:24 [1758] Max Transfer Length        : 285212655 Bytes, 271 MB
2006/07/06 10:57:24 [1758] Max Physical Pages         : 255
2006/07/06 10:57:24 [1758] Max Transfer Length 2      : 1044480 Bytes, 0 MB
2006/07/06 10:57:24 [1758] ----------------------------------------------------------------
2006/07/06 10:57:24 [1758] ---------------------- Host Adapter 4 Details------------------
2006/07/06 10:57:24 [1758] Host Adapter Vendor Name   : cpqarry2
2006/07/06 10:57:24 [1758] Host Adapter Name          : cpqarry2
2006/07/06 10:57:24 [1758] Host Adapter No            : 4
2006/07/06 10:57:24 [1758] Host ScsiId                : 255
2006/07/06 10:57:24 [1758] IRQ Level                  : 19h
2006/07/06 10:57:24 [1758] Max Transfer Length        : 65536 Bytes, 0 MB
2006/07/06 10:57:24 [1758] Max Physical Pages         : 17
2006/07/06 10:57:24 [1758] Max Transfer Length 2      : 69632 Bytes, 0 MB
2006/07/06 10:57:24 [1758] ----------------------------------------------------------------
2006/07/06 10:57:24 [1758] ----------------------Device 1 Detail-----------------------------
2006/07/06 10:57:24 [1758] Vendor, Product, Firmware  : QUANTUM SDLT600         2323
2006/07/06 10:57:24 [1758] Device Number              : 0
2006/07/06 10:57:24 [1758] SCSI compliance, SCSI      : 4
2006/07/06 10:57:24 [1758] SCSI ID                    : 5
2006/07/06 10:57:24 [1758] LUN                        : 0
2006/07/06 10:57:24 [1758] Host Board No              : 2
2006/07/06 10:57:24 [1758] Device Type                : 5
2006/07/06 10:57:24 [1758] Cartridge Type             : 248
2006/07/06 10:57:24 [1758] TapeFlags                  : 00002000
2006/07/06 10:57:24 [1758] Cap Flags                  : 0000004D
2006/07/06 10:57:24 [1758] Host Adapter No            : 1
2006/07/06 10:57:24 [1758] -----------------------------------------------------------------
2006/07/06 10:57:24 [1758] ...in ActivateScanDaemon usNumOfDevices[5]
2006/07/06 10:57:24 [1758] ActivateScanDaemon: Device[0] Scanning NOT on! DeviceType[0x102] Flag[0x40]
2006/07/06 10:57:24 [1758] ActivateScanDaemon: Device[1] Scanning turned on!
2006/07/06 10:57:24 [1758] ActivateScanDaemon: Device[2] Scanning NOT on! DeviceType[0x2] Flag[0x40]
2006/07/06 10:57:24 [1758] ActivateScanDaemon: Device[3] Scanning NOT on! DeviceType[0x2] Flag[0x40]
2006/07/06 10:57:24 [1758] ActivateScanDaemon: Device[4] Scanning NOT on! DeviceType[0x8000] Flag[0x0]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [LOAD TAPE       ] 1b 00 00 00 01 00 00 00 00 00 tt[30]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [MODE SENSE      ] 1a 00 00 00 0c 00 00 00 00 00 tt[5]
2006/07/06 10:57:24 [03e8]    MS Page[00] ABSL:2050      0b 87 10 08 4a 00 00 00 00 01 00 00
2006/07/06 10:57:24 [03e8]                ABSL:2050      00 00 00 00 00 00 00 00 00 00 00 00
2006/07/06 10:57:24 [03e8]         DRV:1 Tape is NOT Write Protected
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [MODE SENSE      ] 1a 00 0f 00 1c 00 00 00 00 00 tt[5]
2006/07/06 10:57:24 [03e8]    MS Page[0f] ABSL:2050      1b 87 10 08 4a 00 00 00 00 01 00 00
2006/07/06 10:57:24 [03e8]                ABSL:2050      0f 0e c0 80 00 00 00 10 00 00 00 10
2006/07/06 10:57:24 [03e8]         DRV:1 Compression Enabled
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [INQUIRY         ] 12 01 c0 00 40 00 00 00 00 00 tt[1]
2006/07/06 10:57:24 [03e8] ---------------INQUIRY HEX DUMP----------------
2006/07/06 10:57:24 [03e8] Hexdump Starts --------------------
2006/07/06 10:57:24 [03e8] 01 c0 01 28 23 00 00 00 7d 0c 75 42 00 00 00 00   ...(#...}.uB....
2006/07/06 10:57:24 [03e8] d8 dc 44 c6 32 39 2d 53 65 70 2d 32 30 30 34 20   ..D.29-Sep-2004
2006/07/06 10:57:24 [03e8] 31 39 3a 35 33 3a 35 35 00 00 00 00 00 00 00 00   19:53:55........
2006/07/06 10:57:24 [03e8] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
2006/07/06 10:57:24 [03e8]
2006/07/06 10:57:24 [03e8] DEVICE1 [QUANTUM SDLT600         2323] - WORM drive
2006/07/06 10:57:24 [03e8] [SDLT600         2323] WORM drive
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [Read Attribute  ] 8c 00 00 00 00 00 00 00 04 08 tt[2]
2006/07/06 10:57:24 [03e8] NOT WORM []loaded
2006/07/06 10:57:24 [03e8] WORM capable tape []loaded, Density[0x4a]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [REWIND          ] 01 00 00 00 00 00 00 00 04 08 tt[30]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [READ POSITION   ] 34 00 00 00 00 00 00 00 00 00 tt[6]
2006/07/06 10:57:24 [03e8]         DRV:1 Current block #: [00000000] [0]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [MODE SENSE      ] 1a 00 00 00 0c 00 00 00 00 00 tt[5]
2006/07/06 10:57:24 [03e8]    MS Page[00] ABSL:2050      0b 87 10 08 4a 00 00 00 00 01 00 00
2006/07/06 10:57:24 [03e8]                ABSL:2050      00 00 00 00 00 00 00 00 00 00 00 00
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [READ            ] 08 01 00 00 01 00 00 00 00 00 tt[20]
2006/07/06 10:57:24 [03e8]         DRV:1 Read Tape Header Sequence Number: [1]
2006/07/06 10:57:24 [03e8]         DRV:1 Read Tape Header Random ID: [ACA3]
2006/07/06 10:57:24 [03e8]         DRV:1 Read Tape Header Tape Name: [TEST1                   ]
2006/07/06 10:57:24 [03e8]         DRV:1 Read Tape Header PreviousSessions: [0]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [REWIND          ] 01 00 00 00 00 00 00 00 00 00 tt[30]
2006/07/06 10:57:24 [03e8] =>ABSL:2050 [READ POSITION   ] 34 00 00 00 00 00 00 00 00 00 tt[6]
2006/07/06 10:57:24 [03e8]         DRV:1 Current block #: [00000000] [0]
2006/07/06 10:57:24 [03e8] DB: DBRegisterTape is being called. DBState[1]
2006/07/06 10:57:24 [03e8] DB: DBRegisterTape waiting for DB state to change [0]
2006/07/06 10:57:25 [0c44] -Total Jobs now ACTIVE: [2]-
2006/07/06 10:57:25 [0c44] CREATEJOBHANDLE[00D55C38] JobID[0] Generated for new Managment Handle
2006/07/06 10:57:25 [0c44] CREATEJOBHANDLE[00D55C38] JobType:[8][Management]
2006/07/06 10:57:25 [0c44] CREATEJOBHANDLE[00D55C38] Client:[MEDIASVR]
2006/07/06 10:57:25 [0c44] CREATEJOBHANDLE[00D55C38] Connection:[Local]
2006/07/06 10:57:25 [03e8] DB: DBRegisterTape waiting for DB state to change [1]
2006/07/06 10:57:26 [03e8] DB: DBRegisterTape waiting for DB state to change [2]
2006/07/06 10:57:27 [03e8] DB: DBRegisterTape waiting for DB state to change [3]
2006/07/06 10:57:28 [03e8] DB: DBRegisterTape waiting for DB state to change [4]
2006/07/06 10:57:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 10:57:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 10:58:39 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 10:58:39 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 10:59:44 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 10:59:44 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:00:53 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:00:53 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:01:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:01:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:03:02 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:03:02 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:04:09 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:04:09 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:04:31 [0c44] -Total Jobs now ACTIVE: [3]-
2006/07/06 11:04:31 [0c44] CREATEJOBHANDLE[00D55FD8] JobID[0] Generated for new Managment Handle
2006/07/06 11:04:31 [0c44] CREATEJOBHANDLE[00D55FD8] JobType:[8][Management]
2006/07/06 11:04:31 [0c44] CREATEJOBHANDLE[00D55FD8] Client:[ASMGR@BACKUPTEST]
2006/07/06 11:04:31 [0c44] CREATEJOBHANDLE[00D55FD8] Connection:[Local]
2006/07/06 11:05:16 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:05:16 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:06:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:06:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:07:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:07:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:08:33 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:08:33 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:09:40 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:09:40 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:10:44 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:10:44 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:11:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:11:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:12:51 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:12:51 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:13:58 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:13:58 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:15:03 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:15:03 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:16:07 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:16:07 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:17:12 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:17:12 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 11:17:12 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 11:17:12 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 11:17:12 [1058] DB: Database Library not ready.
2006/07/06 11:17:12 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 11:17:12 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 11:17:12 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 11:17:12 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 11:17:12 [1058] DB:: SetHooks:: Retrying. count = 1
2006/07/06 11:17:12 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 11:17:12 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 11:17:12 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 11:17:12 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 11:17:12 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 11:17:12 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 11:17:12 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:18:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:18:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:19:26 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:19:26 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:20:31 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:20:31 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:21:35 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:21:35 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:22:42 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:22:42 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:23:49 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:23:49 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:24:56 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:24:56 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:26:00 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:26:00 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:27:05 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:27:05 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:28:07 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:28:07 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:29:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:29:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:30:20 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:30:20 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:31:24 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:31:24 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:32:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:32:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:33:36 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:33:36 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:34:39 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:34:39 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:35:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:35:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:36:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:36:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:37:52 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:37:52 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:38:59 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:38:59 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 11:38:59 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 11:38:59 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 11:38:59 [1058] DB: Database Library not ready.
2006/07/06 11:38:59 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 11:38:59 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 11:38:59 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 11:38:59 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 11:38:59 [1058] DB:: SetHooks:: Retrying. count = 2
2006/07/06 11:38:59 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 11:38:59 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 11:38:59 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 11:38:59 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 11:38:59 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 11:38:59 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 11:38:59 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:40:04 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:40:04 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:41:06 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:41:06 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:42:10 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:42:10 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:43:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:43:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:44:19 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:44:19 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:45:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:45:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:46:27 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:46:27 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:47:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:47:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:48:34 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:48:34 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:49:38 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:49:38 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:50:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:50:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:51:48 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:51:48 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:52:52 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:52:52 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:53:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:53:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:55:01 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:55:01 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:56:06 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:56:06 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:57:08 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:57:08 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:58:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:58:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 11:59:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 11:59:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:00:25 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:00:25 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 12:00:25 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 12:00:25 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 12:00:25 [1058] DB: Database Library not ready.
2006/07/06 12:00:25 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 12:00:25 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 12:00:25 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 12:00:25 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 12:00:25 [1058] DB:: SetHooks:: Retrying. count = 3
2006/07/06 12:00:25 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 12:00:25 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 12:00:25 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 12:00:25 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 12:00:25 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 12:00:25 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 12:00:25 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:01:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:01:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:02:34 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:02:34 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:03:38 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:03:38 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:04:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:04:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:05:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:05:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:06:52 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:06:52 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:07:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:07:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:09:04 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:09:04 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:10:10 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:10:10 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:11:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:11:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:12:17 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:12:17 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:13:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:13:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:14:24 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:14:24 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:15:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:15:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:16:35 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:16:35 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:17:38 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:17:38 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:18:42 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:18:42 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:19:48 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:19:48 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:20:53 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:20:53 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:21:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:21:57 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 12:21:57 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 12:21:57 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 12:21:57 [1058] DB: Database Library not ready.
2006/07/06 12:21:57 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 12:21:57 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 12:21:57 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 12:21:57 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 12:21:57 [1058] DB:: SetHooks:: Retrying. count = 4
2006/07/06 12:21:57 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 12:21:57 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 12:21:57 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 12:21:57 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 12:21:57 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 12:21:57 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 12:21:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:23:02 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:23:02 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:24:04 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:24:04 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:25:11 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:25:11 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:26:16 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:26:16 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:27:20 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:27:20 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:28:25 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:28:25 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:29:30 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:29:30 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:30:35 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:30:35 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:31:40 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:31:40 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:32:42 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:32:42 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:33:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:33:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:34:52 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:34:52 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:35:56 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:35:56 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:37:02 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:37:02 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:38:06 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:38:06 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:39:09 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:39:09 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:40:13 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:40:13 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:41:18 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:41:18 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:42:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:42:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:43:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:43:29 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 12:43:29 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 12:43:29 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 12:43:29 [1058] DB: Database Library not ready.
2006/07/06 12:43:29 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 12:43:29 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 12:43:29 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 12:43:29 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 12:43:29 [1058] DB:: SetHooks:: Retrying. count = 5
2006/07/06 12:43:29 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 12:43:29 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 12:43:29 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 12:43:29 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 12:43:29 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 12:43:29 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 12:43:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:44:34 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:44:34 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:45:38 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:45:38 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:46:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:46:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:47:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:47:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:48:50 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:48:50 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:49:54 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:49:54 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:50:59 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:50:59 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:52:03 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:52:03 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:53:08 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:53:08 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:54:16 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:54:16 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:55:20 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:55:20 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:56:23 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:56:23 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:57:27 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:57:27 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:58:32 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:58:32 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 12:59:39 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 12:59:39 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:00:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:00:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:01:46 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:01:46 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:02:53 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:02:53 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:03:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:03:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:05:02 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:05:02 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 13:05:02 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 13:05:02 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 13:05:02 [1058] DB: Database Library not ready.
2006/07/06 13:05:02 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 13:05:02 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 13:05:02 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 13:05:02 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 13:05:02 [1058] DB:: SetHooks:: Retrying. count = 6
2006/07/06 13:05:02 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 13:05:02 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 13:05:02 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 13:05:02 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 13:05:02 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 13:05:02 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 13:05:02 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:06:06 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:06:06 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:07:09 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:07:09 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:08:13 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:08:13 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:09:18 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:09:18 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:10:21 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:10:21 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:11:26 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:11:26 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:12:28 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:12:28 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:13:36 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:13:36 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:14:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:14:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:15:48 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:15:48 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:16:52 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:16:52 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:17:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:17:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:19:03 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:19:03 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:20:06 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:20:06 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:21:10 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:21:10 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:22:18 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:22:18 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:23:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:23:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:24:27 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:24:27 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:25:31 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:25:31 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:26:37 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:26:37 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 13:26:37 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 13:26:37 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 13:26:37 [1058] DB: Database Library not ready.
2006/07/06 13:26:37 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 13:26:37 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 13:26:37 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 13:26:37 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 13:26:37 [1058] DB:: SetHooks:: Retrying. count = 7
2006/07/06 13:26:37 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 13:26:37 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 13:26:37 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 13:26:37 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 13:26:37 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 13:26:37 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 13:26:37 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:27:41 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:27:41 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:28:46 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:28:46 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:29:48 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:29:48 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:30:53 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:30:53 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:31:56 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:31:56 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:33:00 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:33:00 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:34:05 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:34:05 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:35:12 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:35:12 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:36:17 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:36:17 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:37:21 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:37:21 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:38:26 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:38:26 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:39:30 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:39:30 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:40:36 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:40:36 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:41:40 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:41:40 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:42:43 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:42:43 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:43:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:43:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:44:52 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:44:52 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:45:56 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:45:56 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:47:01 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:47:01 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:48:05 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:48:05 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 13:48:05 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 13:48:05 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 13:48:05 [1058] DB: Database Library not ready.
2006/07/06 13:48:05 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 13:48:05 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 13:48:05 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 13:48:05 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 13:48:05 [1058] DB:: SetHooks:: Retrying. count = 8
2006/07/06 13:48:05 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 13:48:05 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 13:48:05 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 13:48:05 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 13:48:05 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 13:48:05 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 13:48:05 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:49:10 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:49:10 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:50:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:50:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:51:19 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:51:19 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:52:24 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:52:24 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:53:28 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:53:28 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:54:35 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:54:35 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:55:42 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:55:42 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:56:46 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:56:46 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:57:51 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:57:51 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 13:58:55 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 13:58:55 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:00:03 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:00:03 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:01:10 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:01:10 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:02:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:02:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:03:19 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:03:19 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:04:25 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:04:25 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:05:28 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:05:28 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:06:32 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:06:32 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:07:37 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:07:37 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:08:41 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:08:41 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:09:48 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:09:48 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 14:09:48 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 14:09:48 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 14:09:48 [1058] DB: Database Library not ready.
2006/07/06 14:09:48 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 14:09:48 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 14:09:48 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 14:09:48 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 14:09:48 [1058] DB:: SetHooks:: Retrying. count = 9
2006/07/06 14:09:48 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 14:09:49 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 14:09:49 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 14:09:49 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 14:09:49 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 14:09:49 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 14:09:49 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:10:56 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:10:56 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:12:01 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:12:01 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:13:05 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:13:05 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:14:08 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:14:08 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:15:12 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:15:12 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:16:17 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:16:17 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:17:21 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:17:21 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:18:24 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:18:24 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:19:30 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:19:30 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:20:36 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:20:36 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:21:41 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:21:41 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:22:45 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:22:45 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:23:47 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:23:47 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:24:54 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:24:54 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:25:59 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:25:59 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:27:03 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:27:03 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:28:08 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:28:08 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:29:12 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:29:12 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:30:18 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:30:18 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:31:22 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:31:22 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 14:31:22 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 14:31:22 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 14:31:22 [1058] DB: Database Library not ready.
2006/07/06 14:31:22 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 14:31:22 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 14:31:22 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 14:31:22 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 14:31:22 [1058] DB:: SetHooks:: Retrying. count = 10
2006/07/06 14:31:22 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 14:31:22 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 14:31:22 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 14:31:22 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 14:31:22 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 14:31:22 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 14:31:22 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:32:27 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:32:27 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:33:30 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:33:30 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:34:35 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:34:35 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:35:39 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:35:39 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:36:44 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:36:44 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:37:48 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:37:48 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:38:53 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:38:53 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:39:57 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:39:57 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:41:04 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:41:04 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:42:09 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:42:09 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:43:11 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:43:11 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:44:16 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:44:16 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:45:20 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:45:20 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:46:25 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:46:25 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:47:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:47:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:48:34 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:48:34 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:49:36 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:49:36 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:50:41 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:50:41 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:51:45 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:51:45 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:52:50 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:52:50 [1058] DB: Finished Registering drive[1] in database: END
2006/07/06 14:52:50 [1058] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 14:52:50 [1058] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 14:52:50 [1058] DB: Database Library not ready.
2006/07/06 14:52:50 [1058] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 14:52:50 [1058] DB:DB_GetEntryPoints: Status [1).
2006/07/06 14:52:50 [1058] DB: DB_GetEntryPoints() is returned.
2006/07/06 14:52:50 [1058] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 14:52:50 [1058] DB:: SetHooks:: Retrying. count = 11
2006/07/06 14:52:50 [1058] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 14:52:50 [1058] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 14:52:50 [1058] DB: Connect to database [BACKUPTEST]: START
2006/07/06 14:52:50 [1058] DB: Connect to database [BACKUPTEST]: END
2006/07/06 14:52:50 [1058] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 14:52:50 [1058] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 14:52:50 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:53:54 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:53:54 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:54:59 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:54:59 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:56:02 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:56:02 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:57:06 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:57:06 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:58:11 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:58:11 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 14:59:15 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 14:59:15 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 15:00:20 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 15:00:20 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 15:01:24 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 15:01:24 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 15:02:29 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 15:02:29 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 15:03:30 [14c8] Connection terminated for job 00d55fd8
2006/07/06 15:03:30 [14c8] DestroyJobHandle[00D55FD8]
2006/07/06 15:03:30 [14c8] ClientDisConnectFromTape: hJob[00D55FD8] Not Connected...
2006/07/06 15:03:30 [14c8] Destroy JobID[0]: Active Jobs now: [2] -- jID[0], mID[0], jTKN[0]
2006/07/06 15:03:34 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 15:03:34 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 15:04:24 [03e8] 07/06 15:04:24 ABSL:2050 SCSICommandRetry: NT Error that can be retried. CMD:[00], Error 1167
2006/07/06 15:04:24 [03e8] =>ABSL:2050 Error. MS=1167 [The device is not connected.
]
2006/07/06 15:04:24 [03e8] JobID[0] SCSIPort Error DevType[5] LDN[1] SN[RB0451AMC05123  ] CMD[TEST UNIT READY] Sent[15:04:23] MS Error[1167]
2006/07/06 15:04:24 [03e8] JobID[0] SCSI Port Error Tape.log ONLY!
2006/07/06 15:04:24 [03e8]         DRV:1 Hit HARDWARE_ERROR[61700] from TUR
2006/07/06 15:04:24 [03e8] JobID[0] SCSIPort Error DevType[5] LDN[1] SN[RB0451AMC05123  ] CMD[TEST UNIT READY] Sent[15:04:23] MS Error[61700]
2006/07/06 15:04:24 [03e8] ASWriteToASLogWithJobID: JobID[0] [6300] [Windows NT SCSI PORT Error]
2006/07/06 15:04:41 [1058] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 15:04:41 [1058] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 15:05:32 [09ac] Terminating the NDMP sub system...

2006/07/06 15:05:32 [09ac] NDMP sub system terminated

2006/07/06 15:05:32 [09ac] *** ClientStopTapesvr: called by Client ***
2006/07/06 15:05:32 [09ac] Stop MediaSvr ...
2006/07/06 15:05:32 [09ac] MediaSvr process Stopped.
2006/07/06 15:05:32 [09ac] DB: DisConnect from database: START
2006/07/06 15:05:32 [09ac] DB: DisConnect from database: END
2006/07/06 15:05:32 [09ac] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 16:16:27 [06ec] -----------START LOGGING---------

2006/07/06 16:16:27 [06fc] Registering protocolSeq ncalrpc, EndPoint tapeeng_ncalrpc
2006/07/06 16:16:27 [06fc] Registering protocolSeq ncacn_np, EndPoint \pipe\tapeeng_rpc
2006/07/06 16:16:27 [06fc] Registering protocolSeq ncacn_ip_tcp, EndPoint 6502
2006/07/06 16:16:27 [06ec] Loading Server and Device List

2006/07/06 16:16:27 [06ec] Unable to get the NAS Server list (RC= 12)

2006/07/06 16:16:27 [06ec] Virtual Libraries Manager NOT Installed
2006/07/06 16:16:27 [06ec] Mux_Init successfully launched.
2006/07/06 16:16:27 [06ec] Mux_Device_Init successfully launched.
2006/07/06 16:16:27 [06ec] Failed to get Tape log path. MS=0
2006/07/06 16:16:27 [06ec] USE DEFAULT PATH = C:\Program Files\CA\BrightStor ARCserve Backup\LOG FOR LOGGING
2006/07/06 16:16:27 [06ec] Successfully logon with User[ale] Domain[BACKUPTEST]
2006/07/06 16:16:27 [06ec]  !!!  SAN Option is not Installed  !!!
2006/07/06 16:16:27 [06ec]
2006/07/06 16:16:27 [06ec] ============ TAPE ENGINE DETAIL LOG ====================
2006/07/06 16:16:27 [06ec] OEM                              : CA Inc.
2006/07/06 16:16:27 [06ec] Name                             : BrightStor ARCserve Backup
2006/07/06 16:16:27 [06ec] Major Version                    : 11
2006/07/06 16:16:27 [06ec] Minor Version                    : 5
2006/07/06 16:16:27 [06ec] Date                             : Aug  9 2005
2006/07/06 16:16:27 [06ec] Time                             : 15:37:15
2006/07/06 16:16:27 [06ec] Description                      : 11.5 Release of Tape Engine
2006/07/06 16:16:27 [06ec] Debug Level                      : 2
2006/07/06 16:16:27 [06ec] Read/Write/TestUnitReady Logging : OFF
2006/07/06 16:16:27 [06ec] =========================================================
2006/07/06 16:16:27 [06ec] Client Set DebugLevel: 2
2006/07/06 16:16:27 [06ec] ...Scanning for Removable Media Devices...
2006/07/06 16:16:27 [06ec] Scanning the system for devices
2006/07/06 16:16:27 [06ec] Found 5 adapters
2006/07/06 16:16:27 [06ec] Scanning for devices at \Scsi Port 0
2006/07/06 16:16:27 [06ec]   LDN:0 on ABSL:0010 [COMPAQ  CDR-8435        0013]
2006/07/06 16:16:27 [06ec] Scanning for devices at \Scsi Port 1
2006/07/06 16:16:27 [06ec] Scanning for devices at \Scsi Port 2
2006/07/06 16:16:27 [06ec]   LDN:1 on ABSL:2050 [QUANTUM SDLT600         2323]
2006/07/06 16:16:27 [06ec] Scanning for devices at \Scsi Port 3
2006/07/06 16:16:27 [06ec] Scanning for devices at \Scsi Port 4
2006/07/06 16:16:27 [06ec]   LDN:2 on ABSL:4000 [Compaq  Disk Array      1.02]
2006/07/06 16:16:27 [06ec]   LDN:3 on ABSL:4010 [Compaq  Disk Array      1.02]
2006/07/06 16:16:27 [06ec]   LDN:4 on ABSL:4100 [COMPAQ  SCSI COMMUNICATEDA02]
2006/07/06 16:16:27 [06ec] BCB size = 1416
2006/07/06 16:16:27 [06ec] DCB size = 6528
2006/07/06 16:16:27 [06ec] JCB size = 118784
2006/07/06 16:16:27 [06ec] GCB size = 250368
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:27 [06ec] Info[board-0,bus-0,scsiid-1,lun-0,type-258,COMPAQ  CDR-8435        0013]
2006/07/06 16:16:27 [06ec] ABSL[0010] DeviceType[258] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 16:16:27 [06ec] ABSL[0010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:27 [06ec] ABSL[0010] muxSeekType[2] stagingPath[]
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:27 [06ec] Info[board-2,bus-0,scsiid-5,lun-0,type-5,QUANTUM SDLT600         2323]
2006/07/06 16:16:27 [06ec] ABSL[2050] DeviceType[5] Inquiry_String[QUANTUM] SerialNumber[RB0451AMC05123  ]
2006/07/06 16:16:27 [06ec] ABSL[2050] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:27 [06ec] ABSL[2050] muxSeekType[0] stagingPath[]
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:27 [06ec] Info[board-4,bus-0,scsiid-0,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 16:16:27 [06ec] ABSL[4000] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 16:16:27 [06ec] ABSL[4000] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:27 [06ec] ABSL[4000] muxSeekType[2] stagingPath[]
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:27 [06ec] Info[board-4,bus-0,scsiid-1,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 16:16:27 [06ec] ABSL[4010] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 16:16:27 [06ec] ABSL[4010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:27 [06ec] ABSL[4010] muxSeekType[2] stagingPath[]
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:27 [06ec] Info[board-4,bus-1,scsiid-0,lun-0,type-32768,COMPAQ  SCSI COMMUNICATEDA02]
2006/07/06 16:16:27 [06ec] ABSL[4100] DeviceType[32768] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 16:16:27 [06ec] ABSL[4100] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:27 [06ec] ABSL[4100] muxSeekType[2] stagingPath[]
2006/07/06 16:16:27 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:27 [06ec] MicrosoftiScsiInitiator: ScsiPort [0] device name []
2006/07/06 16:16:27 [06ec] MicrosoftiScsiInitiator: ScsiPort [1] device name []
2006/07/06 16:16:27 [06ec] MicrosoftiScsiInitiator: ScsiPort [2] device name []
2006/07/06 16:16:27 [06ec] MicrosoftiScsiInitiator: ScsiPort [3] device name []
2006/07/06 16:16:27 [06ec] MicrosoftiScsiInitiator: ScsiPort [4] device name []
2006/07/06 16:16:27 [06ec] Detected a total of 5 SCSI Board
2006/07/06 16:16:27 [06ec] 5 local devices detected by the media engine

2006/07/06 16:16:27 [06ec] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 16:16:27 [06ec] TapeAlert registry setting successful
2006/07/06 16:16:27 [06ec] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 16:16:27 [06ec] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 16:16:27 [06ec] ABSL:2050: Successfully open Tape handle.
2006/07/06 16:16:27 [06ec] ABSL:2050: Tape IOCTL_STORAGE_MCN_CONTROL Disabled.
2006/07/06 16:16:27 [06ec] LDN:1 Mark claimed tape device as unclaimed!!!
2006/07/06 16:16:27 [06ec] FindTapeDriveCategory: inquirydata->VendorID: QUANTUM SDLT600         2323
2006/07/06 16:16:27 [06ec] Device Support INI found.
2006/07/06 16:16:27 [06ec] FindTapeDriveCategory: Drive: [QUANTUM SDLT600         2323] matches...
2006/07/06 16:16:27 [06ec] FindTapeDriveCategory: Entry: [QUANTUM SDLT            ] in Tapelist
2006/07/06 16:16:27 [06ec] =>ABSL:2050 [MODE SENSE      ] 1a 00 0f 00 1c 00 00 00 00 00 tt[5]
2006/07/06 16:16:27 [06ec]    MS Page[0f] ABSL:2050      1b 87 10 08 4a 00 00 00 00 00 00 00
2006/07/06 16:16:27 [06ec]                ABSL:2050      0f 0e c0 80 00 00 00 10 00 00 00 10
2006/07/06 16:16:27 [06ec]         DRV:1 Compression Enabled
2006/07/06 16:16:27 [06ec] =>ABSL:2050 [INQUIRY         ] 12 00 00 00 40 00 00 00 00 00 tt[1]
2006/07/06 16:16:27 [06ec] ---------------INQUIRY HEX DUMP----------------
2006/07/06 16:16:27 [06ec] Hexdump Starts --------------------
2006/07/06 16:16:27 [06ec] 01 80 04 12 3d 00 01 30 51 55 41 4e 54 55 4d 20   ....=..0QUANTUM
2006/07/06 16:16:27 [06ec] 53 44 4c 54 36 30 30 20 20 20 20 20 20 20 20 20   SDLT600        
2006/07/06 16:16:27 [06ec] 32 33 32 33 c1 23 00 23 01 04 01 01 00 00 00 00   2323.#.#........
2006/07/06 16:16:27 [06ec] 00 00 00 00 41 30 32 20 0c 00 00 40 0b 1c 02 60   ....A02 ...@...`
2006/07/06 16:16:27 [06ec]
2006/07/06 16:16:27 [06ec] =>ABSL:2050 [INQUIRY         ] 12 01 80 00 40 00 00 00 00 00 tt[1]
2006/07/06 16:16:27 [06ec] ---------------INQUIRY HEX DUMP----------------
2006/07/06 16:16:27 [06ec] Hexdump Starts --------------------
2006/07/06 16:16:27 [06ec] 01 80 00 10 52 42 30 34 35 31 41 4d 43 30 35 31   ....RB0451AMC051
2006/07/06 16:16:27 [06ec] 32 33 20 20 36 30 30 20 20 20 20 20 20 20 20 20   23  600        
2006/07/06 16:16:27 [06ec] 32 33 32 33 c1 23 00 23 01 04 01 01 00 00 00 00   2323.#.#........
2006/07/06 16:16:27 [06ec] 00 00 00 00 41 30 32 20 0c 00 00 40 0b 1c 02 60   ....A02 ...@...`
2006/07/06 16:16:27 [06ec]
2006/07/06 16:16:27 [06ec] LDN:1 - *Serial Number [RB0451AMC05123  ] Found*
2006/07/06 16:16:27 [06ec] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 16:16:27 [06ec] DYNAMIC DEVICE SHARING -> NO LICENSE
2006/07/06 16:16:27 [06ec] fsdev: Windows 2003 or greater detected...
2006/07/06 16:16:27 [06ec] fsdev:InitRemovableMediaDevices() : gBTOFSDoNotUseImpersonation = [0]
2006/07/06 16:16:27 [06ec] fsdev:InitRemovableMediaDevices() : Looking for Removable Media Devices.....
2006/07/06 16:16:27 [06ec] fsdev:InitRemovableMediaDevices() : Number of Removable Media Devices Found:[ZERO]
2006/07/06 16:16:27 [06ec] Detected a total of 5 SCSI Devices
2006/07/06 16:16:27 [06ec] DB: Database initialization thread created TID[750]
2006/07/06 16:16:27 [06ec] Initializing changer(s)
2006/07/06 16:16:27 [06ec] Loading Changer.dll: Date[Aug  9 2005] Time[13:43:10]
2006/07/06 16:16:27 [06ec] Waiting for [0] Library(s) to initialize!
2006/07/06 16:16:27 [06ec] SaveChangerConfigurationThread created. ThreadID[760]
2006/07/06 16:16:27 [06ec] Done Initializing changer(s)
2006/07/06 16:16:27 [0750] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 16:16:27 [0750] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 16:16:27 [0750] DB: Connect to database [BACKUPTEST]: START
2006/07/06 16:16:27 [0750] DB: Connect to database [BACKUPTEST]: END
2006/07/06 16:16:27 [0750] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 16:16:27 [0750] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 16:16:27 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:16:27 [0760] Start SaveChangerConfigurationThread
2006/07/06 16:16:27 [0760] SaveChangerConfigurationThread: All Libraries have been initialized
2006/07/06 16:16:27 [06ec] InitializeGroupTables: Found Group[GROUP0] Type[TAPE_GROUP]
2006/07/06 16:16:27 [06ec] InitializeGroupTables:  Found Group[GROUP0] Drive[0] Device[1]
2006/07/06 16:16:27 [06ec] FSD: FSD_InitThresholdControl(), initialized successfully.
2006/07/06 16:16:27 [06ec] ClientActivateTapesvr: ExecuteQueuedDBCommands(). RemoteDB or Central DB not used.  
2006/07/06 16:16:27 [06ec] DB: ExecuteQueuedDBCommands()
2006/07/06 16:16:27 [06ec] DB: No Queued DB Commands
2006/07/06 16:16:27 [06ec] Home directory:[C:\Program Files\CA\BrightStor ARCserve Backup]
2006/07/06 16:16:27 [06ec] HostServer    :[BACKUPTEST]
2006/07/06 16:16:27 [06ec] ASWriteToASLog: NOTE[62817] [Tape Engine is Started.]
2006/07/06 16:16:29 [06ec] TapeDrive[1] ABSL:2050 <SDLT600         2323> S/N[RB0451AMC05123  ]
2006/07/06 16:16:29 [06ec] Loading Media Server
2006/07/06 16:16:29 [06ec] Started MediaSvr process.
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:29 [06ec] Info[board-0,bus-0,scsiid-1,lun-0,type-258,COMPAQ  CDR-8435        0013]
2006/07/06 16:16:29 [06ec] ABSL[0010] DeviceType[258] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 16:16:29 [06ec] ABSL[0010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:29 [06ec] ABSL[0010] muxSeekType[2] stagingPath[]
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:29 [06ec] Info[board-2,bus-0,scsiid-5,lun-0,type-5,QUANTUM SDLT600         2323]
2006/07/06 16:16:29 [06ec] ABSL[2050] DeviceType[5] Inquiry_String[QUANTUM] SerialNumber[RB0451AMC05123  ]
2006/07/06 16:16:29 [06ec] ABSL[2050] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:29 [06ec] ABSL[2050] muxSeekType[2] stagingPath[]
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:29 [06ec] Info[board-4,bus-0,scsiid-0,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 16:16:29 [06ec] ABSL[4000] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 16:16:29 [06ec] ABSL[4000] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:29 [06ec] ABSL[4000] muxSeekType[2] stagingPath[]
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:29 [06ec] Info[board-4,bus-0,scsiid-1,lun-0,type-2,Compaq  Disk Array      1.02]
2006/07/06 16:16:29 [06ec] ABSL[4010] DeviceType[2] Inquiry_String[Compaq] SerialNumber[]
2006/07/06 16:16:29 [06ec] ABSL[4010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:29 [06ec] ABSL[4010] muxSeekType[2] stagingPath[]
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:29 [06ec] Info[board-4,bus-1,scsiid-0,lun-0,type-32768,COMPAQ  SCSI COMMUNICATEDA02]
2006/07/06 16:16:29 [06ec] ABSL[4100] DeviceType[32768] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 16:16:29 [06ec] ABSL[4100] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:29 [06ec] ABSL[4100] muxSeekType[2] stagingPath[]
2006/07/06 16:16:29 [06ec] ---------- GetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:29 [06ec] ---------- SetMuxParametersFromReg:: MUX Start Info ----------------
2006/07/06 16:16:29 [06ec] ABSL[0010] DeviceType[258] Inquiry_String[COMPAQ] SerialNumber[]
2006/07/06 16:16:29 [06ec] ABSL[0010] chunkSize[1024] nChunks[4 nMaxStreams[4]
2006/07/06 16:16:29 [06ec] ABSL[0010] muxSeekType[2] stagingPath[]
2006/07/06 16:16:29 [06ec] ---------- SetMuxParametersFromReg:: MUX End Info ----------------
2006/07/06 16:16:29 [06ec] ABSL = 0 0 1 0, adapterTransferSize = 131072
2006/07/06 16:16:29 [06ec] ABSL = 2 0 5 0, adapterTransferSize = 1044480
2006/07/06 16:16:29 [06ec] ABSL = 4 0 0 0, adapterTransferSize = 69632
2006/07/06 16:16:29 [06ec] ABSL = 4 0 1 0, adapterTransferSize = 69632
2006/07/06 16:16:29 [06ec] ABSL = 4 1 0 0, adapterTransferSize = 69632
2006/07/06 16:16:34 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:16:39 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:16:45 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:16:50 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:16:58 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:04 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:09 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:14 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:19 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:24 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:29 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:17:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:17:34 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:39 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:44 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:49 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:54 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:17:59 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:04 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:09 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:14 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:19 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:24 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:29 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:18:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:18:34 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:39 [06ec] ClientActivateTapesvr:: WaitForSingleObject timed out for hDBInitThread. Thus possibility of DB dummy funcs.
2006/07/06 16:18:39 [06ec] -Total Jobs now ACTIVE: [1]-
2006/07/06 16:18:39 [06ec] CREATEJOBHANDLE[00D55898] JobID[17772] Generated for new Job Queue Handle
2006/07/06 16:18:39 [06ec] CREATEJOBHANDLE[00D55898] JobType:[64][Tape Engine ScanDeamon]
2006/07/06 16:18:39 [06ec] CREATEJOBHANDLE[00D55898] Client:[Main Job]
2006/07/06 16:18:39 [06ec] CREATEJOBHANDLE[00D55898] Connection:[Local]
2006/07/06 16:18:39 [06ec] ---------------------- Host Adapter 0 Details------------------
2006/07/06 16:18:39 [06ec] Host Adapter Vendor Name   : Generic
2006/07/06 16:18:39 [06ec] Host Adapter Name          : atapi
2006/07/06 16:18:39 [06ec] Host Adapter No            : 0
2006/07/06 16:18:39 [06ec] Host ScsiId                : 255
2006/07/06 16:18:39 [06ec] IRQ Level                  : ffffh
2006/07/06 16:18:39 [06ec] Max Transfer Length        : 131072 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] Max Physical Pages         : 32
2006/07/06 16:18:39 [06ec] Max Transfer Length 2      : 131072 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] ----------------------------------------------------------------
2006/07/06 16:18:39 [06ec] ---------------------- Host Adapter 1 Details------------------
2006/07/06 16:18:39 [06ec] Host Adapter Vendor Name   : Generic
2006/07/06 16:18:39 [06ec] Host Adapter Name          : atapi
2006/07/06 16:18:39 [06ec] Host Adapter No            : 1
2006/07/06 16:18:39 [06ec] Host ScsiId                : 255
2006/07/06 16:18:39 [06ec] IRQ Level                  : ffffh
2006/07/06 16:18:39 [06ec] Max Transfer Length        : 131072 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] Max Physical Pages         : 32
2006/07/06 16:18:39 [06ec] Max Transfer Length 2      : 131072 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] ----------------------------------------------------------------
2006/07/06 16:18:39 [06ec] ---------------------- Host Adapter 2 Details------------------
2006/07/06 16:18:39 [06ec] Host Adapter Vendor Name   : Compaq Inc.
2006/07/06 16:18:39 [06ec] Host Adapter Name          : cpq32fs2
2006/07/06 16:18:39 [06ec] Host Adapter No            : 2
2006/07/06 16:18:39 [06ec] Host ScsiId                : 255
2006/07/06 16:18:39 [06ec] IRQ Level                  : 13h
2006/07/06 16:18:39 [06ec] Max Transfer Length        : 285212655 Bytes, 271 MB
2006/07/06 16:18:39 [06ec] Max Physical Pages         : 255
2006/07/06 16:18:39 [06ec] Max Transfer Length 2      : 1044480 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] ----------------------------------------------------------------
2006/07/06 16:18:39 [06ec] ---------------------- Host Adapter 3 Details------------------
2006/07/06 16:18:39 [06ec] Host Adapter Vendor Name   : Compaq Inc.
2006/07/06 16:18:39 [06ec] Host Adapter Name          : cpq32fs2
2006/07/06 16:18:39 [06ec] Host Adapter No            : 3
2006/07/06 16:18:39 [06ec] Host ScsiId                : 255
2006/07/06 16:18:39 [06ec] IRQ Level                  : 12h
2006/07/06 16:18:39 [06ec] Max Transfer Length        : 285212655 Bytes, 271 MB
2006/07/06 16:18:39 [06ec] Max Physical Pages         : 255
2006/07/06 16:18:39 [06ec] Max Transfer Length 2      : 1044480 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] ----------------------------------------------------------------
2006/07/06 16:18:39 [06ec] ---------------------- Host Adapter 4 Details------------------
2006/07/06 16:18:39 [06ec] Host Adapter Vendor Name   : cpqarry2
2006/07/06 16:18:39 [06ec] Host Adapter Name          : cpqarry2
2006/07/06 16:18:39 [06ec] Host Adapter No            : 4
2006/07/06 16:18:39 [06ec] Host ScsiId                : 255
2006/07/06 16:18:39 [06ec] IRQ Level                  : 19h
2006/07/06 16:18:39 [06ec] Max Transfer Length        : 65536 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] Max Physical Pages         : 17
2006/07/06 16:18:39 [06ec] Max Transfer Length 2      : 69632 Bytes, 0 MB
2006/07/06 16:18:39 [06ec] ----------------------------------------------------------------
2006/07/06 16:18:39 [06ec] ----------------------Device 1 Detail-----------------------------
2006/07/06 16:18:39 [06ec] Vendor, Product, Firmware  : QUANTUM SDLT600         2323
2006/07/06 16:18:39 [06ec] Device Number              : 0
2006/07/06 16:18:39 [06ec] SCSI compliance, SCSI      : 4
2006/07/06 16:18:39 [06ec] SCSI ID                    : 5
2006/07/06 16:18:39 [06ec] LUN                        : 0
2006/07/06 16:18:39 [06ec] Host Board No              : 2
2006/07/06 16:18:39 [06ec] Device Type                : 5
2006/07/06 16:18:39 [06ec] Cartridge Type             : 248
2006/07/06 16:18:39 [06ec] TapeFlags                  : 00002000
2006/07/06 16:18:39 [06ec] Cap Flags                  : 0000004D
2006/07/06 16:18:39 [06ec] Host Adapter No            : 1
2006/07/06 16:18:39 [06ec] -----------------------------------------------------------------
2006/07/06 16:18:39 [06ec] ...in ActivateScanDaemon usNumOfDevices[5]
2006/07/06 16:18:39 [06ec] ActivateScanDaemon: Device[0] Scanning NOT on! DeviceType[0x102] Flag[0x40]
2006/07/06 16:18:39 [06ec] ActivateScanDaemon: Device[1] Scanning turned on!
2006/07/06 16:18:39 [06ec] ActivateScanDaemon: Device[2] Scanning NOT on! DeviceType[0x2] Flag[0x40]
2006/07/06 16:18:39 [06ec] ActivateScanDaemon: Device[3] Scanning NOT on! DeviceType[0x2] Flag[0x40]
2006/07/06 16:18:39 [06ec] ActivateScanDaemon: Device[4] Scanning NOT on! DeviceType[0x8000] Flag[0x0]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [LOAD TAPE       ] 1b 00 00 00 01 00 00 00 00 00 tt[30]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [MODE SENSE      ] 1a 00 00 00 0c 00 00 00 00 00 tt[5]
2006/07/06 16:18:39 [1248]    MS Page[00] ABSL:2050      0b 87 10 08 4a 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248]                ABSL:2050      00 00 00 00 00 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248]         DRV:1 Tape is NOT Write Protected
2006/07/06 16:18:39 [1248] =>ABSL:2050 [MODE SENSE      ] 1a 00 0f 00 1c 00 00 00 00 00 tt[5]
2006/07/06 16:18:39 [1248]    MS Page[0f] ABSL:2050      1b 87 10 08 4a 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248]                ABSL:2050      0f 0e c0 80 00 00 00 10 00 00 00 10
2006/07/06 16:18:39 [1248]         DRV:1 Compression Enabled
2006/07/06 16:18:39 [1248] =>ABSL:2050 [INQUIRY         ] 12 01 c0 00 40 00 00 00 00 00 tt[1]
2006/07/06 16:18:39 [1248] ---------------INQUIRY HEX DUMP----------------
2006/07/06 16:18:39 [1248] Hexdump Starts --------------------
2006/07/06 16:18:39 [1248] 01 c0 01 28 23 00 00 00 7d 0c 75 42 00 00 00 00   ...(#...}.uB....
2006/07/06 16:18:39 [1248] d8 dc 44 c6 32 39 2d 53 65 70 2d 32 30 30 34 20   ..D.29-Sep-2004
2006/07/06 16:18:39 [1248] 31 39 3a 35 33 3a 35 35 00 00 00 00 00 00 00 00   19:53:55........
2006/07/06 16:18:39 [1248] 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00   ................
2006/07/06 16:18:39 [1248]
2006/07/06 16:18:39 [1248] DEVICE1 [QUANTUM SDLT600         2323] - WORM drive
2006/07/06 16:18:39 [1248] [SDLT600         2323] WORM drive
2006/07/06 16:18:39 [1248] =>ABSL:2050 [Read Attribute  ] 8c 00 00 00 00 00 00 00 04 08 tt[2]
2006/07/06 16:18:39 [1248] NOT WORM []loaded
2006/07/06 16:18:39 [1248] WORM capable tape []loaded, Density[0x4a]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [REWIND          ] 01 00 00 00 00 00 00 00 04 08 tt[30]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [READ POSITION   ] 34 00 00 00 00 00 00 00 00 00 tt[6]
2006/07/06 16:18:39 [1248]         DRV:1 Current block #: [00000000] [0]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [MODE SENSE      ] 1a 00 00 00 0c 00 00 00 00 00 tt[5]
2006/07/06 16:18:39 [1248]    MS Page[00] ABSL:2050      0b 87 10 08 4a 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248]                ABSL:2050      00 00 00 00 00 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248]        MSelect ABSL:2050      00 00 10 08 4a 00 00 00 00 01 00 00
2006/07/06 16:18:39 [1248]                ABSL:2050      00 00 00 00 00 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248] =>ABSL:2050 [MODE SELECT     ] 15 10 00 00 0c 00 00 00 00 00 tt[5]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [MODE SENSE      ] 1a 00 00 00 0c 00 00 00 00 00 tt[5]
2006/07/06 16:18:39 [1248]    MS Page[00] ABSL:2050      0b 87 10 08 4a 00 00 00 00 01 00 00
2006/07/06 16:18:39 [1248]                ABSL:2050      00 00 00 00 00 00 00 00 00 00 00 00
2006/07/06 16:18:39 [1248] =>ABSL:2050 [READ            ] 08 01 00 00 01 00 00 00 00 00 tt[20]
2006/07/06 16:18:39 [1248]         DRV:1 Read Tape Header Sequence Number: [1]
2006/07/06 16:18:39 [1248]         DRV:1 Read Tape Header Random ID: [ACA3]
2006/07/06 16:18:39 [1248]         DRV:1 Read Tape Header Tape Name: [TEST1                   ]
2006/07/06 16:18:39 [1248]         DRV:1 Read Tape Header PreviousSessions: [0]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [REWIND          ] 01 00 00 00 00 00 00 00 00 00 tt[30]
2006/07/06 16:18:39 [1248] =>ABSL:2050 [READ POSITION   ] 34 00 00 00 00 00 00 00 00 00 tt[6]
2006/07/06 16:18:39 [1248]         DRV:1 Current block #: [00000000] [0]
2006/07/06 16:18:39 [1248] DB: DBRegisterTape is being called. DBState[1]
2006/07/06 16:18:39 [1248] DB: DBRegisterTape waiting for DB state to change [0]
2006/07/06 16:18:39 [0710] -Total Jobs now ACTIVE: [2]-
2006/07/06 16:18:39 [0710] CREATEJOBHANDLE[00D55C38] JobID[0] Generated for new Managment Handle
2006/07/06 16:18:39 [0710] CREATEJOBHANDLE[00D55C38] JobType:[8][Management]
2006/07/06 16:18:39 [0710] CREATEJOBHANDLE[00D55C38] Client:[MEDIASVR]
2006/07/06 16:18:39 [0710] CREATEJOBHANDLE[00D55C38] Connection:[Local]
2006/07/06 16:18:40 [1248] DB: DBRegisterTape waiting for DB state to change [1]
2006/07/06 16:18:41 [1248] DB: DBRegisterTape waiting for DB state to change [2]
2006/07/06 16:18:42 [1248] DB: DBRegisterTape waiting for DB state to change [3]
2006/07/06 16:18:43 [1248] DB: DBRegisterTape waiting for DB state to change [4]
2006/07/06 16:19:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:19:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:20:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:20:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:21:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:21:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:22:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:22:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:23:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:23:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:24:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:24:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:25:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:25:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:26:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:26:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:27:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:27:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:28:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:28:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:29:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:29:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:30:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:30:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:31:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:31:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:32:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:32:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:33:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:33:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:34:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:34:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:35:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:35:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:36:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:36:30 [0750] DB: Finished Registering drive[1] in database: END
2006/07/06 16:36:30 [0750] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 16:36:30 [0750] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 16:36:30 [0750] DB: Database Library not ready.
2006/07/06 16:36:30 [0750] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 16:36:30 [0750] DB:DB_GetEntryPoints: Status [1).
2006/07/06 16:36:30 [0750] DB: DB_GetEntryPoints() is returned.
2006/07/06 16:36:30 [0750] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 16:36:30 [0750] DB:: SetHooks:: Retrying. count = 1
2006/07/06 16:36:30 [0750] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 16:36:30 [0750] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 16:36:30 [0750] DB: Connect to database [BACKUPTEST]: START
2006/07/06 16:36:30 [0750] DB: Connect to database [BACKUPTEST]: END
2006/07/06 16:36:30 [0750] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 16:36:30 [0750] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 16:36:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:37:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:37:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:38:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:38:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:39:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:39:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:40:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:40:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:41:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:41:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:42:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:42:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:43:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:43:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:44:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:44:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:45:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:45:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:46:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:46:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:47:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:47:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:48:30 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:48:30 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:49:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:49:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:50:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:50:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:51:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:51:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:52:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:52:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:53:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:53:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:54:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:54:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:55:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:55:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:56:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:56:31 [0750] DB: Finished Registering drive[1] in database: END
2006/07/06 16:56:31 [0750] DB: ASDBRegisterTapeDrv: ret=[-1]. failed for Device [1]
2006/07/06 16:56:31 [0750] DB:ASDBRegisterTapeDrv: Finished Registering drive[1] in database: END
2006/07/06 16:56:31 [0750] DB: Database Library not ready.
2006/07/06 16:56:31 [0750] DB:: SetDummyEntryPoints:: Executing SetDummyEntryPoints. error = 1
2006/07/06 16:56:31 [0750] DB:DB_GetEntryPoints: Status [1).
2006/07/06 16:56:31 [0750] DB: DB_GetEntryPoints() is returned.
2006/07/06 16:56:31 [0750] DB:SetHooks: Error in DB_GetEntryPoints. error = 1
2006/07/06 16:56:31 [0750] DB:: SetHooks:: Retrying. count = 2
2006/07/06 16:56:31 [0750] DB:DB_GetEntryPoints: called ulDBState is [1]
2006/07/06 16:56:31 [0750] DB:DB_GetEntryPoints: Testing if Database is Ready.
2006/07/06 16:56:31 [0750] DB: Connect to database [BACKUPTEST]: START
2006/07/06 16:56:31 [0750] DB: Connect to database [BACKUPTEST]: END
2006/07/06 16:56:31 [0750] DB:DB_GetEntryPoints: Loaded Database Library.
2006/07/06 16:56:31 [0750] DB:DBRegisterTapeDrv: register drive [1] in database: START
2006/07/06 16:56:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:57:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:57:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:58:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:58:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 16:59:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 16:59:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:00:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:00:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:01:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:01:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:02:15 [0714] -Total Jobs now ACTIVE: [3]-
2006/07/06 17:02:15 [0714] CREATEJOBHANDLE[00D55FD8] JobID[0] Generated for new Managment Handle
2006/07/06 17:02:15 [0714] CREATEJOBHANDLE[00D55FD8] JobType:[8][Management]
2006/07/06 17:02:15 [0714] CREATEJOBHANDLE[00D55FD8] Client:[ASMGR@BACKUPTEST]
2006/07/06 17:02:15 [0714] CREATEJOBHANDLE[00D55FD8] Connection:[Local]
2006/07/06 17:02:31 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:02:31 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:03:26 [0710] Connection terminated for job 00d55fd8
2006/07/06 17:03:26 [0710] DestroyJobHandle[00D55FD8]
2006/07/06 17:03:26 [0710] ClientDisConnectFromTape: hJob[00D55FD8] Not Connected...
2006/07/06 17:03:26 [0710] Destroy JobID[0]: Active Jobs now: [2] -- jID[0], mID[0], jTKN[0]
2006/07/06 17:03:32 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:03:32 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:04:32 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:04:32 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:05:32 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:05:32 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:06:32 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:06:32 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:07:33 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:07:33 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:08:33 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:08:33 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:09:33 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:09:33 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:10:33 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:10:33 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START
2006/07/06 17:11:33 [0750] DB:DBRegisterTapeDrv: done ASDBRegisterTapeDrv(): END
2006/07/06 17:11:33 [0750] DB:DBRegisterTapeDrv: call ASDBRegisterTapeDrv(): START

Hope this is what you needed.  I really appreciate the time and effort you're putting into this!
0
 
LVL 6

Accepted Solution

by:
kbens0n earned 200 total points
ID: 17057579
Sorry that I have to be brief right now... pressing matters await. I'm still not clear on what is happening exactly or procedurally what operations were attempted just now above. Have you already attempted the SCAN of this tape's existing sessions and/or attempted a MERGE of those sessions into the VLDB database? You stated that ARCserve was fine until you went to operations with this tape. What did you mean by fine? What other operations did you succeed at or what gave you cause to believe it is just this tape? and you were faced with the "hanging" issue of this tape, but it sounds like subsequent formatting of a blank and also... whatever operation just produced the above logging are beyond a hang, at least? The key to getting the tape off that server backup is the original database with session info for that tape -or- merging the session info from this tape into the "new" database so you can Restore with the necessary "filtering".

Here's a link to some considerations for little things that "bite":
http://supportconnectw.ca.com/premium/storage/infodocs/basb11win/TEC342813.pdf

Here's a link to info about an alternative test approach for problem understanding:
http://supportconnect.ca.com/sc/kb/techdetail.jsp?searchID=TEC266962&docid=266962&bypass=yes&fromscreen=kbresults

Looks like C:\Program Files\CA\BrightStor ARCserve Backup\LOG\ is the magic path to be perusing.

Sorry, so short , Iwon't be back until late, but will check in again tomorrow some time. My apologies!
0
 

Author Comment

by:alendozo
ID: 17073240
Hi kbens0n - the first link you provided recomended several things to look at to fix my problem.  One of them was to try installing the latest firmware updates.  This was all I needed to get the tape drive recognized correctly.  I was able to do a test format, backup and restore on a brand new tape.  I feel confident that I'll be able to access the tapes I need to go through to try and recover my user's lost folder.

Thanks for all your help, but most of all for your endless patience!

I really appreciate Experts like you who hang in there for newbies like me.

Al
0
 
LVL 6

Expert Comment

by:kbens0n
ID: 17075856
That sounds very encouraging, Al ! Glad something in the info has moved you along. Thanks for the kind words... they are appresciated.
0

Featured Post

Complete Microsoft Windows PC® & Mac Backup

Backup and recovery solutions to protect all your PCs & Mac– on-premises or in remote locations. Acronis backs up entire PC or Mac with patented reliable disk imaging technology and you will be able to restore workstations to a new, dissimilar hardware in minutes.

Join & Write a Comment

If your app took Google’s lash recently, here are the 5 most likely reasons.
The article will include the best Data Recovery Tools along with their Features, Capabilities, and their Download Links. Hope you’ll enjoy it and will choose the one as required by you.
This video will demonstrate how to find the puppet warp tool from the edit menu and where to put the points to edit.
An overview on how to enroll an hourly employee into the employee database and how to give them access into the clock in terminal.

759 members asked questions and received personalized solutions in the past 7 days.

Join the community of 500,000 technology professionals and ask your questions.

Join & Ask a Question

Need Help in Real-Time?

Connect with top rated Experts

17 Experts available now in Live!

Get 1:1 Help Now