*************************************************************************** ***** SGMon log for \\EPACBIEMTSNODE ***** *************************************************************************** BESERVER: [12/02/11 15:47:35] [3168] -1 Job Manager: LoadJob() request completed BESERVER: [12/02/11 15:47:35] [3168] 17 JobManager::UpdateJobInstanceData() new task state=22 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = NULL , Entity = MACHINE PVLSVR: [12/02/11 15:47:37] [4324] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MACHINE_ENTITY_TYPE EntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = NULL , Entity = VAULT PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = VAULT_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = NULL , Entity = STORAGE_ARRAY_ENTITY PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = STORAGE_ARRAY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = NULL , Entity = DEVICE_POOL PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DEVICE_POOL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE PVLSVR: [12/02/11 15:47:37] [4480] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = BACKUP_TO_DISK PVLSVR: [12/02/11 15:47:37] [4480] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE_POOL_DRIVE PVLSVR: [12/02/11 15:47:37] [4324] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = DRIVE_POOL_DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = CHANGER PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = LIBRARY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = " EntityFlags = 0x00008000 BESERVER: [12/02/11 15:47:37] [4012] 12 AdammAdminBO::Query() - hr = 0x0 BESERVER: [12/02/11 15:47:37] [5268] -1 ActiveState::doDispatch( ): 'Backup 00001' BESERVER: [12/02/11 15:47:37] [5268] -1 LocalSvr, runTask():{d5b498b1-c030-4980-a9dd-690a436ae79f} BESERVER: [12/02/11 15:47:37] [5268] -1 BengineWrapper runtask() start BESERVER: [12/02/11 15:47:37] [5268] -1 BengineWrapper JobEngine.Open() OK BESERVER: [12/02/11 15:47:37] [4012] 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR BESERVER: [12/02/11 15:47:37] [3216] -1 Client requested key (1322859011). BESERVER: [12/02/11 15:47:37] [3168] "Cluster" key does not appear to be present in the registry BESERVER: [12/02/11 15:47:37] [3168] Failed to open Microsoft cluster () BESERVER: [12/02/11 15:47:37] [3168] VCS cluster keys do not appear to be present in the registry BESERVER: [12/02/11 15:47:37] [3168] Failed to open VCS cluster () BESERVER: [12/02/11 15:47:37] [3168] 01 Server Configuration: Client added: 8 BESERVER: [12/02/11 15:47:37] [3168] -1 Client 'EPACBIEMTSNODE' connected('','NT AUTHORITY\SYSTEM'): 0x1d98cb0 BENGINE: [12/02/11 15:47:37] [4596] JobEngine::LaunchJob BENGINE: [12/02/11 15:47:37] [3164] Thread for job Backup 00001 ({D5B498B1-C030-4980-A9DD-690A436AE79F}) starting BENGINE: [12/02/11 15:47:37] [3164] Thread for job Backup 00001 ({D5B498B1-C030-4980-A9DD-690A436AE79F}) starting BESERVER: [12/02/11 15:47:37] [5268] -1 BengineWrapper JobEngine.RunJobWithGuidAndServer() rc=0 BESERVER: [12/02/11 15:47:37] [5268] -1 JobManager: BengineWrapper RunJob() done BENGINE: [12/02/11 15:47:37] [3164] viaNetwork enabled 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork enabled 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork protocol 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork protocol 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork MAC ---><--- BENGINE: [12/02/11 15:47:37] [3164] viaNetwork MAC ---><--- BENGINE: [12/02/11 15:47:37] [3164] viaNetwork IPv4 subnetAddress 0.0.0.0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork IPv4 subnetAddress 0.0.0.0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork IPv4 autoObtain 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork IPv4 autoObtain 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork selectedProtocol 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork selectedProtocol 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork InterfaceIndex 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork InterfaceIndex 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork PrefixLength 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork PrefixLength 0 BENGINE: [12/02/11 15:47:37] [3164] viaNetwork FallBackOptions 0xF Protocol Tunnel NIC Subnet BENGINE: [12/02/11 15:47:37] [3164] viaNetwork FallBackOptions 0xF Protocol Tunnel NIC Subnet BESERVER: [12/02/11 15:47:37] [3216] -1 Client requested key (1322859011). BESERVER: [12/02/11 15:47:37] [3168] 01 Server Configuration: Client added: 9 BESERVER: [12/02/11 15:47:37] [3168] -1 Client 'EPACBIEMTSNODE' connected('','NT AUTHORITY\SYSTEM'): 0x1d98d10 BESERVER: [12/02/11 15:47:37] [3168] 01 Server Configuration: Client removed: 8 BENGINE: [12/02/11 15:47:37] [3164] "Cluster" key does not appear to be present in the registry BENGINE: [12/02/11 15:47:37] [3164] "Cluster" key does not appear to be present in the registry BENGINE: [12/02/11 15:47:37] [0448] Status for: 'Backup 00001' updated BENGINE: [12/02/11 15:47:37] [0448] Status for: 'Backup 00001' updated BENGINE: [12/02/11 15:47:37] [3164] Failed to open Microsoft cluster () BENGINE: [12/02/11 15:47:37] [3164] Failed to open VCS cluster () BENGINE: [12/02/11 15:47:37] [3164] Failed to open VCS cluster () BENGINE: [12/02/11 15:47:37] [3164] Checking for Evaluation expiration. BENGINE: [12/02/11 15:47:37] [3164] ENGSCRPT: IN - oldDeviceName = D: BENGINE: [12/02/11 15:47:37] [3164] ENGSCRPT: IN - oldDeviceName = D: BENGINE: [12/02/11 15:47:37] [3164] ENGSCRPT: OUT - newName = D: BENGINE: [12/02/11 15:47:37] [3164] SetupCryptInfoInBSD: Backup encryption options: bHWEncryption=0,bSWEncryption=0,bSWEncryptionIfNoHW=0 BENGINE: [12/02/11 15:47:37] [3164] Failed to Load AllowExcludes key, We will update DR file evenif there are userExcludes. Error: 5 BENGINE: [12/02/11 15:47:37] [3164] Failed to Load AllowExcludes key, We will update DR file evenif there are userExcludes. Error: 5 PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = DRIVE_POOL_ENTITY_TYPE ParentEntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityType = NULL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityName = "" EntityFlags = 0x00000000 BENGINE: [12/02/11 15:47:37] [4144] DeviceManager: incoming event fired BENGINE: [12/02/11 15:47:37] [4144] DeviceManager: query cache miss BENGINE: [12/02/11 15:47:37] [4144] DeviceManager: query cache miss PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = DRIVE_POOL_ENTITY_TYPE ParentEntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityType = NULL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 PVLSVR: [12/02/11 15:47:37] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityName = "" EntityFlags = 0x00000000 BENGINE: [12/02/11 15:47:37] [4144] Available and held drives from the pool BENGINE: [12/02/11 15:47:37] [4144] Drives not in use by the Engine BENGINE: [12/02/11 15:47:37] [4144] Drives not in use by the Engine BENGINE: [12/02/11 15:47:37] [4144] Drives available (not in use and not held) BENGINE: [12/02/11 15:47:37] [4144] Drives available (not in use and not held) BENGINE: [12/02/11 15:47:37] [4144] Backup-to-Disk Folder 1 {3042d161-2cd7-4d57-8e4d-fd1af1bc852b}.0 PVLSVR: [12/02/11 15:47:37] [4480] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} TargetDevice = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" (Backup To Disk) PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set) PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} BENGINE: [12/02/11 15:47:37] [4144] Handing out session {97bba5d8-6f3a-406d-a28a-8361f8295e65} based on available drive: PVLSVR: [12/02/11 15:47:37] [4324] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityName = "" EntityFlags = 0x00000000 PVLSVR: [12/02/11 15:47:37] [4324] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityName = "" EntityFlags = 0x00000000 BENGINE: [12/02/11 15:47:37] [4144] DeviceManager: returned result to caller 0x00000000 BENGINE: [12/02/11 15:47:37] [4144] DeviceManager: going to sleep for 900000 msecs PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_JOB_INFO ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Job = {D5B498B1-C030-4980-A9DD-690A436AE79F}, "Backup 00001" PVLSVR: [12/02/11 15:47:37] [4480] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_HOST ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Device Host = "" Device Port = 0000 BENGINE: [12/02/11 15:47:37] [5232] BackupJob: thread id 0x00001470 acquired device Backup-to-Disk Folder 1. BENGINE: [12/02/11 15:47:37] [5232] BackupJob: thread id 0x00001470 trying to mount appending volume. PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} TargetDevice = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" (Backup To Disk) PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set) PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::CheckTargetDeviceStatus() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Job = {D5B498B1-C030-4980-A9DD-690A436AE79F}, "Backup 00001" TargetDevice = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" State = 0003, ReservationID = 0000, AvailableMovers = 0001 PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::MountAppendMedia() PVLSVR: [12/02/11 15:47:37] [0688] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} ERROR = 0xE000810D (E_PVL_MEDIA_NOT_FOUND) BENGINE: [12/02/11 15:47:37] [5232] BackupJob: thread id 0x00001470 unable to mount appending volume, attempting scratch mount. PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_OVERWRITE ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} TargetDevice = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" (Backup To Disk) PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set) PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::CheckTargetDeviceStatus() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Job = {D5B498B1-C030-4980-A9DD-690A436AE79F}, "Backup 00001" TargetDevice = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" State = 0003, ReservationID = 0000, AvailableMovers = 0001 PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::MountOverwriteMedia() PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::MountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} Slot = 0000 Side = 0000 PVLSVR: [12/02/11 15:47:37] [4324] PvlDrive::MountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Slot = 0000 Side = 0001 PVLSVR: [12/02/11 15:47:37] [4324] PvlChanger::MountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Library = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Slot = 0000 Side = 0001 PVLSVR: [12/02/11 15:47:37] [4324] PvlDrive::GetMediaStatus() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Elapsed Seconds = 0000, Sense Code = 0x00000000 SUCCESS! PVLSVR: [12/02/11 15:47:37] [4324] PvlDrive::MountMedia() - Establish connection Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::MountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Library = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Slot = 0000 Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Media = {ACC37D55-62A0-451E-93BF-6573A4B80353}, "B2D000003" Side = 0001 SUCCESS! PVLSVR: [12/02/11 15:47:37] [4324] AdammSession::IsOverwriteMediaMounted() - Need WORM: No Need Encryption: No PVLSVR: [12/02/11 15:47:38] [4324] AdammSession::IsOverwriteMediaMounted() - SUCCESS! PVLSVR: [12/02/11 15:47:38] [4324] PvlSession::MountOverwriteMedia() MediaLabel = B2D000003 m_nPriority = 0xBC18045B PVLSVR: [12/02/11 15:47:38] [4480] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B} EntityName = "" EntityFlags = 0x00000000 BENGINE: [12/02/11 15:47:38] [5232] Updating session {97bba5d8-6f3a-406d-a28a-8361f8295e65} with drive Backup-to-Disk Folder 1 {3042d161-2cd7-4d57-8e4d-fd1af1bc852b}.1 BENGINE: [12/02/11 15:47:38] [5232] BackupJob: thread id 0x00001470 medium successfully mounted. BENGINE: [12/02/11 15:47:38] [3164] Media Label: B2D000003 BENGINE: [12/02/11 15:47:38] [3164] Media Label: {acc37d55-62a0-451e-93bf-6573a4b80353} BENGINE: [12/02/11 15:47:38] [3164] Overwrite Protected Until: 12/30/9999 7:00:00 PM BENGINE: [12/02/11 15:47:38] [3164] Appendable Until: 12/30/9999 7:00:00 PM BENGINE: [12/02/11 15:47:38] [3164] TAPEALERT: Get TapeAlert Flags Return Code = 0X0 BENGINE: [12/02/11 15:47:38] [3164] TAPEALERT: TapeAlert Device Flag = 0X0 BENGINE: [12/02/11 15:47:38] [3164] TAPEALERT: TapeAlert Changer Flag = 0X0 BENGINE: [12/02/11 15:47:38] [3164] TAPEALERT: Get TapeAlert Flags Return Code = 0X0 BENGINE: [12/02/11 15:47:38] [3164] TAPEALERT: Get TapeAlert Flags Return Code = 0X0 BENGINE: [12/02/11 15:47:38] [3164] Job Family ID: {F5B33AA0-FB45-47E6-A5D8-705365F9E38E} BENGINE: [12/02/11 15:47:38] [3164] Backup-to-Disk Folder 1 Block size: 1024 BENGINE: [12/02/11 15:47:38] [3164] Backup-to-Disk Folder 1 Buffer size: 32768 BENGINE: [12/02/11 15:47:38] [3164] Backup-to-Disk Folder 1 Num buffers: 10 BENGINE: [12/02/11 15:47:38] [3164] Backup-to-Disk Folder 1 High water: 7 BENGINE: [12/02/11 15:47:38] [3164] Enter CheckHostBSDsForSnaps BENGINE: [12/02/11 15:47:38] [3164] Exit CheckHostBSDsForSnaps: true BENGINE: [12/02/11 15:47:38] [3164] NDMPAgentConnector:SetupConnection(): Data Server = EPACBIEMTSNODE BENGINE: [12/02/11 15:47:38] [3164] Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000 BENGINE: [12/02/11 15:47:38] [3164] ndmpConnectEx: 'EPACBIEMTSNODE' resolves to the local machine itself. So, we will try and connect to a loopback address ... BENGINE: [12/02/11 15:47:38] [3164] ndmpConnectEx : Control Connection information: A connection was established between end-points 127.0.0.1:2389 and 127.0.0.1:10000. BESERVER: [12/02/11 15:47:38] [4428] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE PVLSVR: [12/02/11 15:47:38] [4480] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 BESERVER: [12/02/11 15:47:38] [4428] 12 AdammAdminBO::Execute() - hr = 0x0 BESERVER: [12/02/11 15:47:38] [4428] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK PVLSVR: [12/02/11 15:47:38] [4324] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 BESERVER: [12/02/11 15:47:38] [4428] 12 AdammAdminBO::Execute() - hr = 0x0 BESERVER: [12/02/11 15:47:42] [4012] 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR BENGINE: [12/02/11 15:47:42] [0448] Updating status for: 'Backup 00001' (0x0 0xB) BENGINE: [12/02/11 15:47:42] [0448] Status for: 'Backup 00001' updated BENGINE: [12/02/11 15:48:08] [3164] ERROR: ndmpcSendRequest(): timeout waiting for reply from control connection BENGINE: [12/02/11 15:48:08] [3164] ERROR: ndmpSendRequest failed: BENGINE: [12/02/11 15:48:08] [3164] NDMPAgentConnector::SetupConnection: connectOpen failed on server EPACBIEMTSNODE. BENGINE: [12/02/11 15:48:08] [3164] NDMPHostBackupEngine:ConnectToDataServer() failed for machine EPACBIEMTSNODE. Skipping BSDs for this machine. BENGINE: [12/02/11 15:48:08] [3164] TAPEALERT: Get TapeAlert Flags Return Code = 0X0 BENGINE: [12/02/11 15:48:08] [3164] TAPEALERT: TapeAlert Device Flag = 0X0 BENGINE: [12/02/11 15:48:08] [3164] TAPEALERT: TapeAlert Changer Flag = 0X0 BENGINE: [12/02/11 15:48:08] [3164] TAPEALERT: Get TapeAlert Flags Return Code = 0X0 BENGINE: [12/02/11 15:48:08] [3164] TAPEALERT: Get TapeAlert Flags Return Code = 0X0 PVLSVR: [12/02/11 15:48:08] [0688] PvlEntityDatabase::UpdateEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE Media = {ACC37D55-62A0-451E-93BF-6573A4B80353}, "" EntityFlags = 0x008C0000 PVLSVR: [12/02/11 15:48:08] [0688] PvlEntityDatabase::UpdateEntity() : SUCCESS! BENGINE: [12/02/11 15:48:08] [4144] DeviceManager: incoming event fired PVLSVR: [12/02/11 15:48:08] [4480] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_DISMOUNT ) Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Flags = 0x00000020 PVLSVR: [12/02/11 15:48:08] [4480] AdammSession::DismountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Library = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Slot = 0000 Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Media = {ACC37D55-62A0-451E-93BF-6573A4B80353}, "B2D000003" Side = 0001 PVLSVR: [12/02/11 15:48:08] [4480] PvlDrive::DismountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Slot = 4294967295 Side = 4294967295 PVLSVR: [12/02/11 15:48:08] [4480] PvlDrive::DismountMedia() - Release connection Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" PVLSVR: [12/02/11 15:48:08] [4480] PvlChanger::DismountMedia() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} Library = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Drive = {3042D161-2CD7-4D57-8E4D-FD1AF1BC852B}, "Backup-to-Disk Folder 1" Slot = 0000 Side = 0001 PVLSVR: [12/02/11 15:48:08] [0688] AdammSession::Release() Session = {97BBA5D8-6F3A-406D-A28A-8361F8295E65} BENGINE: [12/02/11 15:48:08] [4144] DeviceManager: processing pending requests BENGINE: [12/02/11 15:48:08] [4144] DeviceManager: going to sleep for 900000 msecs BENGINE: [12/02/11 15:48:08] [4144] DeviceManager: dismount event fired BENGINE: [12/02/11 15:48:08] [4144] DeviceManager: processing pending requests BENGINE: [12/02/11 15:48:08] [4144] DeviceManager: going to sleep for 900000 msecs BENGINE: [12/02/11 15:48:08] [3164] Removing 'Backup 00001' from status update list BENGINE: [12/02/11 15:48:08] [3164] Updating status for: 'Backup 00001' (0x18 0x0) BENGINE: [12/02/11 15:48:08] [3164] Status for: 'Backup 00001' updated BENGINE: [12/02/11 15:48:08] [3164] BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary BENGINE: [12/02/11 15:48:08] [3164] Ending job 'Backup 00001' with error status (-536837013) BESERVER: [12/02/11 15:48:08] [3168] 17 JobManager::HandleEndJob() called BESERVER: [12/02/11 15:48:08] [2916] -1 ActiveState::doEndEvent( ): 'Backup 00001' BESERVER: [12/02/11 15:48:08] [2916] 17 JobManager::DoJobCompletionTasks(Backup 00001): GetFinalJobStatus()=6 BENGINE: [12/02/11 15:48:08] [3164] Job thread terminating BESERVER: [12/02/11 15:48:08] [2916] 17 JobManager::DoJobCompletionTasks() Handling normal JOB_STATE_ERROR BESERVER: [12/02/11 15:48:08] [2916] 17 JobManager::DoJobCompletionTasks() Finished building JOB_STATE_ERROR message BESERVER: [12/02/11 15:48:08] [4428] 01 Server Configuration: Client removed: 7 BESERVER: [12/02/11 15:48:08] [4428] -1 Client 'EPACBIEMTSNODE' Disconnected:0x1d98cb0 BESERVER: [12/02/11 15:48:08] [2916] 18 Alert Job Failed, Notify: No, EventLog: Yes, Trap: No BESERVER: [12/02/11 15:48:08] [2916] -1 ActiveState::doEndEvent( ): CJobManager::DoJobCompletionTasks() returned 0x0 BKUPEXEC: [12/02/11 15:48:08] [0000] UIVirtualObjectView(JobHistoryView) Queried 10 items: 0 to 9 BESERVER: [12/02/11 15:48:10] [4428] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE PVLSVR: [12/02/11 15:48:10] [4324] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 BESERVER: [12/02/11 15:48:10] [4428] 12 AdammAdminBO::Execute() - hr = 0x0 BESERVER: [12/02/11 15:48:10] [4428] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK PVLSVR: [12/02/11 15:48:10] [0688] PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {BF18D278-E245-4F44-92CE-F0FD72EB4ABE} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 BESERVER: [12/02/11 15:48:10] [4428] 12 AdammAdminBO::Execute() - hr = 0x0 BESERVER: [12/02/11 15:48:12] [4012] 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR BESERVER: [12/02/11 15:48:17] [4012] 17 JobManager: Sending History Update VSN, count:1. BKUPEXEC: [12/02/11 15:48:17] [0000] UIVirtualObjectView(JobHistoryView) Queried 10 items: 0 to 9