I have an Auxiliary Disk-to-Disk copy and the throughput is very low, I see a lot of intermittent reading from the disk where the data lives.

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 [128 KB], SI Block Size [128 KB]
346796 56e20 09/02 18:11:14 177027 EncryptionType:PASSTHRU for target copy:[151]
346796 56e20 09/02 18:11:14 177027 EncryptionType:PASSTHRU(NOENCRYPTION) for target copy:[151] 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 -[srcClientName - commvault-shf] Block Level [true], Block Size [128], File Level [false], Min Signature Size [128]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Initiating SDT connection [000000D50C41C7E0] 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 [CV_NO_COMPRESS], compressWhere [CV_COMPRESS_NOWHERE]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline encryptionType [CV_NO_ENCRYPT], encryptionWhere [CV_ENCRYPT_NOWHERE]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline signatureType [CV_SIGNATURE_SHA_512], signatureWhere [CV_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 [1000 MB/Hr]
346796 56e20 09/02 18:11:14 177027 CPipelayer::InitiatePipeline Num Buffers [90], Buffer size [65536]
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 []
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 [SDTPipe_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 [false]
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] [AppId - 151][JobId - 177027][RCId - 410101] [Dest - commvault-drp_new] [Subclient/Copy Throughput MBPH - 1000] [Stream 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 [410101]
346796 56e20 09/02 18:11:15 177027 MODULE_SEQUENCE Adding signature at index [1] RCId [410101]
346796 56e20 09/02 18:11:15 ###### Set the signature type to [4] [SHA512]
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 [72]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs [18]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs [0]
346796 56e20 09/02 18:11:15 177027 SdtBase::cfgProcs() - Free Bufs [0]
346796 56e20 09/02 18:11:15 177027 Switching socket 75548 (1/2) to non-blking mode. RCId [410101]
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. [Using slice-by-8] RCId [410097]
346796 56e3c 09/02 18:11:15 177027 Will dedup data >= [131072] bytes. Scaling factor [1]. Min data size [8192], Block Mode [true], Use Uncomp [false], Follow Primary [false]
346796 56e3c 09/02 18:11:15 177027 Will dedup data >= [131072] bytes. Scaling factor [1]. Min data size [8192], Block Mode [true], Use Uncomp [false], Follow Primary [false]
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 [PIPELAYER  ] 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 [PIPELAYER  ] 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 >= [131072] bytes. Scaling factor [1]. Min data size [8192], Block Mode [true], Use Uncomp [false], Follow Primary [false]
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 []
346796 55b5c 09/02 18:11:15 177027 [Reader_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 [Reader_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 [Reader_61] Seeking to offset [0] within afid [382318]
346796 56e20 09/02 18:11:15 177027 Switching socket 75604 (2/2) to non-blking mode. RCId [410101]
346796 55b5c 09/02 18:11:15 177027 [Reader_61] Seek to offset [0] within afid [382318] is successful. Current Read Offset [0]
346796 56e20 09/02 18:11:15 177027 InitializePerfCounters() - Initializing Perf counters message[Pipeline-ID - 410101] [Starttime - 1662160275] [Starttime UTC - 1662181875]
346796 55b5c 09/02 18:11:15 177027 [DM_BASE    ] Starting read ahead. MDChunkId [2199243], OffQInUse [0], AfId [382318], Offset [Af:8769, Md:74305, Fs:-1], ChunkOff [0-113225034], CloudLaptop [0]
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 [DM_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: [<?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 [DM_BASE    ] Successfully mounted Volume 44211  for Reading. Reservation id [0]
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] CreateMediaObjectSpecific [528]: Min/Max Dedup Data Block Size [393216 / 2490368], Non Dedup Blk Size [131072], Dedup Buf Queue Len [8], Max Async AddRef [8]
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] Scratch mem [0], Wait for commit [0], Commit by rng [1], DV2LookAltPrim [1]; Lookahead [1], # of lnks [256], DisableMediaBasedChunk [0] MaxChunkSizeGB [64]
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] Looks like it is SDM, NAS, Centera, Single Instance or Web Storage Backup.
346796 569fc 09/02 18:11:15 177027 [MEDIAFS    ] DataMoverMode = [2]
346796 569fc 09/02 18:11:15 177027 [MEDIAFS    ] The volume will be recorded with the block size [64] KB
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] Opening the Chunk =2199322, ArchFileId = 382331, FileMarker=13, ArchFilePhysSizeInChunk=7619688224 VolId=44211
346796 569fc 09/02 18:11:15 177027 [MEDIAFS    ] Initializing file interface cache.
346796 569fc 09/02 18:11:15 177027 [MEDIAFS    ] ChunkMetaDataIndex File [G:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44211\CHUNK_2199322\CHUNK_META_DATA_2199322.idx] Version [4]
346796 56e40 09/02 18:11:15 177027 JobStatusWorker Setting proxy Connection stats for job: [<?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 [DM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:[bUseReadless - No] [USe CacheDb - No] [Cache-DB size - 0] [Highlatency - No]
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy [151] SIDBStoreId [3]
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] Init: AIoMode [0x0], Slot size [128] KB, PRead [0], PurgeLimit [16], CompacMapSize [128] KB, Delay Count [3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO [0], Ctrs [0]
346796 569fc 09/02 18:11:15 177027 [DM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BDD20. Links to read ahead [256], Links/Batch [64], HyperScale [0], LogLvl [1], WaitLogT [300], (Jul 21 2022 17:09:28)
346796 56e44 09/02 18:11:15 177027 [DM_BASE    ] ReadAhead: Readahead thread [000000D4F34BDD20] 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 [DM_BASE    ] Successfully mounted Volume 44208  for Reading. Reservation id [0]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] CreateMediaObjectSpecific [528]: Min/Max Dedup Data Block Size [393216 / 2490368], Non Dedup Blk Size [131072], Dedup Buf Queue Len [8], Max Async AddRef [8]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] Scratch mem [0], Wait for commit [0], Commit by rng [1], DV2LookAltPrim [1]; Lookahead [1], # of lnks [256], DisableMediaBasedChunk [0] MaxChunkSizeGB [64]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] Looks like it is SDM, NAS, Centera, Single Instance or Web Storage Backup.
346796 569e8 09/02 18:11:15 177027 [MEDIAFS    ] DataMoverMode = [2]
346796 569e8 09/02 18:11:15 177027 [MEDIAFS    ] The volume will be recorded with the block size [64] KB
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] Successfully mounted Volume 44210  for Reading. Reservation id [0]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] Opening the Chunk =2199318, ArchFileId = 382328, FileMarker=17, ArchFilePhysSizeInChunk=6684136703 VolId=44208
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] CreateMediaObjectSpecific [528]: Min/Max Dedup Data Block Size [393216 / 2490368], Non Dedup Blk Size [131072], Dedup Buf Queue Len [8], Max Async AddRef [8]
346796 569e8 09/02 18:11:15 177027 [MEDIAFS    ] Initializing file interface cache.
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] Scratch mem [0], Wait for commit [0], Commit by rng [1], DV2LookAltPrim [1]; Lookahead [1], # of lnks [256], DisableMediaBasedChunk [0] 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 [MEDIAFS    ] DataMoverMode = [2]
346796 56a18 09/02 18:11:15 177027 [MEDIAFS    ] The volume will be recorded with the block size [64] KB
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] Opening the Chunk =2199364, ArchFileId = 382339, FileMarker=67, ArchFilePhysSizeInChunk=4281306972 VolId=44210
346796 56a18 09/02 18:11:15 177027 [MEDIAFS    ] Initializing file interface cache.
346796 569e8 09/02 18:11:15 177027 [MEDIAFS    ] ChunkMetaDataIndex File [G:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44208\CHUNK_2199318\CHUNK_META_DATA_2199318.idx] Version [4]
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 [DM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:[bUseReadless - No] [USe CacheDb - No] [Cache-DB size - 0] [Highlatency - No]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy [151] SIDBStoreId [3]
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:[bUseReadless - No] [USe CacheDb - No] [Cache-DB size - 0] [Highlatency - No]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] Init: AIoMode [0x0], Slot size [128] KB, PRead [0], PurgeLimit [16], CompacMapSize [128] KB, Delay Count [3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO [0], Ctrs [0]
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy [151] SIDBStoreId [3]
346796 569e8 09/02 18:11:15 177027 [DM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BF3D0. Links to read ahead [256], Links/Batch [64], HyperScale [0], LogLvl [1], WaitLogT [300], (Jul 21 2022 17:09:28)
346796 56e48 09/02 18:11:15 177027 [DM_BASE    ] ReadAhead: Readahead thread [000000D4F34BF3D0] started.
346796 56a18 09/02 18:11:15 177027 [DM_BASE    ] Init: AIoMode [0x0], Slot size [128] KB, PRead [0], PurgeLimit [16], CompacMapSize [128] KB, Delay Count [3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO [0], Ctrs [0]
346796 569e8 09/02 18:11:15 177027 [DM_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 [DM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BCE00. Links to read ahead [256], Links/Batch [64], HyperScale [0], LogLvl [1], WaitLogT [300], (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 [DM_BASE    ] Successfully mounted Volume 44207  for Reading. Reservation id [0]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] CreateMediaObjectSpecific [528]: Min/Max Dedup Data Block Size [393216 / 2490368], Non Dedup Blk Size [131072], Dedup Buf Queue Len [8], Max Async AddRef [8]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] Scratch mem [0], Wait for commit [0], Commit by rng [1], DV2LookAltPrim [1]; Lookahead [1], # of lnks [256], DisableMediaBasedChunk [0] MaxChunkSizeGB [64]
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 [MEDIAFS    ] DataMoverMode = [2]
346796 569dc 09/02 18:11:15 177027 [MEDIAFS    ] The volume will be recorded with the block size [64] KB
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] Opening the Chunk =2199317, ArchFileId = 382327, FileMarker=39, ArchFilePhysSizeInChunk=4505112376 VolId=44207
346796 569dc 09/02 18:11:15 177027 [MEDIAFS    ] Initializing file interface cache.
346796 569dc 09/02 18:11:15 177027 [MEDIAFS    ] ChunkMetaDataIndex File [G:\BackupData3\1VK08T_08.04.2022_22.24\CV_MAGNETIC\V_44207\CHUNK_2199317\CHUNK_META_DATA_2199317.idx] Version [4]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] DataMoverSingleInstancer::useReadlessDedupe:[bUseReadless - No] [USe CacheDb - No] [Cache-DB size - 0] [Highlatency - No]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] OpenChunkSpecific: Disabling Auxcopy Readless for Copy [151] SIDBStoreId [3]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] Init: AIoMode [0x0], Slot size [128] KB, PRead [0], PurgeLimit [16], CompacMapSize [128] KB, Delay Count [3]; Thds Ovl:64, NonOvl:32, Glus:64; DirectIO [0], Ctrs [0]
346796 569dc 09/02 18:11:15 177027 [DM_BASE    ] CLookAheadLinkReader::Init: Initialized Lookahead Rdr 000000D4F34BE4B0. Links to read ahead [256], Links/Batch [64], HyperScale [0], LogLvl [1], WaitLogT [300], (Jul 21 2022 17:09:28)
346796 56e50 09/02 18:11:15 177027 [DM_BASE    ] ReadAhead: Readahead thread [000000D4F34BE4B0] started.
346796 569dc 09/02 18:11:15 177027 [DM_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 [PIPELAYER  ] 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 [Client name - commvault-shf] [SIDBStoreId - 3] [isClientSideDedupEnabled - yes] [CacheDBSize - 131072 MB] [Cache-buffer-size - 4096 KB][Compress-enabled - No] [latencyOptimizeEnabled - 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 [PIPELAYER  ] 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 [Client name - commvault-shf] [SIDBStoreId - 3] [isClientSideDedupEnabled - yes] [CacheDBSize - 131072 MB] [Cache-buffer-size - 4096 KB][Compress-enabled - No] [latencyOptimizeEnabled - 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 [PIPELAYER  ] 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 [Client name - commvault-shf] [SIDBStoreId - 3] [isClientSideDedupEnabled - yes] [CacheDBSize - 131072 MB] [Cache-buffer-size - 4096 KB][Compress-enabled - No] [latencyOptimizeEnabled - 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 [PIPELAYER  ] 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 [Client name - commvault-shf] [SIDBStoreId - 3] [isClientSideDedupEnabled - yes] [CacheDBSize - 131072 MB] [Cache-buffer-size - 4096 KB][Compress-enabled - No] [latencyOptimizeEnabled - 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 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410069]
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 [Controller] Getting Chunks for Reader_90 Last ArchiveFileId [382841] CommCellId [2]
346796 557e0 09/02 18:11:20 177027 [Controller] getNextChunksForReplicateReader() Successful. Adding Element to Repl Queue of Reader:[90]
346796 557e0 09/02 18:11:20 177027 [Reader_90] Setting Not to Read Any more Chunks For Controller
346796 5662c 09/02 18:11:20 177027 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410065]
346796 54f24 09/02 18:11:20 177027 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410070]
346796 56634 09/02 18:11:20 177027 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410066]
346796 566cc 09/02 18:11:20 177027 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410068]
346796 56600 09/02 18:11:20 177027 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410063]
346796 56e3c 09/02 18:11:21 ###### CSignatureQuery::VerifyDBSize() - Existing CLDB-size[67947473947] 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 [JOB-ID - 177027][Copy-id - 151][Path - C:\Program Files\Commvault\ContentStore\iDataAgent\JobResults] [CLDBID 151][SIDB-ID - 3][Auxy-copy - Yes][Sig-type - 4][nAge - 0][Expire-days - 90][Backup Start Time - 1662160211][maxDB size - 131072 MB][KeepSignaturesValidDays - 0]
346796 56e3c 09/02 18:11:21 ###### CLDBOutOfProcClientApi::Connect() - Connecting CLDB [srvr-host-name - commvault-shf.da.shf.gob.mx ][srvr-computer-name - commvault-shf ][getHostName() - commvault-shf.da.shf.gob.mx]
346796 56e3c 09/02 18:11:21 ###### [User: SYSTEM] Mutex: [0] [CNamedMutex(): 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 [Job-Id - 177027 ][Copy 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-size[67947473947] 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 [JOB-ID - 177027][Copy-id - 151][Path - C:\Program Files\Commvault\ContentStore\iDataAgent\JobResults] [CLDBID 151][SIDB-ID - 3][Auxy-copy - Yes][Sig-type - 4][nAge - 0][Expire-days - 90][Backup Start Time - 1662160211][maxDB size - 131072 MB][KeepSignaturesValidDays - 0]
346796 56e04 09/02 18:11:21 ###### CLDBOutOfProcClientApi::Connect() - Connecting CLDB [srvr-host-name - commvault-shf.da.shf.gob.mx ][srvr-computer-name - commvault-shf ][getHostName() - 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 [577.05 MB, 577.05 MB], IO Rate [92.62 MB/s, 92.62 MB/s] IO Size [64.80 KB, 64.80 KB]. Time [683.32 us/op, 683.32 us/op], Rate [1463.45 op/s, 1463.45 op/s], Tot Time [6.23 s, 6.23 s], Tot Samples [9118, 9118] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-GetLink: Time [570.22 us/op, 570.22 us/op], Rate [1753.70 op/s, 1753.70 op/s], Tot Time [1.48 s, 1.48 s], Tot Samples [2594, 2594] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-SyncReadAhead: Time [356.57 us/op, 356.57 us/op], Rate [2804.47 op/s, 2804.47 op/s], Tot Time [1.61 s, 1.61 s], Tot Samples [4524, 4524] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-MdOpen: Time [94.16 us/op, 94.16 us/op], Rate [10620.26 op/s, 10620.26 op/s], Tot Time [5.37 ms, 5.37 ms], Tot Samples [57, 57] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Free Slot Wait: Time [479.27 ns/op, 479.27 ns/op], Rate [2086494.28 op/s, 2086494.28 op/s], Tot Time [3.74 ms, 3.74 ms], Tot Samples [7811, 7811] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Batch Wait: Time [70.39 ms/op, 70.39 ms/op], Rate [14.21 op/s, 14.21 op/s], Tot Time [271.00 s, 271.00 s], Tot Samples [3850, 3850] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-DMCmd Wait: Time [4.87 s/op, 4.87 s/op], Rate [0.21 op/s, 0.21 op/s], Tot Time [277.72 s, 277.72 s], Tot Samples [57, 57] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Get Link: Time [2.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 [17133, 17133] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Loop: Time [33.88 ms/op, 33.88 ms/op], Rate [29.52 op/s, 29.52 op/s], Tot Time [33.88 ms, 33.88 ms], Tot Samples [1, 1] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-Batch Loop: Time [937.85 us/op, 937.85 us/op], Rate [1066.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 [0.18 op/s, 0.18 op/s], Tot Time [324.39 s, 324.39 s], Tot Samples [57, 57] 
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] 
346796 54c94 09/02 18:11:23 ###### 177027-0-DataMover-LookAhead-LinkReader-MPId 28-MdRead: Bytes [6.09 MB, 6.09 MB], IO Rate [172.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 [1314234.05 op/s, 1314234.05 op/s], Tot Time [35.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 [474.07 ns/op, 474.07 ns/op], Rate [2109375.00 op/s, 2109375.00 op/s], Tot Time [25.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 [34627], PortV6[0]
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 [25] Job-Id [177027] Rc-Id[410064] on client [] on host []
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 [3], Group [0], Client [commvault-drp_new], Host [commvault-drp.da.shf.gob.mx*commvault-drp_new*8400], Recovery Mode [false]
346796 56e3c 09/02 18:11:24 177027 Connect: Connecting to Engine [3], Group [1], Client [commvault-drp_new], Host [commvault-drp.da.shf.gob.mx*commvault-drp_new*8400], Recovery Mode [false]
346796 56e04 09/02 18:11:24 ###### CCLDBClientAPI::IsCacheDBDirty() - isCopyClientCacheDBClean [Job-Id - 177027 ][Copy 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 [3], Group [0], Socket [0x0000000000012AFC], Desc [commvault-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 [IPv4], Local IP [127.0.0.1], Local Port [38630], Peer IP [127.0.0.1], Peer Port [33542]
346796 56e3c 09/02 18:11:24 ###### Disabling nagle on ddb connection.
346796 56e3c 09/02 18:11:24 ###### Socket buffer size [65536]
346796 56e3c 09/02 18:11:24 177027 Connected to Engine [3], Group [1], Socket [0x0000000000012AD0], Desc [commvault-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 [IPv4], Local IP [127.0.0.1], Local Port [38631], Peer IP [127.0.0.1], Peer Port [33542]
346796 56e3c 09/02 18:11:24 ###### Disabling nagle on ddb connection.
346796 56e3c 09/02 18:11:24 ###### Socket buffer size [65536]
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 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [0/2], RCId [410062]
346796 55b5c 09/02 18:11:25 177027 [PIPELAYER  ] Waiting for pipeline buf. SndIdx [1/2], RCId [410072]
346796 557e0 09/02 18:11:25 177027 [Controller] ==================== Controller Current State =======================
346796 557e0 09/02 18:11:25 177027 [Controller]     Controller is Waiting for [89] Readers to be processed. Pending Readers List:[90,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 [Controller] =====================================================================