C:\Users\admin>vxlogview -p 51216 -X "jobid=48254"
21/04/2022 08:30:58.191 [Info] V-116-25 job with jobid=48250 restarted as jobid=48254
21/04/2022 08:30:58.801 [JobInstance::state] (000000000304FFB0) state=0(JobInstance.cpp:365)
21/04/2022 08:30:58.801 V-117-56 [BackupJob::sendRequestToRB] requesting resources from RB for backup job (jobid=48254)
21/04/2022 08:30:58.801 V-118-227 [ResBroker_i::requestResources] received resource request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}, priority 999, secondary priority 26366, description THE_BACKUP_JOB-48254-{D76068B5-E202-4DA4-9D2A-28C03E158545}
21/04/2022 08:30:58.801 [ResBroker_i::requestResources] RequestSeq:
Request=0 provider=NamedResourceProvider resourcename=bkp01.NBU_CATALOG.MAXJOBS CountedResourceRequest { max=1 }
21/04/2022 08:30:59.129 [ResBroker_i::evaluateOne] Evaluating request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}
21/04/2022 08:30:59.129 [ProviderManager::allocate] NamedResourceProvider returned Allocation Granted for request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}
21/04/2022 08:30:59.129 [ResBroker_i::evaluateOne] resources found for request, requestId={D76068B5-E202-4DA4-9D2A-28C03E158545}, userId=jobid=48254, birthTime=1650526258, timeToFirstEval=1 sec, timeToSuccessOrFailure=1 sec, numberOfTimesEvaluated=1
21/04/2022 08:30:59.129 V-118-109 [ResBroker_i::passOne] resources found for request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}, following allocations are granted:
21/04/2022 08:30:59.129 V-118-228 [ResBroker_i::passOne] allocation ID {DFF8F926-2867-49E2-8B22-3CC1DD5C1E2E}, group ID {00000000-0000-0000-0000-000000000000}, provider NamedResourceProvider, resource bkp01.NBU_CATALOG.MAXJOBS
21/04/2022 08:30:59.129 [ResBroker_i::passOne] allocation: (Allocation: id={DFF8F926-2867-49E2-8B22-3CC1DD5C1E2E} provider=NamedResourceProvider resourcename=bkp01.NBU_CATALOG.MAXJOBS masterserver=bkp01 groupid={00000000-0000-0000-0000-000000000000} userSequence=-1 userid="jobid=48254"  firstuserid="jobid=48254" named resource allocation)
21/04/2022 08:30:59.449 V-118-249 [CallbackQueue::resourcesAvailable] added allocations to the callback queue for request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}
21/04/2022 08:30:59.449 V-118-250 [CallbackQueue::notifyClientAvailable] queueing notification of availability of resources for request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}
21/04/2022 08:30:59.449 V-118-256 [CorbaCall_notifyClientAvailable::execute] sending availability of resources to nbjm for request ID {D76068B5-E202-4DA4-9D2A-28C03E158545}
21/04/2022 08:30:59.680 [JobInstance::state] (000000000304FFB0) state=1(JobInstance.cpp:365)
21/04/2022 08:30:59.680 V-117-296 [BackupJob::extractResourceAttributes] unable to get storage unit resource attribute
21/04/2022 08:30:59.680 V-117-296 [BackupJob::extractResourceAttributes] unable to get media server resource attribute
21/04/2022 08:30:59.680 [BackupJob::extractResourceAttributes] (000000000304FFB0) media server=(BackupJob.cpp:1439)
21/04/2022 08:30:59.680 V-117-296 [BackupJob::extractResourceAttributes] unable to get stu name resource attribute
21/04/2022 08:30:59.680 V-117-296 [BackupJob::extractResourceAttributes] unable to get mpxid resource attribute
21/04/2022 08:30:59.680 [BackupJob::processProxyResponse] (000000000304FFB0) Done: flush proxy response, next:start job, jobid=48254(BackupJob.cpp:4081)
21/04/2022 08:30:59.680 [JobInstance::state] (000000000304FFB0) state=1(JobInstance.cpp:365)
21/04/2022 08:30:59.680 [JobInstance::state] (000000000304FFB0) state=1(JobInstance.cpp:365)
21/04/2022 08:31:01.098 [GenBackupJob::processProxyResponse] (000000000304FFB0) Done: create image, Next: start genjob, proxy status=0(GenBackupJob.cpp:899)
21/04/2022 08:31:01.098 [JobInstance::state] (000000000304FFB0) state=1(JobInstance.cpp:365)
21/04/2022 08:31:01.113 [GenBackupJob::spawnGenericJob] (000000000304FFB0) start CommandActions(GenBackupJob.cpp:680)
21/04/2022 08:31:01.113 [GenBackupJob::spawnGenericJob] GenBackupJob Commandline Options=
**************************
* GenBackupJob           *
**************************
Jobid            = 48254
GroupId          = 48254
SubType          = 35
MediaServer      =
MasterServer     = bkp01
PolicyName       = BKP01_Catalog
SchedName        = Full_Weekly
SchedType        = 0
ClientName       = bkp01
ClientType       = 35
Stream Num       = -1
Birthtime        = 1650526259
BackupCopy       = 0
BackupId         = bkp01_1650526259
SnapshotId       =
User             = root
BMRSave          = no
StreamDiscovery  = no
Resume           = no
Checkpoint       = no
UseInternalJobid = no
OpaqueString     =  -cbustart 0 -cbuend 0 -cbustart 0 -cbuend 0

**************************
*BackupJob               *
(jobid=48254 jobtype=6)
**************************
isStdBackup       = false
backupType        = 6
subtype           = 35
mpxid             = {00000000-0000-0000-0000-000000000000}
maxmpx            = 1
Birthtime         = 1650526259
backupid          = bkp01_1650526259
Active pid        = 0
Job Count         = 1
IPaddr            =
currentThrottle   = -1
estimatedSize     = 0
estimatedFileCount= 0
suspendable       = 0
progressLogTimerID= -1
localBackup       = 0
policy            = BKP01_Catalog
Num copies        = 1
Include_file#     = 1
resourceCount     = 1
Stu               = MSDP_DP-MSDP2
Pool              = NetBackup
Retention         = 0
UseLocal          = false
(GenBackupJob.cpp:748)
21/04/2022 08:31:01.113 [Info] V-117-32 Map jobtype to column=7, column name=CATALOG_BACKUP:
21/04/2022 08:31:01.113 [Info] V-117-88 FSM JobTable[CATALOG_BACKUP:StartNotifyScript] m_JobStep=1, Jobid=48254
21/04/2022 08:31:01.488 [Info] V-117-122 started backup job for client bkp01, policy BKP01_Catalog, schedule Full_Weekly on storage unit <?>
21/04/2022 08:31:09.941 [Info] V-117-94 FSM JobTable[CATALOG_BACKUP:StartNotifyScript] m_JobStep=1, Jobid=48254, returns=0
21/04/2022 08:31:09.941 [Info] V-117-88 FSM JobTable[CATALOG_BACKUP:DbmQuery] m_JobStep=2, Jobid=48254
21/04/2022 08:41:53.506 [JobInstance::state] (000000000304FFB0) state=5(JobInstance.cpp:365)
21/04/2022 08:41:53.506 [Info] V-117-94 FSM JobTable[CATALOG_BACKUP:DbmQuery] m_JobStep=2, Jobid=48254, returns=2
21/04/2022 08:41:53.506 [Info] V-117-88 FSM JobTable[CATALOG_BACKUP:StopOnError] m_JobStep=-5, Jobid=48254
21/04/2022 08:41:53.506 [Info] V-117-94 FSM JobTable[CATALOG_BACKUP:StopOnError] m_JobStep=3, Jobid=48254, returns=0
21/04/2022 08:41:53.506 [Info] V-117-88 FSM JobTable[CATALOG_BACKUP:EndNotifyScript] m_JobStep=4, Jobid=48254
21/04/2022 08:41:54.069 [Info] V-117-94 FSM JobTable[CATALOG_BACKUP:EndNotifyScript] m_JobStep=4, Jobid=48254, returns=0
21/04/2022 08:41:54.069 [Info] V-117-107 *********** Jobid 48254 status summary for CATALOG_BACKUP: *********
21/04/2022 08:41:54.069 [Info] V-117-114 Step 1 StartNotifyScript status 0 jobstatus -
21/04/2022 08:41:54.069 [Info] V-117-114 Step 2 DbmQuery status 2 jobstatus 2
21/04/2022 08:41:54.069 [Info] V-117-114 Step 3 ValidateImage status - jobstatus 2
21/04/2022 08:41:54.069 [Info] V-117-114 Step 4 EndNotifyScript status 0 jobstatus 2
21/04/2022 08:41:54.069 [Info] V-117-111 ********** job status is 2 **********
21/04/2022 08:41:54.069 [Info] V-117-52 GenBackupJob Terminating status=2
21/04/2022 08:41:54.069 [JobInstance::state] (000000000304FFB0) state=5(JobInstance.cpp:365)
21/04/2022 08:41:54.069 [JobInstance::state] (000000000304FFB0) state=5(JobInstance.cpp:365)
21/04/2022 08:41:54.069 [JobInstance::state] (000000000304FFB0) state=11(JobInstance.cpp:365)
21/04/2022 08:41:54.069 [BackupJob::sendNotifications] (000000000304FFB0) disable resume=false, brm started=false(BackupJob.cpp:2717)
21/04/2022 08:41:54.069 V-117-239 [BackupJob::terminateThisJob] terminated job, jobid=48254, status=2 (null)
21/04/2022 08:41:54.069 [BackupJob::~BackupJob] (dump Backup Job):
**************************
*BackupJob               *
(jobid=48254 jobtype=6)
**************************
isStdBackup       = false
backupType        = 6
subtype           = 35
mpxid             = {00000000-0000-0000-0000-000000000000}
maxmpx            = 1
Birthtime         = 1650526259
backupid          = bkp01_1650526259
Active pid        = 0
Job Count         = 1
IPaddr            =
currentThrottle   = -1
estimatedSize     = 0
estimatedFileCount= 0
suspendable       = 0
progressLogTimerID= -1
localBackup       = 0
policy            = BKP01_Catalog
Num copies        = 1
Include_file#     = 1
resourceCount     = 1
Stu               = MSDP_DP-MSDP2
Pool              = NetBackup
Retention         = 0
UseLocal          = false
(BackupJob.cpp:437)
21/04/2022 08:41:54.069 V-116-216 [RecoverableJob::doJobCompletion] jobid=48254 completed with status 2
21/04/2022 08:41:54.084 V-116-221 [RecoverableJob::run_PROCESS_STATUS] retry of jobid=48254 exceeds failure history, ending retries
21/04/2022 08:41:54.100 [PemTask::taskComplete] (ID:000000000442FBD8 CTX:00000000043B5DC8) Active subtask count=0(PemTask.cpp:530)
21/04/2022 08:41:54.100 [Info] V-116-14 CLIENT bkp01  POLICY BKP01_Catalog  SCHED Full_Weekly  EXIT STATUS 2 (none of the requested files were backed up) VBRF 1 2
21/04/2022 08:41:54.100 V-118-102 [ResBroker_i::releaseOne] releasing allocation ID {DFF8F926-2867-49E2-8B22-3CC1DD5C1E2E}
21/04/2022 08:41:54.115 [Error] V-116-305 catalog backup exited with status 2 (none of the requested files were backed up)
21/04/2022 08:41:54.131 [RecoverableJob::unregisterForEmail] (ID:000000000442FBD8 CTX:00000000043B5DC8) Unregistered for email(RecoverableJob.cpp:3068)