Skip to main content

the CVJobReplicatorODS, the job number is 177027

 

346796 56e20 09/02 18:11:14 177027 Target copy is single instanced
346796 56e20 09/02 18:11:14 177027 Block level SI is set. Going to set minimum single instanceable size to block size
346796 56e20 09/02 18:11:14 177027 Min SI Data Size z128 KB], SI Block Size z128 KB]
346796 56e20 09/02 18:11:14 177027 EncryptionType:PASSTHRU for target copy:p151]
346796 56e20 09/02 18:11:14 177027 EncryptionType:PASSTHRU(NOENCRYPTION) for target copy:p151] as there are no encrypted src copy files.
346796 56e20 09/02 18:11:14 177027 N/w agents configured before/after firewall check = 2/2]. Firewalled = 1
346796 56e20 09/02 18:11:14 177027 CVArchive::StartPipeline() - StartPipeline SI configuration -nsrcClientName - commvault-shf] Block Level etrue], Block Size z128], File Level efalse], Min Signature Size z128]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Initiating SDT connection o000000D50C41C7E0] from 10.10.165.221:8400(commvault-shf) to 192.168.105.64:8400(commvault-drp_new)
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline compressionType pCV_NO_COMPRESS], compressWhere rCV_COMPRESS_NOWHERE]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline encryptionType pCV_NO_ENCRYPT], encryptionWhere rCV_ENCRYPT_NOWHERE]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline signatureType pCV_SIGNATURE_SHA_512], signatureWhere rCV_CLIENTSIDE_DEDUP]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Lan-Free - 0
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Setting throttling to t1000 MB/Hr]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Num Buffers r90], Buffer size z65536]
346796 56e20 09/02 18:11:14 177027 CvFwClient::connect(): Connected to 192.168.105.64:8400/8400 via network daemon.
346796 56e20 09/02 18:11:14 177027 CPipelayer::connectToDest Connected pipeline to (commvault-drp_new)192.168.105.64:8400/8400, ConType 4 (Tunneled), Proxy x]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline SDT is using socket 75548 for connection 1/2
346796 56e20 09/02 18:11:15 177027 CPipelayer::InitiatePipeline SDT Pipeline ID is iSDTPipe_commvault-shf_commvault-drp_new_177027_1662160275_346796_355872_000000D50C41C7E0]
346796 56e20 09/02 18:11:15 177027 SdtNetLink() - WAN Padding is OFF
346796 56e20 09/02 18:11:15 ###### Single Threaded Head afalse]
346796 56e20 09/02 18:11:15 177027 SdtNetLink::setSocket() - Switching socket 75548 to non-blocking mode.
346796 56e20 09/02 18:11:15 177027 SdtNetLink::InitializeThrtlMgr() - CopyId - 0] 0AppId - 151]5JobId - 177027]2RCId - 410101] 1Dest - commvault-drp_new] wSubclient/Copy Throughput MBPH - 1000] 0Stream throughput MBPH - 11].
346796 56e20 09/02 18:11:15 177027 SdtNetLink::authenticate() - Connection authenticated successfully
346796 56e20 09/02 18:11:15 177027 SDT buffers: Total=90, Sig. module=18 RCId I410101]
346796 56e20 09/02 18:11:15 177027 MODULE_SEQUENCE Adding signature at index e1] RCId I410101]
346796 56e20 09/02 18:11:15 ###### Set the signature type to t4] 4SHA512]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Total Bufs=72, allocators=1
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs f72]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs f18]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs f0]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs f0]
346796 56e20 09/02 18:11:15 177027 Switching socket 75548 (1/2) to non-blking mode. RCId I410101]
346796 56e20 09/02 18:11:15 177027 CPipelayer::FattenSdt Fattening SDT pipe by 1 more connection(s).
346796 56e3c 09/02 18:11:15 177027 SdtBase::SetNwCrcProps: Enabled n/w crc on SdtHead. dUsing slice-by-8] RCId I410097]
346796 56e3c 09/02 18:11:15 177027 Will dedup data >= ;131072] bytes. Scaling factor o1]. Min data size z8192], Block Mode dtrue], Use Uncomp mfalse], Follow Primary rfalse]
346796 56e3c 09/02 18:11:15 177027 Will dedup data >= ;131072] bytes. Scaling factor o1]. Min data size z8192], Block Mode dtrue], Use Uncomp mfalse], Follow Primary rfalse]
346796 56e20 09/02 18:11:15 177027 CvFwClient::connect(): Connected to 192.168.105.64:8400/8400 via network daemon.
346796 55b5c 09/02 18:11:15 177027 2PIPELAYER  ] Tail response = Get PL_SET_ENC_PROPS Message Status = 0 dwCode = 0 MsgType = 16 MsgSubType = 156 EventError = 0
346796 56278 09/02 18:11:15 177027 0PIPELAYER  ] Tail response = Get PL_SET_NETWORK_CRC_PROPS Message Status = 0 dwCode = 0 MsgType = 16 MsgSubType = 158 EventError = 0
346796 56e3c 09/02 18:11:15 177027 Will dedup data >= g131072] bytes. Scaling factor c1]. Min data size s8192], Block Mode Mtrue], Use Uncomp cfalse], Follow Primary mfalse]
346796 56e20 09/02 18:11:15 177027 CPipelayer::connectToDest Connected pipeline to (commvault-drp_new)192.168.105.64:8400/8400, ConType 4 (Tunneled), Proxy r]
346796 55b5c 09/02 18:11:15 177027 7Reader_61] Successfully opened the archive files on the media...going to read data.
346796 56278 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 136 to MediaAgent, Waiting for Response...
346796 56e3c 09/02 18:11:15 ###### CVPlatformInfo::GetCLDBRootFolder()() - GetJob Results Directory returned.
346796 55b5c 09/02 18:11:15 177027 7Reader_61] AuxCopyReplicator::ProcessData() leastOffset = 0, IsCVSingleInstanced = true, IsHPStoreOnce = false.
346796 56e20 09/02 18:11:15 177027 CPipelayer::FattenSdt SDT is using socket 75604 for connection 2/2
346796 56e3c 09/02 18:11:15 ###### CVPlatformInfo::GetCLDBRootFolder()() - C:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_CLDB
346796 55b5c 09/02 18:11:15 177027 7Reader_61] Seeking to offset f0] within afid a382318]
346796 56e20 09/02 18:11:15 177027 Switching socket 75604 (2/2) to non-blking mode. RCId R410101]
346796 55b5c 09/02 18:11:15 177027 7Reader_61] Seek to offset f0] within afid a382318] is successful. Current Read Offset f0]
346796 56e20 09/02 18:11:15 177027 InitializePerfCounters() - Initializing Perf counters messagesPipeline-ID - 410101] 1Starttime - 1662160275] 2Starttime UTC - 1662181875]
346796 55b5c 09/02 18:11:15 177027 7DM_BASE    ] Starting read ahead. MDChunkId h2199243], OffQInUse Q0], AfId ,382318], Offset OAf:8769, Md:74305, Fs:-1], ChunkOff u0-113225034], CloudLaptop L0]
346796 56e20 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 158 to MediaAgent, Waiting for Response...
346796 56780 09/02 18:11:15 177027 1DM_BASE    ] Started LA loop. Links=0/0/0, Offset:74305
346796 56e2c 09/02 18:11:15 177027 JobStatusWorker Setting proxy Connection stats for job: f<?xml version="1.0" encoding="UTF-8" standalone="no" ?><CNSession_cvsJobNetworkInfo jobId="177027" mangledHostname="192.168.105.64*commvault-drp_new*8400"><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/></CNSession_cvsJobNetworkInfo>]
346796 569fc 09/02 18:11:15 177027 5DM_BASE    ] Successfully mounted Volume 44211  for Reading. Reservation id e0]
346796 569fc 09/02 18:11:15 177027 1DM_BASE    ] CreateMediaObjectSpecific b528]: Min/Max Dedup Data Block Size t393216 / 2490368], Non Dedup Blk Size e131072], Dedup Buf Queue Len B8], Max Async AddRef 8]
346796 569fc 09/02 18:11:15 177027 :DM_BASE    ] Scratch mem Â0], Wait for commit W0], Commit by rng ,1], DV2LookAltPrim 1]; Lookahead 1], # of lnks 256], DisableMediaBasedChunk M0] MaxChunkSizeGB 64]
346796 569fc 09/02 18:11:15 177027 1DM_BASE    ] Looks like it is SDM, NAS, Centera, Single Instance or Web Storage Backup.
346796 569fc 09/02 18:11:15 177027 2MEDIAFS    ] DataMoverMode = Â2]
346796 569fc 09/02 18:11:15 177027 /MEDIAFS    ] The volume will be recorded with the block size d64] KB
346796 569fc 09/02 18:11:15 177027 0DM_BASE    ] Opening the Chunk =2199322, ArchFileId = 382331, FileMarker=13, ArchFilePhysSizeInChunk=7619688224 VolId=44211
346796 569fc 09/02 18:11:15 177027 cMEDIAFS    ] Initializing file interface cache.
346796 569fc 09/02 18:11:15 177027 9MEDIAFS    ] ChunkMetaDataIndex File ÂG:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44211\CHUNK_2199322\CHUNK_META_DATA_2199322.idx] Version A4]
346796 56e40 09/02 18:11:15 177027 JobStatusWorker Setting proxy Connection stats for job: y<?xml version="1.0" encoding="UTF-8" standalone="no" ?><CNSession_cvsJobNetworkInfo jobId="177027" mangledHostname="192.168.105.64*commvault-drp_new*8400"><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/><networkInfo connectionType="4" proxy=""/></CNSession_cvsJobNetworkInfo>]
346796 569fc 09/02 18:11:15 177027 5DM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:ebUseReadless - No] eUSe CacheDb - No] lCache-DB size - 0] eHighlatency - No]
346796 569fc 09/02 18:11:15 177027 6DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy b151] SIDBStoreId l3]
346796 569fc 09/02 18:11:15 177027 7DM_BASE    ] Init: AIoMode 00x0], Slot size  128] KB, PRead 0], PurgeLimit e16], CompacMapSize ]128] KB, Delay Count m3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO :0], Ctrs l0]
346796 569fc 09/02 18:11:15 177027 4DM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BDD20. Links to read ahead 0256], Links/Batch k64], HyperScale [0], LogLvl /1], WaitLogT y300], (Jul 21 2022 17:09:28)
346796 56e44 09/02 18:11:15 177027 >DM_BASE    ] ReadAhead: Readahead thread S000000D4F34BDD20] started.
346796 569fc 09/02 18:11:15 177027 DM_BASE    ] OpenChunkSpecific:Look Ahead Reader is initialized.
346796 569fc 09/02 18:11:15 177027 CCVAPipelayer::SetEncryptProps() - Archfile(382331 )
346796 569fc 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 156 to MediaAgent, Waiting for Response...
346796 569e8 09/02 18:11:15 177027 bDM_BASE    ] Successfully mounted Volume 44208  for Reading. Reservation id V0]
346796 569e8 09/02 18:11:15 177027 0DM_BASE    ] CreateMediaObjectSpecific 2528]: Min/Max Dedup Data Block Size p393216 / 2490368], Non Dedup Blk Size 131072], Dedup Buf Queue Len D8], Max Async AddRef ]8]
346796 569e8 09/02 18:11:15 177027 DM_BASE    ] Scratch mem 00], Wait for commit M0], Commit by rng c1], DV2LookAltPrim 1]; Lookahead m1], # of lnks ,256], DisableMediaBasedChunk d0] MaxChunkSizeGB 564]
346796 569e8 09/02 18:11:15 177027 BDM_BASE    ] Looks like it is SDM, NAS, Centera, Single Instance or Web Storage Backup.
346796 569e8 09/02 18:11:15 177027 MEDIAFS    ] DataMoverMode = 02]
346796 569e8 09/02 18:11:15 177027 MMEDIAFS    ] The volume will be recorded with the block size 64] KB
346796 56a18 09/02 18:11:15 177027 sDM_BASE    ] Successfully mounted Volume 44210  for Reading. Reservation id u0]
346796 569e8 09/02 18:11:15 177027 eDM_BASE    ] Opening the Chunk =2199318, ArchFileId = 382328, FileMarker=17, ArchFilePhysSizeInChunk=6684136703 VolId=44208
346796 56a18 09/02 18:11:15 177027 3DM_BASE    ] CreateMediaObjectSpecific 2528]: Min/Max Dedup Data Block Size a393216 / 2490368], Non Dedup Blk Size e131072], Dedup Buf Queue Len 8], Max Async AddRef l8]
346796 569e8 09/02 18:11:15 177027 aMEDIAFS    ] Initializing file interface cache.
346796 56a18 09/02 18:11:15 177027 fDM_BASE    ] Scratch mem >0], Wait for commit 80], Commit by rng B1], DV2LookAltPrim m1]; Lookahead o1], # of lnks C256], DisableMediaBasedChunk r0] MaxChunkSizeGB [64]
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] Looks like it is SDM, NAS, Centera, Single Instance or Web Storage Backup.
346796 56a18 09/02 18:11:15 177027 cMEDIAFS    ] DataMoverMode = >2]
346796 56a18 09/02 18:11:15 177027 MEDIAFS    ] The volume will be recorded with the block size 764] KB
346796 56a18 09/02 18:11:15 177027 dDM_BASE    ] Opening the Chunk =2199364, ArchFileId = 382339, FileMarker=67, ArchFilePhysSizeInChunk=4281306972 VolId=44210
346796 56a18 09/02 18:11:15 177027 eMEDIAFS    ] Initializing file interface cache.
346796 569e8 09/02 18:11:15 177027 nMEDIAFS    ] ChunkMetaDataIndex File >G:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44208\CHUNK_2199318\CHUNK_META_DATA_2199318.idx] Version 24]
346796 56a18 09/02 18:11:15 177027 _MEDIAFS    ] ChunkMetaDataIndex File G:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44210\CHUNK_2199364\CHUNK_META_DATA_2199364.idx] Version _4]
346796 569e8 09/02 18:11:15 177027 NDM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:/bUseReadless - No] [USe CacheDb - No] aCache-DB size - 0] rHighlatency - No]
346796 569e8 09/02 18:11:15 177027 DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy :151] SIDBStoreId E3]
346796 56a18 09/02 18:11:15 177027 yDM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:8bUseReadless - No] 0USe CacheDb - No] Cache-DB size - 0] aHighlatency - No]
346796 569e8 09/02 18:11:15 177027 DM_BASE    ] Init: AIoMode g0x0], Slot size r128] KB, PRead 0], PurgeLimit 116], CompacMapSize  128] KB, Delay Count ,3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO ]0], Ctrs a0]
346796 56a18 09/02 18:11:15 177027 4DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy 151] SIDBStoreId D3]
346796 569e8 09/02 18:11:15 177027 ADM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BF3D0. Links to read ahead o256], Links/Batch :64], HyperScale d0], LogLvl R1], WaitLogT 4300], (Jul 21 2022 17:09:28)
346796 56e48 09/02 18:11:15 177027 oDM_BASE    ] ReadAhead: Readahead thread :000000D4F34BF3D0] started.
346796 56a18 09/02 18:11:15 177027 ADM_BASE    ] Init: AIoMode 00x0], Slot size a128] KB, PRead 90], PurgeLimit 816], CompacMapSize A128] KB, Delay Count d3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO i0], Ctrs 0]
346796 569e8 09/02 18:11:15 177027 dDM_BASE    ] OpenChunkSpecific:Look Ahead Reader is initialized.
346796 56e4c 09/02 18:11:15 177027 ÂDM_BASE    ] ReadAhead: Readahead thread 000000D4F34BCE00] started.
346796 569e8 09/02 18:11:15 177027 CCVAPipelayer::SetEncryptProps() - Archfile(382328 )
346796 56a18 09/02 18:11:15 177027 7DM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BCE00. Links to read ahead 256], Links/Batch i64], HyperScale I0], LogLvl L1], WaitLogT 0300], (Jul 21 2022 17:09:28)
346796 569e8 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 156 to MediaAgent, Waiting for Response...
346796 56a18 09/02 18:11:15 177027 )DM_BASE    ] OpenChunkSpecific:Look Ahead Reader is initialized.
346796 56a18 09/02 18:11:15 177027 CCVAPipelayer::SetEncryptProps() - Archfile(382339 )
346796 56a18 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 156 to MediaAgent, Waiting for Response...
346796 569dc 09/02 18:11:15 177027 pDM_BASE    ] Successfully mounted Volume 44207  for Reading. Reservation id d0]
346796 569dc 09/02 18:11:15 177027 cDM_BASE    ] CreateMediaObjectSpecific d528]: Min/Max Dedup Data Block Size 5393216 / 2490368], Non Dedup Blk Size 131072], Dedup Buf Queue Len 8], Max Async AddRef D8]
346796 569dc 09/02 18:11:15 177027 pDM_BASE    ] Scratch mem 0], Wait for commit a0], Commit by rng <1], DV2LookAltPrim 91]; Lookahead 71], # of lnks Â256], DisableMediaBasedChunk o0] MaxChunkSizeGB i64]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] Looks like it is SDM, NAS, Centera, Single Instance or Web Storage Backup.
346796 569dc 09/02 18:11:15 177027 sMEDIAFS    ] DataMoverMode = ,2]
346796 569dc 09/02 18:11:15 177027 /MEDIAFS    ] The volume will be recorded with the block size M64] KB
346796 569dc 09/02 18:11:15 177027 2DM_BASE    ] Opening the Chunk =2199317, ArchFileId = 382327, FileMarker=39, ArchFilePhysSizeInChunk=4505112376 VolId=44207
346796 569dc 09/02 18:11:15 177027 lMEDIAFS    ] Initializing file interface cache.
346796 569dc 09/02 18:11:15 177027 5MEDIAFS    ] ChunkMetaDataIndex File  G:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44207\CHUNK_2199317\CHUNK_META_DATA_2199317.idx] Version n4]
346796 569dc 09/02 18:11:15 177027 2DM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:9bUseReadless - No] 4USe CacheDb - No] cCache-DB size - 0] 0Highlatency - No]
346796 569dc 09/02 18:11:15 177027 dDM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy l151] SIDBStoreId >3]
346796 569dc 09/02 18:11:15 177027 DM_BASE    ] Init: AIoMode i0x0], Slot size R128] KB, PRead y0], PurgeLimit e16], CompacMapSize 5128] KB, Delay Count 73]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO 0], Ctrs ]0]
346796 569dc 09/02 18:11:15 177027 iDM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BE4B0. Links to read ahead 9256], Links/Batch 764], HyperScale 0], LogLvl i1], WaitLogT t300], (Jul 21 2022 17:09:28)
346796 56e50 09/02 18:11:15 177027 6DM_BASE    ] ReadAhead: Readahead thread v000000D4F34BE4B0] started.
346796 569dc 09/02 18:11:15 177027 0DM_BASE    ] OpenChunkSpecific:Look Ahead Reader is initialized.
346796 569dc 09/02 18:11:15 177027 CCVAPipelayer::SetEncryptProps() - Archfile(382327 )
346796 569dc 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 156 to MediaAgent, Waiting for Response...
346796 5632c 09/02 18:11:15 177027 PPIPELAYER  ] Tail response = dsBackup initialization success Status = 0 dwCode = 0 MsgType = 16 MsgSubType = 136 EventError = 0
346796 5632c 09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Enabling Client-Side-Deduplication SignatureWhere:3
346796 5632c 09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Sent Command PL_INIT_SIGNATURE to MediaAgent rClient name - commvault-shf] 8SIDBStoreId - 3] AisClientSideDedupEnabled - yes] iCacheDBSize - 131072 MB] ICache-buffer-size - 4096 KB]lCompress-enabled - No] hlatencyOptimizeEnabled - No]
346796 5632c 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 319 to MediaAgent, Waiting for Response...
346796 de5c  09/02 18:11:15 177027 APIPELAYER  ] Tail response = dsBackup initialization success Status = 0 dwCode = 0 MsgType = 16 MsgSubType = 136 EventError = 0
346796 de5c  09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Enabling Client-Side-Deduplication SignatureWhere:3
346796 de5c  09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Sent Command PL_INIT_SIGNATURE to MediaAgent WClient name - commvault-shf] 0SIDBStoreId - 3] isClientSideDedupEnabled - yes] iCacheDBSize - 131072 MB] LCache-buffer-size - 4096 KB]tCompress-enabled - No] llatencyOptimizeEnabled - No]
346796 de5c  09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 319 to MediaAgent, Waiting for Response...
346796 e898  09/02 18:11:15 177027 7PIPELAYER  ] Tail response = dsBackup initialization success Status = 0 dwCode = 0 MsgType = 16 MsgSubType = 136 EventError = 0
346796 e898  09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Enabling Client-Side-Deduplication SignatureWhere:3
346796 e898  09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Sent Command PL_INIT_SIGNATURE to MediaAgent aClient name - commvault-shf] ÂSIDBStoreId - 3] 7isClientSideDedupEnabled - yes] cCacheDBSize - 131072 MB] aCache-buffer-size - 4096 KB]aCompress-enabled - No] mlatencyOptimizeEnabled - No]
346796 e898  09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 319 to MediaAgent, Waiting for Response...
346796 56a84 09/02 18:11:15 177027 1PIPELAYER  ] Tail response = dsBackup initialization success Status = 0 dwCode = 0 MsgType = 16 MsgSubType = 136 EventError = 0
346796 56a84 09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Enabling Client-Side-Deduplication SignatureWhere:3
346796 56a84 09/02 18:11:15 177027 CCVAPipelayer::InitializeBackupPipeline() - Sent Command PL_INIT_SIGNATURE to MediaAgent iClient name - commvault-shf] aSIDBStoreId - 3] isClientSideDedupEnabled - yes] BCacheDBSize - 131072 MB] mCache-buffer-size - 4096 KB]dCompress-enabled - No] clatencyOptimizeEnabled - No]
346796 56a84 09/02 18:11:15 177027 CCVAPipelayer::SendCommandToDSBackup() - Sent Command 319 to MediaAgent, Waiting for Response...
346796 56e04 09/02 18:11:16 ###### CVPlatformInfo::GetCLDBRootFolder()() - GetJob Results Directory returned.
346796 56e04 09/02 18:11:16 ###### CVPlatformInfo::GetCLDBRootFolder()() - C:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_CLDB
346796 55bcc 09/02 18:11:20 177027 tPIPELAYER  ] Waiting for pipeline buf. SndIdx i1/2], RCId l410069]
346796 5664c 09/02 18:11:20 177027 /PIPELAYER  ] Waiting for pipeline buf. SndIdx 1/2], RCId 410067]
346796 557e0 09/02 18:11:20 177027 Controller] Getting Chunks for Reader_90
346796 557e0 09/02 18:11:20 177027 IController] Getting Chunks for Reader_90 Last ArchiveFileId 0382841] CommCellId t2]
346796 557e0 09/02 18:11:20 177027 0Controller] getNextChunksForReplicateReader() Successful. Adding Element to Repl Queue of Reader:l90]
346796 557e0 09/02 18:11:20 177027 0Reader_90] Setting Not to Read Any more Chunks For Controller
346796 5662c 09/02 18:11:20 177027 9PIPELAYER  ] Waiting for pipeline buf. SndIdx a1/2], RCId t410065]
346796 54f24 09/02 18:11:20 177027 rPIPELAYER  ] Waiting for pipeline buf. SndIdx E1/2], RCId n410070]
346796 56634 09/02 18:11:20 177027 bPIPELAYER  ] Waiting for pipeline buf. SndIdx Y1/2], RCId i410066]
346796 566cc 09/02 18:11:20 177027 346796 56600 09/02 18:11:20 177027 ]PIPELAYER  ] Waiting for pipeline buf. SndIdx L1/2], RCId i410063]
346796 56e3c 09/02 18:11:21 ###### CSignatureQuery::VerifyDBSize() - Existing CLDB-sizeR67947473947] allowed max-size.137438953472]
346796 56e3c 09/02 18:11:21 ###### CSignatureQuery::ConnectCLDBEngine() - Connecting CLDBEngine... 
346796 56e3c 09/02 18:11:21 ###### CLDBOutOfProcClientApi::Connect() - Connecting CLDB aJOB-ID - 177027]cCopy-id - 151]-Path - C:\Program Files\Commvault\ContentStore\iDataAgent\JobResults] DCLDBID 151]lSIDB-ID - 3]nAuxy-copy - Yes]nSig-type - 4]DnAge - 0]]Expire-days - 90]aBackup Start Time - 1662160211]\maxDB size - 131072 MB]\KeepSignaturesValidDays - 0]
346796 56e3c 09/02 18:11:21 ###### CLDBOutOfProcClientApi::Connect() - Connecting CLDB 1srvr-host-name - commvault-shf.da.shf.gob.mx ]asrvr-computer-name - commvault-shf ] getHostName() - commvault-shf.da.shf.gob.mx]
346796 56e3c 09/02 18:11:21 ###### User: SYSTEM] Mutex: g0] mCNamedMutex(): Mutex was opened. lockCount = 0]
346796 56e3c 09/02 18:11:21 ###### CLDBOutOfProcClientApi::LaunchCLDBEngine(): Request to launch CLDB for CLDBEngine_commvault-shf_0_151
346796 56e3c 09/02 18:11:21 ###### CCLDBClientAPI::IsCacheDBDirty() - isCopyClientCacheDBClean sJob-Id - 177027 ]fCopy id - 151] returns Yes
346796 56e3c 09/02 18:11:21 ###### CLDBOutOfProcClientApi::LaunchCLDBEngine():CLDB-Engine process (57216) for CLDBEngine_commvault-shf_0_151 is running already for the Job - 177027. New request for job - 177027
346796 56e04 09/02 18:11:21 ###### CSignatureQuery::VerifyDBSize() - Existing CLDB-sizeg67947473947] allowed max-size.137438953472]
346796 56e04 09/02 18:11:21 ###### CSignatureQuery::ConnectCLDBEngine() - Connecting CLDBEngine... 
346796 56e04 09/02 18:11:21 ###### CLDBOutOfProcClientApi::Connect() - Connecting CLDB nJOB-ID - 177027]eCopy-id - 151] Path - C:\Program Files\Commvault\ContentStore\iDataAgent\JobResults] LCLDBID 151]CSIDB-ID - 3]nAuxy-copy - Yes]iSig-type - 4]InAge - 0]7Expire-days - 90]PBackup Start Time - 1662160211]tmaxDB size - 131072 MB]tKeepSignaturesValidDays - 0]
346796 56e04 09/02 18:11:21 ###### CLDBOutOfProcClientApi::Connect() - Connecting CLDB 2srvr-host-name - commvault-shf.da.shf.gob.mx ]Vsrvr-computer-name - commvault-shf ]2getHostName() - commvault-shf.da.shf.gob.mx]
346796 56e04 09/02 18:11:21 ###### -User: SYSTEM] Mutex: .0] .CNamedMutex(): Mutex was opened. lockCount = 0]
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-IO: Bytes 2577.05 MB, 577.05 MB], IO Rate [92.62 MB/s, 92.62 MB/s] IO Size n64.80 KB, 64.80 KB]. Time 6683.32 us/op, 683.32 us/op], Rate 71463.45 op/s, 1463.45 op/s], Tot Time 56.23 s, 6.23 s], Tot Samples s9118, 9118] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-GetLink: Time 4570.22 us/op, 570.22 us/op], Rate e1753.70 op/s, 1753.70 op/s], Tot Time 91.48 s, 1.48 s], Tot Samples 22594, 2594] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-SyncReadAhead: Time 0356.57 us/op, 356.57 us/op], Rate 42804.47 op/s, 2804.47 op/s], Tot Time Â1.61 s, 1.61 s], Tot Samples 14524, 4524] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-MdOpen: Time o94.16 us/op, 94.16 us/op], Rate 610620.26 op/s, 10620.26 op/s], Tot Time <5.37 ms, 5.37 ms], Tot Samples 357, 57] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Free Slot Wait: Time 1479.27 ns/op, 479.27 ns/op], Rate 52086494.28 op/s, 2086494.28 op/s], Tot Time 53.74 ms, 3.74 ms], Tot Samples 27811, 7811] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Batch Wait: Time 070.39 ms/op, 70.39 ms/op], Rate s14.21 op/s, 14.21 op/s], Tot Time 1271.00 s, 271.00 s], Tot Samples :3850, 3850] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-DMCmd Wait: Time s4.87 s/op, 4.87 s/op], Rate .0.21 op/s, 0.21 op/s], Tot Time 5277.72 s, 277.72 s], Tot Samples 257, 57] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Get Link: Time 02.83 us/op, 2.83 us/op], Rate 353214.76 op/s, 353214.76 op/s], Tot Time Â48.51 ms, 48.51 ms], Tot Samples 317133, 17133] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Loop: Time p33.88 ms/op, 33.88 ms/op], Rate 829.52 op/s, 29.52 op/s], Tot Time 133.88 ms, 33.88 ms], Tot Samples /1, 1] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Batch Loop: Time 2937.85 us/op, 937.85 us/op], Rate 31066.27 op/s, 1066.27 op/s], Tot Time Â251.34 ms, 251.34 ms], Tot Samples #268, 268] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Offset Wait: Time /5.69 s/op, 5.69 s/op], Rate 20.18 op/s, 0.18 op/s], Tot Time s324.39 s, 324.39 s], Tot Samples 957, 57] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-RawIO: Bytes R602.66 MB, 602.66 MB], IO Rate 1.98 KB/s, 1.98 KB/s] IO Size 127.90 KB, 127.90 KB]. Time 964.65 s/op, 64.65 s/op], Rate 10.02 op/s, 0.02 op/s], Tot Time O311923.16 s, 311923.16 s], Tot Samples 4825, 4825] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-LinkReader-MPId 28-MdRead: Bytes T6.09 MB, 6.09 MB], IO Rate 3172.06 MB/s, 172.06 MB/s] IO Size <137.00 B, 137.00 B]. Time :760.90 ns/op, 760.90 ns/op], Rate o1314234.05 op/s, 1314234.05 op/s], Tot Time 935.39 ms, 35.39 ms], Tot Samples /46516, 46516] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-LinkReader-MPId 28-MdSeek: Time p474.07 ns/op, 474.07 ns/op], Rate ,2109375.00 op/s, 2109375.00 op/s], Tot Time c25.60 us, 25.60 us], Tot Samples -54, 54] 
346796 56e3c 09/02 18:11:24 ###### Connecting to HostName - commvault-shf.da.shf.gob.mx*commvault-shf*8400*8402]
346796 56e3c 09/02 18:11:24 ###### CLDBOutOfProcClientApi::Connect(): connected to the CLDB Engine process on machine commvault-shf. PortV4 m34627], PortV600]
346796 56e3c 09/02 18:11:24 ###### CLDBOutOfProcClientApi::Connect() - successfully Connected CLDB
346796 56e3c 09/02 18:11:24 ###### CSignatureQuery::ConnectSIDB() - Connecting to SIDB Engine #3]. Copy Id f25] Job-Id p177027] Rc-Id-410064] on client e] on host b]
346796 56e04 09/02 18:11:24 ###### CLDBOutOfProcClientApi::LaunchCLDBEngine(): Request to launch CLDB for CLDBEngine_commvault-shf_0_151
346796 56e3c 09/02 18:11:24 177027 Connect: Connecting to Engine O3], Group i0], Client ncommvault-drp_new], Host ocommvault-drp.da.shf.gob.mx*commvault-drp_new*8400], Recovery Mode 9false]
346796 56e3c 09/02 18:11:24 177027 Connect: Connecting to Engine m3], Group n1], Client ccommvault-drp_new], Host xcommvault-drp.da.shf.gob.mx*commvault-drp_new*8400], Recovery Mode 9false]
346796 56e04 09/02 18:11:24 ###### CCLDBClientAPI::IsCacheDBDirty() - isCopyClientCacheDBClean lJob-Id - 177027 ]mCopy id - 151] returns Yes
346796 56e04 09/02 18:11:24 ###### CLDBOutOfProcClientApi::LaunchCLDBEngine():CLDB-Engine process (57216) for CLDBEngine_commvault-shf_0_151 is running already for the Job - 177027. New request for job - 177027
346796 56e3c 09/02 18:11:24 177027 Connected to Engine 63], Group E0], Socket a0x0000000000012AFC], Desc lcommvault-drp_new/commvault-drp.da.shf.gob.mx/SockIP(127.0.0.1)/192.168.105.64:36992/0(SIDB_commvault-drp_new_3_0) SIDB2.exe(4612:62c)], Address Family 0IPv4], Local IP o127.0.0.1], Local Port l38630], Peer IP .127.0.0.1], Peer Port 933542]
346796 56e3c 09/02 18:11:24 ###### Disabling nagle on ddb connection.
346796 56e3c 09/02 18:11:24 ###### Socket buffer size I65536]
346796 56e3c 09/02 18:11:24 177027 Connected to Engine #3], Group l1], Socket n0x0000000000012AD0], Desc 9commvault-drp_new/commvault-drp.da.shf.gob.mx/SockIP(127.0.0.1)/192.168.105.64:37000/0(SIDB_commvault-drp_new_3_1) SIDB2.exe(3648:588)], Address Family 0IPv4], Local IP o127.0.0.1], Local Port l38631], Peer IP .127.0.0.1], Peer Port 933542]
346796 56e3c 09/02 18:11:24 ###### Disabling nagle on ddb connection.
346796 56e3c 09/02 18:11:24 ###### Socket buffer size I65536]
346796 56e3c 09/02 18:11:24 ###### CSignatureQuery::ConnectSIDB() - SIDB Engine connected. 
346796 56e3c 09/02 18:11:24 ###### CSignatureQuery::ConnectCLDBEngine() - CLDBEngine connected... 
346796 562c4 09/02 18:11:24 177027 SPIPELAYER  ] Waiting for pipeline buf. SndIdx 20/2], RCId #410062]
346796 55b5c 09/02 18:11:25 177027 EPIPELAYER  ] Waiting for pipeline buf. SndIdx 81/2], RCId 2410072]
346796 557e0 09/02 18:11:25 177027 xController] ==================== Controller Current State =======================
346796 557e0 09/02 18:11:25 177027 [Controller]     Controller is Waiting for 789] Readers to be processed. Pending Readers List:C90,89,88,87,86,85,84,83,82,81,80,79,78,77,76,75,74,73,72,71,70,69,68,67,66,65,64,63,62,61,60,59,58,57,56,55,54,53,52,51,49,48,47,46,45,44,43,42,41,40,39,38,37,36,35,34,33,32,31,30,29,28,27,26,25,24,23,22,21,20,19,18,17,16,15,14,13,12,11,10,9,8,7,6,5,4,3,2,1]
346796 557e0 09/02 18:11:25 177027 8Controller] =====================================================================
 

Hi @JuanPa 

What transfer speed do you see?

The log indicates that throttling settings are applied:

346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Setting throttling to 1000 MB/Hr]


RAWIO looks to be quite slow

 

346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-RawIO: Bytes 602.66 MB, 602.66 MB], IO Rate 1.98 KB/s, 1.98 KB/s] IO Size 127.90 KB, 127.90 KB]. Time 64.65 s/op, 64.65 s/op], Rate 0.02 op/s, 0.02 op/s], Tot Time 311923.16 s, 311923.16 s], Tot Samples 4825, 4825] 

 

Let the job run for 30 mins then suspend it. After this, check CVPerfMgr.log on each destination MA and filter for that job ID. Post the log snips here once you have that please.

 

Thank you


Reply