Solved

Backup of VMWare fails

  • 31 March 2021
  • 2 replies
  • 6127 views

Userlevel 4
Badge +11
  • Commvault Certified Expert
  • 89 replies

Hello Guys,

 

I need your help.

 

The Customer protects two VMware vCenters.

 

During backup job of any subclient and any vCenter they are getting the following error:

 

 

I tried run backup using diferent VSA but I got exactly the same error.

 

I can see the following error in the vsbkp.log file but I don’t know what it means:


3928  13a8  03/31 09:21:04 403680 CvCopyFile::CopyFiles() - Failed to copy file; All files are not copied, TimedOut=True, error=0x80070102:{CCvNetwork::PutFileRequest(9047)} + {CCvNetwork::ReceiveXDRMessageInternal(5358)} + {CCvNetwork::CheckIfDataAvailable(5425)/cplsvkol-cv01_2/CPLSVKOL-CV01/SockIP(10.219.0.52)/10.219.0.52:8405/8405 cvd.exe(24748:55d4)} + {CCvNetwork::CheckDataWaiting(6099)} + {CSessionConnectionSocket::CheckDataWaiting(663)/W32.258.(The wait operation timed out. (WAIT_TIMEOUT.258))-Timed out}
3928  13a8  03/31 09:21:04 403680 NoPrefix [SendDirChange::sendFiles]: failed to send dirchange files to IndexingService. Error:[0x80070102:{CvCopyFile::CopyFiles(10551)/Failed to copy files to client (cplsvkol-cv01_2/CPLSVKOL-CV01/SockIP(10.219.0.52)/10.219.0.52:8405/8405 cvd.exe(24748:55d4))} + {CCvNetwork::PutFileRequest(9047)} + {CCvNetwork::ReceiveXDRMessageInternal(5358)} + {CCvNetwork::CheckIfDataAvailable(5425)/cplsvkol-cv01_2/CPLSVKOL-CV01/SockIP(10.219.0.52)/10.219.0.52:8405/8405 cvd.exe(24748:55d4)} + {CCvNetwork::CheckDataWaiting(6099)} + {CSessionConnectionSocket::CheckDataWaiting(663)/W32.258.(The wait operation timed out. (WAIT_TIMEOUT.258))-Timed out}]
3928  13a8  03/31 09:21:04 403680 [::sendDirChange]: failed to transfer files to createIndex
3928  13a8  03/31 09:21:04 403680 SENDCOMPDCHANGE sendDirChange: send component dirchange failed with ret code [-1]
3928  13a8  03/31 09:21:04 403680 vsIndexClient::createIndex() - Send Dir change failed. retCode [-1]
3928  13a8  03/31 09:21:04 403680 VSBkpCoordinator::CreateIndex() - Create Index Failed
3928  13a8  03/31 09:21:04 403680 JOBRESULTSTABLE(D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\2\0\403680\JobResults removing copy 'D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\2\0\403680'
3928  2578  03/31 09:21:04 403680 VSBkpController::ControllerThread() - All [0] VMs have been processed
3928  13a8  03/31 09:21:04 403680 BKP CALLED COMPLETE (PHASE Status::FAIL), 403680. Token [403680:4:1:0:51936]
3928  13fc  03/31 09:21:05 403680 CVJobCtrlLog::unregisterProcess(): successfully removed file [D:\Commvault\ContentStore2\Base\JobControl\3.928]
3928  13a8  03/31 09:21:05 403680 CVMWareInfo::ShutdownVMInfo() - VMDiskInfo::Shutdown complete
3928  13a8  03/31 09:21:05 403680 CVMWareInfo::ShutdownVMInfo() - CVISDKCppBridge::Cleanup complete
3928  13a8  03/31 09:21:05 403680 = [vsbkp] has exited [BACKUP PHASE]


Ports 8405, 443 and 902 are open.

 

Any ideas?

 

Rgds,

Kamil

 

P.S. Here’s the full log for the job 403680:

 


 


@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01
File    : JobManager.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

18472 4d3c  03/31 09:15:33 403680 Servant    [---- IMMEDIATE BACKUP REQUEST ----], taskid [3507] Clnt[CPLSV006320] AppType[Virtual Server][106] BkpSet[defaultBackupSet] SubClnt[CPLSV006373] BkpLevel[Full][1]
18472 6390  03/31 09:15:33 403680 Scheduler  Indexing media agent id [3] name [CPLSVKOL-CV01].
18472 1e9c  03/31 09:15:34 403680 Scheduler  Phase [2-Discover] (0,0) started on [CPLSVKOL-CV02] in [1] second(s) - vsdisc.exe -j 403680 -a 2:231 -t 1 -ref 0 -i 2 -d CPLSVKOL-CV01*cplsvkol-cv01_2*8405 -io 1  -jt 403680:2:1:0:51936  -idxma CPLSVKOL-CV01*cplsvkol-cv01_2*8405  -numstreams 0
18472 59b8  03/31 09:15:36 403680 Servant    Reg [Control] received. Client [cplsvkol-cv02_2] plattype = 4. Token [403680:2:1:0:51936]
18472 59b8  03/31 09:15:45 403680 Scheduler  Phase [Completed] message received from [CPLSVKOL-CV02] Module [vsdiscovery] Token [403680:2:1:0:51936] restartPhase [0]
18472 59b8  03/31 09:15:45 403680 Delay Msg  successfully deleteSavedJMJobUpdateMsgByJob
18472 59b8  03/31 09:15:45 403680 JobSvr Obj Phase [2-Discover] for Backup Job Completed. Backup will continue with phase [Backup].
18472 59b8  03/31 09:15:45 403680 ArchiveMgr JMArMgr::releaseAllJobStreams() Calling CvResourceManager::releaseJobStreams(jobType=1,appType=106,logicalRelease=true)
18472 1e9c  03/31 09:15:56 403680 Scheduler  Phase [4-Backup] (0,0) started on [CPLSVKOL-CV02] in [0] second(s) - vsbkp.exe -j 403680 -a 2:231 -t 1 -ref 0 -i 2 -d CPLSVKOL-CV01*cplsvkol-cv01_2*8405 -io 1  -jt 403680:4:1:0:51936  -idxma CPLSVKOL-CV01*cplsvkol-cv01_2*8405  -numstreams 1
18472 69e0  03/31 09:15:59 403680 Servant    Reg [Control] received. Client [cplsvkol-cv02_2] plattype = 4. Token [403680:4:1:0:51936]
18472 69e0  03/31 09:16:04 403680 Servant    Reg [Non Control Monitor] received. Client [cplsvkol-cv01_2] plattype = 4. Token [403680:4:1:0:51936]
18472 59b8  03/31 09:21:04 403680 Scheduler  Phase [Failed] message received from [CPLSVKOL-CV02] Module [vsbkp] Token [403680:4:1:0:51936] restartPhase [0]
18472 59b8  03/31 09:21:04 403680 Delay Msg  successfully deleteSavedJMJobUpdateMsgByJob
18472 59b8  03/31 09:21:05 403680 JobSvr Obj Phase [4-Backup] for Backup Job Failed. Backup will continue with phase [Backup].
18472 59b8  03/31 09:21:05 403680 ArchiveMgr JMArMgr::releaseAllJobStreams() Calling CvResourceManager::releaseJobStreams(jobType=1,appType=106,logicalRelease=true)
18472 69e0  03/31 09:21:05 403680 Scheduler  Got message to set job state to 3 but the job is not running.
18472 2064  03/31 09:22:21 403680 Scheduler   -> STOPPED <-; Backup job [403680] has been suspended by user ZD\cplsvc-backup. Reason:[]. Client [CPLSV006320], Agent Type [Virtual Server], Subclient [CPLSV006373], Backup Level [Full].
18472 2064  03/31 09:22:21 ###### Servant    Stop operation completed in [0] seconds. Number of jobs affected=[1]

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01
File    : Licensing.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

18472 4d3c  03/31 09:15:28 403680 Check license: appType [106] Name [Virtual Server] on client/library [124]- Valid
18472 4d3c  03/31 09:15:28 ###### License - Type [196] Name [NetApp Snap Management]: Free permanent license available.
18472 4d3c  03/31 09:15:28 ###### License - Type [196] Name [NetApp Snap Management]: available [1000000] used [0]
18472 4d3c  03/31 09:15:28 ###### [1000000] perm license [NetApp Snap Management (196)] are issued
18472 6390  03/31 09:15:40 ###### Number of used license [1] is equal to number of licenses issued [1]. So no free eval licenses available for license type [110]
18472 6390  03/31 09:15:40 ###### License - Type [110] Name [1-Touch Server for MS Windows]: Permanent license is consumed
18472 6a84  03/31 09:16:38 ###### License - Type [196] Name [NetApp Snap Management]: Free permanent license available.
18472 6a84  03/31 09:16:38 ###### License - Type [196] Name [NetApp Snap Management]: available [1000000] used [0]
18472 6a84  03/31 09:16:38 ###### [1000000] perm license [NetApp Snap Management (196)] are issued

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01
File    : ResourceManager.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

18472 5e10  03/31 09:15:55 403680 Resource   Reserved Resource: NoOfReservations [1], StoragePolicy [vm], MediaAgent[cplsvkol-cv01_2], StartNewMedia = false, InterruptedJobList []
18472 59b8  03/31 09:21:05 403680 Resource   Releasing Stream(s) for Job [403680]

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01_2
File    : CreateIndex.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize commserve [CPLSVKOL-CV01]
20188 35e4  03/31 09:16:04 ###### Init() - Initializing job control [token=403680:4:1:0:51936,cn=cplsvkol-cv01_2] in single threaded mode, serverName [CPLSVKOL-CV01], ControlFlag [7], Job Id [403680]
20188 35e4  03/31 09:16:04 403680 TMonitorJob: Registry key MediaAgent\heartBeatPeriod is not present, defaulting to 5 minutes
20188 35e4  03/31 09:16:04 403680 TMonitorJob: Registry key MediaAgent\JMQuitCheckPeriod is not present, defaulting to 1 minute
20188 35e4  03/31 09:16:04 403680 TMonitorJob: Sending XDR heartbeat to keep connection alive
20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize guiOverrideReceived true, guiOverride 1
20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize Remote machine short name: CPLSV006320
20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize createIndex executed for commCell 2 appNumber 231 btype 1 findStart 1617174963 jobToken '403680:4:1:0:51936'
20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize Index cache pruning for aged data will be done as part of centralized cleanup
20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize apptype props: overrideUpdateFlag set to 1, isNewIndexIfFail is false
20188 35e4  03/31 09:16:04 403680 TCreateIndex::initialize INFO: Overriding the create new index to 1 for FULL and SYNTHFULL
20188 35e4  03/31 09:16:04 403680 INDEXCACHEDIR: getAfileIdByTime: commCell 2 app 231 time 2147483647 afile 2/5/410656 type (requested) 2 size 16357364 createTime 1617034283 copyId 0 refCopy 0
20188 35e4  03/31 09:16:04 403680 INDEXCACHEDIR: DiscardUnusable 'I:\IndexDirectory\CV_Index\2\231\1617174505' [2021/03/31 09:16:04 ]
20188 35e4  03/31 09:16:04 ###### DIRECTORY removeFiles from I:\IndexDirectory\CV_Index\2\231\1617174505 (except State)
20188 35e4  03/31 09:16:04 403680 INDEXCACHEDIR: cleanFailedCachedirs: discarding directory I:\IndexDirectory\CV_Index\2\231\1617174505 with time stamp > 1617034283
20188 35e4  03/31 09:16:04 403680 INDEXCACHEDIR: job 403680 creating 'I:\IndexDirectory\CV_Index\2\231\1617174964'

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01_2
File    : CVD.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

24748 55b8  03/31 09:16:04 403680 UICONNECTION Request for JobId 403680 will be handled by service with name [IndexingService_2] id [2]
24748 55b8  03/31 09:16:04 403680 UICONNECTION First stream for job 403680 to [IndexingService_2]
24748 55b8  03/31 09:16:04 403680 IDXMSGHANDLER Received heartbeat from CreateIndex.Forwarding it to backup process
24748 55b8  03/31 09:16:04 ###### IdxMessageHandler::handleIndexingMessageXML [IdxMessageHandler::handleIndexingMessageXML]: Received GetIndexCacheDir
24748 55b8  03/31 09:21:04 ###### IdxMessageHandler::handleIndexingMessageXML [IdxMessageHandler::handleIndexingMessageXML]: Received ProcessFiles

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01_2
File    : IndexCacheCleanup.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

20188 35e4  03/31 09:16:04 403680 INDEXCACHEDIR: DiscardUnusable 'I:\IndexDirectory\CV_Index\2\231\1617174505' [2021/03/31 09:16:04 ]

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv01_2
File    : IndexingService.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

20188 35e4  03/31 09:21:04 403680 ProcessRequestXML Failed to process dirchange for job id403680
20188 42d0  03/31 09:21:49 ###### CheckForRunningJobs Number of requests currently being processed:[1]
20188 42d0  03/31 09:22:49 ###### CheckForRunningJobs Number of requests currently being processed:[1]

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv02_2
File    : vsbkp.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

3928  13a8  03/31 09:15:57 403680 ================================================================
3928  13a8  03/31 09:15:57 403680 = vsbkp [BACKUP PHASE] has started vsbkp.exe -j 403680 -a 2:231 -t 1 -ref 0 -i 2 -d CPLSVKOL-CV01*cplsvkol-cv01_2*8405 -io 1 -jt 403680:4:1:0:51936 -idxma CPLSVKOL-CV01*cplsvkol-cv01_2*8405 -numstreams 1 -cn cplsvkol-cv02_2 -vm Instance002
3928  13a8  03/31 09:15:57 403680 ================================================================
3928  13a8  03/31 09:15:57 403680 CVMDiskInfo::GetVDDKInstallPath() - Using vStorage VDDK from [D:\Commvault\ContentStore2\Base\VMware\VDDK\VDDK673] version [6.7.3 build-14389676]
3928  13a8  03/31 09:15:57 403680 CVMDiskInfo::LoadVDDKLibrary() - Successfully loaded VDDK Lib (libeay32.dll) from D:\Commvault\ContentStore2\Base\VMware\VDDK\VDDK673\bin\libeay32.dll
3928  13a8  03/31 09:15:57 403680 CVMDiskInfo::LoadVDDKLibrary() - Successfully loaded VDDK Lib (ssleay32.dll) from D:\Commvault\ContentStore2\Base\VMware\VDDK\VDDK673\bin\ssleay32.dll
3928  13a8  03/31 09:15:57 403680 CVMDiskInfo::LoadVDDKLibrary() - Successfully loaded VDDK Lib (libldap_r.dll) from D:\Commvault\ContentStore2\Base\VMware\VDDK\VDDK673\bin\libldap_r.dll
3928  13a8  03/31 09:15:57 403680 CVMDiskInfo::LoadVDDKLibrary() - Successfully loaded VDDK Lib (libcurl.dll) from D:\Commvault\ContentStore2\Base\VMware\VDDK\VDDK673\bin\libcurl.dll
3928  13a8  03/31 09:15:57 403680 VSBkpApp::Initialize() - Initializing Job manager object
3928  13a8  03/31 09:15:57 403680 JM Client  CVBkpJobClient::init(): Initializing job object with token [403680:4:1:0:51936].
3928  13a8  03/31 09:15:57 403680 vsJobMgr::_initBackup() - Backup Level  [Full]
3928  13a8  03/31 09:15:59 403680 Cvcl::init() - CVCL: Running in FIPS Mode
3928  13a8  03/31 09:15:59 403680 vsJobMgr::LoadJobOptions() - Job Options: <?xml version='1.0' encoding='utf-8' ?><TMMsg_JobOption><backupOpts truncateLogsOnSource="0" sybaseSkipFullafterLogBkp="0" notSynthesizeFullFromPrevBackup="0" collectMetaInfo="0" backupLevel="1" incLevel="1" adHocBackup="0" runIncrementalBackup="0" isSpHasInLineCopy="0" runSILOBackup="0" doNotTruncateLog="0"><vsaBackupOptions backupFailedVMsOnly="0"/><dataOpt skipCatalogPhaseForSnapBackup="1" useCatalogServer="1" followMountPoints="1" enforceTransactionLogUsage="0" skipConsistencyCheck="0" granularrecovery="0" createNewIndex="1"/><mediaOpt><storagePolicy storagePolicyName="VM"/></mediaOpt><dbBackupOptions maxDbBackupType="0"/></backupOpts><adminOpts><contentIndexingOption subClientBasedAnalytics="0"/></adminOpts><restoreOptions><virtualServerRstOption isBlockLevelReplication="0"/></restoreOptions><commonOpts notifyUserOnJobCompletion="0"><perfJobOpts/></commonOpts></TMMsg_JobOption>
3928  13a8  03/31 09:15:59 403680 Init() - Initializing job control [token=403680:4:1:0:51936,cn=cplsvkol-cv02_2], serverName [CPLSVKOL-CV01], ControlFlag [1], Job Id [403680]
3928  13a8  03/31 09:15:59 403680 CVJobCtrlLog::registerProcess(): successfully created file [D:\Commvault\ContentStore2\Base\JobControl\3.928]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::querySettings() - Found CommCell Id    = [2]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::querySettings() - Found Application Id = [231]
3928  13a8  03/31 09:15:59 403680 isCCSDbEnabled() - Use CCSDb Enabled [0] IsCloudLaptop [0] IsFSCoreLaptop [0]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::querySettings() - Found CommCell Number    = [237549791]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrIds() - Found subclient id = [231] [CPLSV006373]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrIds() - Found Client Id = [124] [CPLSV006320]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryPhysicalClientDetails() - Found Physical Client Id = [9] [cplsvkol-cv02_2]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrIds() - Found Instance id = [34] [VMware]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrIds() - Found backup set id [147] [defaultBackupSet] [26DBB52B-F303-464B-B049-E08DA9FBEF2E]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::querySettings() - IndexingV2 is Disabled
3928  13a8  03/31 09:15:59 403680 vsAppMgr::querySettingsForInstance() - Found instance type (101) 'VMware vCenter'  [VMware]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::querySettingsForInstance() - Using VM Instance UUIDs
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryFBRMA() - Failed to get FBR MA with error [0]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryPhysicalClientDetails() - Found Physical Client Id = [9] [cplsvkol-cv02_2]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryLocalHostName() - Name of the local machine: [CPLSVKOL-CV02.zd.if.atcsg.net]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::updateMemberServersList() - Found member servers [cplsvkol-cv02_2] at level [7]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Found virtual server backup method [Auto-detect]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Subclient backup level: [Disk Level]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryDynamicContent() - VM Content: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><VirtualServer_VMSubClientEntity allOrAnyChildren="0"><children allOrAnyChildren="1" displayName="cplsv006373.zd.if.atcsg.net" equalsOrNotEquals="1" name="cplsv006373.zd.if.atcsg.net" path="cplsv006373.zd.if.atcsg.net" type="1"/><children allOrAnyChildren="1" displayName="CPLSV006666" equalsOrNotEquals="1" name="502bcf54-0402-5df7-373f-7c599fe87d91" path="CPLSV006666" type="9"/></VirtualServer_VMSubClientEntity>]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::GetSubClientFilters() - VM Filter: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><VirtualServer_VMSubClientEntity allOrAnyChildren="0"/>]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::GetSubClientFilters() - VM Disk Filter: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><EVGui_VMSubclientDiskFilterList/>]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Max number of streams to used is [2]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Transport Mode is [0] [auto]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Use Separate proxy for backup copy: [disabled]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Quiesce File system [on]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Quiesce using Simpana [off]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Application aware [Disabled]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Application Validation [Disabled]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Use Changed Block Tracking [on]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Truncate Exchange DB logs [off]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Datastore space values from App Manager:[10]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Didn't get SSH key details from App Manager
3928  13a8  03/31 09:15:59 403680 vsAppMgr::_queryVMArchivingRules() - Reading VM archiving rules
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Skip VM page file or Linux swap partition [on]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Subclient Client group is [0] [-- Select --]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::QueryBLRSettings() - isVMBlockLevelReplicationSet: [0]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Use Backupset Disk Filters [true]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Catalog File Details for Streaming or Backup Copy: [false]
3928  13a8  03/31 09:15:59 403680 vsAppMgr::queryAppMgrProperties() - Catalog File Details for Snapshot Copy: [false]
3928  13a8  03/31 09:15:59 403680 vsJobMgr::initBackupMetadaCollectionDefault() - Collect File Details option for the job as per subclient option: [false]
3928  13a8  03/31 09:16:00 403680 CVMWareInfo::DetectLocalVMGuid() - Bios Serial Number [7NF6LY1]  UUID [4C4C4544-004E-4610-8036-B7C04F4C5931]
3928  13a8  03/31 09:16:00 403680 CVMWareInfo::CVMWareInfo() - VMInfo Focus:[Instance002] App:[vsbkp] JobId:[403680]
3928  13a8  03/31 09:16:00 403680 CVMWareInfo::Connect() - Connecting to Url=[https://CPLSV006320.zd.if.atcsg.net/sdk] User=[ZD\CPLSVC-BACKUP]
3928  13a8  03/31 09:16:03 403680 CVIWrapper::Connect() - Connection successful with [https://CPLSV006320.zd.if.atcsg.net/sdk] [VMware vCenter Server 6.7.0 build-17137327]
3928  13a8  03/31 09:16:03 403680 CVOnDemandSvcClient::SubmitTask() - On Demand service CVODS_vscleanup_cplsvkol-cv02_2_1 launched at host CPLSVKOL-CV02*cplsvkol-cv02_2*8405*8402. Unique ID is 3426EB42-F4D0-4F2E-AA15-17C85AA56843
3928  13a8  03/31 09:16:03 403680 CVOnDemandSvcClient::Attach() - Successfully attached to on Demand service CVODS_vscleanup_cplsvkol-cv02_2_1 launched at host CPLSVKOL-CV02*cplsvkol-cv02_2*8405*8402. Unique ID is 3426EB42-F4D0-4F2E-AA15-17C85AA56843
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::Initialize() - vm collect file = [D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\iDataAgent\VirtualServerAgent\2\231\vmcollect.cvf]
3928  13a8  03/31 09:16:03 403680 JOBRESCACHEDIR: job 403680 creating 'D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\2\0\403680'
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::InitBackupResults() - Backup.out file created
3928  13a8  03/31 09:16:03 403680 vsJobMgr::CheckForRestart() - Empty restart string
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::UpdateJobManagerVMList_Restart() - This is a new job
3928  13a8  03/31 09:16:03 403680 vsJobMgr::updateVMBkpJobStatus() - Sending VM status for [1] virtual machines
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::calculateRequiredStreams() - There are [1] VMs and [1] Disks to be processed by a max of [2] streams
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::calculateRequiredStreams() - All the streams that are required have been allocated
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::InitStreams() - Streams will be distributed by agent resource percentage
3928  13a8  03/31 09:16:03 403680 VSBkpCoordinator::InitAgents() - Adding [cplsvkol-cv02_2] as a local agent
3928  13a8  03/31 09:16:03 403680 CVRemoteAgentServer::AddAgent() - Added Agent [cplsvkol-cv02_2]
3928  13a8  03/31 09:16:03 403680 Thread pool monitor interval [300] seconds, Refresh interval [60] seconds
3928  13a8  03/31 09:16:03 403680 vsIndexClient::createIndex() - Indexing Job options [110] Disk EncodeExtents
3928  13a8  03/31 09:16:03 403680 NoPrefix Heartbeat timeout is set to 1800
3928  13a8  03/31 09:16:03 403680 CreateIndex would be started with the following backup parameters
3928  13a8  03/31 09:16:03 403680 Commcell Id = [2], Subclient Id/App Id = [231], Backup type = [1], Start time = [1617174963], Job token = [403680:4:1:0:51936], Snap job id = [0], Client network host name = [CPLSVKOL-CV02], Gui override = [1], Pruning time = [0], Options = [2049]
3928  13a8  03/31 09:16:03 403680 SENDDIRCHANGE heartbeat timeout is set to 1800 sec
3928  13a8  03/31 09:16:04 403680 ALL systems GO from CreateIndex - send one-line DIR_CHANGE
3928  13a8  03/31 09:16:04 403680 [::sendDirChange]: creating one line dirchange
3928  13a8  03/31 09:16:04 403680 [::sendDirChange]: createindex will process dirchange [D:\Commvault\ContentStore2\Base\Temp\Idx_3928_30877181_2973966081_0]
3928  13a8  03/31 09:21:04 403680 CvCopyFile::CopyFiles() - Failed to copy file; All files are not copied, TimedOut=True, error=0x80070102:{CCvNetwork::PutFileRequest(9047)} + {CCvNetwork::ReceiveXDRMessageInternal(5358)} + {CCvNetwork::CheckIfDataAvailable(5425)/cplsvkol-cv01_2/CPLSVKOL-CV01/SockIP(10.219.0.52)/10.219.0.52:8405/8405 cvd.exe(24748:55d4)} + {CCvNetwork::CheckDataWaiting(6099)} + {CSessionConnectionSocket::CheckDataWaiting(663)/W32.258.(The wait operation timed out. (WAIT_TIMEOUT.258))-Timed out}
3928  13a8  03/31 09:21:04 403680 NoPrefix [SendDirChange::sendFiles]: failed to send dirchange files to IndexingService. Error:[0x80070102:{CvCopyFile::CopyFiles(10551)/Failed to copy files to client (cplsvkol-cv01_2/CPLSVKOL-CV01/SockIP(10.219.0.52)/10.219.0.52:8405/8405 cvd.exe(24748:55d4))} + {CCvNetwork::PutFileRequest(9047)} + {CCvNetwork::ReceiveXDRMessageInternal(5358)} + {CCvNetwork::CheckIfDataAvailable(5425)/cplsvkol-cv01_2/CPLSVKOL-CV01/SockIP(10.219.0.52)/10.219.0.52:8405/8405 cvd.exe(24748:55d4)} + {CCvNetwork::CheckDataWaiting(6099)} + {CSessionConnectionSocket::CheckDataWaiting(663)/W32.258.(The wait operation timed out. (WAIT_TIMEOUT.258))-Timed out}]
3928  13a8  03/31 09:21:04 403680 [::sendDirChange]: failed to transfer files to createIndex
3928  13a8  03/31 09:21:04 403680 SENDCOMPDCHANGE sendDirChange: send component dirchange failed with ret code [-1]
3928  13a8  03/31 09:21:04 403680 vsIndexClient::createIndex() - Send Dir change failed. retCode [-1]
3928  13a8  03/31 09:21:04 403680 VSBkpCoordinator::CreateIndex() - Create Index Failed
3928  13a8  03/31 09:21:04 403680 JOBRESULTSTABLE(D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\2\0\403680\JobResults removing copy 'D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\2\0\403680'
3928  2578  03/31 09:21:04 403680 VSBkpController::ControllerThread() - All [0] VMs have been processed
3928  13a8  03/31 09:21:04 403680 BKP CALLED COMPLETE (PHASE Status::FAIL), 403680. Token [403680:4:1:0:51936]
3928  13fc  03/31 09:21:05 403680 CVJobCtrlLog::unregisterProcess(): successfully removed file [D:\Commvault\ContentStore2\Base\JobControl\3.928]
3928  13a8  03/31 09:21:05 403680 CVMWareInfo::ShutdownVMInfo() - VMDiskInfo::Shutdown complete
3928  13a8  03/31 09:21:05 403680 CVMWareInfo::ShutdownVMInfo() - CVISDKCppBridge::Cleanup complete
3928  13a8  03/31 09:21:05 403680 = [vsbkp] has exited [BACKUP PHASE]
3928  13a8  03/31 09:21:05 403680 ================================================================

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv02_2
File    : VSCLEANUP.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

8644  1e00  03/31 09:16:03 403680 VSCleanupService::RegisterProcess Register process [3928]
8644  239c  03/31 09:16:06 ###### TPool [IOCPServerPool_CVODS_vscleanup_cplsvkol-cv02_2_1]. Ser# [1] Tot [12], Pend [12], Comp [0], Max Par [0], Time (Serial) [0.000000]s, Time (Parallel) [0.000000]s, Wait [0.000000]s
8644  239c  03/31 09:16:06 ###### TPool [CVODS_vscleanup_cplsvkol-cv02_2_1]. Ser# [0] Tot [1], Pend [0], Comp [1], Max Par [1], Time (Serial) [0.000041]s, Time (Parallel) [0.000041]s, Wait [0.000093]s
8644  239c  03/31 09:16:06 ###### TPool [CVODS_vscleanup_cplsvkol-cv02_2_1]. Ser# [1] Tot [3], Pend [0], Comp [3], Max Par [1], Time (Serial) [0.002433]s, Time (Parallel) [0.002433]s, Wait [0.000304]s
8644  1e00  03/31 09:21:04 403680 VSCleanupService::UnregisterProcess Unregister process [3928]
8644  239c  03/31 09:21:06 ###### TPool [IOCPServerPool_CVODS_vscleanup_cplsvkol-cv02_2_1]. Ser# [1] Tot [12], Pend [12], Comp [0], Max Par [0], Time (Serial) [0.000000]s, Time (Parallel) [0.000000]s, Wait [0.000000]s
8644  239c  03/31 09:21:06 ###### TPool [CVODS_vscleanup_cplsvkol-cv02_2_1]. Ser# [0] Tot [1], Pend [0], Comp [1], Max Par [1], Time (Serial) [0.000038]s, Time (Parallel) [0.000038]s, Wait [0.000092]s
8644  239c  03/31 09:21:06 ###### TPool [CVODS_vscleanup_cplsvkol-cv02_2_1]. Ser# [1] Tot [5], Pend [0], Comp [5], Max Par [1], Time (Serial) [0.005461]s, Time (Parallel) [0.005461]s, Wait [0.000513]s

 

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : cplsvkol-cv02_2
File    : vsdiscovery.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

10172 256c  03/31 09:15:34 403680 ================================================================
10172 256c  03/31 09:15:34 403680 = vsdiscovery has started vsdisc.exe -j 403680 -a 2:231 -t 1 -ref 0 -i 2 -d CPLSVKOL-CV01*cplsvkol-cv01_2*8405 -io 1 -jt 403680:2:1:0:51936 -idxma CPLSVKOL-CV01*cplsvkol-cv01_2*8405 -numstreams 0 -cn cplsvkol-cv02_2 -vm Instance002
10172 256c  03/31 09:15:34 403680 ================================================================
10172 256c  03/31 09:15:34 403680 VSDiscApp::Initialize() - Initializing Job manager object
10172 256c  03/31 09:15:34 403680 JM Client  CVBkpJobClient::init(): Initializing job object with token [403680:2:1:0:51936].
10172 256c  03/31 09:15:34 403680 vsJobMgr::_initBackup() - Backup Level  [Full]
10172 256c  03/31 09:15:36 403680 Cvcl::init() - CVCL: Running in FIPS Mode
10172 256c  03/31 09:15:36 403680 vsJobMgr::LoadJobOptions() - Job Options: <?xml version='1.0' encoding='utf-8' ?><TMMsg_JobOption><backupOpts truncateLogsOnSource="0" sybaseSkipFullafterLogBkp="0" notSynthesizeFullFromPrevBackup="0" collectMetaInfo="0" backupLevel="1" incLevel="1" adHocBackup="0" runIncrementalBackup="0" isSpHasInLineCopy="0" runSILOBackup="0" doNotTruncateLog="0"><vsaBackupOptions backupFailedVMsOnly="0"/><dataOpt skipCatalogPhaseForSnapBackup="1" useCatalogServer="1" followMountPoints="1" enforceTransactionLogUsage="0" skipConsistencyCheck="0" granularrecovery="0" createNewIndex="1"/><mediaOpt><storagePolicy storagePolicyName="VM"/></mediaOpt><dbBackupOptions maxDbBackupType="0"/></backupOpts><adminOpts><contentIndexingOption subClientBasedAnalytics="0"/></adminOpts><restoreOptions><virtualServerRstOption isBlockLevelReplication="0"/></restoreOptions><commonOpts notifyUserOnJobCompletion="0"><perfJobOpts/></commonOpts></TMMsg_JobOption>
10172 256c  03/31 09:15:36 403680 Init() - Initializing job control [token=403680:2:1:0:51936,cn=cplsvkol-cv02_2], serverName [CPLSVKOL-CV01], ControlFlag [1], Job Id [403680]
10172 256c  03/31 09:15:36 403680 CVJobCtrlLog::registerProcess(): successfully created file [D:\Commvault\ContentStore2\Base\JobControl\10.172]
10172 256c  03/31 09:15:36 403680 vsAppMgr::querySettings() - Found CommCell Id    = [2]
10172 256c  03/31 09:15:36 403680 vsAppMgr::querySettings() - Found Application Id = [231]
10172 256c  03/31 09:15:36 403680 isCCSDbEnabled() - Use CCSDb Enabled [0] IsCloudLaptop [0] IsFSCoreLaptop [0]
10172 256c  03/31 09:15:36 403680 vsAppMgr::querySettings() - Found CommCell Number    = [237549791]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrIds() - Found subclient id = [231] [CPLSV006373]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrIds() - Found Client Id = [124] [CPLSV006320]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryPhysicalClientDetails() - Found Physical Client Id = [9] [cplsvkol-cv02_2]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrIds() - Found Instance id = [34] [VMware]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrIds() - Found backup set id [147] [defaultBackupSet] [26DBB52B-F303-464B-B049-E08DA9FBEF2E]
10172 256c  03/31 09:15:36 403680 vsAppMgr::querySettings() - IndexingV2 is Disabled
10172 256c  03/31 09:15:36 403680 vsAppMgr::querySettingsForInstance() - Found instance type (101) 'VMware vCenter'  [VMware]
10172 256c  03/31 09:15:36 403680 vsAppMgr::querySettingsForInstance() - Using VM Instance UUIDs
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryFBRMA() - Failed to get FBR MA with error [0]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryPhysicalClientDetails() - Found Physical Client Id = [9] [cplsvkol-cv02_2]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryLocalHostName() - Name of the local machine: [CPLSVKOL-CV02.zd.if.atcsg.net]
10172 256c  03/31 09:15:36 403680 vsAppMgr::updateMemberServersList() - Found member servers [cplsvkol-cv02_2] at level [7]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Found virtual server backup method [Auto-detect]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Subclient backup level: [Disk Level]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryDynamicContent() - VM Content: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><VirtualServer_VMSubClientEntity allOrAnyChildren="0"><children allOrAnyChildren="1" displayName="cplsv006373.zd.if.atcsg.net" equalsOrNotEquals="1" name="cplsv006373.zd.if.atcsg.net" path="cplsv006373.zd.if.atcsg.net" type="1"/><children allOrAnyChildren="1" displayName="CPLSV006666" equalsOrNotEquals="1" name="502bcf54-0402-5df7-373f-7c599fe87d91" path="CPLSV006666" type="9"/></VirtualServer_VMSubClientEntity>]
10172 256c  03/31 09:15:36 403680 vsAppMgr::GetSubClientFilters() - VM Filter: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><VirtualServer_VMSubClientEntity allOrAnyChildren="0"/>]
10172 256c  03/31 09:15:36 403680 vsAppMgr::GetSubClientFilters() - VM Disk Filter: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><EVGui_VMSubclientDiskFilterList/>]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Max number of streams to used is [2]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Transport Mode is [0] [auto]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Use Separate proxy for backup copy: [disabled]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Quiesce File system [on]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Quiesce using Simpana [off]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Application aware [Disabled]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Application Validation [Disabled]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Use Changed Block Tracking [on]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Truncate Exchange DB logs [off]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Datastore space values from App Manager:[10]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Didn't get SSH key details from App Manager
10172 256c  03/31 09:15:36 403680 vsAppMgr::_queryVMArchivingRules() - Reading VM archiving rules
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Skip VM page file or Linux swap partition [on]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Subclient Client group is [0] [-- Select --]
10172 256c  03/31 09:15:36 403680 vsAppMgr::QueryBLRSettings() - isVMBlockLevelReplicationSet: [0]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Use Backupset Disk Filters [true]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Catalog File Details for Streaming or Backup Copy: [false]
10172 256c  03/31 09:15:36 403680 vsAppMgr::queryAppMgrProperties() - Catalog File Details for Snapshot Copy: [false]
10172 256c  03/31 09:15:36 403680 vsJobMgr::initBackupMetadaCollectionDefault() - Collect File Details option for the job as per subclient option: [false]
10172 256c  03/31 09:15:37 403680 CVMWareInfo::DetectLocalVMGuid() - Bios Serial Number [7NF6LY1]  UUID [4C4C4544-004E-4610-8036-B7C04F4C5931]
10172 256c  03/31 09:15:37 403680 CVMWareInfo::CVMWareInfo() - VMInfo Focus:[Instance002] App:[vsdiscovery] JobId:[403680]
10172 256c  03/31 09:15:37 403680 CVMWareInfo::Connect() - Connecting to Url=[https://CPLSV006320.zd.if.atcsg.net/sdk] User=[ZD\CPLSVC-BACKUP]
10172 256c  03/31 09:15:41 403680 CVIWrapper::Connect() - Connection successful with [https://CPLSV006320.zd.if.atcsg.net/sdk] [VMware vCenter Server 6.7.0 build-17137327]
10172 256c  03/31 09:15:41 403680 VSDisc::DiscoverVMs() - --- Discovering VMs ---
10172 256c  03/31 09:15:41 403680 vsAppMgr::getDoNotBackupSubclientContent() - DoNotBackup Content: [<?xml version="1.0" encoding="UTF-8" standalone="no" ?><VirtualServer_VMSubClientEntity allOrAnyChildren="0"/>]
10172 256c  03/31 09:15:41 403680 VSDisc::ProcessSubClientContent() - Processing Subclient [CPLSV006373] Content
10172 256c  03/31 09:15:41 403680 VSDisc::ProcessContent_Host() - Processing Host [cplsv006373.zd.if.atcsg.net][cplsv006373.zd.if.atcsg.net] on [CPLSV006320.zd.if.atcsg.net]
10172 256c  03/31 09:15:42 403680 VSDisc::ProcessContent_Host() - Found [1] VMs from Host [cplsv006373.zd.if.atcsg.net][cplsv006373.zd.if.atcsg.net] on [CPLSV006320.zd.if.atcsg.net]
10172 256c  03/31 09:15:42 403680 VSDisc::_ProcessContent_Item() - Discovered [1] VMs from item [cplsv006373.zd.if.atcsg.net] on server [CPLSV006320.zd.if.atcsg.net]
10172 256c  03/31 09:15:42 403680 VSDisc::UnionVMCollectList() - Unioned [0] and [1] VMs to [1]
10172 256c  03/31 09:15:42 403680 VSDisc::ProcessContent_VM() - Processing VM [CPLSV006666][502bcf54-0402-5df7-373f-7c599fe87d91]
10172 256c  03/31 09:15:42 403680 VSDisc::_ProcessContent_Item() - Discovered [1] VMs from item [CPLSV006666] on server [CPLSV006320.zd.if.atcsg.net]
10172 256c  03/31 09:15:42 403680 VSDisc::UnionVMCollectList() - Unioned [1] and [1] VMs to [1]
10172 256c  03/31 09:15:42 403680 VSDisc::ProcessContent_List() - Discovered [1] VMs from List
10172 256c  03/31 09:15:42 403680 VSDisc::ProcessSubClientContent() - Finished Processing Subclient [CPLSV006373]
10172 256c  03/31 09:15:42 403680 VSDisc::DiscoverVMs() - --- Discovered [1] VMs ---
10172 256c  03/31 09:15:42 403680 VSDisc::DumpVMCollectList() - VM: [CPLSV006666] Guid: [502bcf54-0402-5df7-373f-7c599fe87d91] Host: [cplsv006373.zd.if.atcsg.net]
10172 256c  03/31 09:15:42 403680 VSDisc::DiscoverVMs() - ---------------------------
10172 256c  03/31 09:15:42 403680 VSDisc::UpdateVMInfoListDetails() - Updating VM Details
10172 256c  03/31 09:15:43 403680 VSDisc::UpdateVMInfoListDetails() - Updated details for 1 VMs in the map
10172 256c  03/31 09:15:43 403680 VSDisc::AddVMsAsSimpanaClients() - Creating VM Clients
10172 256c  03/31 09:15:43 403680 vsSim::createVMClients() - Successfully created DB for local caching of client Information.
10172 256c  03/31 09:15:43 403680 vsSim::createVMClients() - VM [502bcf54-0402-5df7-373f-7c599fe87d91] is not found in local cache
10172 256c  03/31 09:15:43 403680 vsSim::createVMClients() - Discovered VM Statistics with respect to local client cache: #Discovered=[1] #NewVM=[1] #VMWithChangedInfo=[0] #VMUnchanged=[0]
10172 256c  03/31 09:15:43 403680 VirtualDiscoveredClientCreation::Initialize() -  commcell Id is zero, using 2
10172 256c  03/31 09:15:43 403680 VirtualDiscoveredClientCreation::Initialize() - client group [CPLSV006320] commcellId [2]
10172 256c  03/31 09:15:43 403680 NetMessage::verifyResponseHeader() - Error Code [50209] returned - [Data already exists].
10172 256c  03/31 09:15:43 403680 ClientGroupInterfaceNet::create() - Data already exists
10172 256c  03/31 09:15:43 403680 vsSim::createVMClient100() - Calling VirtualDiscoveredClientCreation::Execute()...
10172 256c  03/31 09:15:45 403680 vsSim::createVMClient100() - Done with VirtualDiscoveredClientCreation::Execute()...
10172 256c  03/31 09:15:45 403680 VSDisc::AddVMsAsSimpanaClients() - Updating Client IDs for VMs
10172 256c  03/31 09:15:45 403680 VSDisc::AddVMsAsSimpanaClients() - Overwriting subclientId [231] under 1 VM Clients to populate Client Group associated with this subclient.
10172 256c  03/31 09:15:45 403680 vsSim::SetSubClientIdForVMClients() - Setting the VSA subclientId for each discovered VM client.
10172 256c  03/31 09:15:45 403680 VSDisc::WriteVMCollectFile() - Discover VM collect file path: [D:\Commvault\ContentStore2\iDataAgent\JobResults\CV_JobResults\iDataAgent\VirtualServerAgent\2\231\vmcollect.cvf]
10172 256c  03/31 09:15:45 403680 VSDisc::Run() - Discovery completed successfully
10172 256c  03/31 09:15:45 403680 BKP CALLED COMPLETE (PHASE Status::SUCCESS), 403680. Token [403680:2:1:0:51936]
10172 256c  03/31 09:15:46 403680 CVMWareInfo::ShutdownVMInfo() - VMDiskInfo::Shutdown complete
10172 256c  03/31 09:15:46 403680 CVMWareInfo::ShutdownVMInfo() - CVISDKCppBridge::Cleanup complete
10172 256c  03/31 09:15:46 403680 = vsdiscovery has exited
10172 256c  03/31 09:15:46 403680 ================================================================
10172 12dc  03/31 09:15:46 403680 CVJobCtrlLog::unregisterProcess(): successfully removed file [D:\Commvault\ContentStore2\Base\JobControl\10.172]


 

icon

Best answer by Kamil W 31 March 2021, 16:11

View original

2 replies

Userlevel 4
Badge +11

Hi Guys,

 

Problem’s resolved.

Restart Media Agent Services (Instance002) did work.

 

Rgds,

Kamil

Userlevel 7
Badge +23

Appreciate you sharing back!  If it reoccurs, let us know (I can always unmark the best answer).

Reply