Backup Exec 10d 2003 Backup Errors

[michael_papet]

Hi,

After upgrading a two-node cluster to 2003 in an environment with 2000 Advanced server machines, back up jobs started failing. Following some advice posted in the forums, I took the 2003 machines out of their old job and created a new one using two policies.

Policy 1:
Daily incremental backup of the 2003 machines with the archive flag set. This job test runs successfully.

Policy 2:
Weekly full backup of the 2003 machines. The second policy is in the same group as policy 1 above.

Both jobs use the same backup selections.

Here's some error messages
<189>Oct 18 00:00:04 ksdc2.KwikSystems.com MSWinEventLog1Application18265Wed Oct 18 00:00:04 200617177MSSQL$BKUPEXECDLOUnknown UserN/AInformationKSDC2Disk0000: 19 43 00 00 0a 00 00 00 ....... 0008: 12 00 00 00 4b 00 53 00 ...... 0010: 44 00 43 00 32 00 5c 00 .... 0018: 42 00 4b 00 55 00 50 00 .... 0020: 45 00 58 00 45 00 43 00 .... 0028: 44 00 4c 00 4f 00 00 00 ..... 0030: 00 00 00 00 6f 73 00 00 ......

SGMon Log:
beserver: 10/18/06 13:04:28 -1 Job Manager: LoadJob() request completed
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - Parent = NULL , Entity = MACHINE
pvlsvr: 10/18/06 13:04:28 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
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - Parent = NULL , Entity = DEVICE_POOL
pvlsvr: 10/18/06 13:04:28 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: 10/18/06 13:04:28 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = DRIVE
pvlsvr: 10/18/06 13:04:28 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: 10/18/06 13:04:28 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = BACKUP_TO_DISK
pvlsvr: 10/18/06 13:04:28 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: 10/18/06 13:04:28 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - Parent = NULL , Entity = DRIVE_POOL_DRIVE
pvlsvr: 10/18/06 13:04:28 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: 10/18/06 13:04:28 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:28 12 AdammAdminBO::Query() - Parent = MACHINE , Entity = CHANGER
pvlsvr: 10/18/06 13:04:28 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: 10/18/06 13:04:28 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:28 -1 ActiveState::doDispatch( :( 'TestRun 00011'
beserver: 10/18/06 13:04:28 -1 LocalSvr, runTask():{66402957-c230-48d2-934c-14b1c7bd26c8}
beserver: 10/18/06 13:04:28 11 LocalSvr, RunTask: EngineHandedJob SUCCESS, jobid:{66402957-c230-48d2-934c-14b1c7bd26c8}.
beserver: 10/18/06 13:04:28 -1 JMTestRunEng: Run() started: 66402957-c230-48d2-934c-14b1c7bd26c8.
beserver: 10/18/06 13:04:28 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
beserver: 10/18/06 13:04:29 -1 LocalSvr, runTask():{66402957-c230-48d2-934c-14b1c7bd26c8}
beserver: 10/18/06 13:04:29 -1 BengineWrapper runtask() start
beserver: 10/18/06 13:04:29 -1 BengineWrapper JobEngine.Open() OK
beserver: 10/18/06 13:04:29 -1 Client requested key.
beserver: 10/18/06 13:04:29 -1 Client 'KSDC2' connected('','KWIKSYSTEMS\michael'): 0x3df5270
bengine: 10/18/06 13:04:29 JobEngine::LaunchJob
bengine: 10/18/06 13:04:29 RunJobWithGuidAndServer( {66402957-C230-48D2-934C-14B1C7BD26C8}, KSDC2 ) = 0
beserver: 10/18/06 13:04:29 -1 BengineWrapper JobEngine.RunJobWithGuidAndServer() rc=0
beserver: 10/18/06 13:04:29 -1 JobManager: BengineWrapper RunJob() done
beserver: 10/18/06 13:04:29 11 LocalSvr, RunTask: EngineHandedJob SUCCESS, jobid:{66402957-c230-48d2-934c-14b1c7bd26c8}.
bengine: 10/18/06 13:04:29 Thread for job TestRun 00011 ({66402957-C230-48D2-934C-14B1C7BD26C8}) starting
beserver: 10/18/06 13:04:29 -1 Client requested key.
beserver: 10/18/06 13:04:29 -1 Client 'KSDC2' connected('','KWIKSYSTEMS\michael'): 0x1ba6bd0
beserver: 10/18/06 13:04:29 -1 Client 'KSDC2' Disconnected:0x1ba6bd0
bengine: 10/18/06 13:04:29 Checking for Evaluation expiration.
bengine: 10/18/06 13:04:29 ENGSCRPT: ConvertDeviceName osId = 0X2f
bengine: 10/18/06 13:04:29 ENGSCRPT: IN - oldDeviceName = \\KSH2H1\Shadow?Copy?Components
bengine: 10/18/06 13:04:29 ENGSCRPT: OUT - newName = \\KSH2H1\Shadow?Copy?Components
bengine: 10/18/06 13:04:29 ENGSCRPT: ConvertDeviceName osId = 0Xe
bengine: 10/18/06 13:04:29 ENGSCRPT: IN - oldDeviceName = \\KSH2HCLA\G:
bengine: 10/18/06 13:04:29 ENGSCRPT: OUT - newName = \\KSH2HCLA\G:
bengine: 10/18/06 13:04:29 ENGSCRPT: ConvertDeviceName osId = 0Xe
bengine: 10/18/06 13:04:29 ENGSCRPT: IN - oldDeviceName = \\KSH2HCLA\Backup
bengine: 10/18/06 13:04:29 ENGSCRPT: OUT - newName = \\KSH2HCLA\Backup
bengine: 10/18/06 13:04:29 ENGSCRPT: ConvertDeviceName osId = 0Xe
bengine: 10/18/06 13:04:29 ENGSCRPT: IN - oldDeviceName = \\KSH2H2\Backup
bengine: 10/18/06 13:04:29 ENGSCRPT: OUT - newName = \\KSH2H2\Backup
bengine: 10/18/06 13:04:29 ENGSCRPT: ConvertDeviceName osId = 0Xe
bengine: 10/18/06 13:04:29 ENGSCRPT: IN - oldDeviceName = \\KSH2H2\G:
bengine: 10/18/06 13:04:29 ENGSCRPT: OUT - newName = \\KSH2H2\G:
bengine: 10/18/06 13:04:29 ENGSCRPT: ConvertDeviceName osId = 0X2f
bengine: 10/18/06 13:04:29 ENGSCRPT: IN - oldDeviceName = \\KSH2H2\Shadow?Copy?Components
bengine: 10/18/06 13:04:29 ENGSCRPT: OUT - newName = \\KSH2H2\Shadow?Copy?Components
bengine: 10/18/06 13:04:29 ENGSCRPT: No script found
bengine: 10/18/06 13:04:29 ndmpConnect : Control Connection information : connection established between IP 172.18.1.32, port 1302 and IP 172.18.1.26, port 10000
bengine: 10/18/06 13:04:29 NDMP version 3 connection CONNECTED
bengine: 10/18/06 13:04:31
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 10/18/06 13:04:31 data halted: SUCCESSFUL
bengine: 10/18/06 13:04:31 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:31 ndmpConnect : Control Connection information : connection established between IP 172.18.1.32, port 1305 and IP 172.18.1.28, port 10000
bengine: 10/18/06 13:04:31 NDMP version 3 connection CONNECTED
bengine: 10/18/06 13:04:32
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 10/18/06 13:04:33 data halted: SUCCESSFUL
bengine: 10/18/06 13:04:33 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:33
dataStartBackup: ndmpSendRequest returned: 0x0, 0
beserver: 10/18/06 13:04:33 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
bengine: 10/18/06 13:04:34 data halted: SUCCESSFUL
bengine: 10/18/06 13:04:34 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:34 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:34 ndmpConnect : Control Connection information : connection established between IP 172.18.1.32, port 1308 and IP 172.18.1.27, port 10000
bengine: 10/18/06 13:04:34 NDMP version 3 connection CONNECTED
beserver: 10/18/06 13:04:35 -1 SQLLog(29):AgeSession m_threadMap: SessionThreadID:51c, CurrentThreadID:734
bengine: 10/18/06 13:04:35
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 10/18/06 13:04:35 data halted: SUCCESSFUL
bengine: 10/18/06 13:04:35 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:35 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:36
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 10/18/06 13:04:36 data halted: SUCCESSFUL
bengine: 10/18/06 13:04:36 NDMPEngine: Shutting down.
beserver: 10/18/06 13:04:36 11 ForcedDisplay
beserver: 10/18/06 13:04:36 11 Backup server status:
beserver: 10/18/06 13:04:36 11 KSDC2 (KSDC2 ) (0300/0900) ONLINE Drift:0(0), 0.
bengine: 10/18/06 13:04:37
dataStartBackup: ndmpSendRequest returned: 0x0, 0
bengine: 10/18/06 13:04:38 data halted: SUCCESSFUL
bengine: 10/18/06 13:04:38 NDMPEngine: Shutting down.
bengine: 10/18/06 13:04:38 BackupJob::MergeBEVSRJobLogsIfNecessary: No VSR log file found, no merging necessary
beserver: 10/18/06 13:04:38 17 JobManager::HandleEndJob() called
beserver: 10/18/06 13:04:38 -1 ActiveState::doEndEvent( :( 'TestRun 00011'
bengine: 10/18/06 13:04:38 Job thread terminating
beserver: 10/18/06 13:04:38 -1 Client 'KSDC2' Disconnected:0x3df5270
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - Parent = NULL , Entity = DEVICE
pvlsvr: 10/18/06 13:04:41 PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = DEVICE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - Parent = DEVICE_POOL , Entity = DEVICE_POOL_DEVICE
pvlsvr: 10/18/06 13:04:41 PvlEntityDatabase::QueryEntity()
ParentEntityType = DEVICE_POOL_ENTITY_TYPE
ParentEntityGuid = {69ED34D7-682E-4EE1-95C9-AD4CCE31C964}
EntityType = DEVICE_POOL_DEVICE_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - hr = 0x0
pvlsvr: 10/18/06 13:04:41 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_QUERY_TARGET_DEVICES )
Session = {63FEEA19-BBEF-410B-AD58-2054BEC4A326}
pvlsvr: 10/18/06 13:04:41 AdammSession::Execute( ADAMM_SESSION_EXECUTE_SESSION_FETCH_TARGET_DEVICES )
Session = {63FEEA19-BBEF-410B-AD58-2054BEC4A326}
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Get() - Parent = NULL , Entity = PVL
pvlsvr: 10/18/06 13:04:41 PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = PVL_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Get() - hr = 0x0
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - Parent = NULL , Entity = MEDIA_SET
pvlsvr: 10/18/06 13:04:41 PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_SET_ENTITY_TYPE
EntityGuid = {00000009-03E8-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:41 12 AdammAdminBO::Query() - Parent = NULL , Entity = MEDIA
pvlsvr: 10/18/06 13:04:41 PvlEntityDatabase::QueryEntity()
ParentEntityType = NULL_ENTITY_TYPE
ParentEntityGuid = {00000000-0000-0000-0000-000000000000}
EntityType = MEDIA_ENTITY_TYPE
EntityGuid = {00000000-0000-0000-0000-000000000000}
EntityName = ""
EntityFlags = 0x00000000
pvlsvr: 10/18/06 13:04:41 PvlEntityDatabase::QueryEntity() : SUCCESS!
beserver: 10/18/06 13:04:42 12 AdammAdminBO::Query() - hr = 0x0
beserver: 10/18/06 13:04:42 -1 JMTestRunEng: Media Check.
beserver: 10/18/06 13:04:42 -1 JMTestRunEng: AppendFirstThenOverwrite online append bytes success.
beserver: 10/18/06 13:04:42 -1 JMTestRunEng: Tape Capacity failed at AppendFirstThenOverwrite.
beserver: 10/18/06 13:04:42 20 Auto Clear Alerts Query
beserver: 10/18/06 13:04:42 17 JobManager::UpdateJobInstanceData() new task state=18
beserver: 10/18/06 13:04:43 17 JobManager::HandleEndJob() called
beserver: 10/18/06 13:04:43 -1 JMTestRunEng: Run() ended: 66402957-c230-48d2-934c-14b1c7bd26c8.
beserver: 10/18/06 13:04:43 -1 ActiveState::doEndEvent( :( 'TestRun 00011'
beserver: 10/18/06 13:04:43 17 JobManager::DoJobCompletionTasks(TestRun 00011): GetFinalJobStatus()=6
beserver: 10/18/06 13:04:43 17 JobManager::DoJobCompletionTasks() Handling normal JOB_STATE_ERROR
beserver: 10/18/06 13:04:43 17 JobManager::DoJobCompletionTasks() Finished building JOB_STATE_ERROR message
beserver: 10/18/06 13:04:43 18 Alert Job Failed, Notify: No, EventLog: Yes, Trap: No
beserver: 10/18/06 13:04:43 -1 ActiveState::doEndEvent( :( CJobManager::DoJobCompletionTasks() returned 0x0
beserver: 10/18/06 13:04:43 -1 Released virtual array rowset(13).
beserver: 10/18/06 13:04:43 -1 Registered virtual array rowset(15): Client:KSDC2, Type:MD_OBJTYPE_JOBHISTORYVIEW
beserver: 10/18/06 13:04:44 17 CJobManagerBO::Query QUERY_JOBSETUP_MONITOR
Capturing to C:\Program Files\VERITAS\Backup Exec\NT\logs\SGMon.log

[michael_papet]

I deleted the policies and jobs and now have no remote selections. Not even for my old win2k job.

WTF??

[michael_papet]

Service options were corrupted eliminating remote browsing.

Changing the user options under the Backup Exec services fixed that.

I reconfigured the job starting with the full back up first, then the incremental. It at least passes the test run.

This is some *buggy* software. Thank dog I only admin it.


Time to find another vendor.