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]
Best answer by Kamil W
View original
