ContributionsMost RecentMost LikesSolutionsRe: tem?State V-79-57344-33928 - Access is denied Backup problem !Thanks for your support, the account is a domain admin account, permissions on the accounts have not changed. My guess is maybe the active directory or system state is corrupt, but how do repair this and or diagnose the problem ? Here is the log file from Sgmon, tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:03:40 -1 Job Manager: LoadJob() request completed beserver: 12/02/06 10:03:40 17 JobManager::UpdateJobInstanceData() new task state=22 tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:03:40 12 AdammAdminBO::Query() - Parent = NULL , Entity = MACHINE pvlsvr: 12/02/06 10:03:41 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MACHINE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00008000 tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - hr = 0x0 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - Parent = NULL , Entity = DEVICE_POOL pvlsvr: 12/02/06 10:03:42 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DEVICE_POOL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00008000 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - hr = 0x0 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE pvlsvr: 12/02/06 10:03:42 PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00008000 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - hr = 0x0 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = BACKUP_TO_DISK pvlsvr: 12/02/06 10:03:42 PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00008000 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - hr = 0x0 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - Parent = NULL , Entity = DRIVE_POOL_DRIVE pvlsvr: 12/02/06 10:03:42 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = DRIVE_POOL_DRIVE_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00008000 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - hr = 0x0 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = CHANGER pvlsvr: 12/02/06 10:03:42 PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = LIBRARY_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00008000 beserver: 12/02/06 10:03:42 12 AdammAdminBO::Query() - hr = 0x0 beserver: 12/02/06 10:03:42 -1 ActiveState::doDispatch( ): 'Backup 00009' beserver: 12/02/06 10:03:42 -1 LocalSvr, runTask():{402fb1cd-1249-4717-918c-258d34c5f12b} beserver: 12/02/06 10:03:42 -1 BengineWrapper runtask() start tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:03:42 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR beserver: 12/02/06 10:03:42 -1 BengineWrapper JobEngine.Open() OK beserver: 12/02/06 10:03:42 -1 Client requested key. beserver: 12/02/06 10:03:43 -1 Client 'RA-FILESERVER' connected('','RAPRINTING\Administrator'): 0x1c686e0 bengine: 12/02/06 10:03:43 JobEngine::LaunchJob bengine: 12/02/06 10:03:43 Thread for job Backup 00009 ({402FB1CD-1249-4717-918C-258D34C5F12B}) starting bengine: 12/02/06 10:03:43 RunJobWithGuidAndServer( {402FB1CD-1249-4717-918C-258D34C5F12B}, RA-FILESERVER ) = 0 beserver: 12/02/06 10:03:43 -1 BengineWrapper JobEngine.RunJobWithGuidAndServer() rc=0 beserver: 12/02/06 10:03:43 -1 JobManager: BengineWrapper RunJob() done beserver: 12/02/06 10:03:43 11 LocalSvr, RunTask: EngineHandedJob SUCCESS, jobid:{402fb1cd-1249-4717-918c-258d34c5f12b}. beserver: 12/02/06 10:03:43 -1 Client requested key. beserver: 12/02/06 10:03:43 -1 Client 'RA-FILESERVER' connected('','RAPRINTING\Administrator'): 0x1cd8da0 beserver: 12/02/06 10:03:43 -1 Client 'RA-FILESERVER' Disconnected:0x1cd8da0 bengine: 12/02/06 10:03:43 Checking for Evaluation expiration. bengine: 12/02/06 10:03:43 ENGSCRPT: ConvertDeviceName osId = 0X23 bengine: 12/02/06 10:03:43 ENGSCRPT: IN - oldDeviceName = \\RA-FILESERVER\System?State bengine: 12/02/06 10:03:43 ENGSCRPT: OUT - newName = \\RA-FILESERVER\System?State bengine: 12/02/06 10:03:43 ENGSCRPT: No script found bengine: 12/02/06 10:03:43 DeviceManager: incoming event fired bengine: 12/02/06 10:03:43 DeviceManager: processing pending requests bengine: 12/02/06 10:03:43 DeviceManager: query cache miss pvlsvr: 12/02/06 10:03:43 PvlEntityDatabase::QueryEntity() ParentEntityType = DRIVE_POOL_ENTITY_TYPE ParentEntityGuid = {022C2AF6-E3EB-4F20-A56D-5791252D3249} EntityType = NULL_ENTITY_TYPE EntityGuid = {00000000-0000-0000-0000-000000000000} EntityName = "" EntityFlags = 0x00000000 tmasea : LockedFilePollThread - LockList has 0 entries pvlsvr: 12/02/06 10:03:43 PvlEntityDatabase::QueryEntity() ParentEntityType = MACHINE_ENTITY_TYPE ParentEntityGuid = {662C6868-8AA0-4E73-A1CB-D4654FCF62BF} EntityType = BACKUP_TO_DISK_ENTITY_TYPE EntityGuid = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7} EntityName = "" EntityFlags = 0x00000000 bengine: 12/02/06 10:03:43 Available and held drives from the pool bengine: 12/02/06 10:03:43 RA-Backup-to-Disk Folder 1 {226cf1c6-dd54-48da-a6da-b8b251ceebe7}.0 bengine: 12/02/06 10:03:43 Drives not in use by the Engine bengine: 12/02/06 10:03:43 RA-Backup-to-Disk Folder 1 {226cf1c6-dd54-48da-a6da-b8b251ceebe7}.0 bengine: 12/02/06 10:03:43 Drives available (not in use and not held) bengine: 12/02/06 10:03:43 RA-Backup-to-Disk Folder 1 {226cf1c6-dd54-48da-a6da-b8b251ceebe7}.0 pvlsvr: 12/02/06 10:03:43 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} TargetDevice = {022C2AF6-E3EB-4F20-A56D-5791252D3249}, "All Devices (RA-FILESERVER)" (Device Pool) TargetDevice = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" (Backup To Disk) pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Media Set 1" (Media Set) pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} bengine: 12/02/06 10:03:44 Handing out session {67338314-f3a4-417a-b1e3-c5887aa31f95} based on available drive: bengine: 12/02/06 10:03:44 RA-Backup-to-Disk Folder 1 {226cf1c6-dd54-48da-a6da-b8b251ceebe7}.0 bengine: 12/02/06 10:03:44 DeviceManager: going to sleep for 900000 msecs pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_JOB_INFO ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Job = {402FB1CD-1249-4717-918C-258D34C5F12B}, "Backup 00009" bengine: 12/02/06 10:03:44 BackupJob: acquired device RA-Backup-to-Disk Folder 1 bengine: 12/02/06 10:03:44 BackupJob: trying to mount appending volume pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_MOUNT_APPEND ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_INFO ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_DEVICE_GUID ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} TargetDevice = {022C2AF6-E3EB-4F20-A56D-5791252D3249}, "All Devices (RA-FILESERVER)" (Device Pool) TargetDevice = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" (Backup To Disk) pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_TARGET_MEDIA_GUID ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} TargetMedia = {00000009-03E8-0000-0000-000000000000}, "Media Set 1" (Media Set) pvlsvr: 12/02/06 10:03:44 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_SET_EXCLUDE_MEDIA_GUIDS ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} pvlsvr: 12/02/06 10:03:44 AdammSession::CheckTargetDeviceStatus() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Job = {402FB1CD-1249-4717-918C-258D34C5F12B}, "Backup 00009" TargetDevice = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" State = 0003, ReservationID = 0000, AvailableMovers = 0001 pvlsvr: 12/02/06 10:03:44 AdammSession::MountAppendMedia() pvlsvr: 12/02/06 10:03:44 AdammSession::MountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7} Slot = 0025 Side = 0001 pvlsvr: 12/02/06 10:03:44 PvlDrive::MountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Slot = 0025 Side = 0001 pvlsvr: 12/02/06 10:03:44 PvlChanger::MountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Library = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Slot = 0025 Side = 0001 tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries pvlsvr: 12/02/06 10:03:45 AdammSession::MountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Library = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Slot = 0000 Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Media = {CF386100-4B4A-43EB-A5A8-AF1A171ABC26}, "B2D000024" Side = 0001 SUCCESS! pvlsvr: 12/02/06 10:03:45 AdammSession::IsAppendMediaMounted() - SUCCESS! bengine: 12/02/06 10:03:45 Updating session {67338314-f3a4-417a-b1e3-c5887aa31f95} with drive RA-Backup-to-Disk Folder 1 {226cf1c6-dd54-48da-a6da-b8b251ceebe7}.1 bengine: 12/02/06 10:03:45 Block size: 1024 bengine: 12/02/06 10:03:45 Buffer size: 32768 bengine: 12/02/06 10:03:45 Num buffers: 10 bengine: 12/02/06 10:03:45 High water: 7 bengine: 12/02/06 10:03:45 BackupJob: medium successfully mounted bengine: 12/02/06 10:03:45 Media Label: B2D000024 bengine: 12/02/06 10:03:45 Media Label: {cf386100-4b4a-43eb-a5a8-af1a171abc26} bengine: 12/02/06 10:03:46 Overwrite Protected Until: 12/31/9999 11:00:00 AM bengine: 12/02/06 10:03:46 Appendable Until: 12/31/9999 11:00:00 AM bengine: 12/02/06 10:03:46 TAPEALERT: Get TapeAlert Flags Return Code = 0X0 bengine: 12/02/06 10:03:46 TAPEALERT: TapeAlert Device Flag = 0X0 bengine: 12/02/06 10:03:46 TAPEALERT: TapeAlert Changer Flag = 0X0 bengine: 12/02/06 10:03:46 TAPEALERT: Get TapeAlert Flags Return Code = 0X0 bengine: 12/02/06 10:03:46 TAPEALERT: Get TapeAlert Flags Return Code = 0X0 bengine: 12/02/06 10:03:46 ndmpConnect : Control Connection information : connection established between IP 127.0.0.1, port 53280 and IP 127.0.0.1, port 10000 beremote: 12/02/06 10:03:46 ndmpRun: Control Connection Accepted : connection established between IP 127.0.0.1, port 10000 and IP 127.0.0.1, port 53280 bengine: 12/02/06 10:03:46 NDMP version 3 connection CONNECTED beremote: 12/02/06 10:03:46 Successfully impersonated RAPRINTING\administrator beremote: 12/02/06 10:03:46 WhoAmI( ) reports: RAPRINTING\Administrator bengine: 12/02/06 10:03:46 Enabling Shared Buffers Connection type bengine: 12/02/06 10:03:46 Informational: Entering CIdr_ndmpWrapper::ndmpCreateDRFile tmasea : LockedFilePollThread - LockList has 0 entries beremote: 12/02/06 10:03:46 FillInDriveList(): GetVolumeInformation(C:\) returned 1. beremote: 12/02/06 10:03:46 File system name: "NTFS" beremote: 12/02/06 10:03:47 CLDMVolumeInfo():: Informational: Gathering LDM volume info beremote: 12/02/06 10:03:47 Informational: LDM volume info initialized beremote: 12/02/06 10:03:47 ~CLDMVolumeInfo beremote: 12/02/06 10:03:47 FillInDriveList(): GetVolumeInformation(E:\) returned 1. beremote: 12/02/06 10:03:47 File system name: "NTFS" beremote: 12/02/06 10:03:47 CLDMVolumeInfo():: Informational: Gathering LDM volume info beremote: 12/02/06 10:03:47 Informational: LDM volume info initialized beremote: 12/02/06 10:03:47 ~CLDMVolumeInfo beremote: 12/02/06 10:03:47 CDRFile::BuildAdvtMediaServerList(). Error 2 in querying for value Agent Directory List. beremote: 12/02/06 10:03:47 Informational: Entering DRAddASRStreams() beremote: 12/02/06 10:03:47 Informational: Exiting DRAddASRStreams(). Return Code: 0 beremote: 12/02/06 10:03:47 Informational: Entering IDR_GatherSFWStream() beremote: 12/02/06 10:03:47 Informational: Not a Windows 2003 system. So not gathering SFW stream. Leaving IDR_GatherSFWStream() bengine: 12/02/06 10:03:47 Informational: DR File Name: RA-FILESERVER.DR bengine: 12/02/06 10:03:47 Informational: Exiting ndmpCreateDRFile. bengine: 12/02/06 10:03:47 BESC: Parsing OS version info - bengine: 12/02/06 10:03:47 ndmpcSnapshotPrepare2: Warning. No devices to snap. Returning with NDMP_SNAPSHOT_NO_DEVICES2SNAP bengine: 12/02/06 10:03:47 TF_OpenTape() bengine: 12/02/06 10:03:47 TF_GetDriveContext ... sessionGuid = {67338314-f3a4-417a-b1e3-c5887aa31f95} initAsync = 1 bengine: 12/02/06 10:03:47 HARDWARE COMPRESSION ===> Compression is NOT configurable. bengine: 12/02/06 10:03:47 GET_DRV_INF: bsize = 1024 bengine: 12/02/06 10:03:47 TF_GetDriveContext returning 0, context = 1B7FFE0 bengine: 12/02/06 10:03:47 Allocated 2 buffers, size 32768 bytes, total used: 65720 bengine: 12/02/06 10:03:47 HARDWARE COMPRESSION ===> Compression is NOT configurable. bengine: 12/02/06 10:03:47 GET_DRV_INF: bsize = 1024 bengine: 12/02/06 10:03:47 Media Server to initiate connection for data transfer bengine: 12/02/06 10:03:47 TF_OpenSet( ) bengine: 12/02/06 10:03:47 FreeFormatEnv( cur_fmt=65535 ) bengine: 12/02/06 10:03:47 RewindDrive mover ret = 0 (0x0) bengine: 12/02/06 10:03:47 ret_val = 0 bengine: 12/02/06 10:03:47 Requested Set: ID = ffffffff Seq = -1 Set = -1 bengine: 12/02/06 10:03:47 Attempting to VCB storage media bengine: 12/02/06 10:03:47 TpRead( ): bengine: 12/02/06 10:03:47 Error = 1101 Req = 6144 Got = 1024 bengine: 12/02/06 10:03:47 HARDWARE COMPRESSION ===> Compression is NOT configurable. bengine: 12/02/06 10:03:47 GET_DRV_INF: bsize = 1024 bengine: 12/02/06 10:03:47 FreeFormatEnv( cur_fmt=65535 ) bengine: 12/02/06 10:03:47 SetupFormatEnv( fmt=0 ) bengine: 12/02/06 10:03:47 TpRead( ): bengine: 12/02/06 10:03:47 Error = 0 Req = 6144 Got = 6144 bengine: 12/02/06 10:03:47 ReadThisSet( cur_fmt=0 ) bengine: 12/02/06 10:03:47 ReadThisSet() return=0 bengine: 12/02/06 10:03:47 Current VCB: ID = 356c8215 Seq = 27 Set = 13 bengine: 12/02/06 10:03:47 PositionAtSet( ): TF Msg = b bengine: 12/02/06 10:03:47 UI Msg = 800f tmasea : LockedFilePollThread - LockList has 0 entries bengine: 12/02/06 10:03:47 HARDWARE COMPRESSION ===> Compression is NOT configurable. bengine: 12/02/06 10:03:47 GET_DRV_INF: bsize = 1024 beserver: 12/02/06 10:03:47 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR bengine: 12/02/06 10:03:48 F40_SeekEOD starting seek... bengine: 12/02/06 10:03:48 F40_SeekEOD calling OTC_GetPrevSM with expect_sm = 1 ... bengine: 12/02/06 10:03:48 OTC_GetPrevSM spacing back two filemarks... bengine: 12/02/06 10:03:48 OTC_GetPrevSM: ESET position = 912751 bengine: 12/02/06 10:03:48 OTC_GetPrevSM calling OTC_SMtoFile... bengine: 12/02/06 10:03:48 SetupFormatEnv( fmt=0 ) bengine: 12/02/06 10:03:48 End of TF_OpenSet: Ret_val = 0 Buffs = 2 HiWater = 7 bengine: 12/02/06 10:03:48 B2D Buffered Writes ===> Setting buffering to default. bengine: 12/02/06 10:03:48 Current Block is = ded8f beremote: 12/02/06 10:03:48 ENV: USERNAME= beremote: 12/02/06 10:03:48 ENV: NOPASSWORD= beremote: 12/02/06 10:03:48 ENV: USERNAME=RAPRINTING\administrator beremote: 12/02/06 10:03:48 ENV: PASSWORD= beremote: 12/02/06 10:03:48 ENV: INITIATOR_SID=0@D0000000DE0000JmIR1:Ta@d0E9Jm7m04000 beremote: 12/02/06 10:03:48 ENV: PRESCANDLE=0 beremote: 12/02/06 10:03:48 ENV: OPER_TYPE=1 beremote: 12/02/06 10:03:48 ENV: BLOCKSIZE=400 beremote: 12/02/06 10:03:48 ENV: SSETRESERVED=400 beremote: 12/02/06 10:03:48 ENV: BU_SQL_PRE_BKUP_DBCC_TYPE=0 beremote: 12/02/06 10:03:48 ENV: BU_SQL_POST_BKUP_DBCC_TYPE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_POST_REST_DBCC_TYPE=0 beremote: 12/02/06 10:03:48 ENV: LAST_SET=0 beremote: 12/02/06 10:03:48 ENV: BU_REGISTRY=0 beremote: 12/02/06 10:03:48 ENV: BU_INUSE=1 beremote: 12/02/06 10:03:48 ENV: BU_MIGRATED=0 beremote: 12/02/06 10:03:48 ENV: BU_EXPANDED=0 beremote: 12/02/06 10:03:48 ENV: PROCESS_EMPTY=0 beremote: 12/02/06 10:03:48 ENV: SWCOMPMODE=0 beremote: 12/02/06 10:03:48 ENV: SWCOMPLEVEL=0 beremote: 12/02/06 10:03:48 ENV: SWCOMPTHRESH=10 beremote: 12/02/06 10:03:48 ENV: BACKUPTYPE=1 beremote: 12/02/06 10:03:48 ENV: BU_SPS_METHOD=1 beremote: 12/02/06 10:03:48 ENV: BU_SQL_METHOD=0 beremote: 12/02/06 10:03:48 ENV: BU_SQL_NO_RECOVER=0 beremote: 12/02/06 10:03:48 ENV: BU_SQL_STANDBY=0 beremote: 12/02/06 10:03:48 ENV: BU_SQL_ALWAYS_SNAP=0 beremote: 12/02/06 10:03:48 ENV: BU_XCH_METHOD=1 beremote: 12/02/06 10:03:48 ENV: BU_MBOX_METHOD=1 beremote: 12/02/06 10:03:48 ENV: BU_MBOX_SIS=0 beremote: 12/02/06 10:03:48 ENV: BU_NTFS_SIS=0 beremote: 12/02/06 10:03:48 ENV: AA_PRIORITY=0 beremote: 12/02/06 10:03:48 ENV: BU_RSM=0 beremote: 12/02/06 10:03:48 ENV: BU_EXCLUDE_ACTIVE_FILES=1 beremote: 12/02/06 10:03:48 ENV: TRAVERSE_LOCAL_MP=1 beremote: 12/02/06 10:03:48 ENV: TRAVERSE_REMOTE_MP=0 beremote: 12/02/06 10:03:48 ENV: DO_SNAPSHOT=0 beremote: 12/02/06 10:03:48 ENV: SQL_FORCE_NP=0 beremote: 12/02/06 10:03:48 ENV: SQL_OVERRIDE_FGDEPS=0 beremote: 12/02/06 10:03:48 ENV: SQL_ALERT_FGDEP_OVERRIDE=1 beremote: 12/02/06 10:03:48 ENV: BU_SFC_WITH_SS=1 beremote: 12/02/06 10:03:48 ENV: BU_REMOTE_STORAGE_HSM=0 beremote: 12/02/06 10:03:48 ENV: SKIP_OPEN_FILES=0 beremote: 12/02/06 10:03:48 ENV: WAIT_SKIP=0 beremote: 12/02/06 10:03:48 ENV: HIDDEN=1 beremote: 12/02/06 10:03:48 ENV: PROC_SYSTEM_FILES=1 beremote: 12/02/06 10:03:48 ENV: BU_LDM_STATE=0 beremote: 12/02/06 10:03:48 ENV: BU_DISK_QUOTA=0 beremote: 12/02/06 10:03:48 ENV: BU_IN_DOC_FMT=0 beremote: 12/02/06 10:03:48 ENV: REST_REGISTRY=0 beremote: 12/02/06 10:03:48 ENV: REST_HW_REGISTRY=0 beremote: 12/02/06 10:03:48 ENV: IGNORE_TREE_RESTORE=0 beremote: 12/02/06 10:03:48 ENV: REST_SECURITY=1 beremote: 12/02/06 10:03:48 ENV: VOL_RESTRICTIONS=0 beremote: 12/02/06 10:03:48 ENV: REST_XCH_PUBLIC=1 beremote: 12/02/06 10:03:48 ENV: REST_MBOX_USER_FLAG=0 beremote: 12/02/06 10:03:48 ENV: REST_MBOX_USER_PASSWORD= beremote: 12/02/06 10:03:48 ENV: MEDIA_SERVER=RA-FILESERVER beremote: 12/02/06 10:03:48 ENV: SPS_SERVER_LIST= beremote: 12/02/06 10:03:48 ENV: SPS_COMMIT_RESTORE=0 beremote: 12/02/06 10:03:48 ENV: BU_SPS_PRE_BKUP_DBCC_TYPE=0 beremote: 12/02/06 10:03:48 ENV: BU_SPS_POST_BKUP_DBCC_TYPE=0 beremote: 12/02/06 10:03:48 ENV: REST_SPS_POST_REST_DBCC_TYPE=2 beremote: 12/02/06 10:03:48 ENV: CONTINUE_ON_SPS_DBCC_ERROR=0 beremote: 12/02/06 10:03:48 ENV: JOBNAME=Backup 00009 beremote: 12/02/06 10:03:48 ENV: REST_XCH_PRIVATE=1 beremote: 12/02/06 10:03:48 ENV: REST_REMOTE_HSM=0 beremote: 12/02/06 10:03:48 ENV: REST_RSM=0 beremote: 12/02/06 10:03:48 ENV: REST_DISK_QUOTA=0 beremote: 12/02/06 10:03:48 ENV: REST_JUNC_AS_DIR=0 beremote: 12/02/06 10:03:48 ENV: REST_PRIM_REPLICA=0 beremote: 12/02/06 10:03:48 ENV: REST_TERMINAL_SERVICES=0 beremote: 12/02/06 10:03:48 ENV: REST_WMI=0 beremote: 12/02/06 10:03:48 ENV: REST_CLUSTER_QUORUM_DB=0 beremote: 12/02/06 10:03:48 ENV: REST_FORCE_RECOVERY=0 beremote: 12/02/06 10:03:48 ENV: REST_EXISTING=3 beremote: 12/02/06 10:03:48 ENV: REST_XCH_NO_LOSS=1 beremote: 12/02/06 10:03:48 ENV: REST_ESE_MOUNT=0 beremote: 12/02/06 10:03:48 ENV: REST_ESE_DISMOUNT=0 beremote: 12/02/06 10:03:48 ENV: REST_ESE_LOG_PATH=C:\TEMP\ beremote: 12/02/06 10:03:48 ENV: REST_ESE_LAST_SET=0 beremote: 12/02/06 10:03:48 ENV: REST_ESE_NO_LOSS=1 beremote: 12/02/06 10:03:48 ENV: REST_ESE_CONSISTENCY_CHECK=1 beremote: 12/02/06 10:03:48 ENV: REST_ESE_CONTINUE_CONSISTENCY_CHECK=1 beremote: 12/02/06 10:03:48 ENV: REST_SQL_DATABASE_OVERWRITE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_POINT_IN_TIME=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_POINT_IN_TIME_DATE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_POINT_IN_TIME_TIME=0 beremote: 12/02/06 10:03:48 ENV: BU_SQL_PRE_BKUP_DBCC=0 beremote: 12/02/06 10:03:48 ENV: BU_SQL_POST_BKUP_DBCC=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_POST_REST_DBCC=2 beremote: 12/02/06 10:03:48 ENV: CONTINUE_ON_DBCC_ERROR=1 beremote: 12/02/06 10:03:48 ENV: REST_SQL_AUTO_MASTER=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_DATABASE_STANDBY=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_DATABASE_FORCERESTORE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_DATABASE_RECOVER=1 beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_RESTORE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_NAME= beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_STOP_BEFORE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_AFTER_TIME=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_DATE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_TIME=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_DEF_INSTANCE_DIR=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_USE_DEFAULT_DRIVE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_ALL_TO_DEFAULT_DRIVE=0 beremote: 12/02/06 10:03:48 ENV: REST_SQL_DEFAULT_DRIVE=0 beremote: 12/02/06 10:03:48 ENV: ARCHIVE_PRESERVE_TREE=0 beremote: 12/02/06 10:03:48 ENV: UTILITY_PARTITION_OEMIDS= beremote: 12/02/06 10:03:48 ENV: APPLY_CPR_TO_BACKUPSET=0 beremote: 12/02/06 10:03:48 ENV: IMAGE_METHOD=0 beremote: 12/02/06 10:03:48 ENV: GUARANTEED_FROZEN_IMAGE=0 beremote: 12/02/06 10:03:48 ENV: FULL_IMAGE_METHOD=0 beremote: 12/02/06 10:03:48 ENV: TESTRUN_JOB_OPTIONS=0 beremote: 12/02/06 10:03:48 ENV: USE_CHANGE_JOURNAL=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_BACKUP_METHOD=1 beremote: 12/02/06 10:03:48 ENV: DOMINO_BACKUP_RECYCLE_LOGFILE=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_LAST_BACKUP_DATE=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_LAST_BACKUP_TIME=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_MAX_TRANLOG_EXTENTS=46 beremote: 12/02/06 10:03:48 ENV: DOMINO_RESTORE_DBRECOVEROPTION=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_RESTORE_WAITINUSE=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_RESTORE_MSECSFORWAIT=1e beremote: 12/02/06 10:03:48 ENV: DOMINO_RESTORE_POINTINTIMERESTORE=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_RESTORE_PITTIME_DATE=0 beremote: 12/02/06 10:03:48 ENV: DOMINO_RESTORE_PITTIME_TIME=0 beremote: 12/02/06 10:03:48 ENV: SAP_JOB_FLAG=0 beremote: 12/02/06 10:03:48 ENV: IMAGE_DBLK_TYPE=0 beremote: 12/02/06 10:03:48 ENV: PROC_CHECKSUM_STRM=1 beremote: 12/02/06 10:03:48 ENV: DOMINO_OFFLINE_BACKUP=0 beremote: 12/02/06 10:03:48 ENV: ENABLE_IDR_INFO_GATHERING=1 beremote: 12/02/06 10:03:48 ENV: IMAGE_OPTION_AUTHORIZED=0 beremote: 12/02/06 10:03:48 ENV: GRFS_TIMEOUT=5a beremote: 12/02/06 10:03:48 ENV: DO_OFF_HOST_BACKUP=0 beremote: 12/02/06 10:03:48 ENV: OFF_HOST_BACKUP_OPER_TYPE=0 beremote: 12/02/06 10:03:48 ENV: OFF_HOST_METADATA_COLL_NAME= beremote: 12/02/06 10:03:48 ENV: PRESERVE_CTIME=0 beremote: 12/02/06 10:03:48 ENV: FOLLOW_SYMDIRS=0 beremote: 12/02/06 10:03:48 ENV: INCLUDE_REMOTE=0 beremote: 12/02/06 10:03:48 ENV: NO_NFS_LOCKING=0 beremote: 12/02/06 10:03:48 ENV: FOLLOW_MOUNTPOINTS=1 beremote: 12/02/06 10:03:48 ENV: TRUE_IMAGE_BACKUP=0 beremote: 12/02/06 10:03:48 ENV: NDMP_PROP_PORT_RANGE_ENABLED=0 beremote: 12/02/06 10:03:48 ENV: NDMP_PORT_RANGE= beremote: 12/02/06 10:03:48 ENV: REVERSE_CONNECTION=1 beremote: 12/02/06 10:03:48 ENV: SNAPSHOT_ID={00000000-0000-0000-0000-000000000000} beremote: 12/02/06 10:03:48 ENV: OFF_HOST_SNAPSHOT_ID={00000000-0000-0000-0000-000000000000} beremote: 12/02/06 10:03:48 ENV: DEVICENAME="\\RA-FILESERVER\System?State" beremote: 12/02/06 10:03:48 ENV: DEVICE_OS_ID=23 beremote: 12/02/06 10:03:48 ENV: DEVICE_OS_VER=0 beremote: 12/02/06 10:03:48 ENV: DEVICE_MAINTYPE=f beremote: 12/02/06 10:03:48 ENV: DEVICE_SUBTYPE=0 beremote: 12/02/06 10:03:48 ENV: FILESYSTEM="\\RA-FILESERVER\System?State\*.*",s,v0,t0,l0,n0,f0 beremote: 12/02/06 10:03:48 ENV: JOBNAME=Backup 00009 beremote: 12/02/06 10:03:48 ENV: REST_ESE_LOG_PATH=C:\TEMP\ beremote: 12/02/06 10:03:48 ENV: REST_SQL_LOG_MARK_NAME= beremote: 12/02/06 10:03:48 ModifiedOnly is 0 beremote: 12/02/06 10:03:48 SWCompMode is 0 beremote: 12/02/06 10:03:48 ENV: MEDIA_SERVER=RA-FILESERVER beremote: 12/02/06 10:03:48 ENV: SPS_SERVER_LIST= beremote: 12/02/06 10:03:48 ENV: SPS_COMMIT_RESTORE=0 beremote: 12/02/06 10:03:48 ENV: GRFS_TIMEOUT=5a beremote: 12/02/06 10:03:48 ENV: DO_OFF_HOST_BACKUP=0 beremote: 12/02/06 10:03:48 ENV: OFF_HOST_BACKUP_OPER_TYPE=0 beremote: 12/02/06 10:03:48 ENV: OFF_HOST_METADATA_COLL_NAME= beremote: 12/02/06 10:03:48 ENV: PRESERVE_CTIME=0 beremote: 12/02/06 10:03:48 ENV: FOLLOW_SYMDIRS=0 beremote: 12/02/06 10:03:48 ENV: INCLUDE_REMOTE=0 beremote: 12/02/06 10:03:48 ENV: NO_NFS_LOCKING=0 beremote: 12/02/06 10:03:48 ENV: FOLLOW_MOUNTPOINTS=1 beremote: 12/02/06 10:03:48 ENV: TRUE_IMAGE_BACKUP=0 tmasea : LockedFilePollThread - LockList has 0 entries beremote: 12/02/06 10:03:49 Parsed /os=RA-FILESERVER beremote: 12/02/06 10:03:49 Parsed /os=RA-FILESERVER beremote: 12/02/06 10:03:49 Error returned is 259 when enumerating FRS Sets beremote: 12/02/06 10:03:49 Active Directory is Online beremote: 12/02/06 10:03:49 Boot drive is C:, status is 0 beremote: 12/02/06 10:03:49 SQL2_FindDrives - the default SQL Service is not running on RA-FILESERVER. beremote: 12/02/06 10:03:49 SQL2_FindDrives - checking named instance BKUPEXECDLO. beremote: 12/02/06 10:03:49 SQL2_CreateDLE - creating RA-FILESERVER\BKUPEXECDLO. beremote: 12/02/06 10:03:49 AddESE_DLEs( ) beremote: 12/02/06 10:03:49 AddESE_DLEs for sever name RA-FILESERVER beremote: 12/02/06 10:03:49 HrESEBackupRestoreGetRegistered: RA-FILESERVER, NULL = 0x00000000 beremote: 12/02/06 10:03:49 AreYouSureThisIsAnExchangeServer: RA-FILESERVER beremote: 12/02/06 10:03:49 HrESEBackupRestoreNodes: RA-FILESERVER = 0x00000000 beremote: 12/02/06 10:03:49 Recursing beremote: 12/02/06 10:03:49 AreYouSureThisIsAnExchangeServer: RA-FILESERVER beremote: 12/02/06 10:03:49 RA-FILESERVER is an Exchange server beremote: 12/02/06 10:03:49 AreYouSureThisIsAnExchangeServer RA-FILESERVER: 1 beremote: 12/02/06 10:03:49 HrESEBackupFreeNodes: beremote: 12/02/06 10:03:49 AreYouSureThisIsAnExchangeServer RA-FILESERVER: 1 beremote: 12/02/06 10:03:49 HrESEBackupPrepare: Microsoft Information Store, RA-FILESERVER = 0x00000000 beremote: 12/02/06 10:03:49 HrESEBackupFreeInstance: beremote: 12/02/06 10:03:49 HrESEBackupEnd: Microsoft Information Store = 0x00000000 beremote: 12/02/06 10:03:49 AddESE_DLEs( ) beremote: 12/02/06 10:03:49 AddESE_DLEs for sever name RA-FILESERVER beremote: 12/02/06 10:03:49 AddESE_DLEs: annotation != NULL beremote: 12/02/06 10:03:49 HrESEBackupPrepare: Microsoft Information Store, RA-FILESERVER = 0x00000000 beremote: 12/02/06 10:03:49 ESE_CheckForRSG( ) beremote: 12/02/06 10:03:49 HrESEBackupFreeInstance: beremote: 12/02/06 10:03:49 HrESEBackupEnd: Microsoft Information Store = 0x00000000 beremote: 12/02/06 10:03:49 HrESEFreeRegistered: beremote: 12/02/06 10:03:49 No utility partitions found on any of the disks beremote: 12/02/06 10:03:49 Active Directory is Online beremote: 12/02/06 10:03:49 Active Directory GetDatabaseLocations size 206 beremote: 12/02/06 10:03:49 BFT_CHECKPOINT_DIR='\\RA-FILESERVER\C$\WINNT\NTDS' beremote: 12/02/06 10:03:49 BFT_LOG_DIR='\\RA-FILESERVER\C$\WINNT\NTDS' beremote: 12/02/06 10:03:49 BFT_NTFS_DATABASE='\\RA-FILESERVER\C$\WINNT\NTDS\ntds.dit' beremote: 12/02/06 10:03:49 Attach to \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:49 Detach from \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:49 Active Directory is Online beremote: 12/02/06 10:03:49 Active Directory GetDatabaseLocations size 206 beremote: 12/02/06 10:03:49 BFT_CHECKPOINT_DIR='\\RA-FILESERVER\C$\WINNT\NTDS' beremote: 12/02/06 10:03:49 BFT_LOG_DIR='\\RA-FILESERVER\C$\WINNT\NTDS' beremote: 12/02/06 10:03:49 BFT_NTFS_DATABASE='\\RA-FILESERVER\C$\WINNT\NTDS\ntds.dit' beremote: 12/02/06 10:03:49 Attach to \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:49 line = "\\RA-FILESERVER\System?State\*.*",s,v0,t0,l0,n0,f0 beremote: 12/02/06 10:03:49 dir = beremote: 12/02/06 10:03:49 file = *.* beremote: 12/02/06 10:03:49 oper = Include beremote: 12/02/06 10:03:49 subs = True beremote: 12/02/06 10:03:49 wild = True beremote: 12/02/06 10:03:49 attr_on_mask = 0 beremote: 12/02/06 10:03:49 attr_off_mask = 0 beremote: 12/02/06 10:03:49 AddFsExcludes: beremote: 12/02/06 10:03:49 BSD for \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:49 + \*.* /s beremote: 12/02/06 10:03:49 BSD for \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:49 + \*.* /s beremote: 12/02/06 10:03:49 Detach from \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:49 Job Start - Sat Dec 02 10:03:49 2006 bengine: 12/02/06 10:03:49 dataStartBackup: ndmpSendRequest returned: 0x0, 0 tmasea : LockedFilePollThread - LockList has 0 entries beremote: 12/02/06 10:03:49 Active Directory is Online beremote: 12/02/06 10:03:50 Active Directory GetDatabaseLocations size 206 beremote: 12/02/06 10:03:50 BFT_CHECKPOINT_DIR='\\RA-FILESERVER\C$\WINNT\NTDS' beremote: 12/02/06 10:03:50 BFT_LOG_DIR='\\RA-FILESERVER\C$\WINNT\NTDS' beremote: 12/02/06 10:03:50 BFT_NTFS_DATABASE='\\RA-FILESERVER\C$\WINNT\NTDS\ntds.dit' beremote: 12/02/06 10:03:50 Attach to \\RA-FILESERVER\System?State beremote: 12/02/06 10:03:50 TF_OpenTape() beremote: 12/02/06 10:03:50 TF_GetDriveContext ... beremote: 12/02/06 10:03:50 TF_GetDriveContext returning 0, context = 1291690 beremote: 12/02/06 10:03:50 Shared Buffers Connection type prepared... beremote: 12/02/06 10:03:50 Allocated 10 buffers, size 32768 bytes, total used: 328520 beremote: 12/02/06 10:03:50 TF_OpenSet() beremote: 12/02/06 10:03:50 SetupFormatEnv( fmt=0 ) beremote: 12/02/06 10:03:50 End of TF_OpenSet() ret_val = 0, num buffers = 10 beremote: 12/02/06 10:03:50 Status 1722 returned enumerating System Protected Files beremote: 12/02/06 10:03:50 LP_ENV::MsgError: error 0xe0008488 processing object Boot Files beremote: 12/02/06 10:03:50 Active Directory BackupGetDatabaseNames size 82 beremote: 12/02/06 10:03:50 BFT_NTFS_DATABASE='\\RA-FILESERVER\C$\WINNT\NTDS\ntds.dit' beserver: 12/02/06 10:03:50 12 AdammAdminBO::Get() - Parent = NULL , Entity = MEDIA pvlsvr: 12/02/06 10:03:50 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {CF386100-4B4A-43EB-A5A8-AF1A171ABC26} EntityName = "" EntityFlags = 0x00000000 pvlsvr: 12/02/06 10:03:50 PvlEntityDatabase::QueryEntity() : SUCCESS! beserver: 12/02/06 10:03:50 12 AdammAdminBO::Get() - hr = 0x0 tmasea : FileNotificationThread - Scheduled Delayed Checking of 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' tmasea : FileNotificationThread - Scheduled Delayed Checking of 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' tmasea : LockedFilePollThread - LockList has 2 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 9 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 9 tmasea : LockedFilePollThread - LockList has 2 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 8 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 8 tmasea : LockedFilePollThread - LockList has 2 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 7 beserver: 12/02/06 10:03:53 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 7 beremote: 12/02/06 10:03:54 Active Directory BackupGetBackupLogs size 262 beremote: 12/02/06 10:03:54 BFT_LOG='\\RA-FILESERVER\C$\WINNT\NTDS\\edb00118.log' beremote: 12/02/06 10:03:54 BFT_LOG='\\RA-FILESERVER\C$\WINNT\NTDS\\edb00119.log' beremote: 12/02/06 10:03:54 BFT_PATCH_FILE='\\RA-FILESERVER\C$\WINNT\NTDS\ntds.pat' tmasea : LockedFilePollThread - LockList has 2 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 6 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 6 tmasea : LockedFilePollThread - LockList has 2 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 5 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 5 beremote: 12/02/06 10:03:55 Exporting Registration Database to file: C:\WINNT\System32\BackupExecData\ComRegistrationDatabase\ComRegDb.bck beremote: 12/02/06 10:03:55 Informational: Registry file C:\WINNT\system32\config\default encountered when processing Registry object beremote: 12/02/06 10:03:56 Informational: Registry file C:\WINNT\system32\config\SAM encountered when processing Registry object tmasea : FileNotificationThread - Scheduled Delayed Checking of 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' beremote: 12/02/06 10:03:56 Informational: Registry file C:\WINNT\system32\config\SECURITY encountered when processing Registry object beremote: 12/02/06 10:03:56 Informational: Registry file C:\WINNT\system32\config\software encountered when processing Registry object tmasea : LockedFilePollThread - LockList has 3 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 4 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 9 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 4 tmasea : LockedFilePollThread - LockList has 3 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 3 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 8 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 3 tmasea : LockedFilePollThread - LockList has 3 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 2 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 7 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 2 tmasea : LockedFilePollThread - LockList has 3 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.DIR.tmp' to 1 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 6 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.REC.tmp' to 1 tmasea : LockedFilePollThread - LockList has 3 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 5 tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 4 beremote: 12/02/06 10:04:02 Informational: Registry file C:\WINNT\system32\config\system encountered when processing Registry object tmasea : LockedFilePollThread - LockList has 1 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 3 tmasea : LockedFilePollThread - LockList has 1 entries tmasea : LockedFilePollThread - Dec retry on 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' to 2 tmasea : FileNotificationThread - Scheduled Delayed Checking of 'C:\WINNT\Temp\JET77B3.tmp' beremote: 12/02/06 10:04:03 TF xfer time = 13 seconds. beremote: 12/02/06 10:04:03 WRITE: tpreceive_fail_count = 278 beremote: 12/02/06 10:04:03 WRITE: waiting_on_buffers_count = 269 beremote: 12/02/06 10:04:03 WRITE: buffers_written_count = 2477 tmasea : FileNotificationThread - Scheduled Delayed Checking of 'C:\WINNT\Temp\JET785F.tmp' beremote: 12/02/06 10:04:03 TF_CloseSet() beremote: 12/02/06 10:04:03 FreeFormatEnv( cur_fmt=0 ) beremote: 12/02/06 10:04:03 Detach from \\RA-FILESERVER\System?State beremote: 12/02/06 10:04:03 TF_FreeDriveContext( 1291690 ) beremote: 12/02/06 10:04:03 TF_FreeTapeBuffers: from 10 to 0 buffers beremote: 12/02/06 10:04:03 Job Stop(0) - Sat Dec 02 10:04:03 2006 bengine: 12/02/06 10:04:03 data halted: SUCCESSFUL beremote: 12/02/06 10:04:03 MailBoxDS::RemoveDrive done. beremote: 12/02/06 10:04:03 MailBoxDS::RemoveDrive done. bengine: 12/02/06 10:04:03 NDMPEngine: Shutting down. tmasea : FileNotificationThread - Scheduled Delayed Checking of 'C:\WINNT\Temp\JET791A.tmp' bengine: 12/02/06 10:04:03 TF_NDMPGetResult(): MediaServer thread done, returning TFLE 0 pvlsvr: 12/02/06 10:04:03 PvlEntityDatabase::QueryEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE EntityGuid = {CF386100-4B4A-43EB-A5A8-AF1A171ABC26} EntityName = "" EntityFlags = 0x00000000 pvlsvr: 12/02/06 10:04:03 PvlEntityDatabase::QueryEntity() : SUCCESS! bengine: 12/02/06 10:04:04 WriteEndSet( 1 ) returning 0 bengine: 12/02/06 10:04:04 WriteEndSet( 1 ) returning 0 bengine: 12/02/06 10:04:04 WriteEndSet( 0 ) returning 0 tmasea : LockedFilePollThread - LockList has 4 entries tmasea : LockedFilePollThread - Checking file 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' tmasea : LockedFilePollThread - Removing file 'C:\Program Files\VERITAS\Backup Exec\NT\Catalogs\{20453E43-9EFD-4A27-AEAD-3857A0713170}_14.fh.FIL.tmp' from lock list tmasea : LockedFilePollThread - Checking file 'C:\WINNT\Temp\JET77B3.tmp' tmasea : LockedFilePollThread - Removing file 'C:\WINNT\Temp\JET77B3.tmp' from lock list tmasea : LockedFilePollThread - Checking file 'C:\WINNT\Temp\JET785F.tmp' tmasea : LockedFilePollThread - Removing file 'C:\WINNT\Temp\JET785F.tmp' from lock list tmasea : LockedFilePollThread - Checking file 'C:\WINNT\Temp\JET791A.tmp' tmasea : LockedFilePollThread - Removing file 'C:\WINNT\Temp\JET791A.tmp' from lock list bengine: 12/02/06 10:04:04 Informational: Entering CIdr_ndmpWrapper::ndmpCreateDRFile beremote: 12/02/06 10:04:04 FillInDriveList(): GetVolumeInformation(C:\) returned 1. beremote: 12/02/06 10:04:04 File system name: "NTFS" beremote: 12/02/06 10:04:05 CLDMVolumeInfo():: Informational: Gathering LDM volume info beremote: 12/02/06 10:04:05 Informational: LDM volume info initialized beremote: 12/02/06 10:04:05 ~CLDMVolumeInfo beremote: 12/02/06 10:04:05 FillInDriveList(): GetVolumeInformation(E:\) returned 1. beremote: 12/02/06 10:04:05 File system name: "NTFS" beremote: 12/02/06 10:04:05 CLDMVolumeInfo():: Informational: Gathering LDM volume info beremote: 12/02/06 10:04:05 Informational: LDM volume info initialized beremote: 12/02/06 10:04:05 ~CLDMVolumeInfo tmasea : LockedFilePollThread - LockList has 0 entries beremote: 12/02/06 10:04:05 CDRFile::BuildAdvtMediaServerList(). Error 2 in querying for value Agent Directory List. beremote: 12/02/06 10:04:05 Informational: Entering DRAddASRStreams() beremote: 12/02/06 10:04:05 Informational: Exiting DRAddASRStreams(). Return Code: 0 beremote: 12/02/06 10:04:05 Informational: Entering IDR_GatherSFWStream() beremote: 12/02/06 10:04:05 Informational: Not a Windows 2003 system. So not gathering SFW stream. Leaving IDR_GatherSFWStream() bengine: 12/02/06 10:04:05 Informational: DR File Name: RA-FILESERVER.DR bengine: 12/02/06 10:04:05 Informational: Exiting ndmpCreateDRFile. bengine: 12/02/06 10:04:05 TF_CloseSet pvlsvr: 12/02/06 10:04:05 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} pvlsvr: 12/02/06 10:04:05 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_RELEASE ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} bengine: 12/02/06 10:04:05 TAPEALERT: Get TapeAlert Flags Return Code = 0X0 bengine: 12/02/06 10:04:05 TAPEALERT: TapeAlert Device Flag = 0X0 bengine: 12/02/06 10:04:05 TAPEALERT: TapeAlert Changer Flag = 0X0 bengine: 12/02/06 10:04:05 TAPEALERT: Get TapeAlert Flags Return Code = 0X0 bengine: 12/02/06 10:04:05 TAPEALERT: Get TapeAlert Flags Return Code = 0X0 pvlsvr: 12/02/06 10:04:05 PvlEntityDatabase::UpdateEntity() ParentEntityType = NULL_ENTITY_TYPE ParentEntityGuid = {00000000-0000-0000-0000-000000000000} EntityType = MEDIA_ENTITY_TYPE Media = {CF386100-4B4A-43EB-A5A8-AF1A171ABC26}, "" EntityFlags = 0x008C0000 pvlsvr: 12/02/06 10:04:05 PvlEntityDatabase::UpdateEntity() : SUCCESS! bengine: 12/02/06 10:04:05 DeviceManager: incoming event fired bengine: 12/02/06 10:04:05 TF_FreeDriveContext( 1B7FFE0 ) bengine: 12/02/06 10:04:05 TF_FreeTapeBuffers: from 2 to 0 buffers bengine: 12/02/06 10:04:05 FreeFormatEnv( cur_fmt=0 ) pvlsvr: 12/02/06 10:04:05 AdammSession::Execute( ADAMM_SESSION_EXECUTE_MOVER_DISMOUNT ) Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Flags = 0x00000000 pvlsvr: 12/02/06 10:04:05 AdammSession::DismountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Library = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Slot = 0000 Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Media = {CF386100-4B4A-43EB-A5A8-AF1A171ABC26}, "B2D000024" Side = 0001 pvlsvr: 12/02/06 10:04:05 PvlDrive::DismountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Slot = 0025 Side = 0001 pvlsvr: 12/02/06 10:04:05 PvlChanger::DismountMedia() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} Library = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Drive = {226CF1C6-DD54-48DA-A6DA-B8B251CEEBE7}, "RA-Backup-to-Disk Folder 1" Slot = 0025 Side = 0001 pvlsvr: 12/02/06 10:04:05 AdammSession::Release() Session = {67338314-F3A4-417A-B1E3-C5887AA31F95} bengine: 12/02/06 10:04:05 DeviceManager: processing pending requests bengine: 12/02/06 10:04:05 DeviceManager: going to sleep for 900000 msecs bengine: 12/02/06 10:04:05 BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary bengine: 12/02/06 10:04:06 DeviceManager: dismount event fired bengine: 12/02/06 10:04:06 DeviceManager: processing pending requests bengine: 12/02/06 10:04:06 DeviceManager: going to sleep for 900000 msecs beserver: 12/02/06 10:04:06 17 JobManager::HandleEndJob() called beserver: 12/02/06 10:04:06 -1 ActiveState::doEndEvent( ): 'Backup 00009' beserver: 12/02/06 10:04:06 17 JobManager::DoJobCompletionTasks(Backup 00009): GetFinalJobStatus()=6 beserver: 12/02/06 10:04:06 17 JobManager::DoJobCompletionTasks() Handling normal JOB_STATE_ERROR beserver: 12/02/06 10:04:06 17 JobManager::DoJobCompletionTasks() Finished building JOB_STATE_ERROR message beserver: 12/02/06 10:04:06 18 Alert Job Failed, Notify: No, EventLog: Yes, Trap: No beserver: 12/02/06 10:04:06 -1 ActiveState::doEndEvent( ): CJobManager::DoJobCompletionTasks() returned 0x0 bengine: 12/02/06 10:04:06 Job thread terminating beserver: 12/02/06 10:04:06 -1 Client 'RA-FILESERVER' Disconnected:0x1c686e0 tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:04:06 -1 Registered virtual array rowset(23): Client:RA-FILESERVER, Type:MD_OBJTYPE_JOBHISTORYVIEW tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:04:08 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries beserver: 12/02/06 10:04:12 11 ForcedDisplay beserver: 12/02/06 10:04:12 11 Backup server status: beserver: 12/02/06 10:04:12 11 RA-FILESERVER (RA-FILESERVER ) (0300/0900) ONLINE Drift:0(0), 0. tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tmasea : LockedFilePollThread - LockList has 0 entries tem?State V-79-57344-33928 - Access is denied Backup problem !Hey guys I�ve been trying to research this problem for some time and can�t find a working solution. I�m using backup ex 10x and when ever I try and back up any of the system state settings on my servers I get the following error. Job ended: Wednesday, November 29, 2006 at 9:04:25 PM Completed status: Failed Final error: 0xe0008488 - Access is denied. Final error category: Security Errors For additional information regarding this error refer to link V-79-57344-33928 Errors Click an error below to locate it in the job log Backup- \\SERVER\System?State V-79-57344-33928 - Access is denied. Access denied to directory Boot Files\. Everything else ie exchange mail box�s etc always backs up perfectly. The backup Ex account associated with the backup Ex has full administrator and backup permissions/rights, I�m not sure why this is failing. When I watch the backup process it seems to only fail when its verifying the backup, but if I turn off the verifying process the job still fails, any ideas of advice would be great. Regards James