10/21/13 23:07:33.681 [RecoverableJob::registerForEmail] (ID:101991ca8) Registered for email(../RecoverableJob.cpp:2701) 10/21/13 23:07:33.702 [JobInstance::state] (10203cbc8) state=0(../JobInstance.cpp:311) 10/21/13 23:07:33.702 [Info] V-117-201 requesting resources from RB for command job (jobid=6748365) 10/21/13 23:07:33.705 [ResBroker_i::requestResources] RequestSeq: Request=0 provider=NamedResourceProvider resourcename=LCM_PROD-SQL1-TAPE-STUG CountedResourceRequest { max=16 } 10/21/13 23:07:39.864 [ResBroker_i::evaluateOne] resources found for request, requestId={2FC5E9BE-3A9D-11E3-B376-0021280430DC}, userId=jobid=6748365, birthTime=1382393253, timeToFirstEval=6 sec, timeToSuccessOrFailure=6 sec, numberOfTimesEvaluated=1 10/21/13 23:07:39.865 [ResBroker_i::passOne] allocation: (Allocation: id={3370F77A-3A9D-11E3-ACC2-0021280430DC} provider=NamedResourceProvider resourcename=LCM_PROD-SQL1-TAPE-STUG masterserver=invldnunetbkupv1 groupid={00000000-0000-0000-0000-000000000000} userSequence=-1 userid="jobid=6748365" named resource allocation) 10/21/13 23:07:39.876 [JobInstance::state] (10203cbc8) state=1(../JobInstance.cpp:311) 10/21/13 23:07:40.189 [JobInstance::state] (10203cbc8) state=4(../JobInstance.cpp:311) 10/21/13 23:07:41.094 [JobInstance::state] (10203cbc8) state=4(../JobInstance.cpp:311) 10/21/13 23:07:41.142 [JobInstance::state] (10203cbc8) state=4(../JobInstance.cpp:311) 10/21/13 23:07:41.882 [ResBroker_i::requestResources] RequestSeq: Request=0 provider=ReservationGroupProvider resourcename=ReservationGroupProvider RB::ReservationGroupRequest groupid={00000000-0000-0000-0000-000000000000} SubRequest=0 provider=DriveOperationProvider resourcename=STORAGE UNIT RESOURCE userSequence=0 RB::StorageUnitRequest { StorageUnitRequest { storageUnit=PROD-SQL1-TAPE-STUG mediaPool=ENCR_Onsite-Dupes retentionLevel=12 mustUseLocalMediaServer=yes failOnError=no mpxRequired=yes mustBeNdmp=no getMaxFreeSpace=no minFreeSpaceKBytes=31846497 usageType=9 client= shareGroup=*ANY* isLifeCycle=true } } SubRequest=1 provider=DriveOperationProvider resourcename=MEDIA RESOURCE userSequence=1 MediaRequest { mediaId=GS0324 mediaServer=invldnmsql1vs1 mediaKey=0 userReservationId= assignedTime=0 client= usageType=10 mustBeNdmp=no driveName= drivePath= mediaPool= robotNumber=-1 slotNumber=-1 density=-1 ndmpControlHost= failIfNoMedia=yes externalFile= mediaType=2 mediaSubType=0 } SubRequest=2 provider=ReservationGroupProvider resourcename=GS0324 userSequence=2 MediaReservationRequest { mountCount=1 mediaId=GS0324 } 10/21/13 23:07:48.459 [getTwinStuList] INITIATING: 10/21/13 23:07:48.459 [getTwinStuList] masterServer = invldnunetbkupv1, client = , jobType = 9, capabilityFlags = 256, numberOfCopies = 1 10/21/13 23:07:48.459 [getTwinStuList] Twin_Record: STUIdentifier = PROD-SQL1-TAPE-STUG, STUIdentifierType = 2, PoolName = ENCR_Onsite-Dupes, MediaSharingGroup = *ANY*, RetentionLevel = 12, RequiredMediaServer = , PreferredMediaServer = , RequiredDiskVolumeMediaId = , RequiredStorageUnitName = , GetMaxFreeSpaceSTU = 0, CkptRestart = 0, CkptRestartSTUType = 0, CkptRestartSTUSubType = -1, CkptRestartSTUName = , CkptRestartMediaServer = , CkptRestartDiskGroupName = , CkptRestartDiskGroupServerType = , MpxEnabled = 1, MustUseLocalMediaServer = 1 10/21/13 23:07:48.516 [sql_select_stus] No. of stus selected is 5 10/21/13 23:07:48.671 [sql_select_stus] No. of stus selected is 0 10/21/13 23:07:48.671 [get_twin_stu_list] twin_stu_list has 5 storage units 10/21/13 23:07:48.674 [getTwinStuList] EXIT INFO: 10/21/13 23:07:48.674 [getTwinStuList] EXIT STATUS = 0 (EMM_ERROR_Success, Success) 10/21/13 23:07:48.675 [EMMAccess::getTwinStuList] got 5 STU record(s) 10/21/13 23:07:48.679 [reserveMedia] INITIATING: 10/21/13 23:07:48.679 [reserveMedia] masterServer = invldnunetbkupv1, jobType = 10, shared = 1 10/21/13 23:07:48.679 [reserveMedia] MediaRequest_Record: MediaServer = invldnmsql1vs1, PreferredMediaServer = , MediaId = GS0324, MediaKey = 0, AssignedTime = 0, DriveName = , DrivePath = , PoolName = , Density = -1, RequiredNdmpTapeServer = , PreferredNdmpTapeServer = , MediaType = 2, MediaSubType = 0, StuNameForDupWrite = 10/21/13 23:07:48.723 [reserveMedia] reservationKey = 46750573 10/21/13 23:07:48.723 [reserveMedia] MediaRequest_Record: MediaServer = invldnmsql1vs1, PreferredMediaServer = , MediaId = GS0324, MediaKey = 4003724, AssignedTime = 0, DriveName = , DrivePath = , PoolName = , Density = -1, RequiredNdmpTapeServer = , PreferredNdmpTapeServer = , MediaType = 2, MediaSubType = 0, StuNameForDupWrite = 10/21/13 23:07:48.724 [reserveMedia] EXIT INFO: 10/21/13 23:07:48.724 [reserveMedia] reserved_med_list = 4003724 10/21/13 23:07:48.724 [reserveMedia] EXIT STATUS = 0 (EMM_ERROR_Success, Success) 10/21/13 23:07:48.725 [EMMAccess::reserveMedia] media reserved: mediaKey=4003724, reservationKey=46750573 10/21/13 23:07:48.729 [allocateMedia] INITIATING: 10/21/13 23:07:48.729 [allocateMedia] masterServer = invldnunetbkupv1, client = , jobType = 10, capabilityFlags = 0, fatPipePreference = 0, statusOnly = 0 10/21/13 23:07:48.729 [allocateMedia] MediaRequest_Record: MediaServer = invldnmsql1vs1, PreferredMediaServer = , MediaId = GS0324, MediaKey = 0, AssignedTime = 0, DriveName = , DrivePath = , PoolName = , Density = -1, RequiredNdmpTapeServer = , PreferredNdmpTapeServer = , MediaType = 2, MediaSubType = 0, StuNameForDupWrite = 10/21/13 23:07:48.729 [allocateMedia] reserved_med_list = 4003724 10/21/13 23:07:48.752 [allocate_by_tape_media] could not get key for media server, MediaServer = invldnmsql1vs1 10/21/13 23:07:48.754 [allocateMedia] EXIT INFO: 10/21/13 23:07:48.754 [allocateMedia] reserved_med_list = 4003724 10/21/13 23:07:48.754 [allocateMedia] EXIT STATUS = 2000000 (EMM_ERROR_MachineNotExist, The host is not defined in EMM) 10/21/13 23:07:48.761 [releaseMedia] INITIATING: 10/21/13 23:07:48.762 [releaseMedia] reservationKey = 46750573 10/21/13 23:07:48.762 [releaseMedia] reserved_med_list = 4003724 10/21/13 23:07:48.817 [releaseMedia] EXIT INFO: 10/21/13 23:07:48.817 [releaseMedia] EXIT STATUS = 0 (EMM_ERROR_Success, Success) 10/21/13 23:07:48.818 [EMMAccess::releaseMedia] released media reservation (reservationKey=46750573) 10/21/13 23:07:48.819 [ResBroker_i::evaluateOne] resource request fails, requestId={34A4DBA2-3A9D-11E3-A43B-0021280430DC}, userId=jobid=6748365, status=2000000, birthTime=1382393261, timeToFirstEval=7 sec, timeToSuccessOrFailure=7 sec, numberOfTimesEvaluated=1 10/21/13 23:07:48.876 [Error] V-117-131 NBU status: 800, EMM status: The host is not defined in EMM 10/21/13 23:07:48.883 [Error] V-117-131 NBU status: 800, EMM status: The host is not defined in EMM 10/21/13 23:07:48.890 [Error] V-117-131 NBU status: 800, EMM status: The host is not defined in EMM 10/21/13 23:07:48.907 [JobInstance::state] (10203cbc8) state=4(../JobInstance.cpp:311) 10/21/13 23:07:48.909 [CommandJob::~CommandJob] (dump Command Job): ************************** * CommandJob * (jobid=6748365 jobtype=4) ************************** JobType = 4 PolicyName = SLP_PROD_SILVER_SQL PolicType = 36 SchedName = Dup SchedType = 2 Priority = 5 HostToStartJobOn = invldnunetbkupv1 MaxJobsPerPolicy = 2147483647 NamedResources(count) = 1 CommandPath = /usr/openv/netbackup/bin/admincmd/bpduplicate CommandName = bpduplicate CommandArgs = -dstunit PROD-SQL1-TAPE-STUG -dp ENCR_Onsite-Dupes -fail_on_error 0 -rl 12 -owner *ANY* -dcn 2 -cn 1 -mpx -lcm -priority 5 -mtd 25600 -Bidfile /usr/openv/var/global/nbstserv/bid_file_rs_1382393253_0_0 (../CommandJob.cpp:92) 10/21/13 23:07:48.914 [PemTask::taskComplete] (ID:101991ca8) Active subtask count=0(../PemTask.cpp:527) 10/21/13 23:07:48.978 [Info] V-116-14 CLIENT NONE POLICY SLP_PROD_SILVER_SQL SCHED Dup EXIT STATUS 800 (resource request failed) 10/21/13 23:07:49.019 [Error] V-116-143 duplicate exited with status 800 (resource request failed)