cancel
Showing results for 
Search instead for 
Did you mean: 

AOFO & Cluster Disks

Lawrence_Kosin
Level 4
How should AOFO be configured to handle SAN disks that are part of a MS Cluster???
Even though I have AOFO installed, I get the following error when trying to back them up.

AOFO: Initialization failure on: "\\SERVER\DISK".
Advanced Open File Option used:
VERITAS Volume Snapshot Provider (VSP).
Snapshot provider error (0xE0008538): The volume cannot be snapped using Advanced Open File Option (AOFO) because it is not a drive that is permanently attached to the target computer.
26 REPLIES 26

Renuka_-
Level 6
Employee
Hello,


Verify that the drive selected for backup with the VERITAS Advanced Open File Option is not a CD-ROM, floppy diskette, or other removable media.


NOTE : If we do not receive your reply within two business days, this post would be marked assumed answeredand would be moved toanswered questions pool.

Lawrence_Kosin
Level 4
Give me a break!
Does anyone bother to even read the post??
It's a CLUSTER DISK!!
It isn't 'permanent' because it could posible be assigned to either nodes of a MS cluster. I have the job pointing to the Cluster resource, NOT the physical server.
This worked fine and was the recomended procedure under V-9.1.
Under V-10.0, BE is complaining.
To verify, it is NOT a CD-Rom, Floppy or other removable media.

Renuka_-
Level 6
Employee
hello,

Is it a two node cluster and have you installed aofo on both nodes in the cluster?

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answeredand would be moved toanswered questions pool.

uz
Level 6
Partner Accredited Certified
just a hint if you did not yet check this:
no matter whether the cluster is also the lokal Backup server or a remote server, - the AOFO config needs to be done for both physical cluster nodes - NOT for the virtual servers, that do actually hold the volumes.

Insure the AOFO wizard did run for the nodes, not the virtual servers.
It should work generally.

regards

uz

tejashree_Bhate
Level 6
Hello,


AOFO creates the snapshot of the drive and not the share
You will have to select the actual drive from the share and then run the backup.

Thanks,

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.

Lawrence_Kosin
Level 4
Sorry for the delay but I was on vacation.

AOFO is on both nodes.

Still not celar what it is complaining about

Renuka_-
Level 6
Employee
Hello Lawrence,

We would like to verify that this is a remote backup.

Please try uninstalling ans re-installing AOFO on both the nodes.

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answeredand would be moved toanswered questions pool.

Lawrence_Kosin
Level 4
I will re-install AOFO and will run some tests but it is a real pain since each node needs to be rebooted after removal and again after installation.

Amruta_Purandar
Level 6
Hello,

Do perform the tests and revert with the results.

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.

Lawrence_Kosin
Level 4
I've re-installed the AOFO and installed all the latest patches but the exact same problem still exists.

AOFO: Initialization failure on: "\\SERVER\DISK".
Advanced Open File Option used:
VERITAS Volume Snapshot Provider (VSP).
Snapshot provider error (0xE0008538): The volume cannot be snapped using Advanced Open File Option (AOFO) because it is not a drive that is permanently attached to the target computer.

priya_khire
Level 6
Hi Lawrence,

What is the OS on the cluster servers and what are you backing up in this job with AOFO? Is backup exec outside the cluster or within it?

Try the following steps:
- go to tools-wizards- AOFO wizard
- change the minimum quiet time to 3 secs.

Rerun the job and test results. If the issue persists, revert with details on the above questions.

Note : If we do not receive your reply within two business days, this post would be marked ‘assumed answered’ and would be moved to ‘answered questions’ pool.

Regards.

Lawrence_Kosin
Level 4
All cluster servers are Windows 2003 Server (fully patched)

The backup server is also Server 2003 (fully patched) and it is outsid ethe cluster.

I will reset minimum quiet time and retest.

Lawrence_Kosin
Level 4
I reset the minimum quiet time to 3 seconds and ran some tests. I get the same error message.

Snapshot provider error (0xE0008538): The volume cannot be snapped using Advanced Open File Option (AOFO) because it is not a drive that is permanently attached to the target computer.
Check the Windows Event Viewer for details.

Note: The Windows Event Viewer does NOT show any errors.

Ashutosh_Tamhan
Level 6
Hello !

Does the backup of that drive from the individual cluster node work?


Regards,
Ashutosh

NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.

Lawrence_Kosin
Level 4
If I run the backup to the physical server that the disk is assigned to, it runs properly with no errors.

The reason I run it to the 'virtuial' cluster node is so I don't have to worry about which physical server has the disk at that point in time.

Amruta_Purandar
Level 6
Hello,

- Are you backing up any databases?
- If yes are you using agents to back them up?


NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.

Lawrence_Kosin
Level 4
No databases of any kind.

This doesn't happen on just one cluster node but on all four of my cluster nodes.

Amruta_Purandar
Level 6
Hello,

Please create a debug log when you run the AOFO backup. Revert with the relevant part of the log.
Title:
How to use the SGMON utility to perform a live debug of Backup Exec
http://support.veritas.com/docs/190979


NOTE : If we do not receive your reply within two business days, this post would be marked assumed answered and would be moved to answered questions pool.

Lawrence_Kosin
Level 4
Capturing to C:\Program Files\VERITAS\Backup Exec\NT\logs\SGMon.log
cqmghost: pdhResult of first call for ReadBuffer = -2147481646
cqmghost: pdhResult of first call for WriteBuffer = -2147481646
cqmghost: ReadBuffer = 0.002899
cqmghost: WriteBuffer = 0.000197
cqmghost: AvgDisksecPerReadx10000 = 0
cqmghost: AvgDisksecPerWritex10000 = 0
cqmghost: ReadBuffer = 0.002899
cqmghost: WriteBuffer = 0.000197
cqmghost: AvgDisksecPerReadx10000 = 1
cqmghost: AvgDisksecPerWritex10000 = 1
beserver: 09/06/05 08:03:56 -1 SQLLog(13685):AgeSession m_threadMap: SessionThreadID:9b8, CurrentThreadID:4d8
bengine: 09/06/05 08:04:33 DeviceManager: timeout event fired
bengine: 09/06/05 08:04:33 DeviceManager: processing pending requests
bengine: 09/06/05 08:04:33 DeviceManager: going to sleep for 900000 msecs
cqmghost: pdhResult of first call for ReadBuffer = -2147481646
cqmghost: pdhResult of first call for WriteBuffer = -2147481646
cqmghost: ReadBuffer = 0.003891
cqmghost: WriteBuffer = 0.000172
cqmghost: AvgDisksecPerReadx10000 = 0
cqmghost: AvgDisksecPerWritex10000 = 0
cqmghost: ReadBuffer = 0.003891
cqmghost: WriteBuffer = 0.000172
cqmghost: AvgDisksecPerReadx10000 = 1
cqmghost: AvgDisksecPerWritex10000 = 1
beserver: 09/06/05 08:04:43 23 JobLogBO: vwJobHistory with Version in XML=
yNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
="108
beserver: 411448" TotalRateMBMin="35" TotalNumberOfFiles="58" TotalNumberOfDirectories="10" TotalSkippedFiles="71" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684218" JobHistoryDetailID="53EA6C16-EAE8-4F58-9E19-EBD509CA665E" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="848188180" FamilySeqNumber="0" FamilyGUID="DC6E87F4-059C-4323-A811-DC70CD06A089" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 R Incremental" ResourceTypeID="0" ResourceSubType="0" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="108411448" RateMBMin="35" NumberOfFiles="58" NumberOfDirectories="10" NumberOfSkippedFiles="71" NumberOfCorruptFiles="0" NumberOfInUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.St
artTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00149.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-0
6T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
talDataSizeBytes="108411448" TotalRateMBMin="35" TotalNumberOfFiles="58" TotalNumberOfDirectories="10" TotalSkippedFiles="71" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684218" JobHistoryDetailID="53EA6C16-EAE8-4F58-9E19-EBD509CA665E" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="848188180" FamilySeqNumber="0" FamilyGUID="DC6E87F4-059C-4323-A811-DC70CD06A089" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 R Incremental" ResourceTypeID="0" ResourceSubType="0" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="108411448" RateMBMin="35" NumberOfFiles="58" NumberOfDirectories="10" NumberOfSkippedFiles="71" NumberOfCorruptFiles="0" NumberOfInUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount
="0"
beserver: JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00151.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTim
eComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
HD.St
beserver: artTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00243.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" Agen
tAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
D.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00251.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMedi
aSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
Used=
beserver: "0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00132.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceNam
e="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
ame="C:\Program Files\VERITAS\Backup Exec\NT\Data\BEX_PSBEH15_21620.xml" TotalDataSizeBytes="108411448" TotalRateMBMin="35" TotalNumberOfFiles="58" TotalNumberOfDirectories="10" TotalSkippedFiles="71" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684218" JobHistoryDetailID="53EA6C16-EAE8-4F58-9E19-EBD509CA665E" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="848188180" FamilySeqNumber="0" FamilyGUID="DC6E87F4-059C-4323-A811-DC70CD06A089" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 R Incremental" ResourceTypeID="0" ResourceSubType="0" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="108411448" RateMBMin="35" NumberOfFiles="58" NumberOfDirectories="10" NumberOfSkippedFiles="71" NumberOfCorruptFiles="0" NumberOfInUseFiles="0" O
FOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00143.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTarg
etDev
beserver: iceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
InUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00147.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Acco
unt" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
ame="
beserver: ACADCL2_R" JobLogFileName="C:\Program Files\VERITAS\Backup Exec\NT\Data\BEX_PSBEH15_21620.xml" TotalDataSizeBytes="108411448" TotalRateMBMin="35" TotalNumberOfFiles="58" TotalNumberOfDirectories="10" TotalSkippedFiles="71" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684218" JobHistoryDetailID="53EA6C16-EAE8-4F58-9E19-EBD509CA665E" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="848188180" FamilySeqNumber="0" FamilyGUID="DC6E87F4-059C-4323-A811-DC70CD06A089" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 R Incremental" ResourceTypeID="0" ResourceSubType="0" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="108411448" RateMBMin="35" NumberOfFiles="58" NumberOfDirectories="10" NumberOfSkippedFiles="71" NumberOfCorruptFi
les="0" NumberOfInUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:13:33:000AM" JHD.EndTime="9 6 2005 6:16:26:000AM" ResourceID="C73F131B-F837-4541-B1C6-F03742A79E5F" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684211" InfoType="1" InfoSubType="1" Data="\\PSBDPRINTSRVR\R_Disk$\spool\FP00191.SHD" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\PSBDPRINTSRVR" DisplayName="R_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Back
up Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:13:33" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>
OfCor
beserver: ruptFiles="0" NumberOfInUseFiles="0" O
beserver: 09/06/05 08:04:46 23 JobLogBO:Tranform End Transformed String Size=37176
beserver: 09/06/05 08:04:55 23 JobLogBO: vwJobHistory with Version in XML=
TotalRateMBMin="12" TotalNumberOfFiles="2" TotalNumberOfDirectories="9" TotalSkippedFiles="0" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684240" JobHistoryDetailID="D1B4F103-7F20-4CBC-9592-928467B8DC5C" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="848188180" FamilySeqNumber="0" FamilyGUID="DC6E87F4-059C-4323-A811-DC70CD06A089" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 Q Incremental" ResourceTypeID="1" ResourceSubType="2" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="1337068" RateMBMin="12" NumberOfFiles="2" NumberOfDirectories="9" NumberOfSkippedFiles="0" NumberOfCorruptFiles="0" NumberOfInUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 6:20:5
9:000AM" JHD.EndTime="9 6 2005 6:21:05:000AM" ResourceID="34112010-B8FB-4F27-895E-550F611547CA" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684235" InfoType="3" InfoSubType="0" Data="ATE755S" DataGUID="2DA42C68-9D43-48B4-919D-02C7DE26DD43" ServerName="\\ACADCL2" DisplayName="Q_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T06:20:59" RetryNumber="0" MaxRetries="0" FinalDisposition="0" Co
pyJobSubType="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>

beserver: 09/06/05 08:04:57 23 JobLogBO:Tranform End Transformed String Size=13769
beserver: 09/06/05 08:05:11 -1 Registered virtual array rowset(1334): Client:CCRGWY01777, Type:MD_OBJTYPE_JOBHISTORYVIEW
beserver: 09/06/05 08:05:27 17 JobManager::UpdateJobInstanceData() new task state=22
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - Parent = NULL , Entity = MACHINE
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - hr = 0x0
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - Parent = NULL , Entity = DEVICE_POOL
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - hr = 0x0
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - hr = 0x0
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = BACKUP_TO_DISK
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - hr = 0x0
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE_POOL_DRIVE
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - hr = 0x0
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = CHANGER
beserver: 09/06/05 08:05:27 12 AdammAdminBO::Query() - hr = 0x0
beserver: 09/06/05 08:05:27 -1 ActiveState::doDispatch( :( 'ACADCL2 Q Full'
beserver: 09/06/05 08:05:27 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
beserver: 09/06/05 08:05:27 -1 LocalSvr, runTask():{d54b1c15-d116-473f-ad74-001e1235987f}
beserver: 09/06/05 08:05:27 -1 BengineWrapper runtask() start
beserver: 09/06/05 08:05:27 -1 BengineWrapper JobEngine.Open() OK
beserver: 09/06/05 08:05:27 -1 Client requested key.
beserver: 09/06/05 08:05:27 -1 Client 'PSBEH15' connected('','PSU-ERIE\Manager'): 0x64feff8
bengine: 09/06/05 08:05:27 JobEngine::LaunchJob
bengine: 09/06/05 08:05:27 RunJobWithGuidAndServer( {D54B1C15-D116-473F-AD74-001E1235987F}, PSBEH15 ) = 0
bengine: 09/06/05 08:05:27 Thread for job ACADCL2 Q Full ({D54B1C15-D116-473F-AD74-001E1235987F}) starting
beserver: 09/06/05 08:05:27 -1 BengineWrapper JobEngine.RunJobWithGuidAndServer() rc=0
beserver: 09/06/05 08:05:27 -1 JobManager: BengineWrapper RunJob() done
beserver: 09/06/05 08:05:27 11 LocalSvr, RunTask: EngineHandedJob SUCCESS, jobid:{d54b1c15-d116-473f-ad74-001e1235987f}.
beserver: 09/06/05 08:05:27 -1 Client requested key.
beserver: 09/06/05 08:05:27 -1 Client 'PSBEH15' connected('','PSU-ERIE\Manager'): 0x47e6ba8
beserver: 09/06/05 08:05:27 -1 Client 'PSBEH15' Disconnected:0x47e6ba8
bengine: 09/06/05 08:05:27 Checking for Evaluation expiration.
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xe
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\ACADCL2\Q_Disk$
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\ACADCL2\Q_Disk$
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xe
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\ACADCL2\Q_Disk$
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\ACADCL2\Q_Disk$
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: ConvertDeviceName osId = 0Xff
bengine: 09/06/05 08:05:27 ENGSCRPT: IN - oldDeviceName = \\*\*
bengine: 09/06/05 08:05:27 ENGSCRPT: OUT - newName = \\*\*
bengine: 09/06/05 08:05:27 DeviceManager: incoming event fired
bengine: 09/06/05 08:05:27 DeviceManager: processing pending requests
bengine: 09/06/05 08:05:27 DeviceManager: query cache miss
bengine: 09/06/05 08:05:27 Available and held drives from the pool
bengine: 09/06/05 08:05:27 SDLT_0 {f0b826a1-a939-40dc-85e3-989b2aa7fc5c}
bengine: 09/06/05 08:05:27 SDLT_1 {28d0bc3e-e317-4f2f-93d2-8fd92ffe5ed2}
bengine: 09/06/05 08:05:27 SDLT_2 {a613d8e5-41cb-4155-9434-71afd58d23c2}
bengine: 09/06/05 08:05:27 SDLT_3 {783c06f5-0395-49f8-91f4-b72984316961}
bengine: 09/06/05 08:05:27 Drives not in use by the Engine
bengine: 09/06/05 08:05:27 SDLT_0 {f0b826a1-a939-40dc-85e3-989b2aa7fc5c}
bengine: 09/06/05 08:05:27 SDLT_1 {28d0bc3e-e317-4f2f-93d2-8fd92ffe5ed2}
bengine: 09/06/05 08:05:27 SDLT_2 {a613d8e5-41cb-4155-9434-71afd58d23c2}
bengine: 09/06/05 08:05:27 SDLT_3 {783c06f5-0395-49f8-91f4-b72984316961}
bengine: 09/06/05 08:05:27 Drives available (not in use and not held)
bengine: 09/06/05 08:05:27 SDLT_0 {f0b826a1-a939-40dc-85e3-989b2aa7fc5c}
bengine: 09/06/05 08:05:27 SDLT_1 {28d0bc3e-e317-4f2f-93d2-8fd92ffe5ed2}
bengine: 09/06/05 08:05:27 SDLT_2 {a613d8e5-41cb-4155-9434-71afd58d23c2}
bengine: 09/06/05 08:05:27 SDLT_3 {783c06f5-0395-49f8-91f4-b72984316961}
bengine: 09/06/05 08:05:27 Handing out session {ab549098-e45e-4327-a17c-21bcff5857fb} based on available drive:
bengine: 09/06/05 08:05:27 SDLT_0 {f0b826a1-a939-40dc-85e3-989b2aa7fc5c}
bengine: 09/06/05 08:05:27 DeviceManager: returned result to caller 0x00000000
bengine: 09/06/05 08:05:27 DeviceManager: going to sleep for 900000 msecs
bengine: 09/06/05 08:05:27 BackupJob: acquired device SDLT_0
bengine: 09/06/05 08:05:27 BackupJob: trying to mount appending volume
beserver: 09/06/05 08:05:32 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
beserver: 09/06/05 08:05:56 -1 SQLLog(13686):AgeSession m_threadMap: SessionThreadID:4d8, CurrentThreadID:7d0
bengine: 09/06/05 08:06:07 Updating session {ab549098-e45e-4327-a17c-21bcff5857fb} with drive SDLT_0 {f0b826a1-a939-40dc-85e3-989b2aa7fc5c}
bengine: 09/06/05 08:06:07 Block size: 65536
bengine: 09/06/05 08:06:07 Buffer size: 65536
bengine: 09/06/05 08:06:07 Num buffers: 20
bengine: 09/06/05 08:06:07 High water: 0
bengine: 09/06/05 08:06:07 BackupJob: medium successfully mounted
bengine: 09/06/05 08:06:07 Media Label: ATE745S
bengine: 09/06/05 08:06:07 Media Label: {0eeaeede-c139-4dca-ad77-47e114789a0c}
bengine: 09/06/05 08:06:07 Overwrite Protected Until: 12/15/2005 01:29:06
bengine: 09/06/05 08:06:07 Appendable Until: 9/19/2005 03:07:06
bengine: 09/06/05 08:06:07 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 09/06/05 08:06:07 TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 09/06/05 08:06:07 TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 09/06/05 08:06:07 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 09/06/05 08:06:07 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 09/06/05 08:06:07 Failed to Load Software\VERITAS\Backup Exec\Engine\IDR key, We will update DR file evenif there are userExcludes. Error: 2
bengine: 09/06/05 08:06:07 Failed to Load Software\VERITAS\Backup Exec\Engine\IDR key, We will update DR file evenif there are userExcludes. Error: 2
bengine: 09/06/05 08:06:07 ndmpConnect : Control Connection information : connection established between IP 146.186.52.209, port 4296 and IP 146.186.55.233, port 10000
bengine: 09/06/05 08:06:07 NDMP version 3 connection CONNECTED
beserver: 09/06/05 08:06:07 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
bengine: 09/06/05 08:06:07 BESC: Parsing OS version info -
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare: adding selection string for device_name: \\ACADCL2\Q_Disk$
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\*.*",s,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\613BCFB7-42AE-46AD-8B23-4D9968318580}.U01",s,x,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\pagefile.sys",s,x,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\outlook.pst",s,x,v0,t0,l0,n256,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\RECYCLER\*.*",s,x,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\outlook.pst",s,x,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\**\*.vsp",s,x,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\Backup Exec AOFO Store\*.*",s,x,v0,t0,l0,n0,f0
bengine: 09/06/05 08:06:07 ndmpcSnapshotPrepare2: selection string: "\\ACADCL2\Q_Disk$\*.dmp",s,x,v0,t0,l0,n0,f0
cqmghost: pdhResult of first call for ReadBuffer = -2147481646
cqmghost: pdhResult of first call for WriteBuffer = -2147481646
cqmghost: ReadBuffer = 0.007800
cqmghost: WriteBuffer = 0.000268
cqmghost: AvgDisksecPerReadx10000 = 0
cqmghost: AvgDisksecPerWritex10000 = 0
cqmghost: ReadBuffer = 0.007800
cqmghost: WriteBuffer = 0.000268
cqmghost: AvgDisksecPerReadx10000 = 1
cqmghost: AvgDisksecPerWritex10000 = 1
bengine: 09/06/05 08:06:54 ndmpcSnapshotPrepare2: Snap shot error. ErrorCode: -536836808 ErrorLevel: 2 Provider: 3
bengine: 09/06/05 08:06:54 TF_OpenTape()
bengine: 09/06/05 08:06:54 TF_GetDriveContext ...
sessionGuid = {ab549098-e45e-4327-a17c-21bcff5857fb}
initAsync = 1
bengine: 09/06/05 08:06:54 HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 09/06/05 08:06:54 GET_DRV_INF: bsize = 65536
bengine: 09/06/05 08:06:54 HARDWARE COMPRESSION ===> Setting compression off.
bengine: 09/06/05 08:06:54 TF_GetDriveContext returning 0, context = 1C76008
bengine: 09/06/05 08:06:54 Allocated 2 buffers, size 65536 bytes, total used: 131256
bengine: 09/06/05 08:06:54 HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 09/06/05 08:06:54 GET_DRV_INF: bsize = 65536
bengine: 09/06/05 08:06:54 Media Server to initiate connection for data transfer
bengine: 09/06/05 08:06:54 TF_OpenSet( )
bengine: 09/06/05 08:06:54 FreeFormatEnv( cur_fmt=65535 )
bengine: 09/06/05 08:06:54 RewindDrive mover ret = 0 (0x0)
bengine: 09/06/05 08:06:54 ret_val = 0
bengine: 09/06/05 08:06:54 Requested Set: ID = ffffffff Seq = -1 Set = -1
bengine: 09/06/05 08:06:54 Attempting to VCB storage media
bengine: 09/06/05 08:06:54 TpRead( :(
bengine: 09/06/05 08:06:55 Error = 0 Req = 65536 Got = 65536
bengine: 09/06/05 08:06:55 HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 09/06/05 08:06:55 GET_DRV_INF: bsize = 65536
bengine: 09/06/05 08:06:55 FreeFormatEnv( cur_fmt=65535 )
bengine: 09/06/05 08:06:55 SetupFormatEnv( fmt=0 )
bengine: 09/06/05 08:06:55 TpRead( :(
bengine: 09/06/05 08:06:55 Error = 0 Req = 65536 Got = 65536
bengine: 09/06/05 08:06:55 ReadThisSet( cur_fmt=0 )
bengine: 09/06/05 08:06:55 ReadThisSet() return=0
bengine: 09/06/05 08:06:55 Current VCB: ID = 32ba070c Seq = 12 Set = 1329
bengine: 09/06/05 08:06:55 PositionAtSet( :( TF Msg = b
bengine: 09/06/05 08:06:55 UI Msg = 800f
bengine: 09/06/05 08:06:55 HARDWARE COMPRESSION ===> Compression is configurable.
bengine: 09/06/05 08:06:55 GET_DRV_INF: bsize = 65536
bengine: 09/06/05 08:06:55 F40_SeekEOD starting seek...
bengine: 09/06/05 08:07:50 F40_SeekEOD calling OTC_GetPrevSM with expect_sm = 1 ...
bengine: 09/06/05 08:07:50 OTC_GetPrevSM spacing back two filemarks...
bengine: 09/06/05 08:07:50 OTC_GetPrevSM: ESET position = 6064278
bengine: 09/06/05 08:07:51 OTC_GetPrevSM calling OTC_SMtoFile...
bengine: 09/06/05 08:07:51 SetupFormatEnv( fmt=0 )
bengine: 09/06/05 08:07:51 End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 0
bengine: 09/06/05 08:07:51 HARDWARE COMPRESSION ===> Setting compression on.
bengine: 09/06/05 08:07:51 Current Block is = 5c8a94
bengine: 09/06/05 08:07:51 TF_InitMediaServerReverseConnection : Data Connection information : connection established between IP 146.186.52.209, port 4315 and IP 146.186.55.233, port 1025
beserver: 09/06/05 08:07:56 -1 SQLLog(13687):AgeSession m_threadMap: SessionThreadID:7d0, CurrentThreadID:b74
beserver: 09/06/05 08:07:56 -1 SQLLog(13688):AgeSession m_threadMap: SessionThreadID:974, CurrentThreadID:b74
beserver: 09/06/05 08:07:56 -1 SQLLog(13689):AgeSession m_threadMap: SessionThreadID:b78, CurrentThreadID:b74
beserver: 09/06/05 08:07:56 -1 SQLLog(13690):AgeSession m_threadMap: SessionThreadID:bfc, CurrentThreadID:b74
beserver: 09/06/05 08:07:56 -1 SQLLog(13691):AgeSession m_threadMap: SessionThreadID:d64, CurrentThreadID:b74
bengine: 09/06/05 08:08:36
dataStartBackup: ndmpSendRequest returned: 0x0, 0
beserver: 09/06/05 08:08:39 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
beserver: 09/06/05 08:08:39 12 AdammAdminBO::Get() - hr = 0x0
bengine: 09/06/05 08:08:40 TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0
bengine: 09/06/05 08:08:40 NDMPEngine::MessagePumpAndWaitForResults(): TF_NDMPGetResult() returned 0
bengine: 09/06/05 08:08:40 data halted: SUCCESSFUL
bengine: 09/06/05 08:08:40 NDMPEngine: Shutting down.
bengine: 09/06/05 08:08:42 WriteEndSet( 1 ) returning 0
bengine: 09/06/05 08:08:44 WriteEndSet( 1 ) returning 0
bengine: 09/06/05 08:08:44 WriteEndSet( 0 ) returning 0
bengine: 09/06/05 08:08:44 HARDWARE COMPRESSION ===> Setting compression off.
bengine: 09/06/05 08:08:45 TF_CloseSet
cqmghost: pdhResult of first call for ReadBuffer = -2147481646
cqmghost: pdhResult of first call for WriteBuffer = -2147481646
cqmghost: ReadBuffer = 0.011600
cqmghost: WriteBuffer = 0.000225
cqmghost: AvgDisksecPerReadx10000 = 0
cqmghost: AvgDisksecPerWritex10000 = 0
cqmghost: ReadBuffer = 0.011600
cqmghost: WriteBuffer = 0.000225
cqmghost: AvgDisksecPerReadx10000 = 1
cqmghost: AvgDisksecPerWritex10000 = 1
bengine: 09/06/05 08:09:32 RewindDrive mover ret = 0 (0x0)
bengine: 09/06/05 08:09:32 ret_val = 0
bengine: 09/06/05 08:09:32 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 09/06/05 08:09:32 TAPEALERT: TapeAlert Device Flag = 0X0
bengine: 09/06/05 08:09:32 TAPEALERT: TapeAlert Changer Flag = 0X0
bengine: 09/06/05 08:09:32 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 09/06/05 08:09:32 TAPEALERT: Get TapeAlert Flags Return Code = 0X0
bengine: 09/06/05 08:09:32 DeviceManager: incoming event fired
bengine: 09/06/05 08:09:32 Updating session {ab549098-e45e-4327-a17c-21bcff5857fb} with drive SDLT_0 {f0b826a1-a939-40dc-85e3-989b2aa7fc5c}
bengine: 09/06/05 08:09:32 DeviceManager: processing pending requests
bengine: 09/06/05 08:09:32 DeviceManager: going to sleep for 301000 msecs
bengine: 09/06/05 08:09:32 BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary
beserver: 09/06/05 08:09:32 17 JobManager::HandleEndJob() called
beserver: 09/06/05 08:09:32 -1 ActiveState::doEndEvent( :( 'ACADCL2 Q Full'
beserver: 09/06/05 08:09:32 17 JobManager::DoJobCompletionTasks(ACADCL2 Q Full): GetFinalJobStatus()=19
beserver: 09/06/05 08:09:32 18 Alert Job Completed with Exceptions, Notify: No, EventLog: Yes, Trap: No
beserver: 09/06/05 08:09:32 -1 Released virtual array rowset(1334).
beserver: 09/06/05 08:09:32 -1 ActiveState::doEndEvent( :( CJobManager::DoJobCompletionTasks() returned 0x0
bengine: 09/06/05 08:09:32 Job thread terminating
beserver: 09/06/05 08:09:32 -1 Client 'PSBEH15' Disconnected:0x64feff8
beserver: 09/06/05 08:09:32 -1 Registered virtual array rowset(1336): Client:CCRGWY01777, Type:MD_OBJTYPE_JOBHISTORYVIEW
beserver: 09/06/05 08:09:37 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
beserver: 09/06/05 08:09:56 -1 SQLLog(13692):AgeSession m_threadMap: SessionThreadID:974, CurrentThreadID:ca8
beserver: 09/06/05 08:09:56 -1 SQLLog(13693):AgeSession m_threadMap: SessionThreadID:b74, CurrentThreadID:ca8
beserver: 09/06/05 08:10:32 23 JobLogBO: vwJobHistory with Version in XML=
ateMBMin="21" TotalNumberOfFiles="21" TotalNumberOfDirectories="9" TotalSkippedFiles="0" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684272" JobHistoryDetailID="829668D2-8B54-4041-9CBA-D3F5738702DA" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="851052300" FamilySeqNumber="0" FamilyGUID="DBB74EA9-328F-4BA1-B5D3-ECFDB29519B3" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 Q Full" ResourceTypeID="1" ResourceSubType="2" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="2299664" RateMBMin="21" NumberOfFiles="21" NumberOfDirectories="9" NumberOfSkippedFiles="0" NumberOfCorruptFiles="0" NumberOfInUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 12:08:39:000PM" JH
D.EndTime="9 6 2005 12:08:45:000PM" ResourceID="34112010-B8FB-4F27-895E-550F611547CA" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684267" InfoType="3" InfoSubType="0" Data="ATE745S" DataGUID="0EEAEEDE-C139-4DCA-AD77-47E114789A0C" ServerName="\\ACADCL2" DisplayName="Q_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T12:08:39" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubTyp
e="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>

beserver: 09/06/05 08:10:34 23 JobLogBO:Tranform End Transformed String Size=13740
beserver: 09/06/05 08:10:46 23 JobLogBO: vwJobHistory with Version in XML=
ateMBMin="21" TotalNumberOfFiles="21" TotalNumberOfDirectories="9" TotalSkippedFiles="0" TotalCorruptFiles="0" TotalInUseFiles="0" UtilityTaskSubType="0" EraseType="0" SlotNumber="0" SlotCount="0" TimeStamp="3684272" JobHistoryDetailID="829668D2-8B54-4041-9CBA-D3F5738702DA" ADJNumDomainsProcessed="0" ADJNumServersProcessed="0" ADJNumResourcesProcessed="0" ADJNumResourcesNotProtected="0" MediaGUID="00000000-0000-0000-0000-000000000000" MediaSide="0" FamilyID="851052300" FamilySeqNumber="0" FamilyGUID="DBB74EA9-328F-4BA1-B5D3-ECFDB29519B3" LoginAccountName="psu-erie\backup" MediaLabel="" Barcode="" SetName="" SetDescription="ACADCL2 Q Full" ResourceTypeID="1" ResourceSubType="2" JobType="200" JobStatus="24" ErrorCode="0" ErrorStatus="0" DataSizeBytes="2299664" RateMBMin="21" NumberOfFiles="21" NumberOfDirectories="9" NumberOfSkippedFiles="0" NumberOfCorruptFiles="0" NumberOfInUseFiles="0" OFOUsed="3" ImageUsed="0" JHD.EraseType="0" JHD.SlotNumber="0" JHD.SlotCount="0" JHD.StartTime="9 6 2005 12:08:39:000PM" JH
D.EndTime="9 6 2005 12:08:45:000PM" ResourceID="34112010-B8FB-4F27-895E-550F611547CA" LoginAccountID="B6F4328A-0C5C-4F11-8158-C2A5F2F222FF" TestRunSubJobStatus="0" TestRunSubJobID="00000000-0000-0000-0000-000000000000" TestRunRecordType="0" TestRunMediaOnlineNearline="0" TestRunOverwriteAppend="0" TestRunLoginStatus="0" TestRunTargetID="00000000-0000-0000-0000-000000000000" TestRunMediaSet="00000000-0000-0000-0000-000000000000" TestRunOnlineAppendKB="0" TestRunTotalAppendKB="0" TestRunOnlineOverwriteKB="0" TestRunTotalOverwriteKB="0" TestRunEngineJobLogFilename="" JHD.TimeStamp="3684267" InfoType="3" InfoSubType="0" Data="ATE745S" DataGUID="0EEAEEDE-C139-4DCA-AD77-47E114789A0C" ServerName="\\ACADCL2" DisplayName="Q_Disk$" FinalErrorDescription="" CategoryDescription="" FinalErrorDescriptionDetail="" Description="Backup Services Account" TestRunTargetDeviceName="" TestRunMediaSetName="" AgentAccelUsed="1" JHD.StartTimeComp="2005-09-06T12:08:39" RetryNumber="0" MaxRetries="0" FinalDisposition="0" CopyJobSubTyp
e="0" ObjectName="" Expr1="" UMIOriginatorDetail="0" UMIOriginatorSummary="79" AbortUserName=""/>

beserver: 09/06/05 08:10:56 23 JobLogBO:Tranform End Transformed String Size=13740
cqmghost: pdhResult of first call for ReadBuffer = -2147481646
cqmghost: pdhResult of first call for WriteBuffer = -2147481646
cqmghost: ReadBuffer = 0.016200
cqmghost: WriteBuffer = 0.000258
cqmghost: AvgDisksecPerReadx10000 = 0
cqmghost: AvgDisksecPerWritex10000 = 0
cqmghost: ReadBuffer = 0.016200
cqmghost: WriteBuffer = 0.000258
cqmghost: AvgDisksecPerReadx10000 = 1
cqmghost: AvgDisksecPerWritex10000 = 1
beserver: 09/06/05 08:11:56 -1 SQLLog(13694):AgeSession m_threadMap: SessionThreadID:874, CurrentThreadID:f14
beserver: 09/06/05 08:11:56 -1 SQLLog(13695):AgeSession m_threadMap: SessionThreadID:b78, CurrentThreadID:f14
beserver: 09/06/05 08:11:56 -1 SQLLog(13696):AgeSession m_threadMap: SessionThreadID:bcc, CurrentThreadID:f14
beserver: 09/06/05 08:11:56 -1 SQLLog(13697):AgeSession m_threadMap: SessionThreadID:bfc, CurrentThreadID:f14
beserver: 09/06/05 08:11:56 -1 SQLLog(13698):AgeSession m_threadMap: SessionThreadID:ca8, CurrentThreadID:f14