all device jobs go to a queued status

Kerr_QIu · ‎10-31-2015

****************************************************************************
***** SGMon log for \\\\www01 *****
***** Local Offset =-08:00 *****
****************************************************************************
BKUPEXEC: [10/31/15 22:34:09] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:09] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:09] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:09] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:09] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:09] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:14] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:14] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:14] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:14] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:14] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:14] [0040] [Debug] VSN added to queues
BECAT: [10/31/15 22:34:15] [12448] Totla records = 2060000
BKUPEXEC: [10/31/15 22:34:19] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:19] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:19] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:19] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:19] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:19] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:24] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:24] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:24] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:24] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:24] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:24] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:29] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:29] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:29] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:29] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:29] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:29] [0040] [Debug] VSN added to queues
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 DeviceManager: timeout event fired
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 DeviceManager: processing pending requests
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 DeviceManager: query cache miss
PVLSVR: [10/31/15 22:34:33] [9292] PvlEntityDatabase::QueryEntity()

ParentEntityType = DRIVE_POOL_ENTITY_TYPE

ParentEntityGuid = {23477E7C-BD80-46C9-8413-46E7216EA533}

EntityType = NULL_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 Available and held drives from the pool
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 4 {8a94d98e-10c0-4530-b763-f4e2d7d79d2a}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 1 {705c37fa-7fa9-4b77-a93e-471cd6f9dc8d}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 6 {56c40f19-8b66-4d76-8a8a-4ba6661003b0}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 Drives not in use by the Engine
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 Drives available (not in use and not held)
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

TargetDevice = {23477E7C-BD80-46C9-8413-46E7216EA533}, "IBM 2 [0001..0007]" (Device Pool)

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7" (Drive)

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4" (Drive)

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1" (Drive)

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6" (Drive)
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 Handing out session {00d4d936-71fb-4fe7-9cdf-f69f43e2355b} based on available drive:
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 DeviceManager: returned result to caller 0x00000000
BENGINE: [10/31/15 22:34:33] [0000] [13348] 10/31/15 22:34:33 DeviceManager: going to sleep for 900000 msecs
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.364 PvlEntityDatabase::QueryEntity()

ParentEntityType = DRIVE_POOL_ENTITY_TYPE

ParentEntityGuid = {23477E7C-BD80-46C9-8413-46E7216EA533}

EntityType = NULL_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_JOB_INFO )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_MORE_JOB_INFO )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"

SessionFlags = 0x00000002

JobFamilyID = {72E79C19-11D1-42D4-9765-A31CDD50167C}
BENGINE: [10/31/15 22:34:33] [5512] BackupJob::MountThread : Device path hint : m_deviceHost.empty() is TRUE
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.364 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_HOST )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

Device Host = ""
BENGINE: [10/31/15 22:34:33] [5512] BackupJob: thread id 0x00001588 acquired device IBM 7.
BENGINE: [10/31/15 22:34:33] [5512] BackupJob: thread id 0x00001588 trying to mount appending volume.
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

TargetDevice = {23477E7C-BD80-46C9-8413-46E7216EA533}, "IBM 2 [0001..0007]" (Device Pool)

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7" (Drive)

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4" (Drive)

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1" (Drive)

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6" (Drive)
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::CheckTargetDeviceStatus()

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7"

State = 0003, ReservationID = 0000, AvailableMovers = 0001

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6"

State = 0003, ReservationID = 0000, AvailableMovers = 0000
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::MountAppendMedia()
PVLSVR: [10/31/15 22:34:33] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

ERROR = 0xE000810D (E_PVL_MEDIA_NOT_FOUND)
BENGINE: [10/31/15 22:34:33] [5512] BackupJob: thread id 0x00001588 unable to mount appending volume, attempting scratch mount.
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_OVERWRITE )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

TargetDevice = {23477E7C-BD80-46C9-8413-46E7216EA533}, "IBM 2 [0001..0007]" (Device Pool)

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7" (Drive)

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4" (Drive)

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1" (Drive)

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6" (Drive)
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::CheckTargetDeviceStatus()

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7"

State = 0003, ReservationID = 0000, AvailableMovers = 0001

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6"

State = 0003, ReservationID = 0000, AvailableMovers = 0000
PVLSVR: [10/31/15 22:34:33] [9292] AdammSession::MountOverwriteMedia()
BESERVER: [10/31/15 22:34:33] [0000] [14224] 10/31/15 22:34:33 01 CServerConfigBO::Query (CBemLicenseInfo) - No archive option installed. EV Embedded status defaults to true.
BESERVER: [10/31/15 22:34:33] [0000] [14224] 10/31/15 22:34:33 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.614 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:33] [0000] [14224] 10/31/15 22:34:33 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [10/31/15 22:34:33] [0000] [14224] 10/31/15 22:34:33 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.629 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = BACKUP_TO_DISK_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:33] [0000] [14224] 10/31/15 22:34:33 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.629 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:34:33] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = LIBRARY_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:33] [8980] 01 CServerConfigBO::Query (CBemLicenseInfo) - No archive option installed. EV Embedded status defaults to true.
BESERVER: [10/31/15 22:34:33] [8980] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.661 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:33] [8980] 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [10/31/15 22:34:33] [8980] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.676 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = BACKUP_TO_DISK_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:33] [8980] 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [10/31/15 22:34:33] [0000] [14032] 10/31/15 22:34:33.676 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:34:33] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = LIBRARY_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
BKUPEXEC: [10/31/15 22:34:33] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:33] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:33] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0039] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [10/31/15 22:34:33] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:33] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0039] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [10/31/15 22:34:33] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:33] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:33] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:33] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0040] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [10/31/15 22:34:33] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:33] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:33] [0040] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [10/31/15 22:34:33] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:33] [0040] [Debug] VSN added to queues
PVLSVR: [10/31/15 22:34:34] [9292] PvlSession::MountOverwriteMedia() - ERROR = 0xE000810C (E_PVL_DRIVE_NOT_AVAILABLE) - 0001 seconds
PVLSVR: [10/31/15 22:34:34] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_OVERWRITE )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

ERROR = 0xE000810C (E_PVL_DRIVE_NOT_AVAILABLE)
BENGINE: [10/31/15 22:34:34] [5512] BackupJob: thread id 0x00001588 unable to scratch mount, will retry append later.
BENGINE: [10/31/15 22:34:34] [5512] BackupJob: thread id 0x00001588 unable to mount, error 0xE000810C.
PVLSVR: [10/31/15 22:34:34] [0000] [14032] 10/31/15 22:34:34.583 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_DISMOUNT )

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}

Flags = 0x00000020
PVLSVR: [10/31/15 22:34:34] [8792] AdammSession::Release()

Session = {00D4D936-71FB-4FE7-9CDF-F69F43E2355B}
BENGINE: [10/31/15 22:34:34] [0000] [13348] 10/31/15 22:34:34 DeviceManager: incoming event fired
BENGINE: [10/31/15 22:34:34] [0000] [13348] 10/31/15 22:34:34 DeviceManager: stalling
BENGINE: [10/31/15 22:34:34] [0000] [13348] 10/31/15 22:34:34 DeviceManager: going to sleep for 29000 msecs
BKUPEXEC: [10/31/15 22:34:34] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:34] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:34] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:34] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:34] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:34] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:34] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:34] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:34] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:34] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:34:34] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:34] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:34] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:34] [0040] [Debug] VSN added to queues
BESERVER: [10/31/15 22:34:35] [8980] 01 CServerConfigBO::Query (CBemLicenseInfo) - No archive option installed. EV Embedded status defaults to true.
BESERVER: [10/31/15 22:34:35] [8980] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [10/31/15 22:34:35] [0000] [14032] 10/31/15 22:34:35.630 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:35] [8980] 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [10/31/15 22:34:35] [8980] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [10/31/15 22:34:35] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = BACKUP_TO_DISK_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:35] [8980] 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [10/31/15 22:34:35] [9292] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:34:35] [0000] [14032] 10/31/15 22:34:35.645 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = LIBRARY_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:35] [1324] 01 CServerConfigBO::Query (CBemLicenseInfo) - No archive option installed. EV Embedded status defaults to true.
BESERVER: [10/31/15 22:34:35] [1324] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [10/31/15 22:34:35] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:35] [1324] 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [10/31/15 22:34:35] [1324] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [10/31/15 22:34:35] [9292] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = BACKUP_TO_DISK_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:34:35] [1324] 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [10/31/15 22:34:35] [0000] [14032] 10/31/15 22:34:35.692 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:34:35] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = LIBRARY_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
BKUPEXEC: [10/31/15 22:34:35] [0039] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [10/31/15 22:34:35] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:35] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:35] [0039] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [10/31/15 22:34:35] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:35] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:35] [0040] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [10/31/15 22:34:35] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:35] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:35] [0040] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [10/31/15 22:34:35] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:35] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:39] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:39] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:39] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:39] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:39] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:39] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:44] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:44] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:44] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:44] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:44] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:44] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:49] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:49] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:49] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:49] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:49] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:49] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:54] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:54] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:54] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:54] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:54] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:54] [0040] [Debug] VSN added to queues
BECAT: [10/31/15 22:34:58] [12448] Totla records = 2540000
BKUPEXEC: [10/31/15 22:34:59] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:59] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:59] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:34:59] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:34:59] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:34:59] [0040] [Debug] VSN added to queues
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 DeviceManager: timeout event fired
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 DeviceManager: processing pending requests
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 DeviceManager: query cache miss
PVLSVR: [10/31/15 22:35:03] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = DRIVE_POOL_ENTITY_TYPE

ParentEntityGuid = {23477E7C-BD80-46C9-8413-46E7216EA533}

EntityType = NULL_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 Available and held drives from the pool
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 4 {8a94d98e-10c0-4530-b763-f4e2d7d79d2a}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 1 {705c37fa-7fa9-4b77-a93e-471cd6f9dc8d}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 6 {56c40f19-8b66-4d76-8a8a-4ba6661003b0}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 Drives not in use by the Engine
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 Drives available (not in use and not held)
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )

Session = {067FD268-9040-46F1-A909-991386651345}

TargetDevice = {23477E7C-BD80-46C9-8413-46E7216EA533}, "IBM 2 [0001..0007]" (Device Pool)

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7" (Drive)

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4" (Drive)

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1" (Drive)

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6" (Drive)
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )

Session = {067FD268-9040-46F1-A909-991386651345}

TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )

Session = {067FD268-9040-46F1-A909-991386651345}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 Handing out session {067fd268-9040-46f1-a909-991386651345} based on available drive:
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 IBM 7 {1eae01ee-0aff-46f0-93ac-44d3759a521a}
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 DeviceManager: returned result to caller 0x00000000
BENGINE: [10/31/15 22:35:03] [0000] [13348] 10/31/15 22:35:03 DeviceManager: going to sleep for 900000 msecs
PVLSVR: [10/31/15 22:35:03] [0000] [14032] 10/31/15 22:35:03.620 PvlEntityDatabase::QueryEntity()

ParentEntityType = DRIVE_POOL_ENTITY_TYPE

ParentEntityGuid = {23477E7C-BD80-46C9-8413-46E7216EA533}

EntityType = NULL_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_JOB_INFO )

Session = {067FD268-9040-46F1-A909-991386651345}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_MORE_JOB_INFO )

Session = {067FD268-9040-46F1-A909-991386651345}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"

SessionFlags = 0x00000002

JobFamilyID = {72E79C19-11D1-42D4-9765-A31CDD50167C}
BENGINE: [10/31/15 22:35:03] [5512] BackupJob::MountThread : Device path hint : m_deviceHost.empty() is TRUE
PVLSVR: [10/31/15 22:35:03] [0000] [14032] 10/31/15 22:35:03.620 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_HOST )

Session = {067FD268-9040-46F1-A909-991386651345}

Device Host = ""
BENGINE: [10/31/15 22:35:03] [5512] BackupJob: thread id 0x00001588 acquired device IBM 7.
BENGINE: [10/31/15 22:35:03] [5512] BackupJob: thread id 0x00001588 trying to mount appending volume.
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND )

Session = {067FD268-9040-46F1-A909-991386651345}
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO )

Session = {067FD268-9040-46F1-A909-991386651345}
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )

Session = {067FD268-9040-46F1-A909-991386651345}

TargetDevice = {23477E7C-BD80-46C9-8413-46E7216EA533}, "IBM 2 [0001..0007]" (Device Pool)

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7" (Drive)

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4" (Drive)

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1" (Drive)

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6" (Drive)
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )

Session = {067FD268-9040-46F1-A909-991386651345}

TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )

Session = {067FD268-9040-46F1-A909-991386651345}
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::CheckTargetDeviceStatus()

Session = {067FD268-9040-46F1-A909-991386651345}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7"

State = 0003, ReservationID = 0000, AvailableMovers = 0001

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6"

State = 0003, ReservationID = 0000, AvailableMovers = 0000
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::MountAppendMedia()
PVLSVR: [10/31/15 22:35:03] [9292] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND )

Session = {067FD268-9040-46F1-A909-991386651345}

ERROR = 0xE000810D (E_PVL_MEDIA_NOT_FOUND)
BENGINE: [10/31/15 22:35:03] [5512] BackupJob: thread id 0x00001588 unable to mount appending volume, attempting scratch mount.
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_OVERWRITE )

Session = {067FD268-9040-46F1-A909-991386651345}
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO )

Session = {067FD268-9040-46F1-A909-991386651345}
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )

Session = {067FD268-9040-46F1-A909-991386651345}

TargetDevice = {23477E7C-BD80-46C9-8413-46E7216EA533}, "IBM 2 [0001..0007]" (Device Pool)

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7" (Drive)

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4" (Drive)

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1" (Drive)

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6" (Drive)
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )

Session = {067FD268-9040-46F1-A909-991386651345}

TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )

Session = {067FD268-9040-46F1-A909-991386651345}
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::CheckTargetDeviceStatus()

Session = {067FD268-9040-46F1-A909-991386651345}

Job = {CC4FBAB6-D26E-4EBB-9736-5ADB02043D9A}, "DB01&02 -- Full Backup"

TargetDevice = {1EAE01EE-0AFF-46F0-93AC-44D3759A521A}, "IBM 7"

State = 0003, ReservationID = 0000, AvailableMovers = 0001

TargetDevice = {705C37FA-7FA9-4B77-A93E-471CD6F9DC8D}, "IBM 1"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {8A94D98E-10C0-4530-B763-F4E2D7D79D2A}, "IBM 4"

State = 0003, ReservationID = 0000, AvailableMovers = 0000

TargetDevice = {56C40F19-8B66-4D76-8A8A-4BA6661003B0}, "IBM 6"

State = 0003, ReservationID = 0000, AvailableMovers = 0000
PVLSVR: [10/31/15 22:35:03] [8792] AdammSession::MountOverwriteMedia()
BKUPEXEC: [10/31/15 22:35:03] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:03] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:03] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:03] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:03] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:03] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:03] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:03] [0040] [Debug] VSN added to queues
PVLSVR: [10/31/15 22:35:04] [8792] PvlSession::MountOverwriteMedia() - ERROR = 0xE000810C (E_PVL_DRIVE_NOT_AVAILABLE) - 0001 seconds
PVLSVR: [10/31/15 22:35:04] [8792] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_OVERWRITE )

Session = {067FD268-9040-46F1-A909-991386651345}

ERROR = 0xE000810C (E_PVL_DRIVE_NOT_AVAILABLE)
BENGINE: [10/31/15 22:35:04] [5512] BackupJob: thread id 0x00001588 unable to scratch mount, will retry append later.
BENGINE: [10/31/15 22:35:04] [5512] BackupJob: thread id 0x00001588 unable to mount, error 0xE000810C.
PVLSVR: [10/31/15 22:35:05] [0000] [14032] 10/31/15 22:35:04.870 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_DISMOUNT )

Session = {067FD268-9040-46F1-A909-991386651345}

Flags = 0x00000020
PVLSVR: [10/31/15 22:35:04] [9292] AdammSession::Release()

Session = {067FD268-9040-46F1-A909-991386651345}
BENGINE: [10/31/15 22:35:05] [0000] [13348] 10/31/15 22:35:04 DeviceManager: incoming event fired
BENGINE: [10/31/15 22:35:05] [0000] [13348] 10/31/15 22:35:04 DeviceManager: stalling
BENGINE: [10/31/15 22:35:05] [0000] [13348] 10/31/15 22:35:04 DeviceManager: going to sleep for 29000 msecs
BKUPEXEC: [10/31/15 22:35:04] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:04] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:04] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:35:04] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:04] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:04] [0039] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:04] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:04] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:04] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:04] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:35:04] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:04] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:04] [0040] [Info] VSN received: type = DEVICE_UPDATE
BKUPEXEC: [10/31/15 22:35:04] [0040] [Debug] VSN added to queues
BESERVER: [10/31/15 22:35:05] [0000] [13440] 10/31/15 22:35:05 01 CServerConfigBO::Query (CBemLicenseInfo) - No archive option installed. EV Embedded status defaults to true.
BESERVER: [10/31/15 22:35:05] [0000] [13440] 10/31/15 22:35:05 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [10/31/15 22:35:05] [0000] [14032] 10/31/15 22:35:05.651 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:35:05] [0000] [13440] 10/31/15 22:35:05 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [10/31/15 22:35:05] [0000] [13440] 10/31/15 22:35:05 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [10/31/15 22:35:05] [9292] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = BACKUP_TO_DISK_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:35:05] [0000] [13440] 10/31/15 22:35:05 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [10/31/15 22:35:05] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:35:05] [0000] [14032] 10/31/15 22:35:05.667 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = LIBRARY_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:35:05] [3148] 01 CServerConfigBO::Query (CBemLicenseInfo) - No archive option installed. EV Embedded status defaults to true.
BESERVER: [10/31/15 22:35:05] [3148] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [10/31/15 22:35:05] [9292] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:35:05] [3148] 05 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [10/31/15 22:35:05] [3148] 05 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [10/31/15 22:35:05] [8792] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = BACKUP_TO_DISK_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = ""

EntityFlags = 0x00000000
BESERVER: [10/31/15 22:35:05] [3148] 05 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [10/31/15 22:35:05] [0000] [14032] 10/31/15 22:35:05.714 PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = DRIVE_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
PVLSVR: [10/31/15 22:35:05] [9292] PvlEntityDatabase::QueryEntity()

ParentEntityType = MACHINE_ENTITY_TYPE

ParentEntityGuid = {198D570B-378F-4829-A090-C5CCC2D3D348}

EntityType = LIBRARY_ENTITY_TYPE

EntityGuid = {00000000-0000-0000-0000-000000000000}

EntityName = "

EntityFlags = 0x00000000
BKUPEXEC: [10/31/15 22:35:05] [0039] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [10/31/15 22:35:05] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:05] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:05] [0039] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [10/31/15 22:35:05] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:05] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:05] [0040] [Info] VSN received: type = BEM_DRIVE
BKUPEXEC: [10/31/15 22:35:05] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:05] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:05] [0040] [Info] VSN received: type = BEM_ADAMM_MACHINE
BKUPEXEC: [10/31/15 22:35:05] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:05] [0040] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:09] [0039] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:35:09] [0039] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:09] [0039] [Debug] VSN added to queues
BKUPEXEC: [10/31/15 22:35:09] [0040] [Info] VSN received: type = ACTJOBEX_UPDATE
BKUPEXEC: [10/31/15 22:35:09] [0040] [Debug] VSN is a MODIFY
BKUPEXEC: [10/31/15 22:35:09] [0040] [Debug] VSN added to queues
BECAT: [10/31/15 22:35:10] [12448] Totla records = 2070000

pls help,thank you

CraigV · ‎11-02-2015

So you've given the log, but no details on the device.

Is it online in WIndows and in BE? Have you tried to restart both the device and the media server? Have you checked for any errors on the device?

THanks!

VOX

all device jobs go to a queued status