Job hangs in the backup - There is no error found
Backup jobs hangs while taking the backup of Catalogs folder in the media server. There is no other job running at this time. Using the AOFO option for the backup. The backup job takes only 8 minutes. But some times, the job hangs at snapshot processing for very long time (approximately 55 mins). There is no error shown in the VSS writer. Collected the SGMon log and found two places where it hangs.
1. Hangs between [05/29/14 08:35:03] and [05/29/14 08:40:04] [4736]
2. Hangs between [05/29/14 08:43:41] and [05/29/14 08:49:40]
BENETNS: [05/29/14 08:27:39] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:27:39] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:27:40] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:27:40] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:28:40] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:28:40] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:28:40] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:28:40] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:28:40] [1816] Beginning agent database grooming.
BENETNS: [05/29/14 08:28:40] [1816] Agent database grooming complete.
BENETNS: [05/29/14 08:29:40] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:29:40] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:29:40] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:29:40] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:30:40] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:30:40] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:30:40] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:30:40] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:31:40] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:31:40] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:31:41] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:31:41] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - ...completed IVssBackupComponents::GatherWriterMetadata
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Attach to Shadow?Copy?Components
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Starting to generate file list based on SYSTEM\CurrentControlSet\Control\BackupRestore\FilesNotToSnapshot. (Function: LoadExcludeFiles, Line: 31)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\Windows\softwaredistribution\*.* /s to exclude list. (Function: LoadExcludeFiles, Line: 181)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\ProgramData\Microsoft\RAC\* to exclude list. (Function: LoadExcludeFiles, Line: 181)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\ProgramData\Microsoft\RAC\StateData\* to exclude list. (Function: LoadExcludeFiles, Line: 181)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\ProgramData\Microsoft\RAC\Outbound\* to exclude list. (Function: LoadExcludeFiles, Line: 181)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\ProgramData\Microsoft\RAC\PublishedData\* to exclude list. (Function: LoadExcludeFiles, Line: 181)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\ProgramData\Microsoft\RAC\Temp\* to exclude list. (Function: LoadExcludeFiles, Line: 181)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Started enumerating all UserProfile directories. (Function: GetUsersProfiles, Line: 228)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Finished enumerating all user profile directories. (Function: GetUsersProfiles, Line: 242)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - User profile related files excluded from backup: (Function: LoadExcludeFiles, Line: 134)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\Windows\system32\config\Journal\AppData\Local\Microsoft\Outlook\*.ost to exclude list. (Function: LoadExcludeFiles, Line: 143)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\Windows\system32\config\RegBack\AppData\Local\Microsoft\Outlook\*.ost to exclude list. (Function: LoadExcludeFiles, Line: 143)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\Windows\system32\config\systemprofile\AppData\Local\Microsoft\Outlook\*.ost to exclude list. (Function: LoadExcludeFiles, Line: 143)
BEREMOTE: [05/29/14 08:32:00] [6668] [writermanager] - Adding C:\Windows\system32\config\TxR\AppData\Local\Microsoft\Outlook\*.ost to exclude list. (Function: LoadExcludeFiles, Line: 143)
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Automated System Recovery Logical Path:ASR Component:ASR exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Automated System Recovery Logical Path:Volumes Component:Volume{6d93bf20-193d-11e1-ba69-806e6f6e6963} exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Automated System Recovery Logical Path:Volumes Component:Volume{50e8a89e-8c48-11e1-8d70-001b78962c06} exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Automated System Recovery Logical Path:Volumes Component:Volume{effe5a22-732c-11e1-b5d7-001b78962c04} exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Automated System Recovery Logical Path:Disks Component:harddisk0 exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Automated System Recovery Logical Path:Disks Component:harddisk1 exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - OSBUG: GetVolumePathNamesForVolumeName ret wrong len
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - strlen=3 gvpnfvn=5 path=C:\
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:SQL Server 2005 Logical Path:DCCBKP01\BKUPEXEC Component:master exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:SQL Server 2005 Logical Path:DCCBKP01\BKUPEXEC Component:model exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:SQL Server 2005 Logical Path:DCCBKP01\BKUPEXEC Component:msdb exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:SQL Server 2005 Logical Path:DCCBKP01\BKUPEXEC Component:BEDB exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:SQL Server 2005 Logical Path:DCCBKP01\BKUPEXEC Component:BE_DLO exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer Shadow Copy Optimization Writer contains no components in SHADOW::EnumSpecFiles
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shadow] - Informational: Writer:Shadow Copy Optimization Writer Logical Path:null Component:null exclusions not used
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Detach from Shadow?Copy?Components
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\systemstate] - AD:Attach reason (0x800)
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\systemstate] - AD:Nothing to exclude...
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\loops] - Informational status 0x2000FE1B calling FS_AttachToDLE for device System?State in LP_ENV::LoadEnumSpecFileExcludes
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - FS_FshGen: Failure to create debug trace object at line number 126
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\loops] - Informational status 0xE000FE03 calling FS_AttachToDLE for device in LP_ENV::LoadEnumSpecFileExcludes
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\ese] - [ESE_AttachToDLE] device_name:ESEFS_DAG::\\.\domdubai01.com attach_reason:0x800 backup_type:0 exchange_version:0
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Attach to ESEFS_DAG::\\.\domdubai01.com
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Detach from ESEFS_DAG::\\.\domdubai01.com
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\ese] - [ESE_AttachToDLE] device_name:ESEFS_PFS::\\.\domdubai01.com attach_reason:0x800 backup_type:0 exchange_version:0
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Attach to ESEFS_PFS::\\.\domdubai01.com
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Detach from ESEFS_PFS::\\.\domdubai01.com
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - TF_OpenTape()
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - TF_GetDriveContext ...
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - TF_GetDriveContext returning 0x00000000, context = 9461840
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - Shared Buffers Connection type prepared...
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - Allocated 10 buffers, size 65536 bytes, total used: 656720
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - TF_MoverAsyncInit(): Started ProcessRequest thread 5108
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - TF_OpenSet()
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - SetupFormatEnv( fmt=0 )
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\tpfmt] - End of TF_OpenSet() ret_val = 0x00000000, num buffers = 10
BEREMOTE: [05/29/14 08:32:00] [6668] [ndmp\ndmpsrvr] - Going to perform pre backup dbcc.
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\ntfs] - NTFS_GetDeviceVolumeInformation() - Enter
BEREMOTE: [05/29/14 08:32:00] [6668] [fsys\shared] - Volume::GetInformation(E:) - Enter
BESERVER: [05/29/14 08:32:00] [2052] "Cluster" key does not appear to be present in the registry
BESERVER: [05/29/14 08:32:00] [2052] Failed to open Microsoft cluster ()
BESERVER: [05/29/14 08:32:00] [2052] VCS cluster keys do not appear to be present in the registry
BESERVER: [05/29/14 08:32:00] [2052] Failed to open VCS cluster ()
BESERVER: [05/29/14 08:32:00] [2052] 05 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
PVLSVR: [05/29/14 08:32:00] [2576] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} EntityName = "" EntityFlags = 0x00000000
PVLSVR: [05/29/14 08:32:00] [2576] PvlEntityDatabase::QueryEntity() : SUCCESS!
BESERVER: [05/29/14 08:32:00] [2052] 05 AdammAdminBO::Get() - hr = 0x0
BENGINE: [05/29/14 08:32:00] [5780] [tpfmt] - TF_NDMPStartWrite(): Started LocalMediaServerWriteThread 5352
BENGINE: [05/29/14 08:32:00] [5252] [server] - Updating status for: 'Daily Catalog' (0x1 0x0)
BENGINE: [05/29/14 08:32:00] [5252] [server] - Status for: 'Daily Catalog' updated
PVLSVR: [05/29/14 08:32:01] [2576] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} EntityName = "" EntityFlags = 0x00000000
PVLSVR: [05/29/14 08:32:01] [2576] PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR: [05/29/14 08:32:01] [1520] PvlEntityDatabase::UpdateEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE Media = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, "000056L5" EntityFlags = 0x00830000
PVLSVR: [05/29/14 08:32:01] [1520] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BEREMOTE: [05/29/14 08:32:02] [6668] [ndmp\loops] - Windows Server 2003 (or higher) detected. No S2LP mapping required.
BESERVER: [05/29/14 08:32:02] [4712] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
PVLSVR: [05/29/14 08:32:02] [1520] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = 0031 EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:32:02] [4712] 05 AdammAdminBO::Execute() - hr = 0x0
BENETNS: [05/29/14 08:32:41] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:32:41] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:32:41] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:32:41] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:33:41] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:33:41] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:33:41] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:33:41] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:34:41] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:34:41] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:34:41] [4736] [nrds] - AcceptConnection: Failed Server Side SSL handshake.
BENETNS: [05/29/14 08:34:41] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BEREMOTE: [05/29/14 08:34:50] [6668] [ndmp\tpfmt] - TF xfer time = 170 seconds.
BEREMOTE: [05/29/14 08:34:50] [6668] [ndmp\tpfmt] - WRITE: tpreceive_fail_count = 21150
BEREMOTE: [05/29/14 08:34:50] [6668] [ndmp\tpfmt] - WRITE: waiting_on_buffers_count = 21140
BEREMOTE: [05/29/14 08:34:50] [6668] [ndmp\tpfmt] - WRITE: buffers_written_count = 87193
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\tpfmt] - TF_CloseSet()
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\tpfmt] - FreeFormatEnv( cur_fmt=0 )
BEREMOTE: [05/29/14 08:34:51] [6668] [fsys\shared] - Detach from E:
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\tpfmt] - TF_FreeDriveContext( 9461840 )
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\tpfmt] - TF_FreeTapeBuffers: from 10 to 0 buffers
BENGINE: [05/29/14 08:34:51] [5780] [loops] - data halted: SUCCESSFUL
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\ndmpsrvr] + ndmp_data.cpp (12046):
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\ndmpsrvr] | Backup Job Stopped(0)
BENGINE: [05/29/14 08:34:51] [5780] [loops] - BaseBSDProcessor::PollDataServer: Shutting down.
BENGINE: [05/29/14 08:34:51] [5780] [tpfmt] - TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0x00000000
BEREMOTE: [05/29/14 08:34:51] [6668] [ndmp\tpfmt] - FreeFormatEnv( cur_fmt=0 )
BENGINE: [05/29/14 08:34:51] [5780] [server] - BackupMessageHandlerForBackupJob::process( eng_msgs::close_catalog & msg ) called
BENGINE: [05/29/14 08:34:51] [5780] [server] - Not: 'm_bCatalogDrivenBackup && CatBuildVector[engine_id] && GetCarryFwdCatalogInfoForRemainingObjs( )'
BENGINE: [05/29/14 08:34:51] [5780] [server] - called CatBuildVector[engine_id]->GetErrorCondition() successfully
BENGINE: [05/29/14 08:34:51] [5780] [server] - BackupMessageHandler::CDB_EndCurrentCatalogOperation( ) -- entered
BENGINE: [05/29/14 08:34:51] [5780] [server] - BackupMessageHandler::CDB_EndCurrentCatalogOperation( ) is calling PerBSDDataVector[engine_id]->EndCatOperation( )
BENGINE: [05/29/14 08:34:51] [5780] [server] - EndCatOperation succeeded
BENGINE: [05/29/14 08:34:51] [5780] [server] - BackupMessageHandler::CDB_EndCurrentCatalogOperation( ) -- returning
BENGINE: [05/29/14 08:34:51] [5780] [server] - calling CatBuildVector[engine_id]->FinishBackup( ) for non-cat drive backup
BENGINE: [05/29/14 08:34:53] [5780] [tpfmt] - WriteEndSet( 1 ) returning 0x00000000
BENGINE: [05/29/14 08:34:58] [5780] [tpfmt] - WriteEndSet( 1 ) returning 0x00000000
BENGINE: [05/29/14 08:34:58] [5780] [tpfmt] - OTC_Close: Closing otc_sm_fptr 0x75469f70.
BENGINE: [05/29/14 08:34:58] [5780] [tpfmt] - WriteEndSet( 0 ) returning 0x00000000
PVLSVR: [05/29/14 08:34:58] [4504] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} EntityName = "" EntityFlags = 0x00000000
PVLSVR: [05/29/14 08:34:58] [4504] PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR: [05/29/14 08:34:58] [5404] PvlEntityDatabase::UpdateEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE Media = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, "000056L5" EntityFlags = 0x00830000
PVLSVR: [05/29/14 08:34:58] [5404] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [05/29/14 08:34:58] [5780] [tpfmt] - HARDWARE COMPRESSION ===> Setting compression off.
BESERVER: [05/29/14 08:34:59] [5320] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
BESERVER: [05/29/14 08:34:59] [5320] "Cluster" key does not appear to be present in the registry
BESERVER: [05/29/14 08:34:59] [5320] Failed to open Microsoft cluster ()
BESERVER: [05/29/14 08:34:59] [5320] VCS cluster keys do not appear to be present in the registry
BESERVER: [05/29/14 08:34:59] [5320] Failed to open VCS cluster ()
PVLSVR: [05/29/14 08:34:59] [4504] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = 0031 EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:34:59] [5320] 05 AdammAdminBO::Execute() - hr = 0x0
BENGINE: [05/29/14 08:35:00] [3264] [server] - DeviceManager: timeout event fired
BENGINE: [05/29/14 08:35:00] [3264] [server] - DeviceManager: processing pending requests
BENGINE: [05/29/14 08:35:00] [3264] [server] - DeviceManager: going to sleep for 900000 msecs
BENGINE: [05/29/14 08:35:03] [5780] [tpfmt] - TF_FreeTapeBuffers: from 0 to 0 buffers
BENGINE: [05/29/14 08:35:03] [5780] [tpfmt] - TF_CloseSet
BENGINE: [05/29/14 08:35:03] [5780] [loops] - Enter NDMPSnapHostBackupEngine::TrackSnappedBSD
BENETNS: [05/29/14 08:40:04] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:40:04] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:40:04] [4736] - SSL connection using version TLSv1
BENETNS: [05/29/14 08:40:04] [4736] - SSL connection using cipher AES256-SHA
BENETNS: [05/29/14 08:40:04] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 644(0x284) retval = 0
BENETNS: [05/29/14 08:40:04] [4736] Received 10641 bytes.
BENETNS: [05/29/14 08:40:04] [4736] NRDS Protocol Message:
BENETNS: [05/29/14 08:40:04] [4736] Message Header:
BENETNS: [05/29/14 08:40:04] [4736] Version: 1.0
BENETNS: [05/29/14 08:40:04] [4736] Type: agent advertisement
BENETNS: [05/29/14 08:40:04] [4736] Name parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Type: resource name
BENETNS: [05/29/14 08:40:04] [4736] Name: DCCDEVBI.domdubai01.com
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] Timestamp parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Type: remote server resource time
BENETNS: [05/29/14 08:40:04] [4736] Time: Thu May 29 08:40:04 2014
BENETNS: [05/29/14 08:40:04] [4736] Clock changed backwards: false
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] Network address list parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Network address parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Protocol: IPv4
BENETNS: [05/29/14 08:40:04] [4736] Address: 10.10.0.114/24
BENETNS: [05/29/14 08:40:04] [4736] Port: 10000
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] Platform parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Platform: 500
BENETNS: [05/29/14 08:40:04] [4736] Type: 36871
BENETNS: [05/29/14 08:40:04] [4736] Version: 6.1
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] DLE info parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Type: 7
BENETNS: [05/29/14 08:40:04] [4736] Subtype: 2
BENETNS: [05/29/14 08:40:04] [4736] Feature bits: 0x0030C082
BENETNS: [05/29/14 08:40:04] [4736] Ext Ftr bits: 0x00000280
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] Misc info parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Application version: 13.0.5204.0
BENETNS: [05/29/14 08:40:04] [4736] Restrict anon: 240
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] Flags parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Flags 1: 0x20
BENETNS: [05/29/14 08:40:04] [4736] Valid: true
BENETNS: [05/29/14 08:40:04] [4736] Xml parameter:
BENETNS: [05/29/14 08:40:04] [4736] Present: true
BENETNS: [05/29/14 08:40:04] [4736] Size: 10498
BENETNS: [05/29/14 08:40:04] [4736] Data: <?xml version="1.0"?>
BENETNS: [05/29/14 08:40:04] [4736] <Computer>
BENETNS: [05/29/14 08:40:04] [4736] <FQDN>DCCDEVBI.domdubai01.com</FQDN>
BENETNS: [05/29/14 08:40:04] [4736] <NetBIOS>DCCDEVBI</NetBIOS>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterResource>0</ClusterResource>
BENETNS: [05/29/14 08:40:04] [4736] <osDescription>Microsoft Windows Server 2008 R2 Enterprise</osDescription>
BENETNS: [05/29/14 08:40:04] [4736] <osServicePackDescription>Service Pack 1</osServicePackDescription>
BENETNS: [05/29/14 08:40:04] [4736] <osVersion>6.1</osVersion>
BENETNS: [05/29/14 08:40:04] [4736] <osBuild>7601</osBuild>
BENETNS: [05/29/14 08:40:04] [4736] <osPlatform>2</osPlatform>
BENETNS: [05/29/14 08:40:04] [4736] <osServicePackVersion>1.0</osServicePackVersion>
BENETNS: [05/29/14 08:40:04] [4736] <osSuiteMask>274</osSuiteMask>
BENETNS: [05/29/14 08:40:04] [4736] <osProductType>3</osProductType>
BENETNS: [05/29/14 08:40:04] [4736] <osProductInfo>10</osProductInfo>
BENETNS: [05/29/14 08:40:04] [4736] <osServerR2>0</osServerR2>
BENETNS: [05/29/14 08:40:04] [4736] <osMediaCenter>0</osMediaCenter>
BENETNS: [05/29/14 08:40:04] [4736] <osStarter>0</osStarter>
BENETNS: [05/29/14 08:40:04] [4736] <osTabletPC>0</osTabletPC>
BENETNS: [05/29/14 08:40:04] [4736] <ProcessorArchitecture>9</ProcessorArchitecture>
BENETNS: [05/29/14 08:40:04] [4736] <ProcessorType>8664</ProcessorType>
BENETNS: [05/29/14 08:40:04] [4736] <ProcessorCount>4</ProcessorCount>
BENETNS: [05/29/14 08:40:04] [4736] <volumes>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{fc6a7b3e-5e75-11e0-bbaf-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume1</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>System Reserved</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>75354112</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>104857600</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513e4-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>P:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume3</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>PageFile</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>4700229632</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>25766658048</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513ba-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>E:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume4</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>DBDDATA1</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>99932033024</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>107371036672</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513c1-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>F:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume5</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>DBDDATA2</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>100712108032</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>107371036672</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513cf-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>I:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume6</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>DBDDATA3</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>52106014720</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>107371036672</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513c8-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>H:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume7</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>DBDLOG1</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>52539609088</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>53683945472</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513d6-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>G:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume8</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>DBDUSR</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>26707193856</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>53683945472</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{8be513dd-84a9-11e0-bebf-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>J:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume9</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>DBDMSSQL</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>12763807744</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>21471690752</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{7e8f30b3-0ab3-11e1-99ab-005056b2000a}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>Z:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume10</device>
BENETNS: [05/29/14 08:40:04] [4736] <label>BACKUP</label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>80232337408</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>107371036672</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{fc6a7b3f-5e75-11e0-bbaf-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>C:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>3</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\HarddiskVolume2</device>
BENETNS: [05/29/14 08:40:04] [4736] <label></label>
BENETNS: [05/29/14 08:40:04] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:40:04] [4736] <free>50443620352</free>
BENETNS: [05/29/14 08:40:04] [4736] <size>85792391168</size>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{fc6a7b43-5e75-11e0-bbaf-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>A:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>2</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\Floppy0</device>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] <volume>
BENETNS: [05/29/14 08:40:04] [4736] <id>\\?\Volume{fc6a7b42-5e75-11e0-bbaf-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <mountPoint>D:\</mountPoint>
BENETNS: [05/29/14 08:40:04] [4736] </mountPoints>
BENETNS: [05/29/14 08:40:04] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:40:04] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:40:04] [4736] <type>5</type>
BENETNS: [05/29/14 08:40:04] [4736] <device>\Device\CdRom0</device>
BENETNS: [05/29/14 08:40:04] [4736] </volume>
BENETNS: [05/29/14 08:40:04] [4736] </volumes>
BENETNS: [05/29/14 08:40:04] [4736] <Applications>
BENETNS: [05/29/14 08:40:04] [4736] <EnterpriseVault><EVVersion></EVVersion><CAVersion></CAVersion><DAVersion></DAVersion></EnterpriseVault></Applications>
BENETNS: [05/29/14 08:40:04] [4736] <beVersion>13.0.5204.0</beVersion>
BENETNS: [05/29/14 08:40:04] [4736] <bePatchList><bePatch>{DC566EB2-FD90-4B99-A637-11EDAEA14D7B}</bePatch>
BENETNS: [05/29/14 08:40:04] [4736] <bePatch>{913E95E5-BF27-4802-9C77-C31608E4A789}</bePatch>
BENETNS: [05/29/14 08:40:04] [4736] </bePatchList>
BENETNS: [05/29/14 08:40:04] [4736] <beRunning64Bit>1</beRunning64Bit>
BENETNS: [05/29/14 08:40:04] [4736] </Computer>
BENETNS: [05/29/14 08:40:04] [4736] End of NRDS Protocol Message.
BENETNS: [05/29/14 08:40:04] [1752] Updating cache for DCCDEVBI.domdubai01.com.
BENETNS: [05/29/14 08:40:04] [1752] existing replaced with new -- master new:0 master old:0 secure new:1 secure old:1
BENETNS: [05/29/14 08:40:04] [1752] Connecting to BE Database.
BESERVER: [05/29/14 08:40:04] [2052] -1 Client requested key (1401338581).
BESERVER: [05/29/14 08:40:04] [2052] "Cluster" key does not appear to be present in the registry
BESERVER: [05/29/14 08:40:04] [2052] Failed to open Microsoft cluster ()
BESERVER: [05/29/14 08:40:04] [2052] VCS cluster keys do not appear to be present in the registry
BESERVER: [05/29/14 08:40:04] [2052] Failed to open VCS cluster ()
BESERVER: [05/29/14 08:40:04] [2052] 01 Server Configuration: Client added: 11
BESERVER: [05/29/14 08:40:04] [2052] -1 Client 'DCCBKP01' connected('','DOMDUBAI01\backupadmin'): 0xa90fad0
BENETNS: [05/29/14 08:40:04] [1752] Successfully connected to BE Database.
BENETNS: [05/29/14 08:40:04] [1752] Reading agent database record for DCCDEVBI.domdubai01.com.
BENETNS: [05/29/14 08:40:04] [1752] Found agent record 65 for DCCDEVBI.domdubai01.com.
BENETNS: [05/29/14 08:40:04] [1752] Updating agent record for DCCDEVBI.domdubai01.com.
BENETNS: [05/29/14 08:40:04] [1752] Write Successful, key=65.
BESERVER: [05/29/14 08:40:04] [2052] 01 Server Configuration: Client removed: 10
BESERVER: [05/29/14 08:40:04] [2052] -1 Client 'DCCBKP01' Disconnected:0xa90fad0
BENETNS: [05/29/14 08:40:04] [1752] Disconnected from BE Database.
BENETNS: [05/29/14 08:40:04] [1752] Update complete.
DLOADMIN: [05/29/14 08:42:03] [0000] BECRYPTO Base Address is BE00000
DLOADMIN: [05/29/14 08:42:03] [0000] BECRYPTO is not loaded at proper base address, halting FIPS initialization!
DLOADMIN: [05/29/14 08:42:03] [0000] BECryptoInit: Initialized locks for SSL callbacks
DLOADMIN: [05/29/14 08:42:03] [0000] BECryptoInit: BECrypto non-FIPS mode successfully enabled.
BENETNS: [05/29/14 08:43:41] [1816] Beginning agent database grooming.
BENETNS: [05/29/14 08:43:41] [1816] Agent database grooming complete.
BEREMOTE: [05/29/14 08:49:40] [6668] [besc] - VSS BackupComplete called with success.
BEREMOTE: [05/29/14 08:49:40] [6668] - VssSnapshotVolume::GetFailedWriterStatus() - waiting for IVssBackupComponents::GatherWriterMetadata to complete.
BEREMOTE: [05/29/14 08:49:41] [6668] - VssSnapshotVolume::GetFailedWriterStatus() - IVssBackupComponents::GatherWriterStatus complete.
BEREMOTE: [05/29/14 08:49:41] [6668] - VssSnapshotVolume::GetFailedWriterStatus() - Issuing Async QueryStatus check.
BEREMOTE: [05/29/14 08:49:41] [6668] [besc] - Attempting to delete VSS snapshot set: {26a29a63-fe2f-4400-a90a-3e816ae4eb77}
BEREMOTE: [05/29/14 08:49:41] [6668] [besc] - VSS snapshot set deleted. Snapshot SetID = {26a29a63-fe2f-4400-a90a-3e816ae4eb77}
BENGINE: [05/29/14 08:49:41] [5780] [loops] - Exit NDMPSnapHostBackupEngine::TrackSnappedBSD
BENGINE: [05/29/14 08:49:41] [5780] [loops] - Exit NDMPSnapHostBackupEngine::ProcessBSDs
BEREMOTE: [05/29/14 08:49:41] [6668] [ndmp\ndmpsrvr] - sslClose() : Closing SSL for: 00000000E8C3979F
BENGINE: [05/29/14 08:49:41] [5780] [loops] - NDMPAgentConnector::EndConnection(): Data Server = DCCBKP01
BEREMOTE: [05/29/14 08:49:41] [6668] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 2060(0x80c) retval = 0
BEREMOTE: [05/29/14 08:49:41] [6668] [ndmp\tpfmt] - FreeFormatEnv( cur_fmt=0 )
BEREMOTE: [05/29/14 08:49:41] [6668] [ndmp\tpfmt] - FreeFormatEnv( cur_fmt=0 )
BEREMOTE: [05/29/14 08:49:41] [6668] [ndmp\ndmpsrvr] - sslClose() : Closing SSL for: 00000000E8C3979F
BEREMOTE: [05/29/14 08:49:41] [6668] [ndmp\ndmpsrvr] - sslClose() : certinfo = 0 ; sslConn = 0
PVLSVR: [05/29/14 08:49:41] [5320] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E}
BENGINE: [05/29/14 08:49:41] [5780] [tpfmt] - Setting rewind flag to true for older device. This will likely degrade the performance of the current job.
BENGINE: [05/29/14 08:50:00] [3264] [server] - DeviceManager: timeout event fired
BENGINE: [05/29/14 08:50:00] [3264] [server] - DeviceManager: processing pending requests
BENGINE: [05/29/14 08:50:00] [3264] [server] - DeviceManager: going to sleep for 900000 msecs
BKUPEXEC: [05/29/14 08:50:16] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:16] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:16] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:21] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:21] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:21] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:26] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:26] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:26] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:31] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:31] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:31] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:36] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:36] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:36] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:41] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:41] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:41] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:46] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:46] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:46] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:51] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:51] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:51] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:50:56] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:50:56] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:50:56] [0025] [Debug] VSN added to queues
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - RewindDrive mover ret = 0 (0x0)
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - ret_val = 0
PVLSVR: [05/29/14 08:50:57] [0520] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E}
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - TF_PutChannel( ) 04BCC270
BENGINE: [05/29/14 08:50:57] [5780] [tapealrt] - TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [05/29/14 08:50:57] [5780] [tapealrt] - TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [05/29/14 08:50:57] [5780] [tapealrt] - TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [05/29/14 08:50:57] [5780] [tapealrt] - TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [05/29/14 08:50:57] [5780] [tapealrt] - TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [05/29/14 08:50:57] [5780] [server] - Updating session {e185b78d-e0c2-4535-98c1-2bed2cfd698e} with drive HP 0001 {537b1d0b-de72-4944-a600-c1b4ca9dc662}
BENGINE: [05/29/14 08:50:57] [5780] [server] - VerifyJob::GetTargetMediaDeviceName(): Execute(ADAMM_MOVER_EXECUTE_GET_SERVER_INFO) No server found.
PVLSVR: [05/29/14 08:50:57] [0520] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E}
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - TF_OpenTape()
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - TF_GetChannel( ) reusing channel 073BB450
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - TF_MoverAsyncInit(): Started ProcessRequest thread 5460
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - Allocated 10 buffers, size 65536 bytes, total used: 656640
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - HARDWARE COMPRESSION ===> Compression is configurable.
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - HARDWARE ENCRYPTION ===> Encryption is supported.
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - GET_DRV_INF: bsize = 65536
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - GET_DRV_INF: preferredBlockSize = 65536
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - TF_OpenSet( )
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - Requested Set: ID = 44bc9029 Seq = 1 Set = 6
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - Attempting to VCB storage media
BENGINE: [05/29/14 08:50:57] [5780] [tpfmt] - TpRead( ):
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - Error = 0 Req = 65536 Got = 65536
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - HARDWARE COMPRESSION ===> Compression is configurable.
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - HARDWARE ENCRYPTION ===> Encryption is supported.
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - GET_DRV_INF: bsize = 65536
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - GET_DRV_INF: preferredBlockSize = 65536
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - Current media tape header was written by "Symantec Backup Exec 13.0.5204".
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - TpRead( ):
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - Error = 0 Req = 65536 Got = 65536
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - ReadThisSet( cur_fmt=0 )
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - Set 1 was written by version 13.0.
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - ReadThisSet() return=0x00000000
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - Current VCB: ID = 44bc9029 Seq = 1 Set = 1
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - End of TF_OpenSet: Ret_val = 0x00000000 Buffs = 10 HiWater = 0
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - GotoBlock: VCB_PBA = 2539173, VCB_LBA = 0, LBA = 0,
BENGINE: [05/29/14 08:50:59] [5780] [tpfmt] - PBA = 2539173, offset = 0
BENGINE: [05/29/14 08:51:01] [5252] [server] - Updating status for: 'Daily Catalog' (0x4 0x0)
BENGINE: [05/29/14 08:51:01] [5252] [server] - Status for: 'Daily Catalog' updated
BKUPEXEC: [05/29/14 08:51:01] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:51:01] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:51:01] [0025] [Debug] VSN added to queues
BECAT: [05/29/14 08:51:36] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:51:36] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:51:36] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:51:36] [1824] SELECT * FROM CatImageFileDeleteTable
BECAT: [05/29/14 08:51:36] [1824] Number of records returned: 0
BECAT: [05/29/14 08:51:36] [1824] Cleanup images : 0
BECAT: [05/29/14 08:51:36] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:51:36] [1824] Reset connection ...
MMC: [05/29/14 08:51:47] [0000] 6964: [CBS] IsCacheStillGood: True.
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 721 ) Looking up cluster node info at \\DCCBKP01\DLOClus\DLOCluster\ActiveNode.bin exists: No
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 434 ) failed looking up cluster node info: 0000000080070043 virtualServer DCCBKP01\BKUPEXEC media server DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> licensemonitor.cpp( 145 ) entering LicenseMonitor::Run
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 103 ) Entering DeleteUserMonitor::Run
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 126 ) Entering DeleteUserMonitor::DeleteUserProc.
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM SUser WHERE UserFlags != 0
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM SUser WHERE UserFlags != 0
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 182 ) Leaving DeleteUserMonitor::DeleteUserProc.
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 260 ) Entering DeleteUserMonitor::DeleteComputerProc.
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT M.MSID, M.MSName, S.* FROM MachineSettings M, SUser S WHERE M.MSUserID = S.UserID AND M.MSFlags != 0
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT M.MSID, M.MSName, S.* FROM MachineSettings M, SUser S WHERE M.MSUserID = S.UserID AND M.MSFlags != 0
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 333 ) Leaving DeleteUserMonitor::DeleteComputerProc.
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 121 ) Leaving DeleteUserMonitor::Run
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM NotificationQueue
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:52:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM NotificationQueue
MMC: [05/29/14 08:53:49] [0000] 6964: [CBS] IsCacheStillGood: True.
BENGINE: [05/29/14 08:54:08] [5780] [tpfmt] - Set 6 was written by version 13.0.
BENGINE: [05/29/14 08:54:08] [5780] [tpfmt] - TF_GetTargetMediaDeviceInfo(): Execute(ADAMM_MOVER_EXECUTE_GET_SERVER_INFO) No server found.
BKUPEXEC: [05/29/14 08:54:11] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:11] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:16] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:16] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:16] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:21] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:21] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:21] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:26] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:26] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:26] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:31] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:31] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:31] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:36] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:36] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:36] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:41] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:41] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:41] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:46] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:46] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:46] [0025] [Debug] VSN added to queues
BENGINE: [05/29/14 08:54:49] [5780] [tpfmt] - Device Error = 1101
BENGINE: [05/29/14 08:54:49] [5780] [tpfmt] - Len Req = 65536 Len Got = 0
BENGINE: [05/29/14 08:54:50] [5780] [tpfmt] - TF read xfer time = 230 seconds.
BENGINE: [05/29/14 08:54:50] [5780] [tpfmt] - TF_CloseSet
PVLSVR: [05/29/14 08:54:50] [0956] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E}
BENGINE: [05/29/14 08:54:50] [5780] [tpfmt] - Setting rewind flag to true for older device. This will likely degrade the performance of the current job.
BKUPEXEC: [05/29/14 08:54:51] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:51] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:51] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:54:56] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:54:56] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:54:56] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:01] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:01] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:01] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:06] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:06] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:06] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:11] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:11] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:16] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:16] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:16] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:21] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:21] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:21] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:26] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:26] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:26] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:31] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:31] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:31] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:36] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:36] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:36] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:41] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:41] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:41] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:46] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:46] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:46] [0025] [Debug] VSN added to queues
MMC: [05/29/14 08:55:51] [0000] 6964: [CBS] IsCacheStillGood: True.
BKUPEXEC: [05/29/14 08:55:51] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:51] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:51] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:55:56] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:55:56] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:55:56] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:56:01] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:56:01] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:56:01] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:56:06] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:56:06] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:56:06] [0025] [Debug] VSN added to queues
BENGINE: [05/29/14 08:56:09] [5780] [tpfmt] - RewindDrive mover ret = 0 (0x0)
BENGINE: [05/29/14 08:56:09] [5780] [tpfmt] - ret_val = 0
PVLSVR: [05/29/14 08:56:09] [0956] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E}
BENGINE: [05/29/14 08:56:09] [5780] [tpfmt] - TF_PutChannel( ) 073BB450
PVLSVR: [05/29/14 08:56:09] [0956] PvlEntityDatabase::UpdateEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE Media = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, "" EntityFlags = 0x008C0000
PVLSVR: [05/29/14 08:56:09] [0956] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [05/29/14 08:56:09] [3264] [server] - DeviceManager: incoming event fired
BENGINE: [05/29/14 08:56:09] [3264] [server] - DeviceManager: Executing ReturnDeviceCall from thread 5780.
BECAT: [05/29/14 08:56:09] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:56:09] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:56:09] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:56:09] [1824] {CALL UpdateCatMediaInfo( {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, 000056L5, {00000009-03E9-0000-0000-000000000000}) }
BECAT: [05/29/14 08:56:09] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:56:09] [1824] status(1) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:56:09] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:56:09] [1824] ValidateConnection : 1
BENGINE: [05/29/14 08:56:09] [3264] [server] - Updating session {e185b78d-e0c2-4535-98c1-2bed2cfd698e} with drive HP 0001 {537b1d0b-de72-4944-a600-c1b4ca9dc662}
BECAT: [05/29/14 08:56:09] [1824] SELECT * FROM ImageObjectView where 1=1 AND MediaGuid = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} AND (FragmentState != 1 or FragmentState is NULL) AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' ORDER BY MediaFamilyGuid , ImageNumber , MediaNumber
BENGINE: [05/29/14 08:56:09] [3264] [server] - DeviceManager: processing pending requests
BENGINE: [05/29/14 08:56:09] [3264] [server] - DeviceManager: going to sleep for 61000 msecs
BENGINE: [05/29/14 08:56:09] [5780] [server] - Removing 'Daily Catalog' from status update list
BENGINE: [05/29/14 08:56:09] [5780] [server] - Updating status for: 'Daily Catalog' (0x18 0x0)
BENGINE: [05/29/14 08:56:09] [5780] [server] - Status for: 'Daily Catalog' updated
BENGINE: [05/29/14 08:56:09] [5780] [server] - BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary
BENGINE: [05/29/14 08:56:09] [5780] [server] - Ending job 'Daily Catalog' with succuess status
BECAT: [05/29/14 08:56:09] [1824] Number of records returned: 6
BESERVER: [05/29/14 08:56:09] [6560] 16 JobManager::HandleEndJob() called
BENGINE: [05/29/14 08:56:09] [5780] [server] - Job thread terminating
BESERVER: [05/29/14 08:56:09] [6560] 01 Server Configuration: Client removed: 9
BESERVER: [05/29/14 08:56:09] [6560] -1 Client 'DCCBKP01' Disconnected:0x106212f0
BESERVER: [05/29/14 08:56:09] [4672] -1 ActiveState::doEndEvent( ): 'Daily Catalog'
BESERVER: [05/29/14 08:56:09] [4672] 16 JobManager::DoJobCompletionTasks(Daily Catalog): GetFinalJobStatus()=19
BECAT: [05/29/14 08:56:09] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:56:09] [1824] Reset connection ...
BECAT: [05/29/14 08:56:09] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:56:09] [1824] Reset connection ...
BESERVER: [05/29/14 08:56:09] [6560] 15 GetSummary: SJ=14 SEJ=1 CJ=0 FJ=0 UM=0 BB=2070182534 MJ=0 RecoveredJ=0 ResumedJ=0 FeatureRecommend=0
BESERVER: [05/29/14 08:56:10] [4812] -1 Registered virtual array rowset(54): Client:DCCBKP01, Type:MD_OBJTYPE_JOBHISTORYVIEW
BESERVER: [05/29/14 08:56:10] [4672] -1 ActiveState::doEndEvent( ): CJobManager::DoJobCompletionTasks() returned 0x0
BKUPEXEC: [05/29/14 08:56:09] [0025] [Info] VSN received: type = MEDIA_UPDATE
BKUPEXEC: [05/29/14 08:56:09] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:56:09] [0025] [Info] VSN received: type = CATALOG_UPDATE
BKUPEXEC: [05/29/14 08:56:09] [0025] [Debug] No handler registered for this VSN - ignoring
BKUPEXEC: [05/29/14 08:56:09] [0025] [Info] VSN received: type = COMPJOB_UPDATE
BKUPEXEC: [05/29/14 08:56:09] [0025] [Debug] VSN is an ADD
BKUPEXEC: [05/29/14 08:56:09] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:56:10] [0025] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [05/29/14 08:56:10] [0025] [Debug] VSN is a REMOVE
BKUPEXEC: [05/29/14 08:56:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:56:10] [0005] [Debug] UIVirtualObjectView(JobHistoryView) Queried 500 items: 0 to 499
BESERVER: [05/29/14 08:56:11] [0540] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
BESERVER: [05/29/14 08:56:11] [0540] "Cluster" key does not appear to be present in the registry
BESERVER: [05/29/14 08:56:11] [0540] Failed to open Microsoft cluster ()
BESERVER: [05/29/14 08:56:11] [0540] VCS cluster keys do not appear to be present in the registry
BESERVER: [05/29/14 08:56:11] [0540] Failed to open VCS cluster ()
PVLSVR: [05/29/14 08:56:11] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = 0031 EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:56:11] [0540] 05 AdammAdminBO::Execute() - hr = 0x0
BKUPEXEC: [05/29/14 08:56:11] [0025] [Info] VSN received: type = BEM_MEDIA
BKUPEXEC: [05/29/14 08:56:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:56:11] [0025] [Debug] VSN added to queues
BESERVER: [05/29/14 08:56:11] [4980] 16 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
BKUPEXEC: [05/29/14 08:56:11] [0025] [Info] VSN received: type = SCHJOB_UPDATE
BKUPEXEC: [05/29/14 08:56:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:56:11] [0025] [Debug] VSN added to queues
BESERVER: [05/29/14 08:56:16] [4980] 16 JobManager: Sending History Update VSN, count:1.
BESERVER: [05/29/14 08:56:16] [6560] 15 GetSummary: SJ=14 SEJ=1 CJ=0 FJ=0 UM=0 BB=2070182534 MJ=0 RecoveredJ=0 ResumedJ=0 FeatureRecommend=0
BESERVER: [05/29/14 08:56:16] [0540] -1 Registered virtual array rowset(55): Client:DCCBKP01, Type:MD_OBJTYPE_JOBHISTORYVIEW
BESERVER: [05/29/14 08:56:16] [0540] -1 Released virtual array rowset(54).
BKUPEXEC: [05/29/14 08:56:16] [0025] [Info] VSN received: type = JOBHIST_UPDATE
BKUPEXEC: [05/29/14 08:56:16] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:56:16] [0025] [Debug] No VSN payload - do a reset
BKUPEXEC: [05/29/14 08:56:16] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:56:16] [0022] [Debug] UIVirtualObjectView(JobHistoryView) Queried 500 items: 0 to 499
BECAT: [05/29/14 08:56:35] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:56:35] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:56:35] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:56:35] [1824] SELECT * FROM CatImageFileDeleteTable
BECAT: [05/29/14 08:56:35] [1824] Number of records returned: 0
BECAT: [05/29/14 08:56:35] [1824] Cleanup images : 0
BECAT: [05/29/14 08:56:35] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:56:35] [1824] Reset connection ...
BENETNS: [05/29/14 08:56:36] [4736] [nrds] - Accepted new connection.
BENETNS: [05/29/14 08:56:36] [4736] [nrds] - AcceptConnection: SSL was requested
BENETNS: [05/29/14 08:56:36] [4736] [nrds] - AcceptConnection: Matched Active RC Hash.
BENETNS: [05/29/14 08:56:36] [4736] - SSL connection using version TLSv1
BENETNS: [05/29/14 08:56:36] [4736] - SSL connection using cipher AES256-SHA
BENETNS: [05/29/14 08:56:36] [4736] [BESocket] - @@@@@@@MyCloseSocket called with sockfd = 732(0x2dc) retval = 0
BENETNS: [05/29/14 08:56:36] [4736] Received 6092 bytes.
BENETNS: [05/29/14 08:56:36] [4736] NRDS Protocol Message:
BENETNS: [05/29/14 08:56:36] [4736] Message Header:
BENETNS: [05/29/14 08:56:36] [4736] Version: 1.0
BENETNS: [05/29/14 08:56:36] [4736] Type: agent advertisement
BENETNS: [05/29/14 08:56:36] [4736] Name parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Type: resource name
BENETNS: [05/29/14 08:56:36] [4736] Name: DCCDC01.domdubai01.com
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] Timestamp parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Type: remote server resource time
BENETNS: [05/29/14 08:56:36] [4736] Time: Thu May 29 08:56:36 2014
BENETNS: [05/29/14 08:56:36] [4736] Clock changed backwards: false
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] Network address list parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Network address parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Protocol: IPv4
BENETNS: [05/29/14 08:56:36] [4736] Address: 10.10.0.60/24
BENETNS: [05/29/14 08:56:36] [4736] Port: 10000
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] Platform parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Platform: 500
BENETNS: [05/29/14 08:56:36] [4736] Type: 8654891
BENETNS: [05/29/14 08:56:36] [4736] Version: 6.1
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] DLE info parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Type: 7
BENETNS: [05/29/14 08:56:36] [4736] Subtype: 2
BENETNS: [05/29/14 08:56:36] [4736] Feature bits: 0x0010C082
BENETNS: [05/29/14 08:56:36] [4736] Ext Ftr bits: 0x00000280
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] Misc info parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Application version: 13.0.5204.0
BENETNS: [05/29/14 08:56:36] [4736] Restrict anon: 240
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] Flags parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Flags 1: 0x20
BENETNS: [05/29/14 08:56:36] [4736] Valid: true
BENETNS: [05/29/14 08:56:36] [4736] Xml parameter:
BENETNS: [05/29/14 08:56:36] [4736] Present: true
BENETNS: [05/29/14 08:56:36] [4736] Size: 5950
BENETNS: [05/29/14 08:56:36] [4736] Data: <?xml version="1.0"?>
BENETNS: [05/29/14 08:56:36] [4736] <Computer>
BENETNS: [05/29/14 08:56:36] [4736] <FQDN>DCCDC01.domdubai01.com</FQDN>
BENETNS: [05/29/14 08:56:36] [4736] <NetBIOS>DCCDC01</NetBIOS>
BENETNS: [05/29/14 08:56:36] [4736] <ClusterResource>0</ClusterResource>
BENETNS: [05/29/14 08:56:36] [4736] <osDescription>Microsoft Windows Server 2008 R2 Standard</osDescription>
BENETNS: [05/29/14 08:56:36] [4736] <osServicePackDescription>Service Pack 1</osServicePackDescription>
BENETNS: [05/29/14 08:56:36] [4736] <osVersion>6.1</osVersion>
BENETNS: [05/29/14 08:56:36] [4736] <osBuild>7601</osBuild>
BENETNS: [05/29/14 08:56:36] [4736] <osPlatform>2</osPlatform>
BENETNS: [05/29/14 08:56:36] [4736] <osServicePackVersion>1.0</osServicePackVersion>
BENETNS: [05/29/14 08:56:36] [4736] <osSuiteMask>272</osSuiteMask>
BENETNS: [05/29/14 08:56:36] [4736] <osProductType>2</osProductType>
BENETNS: [05/29/14 08:56:36] [4736] <osProductInfo>7</osProductInfo>
BENETNS: [05/29/14 08:56:36] [4736] <osServerR2>0</osServerR2>
BENETNS: [05/29/14 08:56:36] [4736] <osMediaCenter>0</osMediaCenter>
BENETNS: [05/29/14 08:56:36] [4736] <osStarter>0</osStarter>
BENETNS: [05/29/14 08:56:36] [4736] <osTabletPC>0</osTabletPC>
BENETNS: [05/29/14 08:56:36] [4736] <ProcessorArchitecture>9</ProcessorArchitecture>
BENETNS: [05/29/14 08:56:36] [4736] <ProcessorType>8664</ProcessorType>
BENETNS: [05/29/14 08:56:36] [4736] <ProcessorCount>1</ProcessorCount>
BENETNS: [05/29/14 08:56:36] [4736] <volumes>
BENETNS: [05/29/14 08:56:36] [4736] <volume>
BENETNS: [05/29/14 08:56:36] [4736] <id>\\?\Volume{6256ac94-71f1-11e1-b368-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] </mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:56:36] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:56:36] [4736] <type>3</type>
BENETNS: [05/29/14 08:56:36] [4736] <device>\Device\HarddiskVolume1</device>
BENETNS: [05/29/14 08:56:36] [4736] <label>System Reserved</label>
BENETNS: [05/29/14 08:56:36] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:56:36] [4736] <free>73777152</free>
BENETNS: [05/29/14 08:56:36] [4736] <size>104857600</size>
BENETNS: [05/29/14 08:56:36] [4736] </volume>
BENETNS: [05/29/14 08:56:36] [4736] <volume>
BENETNS: [05/29/14 08:56:36] [4736] <id>\\?\Volume{94b425a6-87ae-11e1-a0a1-005056b20035}\</id>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoint>E:\</mountPoint>
BENETNS: [05/29/14 08:56:36] [4736] </mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:56:36] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:56:36] [4736] <type>3</type>
BENETNS: [05/29/14 08:56:36] [4736] <device>\Device\HarddiskVolume3</device>
BENETNS: [05/29/14 08:56:36] [4736] <label>New Volume</label>
BENETNS: [05/29/14 08:56:36] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:56:36] [4736] <free>41436831744</free>
BENETNS: [05/29/14 08:56:36] [4736] <size>53683945472</size>
BENETNS: [05/29/14 08:56:36] [4736] </volume>
BENETNS: [05/29/14 08:56:36] [4736] <volume>
BENETNS: [05/29/14 08:56:36] [4736] <id>\\?\Volume{6256ac95-71f1-11e1-b368-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoint>C:\</mountPoint>
BENETNS: [05/29/14 08:56:36] [4736] </mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:56:36] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:56:36] [4736] <type>3</type>
BENETNS: [05/29/14 08:56:36] [4736] <device>\Device\HarddiskVolume2</device>
BENETNS: [05/29/14 08:56:36] [4736] <label></label>
BENETNS: [05/29/14 08:56:36] [4736] <fileSystem>NTFS</fileSystem>
BENETNS: [05/29/14 08:56:36] [4736] <free>79691943936</free>
BENETNS: [05/29/14 08:56:36] [4736] <size>107267227648</size>
BENETNS: [05/29/14 08:56:36] [4736] </volume>
BENETNS: [05/29/14 08:56:36] [4736] <volume>
BENETNS: [05/29/14 08:56:36] [4736] <id>\\?\Volume{6256ac99-71f1-11e1-b368-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoint>A:\</mountPoint>
BENETNS: [05/29/14 08:56:36] [4736] </mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:56:36] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:56:36] [4736] <type>2</type>
BENETNS: [05/29/14 08:56:36] [4736] <device>\Device\Floppy0</device>
BENETNS: [05/29/14 08:56:36] [4736] </volume>
BENETNS: [05/29/14 08:56:36] [4736] <volume>
BENETNS: [05/29/14 08:56:36] [4736] <id>\\?\Volume{6256ac98-71f1-11e1-b368-806e6f6e6963}\</id>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <mountPoint>D:\</mountPoint>
BENETNS: [05/29/14 08:56:36] [4736] </mountPoints>
BENETNS: [05/29/14 08:56:36] [4736] <Clustered>0</Clustered>
BENETNS: [05/29/14 08:56:36] [4736] <ClusterSharedVolume>0</ClusterSharedVolume>
BENETNS: [05/29/14 08:56:36] [4736] <type>5</type>
BENETNS: [05/29/14 08:56:36] [4736] <device>\Device\CdRom0</device>
BENETNS: [05/29/14 08:56:36] [4736] </volume>
BENETNS: [05/29/14 08:56:36] [4736] </volumes>
BENETNS: [05/29/14 08:56:36] [4736] <Applications>
BENETNS: [05/29/14 08:56:36] [4736] <EnterpriseVault><EVVersion></EVVersion><CAVersion></CAVersion><DAVersion></DAVersion></EnterpriseVault></Applications>
BENETNS: [05/29/14 08:56:36] [4736] <beVersion>13.0.5204.0</beVersion>
BENETNS: [05/29/14 08:56:36] [4736] <bePatchList><bePatch>{DC566EB2-FD90-4B99-A637-11EDAEA14D7B}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] <bePatch>{913E95E5-BF27-4802-9C77-C31608E4A789}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] <bePatch>{BEEDC043-0F05-4465-8ACA-E2F759EDE9FE}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] <bePatch>{B8C2666D-7989-4C3E-AB54-E3215395081F}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] <bePatch>{1CAB6210-66C5-43AD-8E7B-4AE8F5C551B5}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] <bePatch>{9E107BEC-A225-4A5B-91D5-53D147FF83D3}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] <bePatch>{4AD924AA-1C8C-427D-96CA-42D875D86F1B}</bePatch>
BENETNS: [05/29/14 08:56:36] [4736] </bePatchList>
BENETNS: [05/29/14 08:56:36] [4736] <beRunning64Bit>1</beRunning64Bit>
BENETNS: [05/29/14 08:56:36] [4736] </Computer>
BENETNS: [05/29/14 08:56:36] [4736] End of NRDS Protocol Message.
BENETNS: [05/29/14 08:56:36] [1752] Updating cache for DCCDC01.domdubai01.com.
BENETNS: [05/29/14 08:56:36] [1752] existing replaced with new -- master new:0 master old:0 secure new:1 secure old:1
BENETNS: [05/29/14 08:56:36] [1752] Connecting to BE Database.
BESERVER: [05/29/14 08:56:36] [0540] -1 Client requested key (1401339573).
BESERVER: [05/29/14 08:56:36] [0540] 01 Server Configuration: Client added: 10
BESERVER: [05/29/14 08:56:36] [0540] -1 Client 'DCCBKP01' connected('','DOMDUBAI01\backupadmin'): 0xa90fad0
BENETNS: [05/29/14 08:56:36] [1752] Successfully connected to BE Database.
BENETNS: [05/29/14 08:56:36] [1752] Reading agent database record for DCCDC01.domdubai01.com.
BENETNS: [05/29/14 08:56:36] [1752] Found agent record 81 for DCCDC01.domdubai01.com.
BENETNS: [05/29/14 08:56:36] [1752] Updating agent record for DCCDC01.domdubai01.com.
BENETNS: [05/29/14 08:56:36] [1752] Write Successful, key=81.
BESERVER: [05/29/14 08:56:36] [0540] 01 Server Configuration: Client removed: 9
BESERVER: [05/29/14 08:56:36] [0540] -1 Client 'DCCBKP01' Disconnected:0xa90fad0
BENETNS: [05/29/14 08:56:36] [1752] Disconnected from BE Database.
BENETNS: [05/29/14 08:56:36] [1752] Update complete.
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 721 ) Looking up cluster node info at \\DCCBKP01\DLOClus\DLOCluster\ActiveNode.bin exists: No
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 434 ) failed looking up cluster node info: 0000000080070043 virtualServer DCCBKP01\BKUPEXEC media server DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> licensemonitor.cpp( 145 ) entering LicenseMonitor::Run
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 103 ) Entering DeleteUserMonitor::Run
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM GlobalSettings
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 126 ) Entering DeleteUserMonitor::DeleteUserProc.
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM SUser WHERE UserFlags != 0
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM SUser WHERE UserFlags != 0
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 182 ) Leaving DeleteUserMonitor::DeleteUserProc.
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 260 ) Entering DeleteUserMonitor::DeleteComputerProc.
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT M.MSID, M.MSName, S.* FROM MachineSettings M, SUser S WHERE M.MSUserID = S.UserID AND M.MSFlags != 0
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT M.MSID, M.MSName, S.* FROM MachineSettings M, SUser S WHERE M.MSUserID = S.UserID AND M.MSFlags != 0
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 333 ) Leaving DeleteUserMonitor::DeleteComputerProc.
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> deleteusermonitor.cpp( 121 ) Leaving DeleteUserMonitor::Run
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 687 ) timeout: 20, retry interval: 300
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1056 ) : query - SELECT * FROM NotificationQueue
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 921 ) ADODB::GetActiveClusterNodeName m_strLastKnownServerName: DCCBKP01\BKUPEXEC strNodeName: DCCBKP01\BKUPEXEC
DLOADMIN: [05/29/14 08:57:03] [0000] dloadminsvcu.exe 4916: T> adodb.cpp( 1194 ) : finished query - SELECT * FROM NotificationQueue
BENGINE: [05/29/14 08:57:10] [3264] [server] - DeviceManager: timeout event fired
BENGINE: [05/29/14 08:57:10] [3264] [server] - DeviceManager: processing pending requests
BENGINE: [05/29/14 08:57:10] [3264] [server] - DeviceManager: Tossing drive:
BENGINE: [05/29/14 08:57:10] [3264] [server] - HP 0001 {537b1d0b-de72-4944-a600-c1b4ca9dc662}
BENGINE: [05/29/14 08:57:10] [3264] [tpfmt] - TF_FreeDriveContext( 4B0CDA0 )
BENGINE: [05/29/14 08:57:10] [3264] [tpfmt] - TF_FreeTapeBuffers: from 10 to 0 buffers
BENGINE: [05/29/14 08:57:10] [3264] [tpfmt] - FreeFormatEnv( cur_fmt=0 )
BENGINE: [05/29/14 08:57:10] [3264] [tpfmt] - OTC_Close: Deleted set map file.
PVLSVR: [05/29/14 08:57:10] [6396] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_DISMOUNT ) Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E} Flags = 0x00000020
PVLSVR: [05/29/14 08:57:10] [6396] AdammSession::DismountMedia() Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E} Library = {E398FFE8-E4F1-4EBF-8427-454B546A4443}, "HP 0002" Slot = 0001 Drive = {537B1D0B-DE72-4944-A600-C1B4CA9DC662}, "HP 0001" Media = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, "000056L5" Side = 0001
PVLSVR: [05/29/14 08:57:10] [6396] PvlDrive::DismountMedia() Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E} Drive = {537B1D0B-DE72-4944-A600-C1B4CA9DC662}, "HP 0001" Slot = 4294967295 Side = 4294967295
PVLSVR: [05/29/14 08:57:10] [6396] PvlDrive::DismountMedia() - Release connection Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E} Drive = {537B1D0B-DE72-4944-A600-C1B4CA9DC662}, "HP 0001"
BECAT: [05/29/14 08:57:10] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:57:10] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:57:10] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:57:10] [1824] {CALL UpdateCatMediaInfo( {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, 000056L5, {00000009-03E9-0000-0000-000000000000}) }
BECAT: [05/29/14 08:57:10] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:57:10] [1824] status(1) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:57:10] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:57:10] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:57:10] [1824] SELECT * FROM ImageObjectView where 1=1 AND MediaGuid = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} AND (FragmentState != 1 or FragmentState is NULL) AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' ORDER BY MediaFamilyGuid , ImageNumber , MediaNumber
BECAT: [05/29/14 08:57:10] [1824] Number of records returned: 6
BECAT: [05/29/14 08:57:10] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:57:10] [1824] Reset connection ...
BECAT: [05/29/14 08:57:10] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:57:10] [1824] Reset connection ...
PVLSVR: [05/29/14 08:57:10] [6396] PvlDrive::DismountMedia() - changer reservation successful. Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E} Drive = {537B1D0B-DE72-4944-A600-C1B4CA9DC662}, "HP 0001" Slot = 0001 SUCCESS!
PVLSVR: [05/29/14 08:57:10] [6396] PvlChanger::DismountMedia() Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E} Library = {E398FFE8-E4F1-4EBF-8427-454B546A4443}, "HP 0002" Drive = {537B1D0B-DE72-4944-A600-C1B4CA9DC662}, "HP 0001" Slot = 0001 Side = 0001
BKUPEXEC: [05/29/14 08:57:10] [0025] [Info] VSN received: type = DRIVE_UPDATE
BKUPEXEC: [05/29/14 08:57:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:57:10] [0025] [Info] VSN received: type = MEDIA_UPDATE
BKUPEXEC: [05/29/14 08:57:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:57:10] [0025] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [05/29/14 08:57:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:57:10] [0025] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [05/29/14 08:57:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:57:10] [0025] [Info] VSN received: type = CATALOG_UPDATE
BKUPEXEC: [05/29/14 08:57:10] [0025] [Debug] No handler registered for this VSN - ignoring
BESERVER: [05/29/14 08:57:11] [4812] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
PVLSVR: [05/29/14 08:57:11] [0956] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = 0031 EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:57:11] [4812] 05 AdammAdminBO::Execute() - hr = 0x0
BKUPEXEC: [05/29/14 08:57:11] [0025] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [05/29/14 08:57:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:57:11] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:57:11] [0025] [Info] VSN received: type = BEM_LIBRARY
BKUPEXEC: [05/29/14 08:57:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:57:11] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:57:11] [0025] [Info] VSN received: type = BEM_MEDIA
BKUPEXEC: [05/29/14 08:57:11] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:57:11] [0025] [Debug] VSN added to queues
MMC: [05/29/14 08:57:53] [0000] 6964: [CBS] IsCacheStillGood: True.
BECAT: [05/29/14 08:58:10] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:58:10] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:58:10] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:58:10] [1824] {CALL UpdateCatMediaInfo( {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0}, 000056L5, {00000009-03E9-0000-0000-000000000000}) }
BECAT: [05/29/14 08:58:10] [1824] Get a Connection from Pool : Size(3)
BECAT: [05/29/14 08:58:10] [1824] status(1) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:58:10] [1824] status(0) connectServerComponent : in (0) -- current 0
BECAT: [05/29/14 08:58:10] [1824] ValidateConnection : 1
BECAT: [05/29/14 08:58:10] [1824] SELECT * FROM ImageObjectView where 1=1 AND MediaGuid = {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} AND (FragmentState != 1 or FragmentState is NULL) AND ExpirationPolicyID != '9D094A9C-BBBB-402c-A35D-8A46984A19D2' ORDER BY MediaFamilyGuid , ImageNumber , MediaNumber
BECAT: [05/29/14 08:58:10] [1824] Number of records returned: 6
PVLSVR: [05/29/14 08:58:10] [6396] AdammSession::DismountMedia() - {FBDAD29D-CE27-4902-B65C-D2CC6D4C69E0} 2626378
BECAT: [05/29/14 08:58:10] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:58:10] [1824] Reset connection ...
BECAT: [05/29/14 08:58:10] [1824] ReleaseConnection ...
BECAT: [05/29/14 08:58:10] [1824] Reset connection ...
PVLSVR: [05/29/14 08:58:10] [6396] AdammSession::Release() Session = {E185B78D-E0C2-4535-98C1-2BED2CFD698E}
BENGINE: [05/29/14 08:58:10] [3264] [server] - DeviceManager: going to sleep for 900000 msecs
BENGINE: [05/29/14 08:58:10] [3264] [server] - DeviceManager: dismount event fired
BENGINE: [05/29/14 08:58:10] [3264] [server] - DeviceManager: processing pending requests
BENGINE: [05/29/14 08:58:10] [3264] [server] - DeviceManager: going to sleep for 900000 msecs
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = SLOT_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = MEDIA_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = DRIVE_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = CATALOG_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] No handler registered for this VSN - ignoring
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:10] [0025] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [05/29/14 08:58:10] [0025] [Debug] VSN added to queues
BESERVER: [05/29/14 08:58:12] [4812] 01 No archive option installed. EV Embedded status defaults to true and not supported version.
BESERVER: [05/29/14 08:58:12] [4812] 01 ServerConfigBO:GetFeatureText, The Unknown Option's ID is 97
BESERVER: [05/29/14 08:58:12] [4812] "Cluster" key does not appear to be present in the registry
BESERVER: [05/29/14 08:58:12] [4812] Failed to open Microsoft cluster ()
BESERVER: [05/29/14 08:58:12] [4812] VCS cluster keys do not appear to be present in the registry
BESERVER: [05/29/14 08:58:12] [4812] Failed to open VCS cluster ()
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = LIBRARY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 01 No archive option installed. EV Embedded status defaults to true and not supported version.
BESERVER: [05/29/14 08:58:12] [4812] 01 ServerConfigBO:GetFeatureText, The Unknown Option's ID is 97
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {4DC0A3C6-FC7B-40DC-8249-20A0FDD3223E} EntityType = LIBRARY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_MEDIA_SUMMARY.
PVLSVR: [05/29/14 08:58:12] [6396] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = 0031 EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00000000
BESERVER: [05/29/14 08:58:12] [4812] 05 AdammAdminBO::Execute() - hr = 0x0
BKUPEXEC: [05/29/14 08:58:12] [0025] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:12] [0025] [Info] VSN received: type = BEM_LIBRARY
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:12] [0025] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN added to queues
BKUPEXEC: [05/29/14 08:58:12] [0025] [Info] VSN received: type = BEM_MEDIA
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN is a MODIFY
BKUPEXEC: [05/29/14 08:58:12] [0025] [Debug] VSN added to queues
BENETNS: [05/29/14 08:58:41] [1816] Beginning agent database grooming.
BENETNS: [05/29/14 08:58:41] [1816] Agent database grooming complete.
CSRSS: [05/29/14 08:59:16] [0000] Error -
CSRSS: [05/29/14 08:59:16] [0000] ReadProcessMemory failed while trying to read PebBaseAddress
CSRSS: [05/29/14 08:59:16] [0000]
CSRSS: [05/29/14 08:59:16] [0000] Error -
CSRSS: [05/29/14 08:59:16] [0000] Failed to read the peb from the process
CSRSS: [05/29/14 08:59:16] [0000]
