***************************************************************************
***** SGMon log for \\FSE1 *****
***************************************************************************
BESERVER: [12/28/09 14:54:20] [4132] -1 Job Manager: LoadJob() request completed
BESERVER: [12/28/09 14:54:20] [4132] 17 JobManager::UpdateJobInstanceData() new task state=22
BKUPEXEC: [12/28/09 14:54:22] [0000] UIVirtualObjectView(JobHistoryView) Queried 1 items: 0 to 0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = NULL , Entity = MACHINE
PVLSVR: [12/28/09 14:54:24] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MACHINE_ENTITY_TYPE
EntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityName = "
EntityFlags = 0x00008000
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = NULL , Entity = VAULT
PVLSVR: [12/28/09 14:54:24] [5544] 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/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = NULL , Entity = STORAGE_ARRAY_ENTITY
PVLSVR: [12/28/09 14:54:24] [5452] 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/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = NULL , Entity = DEVICE_POOL
PVLSVR: [12/28/09 14:54:24] [3312] 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/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:54:24] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = "
EntityFlags = 0x00008000
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:54:24] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = "
EntityFlags = 0x00008000
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE_POOL_DRIVE
PVLSVR: [12/28/09 14:54:24] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_POOL_DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = "
EntityFlags = 0x00008000
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = CHANGER
PVLSVR: [12/28/09 14:54:24] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = LIBRARY_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = "
EntityFlags = 0x00008000
BESERVER: [12/28/09 14:54:24] [1080] 12 AdammAdminBO::Query() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [0208] -1 ActiveState::doDispatch( :( 'SQL'
BESERVER: [12/28/09 14:54:24] [0208] -1 LocalSvr, runTask():{54aec5bc-e788-4ebf-91eb-0bbac5bf86e5}
BESERVER: [12/28/09 14:54:24] [0208] -1 BengineWrapper runtask() start
BESERVER: [12/28/09 14:54:24] [0208] -1 BengineWrapper JobEngine.Open() OK
BESERVER: [12/28/09 14:54:24] [6092] -1 Client requested key (1262033842).
BESERVER: [12/28/09 14:54:24] [2464] 01 Server Configuration: Client added: 11
BESERVER: [12/28/09 14:54:24] [2464] -1 Client 'FSE1' connected('','NSOI\Administrator'): 0x5ef8b60
BESERVER: [12/28/09 14:54:24] [1080] 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
BENGINE: [12/28/09 14:54:24] [2356] JobEngine::LaunchJob
BENGINE: [12/28/09 14:54:24] [2356] RunJobWithGuidAndServer( {54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}, FSE1 ) = 0
BENGINE: [12/28/09 14:54:24] [3656] Thread for job SQL ({54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}) starting
BESERVER: [12/28/09 14:54:24] [0208] -1 BengineWrapper JobEngine.RunJobWithGuidAndServer() rc=0
BESERVER: [12/28/09 14:54:24] [0208] -1 JobManager: BengineWrapper RunJob() done
BESERVER: [12/28/09 14:54:24] [0208] 11 LocalSvr, RunTask: EngineHandedJob SUCCESS, jobid:{54aec5bc-e788-4ebf-91eb-0bbac5bf86e5}.
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork enabled 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork useAny 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork protocol 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork subnet ---><---
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork MAC ---><---
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork IPv4 Enabled 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork IPv4 subnetAddress 0.0.0.0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork IPv4 subnetMask 0.0.0.0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork IPv4 autoObtain 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork IPv4 physical
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork selectedProtocol 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork InterfaceIndexProtocol 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork InterfaceIndex 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork subnet <zero>
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork PrefixLength 0
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork ipAddr <zero>
BENGINE: [12/28/09 14:54:24] [3656] viaNetwork FallBackOptions 0xF Protocol Tunnel NIC Subnet
BESERVER: [12/28/09 14:54:24] [2464] -1 Client requested key (1262033842).
BESERVER: [12/28/09 14:54:24] [6092] 01 Server Configuration: Client added: 12
BESERVER: [12/28/09 14:54:24] [6092] -1 Client 'FSE1' connected('','NSOI\Administrator'): 0x5e9a7c8
BESERVER: [12/28/09 14:54:24] [6092] 01 Server Configuration: Client removed: 11
BESERVER: [12/28/09 14:54:24] [6092] -1 Client 'FSE1' Disconnected:0x5e9a7c8
BENGINE: [12/28/09 14:54:24] [0372] Updating status for: 'SQL' (0x0 0x0)
BENGINE: [12/28/09 14:54:24] [0372] Status for: 'SQL' updated
BENGINE: [12/28/09 14:54:24] [3656] "Cluster" key does not appear to be present in the registry
BENGINE: [12/28/09 14:54:24] [3656] Failed to open Microsoft cluster ()
BENGINE: [12/28/09 14:54:24] [3656] VCS cluster keys do not appear to be present in the registry
BENGINE: [12/28/09 14:54:24] [3656] Failed to open VCS cluster ()
BENGINE: [12/28/09 14:54:24] [3656] Checking for Evaluation expiration.
BENGINE: [12/28/09 14:54:24] [3656] ENGSCRPT: ConvertDeviceName osId = 0X2b
BENGINE: [12/28/09 14:54:24] [3656] ENGSCRPT: IN - oldDeviceName = FSE1
BENGINE: [12/28/09 14:54:24] [3656] ENGSCRPT: OUT - newName = FSE1
BENGINE: [12/28/09 14:54:24] [3656] SetupCryptInfoInBSD: Backup encryption options: bHWEncryption=0,bSWEncryption=0,bSWEncryptionIfNoHW=0
BENGINE: [12/28/09 14:54:24] [3656] ENGSCRPT: No script found
BENGINE: [12/28/09 14:54:24] [3656] Failed to Load AllowExcludes key, We will update DR file evenif there are userExcludes. Error: 5
PVLSVR: [12/28/09 14:54:24] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = DRIVE_POOL_ENTITY_TYPE
ParentEntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityType = NULL_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:54:24] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:54:24] [3876] DeviceManager: incoming event fired
BENGINE: [12/28/09 14:54:24] [3876] DeviceManager: processing pending requests
BENGINE: [12/28/09 14:54:24] [3876] DeviceManager: query cache miss
PVLSVR: [12/28/09 14:54:24] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = DRIVE_POOL_ENTITY_TYPE
ParentEntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityType = NULL_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:54:24] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:54:24] [3876] Available and held drives from the pool
BENGINE: [12/28/09 14:54:24] [3876] FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.0
BENGINE: [12/28/09 14:54:24] [3876] Drives not in use by the Engine
BENGINE: [12/28/09 14:54:24] [3876] FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.0
BENGINE: [12/28/09 14:54:24] [3876] Drives available (not in use and not held)
BENGINE: [12/28/09 14:54:24] [3876] FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.0
PVLSVR: [12/28/09 14:54:24] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1" (Backup To Disk)
PVLSVR: [12/28/09 14:54:24] [3312] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [12/28/09 14:54:24] [3312] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
BENGINE: [12/28/09 14:54:24] [3876] Handing out session {da0edee0-4ca9-4a9b-aa60-2154d0484556} based on available drive:
BENGINE: [12/28/09 14:54:24] [3876] FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.0
PVLSVR: [12/28/09 14:54:24] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:54:24] [3876] DeviceManager: returned result to caller 0x00000000
BENGINE: [12/28/09 14:54:24] [3876] DeviceManager: going to sleep for 900000 msecs
PVLSVR: [12/28/09 14:54:24] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_JOB_INFO )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Job = {54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}, "SQL"
PVLSVR: [12/28/09 14:54:24] [3312] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_HOST )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Device Host = ""
Device Port = 0000
BENGINE: [12/28/09 14:54:24] [5160] BackupJob: thread id 0x00001428 acquired device FSE1.
BENGINE: [12/28/09 14:54:24] [5160] BackupJob: thread id 0x00001428 trying to mount appending volume.
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1" (Backup To Disk)
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Keep Data Infinitely - Do Not Allow Overwrite" (Media Set)
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::CheckTargetDeviceStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Job = {54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}, "SQL"
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
State = 0003, ReservationID = 0000, AvailableMovers = 0001
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::MountAppendMedia()
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:54:24] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:54:24] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:54:24] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:54:24] [5452] PvlDrive::MountMedia() - Establish connection
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
Side = 0001
SUCCESS!
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::IsAppendMediaMounted() -
Need WORM: No
Need Encryption: No
PVLSVR: [12/28/09 14:54:24] [5452] AdammSession::IsAppendMediaMounted() - SUCCESS!
PVLSVR: [12/28/09 14:54:24] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:54:24] [5160] Updating session {da0edee0-4ca9-4a9b-aa60-2154d0484556} with drive FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.1
BENGINE: [12/28/09 14:54:24] [5160] BackupJob: thread id 0x00001428 medium successfully mounted.
BENGINE: [12/28/09 14:54:24] [3656] Media Label: B2D000004
BENGINE: [12/28/09 14:54:24] [3656] Media Label: {4d255a8f-b8ee-4647-8a11-6a6023bd3af9}
BENGINE: [12/28/09 14:54:24] [3656] Overwrite Protected Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:54:24] [3656] Appendable Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:54:24] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:54:24] [3656] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:54:24] [3656] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:54:24] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:54:24] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:54:24] [3656] Job Family ID: {E696DD82-FEEB-48F3-B3E7-1D01EE5E7B89}
BENGINE: [12/28/09 14:54:24] [3656] FSE1 Block size: 1024
BENGINE: [12/28/09 14:54:24] [3656] FSE1 Buffer size: 32768
BENGINE: [12/28/09 14:54:24] [3656] FSE1 Num buffers: 10
BENGINE: [12/28/09 14:54:24] [3656] FSE1 High water: 7
BENGINE: [12/28/09 14:54:24] [3656] Enter CheckHostBSDsForSnaps
BENGINE: [12/28/09 14:54:24] [3656] Exit CheckHostBSDsForSnaps: true
BENGINE: [12/28/09 14:54:24] [3656] NDMPAgentConnector:SetupConnection(): Data Server = FSE1
BENGINE: [12/28/09 14:54:24] [3656] Could not resolve the "bews-ndmp" or the "ndmp" service, error code: 10109, using port 10000
BENGINE: [12/28/09 14:54:24] [3656] ndmpConnectEx: 'FSE1' resolves to the local machine itself. So, we will try and connect to a loopback address ...
BENGINE: [12/28/09 14:54:24] [3656] ndmpConnectEx : Control Connection information: A connection was established between end-points 127.0.0.1:2038 and 127.0.0.1:10000.
BEREMOTE: [12/28/09 14:54:24] [2096] ndmpRun: Control connection accepted : connection established between end-points 127.0.0.1:10000 and 127.0.0.1:2038
BENGINE: [12/28/09 14:54:24] [3656] NDMP version 3 connection CONNECTED
BEREMOTE: [12/28/09 14:54:25] [0000] BECryptoInit: BECrypto non-FIPS mode successfully enabled.
BESERVER: [12/28/09 14:54:24] [2464] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:54:24] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:54:24] [2464] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:54:24] [2464] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:54:24] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:54:24] [2464] 12 AdammAdminBO::Execute() - hr = 0x0
BENGINE: [12/28/09 14:54:25] [0000] BECryptoInit: BECrypto non-FIPS mode successfully enabled.
BEREMOTE: [12/28/09 14:54:24] [2528] BELogonUser: beclass::IsThisMe() returned error: 87
BEREMOTE: [12/28/09 14:54:24] [2528] LogonType set = [LOGON32_LOGON_INTERACTIVE][0x2]
BEREMOTE: [12/28/09 14:54:25] [0000] NPLogonNotify!
BEREMOTE: [12/28/09 14:54:25] [0000] SvcCtl
BEREMOTE: [12/28/09 14:54:25] [0000]
BEREMOTE: [12/28/09 14:54:24] [2528] LogonUser(LOGON32_LOGON_INTERACTIVE-0x2) success
BEREMOTE: [12/28/09 14:54:24] [2528] Successfully impersonated NSOI\Administrator
BEREMOTE: [12/28/09 14:54:24] [2528] WhoAmI( ) reports: NSOI\Administrator
BENGINE: [12/28/09 14:54:24] [3656] Enabling Shared Buffers Connection type
BENGINE: [12/28/09 14:54:24] [3656] Enter NDMPSnapHostBackupEngine::ProcessBSDs
BENGINE: [12/28/09 14:54:24] [3656] BESC: Parsing OS version info - [Major Version=5 Minor Version=2 Build Number=3790 ServicePack Major=2 ServicePack Minor=0 SuiteMask=272 ProductType=2 ProcessorType=Intel x86]
BENGINE: [12/28/09 14:54:24] [3656] Single logical volume snapshot is enabled for this job.
BENGINE: [12/28/09 14:54:24] [3656] AOFO is enabled for this job.
BENGINE: [12/28/09 14:54:24] [3656] Enter NDMPSnapHostBackupEngine::CreateBSDProcessor
BENGINE: [12/28/09 14:54:24] [3656] PDI processor not needed, performing traditional backup
BENGINE: [12/28/09 14:54:24] [3656] Failed to Load AllowExcludes key, We will update DR file evenif there are userExcludes. Error: 5
BENGINE: [12/28/09 14:54:24] [3656] Enter NDMPSnapHostBackupEngine::SnapAllVolumes
BENGINE: [12/28/09 14:54:24] [3656] Exit NDMPSnapHostBackupEngine::SnapAllVolumes
BENGINE: [12/28/09 14:54:24] [3656] Enter NDMPSnapHostBackupEngine::TrackSnappedBSD
BENGINE: [12/28/09 14:54:24] [3656] Exit NDMPSnapHostBackupEngine::TrackSnappedBSD
BENGINE: [12/28/09 14:54:24] [3656] NDMPSnapHostBackupEngine::ProcessBSDs Top of the loop
BENGINE: [12/28/09 14:54:24] [3656] snapResult = 0x0, result = 0x0
BENGINE: [12/28/09 14:54:24] [3656] Enter NDMPSnapHostBackupEngine::SnapSingleVolume
BENGINE: [12/28/09 14:54:24] [3656] ndmpcSnapshotPrepare: adding selection string for device_name: FSE1
BENGINE: [12/28/09 14:54:24] [3656] ndmpcSnapshotPrepare2: selection string: "FSE1\*.*",v0,t0,l0,n0,f0
BEREMOTE: [12/28/09 14:54:24] [2528] "Cluster" key does not appear to be present in the registry
BEREMOTE: [12/28/09 14:54:24] [2528] Failed to open Microsoft cluster ()
BEREMOTE: [12/28/09 14:54:24] [2528] VCS cluster keys do not appear to be present in the registry
BEREMOTE: [12/28/09 14:54:24] [2528] Failed to open VCS cluster ()
BEREMOTE: [12/28/09 14:54:24] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:24] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:24] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:24] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:24] [2528] Finding MNET entries using PunchDownWithWNetCalls()
BEREMOTE: [12/28/09 14:54:25] [0000] NPGetCaps::WNNC_START
BEREMOTE: [12/28/09 14:54:24] [2528] Notes Agent:FindDrives
BEREMOTE: [12/28/09 14:54:24] [2528] RMAN_FindDrives - Oracle RMAN Agent not authorized.
BEREMOTE: [12/28/09 14:54:24] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:24] [2528] Device Shadow?Copy?Components Dle platform 2 major version 5 minor version 2 build 3790
BEREMOTE: [12/28/09 14:54:24] [2528] No utility partitions found on any of the disks
BEREMOTE: [12/28/09 14:54:24] [2528] NRDS_RA_FindDrives entered
BEREMOTE: [12/28/09 14:54:24] [2528] NRDS_RA_FindDrives completed immediately because we aren't browsing
BEREMOTE: [12/28/09 14:54:24] [2528] Informational: Cps root device creation using default Cps library mechanism when creating device 'Cont?Prot?Srv' in CPS::DetermineRootCpsComponent:696
BEREMOTE: [12/28/09 14:54:24] [2528] Informational: Cps library not available. Not creating Cps root device 'Cont?Prot?Srv' in CPS::DetermineRootCpsComponent:700
BEREMOTE: [12/28/09 14:54:24] [2528] Function called: DB2_FindDrives
BEREMOTE: [12/28/09 14:54:24] [2528] DB2_FindDrives - DB2 Agent not autherized.
BEREMOTE: [12/28/09 14:54:24] [2528] AD:DeviceName "System?State" in SystemState::CreateDLEs:351
BEREMOTE: [12/28/09 14:54:24] [2528] AD:Full Device Path "System?State" in SystemState::CreateDLEs:356
BEREMOTE: [12/28/09 14:54:24] [2528] AD:ADRO Agent is NOT authorized for System?State.
BEREMOTE: [12/28/09 14:54:24] [2528] AD:Found 0 instances of Active Directory Applcation Mode in SystemState::GetADAMInstanceCount:1355
BEREMOTE: [12/28/09 14:54:24] [2528] ADC_FindAgents entered
BEREMOTE: [12/28/09 14:54:24] [2528] ADC_FindAgents completed immediately because we aren't browsing
BEREMOTE: [12/28/09 14:54:24] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:24] [2528] SQL2_FindDrives - a default SQL Service is installed on FSE1.
BEREMOTE: [12/28/09 14:54:24] [2528] SQL2_FindDrives - the default SQL Service appears to be SQL 2000.
BEREMOTE: [12/28/09 14:54:24] [2528] IsServiceClusterOk called on FSE1
BEREMOTE: [12/28/09 14:54:24] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:24] [2528] VCS cluster keys do not appear to be present in the registry
BEREMOTE: [12/28/09 14:54:24] [2528] Failed to open VCS cluster ()
BEREMOTE: [12/28/09 14:54:24] [2528] SQL2_CreateDLE - creating FSE1.
BEREMOTE: [12/28/09 14:54:24] [2528] SQL Version 2005
BEREMOTE: [12/28/09 14:54:24] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:24] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:24] [2528] Setting 2K5 extended feature bit.
BEREMOTE: [12/28/09 14:54:24] [2528] AddESE_DLEs( )
BEREMOTE: [12/28/09 14:54:24] [2528] AddESE_DLEs for sever name FSE1
BEREMOTE: [12/28/09 14:54:24] [2528] HrESEBackupRestoreGetRegistered: FSE1, NULL = 0xc7ff07d7
BEREMOTE: [12/28/09 14:54:24] [2528] ESE_GetBackupAnnotations returned 0xc7ff07d7
BEREMOTE: [12/28/09 14:54:24] [2528] EV_FindDrives
BEREMOTE: [12/28/09 14:54:24] [2528] evFileSystem::EV_FindDrives: Agent not authorized.
BEREMOTE: [12/28/09 14:54:24] [2528] FS_BlowOutMachine: entered -->FSE1<--
BEREMOTE: [12/28/09 14:54:24] [2528] FS_BlowOutMachine: device already in DLE list
BEREMOTE: [12/28/09 14:54:24] [2528] ndmpdSnapshotPrepare2: Calling DLE_FindByName for device: FSE1
BEREMOTE: [12/28/09 14:54:24] [2528] dir =
BEREMOTE: [12/28/09 14:54:24] [2528] file = *.*
BEREMOTE: [12/28/09 14:54:24] [2528] oper = Include
BEREMOTE: [12/28/09 14:54:24] [2528] subs = False
BEREMOTE: [12/28/09 14:54:24] [2528] wild = True
BEREMOTE: [12/28/09 14:54:24] [2528] Attempting VSS Provider
BEREMOTE: [12/28/09 14:54:24] [2528] Snapshot factory, using VSS as the snapshot provider.
BEREMOTE: [12/28/09 14:54:24] [2528] OS Version: 5.2.3790 (Service Pack 2). Suite: 0x110, ProductType: 0x2, SP: 2.0
BEREMOTE: [12/28/09 14:54:24] [2528] VssSnapshotVolume::Initialize() - Using backup type VSS_BT_FULL
BEREMOTE: [12/28/09 14:54:24] [2528] VssSnapshotVolume::Initialize() - calling IVssBackupComponents::InitializeForBackup
BEREMOTE: [12/28/09 14:54:24] [2528] VssSnapshotVolume::Initialize() - calling IVssBackupComponents::SetBackupState with backup type 1
BEREMOTE: [12/28/09 14:54:24] [2528] VssSnapshotVolume::Initialize() - calling IVssBackupComponents::SetContext with context: 0
BEREMOTE: [12/28/09 14:54:25] [2528] -------Begin Provider property dump------
BEREMOTE: [12/28/09 14:54:25] [2528] Using fresh IVssBackupComponents for provider gather
BEREMOTE: [12/28/09 14:54:25] [2528] ==> VSS Provider properties:
BEREMOTE: [12/28/09 14:54:25] [2528] Provider Name = Microsoft Software Shadow Copy provider 1.0
BEREMOTE: [12/28/09 14:54:25] [2528] Provider ID = {b5946137-7b9f-4925-af80-51abd60b20d5}
BEREMOTE: [12/28/09 14:54:25] [2528] Provider Type = System
BEREMOTE: [12/28/09 14:54:25] [2528] Provider Version = 1.0.0.7
BEREMOTE: [12/28/09 14:54:25] [2528] Provider Version ID = {00000001-0000-0000-0007-000000000001}
BEREMOTE: [12/28/09 14:54:25] [2528] Component Class ID = {65ee1dba-8ff4-4a58-ac1c-3470ee2f376a}
BEREMOTE: [12/28/09 14:54:25] [2528] -------End of Provider property dump------
BEREMOTE: [12/28/09 14:54:25] [2528] Provider GUID = {00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnapshotVolume::Initialize() - calling StartSnapshotSet()
BEREMOTE: [12/28/09 14:54:25] [2528] VSS snapstarted. SnapshotSetID = {8bd1fd8b-5e3c-49c0-bd3c-177d75ecfb50}
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnapshotVolume::GetWriterMetaData() - calling IVssBackupComponents::GatherWriterMetadata.
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnapshotVolume::GetWriterMetaData() - waiting for IVssBackupComponents::GatherWriterMetadata to complete.
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnapshotVolume::GetWriterMetaData() - IVssBackupComponents::GatherWriterMetadata complete.
BEREMOTE: [12/28/09 14:54:25] [2528] Initialized VSS Snapshot Provider
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2_Connect worked.
BEREMOTE: [12/28/09 14:54:25] [2528] SQL Agent: Version String { 9.00.1399.06 }
BEREMOTE: [12/28/09 14:54:25] [2528] SQL edition returned: Standard Edition
BEREMOTE: [12/28/09 14:54:25] [2528] [no resource for 3893]
BEREMOTE: [12/28/09 14:54:25] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning master
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning model
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning msdb
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: freeing old meta data to make new metadata.
BEREMOTE: [12/28/09 14:54:25] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:54:25] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:54:25] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:54:25] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:54:25] [2528] Returning msdb
BEREMOTE: [12/28/09 14:54:25] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:54:25] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:54:25] [2528] SnapshotBackupEngine::scan_file_system() : Volume List size (before prune): 9
BEREMOTE: [12/28/09 14:54:25] [2528] SnapshotBackupEngine::scan_file_system(): Volume List size (after prune): 2
BEREMOTE: [12/28/09 14:54:25] [2528] Adding volume: 'C:'
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnapshotVolume::AddVolumeToSnapSet
BEREMOTE: [12/28/09 14:54:25] [2528] Device name: [FSE1], Volume name: [C:]
BEREMOTE: [12/28/09 14:54:25] [2528] Warning: Using null server name
BEREMOTE: [12/28/09 14:54:25] [2528] Set bsdMachineName_: []
BEREMOTE: [12/28/09 14:54:25] [2528] Exit VssSnapshotVolume::AddVolumeToSnapSet - successfully
BEREMOTE: [12/28/09 14:54:25] [2528] Adding volume: 'D:'
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnapshotVolume::AddVolumeToSnapSet
BEREMOTE: [12/28/09 14:54:25] [2528] Device name: [FSE1], Volume name: [D:]
BEREMOTE: [12/28/09 14:54:25] [2528] Warning: Using null server name
BEREMOTE: [12/28/09 14:54:25] [2528] Exit VssSnapshotVolume::AddVolumeToSnapSet - successfully
BEREMOTE: [12/28/09 14:54:25] [2528] SnapshotBackupEngine::snap_file_systems. Start...
BEREMOTE: [12/28/09 14:54:25] [2528] MakeValidVolumeName:
BEREMOTE: [12/28/09 14:54:25] [2528] volume=[\\?\Volume{0228e6e8-72be-11de-a507-806e6f6e6963}] and vol_name=[\\?\Volume{0228e6e8-72be-11de-a507-806e6f6e6963}\]
BEREMOTE: [12/28/09 14:54:25] [2528] MakeValidVolumeName:
BEREMOTE: [12/28/09 14:54:25] [2528] volume=[\\?\Volume{6948efb1-72fc-11de-ba75-003048b81dc1}] and vol_name=[\\?\Volume{6948efb1-72fc-11de-ba75-003048b81dc1}\]
BEREMOTE: [12/28/09 14:54:25] [2528] VssSnashotVolume::PrepareToSnapVolumeSet() - done. LastError_: 0
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2_PrepareForFreeze: Starting on database ReportServerTempDB.
BEREMOTE: [12/28/09 14:54:25] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:54:25] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2_PrepareForSnapBackup: Could not create component: x8007007E
BEREMOTE: [12/28/09 14:54:25] [2528] SQL2: PrepareForFreeze failed.
BEREMOTE: [12/28/09 14:54:25] [2528] SnapshotBackupEngine - SNAPSHOT_NOTIFICATION::SnapshotPrepare failed with fstatus: -536805644
BEREMOTE: [12/28/09 14:54:25] [2528] Error SnapshotBackupEngine::snap_file_systems failed.
BEREMOTE: [12/28/09 14:54:25] [2528] ndmpdSnapshotPrepare2: Snapshot error. ErrorCode: 0xe000fef4 ErrorLevel: 2 Error Src: 3
BEREMOTE: [12/28/09 14:54:25] [2528] Not Found
BEREMOTE: [12/28/09 14:54:25] [2528] Not Found
BEREMOTE: [12/28/09 14:54:25] [2528] ndmpdSnapshotPrepare2: sending reply...
BENGINE: [12/28/09 14:54:25] [3656] ndmpcSnapshotPrepare2: Snap shot error. ErrorCode: 0xe000fef4 ErrorLevel: 2 Provider: 2
BENGINE: [12/28/09 14:54:25] [3656] Exit NDMPSnapHostBackupEngine::SnapSingleVolume
BENGINE: [12/28/09 14:54:25] [3656] TF_OpenTape()
BENGINE: [12/28/09 14:54:25] [3656] TF_GetChannel( ) reusing channel 02FFA240
BENGINE: [12/28/09 14:54:25] [3656] TF_GetDriveContext ...
sessionGuid = {da0edee0-4ca9-4a9b-aa60-2154d0484556}
initAsync = 1
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:54:25] [3656] TF_GetDriveContext returning 0x00000000, context = 2FCBF30
BENGINE: [12/28/09 14:54:25] [3656] Allocated 2 buffers, size 32768 bytes, total used: 65696
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:54:25] [3656] DeviceIsNT4Server: agent info: [Major Version=5 Minor Version=2 Build Number=3790 ServicePack Major=2 ServicePack Minor=0 SuiteMask=272 ProductType=2 ProcessorType=Intel x86].
BENGINE: [12/28/09 14:54:25] [3656] DeviceIsIA64Server: agent info: [Major Version=5 Minor Version=2 Build Number=3790 ServicePack Major=2 ServicePack Minor=0 SuiteMask=272 ProductType=2 ProcessorType=Intel x86].
BENGINE: [12/28/09 14:54:25] [3656] TF_OpenSet( )
BENGINE: [12/28/09 14:54:25] [3656] FreeFormatEnv( cur_fmt=65535 )
BENGINE: [12/28/09 14:54:25] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:54:25] [3656] ret_val = 0
BENGINE: [12/28/09 14:54:25] [3656] Requested Set: ID = ffffffff Seq = -1 Set = -1
BENGINE: [12/28/09 14:54:25] [3656] Attempting to VCB storage media
BENGINE: [12/28/09 14:54:25] [3656] TpRead( :(
BENGINE: [12/28/09 14:54:25] [3656] Error = 1101 Req = 6144 Got = 1024
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:54:25] [3656] FreeFormatEnv( cur_fmt=65535 )
BENGINE: [12/28/09 14:54:25] [3656] SetupFormatEnv( fmt=0 )
BENGINE: [12/28/09 14:54:25] [3656] Current media tape header was written by "Symantec Backup Exec 12.5.2213".
BENGINE: [12/28/09 14:54:25] [3656] TpRead( :(
BENGINE: [12/28/09 14:54:25] [3656] Error = 1101 Req = 6144 Got = 2048
BENGINE: [12/28/09 14:54:25] [3656] ReadThisSet( cur_fmt=0 )
BENGINE: [12/28/09 14:54:25] [3656] Set 1 was written by version 12.5.
BENGINE: [12/28/09 14:54:25] [3656] ReadThisSet() return=0x00000000
BENGINE: [12/28/09 14:54:25] [3656] Current VCB: ID = 3b9c6a70 Seq = 1 Set = 1
BENGINE: [12/28/09 14:54:25] [3656] PositionAtSet( :( TF Msg = b
BENGINE: [12/28/09 14:54:25] [3656] UI Msg = 800f
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:54:25] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:54:25] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:54:25] [3656] F40_SeekEOD starting seek...
BENGINE: [12/28/09 14:54:25] [3656] F40_SeekEOD calling OTC_GetPrevSM with expect_sm = 1 ...
BENGINE: [12/28/09 14:54:25] [3656] OTC_GetPrevSM spacing back two filemarks...
BENGINE: [12/28/09 14:54:25] [3656] OTC_GetPrevSM: ESET position = 6
BENGINE: [12/28/09 14:54:25] [3656] OTC_GetPrevSM calling OTC_SMtoFile...
BENGINE: [12/28/09 14:54:25] [3656] SetupFormatEnv( fmt=0 )
BENGINE: [12/28/09 14:54:25] [3656] End of TF_OpenSet: Ret_val = 0x00000000 Buffs = 2 HiWater = 7
BENGINE: [12/28/09 14:54:25] [3656] B2D Buffered Writes ===> Setting buffering to default.
BENGINE: [12/28/09 14:54:25] [3656] Current Block is = 10
BENGINE: [12/28/09 14:54:25] [3656] Media Server to initiate connection for data transfer
BEREMOTE: [12/28/09 14:54:26] [2528] Error (0x40202) from VSS - EVENT_S_NOSUBSCRIBERS
BEREMOTE: [12/28/09 14:54:26] [2528] after calling function: IVssBackupComponents::AbortBackup
BEREMOTE: [12/28/09 14:54:26] [2528] Error (0x80042301) from VSS - VSS_E_BAD_STATE
BEREMOTE: [12/28/09 14:54:26] [2528] after calling function: IVssBackupComponents::BackupComplete
BEREMOTE: [12/28/09 14:54:26] [2528] Error 0x80042301 calling VSS BackupComplete
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3894]
BEREMOTE: [12/28/09 14:54:26] [2528] SQL DBClose
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_CleanUpSnapInfoList called.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_CleanUpSnapInfoList called.
BEREMOTE: [12/28/09 14:54:26] [2528] ndmpdSnapshotPrepare2: cleanup: all done
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: USERNAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NOPASSWORD=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: USERNAME=NSOI\Administrator
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PASSWORD=<not displayed>
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: INITIATOR_SID=0@D0000000DE0000ES6U7WITaQ5XU71<m04000
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PRESCANDLE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: OPER_TYPE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BLOCKSIZE=400
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SSETRESERVED=400
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: HW_ENCRYPT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_PRE_BKUP_DBCC_TYPE=2b2b
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_POST_BKUP_DBCC_TYPE=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_POST_REST_DBCC_TYPE=3b9c69b5
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: LAST_SET=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_REGISTRY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_INUSE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_MIGRATED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_EXPANDED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PROCESS_EMPTY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SWCOMPMODE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SWCOMPLEVEL=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SWCOMPTHRESH=10
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BACKUPTYPE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SPS_METHOD=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_HOTCOPY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_HOTCOPY_PATH=C:\TEMP
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_NO_RECOVER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_ESE_FROM_REPLICA=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_STANDBY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_CHECKSUMS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_SOFTWARE_COMPRESSION=3
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_NUMSNAPS_TO_KEEP=4
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_ALWAYS_SNAP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_XCH_METHOD=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_MBOX_METHOD=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_MBOX_SIS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_NTFS_SIS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: AA_PRIORITY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_RSM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_EXCLUDE_ACTIVE_FILES=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TRAVERSE_LOCAL_MP=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TRAVERSE_REMOTE_MP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TRAVERSE_SYMBOLIC_LINKS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DO_SNAPSHOT=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SQL_FORCE_NP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SQL_OVERRIDE_FGDEPS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SQL_ALERT_FGDEP_OVERRIDE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SFC_WITH_SS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_REMOTE_STORAGE_HSM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SKIP_OPEN_FILES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: WAIT_SKIP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: HIDDEN=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PROC_SYSTEM_FILES=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_LDM_STATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_DISK_QUOTA=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_IN_DOC_FMT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_REGISTRY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_HW_REGISTRY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: IGNORE_TREE_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SECURITY=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VOL_RESTRICTIONS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_XCH_PUBLIC=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_MBOX_USER_FLAG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_MBOX_REDIR_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_MBOX_USER_PASSWORD=<not displayed>
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_SERVER=FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_SERVER_LIST=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_COMMIT_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_COMMIT_DB_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_IGNORE_LOCK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_PRESERVE_IIS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_REST_OVER_EXISTING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_VER_REST_OVER_EXISTING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_RESTORE_SECURITY=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_RESTORE_CURRENT_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SPS_PRE_BKUP_DBCC_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SPS_POST_BKUP_DBCC_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SPS_POST_REST_DBCC_TYPE=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: CONTINUE_ON_SPS_DBCC_ERROR=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOBNAME=SQL
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_XCH_PRIVATE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_REMOTE_HSM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_RSM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_DISK_QUOTA=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_JUNC_AS_DIR=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_PRIM_REPLICA=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_TERMINAL_SERVICES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_WMI=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_CLUSTER_QUORUM_DB=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_FORCE_RECOVERY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_EXISTING=3
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_XCH_NO_LOSS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_MOUNT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_DISMOUNT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_LOG_PATH=C:\TEMP\
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_REDIR_SG=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_REDIR_DB=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_REDIR_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_LAST_SET=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_NO_LOSS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_CONSISTENCY_CHECK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_CONTINUE_CONSISTENCY_CHECK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_DATABASE_OVERWRITE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_KEEP_REPLICATION=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_VERIFY_ONLY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_RESTORE_TO=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_RESTORE_TO_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_CONTINUE_ON_ERROR=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_POINT_IN_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_POINT_IN_TIME_DATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_POINT_IN_TIME_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_PRE_BKUP_DBCC=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_POST_BKUP_DBCC=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_POST_REST_DBCC=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: CONTINUE_ON_DBCC_ERROR=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_AUTO_MASTER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_DATABASE_STANDBY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_DATABASE_FORCERESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_DATABASE_RECOVER=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_STOP_BEFORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_AFTER_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_DATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_DEF_INSTANCE_DIR=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_USE_DEFAULT_DRIVE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_ALL_TO_DEFAULT_DRIVE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_DEFAULT_DRIVE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ARCHIVE_PRESERVE_TREE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: UTILITY_PARTITION_OEMIDS=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: APPLY_CPR_TO_BACKUPSET=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: IMAGE_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: GUARANTEED_FROZEN_IMAGE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: FULL_IMAGE_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TESTRUN_JOB_OPTIONS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: USE_CHANGE_JOURNAL=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_BACKUP_METHOD=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_BACKUP_RECYCLE_LOGFILE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_SCRATCH_DB2_DB_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_HIDE_CATALOG_ENTRY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_LAST_BACKUP_DATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_LAST_BACKUP_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_MAX_TRANLOG_EXTENTS=46
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_RESTORE_DBRECOVEROPTION=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_RESTORE_WAITINUSE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_RESTORE_MSECSFORWAIT=1e
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_RESTORE_POINTINTIMERESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_RESTORE_PITTIME_DATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_RESTORE_PITTIME_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SAP_JOB_FLAG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: IMAGE_DBLK_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PROC_CHECKSUM_STRM=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_OFFLINE_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_OPTION_AUTHORIZED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ENABLE_IDR_INFO_GATHERING=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: IMAGE_OPTION_AUTHORIZED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DPM_OPTION_AUTHORIZED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_OPTION_AUTHORIZED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_OPTION_AUTHORIZED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DO_OFF_HOST_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: OFF_HOST_BACKUP_OPER_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: OFF_HOST_METADATA_COLL_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PRESERVE_CTIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: FOLLOW_SYMDIRS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: INCLUDE_REMOTE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NO_NFS_LOCKING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: FOLLOW_MOUNTPOINTS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TRUE_IMAGE_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NDMP_PROP_PORT_RANGE_ENABLED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NDMP_PORT_RANGE=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REVERSE_CONNECTION=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ENABLE_GRANULAR_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_OPERATION_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_DATA_TRANSFER_PHASE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_GENERAL_ATTRIBUTES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_IPC_OBJECT=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_LOCATION=F:
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_TEMP_LOCATION=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: POLICY_GUID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_FAMILY_GUID={E696DD82-FEEB-48F3-B3E7-1D01EE5E7B89}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_HISTORY_GUID={627E4838-F0F6-4F4B-A01B-17DA721FD700}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_INSTANCE_GUID={54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_EXECUTION_GUID={627E4838-F0F6-4F4B-A01B-17DA721FD700}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_SET_GUID={00000009-03E8-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_GUID={AEF0DE93-08D2-43F4-B73D-0D354F472B40}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_SET_RETENSION_HOURS=85c170
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_TYPE=f
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_ANCESTRY_LOCATIONS=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: IMAGE_LINK_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: RECREATE_AD_OBJECTS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_SET_COUNT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_MAX_SET_COUNT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: CPS_JOB_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: CPS_JOB_INCR=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_POINT_IN_TIME_LOWWORD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_POINT_IN_TIME_HIGHWORD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: B2D_RESERVED_DISKSPACE_MB=2800
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ARCHIVE_JOB_DELETE_PHASE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BACKUP_JOB_START_DATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BACKUP_JOB_START_TIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_SQL_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_SITENAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_FORCE_DB_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_SKIP_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_LOG_RECVR_OPTN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_BKUP_TEMP_LOCATION=C:\TEMP
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_EXPORT_AS_MONOLITHIC_FILE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_EXPORT_AS_FLAT_DISK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_VCB_TRANSPORT_MODE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_USE_MEDIA_SERVER_AS_PROXY=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_VCB_PROXY_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_EXPORT_PATH_ON_VCB_PROXY=C:\TEMP
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_RESTORE_RUNNING_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_POWER_ON_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_RUN_CONVERTER_JOB=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_USE_MEDIA_SERVER_FOR_STAGING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REMOVE_EXISTING_VCBMOUNTER_SNAPSHOT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_STAGING_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_STAGING_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_VM_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_DATACENTER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_FOLDER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_HOST=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_DATASTORE=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_NETWORKS=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_NUMBER_OF_NICS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_FLAG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_USE_ORG_DISK_DS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_SERVER_ACCT_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_INCLUDE_VS_CONFIG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_EXCLUDE_RUNNING_VMS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_DO_GRT_BACKUP=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_DO_SIS_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_RESTORE_RUNNING_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_POWER_ON_OPTION=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SNAPSHOT_IGNORE_REASONS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_CREATE_VERSION_7_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_CREATE_THIN_PROVISIONED_DISK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SNAPSHOT_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: OFF_HOST_SNAPSHOT_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICENAME="FSE1"
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_OS_ID=2b
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_OS_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_MAINTYPE=11
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_SUBTYPE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: FILESYSTEM="FSE1\*.*",v0,t0,l0,n0,f0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_REDIRECT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_REDIRECT_FILE_SYSTEM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NBBSA_TOTAL_STREAMS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BSA_SERVICE_HOST=FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_BACKUP_TYPE=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_SHUTDOWN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_BK_AUTO_ARCHDELETE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_BK_ARCHLOGS_INCR=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTOREOPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTOREOPTS_SCN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTOREOPTS_OMIT_READONLY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVEROPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVEROPTS_SCN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVEROPTS_DELETE_ALOGS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_STARTUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTORE_CONTROL_FILE_ONLY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTORE_VALIDATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVER_IF_VALID=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_BACKUP_TYPE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_SHUTDOWN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_RESTOREOPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_RECOVER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_ROLLFWDOPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECTED_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECTED_INSTANCE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_PUT_DB_ONLINE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECT_DATABASE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECT_CONTAINER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_OVERRIDE_RECO_LOG_PATH=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_QUIESCE_DB=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_BACKUP_STREAM_FILES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_BACKUP_USER_INCLUDE_FILES=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_RESTORE_DIBSET=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_ACTIVATE_DIB_AFTER_VERIFY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_OPEN_DATABASE_WHEN_FINISHED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_DONOT_VERIFY_DIB_AFTER_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_RESTORE_USER_INCLUDED_FILES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_ROLL_FORWARD_LOG_DIRECTORY_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_WORKING_DIRECTORY_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_LEAVE_BACKUP_FILE_ON_DISK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NO_DATA_CONNECTION=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_PASSWORD_ENCRYPT_ALG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_DATA_COMP_ALG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SOFTWARE_MAJOR_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SOFTWARE_MINOR_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_TIME_ZONE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_AGENT_TIME_ZONE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_IMAGEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_IMAGECONTENTID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_GEN0SOURCEIMAGEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_CATALOGSOURCEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_GENERATIONNUMBER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_DERIVEDSOURCEIMAGEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_POLICY_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_FAMILY_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_HISTORY_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_INSTANCE_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_EXECUTION_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_SET_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_DEVICE_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_SET_RETENSION_HRS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_PREVIOUSIMAGECONTENTID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SELECTION_LIST_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SET_SEQUENCE_NUM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_IMAGE_LINK_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_ATTRIB=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_ATTRIB2=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_COMPRESSED_OBJ=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BSD_DEVICE_ATTRIBUTES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_KEY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_PREFIX=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_SUFFIX=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_NEXTNUM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_NUMDIGITS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_MBOX_USER_PASSWORD=<not displayed>
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOBNAME=SQL
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_LOG_PATH=C:\TEMP\
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_REDIR_SG=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_REDIR_DB=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_REDIR_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BU_SQL_HOTCOPY_PATH=C:\TEMP
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_RESTORE_TO_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_SQL_LOG_MARK_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ModifiedOnly is 0
BEREMOTE: [12/28/09 14:54:26] [2528] SWCompMode is 0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DOMINO_SCRATCH_DB2_DB_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_SERVER=FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_SERVER_LIST=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_COMMIT_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_COMMIT_DB_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_IGNORE_LOCK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_PRESERVE_IIS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_REST_OVER_EXISTING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_VER_REST_OVER_EXISTING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_RESTORE_SECURITY=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SPS_RESTORE_CURRENT_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DO_OFF_HOST_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: OFF_HOST_BACKUP_OPER_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: OFF_HOST_METADATA_COLL_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PRESERVE_CTIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: FOLLOW_SYMDIRS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: INCLUDE_REMOTE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NO_NFS_LOCKING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: FOLLOW_MOUNTPOINTS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TRUE_IMAGE_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: NBBSA_TOTAL_STREAMS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: BSA_SERVICE_HOST=FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_BACKUP_TYPE=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_SHUTDOWN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_RESTOREOPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_RECOVER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_ROLLFWDOPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECTED_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECTED_INSTANCE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_PUT_DB_ONLINE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECT_DATABASE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_REDIRECT_CONTAINER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_RE_OVERRIDE_RECO_LOG_PATH=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DB2_QUIESCE_DB=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_BACKUP_TYPE=2
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_SHUTDOWN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_STARTUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_BK_AUTO_ARCHDELETE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_BK_ARCHLOGS_INCR=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTOREOPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTOREOPTS_SCN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTOREOPTS_OMIT_READONLY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVEROPTS_DATETIME=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVEROPTS_SCN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVEROPTS_DELETE_ALOGS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTORE_CONTROL_FILE_ONLY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RESTORE_VALIDATE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ORACLE_RE_RECOVER_IF_VALID=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ENABLE_GRANULAR_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: CPS_JOB_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: CPS_JOB_INCR=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DO_PDI_DATA_TRANSFER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_DATA_TRANSFER_PHASE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_GENERAL_ATTRIBUTES=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_IPC_OBJECT=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_LOCATION=F:
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_RSTR_STG_LOCATION=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_PASSWORD_ENCRYPT_ALG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_DATA_COMP_ALG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SOFTWARE_MAJOR_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SOFTWARE_MINOR_VER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_TIME_ZONE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_AGENT_TIME_ZONE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_IMAGEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_IMAGECONTENTID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_GEN0SOURCEIMAGEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_CATALOGSOURCEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_GENERATIONNUMBER=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_DERIVEDSOURCEIMAGEID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_POLICY_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_FAMILY_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_HISTORY_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_INSTANCE_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_JOB_EXECUTION_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_SET_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_DEVICE_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_SET_RETENSION_HRS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_TYPE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_PREVIOUSIMAGECONTENTID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SELECTION_LIST_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_SET_SEQUENCE_NUM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_IMAGE_LINK_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_MEDIA_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_ATTRIB1=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_ATTRIB2=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_VCB_COMPRESSED_OBJ=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_RESTORE_DIBSET=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_ACTIVATE_DIB_AFTER_VERIFY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_OPEN_DATABASE_WHEN_FINISHED=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_DONOT_VERIFY_DIB_AFTER_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_RESTORE_ROLL_FORWARD_LOG_DIRECTORY_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DSBK_LEAVE_BACKUP_FILE_ON_DISK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: POLICY_GUID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_FAMILY_GUID={E696DD82-FEEB-48F3-B3E7-1D01EE5E7B89}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_HISTORY_GUID={627E4838-F0F6-4F4B-A01B-17DA721FD700}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_INSTANCE_GUID={54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: JOB_EXECUTION_GUID={627E4838-F0F6-4F4B-A01B-17DA721FD700}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_SET_GUID={00000009-03E8-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: DEVICE_GUID={AEF0DE93-08D2-43F4-B73D-0D354F472B40}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_SET_RETENSION_HOURS=8765808
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: TARGET_MEDIA_TYPE=15
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_ANCESTRY_LOCATIONS=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: IMAGE_LINK_ID = {00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: RECREATE_AD_OBJECTS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_SET_COUNT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_MAX_SET_COUNT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_KEY=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_PREFIX=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_SUFFIX=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_NEXTNUM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL_NUMDIGITS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MEDIA_LABEL=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_POINT_IN_TIME_LOWWORD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: REST_ESE_POINT_IN_TIME_HIGHWORD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: B2D_RESERVED_DISKSPACE_MB=10240
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ARCHIVE_JOB_DELETE_PHASE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EVBackupMethod=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_SQL_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_SITENAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EVForceDBRestore=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_SKIP_RESTORE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: EV_LOG_RECVR_OPTN=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: PDI_BKUP_TEMP_LOCATION=C:\TEMP
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_EXPORT_AS_MONOLITHIC_FILE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_EXPORT_AS_FLAT_DISK=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_VCB_TRANSPORT_MODE=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_USE_MEDIA_SERVER_AS_PROXY=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_VCB_PROXY_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_EXPORT_PATH_ON_VCB_PROXY=C:\TEMP
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_RESTORE_RUNNING_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_POWER_ON_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_RUN_CONVERTER_JOB=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_USE_MEDIA_SERVER_FOR_STAGING=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REMOVE_EXISTING_VCBMOUNTER_SNAPSHOT=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_STAGING_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_STAGING_PATH=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_VM_NAME=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_DATACENTER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_FOLDER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_HOST=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_DATASTORE=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_NETWORKS=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: ENV: VMWARE_AGENT_REDIRECTED_NUMBER_OF_NICS=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_FLAG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_USE_ORG_DISK_DS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_SERVER_ACCT_ID={00000000-0000-0000-0000-000000000000}
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_REDIRECTED_SERVER=
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_BACKUP_METHOD=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_INCLUDE_VS_CONFIG=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_EXCLUDE_RUNNING_VMS=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_DO_GRT_BACKUP=1
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_DO_SIS_BACKUP=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_RESTORE_RUNNING_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: MSVS_AGENT_POWER_ON_OPTION=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: SNAPSHOT_IGNORE_REASONS=0x0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_CREATE_VERSION_7_VM=0
BEREMOTE: [12/28/09 14:54:26] [2528] ENV: VMWARE_AGENT_CREATE_THIN_PROVISIONED_DISK=0
BEREMOTE: [12/28/09 14:54:26] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:26] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:26] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:26] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:26] [2528] Finding MNET entries using PunchDownWithWNetCalls()
BEREMOTE: [12/28/09 14:54:26] [0000] NPGetCaps::WNNC_START
BEREMOTE: [12/28/09 14:54:26] [2528] Notes Agent:FindDrives
BEREMOTE: [12/28/09 14:54:26] [2528] No Oracle SID's were discovered
BEREMOTE: [12/28/09 14:54:26] [2528] GetServerType for local machine
BEREMOTE: [12/28/09 14:54:26] [2528] Device Shadow?Copy?Components Dle platform 2 major version 5 minor version 2 build 3790
BEREMOTE: [12/28/09 14:54:26] [2528] No utility partitions found on any of the disks
BEREMOTE: [12/28/09 14:54:26] [2528] NRDS_RA_FindDrives entered
BEREMOTE: [12/28/09 14:54:26] [2528] NRDS_RA_FindDrives completed immediately because we aren't browsing
BEREMOTE: [12/28/09 14:54:26] [2528] Informational: Cps root device creation using default Cps library mechanism when creating device 'Cont?Prot?Srv' in CPS::DetermineRootCpsComponent:696
BEREMOTE: [12/28/09 14:54:26] [2528] Informational: Cps library not available. Not creating Cps root device 'Cont?Prot?Srv' in CPS::DetermineRootCpsComponent:700
BEREMOTE: [12/28/09 14:54:26] [2528] Function called: DB2_FindDrives
BEREMOTE: [12/28/09 14:54:26] [2528] Status 0xE0008488 returned calling FindDrives for file system 28 in DLE_UpdateList
BEREMOTE: [12/28/09 14:54:26] [2528] AD:DeviceName "System?State" in SystemState::CreateDLEs:351
BEREMOTE: [12/28/09 14:54:26] [2528] AD:Full Device Path "System?State" in SystemState::CreateDLEs:356
BEREMOTE: [12/28/09 14:54:26] [2528] AD:ADRO Agent is authorized for System?State.
BEREMOTE: [12/28/09 14:54:26] [2528] AD:Found 0 instances of Active Directory Applcation Mode in SystemState::GetADAMInstanceCount:1355
BEREMOTE: [12/28/09 14:54:26] [2528] ADC_FindAgents entered
BEREMOTE: [12/28/09 14:54:26] [2528] ADC_FindAgents completed immediately because we aren't browsing
BEREMOTE: [12/28/09 14:54:26] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_FindDrives - a default SQL Service is installed on FSE1.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_FindDrives - the default SQL Service appears to be SQL 2000.
BEREMOTE: [12/28/09 14:54:26] [2528] IsServiceClusterOk called on FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:26] [2528] VCS cluster keys do not appear to be present in the registry
BEREMOTE: [12/28/09 14:54:26] [2528] Failed to open VCS cluster ()
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_CreateDLE - creating FSE1.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL Version 2005
BEREMOTE: [12/28/09 14:54:26] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:26] [2528] Found SOFTWARE\MICROSOFT\Microsoft SQL Server\MSSQL.1 in the registry.
BEREMOTE: [12/28/09 14:54:26] [2528] Setting 2K5 extended feature bit.
BEREMOTE: [12/28/09 14:54:26] [2528] AddESE_DLEs( )
BEREMOTE: [12/28/09 14:54:26] [2528] AddESE_DLEs for sever name FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] HrESEBackupRestoreGetRegistered: FSE1, NULL = 0xc7ff07d7
BEREMOTE: [12/28/09 14:54:26] [2528] ESE_GetBackupAnnotations returned 0xc7ff07d7
BEREMOTE: [12/28/09 14:54:26] [2528] EV_FindDrives
BEREMOTE: [12/28/09 14:54:26] [2528] evFileSystem::EV_FindDrives: Agent not authorized.
BEREMOTE: [12/28/09 14:54:26] [2528] FS_BlowOutMachine: entered -->FSE1<--
BEREMOTE: [12/28/09 14:54:26] [2528] FS_BlowOutMachine: device already in DLE list
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2: Attempting attach with username ''
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_Connect worked.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL Agent: Version String { 9.00.1399.06 }
BEREMOTE: [12/28/09 14:54:26] [2528] SQL edition returned: Standard Edition
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3893]
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3894]
BEREMOTE: [12/28/09 14:54:26] [2528] SQL DBClose
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_CleanUpSnapInfoList called.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2: Attempting attach with username ''
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_Connect worked.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL Agent: Version String { 9.00.1399.06 }
BEREMOTE: [12/28/09 14:54:26] [2528] SQL edition returned: Standard Edition
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3893]
BEREMOTE: [12/28/09 14:54:26] [2528] line = "FSE1\*.*",v0,t0,l0,n0,f0
BEREMOTE: [12/28/09 14:54:26] [2528] dir =
BEREMOTE: [12/28/09 14:54:26] [2528] file = *.*
BEREMOTE: [12/28/09 14:54:26] [2528] oper = Include
BEREMOTE: [12/28/09 14:54:26] [2528] subs = False
BEREMOTE: [12/28/09 14:54:26] [2528] wild = True
BEREMOTE: [12/28/09 14:54:26] [2528] attr_on_mask = 0
BEREMOTE: [12/28/09 14:54:26] [2528] attr_off_mask = 0
BEREMOTE: [12/28/09 14:54:26] [2528] AddFsExcludes:
BEREMOTE: [12/28/09 14:54:26] [2528] BSD for FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] + \*.*
BEREMOTE: [12/28/09 14:54:26] [2528] BSD for FSE1
BEREMOTE: [12/28/09 14:54:26] [2528] + \*.*
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3894]
BEREMOTE: [12/28/09 14:54:26] [2528] SQL DBClose
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_CleanUpSnapInfoList called.
BEREMOTE: [12/28/09 14:54:26] [2528] Backup Job Start - Mon Dec 28 14:54:26 2009
BENGINE: [12/28/09 14:54:26] [3656]
dataStartBackup: ndmpSendRequest returned: 0x0, 0
BEREMOTE: [12/28/09 14:54:26] [2528] Added the media server FSE1 to the advertisement list.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2: Attempting attach with username ''
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_Connect worked.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL Agent: Version String { 9.00.1399.06 }
BEREMOTE: [12/28/09 14:54:26] [2528] SQL edition returned: Standard Edition
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3893]
BEREMOTE: [12/28/09 14:54:26] [2528] TF_OpenTape()
BEREMOTE: [12/28/09 14:54:26] [2528] TF_GetDriveContext ...
BEREMOTE: [12/28/09 14:54:26] [2528] TF_GetDriveContext returning 0x00000000, context = 5020050
BEREMOTE: [12/28/09 14:54:26] [2528] Shared Buffers Connection type prepared...
BEREMOTE: [12/28/09 14:54:26] [2528] Allocated 10 buffers, size 32768 bytes, total used: 328520
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_DoCommand refusing delegation request.
BEREMOTE: [12/28/09 14:54:26] [2528] TF_OpenSet()
BEREMOTE: [12/28/09 14:54:26] [2528] SetupFormatEnv( fmt=0 )
BEREMOTE: [12/28/09 14:54:26] [2528] End of TF_OpenSet() ret_val = 0x00000000, num buffers = 10
BEREMOTE: [12/28/09 14:54:26] [2528] Going to perform pre backup dbcc.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2: Attempting attach with username ''
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_Connect worked.
BEREMOTE: [12/28/09 14:54:26] [2528] SQL Agent: Version String { 9.00.1399.06 }
BEREMOTE: [12/28/09 14:54:26] [2528] SQL edition returned: Standard Edition
BEREMOTE: [12/28/09 14:54:26] [2528] [no resource for 3893]
BENGINE: [12/28/09 14:54:26] [3656] ** Starting SQL DBCC **
BEREMOTE: [12/28/09 14:54:26] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:54:26] [2528] SQL2_StartCommand = DBCC CHECKDB([BEDB])
BESERVER: [12/28/09 14:54:29] [1080] 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
BEREMOTE: [12/28/09 14:54:30] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([BEDB])
BEREMOTE: [12/28/09 14:54:32] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:54:32] [2528] SQL2_StartCommand = DBCC CHECKDB([FSEGoldmine])
BEREMOTE: [12/28/09 14:54:51] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([FSEGoldmine])
BEREMOTE: [12/28/09 14:54:54] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:54:54] [2528] SQL2_StartCommand = DBCC CHECKDB([FSEsmeadlink])
BEREMOTE: [12/28/09 14:54:57] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([FSEsmeadlink])
BEREMOTE: [12/28/09 14:54:59] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:54:59] [2528] SQL2_StartCommand = DBCC CHECKDB([FSETigerPaw])
BEREMOTE: [12/28/09 14:55:10] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([FSETigerPaw])
BEREMOTE: [12/28/09 14:55:12] [2528] Returning master
BEREMOTE: [12/28/09 14:55:12] [2528] SQL2_StartCommand = DBCC CHECKDB([master])
BEREMOTE: [12/28/09 14:55:14] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([master])
BEREMOTE: [12/28/09 14:55:16] [2528] Returning model
BEREMOTE: [12/28/09 14:55:16] [2528] SQL2_StartCommand = DBCC CHECKDB([model])
BEREMOTE: [12/28/09 14:55:18] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([model])
BEREMOTE: [12/28/09 14:55:19] [2528] Returning msdb
BEREMOTE: [12/28/09 14:55:19] [2528] SQL2_StartCommand = DBCC CHECKDB([msdb])
BEREMOTE: [12/28/09 14:55:21] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([msdb])
BEREMOTE: [12/28/09 14:55:22] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:55:22] [2528] SQL2_StartCommand = DBCC CHECKDB([ReportServer])
BEREMOTE: [12/28/09 14:55:24] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([ReportServer])
BEREMOTE: [12/28/09 14:55:26] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:55:26] [2528] SQL2_StartCommand = DBCC CHECKDB([ReportServerTempDB])
BEREMOTE: [12/28/09 14:55:27] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([ReportServerTempDB])
BEREMOTE: [12/28/09 14:55:29] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:55:29] [2528] Returning msdb
BEREMOTE: [12/28/09 14:55:29] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:55:29] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:55:29] [2528] [no resource for 3894]
BEREMOTE: [12/28/09 14:55:29] [2528] SQL DBClose
BEREMOTE: [12/28/09 14:55:29] [2528] SQL2_CleanUpSnapInfoList called.
BENGINE: [12/28/09 14:55:29] [3656] ** Stopping SQL DBCC **
BEREMOTE: [12/28/09 14:55:29] [2528] Windows Server 2003 (or higher) detected. No S2LP mapping required.
BESERVER: [12/28/09 14:55:29] [2464] "Cluster" key does not appear to be present in the registry
BESERVER: [12/28/09 14:55:29] [2464] Failed to open Microsoft cluster ()
BESERVER: [12/28/09 14:55:29] [2464] VCS cluster keys do not appear to be present in the registry
BESERVER: [12/28/09 14:55:29] [2464] Failed to open VCS cluster ()
BESERVER: [12/28/09 14:55:29] [2464] 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
PVLSVR: [12/28/09 14:55:29] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:55:29] [3312] PvlEntityDatabase::QueryEntity() : SUCCESS!
BEREMOTE: [12/28/09 14:55:29] [2528] Returning BEDB
BESERVER: [12/28/09 14:55:29] [2464] 12 AdammAdminBO::Get() - hr = 0x0
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \BEDB
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \FSEGoldmine
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \FSEsmeadlink
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \FSETigerPaw
BEREMOTE: [12/28/09 14:55:29] [2528] Returning master
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \master
BEREMOTE: [12/28/09 14:55:29] [2528] Returning model
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \model
BEREMOTE: [12/28/09 14:55:29] [2528] Returning msdb
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \msdb
BEREMOTE: [12/28/09 14:55:29] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \ReportServer
BEREMOTE: [12/28/09 14:55:29] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:55:29] [2528] Using the Virtual Device mechanism for the backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Open Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] VDI Buffer Timeout set to INFINITE
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check.
BEREMOTE: [12/28/09 14:55:29] [2528] SQL Agent VDI Check: Latest DLL registered.
BEREMOTE: [12/28/09 14:55:29] [2528] Error calling CoCreateInstance: x8007007E
BEREMOTE: [12/28/09 14:55:29] [2528] Failed the Virtual Device allocation for backup operation.
BEREMOTE: [12/28/09 14:55:29] [2528] Destroying VirtualDeviceSet
BEREMOTE: [12/28/09 14:55:29] [2528] Informational, no operation thread handle to close.
BEREMOTE: [12/28/09 14:55:29] [2528] Informational - cannot deallocate Virtual Device object.
BEREMOTE: [12/28/09 14:55:29] [2528] LP_ENV::MsgError: error 0xe00084af processing object \ReportServerTempDB
BEREMOTE: [12/28/09 14:55:29] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:55:29] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:55:29] [2528] Returning msdb
BEREMOTE: [12/28/09 14:55:29] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:55:29] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:55:29] [2528] TF xfer time = 0 seconds.
BEREMOTE: [12/28/09 14:55:29] [2528] WRITE: tpreceive_fail_count = 0
BEREMOTE: [12/28/09 14:55:29] [2528] WRITE: waiting_on_buffers_count = 0
BEREMOTE: [12/28/09 14:55:29] [2528] WRITE: buffers_written_count = 0
BENGINE: [12/28/09 14:55:29] [3656] TF_NDMPStartWrite(): Started LocalMediaServerWriteThread 992
PVLSVR: [12/28/09 14:55:30] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:55:30] [5452] PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR: [12/28/09 14:55:30] [3312] PvlEntityDatabase::UpdateEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
EntityFlags = 0x00830000
PVLSVR: [12/28/09 14:55:30] [3312] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [12/28/09 14:55:30] [0992] WriteEndSet( 1 ) returning 0x00000000
BEREMOTE: [12/28/09 14:55:30] [2528] TF_CloseSet()
BEREMOTE: [12/28/09 14:55:30] [2528] FreeFormatEnv( cur_fmt=0 )
BEREMOTE: [12/28/09 14:55:30] [2528] Going to perform post backup dbcc.
BEREMOTE: [12/28/09 14:55:30] [2528] SQL2: Attempting attach with username ''
BEREMOTE: [12/28/09 14:55:30] [2528] SQL2_Connect worked.
BEREMOTE: [12/28/09 14:55:30] [2528] SQL Agent: Version String { 9.00.1399.06 }
BEREMOTE: [12/28/09 14:55:30] [2528] SQL edition returned: Standard Edition
BEREMOTE: [12/28/09 14:55:30] [2528] [no resource for 3893]
BENGINE: [12/28/09 14:55:30] [3656] ** Starting SQL DBCC **
BEREMOTE: [12/28/09 14:55:30] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:55:30] [2528] SQL2_StartCommand = DBCC CHECKDB([BEDB])
BENGINE: [12/28/09 14:55:31] [0992] WriteEndSet( 1 ) returning 0x00000000
BENGINE: [12/28/09 14:55:31] [0992] WriteEndSet( 0 ) returning 0x00000000
PVLSVR: [12/28/09 14:55:31] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:55:31] [5544] PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR: [12/28/09 14:55:31] [5452] PvlEntityDatabase::UpdateEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
EntityFlags = 0x00830000
PVLSVR: [12/28/09 14:55:31] [5452] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [12/28/09 14:55:31] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:31] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:31] [0992] Requested Set: ID = ffffffff Seq = -1 Set = -1
BENGINE: [12/28/09 14:55:31] [0992] PositionAtSet( :( TF Msg = 4
BENGINE: [12/28/09 14:55:31] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:31] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:31] [0992] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:55:31] [0992] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:55:31] [0992] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:55:31] [0992] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:55:31] [0992] Entering TF_MountNewMedia ... (baseChannel = 02FFA240)
PVLSVR: [12/28/09 14:55:31] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
BENGINE: [12/28/09 14:55:31] [0992] TF_MountNewMedia: mediaId == NULL
PVLSVR: [12/28/09 14:55:31] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_CONTINUATION )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:55:31] [5452] AdammSession::CheckTargetDeviceStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Job = {54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}, "SQL"
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
State = 0003, ReservationID = 0000, AvailableMovers = 0000
PVLSVR: [12/28/09 14:55:31] [5452] AdammSession::MountOverwriteMedia()
PVLSVR: [12/28/09 14:55:31] [5452] AdammSession::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
Side = 0001
PVLSVR: [12/28/09 14:55:31] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 4294967295
Side = 4294967295
PVLSVR: [12/28/09 14:55:31] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:55:32] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 4294967295
Side = 4294967295
PVLSVR: [12/28/09 14:55:32] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 268369920
Side = 0001
PVLSVR: [12/28/09 14:55:32] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 268369920
Side = 0001
PVLSVR: [12/28/09 14:55:33] [0000] EXECUTE: { CALL QueryNextMediaLabel ( ?, ?, ?, ?, ?, ?, ? ) }
BESERVER: [12/28/09 14:55:32] [2464] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:55:32] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:55:33] [2464] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:55:33] [2464] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:55:33] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:55:33] [2464] 12 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [12/28/09 14:55:33] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Side = 0001
PVLSVR: [12/28/09 14:55:33] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Side = 0001
PVLSVR: [12/28/09 14:55:34] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
BENGINE: [12/28/09 14:55:34] [0372] Updating status for: 'SQL' (0x1 0x9)
BENGINE: [12/28/09 14:55:34] [0372] Status for: 'SQL' updated
PVLSVR: [12/28/09 14:55:34] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Side = 0001
PVLSVR: [12/28/09 14:55:34] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Side = 0001
PVLSVR: [12/28/09 14:55:34] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0001
Side = 0001
PVLSVR: [12/28/09 14:55:34] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0001
Side = 0001
PVLSVR: [12/28/09 14:55:34] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
BESERVER: [12/28/09 14:55:35] [2676] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:55:35] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:55:35] [2676] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:55:35] [2676] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:55:35] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:55:35] [2676] 12 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [12/28/09 14:55:35] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0001
Side = 0001
PVLSVR: [12/28/09 14:55:35] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0001
Side = 0001
PVLSVR: [12/28/09 14:55:35] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0002
Side = 0001
PVLSVR: [12/28/09 14:55:35] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0002
Side = 0001
PVLSVR: [12/28/09 14:55:35] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:35] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0002
Side = 0001
PVLSVR: [12/28/09 14:55:35] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0002
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:36] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0004
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0004
Side = 0001
PVLSVR: [12/28/09 14:55:36] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:36] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0004
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0004
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0005
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0005
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:37] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0005
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0005
Side = 0001
BEREMOTE: [12/28/09 14:55:37] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([BEDB])
PVLSVR: [12/28/09 14:55:37] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0006
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0006
Side = 0001
PVLSVR: [12/28/09 14:55:37] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:38] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0006
Side = 0001
PVLSVR: [12/28/09 14:55:38] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0006
Side = 0001
PVLSVR: [12/28/09 14:55:38] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0007
Side = 0001
PVLSVR: [12/28/09 14:55:38] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0007
Side = 0001
PVLSVR: [12/28/09 14:55:38] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:38] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0007
Side = 0001
PVLSVR: [12/28/09 14:55:39] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0007
Side = 0001
PVLSVR: [12/28/09 14:55:39] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0008
Side = 0001
PVLSVR: [12/28/09 14:55:39] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0008
Side = 0001
PVLSVR: [12/28/09 14:55:39] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:40] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0008
Side = 0001
PVLSVR: [12/28/09 14:55:40] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0008
Side = 0001
PVLSVR: [12/28/09 14:55:40] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:55:40] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:55:40] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:55:41] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:55:41] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:55:41] [5452] AdammSession::MountOverwriteMedia()
PVLSVR: [12/28/09 14:55:42] [5452] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
Slot = 0009
Side = 0000
PVLSVR: [12/28/09 14:55:42] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:55:42] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
BEREMOTE: [12/28/09 14:55:42] [2528] Returning FSEGoldmine
BESERVER: [12/28/09 14:55:42] [4132] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:55:42] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BEREMOTE: [12/28/09 14:55:42] [2528] SQL2_StartCommand = DBCC CHECKDB([FSEGoldmine])
BESERVER: [12/28/09 14:55:42] [4132] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:55:42] [4132] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:55:42] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:55:42] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
BESERVER: [12/28/09 14:55:42] [4132] 12 AdammAdminBO::Execute() - hr = 0x0
PVLSVR: [12/28/09 14:55:42] [3932] 23000 - [Microsoft][ODBC SQL Server Driver][SQL Server]The UPDATE statement conflicted with the FOREIGN KEY constraint "Media_MediaType_FKEY". The conflict occurred in database "BEDB", table "dbo.MediaType".
PVLSVR: [12/28/09 14:55:42] [3932] UPDATE Media SET MediaType = ?, MediaSubType = ? WHERE ( MediaGuid = ? ) ;
PVLSVR: [12/28/09 14:55:42] [5452] PvlDrive::MountMedia() - Establish connection
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
PVLSVR: [12/28/09 14:55:42] [5452] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
Side = 0001
SUCCESS!
PVLSVR: [12/28/09 14:55:42] [5452] AdammSession::IsOverwriteMediaMounted() -
Need WORM: No
Need Encryption: No
PVLSVR: [12/28/09 14:55:42] [5452] AdammSession::IsOverwriteMediaMounted() - ERROR = 0x00000450 (ERROR_NO_DATA_DETECTED)
PVLSVR: [12/28/09 14:55:42] [5452] PvlDrive::WriteMediaHeader()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
Slot = 0009
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
MediaLabel = "B2D000010"
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}
MediaHeader =
PVLSVR: [12/28/09 14:55:44] [5452] AdammSession::IsOverwriteMediaMounted() -
Need WORM: No
Need Encryption: No
PVLSVR: [12/28/09 14:55:44] [5452] AdammSession::IsOverwriteMediaMounted() - SUCCESS!
BENGINE: [12/28/09 14:55:44] [0992] TF_MountNewMedia: IsChannelStatus == true. result = 00000000
BENGINE: [12/28/09 14:55:44] [0992] TF_MountNewMedia exiting and succeeded!
BENGINE: [12/28/09 14:55:44] [0992] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:55:44] [0992] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:55:44] [0992] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:55:44] [0992] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:55:44] [0992] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
PVLSVR: [12/28/09 14:55:44] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:55:44] [0992] Updating session {da0edee0-4ca9-4a9b-aa60-2154d0484556} with drive FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.1
BENGINE: [12/28/09 14:55:44] [0992] Media Label: B2D000010
BENGINE: [12/28/09 14:55:44] [0992] Media Label: {88f398fd-c22d-4eae-a58e-58e730eab1a0}
BESERVER: [12/28/09 14:55:44] [2676] 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
PVLSVR: [12/28/09 14:55:44] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:55:44] [5452] PvlEntityDatabase::QueryEntity() : SUCCESS!
BESERVER: [12/28/09 14:55:44] [2676] 12 AdammAdminBO::Get() - hr = 0x0
BENGINE: [12/28/09 14:55:44] [0992] Overwrite Protected Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:55:44] [0992] Appendable Until: 12/30/99 6:00:00 PM
PVLSVR: [12/28/09 14:55:44] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:55:44] [0992] Updating session {da0edee0-4ca9-4a9b-aa60-2154d0484556} with drive FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.1
BENGINE: [12/28/09 14:55:44] [0992] UI Msg = 8008
BENGINE: [12/28/09 14:55:44] [0992] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:55:44] [0992] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:55:44] [0992] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:55:44] [0992] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:55:44] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:44] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:44] [0992] Attempting to VCB storage media
BENGINE: [12/28/09 14:55:44] [0992] TpRead( :(
BENGINE: [12/28/09 14:55:44] [0992] Error = 1101 Req = 6144 Got = 1024
BENGINE: [12/28/09 14:55:44] [0992] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:55:44] [0992] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:55:44] [0992] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:55:44] [0992] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:55:44] [0992] FreeFormatEnv( cur_fmt=65535 )
BENGINE: [12/28/09 14:55:44] [0992] SetupFormatEnv( fmt=0 )
BENGINE: [12/28/09 14:55:44] [0992] Current media tape header was written by "VERITAS:PVL".
BENGINE: [12/28/09 14:55:44] [0992] TpRead( :(
BENGINE: [12/28/09 14:55:44] [0992] Error = 1104 Req = 6144 Got = 0
BENGINE: [12/28/09 14:55:44] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:44] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:44] [0992] PositionAtSet( :( TF Msg = e
BENGINE: [12/28/09 14:55:44] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:44] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:44] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:44] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:44] [0992] UI Msg = 8003
BENGINE: [12/28/09 14:55:44] [0992] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:55:44] [0992] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:55:44] [0992] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:55:44] [0992] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:55:44] [0992] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:55:44] [0992] ret_val = 0
BENGINE: [12/28/09 14:55:44] [0992] FreeFormatEnv( cur_fmt=0 )
BENGINE: [12/28/09 14:55:44] [0992] F40_NDMPWriteContSet crossing lba = 2
BENGINE: [12/28/09 14:55:44] [0992] B2D Buffered Writes ===> Setting buffering to default.
BENGINE: [12/28/09 14:55:44] [0992] WriteEndSet( 1 ) returning 0x00000000
BENGINE: [12/28/09 14:55:44] [0992] Current Block is = 3
PVLSVR: [12/28/09 14:55:44] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:55:44] [3312] PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR: [12/28/09 14:55:44] [5544] PvlEntityDatabase::UpdateEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
EntityFlags = 0x00830000
PVLSVR: [12/28/09 14:55:45] [5544] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [12/28/09 14:55:49] [0372] Updating status for: 'SQL' (0x1 0x0)
BENGINE: [12/28/09 14:55:49] [0372] Status for: 'SQL' updated
BEREMOTE: [12/28/09 14:56:01] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([FSEGoldmine])
BEREMOTE: [12/28/09 14:56:04] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:56:04] [2528] SQL2_StartCommand = DBCC CHECKDB([FSEsmeadlink])
BEREMOTE: [12/28/09 14:56:07] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([FSEsmeadlink])
BEREMOTE: [12/28/09 14:56:09] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:56:09] [2528] SQL2_StartCommand = DBCC CHECKDB([FSETigerPaw])
BEREMOTE: [12/28/09 14:56:21] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([FSETigerPaw])
BEREMOTE: [12/28/09 14:56:23] [2528] Returning master
BEREMOTE: [12/28/09 14:56:23] [2528] SQL2_StartCommand = DBCC CHECKDB([master])
BEREMOTE: [12/28/09 14:56:25] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([master])
BEREMOTE: [12/28/09 14:56:26] [2528] Returning model
BEREMOTE: [12/28/09 14:56:26] [2528] SQL2_StartCommand = DBCC CHECKDB([model])
BEREMOTE: [12/28/09 14:56:28] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([model])
BEREMOTE: [12/28/09 14:56:30] [2528] Returning msdb
BEREMOTE: [12/28/09 14:56:30] [2528] SQL2_StartCommand = DBCC CHECKDB([msdb])
BEREMOTE: [12/28/09 14:56:32] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([msdb])
BEREMOTE: [12/28/09 14:56:33] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:56:33] [2528] SQL2_StartCommand = DBCC CHECKDB([ReportServer])
BEREMOTE: [12/28/09 14:56:35] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([ReportServer])
BEREMOTE: [12/28/09 14:56:37] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:56:37] [2528] SQL2_StartCommand = DBCC CHECKDB([ReportServerTempDB])
BEREMOTE: [12/28/09 14:56:38] [2528] SQL2_StartCommand = DBCC CHECKCATALOG([ReportServerTempDB])
BEREMOTE: [12/28/09 14:56:40] [2528] Returning BEDB
BEREMOTE: [12/28/09 14:56:40] [2528] Returning FSEGoldmine
BEREMOTE: [12/28/09 14:56:40] [2528] Returning FSEsmeadlink
BEREMOTE: [12/28/09 14:56:40] [2528] Returning FSETigerPaw
BEREMOTE: [12/28/09 14:56:40] [2528] Returning msdb
BEREMOTE: [12/28/09 14:56:40] [2528] Returning ReportServer
BEREMOTE: [12/28/09 14:56:40] [2528] Returning ReportServerTempDB
BEREMOTE: [12/28/09 14:56:40] [2528] [no resource for 3894]
BEREMOTE: [12/28/09 14:56:40] [2528] SQL DBClose
BEREMOTE: [12/28/09 14:56:40] [2528] SQL2_CleanUpSnapInfoList called.
BEREMOTE: [12/28/09 14:56:40] [2528] [no resource for 3894]
BENGINE: [12/28/09 14:56:40] [3656] ** Stopping SQL DBCC **
BEREMOTE: [12/28/09 14:56:40] [2528] SQL DBClose
BEREMOTE: [12/28/09 14:56:40] [2528] SQL2_CleanUpSnapInfoList called.
BEREMOTE: [12/28/09 14:56:40] [2528] TF_FreeDriveContext( 5020050 )
BEREMOTE: [12/28/09 14:56:40] [2528] TF_FreeTapeBuffers: from 10 to 0 buffers
BEREMOTE: [12/28/09 14:56:40] [2528] Backup Job Stop(0) - Mon Dec 28 14:56:40 2009
BENGINE: [12/28/09 14:56:40] [3656] data halted: SUCCESSFUL
BENGINE: [12/28/09 14:56:40] [3656] BaseBSDProcessor::PollDataServer: Shutting down.
BENGINE: [12/28/09 14:56:40] [3656] TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0x00000000
BENGINE: [12/28/09 14:56:40] [3656] TF_NDMPProcessOTCEOM crossing lba = 2
BENGINE: [12/28/09 14:56:40] [3656] TF_NDMPProcessOTCEOM processing OTC EOM cont vcb lba = 0
BENGINE: [12/28/09 14:56:40] [3656] BackupMessageHandler::CloseCatalog called
BENGINE: [12/28/09 14:56:40] [3656] Not: 'm_bCatalogDrivenBackup && CatBuildVector[engine_id] && GetCarryFwdCatalogInfoForRemainingObjs( )'
BENGINE: [12/28/09 14:56:40] [3656] called CatBuildVector[engine_id]->GetErrorCondition() successfully
BENGINE: [12/28/09 14:56:40] [3656] BackupMessageHandler::CDB_EndCurrentCatalogOperation( ) -- entered
BENGINE: [12/28/09 14:56:40] [3656] BackupMessageHandler::CDB_EndCurrentCatalogOperation( ) is calling PerBSDDataVector[engine_id]->EndCatOperation( )
BENGINE: [12/28/09 14:56:40] [3656] EndCatOperation succeeded
BENGINE: [12/28/09 14:56:40] [3656] BackupMessageHandler::CDB_EndCurrentCatalogOperation( ) -- returning
BENGINE: [12/28/09 14:56:40] [3656] calling CatBuildVector[engine_id]->FinishBackup( ) for non-cat drive backup
BENGINE: [12/28/09 14:56:41] [3656] WriteEndSet( 1 ) returning 0x00000000
BENGINE: [12/28/09 14:56:41] [3656] WriteEndSet( 1 ) returning 0x00000000
BENGINE: [12/28/09 14:56:41] [3656] WriteEndSet( 0 ) returning 0x00000000
PVLSVR: [12/28/09 14:56:41] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:56:41] [5452] PvlEntityDatabase::QueryEntity() : SUCCESS!
PVLSVR: [12/28/09 14:56:41] [3312] PvlEntityDatabase::UpdateEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
EntityFlags = 0x00830000
PVLSVR: [12/28/09 14:56:41] [3312] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [12/28/09 14:56:41] [3656] TF_FreeTapeBuffers: from 0 to 0 buffers
BENGINE: [12/28/09 14:56:42] [3656] TF_CloseSet
BENGINE: [12/28/09 14:56:42] [3656] Enter NDMPSnapHostBackupEngine::TrackSnappedBSD
BENGINE: [12/28/09 14:56:42] [3656] Exit NDMPSnapHostBackupEngine::TrackSnappedBSD
BENGINE: [12/28/09 14:56:42] [3656] Exit NDMPSnapHostBackupEngine::ProcessBSDs
BENGINE: [12/28/09 14:56:42] [3656] NDMPAgentConnector::EndConnection(): Data Server = FSE1
BEREMOTE: [12/28/09 14:56:42] [2528] @@@@@@@MyCloseSocket called with sockfd = 2208(0x8a0) retval = 0
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:56:42] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
BENGINE: [12/28/09 14:56:42] [3656] TF_PutChannel( ) 02FFA240
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
PVLSVR: [12/28/09 14:56:42] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:56:42] [3656] Updating session {da0edee0-4ca9-4a9b-aa60-2154d0484556} with drive FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.1
BENGINE: [12/28/09 14:56:42] [3656] TF_OpenTape()
BENGINE: [12/28/09 14:56:42] [3656] TF_GetChannel( ) reusing channel 011FFCF8
BENGINE: [12/28/09 14:56:42] [3656] Allocated 10 buffers, size 32768 bytes, total used: 328480
BENGINE: [12/28/09 14:56:42] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:56:42] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:56:42] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:56:42] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:56:42] [3656] TF_OpenSet( )
BENGINE: [12/28/09 14:56:42] [3656] Requested Set: ID = 3b9c6a70 Seq = 1 Set = 2
BENGINE: [12/28/09 14:56:42] [3656] Current VCB: ID = 3b9c6a70 Seq = 2 Set = 2
BENGINE: [12/28/09 14:56:42] [3656] PositionAtSet( :( TF Msg = 9
BENGINE: [12/28/09 14:56:42] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:56:42] [3656] ret_val = 0
BENGINE: [12/28/09 14:56:42] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:56:42] [3656] ret_val = 0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] Entering TF_MountNewMedia ... (baseChannel = 011FFCF8)
PVLSVR: [12/28/09 14:56:42] [3312] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_SPECIFIC )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5544] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 4294967295
Side = 4294967295
PVLSVR: [12/28/09 14:56:42] [5544] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1" (Backup To Disk)
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::CheckTargetDeviceStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Job = {54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}, "SQL"
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
State = 0003, ReservationID = 0000, AvailableMovers = 0001
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::MountSpecificMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
Location = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
LocationIndex = 0003
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5544] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5544] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5544] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:56:42] [5544] PvlDrive::MountMedia() - Establish connection
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
Side = 0001
SUCCESS!
BENGINE: [12/28/09 14:56:42] [3656] TF_MountNewMedia: PVL_SPECIFIC_MOUNT. result = 00000000
BENGINE: [12/28/09 14:56:42] [3656] TF_MountNewMedia exiting and succeeded!
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
PVLSVR: [12/28/09 14:56:42] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:56:42] [3656] Updating session {da0edee0-4ca9-4a9b-aa60-2154d0484556} with drive FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.1
BENGINE: [12/28/09 14:56:42] [3656] Media Label: B2D000004
BENGINE: [12/28/09 14:56:42] [3656] Media Label: {4d255a8f-b8ee-4647-8a11-6a6023bd3af9}
BESERVER: [12/28/09 14:56:42] [5300] "Cluster" key does not appear to be present in the registry
BESERVER: [12/28/09 14:56:42] [5300] Failed to open Microsoft cluster ()
BESERVER: [12/28/09 14:56:42] [5300] VCS cluster keys do not appear to be present in the registry
BESERVER: [12/28/09 14:56:42] [5300] Failed to open VCS cluster ()
BESERVER: [12/28/09 14:56:42] [5300] 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
PVLSVR: [12/28/09 14:56:42] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:56:42] [5544] PvlEntityDatabase::QueryEntity() : SUCCESS!
BESERVER: [12/28/09 14:56:42] [5300] 12 AdammAdminBO::Get() - hr = 0x0
BENGINE: [12/28/09 14:56:42] [3656] Overwrite Protected Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:56:42] [3656] Appendable Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:56:42] [3656] UI Msg = 8008
BENGINE: [12/28/09 14:56:42] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:56:42] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:56:42] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:56:42] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:56:42] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:56:42] [3656] ret_val = 0
BENGINE: [12/28/09 14:56:42] [3656] Attempting to VCB storage media
BENGINE: [12/28/09 14:56:42] [3656] TpRead( :(
BENGINE: [12/28/09 14:56:42] [3656] Error = 1101 Req = 32768 Got = 1024
BENGINE: [12/28/09 14:56:42] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:56:42] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:56:42] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:56:42] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:56:42] [3656] Current media tape header was written by "Symantec Backup Exec 12.5.2213".
BENGINE: [12/28/09 14:56:42] [3656] TpRead( :(
BENGINE: [12/28/09 14:56:42] [3656] Error = 1101 Req = 32768 Got = 2048
BENGINE: [12/28/09 14:56:42] [3656] ReadThisSet( cur_fmt=0 )
BENGINE: [12/28/09 14:56:42] [3656] Set 1 was written by version 12.5.
BENGINE: [12/28/09 14:56:42] [3656] ReadThisSet() return=0x00000000
BENGINE: [12/28/09 14:56:42] [3656] Current VCB: ID = 3b9c6a70 Seq = 1 Set = 1
BENGINE: [12/28/09 14:56:42] [3656] End of TF_OpenSet: Ret_val = 0x00000000 Buffs = 10 HiWater = 7
BENGINE: [12/28/09 14:56:42] [3656] GotoBlock: VCB_PBA = 16, VCB_LBA = 0, LBA = 0,
BENGINE: [12/28/09 14:56:42] [3656] PBA = 16, offset = 0
BENGINE: [12/28/09 14:56:42] [3656] Device Error = 1101
BENGINE: [12/28/09 14:56:42] [3656] Len Req = 32768 Len Got = 2048
BENGINE: [12/28/09 14:56:42] [3656] Set 2 was written by version 12.5.
BENGINE: [12/28/09 14:56:42] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:56:42] [3656] ret_val = 0
BENGINE: [12/28/09 14:56:42] [3656] Requested Set: ID = 3b9c6a70 Seq = 2 Set = 2
BENGINE: [12/28/09 14:56:42] [3656] PositionAtSet( :( TF Msg = 4
BENGINE: [12/28/09 14:56:42] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:56:42] [3656] ret_val = 0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:56:42] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:42] [3656] Entering TF_MountNewMedia ... (baseChannel = 011FFCF8)
PVLSVR: [12/28/09 14:56:42] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:56:42] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_SPECIFIC )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:56:42] [5452] AdammSession::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {4D255A8F-B8EE-4647-8A11-6A6023BD3AF9}, "B2D000004"
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 4294967295
Side = 4294967295
PVLSVR: [12/28/09 14:56:42] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0003
Side = 0001
PVLSVR: [12/28/09 14:56:42] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1" (Backup To Disk)
PVLSVR: [12/28/09 14:56:42] [5452] AdammSession::CheckTargetDeviceStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Job = {54AEC5BC-E788-4EBF-91EB-0BBAC5BF86E5}, "SQL"
TargetDevice = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
State = 0003, ReservationID = 0000, AvailableMovers = 0001
PVLSVR: [12/28/09 14:56:42] [5452] AdammSession::MountSpecificMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
Location = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
LocationIndex = 0009
Side = 0001
PVLSVR: [12/28/09 14:56:43] [5452] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:56:43] [5452] PvlDrive::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:56:43] [5452] PvlChanger::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:56:43] [5452] PvlDrive::GetMediaStatus()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Elapsed Seconds = 0000, Sense Code = 0x00000000
SUCCESS!
PVLSVR: [12/28/09 14:56:43] [5452] PvlDrive::MountMedia() - Establish connection
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
PVLSVR: [12/28/09 14:56:43] [5452] AdammSession::MountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
Side = 0001
SUCCESS!
BENGINE: [12/28/09 14:56:43] [3656] TF_MountNewMedia: PVL_SPECIFIC_MOUNT. result = 00000000
BENGINE: [12/28/09 14:56:43] [3656] TF_MountNewMedia exiting and succeeded!
BENGINE: [12/28/09 14:56:43] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:43] [3656] TAPEALERT: TapeAlert Device Flag = 0X0
BENGINE: [12/28/09 14:56:43] [3656] TAPEALERT: TapeAlert Changer Flag = 0X0
BENGINE: [12/28/09 14:56:43] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
BENGINE: [12/28/09 14:56:43] [3656] TAPEALERT: Get TapeAlert Flags Return Code = 0X0
PVLSVR: [12/28/09 14:56:43] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityName = ""
EntityFlags = 0x00000000
BENGINE: [12/28/09 14:56:43] [3656] Updating session {da0edee0-4ca9-4a9b-aa60-2154d0484556} with drive FSE1 {aef0de93-08d2-43f4-b73d-0d354f472b40}.1
BENGINE: [12/28/09 14:56:43] [3656] Media Label: B2D000010
BENGINE: [12/28/09 14:56:43] [3656] Media Label: {88f398fd-c22d-4eae-a58e-58e730eab1a0}
BESERVER: [12/28/09 14:56:43] [2464] 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA
PVLSVR: [12/28/09 14:56:43] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}
EntityName = ""
EntityFlags = 0x00000000
PVLSVR: [12/28/09 14:56:43] [5452] PvlEntityDatabase::QueryEntity() : SUCCESS!
BESERVER: [12/28/09 14:56:43] [2464] 12 AdammAdminBO::Get() - hr = 0x0
BENGINE: [12/28/09 14:56:43] [3656] Overwrite Protected Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:56:43] [3656] Appendable Until: 12/30/99 6:00:00 PM
BENGINE: [12/28/09 14:56:43] [3656] UI Msg = 8008
BENGINE: [12/28/09 14:56:43] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:56:43] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:56:43] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:56:43] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:56:43] [3656] RewindDrive mover ret = 0 (0x0)
BENGINE: [12/28/09 14:56:43] [3656] ret_val = 0
BENGINE: [12/28/09 14:56:43] [3656] Attempting to VCB storage media
BENGINE: [12/28/09 14:56:43] [3656] TpRead( :(
BENGINE: [12/28/09 14:56:43] [3656] Error = 1101 Req = 32768 Got = 1024
BENGINE: [12/28/09 14:56:43] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:56:43] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:56:43] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:56:43] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:56:43] [3656] Current media tape header was written by "Symantec Backup Exec 12.5.2213".
BENGINE: [12/28/09 14:56:43] [3656] TpRead( :(
BENGINE: [12/28/09 14:56:43] [3656] Error = 1101 Req = 32768 Got = 2048
BENGINE: [12/28/09 14:56:43] [3656] ReadThisSet( cur_fmt=0 )
BENGINE: [12/28/09 14:56:43] [3656] Set 2 was written by version 12.5.
BENGINE: [12/28/09 14:56:43] [3656] ReadThisSet() return=0x00000000
BENGINE: [12/28/09 14:56:43] [3656] Current VCB: ID = 3b9c6a70 Seq = 2 Set = 2
BENGINE: [12/28/09 14:56:43] [3656] PositionAtSet( :( TF Msg = 8
BENGINE: [12/28/09 14:56:43] [3656] UI Msg = 8002
BENGINE: [12/28/09 14:56:43] [3656] HARDWARE COMPRESSION ===> Compression is NOT configurable.
BENGINE: [12/28/09 14:56:43] [3656] HARDWARE ENCRYPTION ===> Encryption is NOT supported.
BENGINE: [12/28/09 14:56:43] [3656] GET_DRV_INF: bsize = 1024
BENGINE: [12/28/09 14:56:43] [3656] GET_DRV_INF: preferredBlockSize = 1024
BENGINE: [12/28/09 14:56:43] [3656] TF xfer time = 0 seconds.
BENGINE: [12/28/09 14:56:43] [3656] TF_CloseSet
PVLSVR: [12/28/09 14:56:43] [5544] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
PVLSVR: [12/28/09 14:56:43] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
BENGINE: [12/28/09 14:56:43] [3656] TF_PutChannel( ) 011FFCF8
PVLSVR: [12/28/09 14:56:43] [5544] PvlEntityDatabase::UpdateEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, ""
EntityFlags = 0x008C0000
PVLSVR: [12/28/09 14:56:43] [5544] PvlEntityDatabase::UpdateEntity() : SUCCESS!
BENGINE: [12/28/09 14:56:43] [3656] TF_FreeDriveContext( 2FCBF30 )
BENGINE: [12/28/09 14:56:43] [3656] TF_FreeTapeBuffers: from 10 to 0 buffers
BENGINE: [12/28/09 14:56:43] [3656] FreeFormatEnv( cur_fmt=0 )
PVLSVR: [12/28/09 14:56:43] [5452] AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_DISMOUNT )
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Flags = 0x00000030
PVLSVR: [12/28/09 14:56:43] [5452] AdammSession::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0000
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = {88F398FD-C22D-4EAE-A58E-58E730EAB1A0}, "B2D000010"
Side = 0001
PVLSVR: [12/28/09 14:56:43] [5452] PvlDrive::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 4294967295
Side = 4294967295
PVLSVR: [12/28/09 14:56:43] [5452] PvlDrive::DismountMedia() - Release connection
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
PVLSVR: [12/28/09 14:56:43] [5452] PvlChanger::DismountMedia()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Library = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Drive = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Slot = 0009
Side = 0001
PVLSVR: [12/28/09 14:56:43] [5452] PvlDriveB2D_Removable::DismountFileSystemVolume() = SUCCESS!
PVLSVR: [12/28/09 14:56:43] [5452] PvlDevice::SendAlert()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
Device = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}, "FSE1"
Media = ""
PVLSVR: [12/28/09 14:56:44] [0000] [5452] 12/28/09 14:56:43 "Cluster" key does not appear to be present in the registry
PVLSVR: [12/28/09 14:56:44] [0000] [5452] 12/28/09 14:56:43 Failed to open Microsoft cluster ()
PVLSVR: [12/28/09 14:56:44] [0000] [5452] 12/28/09 14:56:43 VCS cluster keys do not appear to be present in the registry
PVLSVR: [12/28/09 14:56:44] [0000] [5452] 12/28/09 14:56:43 Failed to open VCS cluster ()
BESERVER: [12/28/09 14:56:43] [2676] 18 Alert Media Remove, Notify: No, EventLog: Yes, Trap: No
BESERVER: [12/28/09 14:56:44] [4132] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:56:44] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:56:44] [4132] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:56:44] [4132] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:56:44] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:56:44] [4132] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:57:16] [5860] 20 AutoClearAlerts: end NumClearAlertFrRS=1 NumClearAlert=1
BENGINE: [12/28/09 14:57:17] [3876] DeviceManager: dismount event fired
BENGINE: [12/28/09 14:57:17] [3876] DeviceManager: processing pending requests
BENGINE: [12/28/09 14:57:17] [3876] DeviceManager: going to sleep for 900000 msecs
PVLSVR: [12/28/09 14:57:17] [5544] AdammSession::Release()
Session = {DA0EDEE0-4CA9-4A9B-AA60-2154D0484556}
BENGINE: [12/28/09 14:57:17] [3656] Removing 'SQL' from status update list
BENGINE: [12/28/09 14:57:17] [3656] Updating status for: 'SQL' (0x18 0x0)
BENGINE: [12/28/09 14:57:17] [3656] Status for: 'SQL' updated
BENGINE: [12/28/09 14:57:17] [3656] BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary
BENGINE: [12/28/09 14:57:17] [3656] Ending job 'SQL' with succuess status
BESERVER: [12/28/09 14:57:17] [2676] 17 JobManager::HandleEndJob() called
BESERVER: [12/28/09 14:57:17] [4060] -1 ActiveState::doEndEvent( :( 'SQL'
BENGINE: [12/28/09 14:57:17] [3656] Job thread terminating
BESERVER: [12/28/09 14:57:17] [4060] 17 JobManager::DoJobCompletionTasks(SQL): GetFinalJobStatus()=19
BESERVER: [12/28/09 14:57:17] [5300] 01 Server Configuration: Client removed: 10
BESERVER: [12/28/09 14:57:17] [5300] -1 Client 'FSE1' Disconnected:0x5ef8b60
BESERVER: [12/28/09 14:57:17] [5300] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = DRIVE
PVLSVR: [12/28/09 14:57:17] [5452] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = DRIVE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:57:17] [5300] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:57:17] [5300] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = MACHINE , Entity = BACKUP_TO_DISK
PVLSVR: [12/28/09 14:57:17] [5544] PvlEntityDatabase::QueryEntity()
ParentEntityType = MACHINE_ENTITY_TYPE
ParentEntityGuid = {38EB665F-31A3-490D-9496-C7AB2BE8F07B}
EntityType = BACKUP_TO_DISK_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
BESERVER: [12/28/09 14:57:17] [5300] 12 AdammAdminBO::Execute() - hr = 0x0
BESERVER: [12/28/09 14:57:17] [5300] 12 AdammAdminBO::Execute() - ADAMM_ADMIN_RECORD_COUNT - Parent = BACKUP_TO_DISK , Entity = MEDIA
PVLSVR: [12/28/09 14:57:17] [3312] PvlEntityDatabase::QueryEntity()
ParentEntityType = BACKUP_TO_DISK_ENTITY_TYPE
ParentEntityGuid = {AEF0DE93-08D2-43F4-B73D-0D354F472B40}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00008400
PVLSVR: [12/28/09 14:57:17] [3312] PvlEntityDatabase::QueryEntity() : SUCCESS!
BESERVER: [12/28/09 14:57:17] [5300] 12 AdammAdminBO::Execute() - hr = 0x0
BKUPEXEC: [12/28/09 14:57:17] [0000] UIVirtualObjectView(VirtualMediaView) Queried Count: 0 items.
BESERVER: [12/28/09 14:57:17] [4060] 18 Alert Job Completed with Exceptions, Notify: No, EventLog: Yes, Trap: No
BKUPEXEC: [12/28/09 14:57:17] [0000] UIVirtualObjectView(JobHistoryView) Queried 2 items: 0 to 1
BESERVER: [12/28/09 14:57:17] [4060] -1 ActiveState::doEndEvent( :( CJobManager::DoJobCompletionTasks() returned 0x0
BESERVER: [12/28/09 14:57:19] [1080] 17 JobManager: Sending History Update VSN, count:1.
BKUPEXEC: [12/28/09 14:57:19] [0000] UIVirtualObjectView(JobHistoryView) Queried 2 items: 0 to 1
BESERVER: [12/28/09 14:57:19] [1080] 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR