@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

Machine : prdjwlcomv01
File : commserveDR.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

37800 8d90 01/05 19:00:17 1641379 forkProcessAsCurrentUser() - Starting

37800 8d90 01/05 19:00:17 1641379 CommservDR::cleanupStagingDir() - Removed files
from staging directory [E:\Program Files\Commvault\ContentStore\CommserveDR\DR]
37800 8d90 01/05 19:00:17 1641379 setSqlVirtualServer() - Set Environment Variable
[_VIRTUAL_SERVER_NAME_] to [PRDJWLCOMV01.tco.local]
37800 8d90 01/05 19:00:17 1641379 forkProcessAsCurrentUser() - Started process 520
37800 8d90 01/05 19:00:17 1641379 forkProcessAsCurrentUser() - Command has been
executed
520 64ac 01/05 19:00:18 1641379 setSqlVirtualServer() - Set Environment Variable
[_VIRTUAL_SERVER_NAME_] to [PRDJWLCOMV01.tco.local]
520 64ac 01/05 19:00:19 1641379 CommservDR::checkGlobalParams() - Client log
wild card:
520 64ac 01/05 19:00:19 1641379 CommservDR::cleanupStagingDir() - Removed files
from staging directory [E:\Program Files\Commvault\ContentStore\CommserveDR\DR]
520 64ac 01/05 19:00:19 1641379 CommservDR::getMinDBLogUsage() - Registry key
nMinDBLogUsage does not exist.
520 64ac 01/05 19:00:19 1641379 setFileName() - minLogUsage is set to 75 by
default.
520 64ac 01/05 19:00:19 1641379 TaskInterfaceDB::getOptions() - Loading default
options into memory
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [commserv],
isFullRecovery: [0], log_Size_MB: [1136.62] log_Used_Percent: [0.50]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [DM2],
isFullRecovery: [0], log_Size_MB: [35.43] log_Used_Percent: [8.44]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [CVCloud],
isFullRecovery: [0], log_Size_MB: [220.24] log_Used_Percent: [4.30]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [HistoryDB],
isFullRecovery: [0], log_Size_MB: [789.30] log_Used_Percent: [0.84]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB:
[UsageHistoryDB], isFullRecovery: [0], log_Size_MB: [11.74] log_Used_Percent:
[12.33]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [AppStudioDB],
isFullRecovery: [0], log_Size_MB: [2.18] log_Used_Percent: [22.92]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [WFEngine],
isFullRecovery: [0], log_Size_MB: [127.37] log_Used_Percent: [3.84]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB:
[ResourceMgrDB], isFullRecovery: [0], log_Size_MB: [2.18] log_Used_Percent: [27.58]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - DB: [CacheDB],
isFullRecovery: [0], log_Size_MB: [2.62] log_Used_Percent: [23.34]
520 64ac 01/05 19:00:19 1641379 CVSimpleDB::SQLINFO() - INFO: [DBCC execution
completed. If DBCC printed error messages, contact your system administrator.]
[RecNum:1, LineNum:1, Spid:199]
520 64ac 01/05 19:00:19 1641379 CommservDR::getDbLogUsage() - Failed to get log
usage with error [100] for database [Troubleshooting]
520 64ac 01/05 19:00:19 1641379 doImpersonateUser() - User
[svc_commvault_ad@myinnovage.com] logged on successfully.
520 64ac 01/05 19:00:20 1641379 CommservDR::doBackupRestore() - CommServeDR:
Full backup started for set [3643].
520 64ac 01/05 19:00:20 1641379 CommservDR::getSQLVersion() - Microsoft SQL
Server Major version: [15] isExpressEdition [2]
520 64ac 01/05 19:00:20 1641379 CommservDR::execSQL() - Compress DB option is
disabled
520 7448 01/05 19:00:20 1641379 execMongoBackup() - Taking backup for
[gcmtracking] databases present on client for service [GCMTracking]
520 64ac 01/05 19:00:20 1641379 getSQLCMDPath() - Info: Unable to find [C:\
Program Files\Microsoft SQL Server\150\Tools\Binn\SQLCMD.EXE]. Trying ODBCToolsPath
location
520 64ac 01/05 19:00:20 1641379 CommservDR::execSQL() - SQL Command: C:\Program
Files\Microsoft SQL Server\Client SDK\ODBC\170\Tools\Binn\SQLCMD.EXE
520 64ac 01/05 19:00:20 1641379 CommservDR::IsWindowsAuthenticationSet() -
Windows Only authentication is not set
520 64ac 01/05 19:00:20 1641379 CommservDR::setEnvForSqlCmdPassword() -
Successfully set environment variable for SQL password
520 64ac 01/05 19:00:20 1641379 CommservDR::execSQL() - Command: "C:\Program
Files\Microsoft SQL Server\Client SDK\ODBC\170\Tools\Binn\SQLCMD.EXE" -S
"prdjwlcomv01\Commvault" -U "sqladmin_cv" -i "E:\Program Files\Commvault\
ContentStore\CommserveDR\DR\cmdFile.txt" -o "E:\Program Files\Commvault\
ContentStore\CommserveDR\DR\cmdFile.log" -d master -l 30 -w 2000 -H "DRBackup"
520 7448 01/05 19:00:20 1641379 execMongoBackup() - Taking backup for
[virtualserver] databases present on client for service [VirtualServer]
520 64ac 01/05 19:00:20 1641379 CommservDR::forkProcessAsUser() - Starting
520 7448 01/05 19:00:20 1641379 execMongoBackup() - Taking backup for
[globalconfigmanager] databases present on client for service [GlobalConfigManager]
520 455c 01/05 19:00:20 1641379 sendRemoteMongoBackupCmd() - Initializing
MongoDR Backup for client [2]
520 8db4 01/05 19:00:20 1641379 sendRemoteMongoBackupCmd() - Initializing
MongoDR Backup for client [2]
520 50c 01/05 19:00:20 1641379 sendRemoteMongoBackupCmd() - Initializing
MongoDR Backup for client [2]
520 64ac 01/05 19:00:20 1641379 CommservDR::forkProcessAsUser() - Started
process 11152
520 64ac 01/05 19:00:20 1641379 CommservDR::forkProcessAsUser() - Command has
been executed
520 455c 01/05 19:00:34 1641379 EvExternal::invokeRemoteMachineCall() - Error
returned by the remote MongoDR call to webserver. Error Code [-1] Error Message
[Failed to add some files to the archive with error: [0xFFFFFFFF:
{C7zip::AddFilesToArchive(190)/Int.-1.0xFFFFFFFF-Warning (Non fatal error(s)). For
example, one or more files were locked by some other application, so they were not
compressed.}]]
520 455c 01/05 19:00:34 1641379 EvExternal::takeMongoDRBackup() - Error occurred
while taking Mongo Backup on the webserver with clientId [2].
520 455c 01/05 19:00:34 1641379 sendRemoteMongoBackupCmd() - Failed to take
MongoDR Backup for client [2]
520 7448 01/05 19:00:50 1641379 execMongoBackup() - MongoDR Backup failed for
service [GCMTracking]. Set job status to ER_BACKUP_WARN_EXIT
520 64ac 01/05 19:07:03 1641379 CommservDR::unsetEnvForSqlCmdPass() -
Successfully unset environment variable [SQLCMDPASSWORD]
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'commserv'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'DM2'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'CVCloud'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'HistoryDB'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'UsageHistoryDB'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'AppStudioDB'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'WFEngine'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'ResourceMgrDB'.
520 64ac 01/05 19:07:03 1641379 CommservDR::getOperationError() - CHECKDB found
0 allocation errors and 0 consistency errors in database 'CacheDB'.
520 64ac 01/05 19:07:03 1641379 CommservDR::dumpFile() - File does not exist:
E:\Program Files\Commvault\ContentStore\base\fwhosts.txt
520 64ac 01/05 19:07:03 1641379 CommservDR::dumpFile() - File does not exist:
E:\Program Files\Commvault\ContentStore\base\fwpeers.txt
520 64ac 01/05 19:07:03 1641379 CommservDR::dumpFile() - File does not exist:
E:\Program Files\Commvault\ContentStore\base\fwports.txt
520 64ac 01/05 19:07:03 1641379 CommservDR::dumpFile() - File does not exist:
E:\Program Files\Commvault\ContentStore\base\IPsToBind.txt
520 64ac 01/05 19:07:03 1641379 CommservDR::dumpFile() - File does not exist:
E:\Program Files\Commvault\ContentStore\CVvirupdatesdump.txt
520 64ac 01/05 19:07:03 1641379 CommservDR::doBackupRestore() - MongoDB Backup
failed.
520 64ac 01/05 19:07:09 1641379 CommservDR::doBackupRestore() - Info: Phase A -
Successfully renamed the folder from [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR] to [E:\Program Files\Commvault\ContentStore\CommserveDR\
DR_1641379]. Setting state to [10]
520 64ac 01/05 19:07:09 1641379 CommservDR::doBackupRestore() - Phase A: Set job
status to PARTIALSUCCESS
520 64ac 01/05 19:07:09 1641379 COMPLETE CALLED (PHASE Status::PARTIALSUCCESS),
Job ID = 1641379
520 64ac 01/05 19:07:09 1641379 CvNetworkPool::initNetworkPool() - Creating the
control thread
520 394c 01/05 19:07:09 1641379 thNetworkControllerThread() - Controller thread
started
520 64ac 01/05 19:07:10 1641379 CCvNetworkServer::CCvNetworkServer() -
CvNetworkServer is using Asynchronous IO model
520 64ac 01/05 19:07:11 1641379 CommservDR::doBackupRestore() -
cloudAzureService - Cloud service initialized for user name
[workflow_commcellregistration]
520 64ac 01/05 19:07:11 1641379 CommservDR::doBackupRestore() - Phase A: Copying
the files from [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379] to
[\\prdazrcvma01.tco.local\CVDR\SET_3643]
520 5bb8 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
CVCloud_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [581099008] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 7a00 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
DM2_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [11681280] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 2d98 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
Mongo_GlobalConfigManager_prdjwlcomv01_2_2025_01_05_19_00.7z] with [640] bytes to
[\\prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 9028 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
Mongo_VirtualServer_prdjwlcomv01_2_2025_01_05_19_00.7z] with [19296] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 6f28 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
commserv_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [8112047616] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 7dc4 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
HistoryDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [1331924480] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 90f4 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
UsageHistoryDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [13753856] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 3274 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
CacheDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [8877568] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 3338 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
WFEngine_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [194162176] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 330 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
ResourceMgrDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [3437056] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 7230 01/05 19:07:11 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\
AppStudioDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] with [4157952] bytes to [\\
prdazrcvma01.tco.local\CVDR\SET_3643\] with free space [127610912768] bytes
520 1650 01/05 19:07:14 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\
Mongo_GlobalConfigManager_prdjwlcomv01_2_2025_01_05_19_00.7z] - File path
[SET_3643]
520 8bd0 01/05 19:07:14 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\Mongo_VirtualServer_prdjwlcomv01_2_2025_01_05_19_00.7z] -
File path [SET_3643]
520 865c 01/05 19:07:17 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\ResourceMgrDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File
path [SET_3643]
520 8da8 01/05 19:07:18 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\AppStudioDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File
path [SET_3643]
520 204c 01/05 19:07:21 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\CacheDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File path
[SET_3643]
520 7588 01/05 19:07:25 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\DM2_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File path
[SET_3643]
520 853c 01/05 19:07:32 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\UsageHistoryDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] -
File path [SET_3643]
520 8618 01/05 19:07:34 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\WFEngine_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File path
[SET_3643]
520 9124 01/05 19:08:18 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\CVCloud_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File path
[SET_3643]
520 6580 01/05 19:08:55 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\HistoryDB_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File
path [SET_3643]
520 8638 01/05 19:14:10 1641379 CommservDR::UploadToCVCloud() -
cloudAzureService - Uploaded file [E:\Program Files\Commvault\ContentStore\
CommserveDR\DR_1641379\commserv_prdjwlcomv01_2025_01_05_19_00_FULL.dmp] - File path
[SET_3643]
520 8558 01/05 19:21:25 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\cmdFile.txt]
with [2665] bytes to [\\prdazrcvma01.tco.local\CVDR\SET_3643\] with free space
[117349285888] bytes
520 27d8 01/05 19:21:26 1641379 CommservDR::CopyFileToDRDestination() - Copying
file [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1641379\cmdFile.log]
with [307452] bytes to [\\prdazrcvma01.tco.local\CVDR\SET_3643\] with free space
[117349220352] bytes
520 64ac 01/05 19:21:26 1641379 CommservDR::doBackupRestore() - Executing DR
Complete workflow in CV cloud.
520 64ac 01/05 19:21:28 1641379 CommservDR::dumpVersion() - Info: Successfully
copied the file from E:\Program Files\Commvault\ContentStore\CommserveDR\
DR_1641379\version.txt to \\prdazrcvma01.tco.local\CVDR\SET_3643\version.txt
520 64ac 01/05 19:21:29 1641379 CommservDR::doPurgeLocal() - Successfully
deleted the folder [E:\Program Files\Commvault\ContentStore\CommserveDR\DR_1640564]
520 64ac 01/05 19:21:29 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\AppStudioDB_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:30 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\CacheDB_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:30 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\cmdFile.log]
520 64ac 01/05 19:21:31 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\cmdFile.txt]
520 64ac 01/05 19:21:32 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\commserv_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:32 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\CVCloud_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:33 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\DM2_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:34 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\HistoryDB_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:35 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\Mongo_GCMTracking_prdjwlcomv01_2_2024_12_29_19_00.7z]
520 64ac 01/05 19:21:35 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\Mongo_GlobalConfigManager_prdjwlcomv01_2_2024_12_29_19_00.7z]
520 64ac 01/05 19:21:36 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\Mongo_VirtualServer_prdjwlcomv01_2_2024_12_29_19_00.7z]
520 64ac 01/05 19:21:36 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\ResourceMgrDB_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:37 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\UsageHistoryDB_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:38 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\version.txt]
520 64ac 01/05 19:21:38 1641379 DRunlink() - Removing [\\prdazrcvma01.tco.local\
CVDR\SET_3636\WFEngine_prdjwlcomv01_2024_12_29_19_00_FULL.dmp]
520 64ac 01/05 19:21:39 1641379 CommservDR::purgeDir(558): -Debug-: Directory
deleted: [\\prdazrcvma01.tco.local\CVDR\SET_3636]
520 64ac 01/05 19:21:39 1641379 CommservDR::doPurge() - Purge dir succeeded: [\\
prdazrcvma01.tco.local\CVDR\SET_3636]
520 64ac 01/05 19:21:39 1641379 CommservDR::doPurgeDRCmdFolders() - Info: No
DR_CMD_* folders found under E:\Program Files\Commvault\ContentStore\CommserveDR
520 64ac 01/05 19:21:39 1641379 CommservDR::backupCmd() - CommServeDR: Full
backup completed Successfully.
520 64ac 01/05 19:21:39 1641379 jobCleanUp() - Phase B: Update job status to
PARTIALSUCCESS
520 64ac 01/05 19:21:39 1641379 main() - Exiting with Code [30]
520 64ac 01/05 19:21:39 1641379 CvNetworkPool::~CvNetworkPool() - Controller
thread successfully stopped.
37800 8d90 01/05 19:21:39 1641379 DBparseCmdArgs() - Successfully Executed Pass2
37800 8d90 01/05 19:21:39 1641379 DRunlink() - Removing [E:\Program Files\
Commvault\ContentStore\CommserveDR\DR\cmdFile.bat]

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : ResourceManager.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

26912 29a4 01/05 19:07:31 1641379 Resource Reserved Resource: NoOfReservations

[1], StoragePolicy [CommServeDR(prdjwlcvma01)], MediaAgent[prdjwlcvma02],
StartNewMedia = false, InterruptedJobList []
26912 91d0 01/05 19:09:59 1641379 Resource Releasing Stream(s) for Job [1641379]

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : MediaManager.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
12812 1c94 01/05 19:07:34 1641379 MOUNT [ ] MOUNT REQUEST from
[prdjwlcvma02] : JOB 1641379
12812 1c94 01/05 19:07:34 1641379 MOUNT [3193405] USING RESERVATION ID
[5374578], DRIVE ID [40]
12812 1c94 01/05 19:07:34 1641379 MOUNT [3193405] JOB [1641379] IS A CSDRBKP
JOB
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] MAGNETIC OML OP Host
[prdjwlcvma02<240>], MountPath \\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\
Folder_07.09.2020_13.13\CV_MAGNETIC\V_3193405, RID 8509677/0/0
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] OML INFORMATION
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] ACCESSPATH
=\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\CV_MAGNETIC\
V_3193405
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] VOLUMEID
=3193405
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] VOLUMEBLOCKSIZE
=512
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] UNIQUEID
=1014498_BC_CV_MAGNETIC_40
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] COMCELLID
=1014498
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] BARCODE
=CV_MAGNETIC
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] SIDENAME
=A_40
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] MEDIACREATION TIME
=1594300423
12812 1c94 01/05 19:07:34 1641379 SERVICE [3193405] VERSION
=11(BUILD80) (** DISASTER RECOVERY MEDIA **)
12812 6550 01/05 19:07:34 1641379 MOUNT [3193405] VOLUME [3193405], IN
MAGNETIC LIBRARY [DiskLibrary17<PRDJWLBKUP01>]
12812 4544 01/05 19:09:45 1641379 MOUNT [3193405] UNMOUNT PARAMS from
[prdjwlcvma02] : JOB 1641379, RESERVATION ID: 5374578 RELEASE VOLUME : 0, RELEASE
DRIVE : 0, LOGICAL UNMOUNT : 1, PHYSICAL UNMOUNT : 0, RESOURCE CONSUMER ID: 0
12812 50c8 01/05 19:09:52 1641379 MOUNT [ ] MOUNT REQUEST from
[prdjwlcvma02] : JOB 1641379
12812 50c8 01/05 19:09:52 1641379 MOUNT [3193405] USING RESERVATION ID
[5374578], DRIVE ID [40]
12812 50c8 01/05 19:09:52 1641379 MOUNT [3193405] JOB [1641379] IS A CSDRBKP
JOB
12812 50c8 01/05 19:09:52 1641379 MOUNT [3193405] VOLUME [3193405], IN
MAGNETIC LIBRARY [DiskLibrary17<PRDJWLBKUP01>]
12812 1a68 01/05 19:09:53 1641379 MOUNT [3193405] UNMOUNT PARAMS from
[prdjwlcvma02] : JOB 1641379, RESERVATION ID: 5374578 RELEASE VOLUME : 0, RELEASE
DRIVE : 0, LOGICAL UNMOUNT : 1, PHYSICAL UNMOUNT : 0, RESOURCE CONSUMER ID: 0

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : JobManager.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

26912 8d34 01/05 19:00:08 1641379 Servant [---- SCHEDULED DR BACKUP

REQUEST ----]. Task Id [4].
26912 5d88 01/05 19:00:09 1641379 Scheduler Phase [1-Backup To Disk]
(0,0) started on [PRDJWLCOMV01.tco.local] in [1] second(s) - CommServeDR.exe -job
1641379 -tid 3 -appid 1 -backup -full -db commserv -attemptNum 1
26912 7cec 01/05 19:00:18 1641379 Servant Reg [Control] received.
Client [prdjwlcomv01] plattype = 1
26912 78f8 01/05 19:07:09 1641379 Scheduler Phase [Completed w/ one
or more errors] message received from [PRDJWLCOMV01.tco.local] Module [commserveDR]
Token [] restartPhase [0]
26912 78f8 01/05 19:07:09 1641379 JobSvr Obj Phase [Backup To Disk]
for Job Completed. The job will continue with phase [Scan].
26912 78f8 01/05 19:07:09 1641379 ArchiveMgr
JMArMgr::releaseAllJobStreams() Calling
CvResourceManager::releaseJobStreams(jobType=4,appType=1000,logicalRelease=true,
retainStreamsForIndex=false)
26912 40b0 01/05 19:07:09 1641379 Scheduler Phase [5-Scan] (0,0)
started on [PRDJWLCOMV01.tco.local] in [0] second(s) - ifind.exe -j 1641379 -a 2:1
-t 1 -d PRDJWLCVMA02.tco.local*prdjwlcvma02*8400 -jt 1641379:5:1:0:0:27506
26912 91d0 01/05 19:07:16 1641379 Servant Reg [Control] received.
Client [prdjwlcomv01] plattype = 1. Token [1641379:5:1:0:0:27506]
26912 4858 01/05 19:07:20 1641379 JobSvr Obj
CVArchCheckJobSvr::updateInfo(), Concat phase params [ -ab 0 -r 1736129236 -c 0 -
appType 1000 -CFI -slt -size 0]
26912 7cec 01/05 19:07:20 1641379 Scheduler Phase [Completed] message
received from [PRDJWLCOMV01.tco.local] Module [FileScan] Token
[1641379:5:1:0:0:27506] restartPhase [0]
26912 7cec 01/05 19:07:20 1641379 JobSvr Obj Phase [Scan] for Job
Completed. The job will continue with phase [Backup].
26912 7cec 01/05 19:07:20 1641379 ArchiveMgr
JMArMgr::releaseAllJobStreams() Calling
CvResourceManager::releaseJobStreams(jobType=4,appType=1000,logicalRelease=true,
retainStreamsForIndex=false)
26912 88a8 01/05 19:07:31 1641379 Scheduler Phase [8-Backup] (0,0)
started on [PRDJWLCOMV01.tco.local] in [0] second(s) - clBackup.exe -j 1641379 -a
2:1 -t 1 -i 3 -d PRDJWLCVMA02.tco.local*prdjwlcvma02*8400 -jt
1641379:8:1:0:0:27506 -numstreams 1 -ab 0 -r 1736129236 -c 0 -appType 1000 -CFI -
slt -size 0
26912 4858 01/05 19:07:33 1641379 Servant Reg [Control] received.
Client [prdjwlcomv01] plattype = 1. Token [1641379:8:1:0:0:27506]
26912 90a4 01/05 19:07:33 1641379 Servant Reg [Non Control Monitor]
received. Client [prdjwlcvma02] plattype = 4. Token [1641379:8:1:0:0:27506]
26912 78f8 01/05 19:07:35 1641379 Servant Reg [Non Control Monitor]
received. Client [prdjwlcvma02] plattype = 4. Token [1641379:8:1:0]
26912 4858 01/05 19:09:47 1641379 Scheduler Phase [Completed] message
received from [PRDJWLCOMV01.tco.local] Module [DRBackup] Token
[1641379:8:1:0:0:27506] restartPhase [0]
26912 4858 01/05 19:09:47 1641379 JobSvr Obj Phase [Backup] for Job
Completed. The job will continue with phase [Archive Index].
26912 40b0 01/05 19:09:49 1641379 Scheduler Phase [10-Archive Index]
(0,0) started on [PRDJWLCVMA02.tco.local] in [0] second(s) - archiveIndex.exe -j
1641379 -a 2:1 -t 1 -jt 1641379:10:1:3:0:27506 -ab 0 -r 1736129236 -c 0 -appType
1000 -CFI -slt -size 0
26912 7cec 01/05 19:09:51 1641379 Servant Reg [Control] received.
Client [prdjwlcvma02] plattype = 4. Token [1641379:10:1:3:0:27506]
26912 91d0 01/05 19:09:59 1641379 Scheduler Phase [Completed] message
received from [PRDJWLCVMA02.tco.local] Module [archiveIndex] Token
[1641379:10:1:3:0:27506] restartPhase [0]
26912 91d0 01/05 19:09:59 1641379 JobSvr Obj Phase [Archive Index] for
Job Completed.
26912 91d0 01/05 19:09:59 1641379 ArchiveMgr
JMArMgr::releaseAllJobStreams() Calling
CvResourceManager::releaseJobStreams(jobType=4,appType=1000,logicalRelease=true,
retainStreamsForIndex=false)
26912 33cc 01/05 19:09:59 1641379 Scheduler Inside
handleBkpJobCompleted
26912 33cc 01/05 19:09:59 1641379 JobSvr Obj Checking if staging copy
is completed.
26912 33cc 01/05 19:09:59 1641379 JobSvr Obj Waiting for staging copy
to complete. Process ID [520] is running.
26912 33cc 01/05 19:21:58 1641379 JobSvr Obj Copy to Staging location
completed.
26912 33cc 01/05 19:21:59 1641379 Scheduler -> COMPLETED WITH
ERRORS/WARNINGS <- Completed JobType[Disaster Recovery Backup]
CompletionStatus[With Errors]
26912 33cc 01/05 19:21:59 1641379 JobSvr Obj Successfully removed
DRBackupListFile [E:\Program Files\Commvault\ContentStore\Base\
DRBackupListFile_1641379].
26912 33cc 01/05 19:21:59 1641379 JobSvr Obj Successfully removed
DRBackupDirectiveFile [E:\Program Files\Commvault\ContentStore\Base\
DRBackupDirectiveFile_1641379].
26912 33cc 01/05 19:21:59 1641379 JobSvr Obj Update aux copy
succeeded. AppId [1] AppType [1000] BackupLevel [1]
26912 33cc 01/05 19:21:59 1641379 JMGUIReport2::sendJobCompleted Sent
job completed message to GUI.

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : FileScan.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

29736 8c74 01/05 19:07:12 1641379 _enclosed_main(509) - Logging configured,

DebugMask=[0x0000FFFF], DebugLvl=[1]
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(521) -
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(522) -
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(523) -
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(524) - ======================
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(525) - IFIND PROCESS STARTING
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(526) - ----------------------
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(527) -
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(528) -
29736 8c74 01/05 19:07:12 1641379 _enclosed_main(529) -
29736 8c74 01/05 19:07:15 1641379 DBBase-SQL() - dbmethods.cpp:1398, DB Layer will
use [Exec] access by default. Use SetAdminAccess API for on demand Admin access.
29736 8c74 01/05 19:07:15 1641379 Cvcl::init() - CVCL: Running in FIPS Mode
29736 8c74 01/05 19:07:15 1641379 DetectNativeClient() - Found reg key [SOFTWARE\
Microsoft\Microsoft SQL Server\SQLNCLI11]. Using driver [SQL Server Native Client
11.0].
29736 8c74 01/05 19:07:16 1641379 _enclosed_main(545) -
ScanJobType=DISASTER_RECOVERY (2)
29736 8c74 01/05 19:07:16 1641379 IsProcessInstanceManagementEnabled(96) - Process
instance manager is enabled.
29736 8c74 01/05 19:07:16 1641379
CProcessInstanceManager::UpdateNewInstanceInfoInCache(255) - Updated new instance's
info [PID=29736] in cache=Global\IFind_1_2_B16EA003-9DEB-43BB-90BD-FAC69AB4FE90
29736 8c74 01/05 19:07:16 1641379 CJobManagerRegistrationPrivate::Register(71) - +
++
29736 8c74 01/05 19:07:16 1641379 CJobManagerRegistrationPrivate::Register(92) -
CommServe=PRDJWLCOMV01.tco.local, JobId=1641379,
JobManagerToken=1641379:5:1:0:0:27506, PlatformType=4
29736 8c74 01/05 19:07:16 1641379 CJobManagerRegistrationPrivate::Register(71) -
--- 0:00.261699
29736 8c74 01/05 19:07:16 1641379 CFind::Run(1127) - +++
29736 8c74 01/05 19:07:16 1641379 CFindAdvanced::ProcessCommandLineArgs(151) - +++
29736 8c74 01/05 19:07:16 1641379 CFindAdvanced::ProcessCommandLineArgs(151) - ---
0:00.003907
29736 8c74 01/05 19:07:16 1641379 CFind::Initialize(1621) - +++
29736 8c74 01/05 19:07:16 1641379
CApplicationIdSingletonWrapper::EnableCaching(36) - Caching of CSDB properties is
enabled
29736 8c74 01/05 19:07:16 1641379 CFind::Initialize_JobControl(1683) - +++
29736 8c74 01/05 19:07:16 1641379 CFind::Initialize_JobControl(1691) - Job Control
object initialized
29736 8c74 01/05 19:07:16 1641379 CFind::Initialize_JobControl(1705) - Overriding
CommServe name [] with registry value [PRDJWLCOMV01.tco.local]
29736 8c74 01/05 19:07:16 1641379 CFind::Initialize_JobControl(1683) - ---
0:00.001998
29736 8c74 01/05 19:07:16 1641379 CFind::Initialize_Client(1732) - Using ClientId
[2]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Subclient(1759) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Subclient(1759) - ---
0:00.001086
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(1775) - +++
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::CreateImpersonationToken(3953) -
+++
29736 8c74 01/05 19:07:17 1641379 CFind::CreateImpersonationToken(2389) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::CreateImpersonationToken(2403) - No
subclient credentials found, trying job results impersonation
29736 8c74 01/05 19:07:17 1641379 CFind::CreateImpersonationToken(2411) - No job
results credentials found, no impersonation configured
29736 8c74 01/05 19:07:17 1641379 CFind::CreateImpersonationToken(2389) - ---
0:00.007038
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::CreateImpersonationToken(3953) -
--- 0:00.008705
29736 8c74 01/05 19:07:17 1641379 JOBRESCACHEDIR: job 1641379 creating 'E:\Program
Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641379'
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(2005) -
AppNumber=[2:1], AppTypeId=[1000], ReferenceTime=[Clear], ReferenceTimeUtc=[Clear],
BackupType=[1 (Full)]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(2006) -
SubclientDir=[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1], CollectFile=[E:\Program Files\Commvault\ContentStore\
iDataAgent\JobResults\CV_JobResults\CommServe\2\1\CollectTot.cvf], FilterFile=[E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\FilterTot.cvf]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(2007) -
DirChangeTmp=[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\DCTmp.cvf]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(2008) -
MediaAgentName=[PRDJWLCVMA02.tco.local*prdjwlcvma02*8400], BackupSetName=[DR-
BackupSet], SubclientName=[]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(2009) -
JobId=[1641379], CommCellId=[2]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(2010) -
CommServeName=[PRDJWLCOMV01.tco.local]
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize_Environment(1775) - ---
0:00.039926
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeScanJob(2620) - +++
29736 8c74 01/05 19:07:17 1641379 CScanContentConfiguration::SetOptions(182) -
SubclientName=[DR Subclient], SubclientConfQueryTime=[Clear]
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeScanJob_PSTEvaluator(2947) -
PST file evaluator will not be used
29736 8c74 01/05 19:07:17 1641379 FindPrivate::IdentifyDirChangeMethod(169) -
ScanJobType=[DISASTER_RECOVERY (2)], DirChangeMethod=[1]
29736 8c74 01/05 19:07:17 1641379
CFind::InitializeScanJob_ConfigureMultithreadedScanSemaphore(3197) - For
Multithreaded fast find scans. Total Configured Threads = [300] , Maximum
simultaneous workunits allowed = [3]
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeScanJob(2620) - --- 0:00.032261
29736 8c74 01/05 19:07:17 1641379 CFind::Initialize(1621) - --- 0:00.104199
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureScanMethod(629) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::ConfigureScanMethod(3272) - +++
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureScanMethod_OnDemand(857)
- OnDemandDirectiveFile=[E:\Program Files\Commvault\ContentStore\Base\
DRBackupDirectiveFile_1641379]
29736 8c74 01/05 19:07:17 1641379
CFind::ConfigureScanMethod_LogConfiguration(3583) - IncrementalImageMode=[0]
29736 8c74 01/05 19:07:17 1641379
CFind::ConfigureScanMethod_LogConfiguration(3585) - UseNewContentList=[1]
29736 8c74 01/05 19:07:17 1641379
CFind::ConfigureScanMethod_LogConfiguration(3587) - UseChangeJournal=[0]
29736 8c74 01/05 19:07:17 1641379
CFind::ConfigureScanMethod_LogConfiguration(3588) - UseOptimizedScan=[0]
29736 8c74 01/05 19:07:17 1641379
CFind::ConfigureScanMethod_LogConfiguration(3589) - OnDemandBackupset=[1],
OnDemandDirectiveFile=[E:\Program Files\Commvault\ContentStore\Base\
DRBackupDirectiveFile_1641379], AutoExpandDirs=[1]
29736 8c74 01/05 19:07:17 1641379
CFind::ConfigureScanMethod_LogConfiguration(3591) - UseArchiveBit=[0]
29736 8c74 01/05 19:07:17 1641379 CFind::ConfigureScanMethod(3272) - ---
0:00.012038
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureScanMethod(629) - ---
0:00.013441
29736 8c74 01/05 19:07:17 1641379 CFind::CleanupOldCollectsAndDirChange(3670) - ++
+
29736 8c74 01/05 19:07:17 1641379 CFind::CleanupOldCollectsAndDirChange(3670) -
--- 0:00.009725
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureStreams(915) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::ConfigureStreams(3976) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::ConfigureStreamsFinalize(4099) - The
number of subclient streams to use is [1]
29736 8c74 01/05 19:07:17 1641379 CFind::ConfigureStreamsFinalize(4123) -
Defaulting to do one read per physical disk, the collect files will not be divided
further
29736 8c74 01/05 19:07:17 1641379 CFind::ConfigureStreams(3976) - --- 0:00.016764
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureStreams(915) - ---
0:00.018208
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureShadowUsage(1041) - +++
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::ConfigureShadowUsage(1041) - ---
0:00.000780
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::InitializeProxyHost(1060) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeProxyHost(4193) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeProxyHost(4193) - ---
0:00.000788
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::InitializeProxyHost(1060) - ---
0:00.002301
29736 8c74 01/05 19:07:17 1641379 CFind::PrepareCommitAndPreviousDirChange(4306) -
+++
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_CommitFile(4490) - +++
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_CommitFile(4490) - --- 0:00.004791
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_IdentifyPreviousDirChange(4539) - +++
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_IdentifyPreviousDirChange(4539) - ---
0:00.000696
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_Prepare(4585) - +++
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_Prepare(4585) - --- 0:00.000592
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_ParsePreviousDirChange(4596) - +++
29736 8c74 01/05 19:07:17 1641379
CPathCacheFlatFileIndexPrivate::InitDirChangeCache(148) - Cache Updation feature :
[Disabled]
29736 8c74 01/05 19:07:17 1641379
CPathCacheFlatFileIndexPrivate::LoadFromCurrentBackingFile(251) - +++
29736 8c74 01/05 19:07:17 1641379
CPathCacheFlatFileIndexPrivate::LoadFromCurrentBackingFile(273) -
CurrentBackingFile=[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\DCTmp.cvf]
29736 8c74 01/05 19:07:17 1641379
CPathCacheFlatFileIndexPrivate::LoadFromCurrentBackingFile(251) - --- 0:00.002579
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_ParsePreviousDirChange(4596) - ---
0:00.004594
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_ConfigureDirChange(4705) - +++
29736 8c74 01/05 19:07:17 1641379
CFind::PrepareCommitAndPreviousDirChange_ConfigureDirChange(4705) - --- 0:00.000626
29736 8c74 01/05 19:07:17 1641379 CFind::PrepareCommitAndPreviousDirChange(4306) -
--- 0:00.015114
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeFiles(4755) - +++
29736 8c74 01/05 19:07:17 1641379
CFind::InitializeFiles_MainCollectFileObject_Open(4860) - Opened Collect file,
FileName=[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\CollectTot.cvf]
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeFiles_LastScanFailures(4903) -
PreviousScanFailures=[0]
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeFiles(4755) - --- 0:00.059514
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::FinalizePreviousJob(1226) - +++
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::FinalizePreviousJob(1226) - ---
0:00.000614
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeFileScanVersionHandler(5033) -
+++
29736 8c74 01/05 19:07:17 1641379 CFileScanVersionHandler::Init(51) - Loading the
version file [E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\FSVersionTot.cvf]
29736 8c74 01/05 19:07:17 1641379
CFileScanVersionHandler::VerifyPreviousUpgrade(83) - Job state of previous job
[1640564] is [0]
29736 8c74 01/05 19:07:17 1641379
CFileScanVersionHandler::VerifyPreviousUpgrade(99) - Previous job [1640564] has
failed; Reverting the version in VersionFile from [11.1] to [0.0].
29736 8c74 01/05 19:07:17 1641379
CFileScanVersionHandler::VerifyPreviousUpgrade(114) - Finalizing the version file
[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\
CommServe\2\1\FSVersionTot.cvf]
29736 8c74 01/05 19:07:17 1641379 CFind::InitializeFileScanVersionHandler(5033) -
--- 0:00.064384
29736 8c74 01/05 19:07:17 1641379 CFindAdvanced::GatherContent(1247) - +++
29736 8c74 01/05 19:07:17 1641379 CFind::GatherContent(5047) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawContent(56) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawContent(56) - --- 0:00.002242
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawNewContentSinceLastBackup(88) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawNewContentSinceLastBackup(88) - ---
0:00.000789
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - --- 0:00.000781
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - --- 0:00.000759
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - --- 0:00.000740
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - +++
29736 8c74 01/05 19:07:17 1641379
CSubclientConfigurationPrivate::GetRawFilterItems(131) - --- 0:00.000741
29736 8c74 01/05 19:07:17 1641379 CScanContentConfiguration::LoadContent(253) - ++
+
29736 8c74 01/05 19:07:17 1641379 FSSubclientConf::setAllowOverlapSCContent(2446)
- Allow overlapped content is enabled.
29736 8c74 01/05 19:07:17 1641379 FSSubclientConf::load(699) - +++
29736 8c74 01/05 19:07:17 1641379 FSSubclientConf::load(699) - --- 0:00.060839
29736 8c74 01/05 19:07:17 1641379 FSSubclientConf::setIfindMode(2389) - ifind mode
is enabled
29736 8c74 01/05 19:07:17 1641379
CScanContentConfiguration::GetSubclientContent(506) - +++
29736 8c74 01/05 19:07:17 1641379
CScanContentConfiguration::GetSubclientContent(506) - --- 0:00.000997
29736 8c74 01/05 19:07:17 1641379
CScanContentConfiguration::GetSubclientContentExceptions(568) - +++
29736 8c74 01/05 19:07:17 1641379
CScanContentConfiguration::GetSubclientContentExceptions(568) - --- 0:00.000633
29736 8c74 01/05 19:07:17 1641379
CScanContentConfiguration::GetNewContentSinceLastBackup(720) - +++
29736 8c74 01/05 19:07:20 1641379
WellKnownFolderImpl::LoadUserShellFolderLookupSetting(482) - User Shell Folders
lookup is enabled only for normal users.
29736 8c74 01/05 19:07:20 1641379
WellKnownFolderImpl::LoadUserShellFolderLookupSetting(482) - User Shell Folders
lookup is enabled only for normal users.
29736 8c74 01/05 19:07:20 1641379 WellKnownFolderImpl::GetPaths(683) - No well-
known folders
29736 8c74 01/05 19:07:20 1641379
WellKnownFolderImpl::LoadUserShellFolderLookupSetting(482) - User Shell Folders
lookup is enabled only for normal users.
29736 8c74 01/05 19:07:20 1641379
WellKnownFolderImpl::LoadUserShellFolderLookupSetting(482) - User Shell Folders
lookup is enabled only for normal users.
29736 8c74 01/05 19:07:20 1641379 WellKnownFolderImpl::GetPaths(683) - No well-
known folders
29736 8c74 01/05 19:07:20 1641379
WellKnownFolderImpl::LoadUserShellFolderLookupSetting(482) - User Shell Folders
lookup is enabled only for normal users.
29736 8c74 01/05 19:07:20 1641379
WellKnownFolderImpl::LoadUserShellFolderLookupSetting(482) - User Shell Folders
lookup is enabled only for normal users.
29736 8c74 01/05 19:07:20 1641379 WellKnownFolderImpl::GetPaths(683) - No well-
known folders
29736 8c74 01/05 19:07:20 1641379
CScanContentConfiguration::GetNewContentSinceLastBackup(720) - --- 0:02.961062
29736 8c74 01/05 19:07:20 1641379
CScanContentConfiguration::GetSubclientSyncContent(664) - +++
29736 8c74 01/05 19:07:20 1641379
CScanContentConfiguration::GetSubclientSyncContent(681) - AppId=[1], Result=[0],
SyncFolders=[0]
29736 8c74 01/05 19:07:20 1641379
CScanContentConfiguration::GetSubclientSyncContent(664) - --- 0:00.017068
29736 8c74 01/05 19:07:20 1641379 CScanContentConfiguration::LoadContent(253) -
--- 0:03.068837
29736 8c74 01/05 19:07:20 1641379 CFind::GatherContent(5047) - --- 0:03.082016
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::GatherContent(1247) - ---
0:03.083554
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::GatherFilters(1346) - +++
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::GatherFilters(1346) - ---
0:00.000732
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::ExecuteScan(1541) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::ExecuteScan(5687) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessFilterBlock(5771) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessFilterBlock(5771) - ---
0:00.000614
29736 8c74 01/05 19:07:20 1641379 CFind::LoadBackupStateFile(5783) - +++
29736 100c 01/05 19:07:20 1641379 CReportingThread::Run(23) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::LoadBackupStateFile(5783) - ---
0:00.000836
29736 8c74 01/05 19:07:20 1641379 CFind::InitializeScanInfoFile(5821) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::InitializeScanInfoFile(5821) - ---
0:00.000575
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::ProcessContent(1633) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessContent(5862) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::handleOnDemandDirectiveFile(124) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::handleOnDemandDirectiveFile(172) -
DirectiveFile CollectFile=[E:\Program Files\Commvault\ContentStore\Base\
DRBackupListFile_1641379]
29736 8c74 01/05 19:07:20 1641379 CFind::CacheParentDirectoriesAndFileCount(206) -
+++ Input=[E:\Program Files\Commvault\ContentStore\Base\DRBackupListFile_1641379],
Output=[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\NumColTot1.tmp]
29736 8c74 01/05 19:07:20 1641379 CFind::CacheParentDirectoriesAndFileCount(206) -
--- 0:00.051842 Input=[E:\Program Files\Commvault\ContentStore\Base\
DRBackupListFile_1641379], Output=[E:\Program Files\Commvault\ContentStore\
iDataAgent\JobResults\CV_JobResults\CommServe\2\1\NumColTot1.tmp]
29736 8c74 01/05 19:07:20 1641379 CFind::handleOnDemandDirectiveFile(124) - ---
0:00.071679
29736 8c74 01/05 19:07:20 1641379
CFind::ProcessContent_OnDemandDirectiveFile(5892) - For on demand backup setting
the number of streams to use as the number of collect files [1]
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessContent_Complete(6961) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessContent_Complete(6965) - Enabling
detailed collect query using DirChange; clearing DirChange cache
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessContent_Complete(6961) - ---
0:00.001638
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessContent(5862) - --- 0:00.076705
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::ProcessContent(1633) - ---
0:00.078066
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::ProcessFailedFiles(1903) - +++
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::ProcessFailedFiles(1903) - ---
0:00.000562
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::SaveBackupStateFile(4025) - +++
29736 8c74 01/05 19:07:20 1641379 CBackupStateFilePrivate::ReplaceWithNewFile(355)
- Updated BackupState file moved into place, TargetFileName=[E:\Program Files\
Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\
cjInfoTot.cvf]
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::SaveBackupStateFile(4025) - ---
0:00.004221
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan(8120) - +++
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::PostExecuteScan_ProxyHost(1956) -
+++
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_ProxyHost(8131) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_ProxyHost(8131) - ---
0:00.000833
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::PostExecuteScan_ProxyHost(1956) -
--- 0:00.002555
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessExplicitFileNames(8238) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessExplicitFileNames(8243) -
Count=[0]
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessExplicitFileNames(8238) - ---
0:00.001601
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_CommitFiles(8258) - +++
ScanPreviousCommitJob=[0]
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_CommitFiles(8258) - ---
0:00.000723 ScanPreviousCommitJob=[0]
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_CloseFiles(8323) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_CloseFiles(8323) - ---
0:00.006553
29736 8c74 01/05 19:07:20 1641379
CFind::PostExecuteScan_PostProcessMainCollectFile(8936) - Closing collect file,
CollectFileName=[E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\CollectTot.cvf]
29736 8c74 01/05 19:07:20 1641379
CFind::PostExecuteScan_FinalizeFileExtentSpecificFiles(15580) - +++
29736 8c74 01/05 19:07:20 1641379
CFind::PostExecuteScan_FinalizeFileExtentSpecificFiles(15580) - --- 0:00.001029
29736 8c74 01/05 19:07:20 1641379
CVolumeMountPathMap::WriteVolumeMountPathFile(72) - Volume=\\?\Volume{9d042472-
0000-0000-0000-100000000000}\, MountPath=e:\
29736 8c74 01/05 19:07:20 1641379
CVolumeMountPathMap::WriteVolumeMountPathFile(79) - 1 volume mount paths written to
E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\
CommServe\2\1\scannedVolList.cvf
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_SetJobInfo(9120) - Found
481 files and 13 folders (494 total items) to back up
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessWebFolderSyncInformation(9164) - +
++
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessWebFolderSyncInformation(9164) -
--- 0:00.000627
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_ScanModeAndReason(9208) -
CumulativeScanModeAndReason=(0x0)
29736 8c74 01/05 19:07:20 1641379 CvNetworkPool::initNetworkPool() - Creating the
control thread
29736 40f0 01/05 19:07:20 1641379 thNetworkControllerThread() - Controller thread
started
29736 100c 01/05 19:07:20 1641379 CReportingThread::OnStop(57) - Stopping
Reporting Thread. Final Reporting all tasks
29736 100c 01/05 19:07:20 1641379 CFileScanJMProgressReportTask::Execute(55) -
Total Scanned items count did not change in last [3] seconds. Current Count:
Folders [0] , Files [0] , Total [0]
29736 100c 01/05 19:07:20 1641379 IReportTask::Finalize(47) - [IFind JM Progress
Report Task] Time Counter: 0:00.000908, 1 samples, 0:00.000908 average,
100/second .
29736 100c 01/05 19:07:20 1641379 IReportTask::Finalize(47) - [IFind Mark Job
Alive Task] Time Counter: 0:00.000000, 0 samples, 0:00.000000 average, 0/second .
29736 100c 01/05 19:07:20 1641379 CContentPreprocessor::LogStatistics(62) -
ExpandToVirtualStore=[0:00.000000, 0 samples, 0:00.000000 average, 0/second]
29736 100c 01/05 19:07:20 1641379 CContentPreprocessor::LogStatistics(69) -
ExpandContent5=[0:00.000000, 0 samples, 0:00.000000 average, 0/second]
29736 100c 01/05 19:07:20 1641379 CContentPreprocessor::LogStatistics(76) -
ExpandContent5 IsJunctionPoint=[0:00.000000, 0 samples, 0:00.000000 average,
0/second]
29736 100c 01/05 19:07:20 1641379 CContentPreprocessor::LogStatistics(83) -
ExpandContent5 SplitPaths=[0:00.000000, 0 samples, 0:00.000000 average, 0/second]
29736 100c 01/05 19:07:20 1641379 CContentPreprocessor::LogStatistics(90) -
ExpandContent5 SetLookup=[0:00.000000, 0 samples, 0:00.000000 average, 0/second]
29736 100c 01/05 19:07:20 1641379 CContentPreprocessor::LogStatistics(97) -
ExpandContent5 SetInsert=[0:00.000000, 0 samples, 0:00.000000 average, 0/second]
29736 100c 01/05 19:07:20 1641379 IReportTask::Finalize(47) - [IFind Log
Performance Stats Task] Time Counter: 0:00.000238, 1 samples, 0:00.000238 average,
100/second .
29736 100c 01/05 19:07:20 1641379 CReportingThread::Run(23) - --- 0:00.200515
29736 8c74 01/05 19:07:20 1641379 CFind::FurtherDivideCollectFiles(9382) -
[TopNSizeBased] algorithm will be used to split collect files
29736 8c74 01/05 19:07:20 1641379 CFind::FurtherDivideCollectFiles(9517) -
Requesting 1 streams for the backup
29736 8c74 01/05 19:07:20 1641379
CPathCacheFlatFileIndexPrivate::~CPathCacheFlatFileIndexPrivate(116) - Total time
taken to write to dirchange file : [0:00.008313, 495 samples, 0:00.000016 average,
49500/second]
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan_LogScanTime(9944) - File
Scan Completed; ElapsedTime=[0:03.706174]
29736 8c74 01/05 19:07:20 1641379 CFind::PostExecuteScan(8120) - --- 0:00.209357
29736 8c74 01/05 19:07:20 1641379 CFind::ExecuteScan(5687) - --- 0:00.317828
29736 8c74 01/05 19:07:20 1641379 CFindAdvanced::ExecuteScan(1541) - ---
0:00.319110
29736 8c74 01/05 19:07:20 1641379
CFind::PerformOneTimeVersionBasedPostScanTasks(9951) - +++
29736 8c74 01/05 19:07:20 1641379
CFind::PerformOneTimeVersionBasedPostScanTasks(9975) - One time task of removing
Trueup folder from system drive is completed
29736 8c74 01/05 19:07:20 1641379
CFind::PerformOneTimeVersionBasedPostScanTasks(9951) - --- 0:00.005288
29736 8c74 01/05 19:07:20 1641379 CFind::UpdateFileScanVersion(9983) - +++
29736 8c74 01/05 19:07:20 1641379 CFind::UpdateFileScanVersion(9983) - ---
0:00.028589
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessJobDisposition(10066) - +++
Status=[0], FailJobFlag=[0]
29736 8c74 01/05 19:07:20 1641379 JOBRESULTSTABLE(E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641 removing copy 'E:\Program
Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641379'
29736 8c74 01/05 19:07:20 1641379 CFind::ProcessJobDisposition(10184) - Next phase
parameters: [ -ab 0 -r 1736129236 -c 0 -appType 1000 -CFI -slt -size 0]
29736 8c74 01/05 19:07:20 1641379
CFind::ProcessJobDisposition_JobCompletion(10243) - +++
29736 8c74 01/05 19:07:20 1641379 BKP CALLED COMPLETE (PHASE Status::SUCCESS),
1641379. Token [1641379:5:1:0:0:27506]
29736 8c74 01/05 19:07:21 1641379
CFind::ProcessJobDisposition_JobCompletion(10243) - ---
29736 8c74 01/05 19:07:21 1641379 CFind::ProcessJobDisposition(10066) - ---
Status=[0], FailJobFlag=[0]
29736 8c74 01/05 19:07:21 1641379 CFind::Run(1127) - --- 0:04.061284
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(614) -
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(615) -
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(616) -
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(617) - ----------------------
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(618) - IFIND PROCESS ENDING
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(619) - ======================
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(620) -
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(621) -
29736 8c74 01/05 19:07:21 1641379 _enclosed_main(622) -
29736 8c74 01/05 19:07:21 1641379 CProcessInstanceManager::Finalize(416) -
removing cache Global\IFind_1_2_B16EA003-9DEB-43BB-90BD-FAC69AB4FE90
29736 8c74 01/05 19:07:21 1641379 CvNetworkPool::~CvNetworkPool() - Controller
thread successfully stopped.

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : EvMgrS.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

23908 5268 01/05 19:41:08 1641379 JOBRESCACHEDIR: opened index cache [E:\Program
Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641379]

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : DRBackup.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

36484 858 01/05 19:07:31 1641379 main(264) -

36484 858 01/05 19:07:31 1641379 main(265) -
36484 858 01/05 19:07:31 1641379 main(266) -
36484 858 01/05 19:07:31 1641379 main(272) - =========================
36484 858 01/05 19:07:31 1641379 main(273) - CLBACKUP PROCESS STARTING
36484 858 01/05 19:07:31 1641379 main(274) - -------------------------
36484 858 01/05 19:07:31 1641379 main(276) -
36484 858 01/05 19:07:31 1641379 main(277) -
36484 858 01/05 19:07:31 1641379 main(278) -
36484 858 01/05 19:07:31 1641379 FsBackupCtlr::InitializeBackup(781) - +++
36484 858 01/05 19:07:31 1641379
CApplicationIdSingletonWrapper::EnableCaching(36) - Caching of CSDB properties is
enabled
36484 858 01/05 19:07:31 1641379 DBBase-SQL() - dbmethods.cpp:1398, DB Layer will
use [Exec] access by default. Use SetAdminAccess API for on demand Admin access.
36484 858 01/05 19:07:31 1641379 Cvcl::init() - CVCL: Running in FIPS Mode
36484 858 01/05 19:07:31 1641379 DetectNativeClient() - Found reg key [SOFTWARE\
Microsoft\Microsoft SQL Server\SQLNCLI11]. Using driver [SQL Server Native Client
11.0].
36484 858 01/05 19:07:33 1641379 CBackupBase::RegisterWithJobManager(1125) - Job
has been registered successfully
36484 858 01/05 19:07:33 1641379 FsBackupCtlr::InitializeBackup(908) -
UseIncrementalImageMode=0
36484 858 01/05 19:07:33 1641379 CBackupBase::logBackupArgs(5782) - Backup Args -
"clBackup.exe -j 1641379 -a 2:1 -t 1 -i 3 -d
PRDJWLCVMA02.tco.local*prdjwlcvma02*8400 -jt 1641379:8:1:0:0:27506 -numstreams 1 -
ab 0 -r 1736129236 -c 0 -appType 1000 -CFI -slt -size 0 -cn prdjwlcomv01 -vm
Instance001" m_aFileType = [0]
36484 858 01/05 19:07:33 1641379
PlatformInfoSingleton::EnableAppMgrMessageCache() - EnableAppMgrMessageCache valid
only on client. Skipping this function.
36484 858 01/05 19:07:33 1641379 FsBackupCtlr::InitializeBackup(1039) -
Application Read pipeline size = 0(KB)
36484 858 01/05 19:07:33 1641379 FsBackupCtlr::InitializeBackup(1062) - Apptype
ID for [2, 1] is 1000
36484 858 01/05 19:07:33 1641379 FsBackupCtlr::InitializeBackup(1170) - IndexV2
is [disabled]
36484 858 01/05 19:07:33 1641379 CvErrorControl::CvErrorControl(414) - Going to
fetch rules, ClientId=2, AppType=1000, EntityString=[<?xml version="1.0"
encoding="UTF-8" standalone="no" ?><CvEntities_GenericEntity _type_="3"
clientId="2"/>]
36484 858 01/05 19:07:33 1641379 CvErrorControl::ParseXMLRulesFromXMLString(528)
- No error rules were parsed
36484 858 01/05 19:07:33 1641379 FsBackupCtlr::ChooseAppType(8729) -
OSVersion=6.2, Build=9200, PlatformId=2, CSDVersion=, ServicePack=0.0,
SuiteMask=0x190, ProductType=3
36484 858 01/05 19:07:33 1641379 FsBackupTw::Initialize(174) - +++
36484 858 01/05 19:07:33 1641379 CBackupBase::Initialize(1245) - +++
36484 858 01/05 19:07:33 1641379 CBackupBase::Initialize(1355) - +++
36484 858 01/05 19:07:33 1641379 CBackupBase::Initialize(1395) - Flag failAfter=0
(0=don't fail!)
36484 858 01/05 19:07:33 1641379 CBackupBase::subclientDir(2561) -
subclientDir=E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1
36484 858 01/05 19:07:33 1641379 CBackupBase::subclientDir(2562) - collect=E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\CollectTot.cvf
36484 858 01/05 19:07:33 1641379 CBackupBase::subclientDir(2563) -
dirchangeTmp=E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\DCTmp.cvf
36484 858 01/05 19:07:33 1641379 CBackupBase::subclientDir(2564) -
dirchangeFull=E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\DCTot.cvf
36484 858 01/05 19:07:33 1641379 CBackupBase::subclientDir(2565) -
dirchangeIncr=E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\DCInc.cvf
36484 858 01/05 19:07:33 1641379 CBackupBase::subclientDir(2566) - stubinfo=E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\StubInfo.cvf
36484 858 01/05 19:07:33 1641379 CBackupBase::DoCreateIndexProcessing(881) -
Starting the create Index phase 'E:\Program Files\Commvault\ContentStore\
iDataAgent\JobResults\CV_JobResults\CommServe\2\1\CIdxDone.cvf'
36484 858 01/05 19:07:33 1641379 CBackupBase::doCreateIndex(452) - +++
36484 858 01/05 19:07:33 1641379 CBackupBase::doCreateIndex(693) - Adding change
journal file [E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\cjInfoTot.cvf] to the optional files that are sent to
the index cache
36484 858 01/05 19:07:33 1641379 CBackupBase::doCreateIndex(701) - Adding Scan
info file [E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\ScanInfoTot.cvf] to the optional files that are sent to
the index cache
36484 858 01/05 19:07:33 1641379 CBackupBase::doCreateIndex(810) - Sending
DirChange, FileName=E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\DCTmp.cvf
36484 858 01/05 19:07:33 1641379 NoPrefix Heartbeat timeout is set to 1800
36484 858 01/05 19:07:33 1641379 CreateIndex would be started with the following
backup parameters
36484 858 01/05 19:07:33 1641379 Commcell Id = [2], Subclient Id/App Id = [1],
Backup type = [1], Start time = [1736129253], Job token = [1641379:8:1:0:0:27506],
Snap job id = [0], Client network host name = [PRDJWLCOMV01.tco.local], Gui
override = [0], Pruning time = [0], Options = [0]
36484 858 01/05 19:07:33 1641379 SENDDIRCHANGE heartbeat timeout is set to 1800
sec
36484 858 01/05 19:07:33 1641379 ALL systems GO from CreateIndex - send one-line
DIR_CHANGE
36484 858 01/05 19:07:33 1641379 [::sendDirChange]: creating one line dirchange
36484 858 01/05 19:07:33 1641379 [::sendDirChange]: createindex will process
dirchange [E:\Program Files\Commvault\ContentStore\Base\Temp\
Idx_36484_31154143_3193419449_0]
36484 858 01/05 19:07:33 1641379 NoPrefix File transfer to IndexingService
completed successfully
36484 858 01/05 19:07:34 1641379 [SendDirChange::wait]: Index Creation completed
Successfully
36484 858 01/05 19:07:34 1641379 [::sendDirChange]: Index Creation completed
Successfully
36484 858 01/05 19:07:34 1641379 CBackupBase::doCreateIndex(452) - ---
0:00.774925
36484 858 01/05 19:07:34 1641379 CBackupBase::Initialize(1521) - [1641379]
Getting new CVArchive
36484 858 01/05 19:07:34 1641379 CBackupBase::Initialize(1560) - Going to setup
pipeline through CVArchive
36484 858 01/05 19:07:34 1641379 CvNetworkPool::initNetworkPool() - Creating the
control thread
36484 2e50 01/05 19:07:34 1641379 thNetworkControllerThread() - Controller thread
started
36484 858 01/05 19:07:34 1641379 CVArchive::ConfigureBlockAlignment() -
Deduplication is not enabled. Disabling block alignment.
36484 858 01/05 19:07:34 1641379 N/w agents configured before/after firewall
check = [2/2]. Firewalled = 0
36484 858 01/05 19:07:34 1641379 CPipelayer::InitiatePipeline Initiating SDT
connection [0000022082C773B0] from PRDJWLCOMV01.tco.local:8400(prdjwlcomv01) to
PRDJWLCVMA02.tco.local:8400/8400(prdjwlcvma02)
36484 858 01/05 19:07:34 1641379 CvFwClient::connectDirectly(): Connected to
PRDJWLCVMA02.tco.local:8400/8400 using direct system connect
36484 858 01/05 19:07:34 1641379 CvFwClient::connectDirectly(): Connected to
PRDJWLCVMA02.tco.local:8400/8400 using direct system connect
36484 858 01/05 19:07:35 1641379 CBackupBase::Initialize(1577) - Pipeline setup
completed
36484 858 01/05 19:07:35 1641379 CBackupBase::Initialize(1581) - Disabling
CVArchive's file signature as this is not a turbo job or not a FS type job
36484 858 01/05 19:07:35 1641379 CBackupBase::Initialize(1601) - appType = 43,
archFileFlags = 0
36484 858 01/05 19:07:35 1641379 CBackupBase::CreateNewArchiveFile(2390) -
Creating archive file with parameters: IsValid=1, AppId=1, AppTypeId=1000,
ArchiveFileName=Not named, BackupLevel=1, ArchiveFileFLags=0x0, DataType=1
inputCTime=0
36484 858 01/05 19:07:35 1641379 CBackupBase::CreateNewArchiveFile(2403) - New
archive file created. FileNumber=2916613, GroupNumber=2, CreateTime=1736129255
(1/6/2025 2:07:35 AMZ)
36484 858 01/05 19:07:35 1641379 CBackupBase::Initialize(1355) - --- 0:02.068290
36484 858 01/05 19:07:35 1641379 CBackupBase::Initialize(1245) - --- 0:02.069961
36484 858 01/05 19:07:35 1641379
CFileBackupFSW2KPrivate::CFileBackupFSW2KPrivate(52) - Additional setting
bSkipNonCommvaultOfflineFilesBkp is not set
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::Init() - The Windows Dedupe
content will backed up as OPTIMIZED_FOR_RESTORE
36484 858 01/05 19:07:35 1641379 FsBackupTw::Initialize(283) - NAS TURBO
Subclient: 0
36484 858 01/05 19:07:35 1641379 CBackupBase::isAccessTimeTracked(7153) -
EnableAccessTimeCollection set as 0 in DB
36484 858 01/05 19:07:35 1641379 CBackupBase::isAccessTimeTracked(7188) - Access
Time Tracking is not active
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::initialize(312) - +++
36484 858 01/05 19:07:35 1641379 CFileBackup::initialize(562) - +++
36484 858 01/05 19:07:35 1641379 StaticUtils::calculateSimOSId() - OS = [x64
Server], SIM OS ID = [210]
36484 858 01/05 19:07:35 1641379 CFileBackup::initialize(742) - This is a DR
backup
36484 858 01/05 19:07:35 1641379 CFileBackup::initialize(852) - ACL collection
for client 2 is not enabled
36484 858 01/05 19:07:35 1641379 CFileBackup::initialize(853) - Catalog
Additional file attribute for client 2 is not enabled
36484 858 01/05 19:07:35 1641379
CFileBackup::GetPerfCounterLoggingInterval(14245) - Using default performance
counters logging interval [30 Minutes]
36484 858 01/05 19:07:35 1641379 CFileBackup::initialize(562) - --- 0:00.016578
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::initialize(335) - Will Not
Backup RSS Data
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::initialize(312) - ---
0:00.023683
36484 858 01/05 19:07:35 1641379 FsBackupTw::Initialize(174) - --- 0:02.119054
36484 858 01/05 19:07:35 1641379
CBackupBase::getNumStreamsAndIsOnDemandBackupSet(5967) - The number of subclient
streams to use is [1]
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::InitializeOFM(541) - Checking
for VSS/QSnap selection
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::InitializeOFM(566) - Using VSS
for all files as this is a Disaster Recover backup
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::InitializeOFM(644) - Use VSS
for local shared write files: 1
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::InitializeOFM(668) - VSS/QSnap
selected
36484 858 01/05 19:07:35 1641379 CFileBackupFSW2K::InitializeOFM(682) - Subclient
info suggest immediate VSS shadow creation
36484 858 01/05 19:07:35 1641379 COpenFileManager::Initialize(89) - Forcing
Microsoft Software Shadow Copy VSS provider for snapshots
36484 858 01/05 19:07:35 1641379 CVSSClientBase::Init() - Cluster State=[0]
Ret=[0]
36484 858 01/05 19:07:35 1641379
SHADOWSET::CShadowSet::AddShadowCreationOption(155) - Explicitly setting VSS SW
provider for snapshot.
36484 858 01/05 19:07:35 1641379 CsVolumeList::Add() - Added [E:\] to volume list
36484 858 01/05 19:07:35 1641379 CsVssAsync::WaitUntilDone() - Waiting
asynchronously for [VSS-Wait_75892c49-dcff-4b14-a9d8-c139bacae2db]
36484 858 01/05 19:07:38 1641379 CsVssAsync::WaitUntilDone() - Async status for
event [VSS-Wait_75892c49-dcff-4b14-a9d8-c139bacae2db] returned final code =
0x0004230a, Description = VSS_S_ASYNC_FINISHED.
36484 858 01/05 19:07:38 1641379 CsVssBackupComponents::GatherWriterMetadata() -
Writer Metadata gathered
36484 858 01/05 19:07:39 1641379 CsVssBackupComponents::GatherWriterMetadata() -
Gathered all writer metadata
36484 858 01/05 19:07:39 1641379 CsVssBackupComponents::SetBackupState() -
Committing backup state: SelectComponents: [1], BootableSystemState: [0],
BackupType: [5 - VSS_BT_COPY], PartialFileSuppport: [0]
36484 858 01/05 19:07:39 1641379 CsSynchronization::MutexSyncWait() - Mutex
[CVSSClientSnapMutex] returned sync status [0, CSSYNC_STATUS_COMPLETE] after [5]
wait.
36484 858 01/05 19:07:39 1641379 CsSynchronization::MutexSyncWait() - Mutex
signaled CSSYNC_STATUS_COMPLETE after [5] seconds.
36484 858 01/05 19:07:39 1641379 CsVssBackupComponents::SetBackupState() -
Committing backup state: SelectComponents: [1], BootableSystemState: [0],
BackupType: [5 - VSS_BT_COPY], PartialFileSuppport: [0]
36484 858 01/05 19:07:39 1641379 CsSnapRequestor::RunCreateShadow() - Usage list
empty, no writers added to the shadow
36484 858 01/05 19:07:39 1641379 CsSnapRequestor::Initialize() - Not gathering
the writer metadata
36484 858 01/05 19:07:39 1641379 CsVssBackupComponents::FreeWriterMetadata() -
Free'd all writer metadata
36484 858 01/05 19:07:39 1641379 CsVssBackupComponents::SetBackupState() -
Committing backup state: SelectComponents: [1], BootableSystemState: [0],
BackupType: [5 - VSS_BT_COPY], PartialFileSuppport: [0]
36484 858 01/05 19:07:39 1641379 CsSnapRequestor::Initialize() - Shadow
Provider=b5946137-7b9f-4925-af80-51abd60b20d5
36484 858 01/05 19:07:39 1641379 CsSnapRequestor::StartSnapshotSet() - Created
shadow set 3ebbb0a3-f83e-439b-8bbc-ac0a574f9723
36484 858 01/05 19:07:39 1641379 CsSnapRequestor::AddVolumesToSnapshotSet() -
Successfully added volume [E:\] to shadow set.
36484 858 01/05 19:07:39 1641379 CsVssAsync::WaitUntilDone() - Waiting
asynchronously for [SnapAddResourcesAsync]
36484 858 01/05 19:07:39 1641379 CsVssAsync::WaitUntilDone() - Async status for
event [SnapAddResourcesAsync] returned final code = 0x0004230a, Description =
VSS_S_ASYNC_FINISHED.
36484 858 01/05 19:07:39 1641379 CsSnapRequestor::CreateFolderMetadata() - Not
attempting to create folder metadata
36484 858 01/05 19:07:39 1641379 CsVssAsync::WaitUntilDone() - Waiting
asynchronously for [SnapCreateAsync]
36484 858 01/05 19:07:41 1641379 CsVssAsync::WaitUntilDone() - Async status for
event [SnapCreateAsync] returned final code = 0x0004230a, Description =
VSS_S_ASYNC_FINISHED.
36484 858 01/05 19:07:41 1641379 CsSnapRequestor::BackupComplete() - Starting
asynchronous BackupComplete. Please wait...
36484 858 01/05 19:07:41 1641379 CsSnapRequestor::BackupComplete() - Asynchronous
BackupComplete finished.
36484 858 01/05 19:07:41 1641379 CVSSClientShadow::GetShadowDeviceObjects() -
SSID:[3ebbb0a3-f83e-439b-8bbc-ac0a574f9723],CopyId[d8d92dbe-bfeb-47ad-8ee6-
50fd46d017c1],sDeviceObjectPath:[\\?\GLOBALROOT\Device\
HarddiskVolumeShadowCopy116],SrcVolumePath:[E:\],SrcVolumeName[\\?\Volume{9d042472-
0000-0000-0000-100000000000}\],ExposedName[]
36484 858 01/05 19:07:41 1641379 COpenFileManager::CacheShadowSetInfo(685) -
Volume [E:\]. ShadowId is [3ebbb0a3-f83e-439b-8bbc-ac0a574f9723], ShadowPath is
[\\?\GLOBALROOT\Device\HarddiskVolumeShadowCopy116].
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::FindCollectFileNames(7432) - +++
36484 858 01/05 19:07:41 1641379 CollectInfoManager::PopulateFromCollectInfo(659)
- Adding collect file with Id: [0], File Name: [E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\NumColTot1.cvf]
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::FindCollectFileNames(7604) -
Adding collect file with Id: [1], File Name: [E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\
FailedFileRetryCollect.cvf]
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::FindCollectFileNames(7625) -
System collect file not found or is empty
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::FindCollectFileNames(7671) - OCS
collect file not found or is empty
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::FindCollectFileNames(7733) -
Adding collect file with Id: [2], File Name: [E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\SCFilesCol.cvf]
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::FindCollectFileNames(7432) - ---
0:00.006275
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::InitializeBackup(781) - ---
0:09.835469
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::Run(3269) - +++
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::Run(3299) - Restart map file not
present for the backup 'E:\Program Files\Commvault\ContentStore\iDataAgent\
JobResults\CV_JobResults\CommServe\2\1\CollectMap_Tot.cvf', starting a clean backup
36484 858 01/05 19:07:41 1641379 JOBRESCACHEDIR: opened index cache [E:\Program
Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641379]
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::CheckAddandFreeRequiredStreams(3263) - Unfinished Collect files [1],
Streams allowed at Subclient properties [1], Streams currently available [1],
Additional Streams Required [0]
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::Run(3841) - +++
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::Run(3868) - Thread assigner is
used
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::InitializeThreads(3784) - +++
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::InitializeThreads(3823) - The min
number of threads in the thread pool is [1] and max asked for is [2]
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::InitializeThreads(3784) - ---
0:00.005244
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::Run_AssignCollectToAvailableStreams(4949) - +++
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::Run_AssignCollectToAvailableStreams(4963) - Next collect to be
processed is [E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\NumColTot1.cvf]
36484 858 01/05 19:07:41 1641379 FsBackupTw::setCollectFile(619) - In clbackup
Object [1] setting the collect file name as [E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\NumColTot1.cvf] and
the restart offset is [-1]
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::Run_AssignCollectToAvailableStreams(4949) - --- 0:00.003424
36484 808c 01/05 19:07:41 1641379 FsBackupTw::Run(540) - +++ ObjectId=1,
CollectFileName=E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\NumColTot1.cvf
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::CheckAddandFreeRequiredStreams(3263) - Unfinished Collect files [1],
Streams allowed at Subclient properties [1], Streams currently available [1],
Additional Streams Required [0]
36484 808c 01/05 19:07:41 1641379 CBackupBase::DoBackup(2657) - +++
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::Run_AssignRemainingCollectsOnThreadFree(5031) - +++
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::Run_AssignRemainingCollectsOnThreadFree(5033) -
iMaxNumberOfThreads=[1], iCurrentlyAvailableStreams=[1],
iCurrentlyActiveStreams=[1]
36484 858 01/05 19:07:41 1641379
FsBackupCtlr::Run_AssignRemainingCollectsOnThreadFree(5031) - --- 0:00.002203
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::Run(3972) - Will not save local
drive information
36484 858 01/05 19:07:41 1641379 FsBackupCtlr::Run_WaitForThreadsToComplete(6010)
- +++
36484 808c 01/05 19:07:41 1641379 CBackupBase::CountLines(5807) - FileName=E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\NumColTot1.cvf, Lines=495
36484 808c 01/05 19:07:41 1641379 CBackupBase::DoBackup(2746) - CollectFile=E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\NumColTot1.cvf, NumberOfFilesItems=495
36484 808c 01/05 19:07:41 1641379 CFileBackup::SaveVolumeSizeInfo(4635) -
Volume=E:\, SRMEnabled=0, AppTypeId=1000, AppId=1, CommCellId=2
36484 808c 01/05 19:07:41 1641379 CFileBackup::IsScaleTestEnabled(6315) -
bEnabledScaleTest value is set to false
36484 808c 01/05 19:09:45 1641379 CBackupBase::DoBackup(2657) - --- 2:03.734359
36484 808c 01/05 19:09:45 1641379 FsBackupTw::Run(540) - --- 2:03.737388
ObjectId=1, CollectFileName=E:\Program Files\Commvault\ContentStore\iDataAgent\
JobResults\CV_JobResults\CommServe\2\1\NumColTot1.cvf
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run_WaitForThreadsToComplete(6023)
- Wait for multiple objects signalled. Releasing one reservation used of object [1]
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::PutInFileTheFailedFilesList(6326)
- FailedFileCnt [0]
36484 858 01/05 19:09:45 1641379
FsBackupCtlr::Run_WaitForThreadsToComplete_ReleaseStream(6155) -
iLatestObjectSignalled=[1], iCurrentlyActiveStreams=[0]
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run_WaitForThreadsToComplete(6010)
- --- 2:03.734864
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4018) -
iLatestObjectSignalled=[1], iMaxNumberOfThreads=[1],
iCurrentlyAvailableStreams=[1], iCurrentlyActiveStreams=[0]
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4068) - Evaluating failed file
retry collect for backup
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4071) - Next collect to be
processed is [E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\FailedFileRetryCollect.cvf]
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4075) - Failed files retry
collect is not present for this backup
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4113) - Starting backup of
subclient collects
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4116) - Next collect to be
processed is [E:\Program Files\Commvault\ContentStore\iDataAgent\JobResults\
CV_JobResults\CommServe\2\1\SCFilesCol.cvf]
36484 858 01/05 19:09:45 1641379 FsBackupTw::setCollectFile(619) - In clbackup
Object [1] setting the collect file name as [E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\SCFilesCol.cvf] and
the restart offset is [-1]
36484 858 01/05 19:09:45 1641379 CBackupBase::BackupSubclientFiles(6604) - +++
36484 858 01/05 19:09:45 1641379 CBackupBase::BackupSubclientFiles(6644) -
Subclient Content collect file not found, no files to back up, CollectFileName=E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\SCFilesCol.cvf
36484 858 01/05 19:09:45 1641379 CBackupBase::BackupSubclientFiles(6604) - ---
0:00.001629
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4121) - Successfully backed up
all subclient collects
36484 858 01/05 19:09:45 1641379 FsBackupCtlr::Run(4133) - Sending scan failures
to index
36484 858 01/05 19:09:45 1641379 CBackupBase::Close(4719) - +++
36484 858 01/05 19:09:45 1641379 CBackupBase::Close(4789) - Updating counts,
FailedFiles=0, FailedFolders=0, SkippedFiles=0
36484 858 01/05 19:09:45 1641379 CBackupBase::Close(4824) - Closing archive file
36484 858 01/05 19:09:45 1641379 CBackupBase::Close(4854) - Closing pipeline
36484 858 01/05 19:09:45 1641379 CBackupBase::Close(4860) - Sending PL_LAST to
tail.
36484 858 01/05 19:09:45 1641379 CBackupBase::Close(4864) - [1641379]
backupState(1=good)=1, closePlArgs.backupState=1
36484 858 01/05 19:09:46 1641379 SdtBase is being destroyed. RCId [5693817]
36484 858 01/05 19:09:46 1641379 Name [Head], Start Delay [17405.14 s], Avg
[69.94 ms], Procsng Time [106.68 s], Avg [428.68 us], Bytes [10.18 GB], Speed
[97.76 MB/s], Samples [248845] RCId [5693817]
36484 858 01/05 19:09:46 1641379 Name [Head CRC32], Start Delay [6.32 s], Avg
[25.38 us], Procsng Time [11.01 s], Avg [44.26 us], Bytes [10.18 GB], Speed [946.95
MB/s], Samples [248845] RCId [5693817]
36484 858 01/05 19:09:46 1641379 Name [Head Network], Start Delay [3281.46 s],
Avg [13.19 ms], Procsng Time [37.32 s], Avg [149.98 us], Bytes [10.18 GB], Speed
[279.44 MB/s], Samples [248845] RCId [5693817]
36484 858 01/05 19:09:46 1641379 CBackupBase::Close(4874) - Back from
closePipeline
36484 858 01/05 19:09:46 1641379 CBackupBase::Close(4719) - --- 0:01.697712
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::UploadMetadataFiles(9534) - +++
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::UploadMetadataFiles(9534) - ---
0:00.000643
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::Run(4936) - Completing the backup
of assigned collect files
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::Run(3841) - --- 2:05.463535
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::Run(3667) - Completed the backup
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::Run(3269) - --- 2:05.488543
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::Close(6380) - Closing [1] Threads
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::Close(6437) - Failed Files Count
[0], Locked File Count [0], MIME Classified Files Count [0]
36484 858 01/05 19:09:46 1641379 JOBRESULTSTABLE(E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641 removing copy 'E:\Program
Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\2\0\1641379'
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::CommitDirChangeFile(7286) -
Temporary DirChange container moved into place, From=[E:\Program Files\Commvault\
ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\2\1\DCTmp.cvf], To=[E:\
Program Files\Commvault\ContentStore\iDataAgent\JobResults\CV_JobResults\CommServe\
2\1\DCTot.cvf]
36484 858 01/05 19:09:46 1641379 FsBackupCtlr::DeleteShadowSets(13995) - Deleting
VSS Shadows. DeletePersistentShadows=[true], JobStatus=[1], JobStatusFor=[1]
36484 858 01/05 19:09:46 1641379 SHADOWSET::CShadowSet::DeleteShadow(122) -
Deleting shadow set [3ebbb0a3-f83e-439b-8bbc-ac0a574f9723]...
36484 858 01/05 19:09:46 1641379 CVSSClientBase::Init() - Cluster State=[0]
Ret=[0]
36484 858 01/05 19:09:47 1641379 CsSynchronization::MutexRelease() - Mutex not
initialized.
36484 858 01/05 19:09:47 1641379 BKP CALLED COMPLETE (PHASE Status::SUCCESS),
1641379. Token [1641379:8:1:0:0:27506]
36484 858 01/05 19:09:47 1641379 FsBackupCtlr::exitHere(7116) - Going to
terminate events and exit
36484 858 01/05 19:09:47 1641379 stat- ID [Hard Link Processing], Samples [493],
Time [0.008648] Sec(s), Average [0.000018] Sec/Sample
36484 858 01/05 19:09:47 1641379 stat- ID [FindFirstFile Open], Samples [493],
Time [0.084783] Sec(s), Average [0.000172] Sec/Sample
36484 858 01/05 19:09:47 1641379 stat- ID [Low Level File Open], Samples [493],
Time [25.484054] Sec(s), Average [0.051692] Sec/Sample
36484 858 01/05 19:09:47 1641379 stat- ID [FSDM load library], Samples [494],
Time [0.000246] Sec(s), Average [0.000000] Sec/Sample
36484 858 01/05 19:09:47 1641379 stat- ID [File Open time], Samples [494], Time
[26.206651] Sec(s), Average [0.053050] Sec/Sample
36484 858 01/05 19:09:47 1641379 stat- ID [Pipeline Write time], Bytes
[10893936670], Time [16.292373] Sec(s), Average Speed [637.676774] MB/Sec
36484 858 01/05 19:09:47 1641379 stat- ID [Disk Read time], Bytes [10893363548],
Time [76.803776] Sec(s), Average Speed [135.263155] MB/Sec
36484 858 01/05 19:09:47 1641379 stat- ID [Pipeline Allocation time], Bytes
[11005233152], Time [3.944938] Sec(s), Average Speed [2660.474615] MB/Sec
36484 858 01/05 19:09:47 1641379 main(312) -
36484 858 01/05 19:09:47 1641379 main(313) -
36484 858 01/05 19:09:47 1641379 main(314) -
36484 858 01/05 19:09:47 1641379 main(320) - -------------------------
36484 858 01/05 19:09:47 1641379 main(321) - CLBACKUP PROCESS ENDING
36484 858 01/05 19:09:47 1641379 main(322) - =========================
36484 858 01/05 19:09:47 1641379 main(324) -
36484 858 01/05 19:09:47 1641379 main(325) -
36484 858 01/05 19:09:47 1641379 main(326) -
36484 858 01/05 19:09:47 1641379 CvNetworkPool::~CvNetworkPool() - Controller
thread successfully stopped.

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : CVCloudService.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

520 64ac 01/05 19:07:09 1641379 CVCloudAzureService::InitAzureFunctions(584): -

Debug-: Creating DR Backup folder in Azure mode
520 64ac 01/05 19:07:10 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 64ac 01/05 19:07:11 1641379 CVCloudAzureService::InitAzureFunctions(712): -
Debug-: Success: Folder creation is successful
520 7958 01/05 19:07:11 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 2c10 01/05 19:07:11 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 33b4 01/05 19:07:11 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 9240 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 5178 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 70d4 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8fa0 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 25cc 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8cf4 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 76a0 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 81f0 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 104c 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8908 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8cf8 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 998 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 4304 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 7ea0 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8670 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 5060 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 7500 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 4e08 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 388c 01/05 19:07:12 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 723c 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 5f8c 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 15fc 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 28dc 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8240 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 80b4 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 1954 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 77c0 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 91c8 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8bd0 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 86e8 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 839c 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 6830 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 1650 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 5c10 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 69e8 01/05 19:07:13 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 7700 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8610 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 70c8 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 19b8 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 1598 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 1e90 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 4c50 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 7ea8 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 890c 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 69c8 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8508 01/05 19:07:14 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 865c 01/05 19:07:17 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8da8 01/05 19:07:17 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 204c 01/05 19:07:20 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 7588 01/05 19:07:24 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 853c 01/05 19:07:31 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8618 01/05 19:07:34 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 9124 01/05 19:08:17 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 6580 01/05 19:08:54 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 8638 01/05 19:14:09 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 64ac 01/05 19:21:26 1641379
CVCloudAzureService::FinalizeAzureFunctions(737): -Debug-: Calling DR Backup
Finalize in Azure mode
520 64ac 01/05 19:21:27 1641379 CvInternetGateway::setProxyFromCSDB() - Did not
find any HTTP Proxy settings in DB
520 64ac 01/05 19:21:28 1641379
CVCloudAzureService::FinalizeAzureFunctions(822): -Debug-: Success: Finalize DR
Backup is successful

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcomv01
File : ArchMgr.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

12812 a50 01/05 19:07:34 1641379 AMCS::getPlInfo110 I/P: app[1] AG/Cp[0-0]

jobType[4] appType[43] dataType[1] bkpLvl[1] AF[0] CC[2] clntTkn[0] flags[0]
12812 a50 01/05 19:07:34 1641379 AMCS::getPlInfo110 AG/Cop[2-1] DDB[0] sigSchm[0]
sigModWhr[0] SIMinObjSizeKB[0] SILvl[0] SIBlkKB [128] isClntDedup[0]
clientCacheDBSizeMB[0] clientCacheBuffKB[0] isVSA[0]numNWAgnt[2] compWhr[4]
wrtSpeed[4294967295] chnkMB[16384] jobUpdInt[300] encType[0] HLO[0] respFlags[3]
DedpFctr[0] RCID[5693817]
12812 a50 01/05 19:07:34 1641379 AMCS::getPlInfo110 Ovrride setting
prop(cmprsWhr=4) found for app[1] AG[2] copy[1] cmprsWhr[2]
12812 a50 01/05 19:07:34 1641379 AMCS::getPlInfo110 Src
I/F[PRDJWLCOMV01.tco.local] Dest I/F[PRDJWLCVMA02.tco.local]
PLSrcClnt[prdjwlcomv01] PLDestClnt[prdjwlcvma02] PLSrcCvdPort [8400]
PLSrcEvmgrCPort [0] PLDestCvdPort [8400] PLDestEvmgrCPort [0]
12812 4038 01/05 19:07:35 1641379 AMCS::handleGetMediaAgentReq GetMediaAgent50Req:
MediaAgent [PRDJWLCVMA02.tco.local*prdjwlcvma02*8400] for appId [1] CopyId [1][1]
12812 5bfc 01/05 19:07:35 1641379 AMCS::createArchFile CreateArchFile option flags
[32768] [pickStoragePolicyByRegion : false] , [updateJobInfoIfSPchanges : false],
opType [11], subOpType [0], RCId [5693817].
12812 5bfc 01/05 19:07:35 1641379 AMCS::createArchFile Created AF[2916613]
name[Not named] AG[2] flags[0] appId[1] fileType[1] cTime[1736129255]
jobId[1641379] bkpLvl[1] isValid[1] encRSA[0] encKeyType[0] encKeyId[0] Copy[1]
AFC_flags[0] clientId[0] appTypeId[1000] instanceId[0]
12812 7a04 01/05 19:08:17 1641379 ArchiveManagerCS::closeChunk Cmt Chnk[13799915]
cop[1] vol[3193405] CC[2] retCnt [0]
12812 76f0 01/05 19:08:59 1641379 ArchiveManagerCS::closeChunk Cmt Chnk[13799917]
cop[1] vol[3193405] CC[2] retCnt [0]
12812 76f0 01/05 19:09:45 1641379 ArchiveManagerCS::closeChunk Cmt Chnk[13799918]
cop[1] vol[3193405] CC[2] retCnt [0]
12812 4928 01/05 19:09:45 1641379 AMCS::resetAllocatedStream Set stream with RCID
[5693817] not in use for input MediaGroupId [1] RCID [5693817]. Rows affected [1]
12812 7f28 01/05 19:09:51 1641379 AMCS::getPlInfo110 I/P: app[1] AG/Cp[0-0]
jobType[4] appType[1] dataType[2] bkpLvl[1] AF[0] CC[2] clntTkn[0] flags[0]
12812 7f28 01/05 19:09:51 1641379 AMCS::getPlInfo110 AG/Cop[2-1] DDB[0] sigSchm[0]
sigModWhr[0] SIMinObjSizeKB[0] SILvl[0] SIBlkKB [128] isClntDedup[0]
clientCacheDBSizeMB[0] clientCacheBuffKB[0] isVSA[0]numNWAgnt[2] compWhr[4]
wrtSpeed[4294967295] chnkMB[16384] jobUpdInt[300] encType[0] HLO[0] respFlags[3]
DedpFctr[0] RCID[5693817]
12812 7f28 01/05 19:09:51 1641379 AMCS::getPlInfo110 Ovrride setting
prop(cmprsWhr=4) found for app[1] AG[2] copy[1] cmprsWhr[2]
12812 7f28 01/05 19:09:51 1641379 AMCS::getPlInfo110 Src
I/F[PRDJWLCVMA02.tco.local] Dest I/F[PRDJWLCVMA02.tco.local]
PLSrcClnt[prdjwlcvma02] PLDestClnt[prdjwlcvma02] PLSrcCvdPort [8400]
PLSrcEvmgrCPort [0] PLDestCvdPort [8400] PLDestEvmgrCPort [0]
12812 3514 01/05 19:09:52 1641379 AMCS::createArchFile CreateArchFile option flags
[32768] [pickStoragePolicyByRegion : false] , [updateJobInfoIfSPchanges : false],
opType [11], subOpType [0], RCId [5693817].
12812 3514 01/05 19:09:52 1641379 AMCS::createArchFile Created AF[2916614]
name[Not named] AG[2] flags[0] appId[1] fileType[2] cTime[1736129392]
jobId[1641379] bkpLvl[1] isValid[0] encRSA[0] encKeyType[0] encKeyId[0] Copy[1]
AFC_flags[0] clientId[0] appTypeId[1] instanceId[0]
12812 1024 01/05 19:09:53 1641379 ArchiveManagerCS::closeChunk Cmt Chnk[13799921]
cop[1] vol[3193405] CC[2] retCnt [0]
12812 67b8 01/05 19:09:53 1641379 AMCS::resetAllocatedStream Set stream with RCID
[5693817] not in use for input MediaGroupId [1] RCID [5693817]. Rows affected [1]

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : cvd.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
2444 4a8c 01/05 19:07:38 1641379 UICONNECTION Request for JobId 1641379 will be
handled by service with name [IndexingService_1] id [1]
2444 4a8c 01/05 19:07:38 1641379 UICONNECTION First stream for job 1641379 to
[IndexingService_1]
2444 4a8c 01/05 19:07:38 1641379 IDXMSGHANDLER Received heartbeat from
CreateIndex.Forwarding it to backup process
2444 3d94 01/05 19:07:38 1641379 Register pipe line for JID=1641379,
Token=1641379:8:1:0:0:27506, SDT Pipe
[SDTPipe_prdjwlcomv01_prdjwlcvma02_1641379_1736129254_36484_2136_0000022082C773B0],
CS [prdjwlcomv01], source client [prdjwlcomv01] RCId [5693817]
2444 3d94 01/05 19:07:38 1641379 Initialized the SDT callback object in
[0.000000] seconds. Head [prdjwlcomv01], Tail [prdjwlcvma02], Internal Id [4836]
RCId [5693817]
2444 3794 01/05 19:07:39 1641379 SdtTailSrvPool::AddClnt: Submitted client
[SDTPipe_prdjwlcomv01_prdjwlcvma02_1641379_1736129254_36484_2136_0000022082C773B0],
Id [12184] to srv [0]. Total Clients [6], Tail=000001B12FCC4030 RCId [5693817]
2444 1cfc 01/05 19:07:39 1641379 5693817-# [DSBACKUP ] Get
PL_SET_NETWORK_CRC_PROPS Message Type: 16 SubType: 158 Status: 0 EventErrorCode: 0
2444 1cfc 01/05 19:07:39 1641379 5693817-# [DSBACKUP ] Get
PL_SET_NETWORK_CRC_PROPS Message Has been Sent
2444 2c78 01/05 19:07:39 1641379 5693817-# [DSBACKUP ] DsBackup received a Init
Message
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_RECEIVER] DATARECIEVER Constructor
Invoked ... SDT Mode [ON]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DSBACKUP ] Initializing datamover
with Storage Policy Number:[2], MediaGroupId [1] AppId [1], Apptype [43], CopyId
[1], MAClientName [prdjwlcvma02], Flg [0]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_RECEIVER] Data Mover Type = [1],
DrivePoolType = [10001]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_RECEIVER] Did not find a
DataWriter for the media group [1]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_RECEIVER] Instantiating REGULAR
DataMover for MediaGroup Id [1]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_RECEIVER] Added a new DataWriter
for the media group [1].. will proceed to mount
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_RECEIVER] USE COUNT for media
group [1] is [1]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_BASE ] Initializing
DataMoverBase for MediaGroupId = 1 ...
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_BASE ] Maximum Number of
Archive Files allowed per Chunk during write mode is :20 ... IsCloudLaptopClient
[0]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_BASE ]
DataMoverMaxMountRetryValue [0] DataMoverMaxMountRetrySleepTime [0]
DataMoverMaxReadRetryValue [0] DataMoverWaitTimeBeforeChunkCloseInSec [0]
DataMoverCloseChunkImmediately [0]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_BASE ]
DataMoverMonitorThreadRetryValue [5], DataMoverMonitorThreadSleepValueMins[10]
2444 2c78 01/05 19:07:39 1641379 5693817-# [DM_BASE ] Going to mount
MediaGroupId = 1 for writing, RCID = 5693817
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DM_BASE ] Successfully
mounted Active volume 3193405 MediaGroupId = 1 for writing. Reservation id
[5374578]
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [MEDIAFS ] DataMoverMode =
[1]
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [MEDIAFS ] mediaagentfs.cpp
392 Number of files got from MLM is less than or equal to Zero
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [MEDIAFS ] The volume will be
recorded with the block size [512] KB
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32324092608, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [MEDIAFS ]
DiskSpaceCheckLimit is set to [250] MB. RegVal [0], IsHedvig [0]
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DM_BASE ] Registered RID
[5374578] with the monitoring thread.
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DM_RECEIVER] DataReciever Init
Successful for the Primary Data Writer
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] Initialize job
client with token [1641379:8:1:0:0:27506], jobid: 1641379
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ]
ShouldEnableIndexing() returned [1]. CloudLaptopClient [No]
EnableStorageAccelarator [0]
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] ArMgr returned
Indexing MA hostname [PRDJWLCVMA02.tco.local*prdjwlcvma02*8400]
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] Initializing
Indexing connection. MA
[prdjwlcvma02:PRDJWLCVMA02.tco.local*prdjwlcvma02*8400:prdjwlcvma02], msgingType
[API mode], cloudLaptop=[0], flgs=[0]
2444 2c78 01/05 19:07:39 1641379 UICONNECTION Request for JobId 1641379 will be
handled by service with name [IndexingService_1] id [1]
2444 2c78 01/05 19:07:39 1641379 UICONNECTION First stream for job 1641379 to
[IndexingService_1]
2444 2c78 01/05 19:07:39 1641379 UICONNECTION UIConnection::initialize successful
2444 2c78 01/05 19:07:39 1641379 IDXCONNECT Update Index has connected
successfully
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] dsBackup
initialization success Type: 16 SubType: 136 Status: 0 EventErrorCode: 0
2444 2c78 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] dsBackup
initialization success Has been Sent
2444 143c 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] DsBackup received
a Init Signature Message (SIDBStoreId - 0) (compressed - 0 ) (Source Side
Deduplication - 0)
2444 143c 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] signature
initialization success Type: 16 SubType: 319 Status: 0 EventErrorCode: 0
2444 143c 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] signature
initialization success Has been Sent
2444 1898 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] PL_SET_ENC_PROPS
Archfile(2916613 )
2444 1898 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] Get
PL_SET_ENC_PROPS Message Type: 16 SubType: 156 Status: 0 EventErrorCode: 0
2444 1898 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] Get
PL_SET_ENC_PROPS Message Has been Sent
2444 226c 01/05 19:07:39 1641379 5693817-5374578 [DSBACKUP ] Creating an
Archive File <2916613> UsedIndexing <1>
2444 226c 01/05 19:07:39 1641379 5693817-5374578 [DM_BASE ] Trying to create
archive file : afileId = 2916613, ArchiveFileSeqNo = 0,FirstChunkSeqNo= 0,
logical_offset = 0, physical_offset = 0 UsedIndexing = 1
2444 226c 01/05 19:07:39 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32324092608, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 226c 01/05 19:07:39 1641379 5693817-5374578 [DM_BASE ] The size of the
chunk will be around 4096 MB
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [DM_BASE ] Creating new chunk
id 13799915 VolId= 3193405
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32324092608, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32324092608, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [MEDIAFS ] Chunk file will be
extended in increments of 128 MB.
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNK_13799915> file
2444 226c 01/05 19:07:40 1641379 IDXCONNECT starting afile (2,2,2916613)
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [DSBACKUP ] Archive File
creation succeeded Type: 16 SubType: 131 Status: 0 EventErrorCode: 0
2444 226c 01/05 19:07:40 1641379 5693817-5374578 [DSBACKUP ] Archive File
creation succeeded Has been Sent
2444 294 01/05 19:07:46 1641379 5693817-5374578 [DSBACKUP ] Discarding Restart
Context 0 because it has not been populated
2444 294 01/05 19:07:46 1641379 5693817-5374578 [DSBACKUP ] Current Restart
Context has been set to 1 Type: 16 SubType: 291 Status: 0 EventErrorCode: 0
2444 294 01/05 19:07:46 1641379 5693817-5374578 [DSBACKUP ] Current Restart
Context has been set to 1 Has been Sent
2444 24e0 01/05 19:07:46 1641379 5693817-5374578 [DSBACKUP ] Job Update
Interval seconds [300]S
2444 4040 01/05 19:08:21 1641379 5693817-5374578 [MEDIAFS ]
RealMagneticFS::FinalizeChunkForBackup: Setting the end of file to offset
[0x00000000FFF00000]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Write time =36
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 4293918720
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DM_BASE ] Successfully
closed chunk on Media for archive file id =2916613, VolumeId = 3193405
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32315705536, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32315705536, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNKMAP_TRAILER_13799915> file
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Write time =0
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 4294443008
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916613], JobId [1641379], chunkSeqNumber[1], PhysicalOffset [0],
logicalOffset [0], physicalSize [4293865283], logicalSize[4281315379],
uncompSize[4281315461], primaryObjects [0], primaryObjSize [0],
primaryMetaDataSize[0], secondaryObjects [0], secondaryObjSize [0],
secondaryMetaDataSize[0], isSingleDataInstance [0], isCVSingleInstanced [0],
isCVSIWebStorage [0], isSoftwareCompressed [0], isNRS [0], isLastChunk [0] JobId:
[1641379] JobToken[1641379:8:1:0:0:27506].
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916613], Primary Objects [0], Size [0], Metadata Size [0]Secondary
Objects [0], Size [0], Metadata Size [0]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
Current Chunk Info : Chunk Id 13799915 CommcellId [2], CopyId[1], VolumeId
[3193405], FileNumber [1], Total No. of ArchFiles in Chunk [1], No. of ArchFiles
with NonZero size [1],Total amount data size in the chunk [4293865283], HwEncrypted
[N], EncKeySharedOnMedia [N]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DM_BASE ] Closed the chunk
with Id 13799915. Got New Chunk Id 13799917 from ArMgr
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DM_BASE ] Creating new chunk
id 13799917 VolId= 3193405
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32315705024, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32315705024, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] Chunk file will be
extended in increments of 128 MB.
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNK_13799917> file
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DSBACKUP ] Committing
previous restart info, CollectFileId: [1] CollectFileOffset: [719]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DSBACKUP ] Updating the Job
Manager that the chunk has been committed
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DSBACKUP ] The RESTART STRING
Sent to Indexing: [ -o 1|719] for Offset: 13390463
2444 4040 01/05 19:08:22 1641379 IDXCONNECT afile (2,2,2916613) is valid through
offset 13390463
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DSBACKUP ] The RESTART STRING
IS [ -o 1|719]. compBytes [13348817], UncompBytes [13348817], Files [4]
2444 4040 01/05 19:08:22 1641379 5693817-5374578 [DSBACKUP ] Sending Indexing
Restart Point XML to JM. XML = [<?xml version="1.0" encoding="UTF-8"
standalone="no" ?><Indexing_IndexingBackupRestartInformation cntOpensForWrite="3"
currentMaClientName="prdjwlcvma02" indexType="2" isRunningJob="1"
resetTotalObjectsBackedup="0" status="0" totalObjectsBackedup="11"/>]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ]
RealMagneticFS::FinalizeChunkForBackup: Setting the end of file to offset
[0x00000000FFF00000]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Write time =42
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 8588361728
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DM_BASE ] Successfully
closed chunk on Media for archive file id =2916613, VolumeId = 3193405
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32311642816, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32311642816, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNKMAP_TRAILER_13799917> file
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Write time =0
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 8588886016
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916613], JobId [1641379], chunkSeqNumber[2], PhysicalOffset
[4293865283], logicalOffset [4281315379], physicalSize [4293881127],
logicalSize[4281337524], uncompSize[4281337524], primaryObjects [0], primaryObjSize
[0], primaryMetaDataSize[0], secondaryObjects [0], secondaryObjSize [0],
secondaryMetaDataSize[0], isSingleDataInstance [0], isCVSingleInstanced [0],
isCVSIWebStorage [0], isSoftwareCompressed [0], isNRS [0], isLastChunk [0] JobId:
[1641379] JobToken[1641379:8:1:0:0:27506].
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916613], Primary Objects [0], Size [0], Metadata Size [0]Secondary
Objects [0], Size [0], Metadata Size [0]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
Current Chunk Info : Chunk Id 13799917 CommcellId [2], CopyId[1], VolumeId
[3193405], FileNumber [3], Total No. of ArchFiles in Chunk [1], No. of ArchFiles
with NonZero size [1],Total amount data size in the chunk [4293881127], HwEncrypted
[N], EncKeySharedOnMedia [N]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DM_BASE ] Closed the chunk
with Id 13799917. Got New Chunk Id 13799918 from ArMgr
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DM_BASE ] Creating new chunk
id 13799918 VolId= 3193405
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32311642304, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32311642304, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] Chunk file will be
extended in increments of 128 MB.
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNK_13799918> file
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DSBACKUP ] Committing
previous restart info, CollectFileId: [1] CollectFileOffset: [846]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DSBACKUP ] Updating the Job
Manager that the chunk has been committed
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DSBACKUP ] The RESTART STRING
Sent to Indexing: [ -o 1|846] for Offset: 13394395
2444 3bf0 01/05 19:09:04 1641379 IDXCONNECT afile (2,2,2916613) is valid through
offset 13394395
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DSBACKUP ] The RESTART STRING
IS [ -o 1|846]. compBytes [8112047796], UncompBytes [8112047796], Files [1]
2444 3bf0 01/05 19:09:04 1641379 5693817-5374578 [DSBACKUP ] Sending Indexing
Restart Point XML to JM. XML = [<?xml version="1.0" encoding="UTF-8"
standalone="no" ?><Indexing_IndexingBackupRestartInformation cntOpensForWrite="3"
currentMaClientName="prdjwlcvma02" indexType="2" isRunningJob="1"
resetTotalObjectsBackedup="0" status="0" totalObjectsBackedup="1"/>]
2444 143c 01/05 19:09:49 1641379 5693817-5374578 [DSBACKUP ] Current Restart
Context has been set to 3 Type: 16 SubType: 291 Status: 0 EventErrorCode: 0
2444 143c 01/05 19:09:49 1641379 5693817-5374578 [DSBACKUP ] Current Restart
Context has been set to 3 Has been Sent
2444 427c 01/05 19:09:49 1641379 5693817-5374578 [DSBACKUP ] Discarding Restart
Context 3 because it has not been populated
2444 427c 01/05 19:09:49 1641379 5693817-5374578 [DSBACKUP ] Current Restart
Context has been set to 4294967295 Type: 16 SubType: 291 Status: 0 EventErrorCode:
0
2444 427c 01/05 19:09:49 1641379 5693817-5374578 [DSBACKUP ] Current Restart
Context has been set to 4294967295 Has been Sent
2444 4028 01/05 19:09:49 1641379 5693817-5374578 [DM_RECEIVER] Recieved close for
ARCHIVE FILE ID = [2916613]
2444 4028 01/05 19:09:49 1641379 5693817-5374578 [DM_BASE ] Going to close
chunk for archive File [2916613] The current size of the chunk is [2337845975 ]
2444 4028 01/05 19:09:49 1641379 5693817-5374578 [MEDIAFS ]
RealMagneticFS::FinalizeChunkForBackup: Setting the end of file to offset
[0x000000008B600000]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] Write time =46
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 10927210496
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DM_BASE ] Successfully
closed chunk on Media for archive file id =2916613, VolumeId = 3193405
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32311193728, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32311193728, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNKMAP_TRAILER_13799918> file
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] Write time =0
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 10927734784
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916613], JobId [1641379], chunkSeqNumber[3], PhysicalOffset
[8587746410], logicalOffset [8562652903], physicalSize [2337845975],
logicalSize[2330710645], uncompSize[2330710645], primaryObjects [0], primaryObjSize
[0], primaryMetaDataSize[0], secondaryObjects [0], secondaryObjSize [0],
secondaryMetaDataSize[0], isSingleDataInstance [0], isCVSingleInstanced [0],
isCVSIWebStorage [0], isSoftwareCompressed [0], isNRS [0], isLastChunk [1] JobId:
[1641379] JobToken[1641379:8:1:0:0:27506].
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916613], Primary Objects [0], Size [0], Metadata Size [0]Secondary
Objects [0], Size [0], Metadata Size [0]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
Current Chunk Info : Chunk Id 13799918 CommcellId [2], CopyId[1], VolumeId
[3193405], FileNumber [5], Total No. of ArchFiles in Chunk [1], No. of ArchFiles
with NonZero size [1],Total amount data size in the chunk [2337845975], HwEncrypted
[N], EncKeySharedOnMedia [N]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DM_BASE ] Closed the chunk
with Id 13799918. Got New Chunk Id 13799920 from ArMgr
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DM_BASE ] Closing
ArchiveFile=2916613 LastChunkCreated=3 totPhysicalSize=10925592385
totLogicalSize=10893363548 UsedIndexing=1
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] Committing current
restart info, CollectFileId: [4294967295] CollectFileOffset: [0]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] Updating the Job
Manager that the chunk has been committed
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] The RESTART STRING
Sent to Indexing: [ -o 1|-1] for Offset: -1
2444 4028 01/05 19:09:50 1641379 IDXCONNECT entire afile (2,2,2916613) is valid
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] The RESTART STRING
IS [ -o 1|-1]. compBytes [2767967017], UncompBytes [2767967017], Files [469]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] Sending Indexing
Restart Point XML to JM. XML = [<?xml version="1.0" encoding="UTF-8"
standalone="no" ?><Indexing_IndexingBackupRestartInformation cntOpensForWrite="3"
currentMaClientName="prdjwlcvma02" indexType="2" isRunningJob="1"
resetTotalObjectsBackedup="0" status="0" totalObjectsBackedup="482"/>]
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] Close archive file
succeeded Type: 16 SubType: 132 Status: 0 EventErrorCode: 0
2444 4028 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] Close archive file
succeeded Has been Sent
2444 3b54 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] PL_FS_DESTROY
Destroying Data Mover
2444 3b54 01/05 19:09:50 1641379 5693817-5374578 [DSBACKUP ] Destroying
DataMover
2444 3b54 01/05 19:09:50 1641379 5693817-5374578 [DM_RECEIVER] USE COUNT for
media group [1] is [0]
2444 3b54 01/05 19:09:50 1641379 5693817-5374578 [DM_RECEIVER] Destroying the
DataWriter for media group [1]. and removing from the map
2444 3b54 01/05 19:09:50 1641379 5693817-5374578 [DM_BASE ] UnReserving the
backup volume 3193405
2444 3b54 01/05 19:09:51 1641379 5693817-# [DM_BASE ] Destroyed DataMoverBase
for MediaGroupId=[1]
2444 3b54 01/05 19:09:51 1641379 5693817-# [DSBACKUP ] DataMover destroyed
Type: 16 SubType: 133 Status: 0 EventErrorCode: 0
2444 3b54 01/05 19:09:51 1641379 5693817-# [DSBACKUP ] DataMover destroyed Has
been Sent
2444 4028 01/05 19:09:51 1641379 5693817-# [DSBACKUP ] telling Index that
backup is done, backupState = 1
2444 4028 01/05 19:09:51 1641379 5693817-# [DSBACKUP ] Tail Generated STOP_PL
and ready to Terminate Type: 4 SubType: 2 Status: 0 EventErrorCode: 0
2444 4028 01/05 19:09:51 1641379 5693817-# [DSBACKUP ] Tail Generated STOP_PL
and ready to Terminate Has been Sent
2444 1958 01/05 19:09:51 1641379 SdtBase::setLastErr: Setting last err [92][The
other side has closed the network connection gracefully] RCId [5693817]
2444 1958 01/05 19:09:51 1641379 SdtTail::onIncomingData: Client
[SDTPipe_prdjwlcomv01_prdjwlcvma02_1641379_1736129254_36484_2136_0000022082C773B0].
Id [16064]. Connection was terminated gracefully. RCId [5693817]
2444 a18 01/05 19:09:51 1641379 SdtTailSrv::DropClientWorker: Removing Client
[SDTPipe_prdjwlcomv01_prdjwlcvma02_1641379_1736129254_36484_2136_0000022082C773B0],
Id [16064] from the list. RCId [5693817]
2444 a18 01/05 19:09:51 1641379 SdtTailSrv::DropClientWorker: Size of selected
list [0] RCId [5693817]
2444 a18 01/05 19:09:51 1641379 SdtTailSrv::DropClientWorker: Not draining away
incoming data because the socket was closed. Graceful [1] RCId [5693817]
2444 a18 01/05 19:09:51 1641379 5693817-# [DSBACKUP ] CVMABackup object
destroyed..
2444 a18 01/05 19:09:51 1641379 5693817-# [DM_RECEIVER] Destroyed
DataReceiverBase. MediaGroupId=[1], AFId=[2916613]
2444 a18 01/05 19:09:51 1641379 SdtTail::logStats:Final Clnt [prdjwlcomv01], PId
[36484], Cnt - BsyProcsg/Allocs [18877/248846]; Time - Procsg/NwRecv/Total
[26/107/133] secs, Bytes recvd [10935613816] RCId [5693817]
2444 a18 01/05 19:09:51 1641379 SdtBase is being destroyed. RCId [5693817]
2444 a18 01/05 19:09:51 1641379 Name [Tail Network], Start Delay [9115.43 s],
Avg [36.63 ms], Procsng Time [107.45 s], Avg [431.79 us], Bytes [10.18 GB], Speed
[97.06 MB/s], Samples [248845] RCId [5693817]
2444 a18 01/05 19:09:51 1641379 Name [Tail End], Start Delay [2371.07 s], Avg
[9.53 ms], Procsng Time [47.80 s], Avg [192.11 us], Bytes [10.18 GB], Speed [218.16
MB/s], Samples [248845] RCId [5693817]
2444 3794 01/05 19:09:56 1641379 Register pipe line for JID=1641379,
Token=1641379:10:1:3:0:27506, SDT Pipe
[SDTPipe_prdjwlcvma02_prdjwlcvma02_1641379_1736129396_16544_17288_0000024891668F70]
, CS [prdjwlcomv01], source client [prdjwlcvma02] RCId [5693817]
2444 3794 01/05 19:09:56 1641379 Initialized the SDT callback object in
[0.000000] seconds. Head [prdjwlcvma02], Tail [prdjwlcvma02], Internal Id [16064]
RCId [5693817]
2444 e04 01/05 19:09:56 1641379 SdtTailSrvPool::AddClnt: Submitted client
[SDTPipe_prdjwlcvma02_prdjwlcvma02_1641379_1736129396_16544_17288_0000024891668F70]
, Id [17048] to srv [0]. Total Clients [6], Tail=000001B12FCC4030 RCId [5693817]
2444 24e0 01/05 19:09:56 1641379 5693817-# [DSBACKUP ] Get
PL_SET_NETWORK_CRC_PROPS Message Type: 16 SubType: 158 Status: 0 EventErrorCode: 0
2444 24e0 01/05 19:09:56 1641379 5693817-# [DSBACKUP ] Get
PL_SET_NETWORK_CRC_PROPS Message Has been Sent
2444 19a0 01/05 19:09:56 1641379 5693817-# [DSBACKUP ] DsBackup received a Init
Message
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_RECEIVER] DATARECIEVER Constructor
Invoked ... SDT Mode [ON]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DSBACKUP ] Initializing datamover
with Storage Policy Number:[2], MediaGroupId [1] AppId [1], Apptype [1], CopyId
[1], MAClientName [prdjwlcvma02], Flg [0]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_RECEIVER] Data Mover Type = [1],
DrivePoolType = [10001]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_RECEIVER] Did not find a
DataWriter for the media group [1]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_RECEIVER] Instantiating REGULAR
DataMover for MediaGroup Id [1]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_RECEIVER] Added a new DataWriter
for the media group [1].. will proceed to mount
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_RECEIVER] USE COUNT for media
group [1] is [1]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_BASE ] Initializing
DataMoverBase for MediaGroupId = 1 ...
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_BASE ] Maximum Number of
Archive Files allowed per Chunk during write mode is :20 ... IsCloudLaptopClient
[0]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_BASE ]
DataMoverMaxMountRetryValue [0] DataMoverMaxMountRetrySleepTime [0]
DataMoverMaxReadRetryValue [0] DataMoverWaitTimeBeforeChunkCloseInSec [0]
DataMoverCloseChunkImmediately [0]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_BASE ]
DataMoverMonitorThreadRetryValue [5], DataMoverMonitorThreadSleepValueMins[10]
2444 19a0 01/05 19:09:56 1641379 5693817-# [DM_BASE ] Going to mount
MediaGroupId = 1 for writing, RCID = 5693817
2444 19a0 01/05 19:09:56 1641379 5693817-5374578 [DM_BASE ] Successfully
mounted Active volume 3193405 MediaGroupId = 1 for writing. Reservation id
[5374578]
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] DataMoverMode =
[1]
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] The volume will be
recorded with the block size [512] KB
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32313420928, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ]
DiskSpaceCheckLimit is set to [250] MB. RegVal [0], IsHedvig [0]
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [DM_BASE ] Registered RID
[5374578] with the monitoring thread.
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [DM_RECEIVER] DataReciever Init
Successful for the Primary Data Writer
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Initialize job
client with token [1641379:10:1:3:0:27506], jobid: 1641379
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ]
ShouldEnableIndexing() returned [0]. CloudLaptopClient [No]
EnableStorageAccelarator [0]
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] dsBackup
initialization success Type: 16 SubType: 136 Status: 0 EventErrorCode: 0
2444 19a0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] dsBackup
initialization success Has been Sent
2444 15bc 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] DsBackup received
a Init Signature Message (SIDBStoreId - 0) (compressed - 0 ) (Source Side
Deduplication - 0)
2444 15bc 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] signature
initialization success Type: 16 SubType: 319 Status: 0 EventErrorCode: 0
2444 15bc 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] signature
initialization success Has been Sent
2444 15b0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] PL_SET_ENC_PROPS
Archfile(2916614 )
2444 15b0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Get
PL_SET_ENC_PROPS Message Type: 16 SubType: 156 Status: 0 EventErrorCode: 0
2444 15b0 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Get
PL_SET_ENC_PROPS Message Has been Sent
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Creating an
Archive File <2916614> UsedIndexing <0>
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [DM_BASE ] Trying to create
archive file : afileId = 2916614, ArchiveFileSeqNo = 0,FirstChunkSeqNo= 0,
logical_offset = 0, physical_offset = 0 UsedIndexing = 0
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32313420928, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [DM_BASE ] The size of the
chunk will be around 4096 MB
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [DM_BASE ] Creating new chunk
id 13799921 VolId= 3193405
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32313420928, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32313420928, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] Chunk file will be
extended in increments of 128 MB.
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNK_13799921> file
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Archive File
creation succeeded Type: 16 SubType: 131 Status: 0 EventErrorCode: 0
2444 4028 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Archive File
creation succeeded Has been Sent
2444 1898 01/05 19:09:57 1641379 5693817-5374578 [DSBACKUP ] Job Update
Interval seconds [300]S
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_RECEIVER] Recieved close for
ARCHIVE FILE ID = [2916614]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_BASE ] Going to close
chunk for archive File [2916614] The current size of the chunk is [2743066 ]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ]
RealMagneticFS::FinalizeChunkForBackup: Setting the end of file to offset
[0x0000000000300000]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] Write time =1
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 3145728
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_BASE ] Successfully
closed chunk on Media for archive file id =2916614, VolumeId = 3193405
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32313417856, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] Magnetic Mount
Path :- KBRemaning 32313417856, Minimum space MB to be left [102400], maximum space
MB to be filled [-1]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] RealMagneticFS
Created <\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405\CHUNKMAP_TRAILER_13799921> file
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] Write time =0
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [MEDIAFS ] Total valid data
size 3670016
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916614], JobId [1641379], chunkSeqNumber[1], PhysicalOffset [0],
logicalOffset [0], physicalSize [2743066], logicalSize[2724351],
uncompSize[2724351], primaryObjects [0], primaryObjSize [0],
primaryMetaDataSize[0], secondaryObjects [0], secondaryObjSize [0],
secondaryMetaDataSize[0], isSingleDataInstance [0], isCVSingleInstanced [0],
isCVSIWebStorage [0], isSoftwareCompressed [0], isNRS [0], isLastChunk [1] JobId:
[1641379] JobToken[1641379:10:1:3:0:27506].
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
ArchFileId [2916614], Primary Objects [0], Size [0], Metadata Size [0]Secondary
Objects [0], Size [0], Metadata Size [0]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_CHUNK ] [CloseChunkInfo]
Current Chunk Info : Chunk Id 13799921 CommcellId [2], CopyId[1], VolumeId
[3193405], FileNumber [7], Total No. of ArchFiles in Chunk [1], No. of ArchFiles
with NonZero size [1],Total amount data size in the chunk [2743066], HwEncrypted
[N], EncKeySharedOnMedia [N]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_BASE ] Closed the chunk
with Id 13799921. Got New Chunk Id 13799922 from ArMgr
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DM_BASE ] Closing
ArchiveFile=2916614 LastChunkCreated=1 totPhysicalSize=2743066
totLogicalSize=2724351 UsedIndexing=0
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DSBACKUP ] Committing current
restart info, CollectFileId: [0] CollectFileOffset: [1470]
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DSBACKUP ] Updating the Job
Manager that the chunk has been committed
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DSBACKUP ] Close archive file
succeeded Type: 16 SubType: 132 Status: 0 EventErrorCode: 0
2444 3714 01/05 19:09:58 1641379 5693817-5374578 [DSBACKUP ] Close archive file
succeeded Has been Sent
2444 2b20 01/05 19:09:58 1641379 5693817-5374578 [DSBACKUP ] PL_FS_DESTROY
Destroying Data Mover
2444 2b20 01/05 19:09:58 1641379 5693817-5374578 [DSBACKUP ] Destroying
DataMover
2444 2b20 01/05 19:09:58 1641379 5693817-5374578 [DM_RECEIVER] USE COUNT for
media group [1] is [0]
2444 2b20 01/05 19:09:58 1641379 5693817-5374578 [DM_RECEIVER] Destroying the
DataWriter for media group [1]. and removing from the map
2444 2b20 01/05 19:09:58 1641379 5693817-5374578 [DM_BASE ] UnReserving the
backup volume 3193405
2444 2b20 01/05 19:10:03 1641379 5693817-# [DM_BASE ] Destroyed DataMoverBase
for MediaGroupId=[1]
2444 2b20 01/05 19:10:03 1641379 5693817-# [DSBACKUP ] DataMover destroyed
Type: 16 SubType: 133 Status: 0 EventErrorCode: 0
2444 2b20 01/05 19:10:03 1641379 5693817-# [DSBACKUP ] DataMover destroyed Has
been Sent
2444 12e8 01/05 19:10:03 1641379 5693817-# [DSBACKUP ] Tail Generated STOP_PL
and ready to Terminate Type: 4 SubType: 2 Status: 0 EventErrorCode: 0
2444 12e8 01/05 19:10:03 1641379 5693817-# [DSBACKUP ] Tail Generated STOP_PL
and ready to Terminate Has been Sent
2444 1958 01/05 19:10:03 1641379 SdtBase::setLastErr: Setting last err [92][The
other side has closed the network connection gracefully] RCId [5693817]
2444 1958 01/05 19:10:03 1641379 SdtTail::onIncomingData: Client
[SDTPipe_prdjwlcvma02_prdjwlcvma02_1641379_1736129396_16544_17288_0000024891668F70]
. Id [17048]. Connection was terminated gracefully. RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 SdtTailSrv::DropClientWorker: Removing Client
[SDTPipe_prdjwlcvma02_prdjwlcvma02_1641379_1736129396_16544_17288_0000024891668F70]
, Id [17048] from the list. RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 SdtTailSrv::DropClientWorker: Size of selected
list [0] RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 SdtTailSrv::DropClientWorker: Not draining away
incoming data because the socket was closed. Graceful [1] RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 5693817-# [DSBACKUP ] CVMABackup object
destroyed..
2444 1cfc 01/05 19:10:03 1641379 5693817-# [DM_RECEIVER] Destroyed
DataReceiverBase. MediaGroupId=[1], AFId=[2916614]
2444 1cfc 01/05 19:10:03 1641379 SdtTail::logStats:Final Clnt [prdjwlcvma02], PId
[16544], Cnt - BsyProcsg/Allocs [0/63]; Time - Procsg/NwRecv/Total [0/7/7] secs,
Bytes recvd [2747594] RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 SdtBase is being destroyed. RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 Name [Tail Network], Start Delay [11.11 s], Avg
[179.19 ms], Procsng Time [7.14 s], Avg [115.13 ms], Bytes [2.62 MB], Speed [375.91
KB/s], Samples [62] RCId [5693817]
2444 1cfc 01/05 19:10:03 1641379 Name [Tail End], Start Delay [7.86 ms], Avg
[126.83 us], Procsng Time [6.21 s], Avg [100.21 ms], Bytes [2.62 MB], Speed [431.86
KB/s], Samples [62] RCId [5693817]

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : archiveIndex.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

16544 4388 01/05 19:09:56 1641379 Cvcl::init() - CVCL: Running in FIPS Mode
16544 4388 01/05 19:09:56 1641379 CBackupBase::RegisterWithJobManager(1125) - Job
has been registered successfully
16544 4388 01/05 19:09:56 1641379 CvNetworkPool::initNetworkPool() - Creating the
control thread
16544 3424 01/05 19:09:56 1641379 thNetworkControllerThread() - Controller thread
started
16544 4388 01/05 19:09:56 1641379 CBackupBase::Initialize(1355) - +++
16544 4388 01/05 19:09:56 1641379 CBackupBase::Initialize(1395) - Flag failAfter=0
(0=don't fail!)
16544 4388 01/05 19:09:56 1641379 CBackupBase::logonAsUser(5978) - Skipping UNC
user impersonation for Archive Index phase
16544 4388 01/05 19:09:56 1641379 CBackupBase::Initialize(1521) - [1641379]
Getting new CVArchive
16544 4388 01/05 19:09:56 1641379 Enable Dedup for Index data [false]
16544 4388 01/05 19:09:56 1641379 CBackupBase::Initialize(1560) - Going to setup
pipeline through CVArchive
16544 4388 01/05 19:09:56 1641379 CVArchive::ConfigureBlockAlignment() -
Deduplication is not enabled. Disabling block alignment.
16544 4388 01/05 19:09:56 1641379 Encryption is disabled for DataType 2
16544 4388 01/05 19:09:56 1641379 N/w agents configured before/after firewall
check = [2/2]. Firewalled = 0
16544 4388 01/05 19:09:56 1641379 CPipelayer::InitiatePipeline Initiating SDT
connection [0000024891668F70] from PRDJWLCVMA02.tco.local:8400(prdjwlcvma02) to
PRDJWLCVMA02.tco.local:8400/8400(prdjwlcvma02)
16544 4388 01/05 19:09:56 1641379 CvFwClient::connectDirectly(): Connected to
PRDJWLCVMA02.tco.local:8400/8400 using direct system connect
16544 4388 01/05 19:09:56 1641379 CvFwClient::connectDirectly(): Connected to
PRDJWLCVMA02.tco.local:8400/8400 using direct system connect
16544 4388 01/05 19:09:57 1641379 CBackupBase::Initialize(1577) - Pipeline setup
completed
16544 4388 01/05 19:09:57 1641379 CBackupBase::Initialize(1581) - Disabling
CVArchive's file signature as this is not a turbo job or not a FS type job
16544 4388 01/05 19:09:57 1641379 CBackupBase::Initialize(1601) - appType = 1,
archFileFlags = 0
16544 4388 01/05 19:09:57 1641379 CBackupBase::CreateNewArchiveFile(2390) -
Creating archive file with parameters: IsValid=0, AppId=1, AppTypeId=0,
ArchiveFileName=Not named, BackupLevel=1, ArchiveFileFLags=0x0, DataType=2
inputCTime=0
16544 4388 01/05 19:09:57 1641379 CBackupBase::CreateNewArchiveFile(2403) - New
archive file created. FileNumber=2916614, GroupNumber=2, CreateTime=1736129392
(1/6/2025 2:09:52 AMZ)
16544 4388 01/05 19:09:57 1641379 CBackupBase::Initialize(1355) - --- 0:01.072844
16544 4388 01/05 19:09:57 1641379 doArchiveIndex(511) - After initialize
16544 4388 01/05 19:09:57 1641379 CFileBackup::initialize(562) - +++
16544 4388 01/05 19:09:57 1641379 StaticUtils::calculateSimOSId() - OS = [x64
Server], SIM OS ID = [210]
16544 4388 01/05 19:09:57 1641379 CFileBackup::initialize(834) - Failed to get
"ACL" and "Catalog additional files attributes" property for client id 0, as
appication id is empty
16544 4388 01/05 19:09:57 1641379 CFileBackup::initialize(852) - ACL collection
for client 0 is not enabled
16544 4388 01/05 19:09:57 1641379 CFileBackup::initialize(853) - Catalog
Additional file attribute for client 0 is not enabled
16544 4388 01/05 19:09:57 1641379
CFileBackup::GetPerfCounterLoggingInterval(14245) - Using default performance
counters logging interval [30 Minutes]
16544 4388 01/05 19:09:57 1641379 CFileBackup::initialize(562) - --- 0:00.009385
16544 4388 01/05 19:09:57 1641379 FIXINDEX index checkpointing remains disabled
based on task options
16544 4388 01/05 19:09:57 1641379 FIXINDEX Effective failover flags are:
UseICS=FALSE, UseTL=FALSE, Checkpointing=FALSE (scheduler flags=[TRUE, FALSE,
FALSE])
16544 4388 01/05 19:09:57 1641379 FIXINDEX icd to be initialized (prdjwlcvma02)
16544 4388 01/05 19:09:57 1641379 FIXINDEX icd to be initialized (prdjwlcvma02)
after finding the job status file
16544 4388 01/05 19:09:57 1641379 INDEXCACHEDIR: WARNING: CTree ISAM was
initialized during check.
16544 4388 01/05 19:09:57 1641379 doArchiveIndex(602) - Collect=I:\Program Files\
Commvault\ContentStore\IndexCache\CV_Index\collects\1641379.txt,
createTime=1736129392
16544 4388 01/05 19:09:57 1641379 FIXINDEX begin index post processing
16544 4388 01/05 19:09:57 1641379 PostProcessIndex INFO: missing version for the
node [] (1) detected (image is 0 but current image is 1)
16544 4388 01/05 19:09:57 1641379 PostProcessIndex calculated image
size=10889356923. Index Cache contains 474 files and 21 folders.
16544 4388 01/05 19:09:57 1641379 FIXINDEX Checking for tree table count [I:\
Program Files\Commvault\ContentStore\IndexCache\CV_Index\2\1\1736129253\
synthfullTreeRecordCount_1641379.txt] if any
16544 4388 01/05 19:09:57 1641379 FIXINDEX File to check count is not preset [I:\
Program Files\Commvault\ContentStore\IndexCache\CV_Index\2\1\1736129253\
synthfullTreeRecordCount_1641379.txt]
16544 4388 01/05 19:09:57 1641379 RESTOREIDX raw partition option and no stubs
option remain disabled
16544 4388 01/05 19:09:57 1641379 CBackupBase::DoBackup(2657) - +++
16544 4388 01/05 19:09:57 1641379 CBackupBase::CountLines(5807) - FileName=I:\
Program Files\Commvault\ContentStore\IndexCache\CV_Index\collects\1641379.txt,
Lines=18
16544 4388 01/05 19:09:57 1641379 CBackupBase::DoBackup(2746) - CollectFile=I:\
Program Files\Commvault\ContentStore\IndexCache\CV_Index\collects\1641379.txt,
NumberOfFilesItems=18
16544 4388 01/05 19:09:57 1641379 CFileBackup::IsScaleTestEnabled(6315) -
bEnabledScaleTest value is set to false
16544 4388 01/05 19:09:58 1641379 CBackupBase::DoBackup(2657) - --- 0:00.112226
16544 4388 01/05 19:09:58 1641379 doArchiveIndex(727) - Set IndexingRestart info
to '<?xml version="1.0" encoding="UTF-8" standalone="no" ?
><Indexing_IndexingBackupRestartInformation currentMaClientName="prdjwlcvma02"
indexType="2" isRunningJob="1" resetTotalObjectsBackedup="0" status="0"/>'
16544 4388 01/05 19:09:58 1641379 ClBackupIndex::Close() - [1641379] Closing
archive file
16544 4388 01/05 19:09:58 1641379 ClBackupIndex::Close() - [1641379] Closing
pipeline.
16544 4388 01/05 19:09:58 1641379 ClBackupIndex::Close() - [1641379] Sending
PL_LAST to tail.
16544 4388 01/05 19:09:58 1641379 ClBackupIndex::Close() - [1641379]
backupState(1=good) = 1, closePlArgs.backupState = 1
16544 4388 01/05 19:10:03 1641379 SdtBase is being destroyed. RCId [5693817]
16544 4388 01/05 19:10:03 1641379 Name [Head], Start Delay [19.27 s], Avg [310.75
ms], Procsng Time [132.91 ms], Avg [2.14 ms], Bytes [2.62 MB], Speed [19.71 MB/s],
Samples [62] RCId [5693817]
16544 4388 01/05 19:10:03 1641379 Name [Head CRC32], Start Delay [11.18 ms], Avg
[180.25 us], Procsng Time [4.60 ms], Avg [74.20 us], Bytes [2.62 MB], Speed [569.55
MB/s], Samples [62] RCId [5693817]
16544 4388 01/05 19:10:03 1641379 Name [Head Network], Start Delay [21.78 ms], Avg
[351.26 us], Procsng Time [8.82 ms], Avg [142.19 us], Bytes [2.62 MB], Speed
[297.22 MB/s], Samples [62] RCId [5693817]
16544 4388 01/05 19:10:03 1641379 ClBackupIndex::Close() - [1641379] back from
closePipeline
16544 4388 01/05 19:10:03 1641379 ClBackupIndex::Close() - doing postBackup()
fixindex
16544 4388 01/05 19:10:03 1641379 FIXINDEX renaming I:\Program Files\Commvault\
ContentStore\IndexCache\CV_Index\2\1\1736129253 to I:\Program Files\Commvault\
ContentStore\IndexCache\CV_Index\2\1\1736129392
16544 4388 01/05 19:10:03 1641379 FIXINDEX Folder 'I:\Program Files\Commvault\
ContentStore\IndexCache\CV_Index\2\1\1736129392' is accessible
16544 4388 01/05 19:10:03 1641379 INDEXCACHEDIR: removing TL from 'I:\Program
Files\Commvault\ContentStore\IndexCache\CV_Index\2\1\1736129392' (cacheDir='I:\
Program Files\Commvault\ContentStore\IndexCache\CV_Index\2\1\1736129392')
16544 4388 01/05 19:10:03 1641379 FIXINDEX removing 'I:\Program Files\Commvault\
ContentStore\IndexCache\CV_Index\2\1\1736129392\1641379.txt.JobStatus'
16544 4388 01/05 19:10:03 1641379 FIXINDEX FixIndex::indexCleanup: cleaning up
redundant cache dirs
16544 4388 01/05 19:10:03 1641379 ClBackupIndex::exitHere() - [1641379] Phase
successful! Updating backup reference time 1736129236 with Job Manager
16544 4388 01/05 19:10:03 1641379 ClBackupIndex::exitHere() - [1641379] Notifying
Job Manager of phase success.
16544 4388 01/05 19:10:03 1641379 BKP CALLED COMPLETE (PHASE Status::SUCCESS),
1641379. Token [1641379:10:1:3:0:27506]
16544 4388 01/05 19:10:03 1641379 ClBackupIndex::exitHere() - [1641379] going to
delete evbackupreturn
16544 4388 01/05 19:10:03 1641379 ClBackupIndex::exitHere() - [1641379] going to
terminate events and exit
16544 4388 01/05 19:10:04 1641379 stat- ID [FindFirstFile Open], Samples [18],
Time [0.001321] Sec(s), Average [0.000073] Sec/Sample
16544 4388 01/05 19:10:04 1641379 stat- ID [Low Level File Open], Samples [18],
Time [0.076562] Sec(s), Average [0.004253] Sec/Sample
16544 4388 01/05 19:10:04 1641379 stat- ID [File Open time], Samples [19], Time
[0.079204] Sec(s), Average [0.004169] Sec/Sample
16544 4388 01/05 19:10:04 1641379 stat- ID [Pipeline Write time], Bytes [2745231],
Time [0.000700] Sec(s), Average Speed [3738.897976] MB/Sec
16544 4388 01/05 19:10:04 1641379 stat- ID [Disk Read time], Bytes [2724351], Time
[0.006246] Sec(s), Average Speed [415.942302] MB/Sec
16544 4388 01/05 19:10:04 1641379 stat- ID [Pipeline Allocation time], Bytes
[7862440], Time [0.003034] Sec(s), Average Speed [2471.022520] MB/Sec
16544 4388 01/05 19:10:04 1641379 CvNetworkPool::~CvNetworkPool() - Controller
thread successfully stopped.
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : UpdateIndex.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

17356 277c 01/05 19:07:39 1641379 TUpdateIndexHandler:: Initializing TupdateIndex

object for client [prdjwlcvma02],job [1641379],bType [1],commCellId [2],appNumber
[1],appType [43]
17356 277c 01/05 19:07:39 1641379 TMonitorJob: Registry key MediaAgent\
heartBeatPeriod is not present, defaulting to 5 minutes
17356 277c 01/05 19:07:39 1641379 TMonitorJob: Registry key MediaAgent\
JMQuitCheckPeriod is not present, defaulting to 1 minute
17356 277c 01/05 19:07:39 1641379 UPDATEINDEX index checkpointing remains disabled
based on task options
17356 277c 01/05 19:07:39 1641379 UPDATEINDEX Effective failover flags are:
UseICS=FALSE, UseTL=FALSE, Checkpointing=FALSE (scheduler flags=[TRUE, FALSE,
FALSE])
17356 1078 01/05 19:09:50 1641379 UPDATEINDEX updateRestartInfo: offset indicates
archive file (2,2,2916613) successfully closed
17356 2ea4 01/05 19:09:51 1641379 UPDATEINDEX received backup completion message
17356 2ea4 01/05 19:09:51 1641379 INDEXCACHEDIR: in IndexCacheDir::backup()
17356 2ea4 01/05 19:09:51 1641379 UPDATEINDEX terminating due to backup completion
17356 2ea4 01/05 19:09:51 1641379 UPDATEINDEX Attempt statistics: Streams=1,
Items=494, Chunks=3, aFiles=1, processing time=0.077314 s, total time=131.806831 s,
average processing time=0.000156 s, average wait time=0.265049 s

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : IndexingService.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

17356 2ea4 01/05 19:09:51 1641379 ProcessRequest Completed processing for

[1641379] , discarding worker

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : CreateIndex.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

17356 1078 01/05 19:07:38 1641379 TCreateIndex::initialize commserve

[prdjwlcomv01]
17356 1078 01/05 19:07:38 1641379 TMonitorJob: Registry key MediaAgent\
heartBeatPeriod is not present, defaulting to 5 minutes
17356 1078 01/05 19:07:38 1641379 TMonitorJob: Registry key MediaAgent\
JMQuitCheckPeriod is not present, defaulting to 1 minute
17356 1078 01/05 19:07:38 1641379 TMonitorJob: Sending XDR heartbeat to keep
connection alive
17356 1078 01/05 19:07:38 1641379 TCreateIndex::initialize guiOverrideReceived
false, guiOverride 0
17356 1078 01/05 19:07:38 1641379 TCreateIndex::initialize Remote machine short
name: prdjwlcomv01
17356 1078 01/05 19:07:38 1641379 TCreateIndex::initialize createIndex executed
for commCell 2 appNumber 1 btype 1 findStart 1736129253 jobToken
'1641379:8:1:0:0:27506'
17356 1078 01/05 19:07:38 1641379 TCreateIndex::initialize Index cache pruning for
aged data will be done as part of centralized cleanup
17356 1078 01/05 19:07:38 1641379 TCreateIndex::initialize INFO: Overriding the
create new index to 1 for FULL and SYNTHFULL
17356 1078 01/05 19:07:38 1641379 INDEXCACHEDIR: getAfileIdByTime: commCell 2 app
1 time 2147483647 afile 2/2/2915187 type (requested) 2 size 2724351 createTime
1736043014 copyId 0 refCopy 0
17356 1078 01/05 19:07:38 1641379 INDEXCACHEDIR: job 1641379 creating 'I:\Program
Files\Commvault\ContentStore\IndexCache\CV_Index\2\1\1736129253'
17356 4994 01/05 19:07:38 1641379 TCreateIndex::initialize index checkpointing
remains disabled based on task options
17356 4994 01/05 19:07:38 1641379 TCreateIndex::initialize Effective failover
flags are: UseICS=FALSE, UseTL=FALSE, Checkpointing=FALSE (scheduler flags=[TRUE,
FALSE, FALSE])
17356 4994 01/05 19:07:38 1641379 TCreateIndex::initialize Set IndexingRestart
info to '<?xml version="1.0" encoding="UTF-8" standalone="no" ?
><Indexing_IndexingBackupRestartInformation cntOpensForWrite="1"
currentMaClientName="prdjwlcvma02" indexType="2" isRunningJob="1"
resetTotalObjectsBackedup="0" status="0"/>'
17356 4994 01/05 19:07:38 1641379 TCreateIndex::initialize createIndex complete

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : CVPerfMgr.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

2444 3b54 01/05 19:09:50 1641379 *CVPERFLOG*|43|5693817|25|1736129254|1736154454|

1736129385|1736154585|1736129259|1736154459|1736129390|1736154590|49|1|*CVCOUNTER*|
502|1|1736154585|3|0|0|0|0|*|11005233152|0|*CVCOUNTER*|501|1|1736154585|76|0|0|0|0|
*|10893363548|0|*CVCOUNTER*|503|1|1736154585|16|0|0|0|0|*|10893936670|0|
*CVCOUNTER*|520|1|1736154585|26|0|0|0|0|*|0|0|*CVCOUNTER*|509|1|1736154585|0|0|0|0|
0|*|0|0|*CVCOUNTER*|549|1|1736154585|0|0|0|0|0|*|0|0|*CVCOUNTER*|1004|505|
1736154585|114|0|0|0|0|*|0|0|*CVCOUNTER*|1001|505|1736154585|14|0|0|0|0|*|0|0|
*CVCOUNTER*|2001|505|1736154585|106|0|248844|0|0|*|10935613678|0|*CVCOUNTER*|2008|
505|1736154585|11|0|248843|0|0|*|10935556142|0|*CVCOUNTER*|2006|505|1736154585|37|
0|248842|0|0|*|10935556006|0|*CVCOUNTER*|2059|505|1736154585|0|0|0|0|0|*|0|0|
*CVCOUNTER*|10002|2059|1736154585|0|0|0|0|0|Compression : ZIP|0|0|*CVCOUNTER*|
10002|2059|1736154585|0|0|0|0|0|Buf size : 65536|0|0|*CVCOUNTER*|10002|2059|
1736154585|0|0|0|0|0|Buf count : 180|0|0|*CVCOUNTER*|10002|2059|1736154585|0|0|0|0|
0|SDT threads : 16|0|0|*CVCOUNTER*|10002|2059|1736154585|0|0|0|0|0|Processor
count : 12|0|0|*CVCOUNTER*|10002|2059|1736154585|0|0|0|0|0|Thread per Connection :
8|0|0|*CVCOUNTER*|10002|508|1736154590|0|0|0|0|0|Stream target: prdjwlcvma02|0|0|
*CVCOUNTER*|2056|508|1736154590|106|0|248844|0|0|*|10935613678|0|*CVCOUNTER*|2057|
508|1736154590|46|0|248842|0|0|*|10935556006|0|*CVCOUNTER*|5002|2057|1736154590|0|
0|0|0|0|*|0|0|*CVCOUNTER*|5003|2057|1736154590|0|0|0|0|0|*|0|0|*CVCOUNTER*|5804|
2057|1736154590|0|0|0|0|0|*|0|0|*CVCOUNTER*|5005|2057|1736154590|43|0|0|0|0|*|
10925600398|0|
2444 3b54 01/05 19:09:50 1641379
|*5693817*|*Perf*|1641379|
===================================================================================
====
|*5693817*|*Perf*|1641379| Job-ID: 1641379 [Pipe-ID: 5693817]
[App-Type: 43] [Data-Type: 1]
|*5693817*|*Perf*|1641379| Stream Source: prdjwlcomv01
|*5693817*|*Perf*|1641379| Network medium: SDT
|*5693817*|*Perf*|1641379| Head duration (Local): [05/01/25 19:07:34 ~ 05/01/25
19:09:45] 00:02:11 (131)
|*5693817*|*Perf*|1641379| Tail duration (Local): [05/01/25 19:07:39 ~ 05/01/25
19:09:50] 00:02:11 (131)
|*5693817*|*Perf*|1641379|
-----------------------------------------------------------------------------------
----------------------------------------------
|*5693817*|*Perf*|1641379| Perf-Counter
Time(seconds) Size
|*5693817*|*Perf*|1641379|
-----------------------------------------------------------------------------------
----------------------------------------------
|*5693817*|*Perf*|1641379|
|*5693817*|*Perf*|1641379| Windows File System Agent
|*5693817*|*Perf*|1641379| |_Buffer
allocation........................................................ 3
11005233152 [10.25 GB] [12299.31 GBPH]
|*5693817*|*Perf*|1641379| |
_Read.....................................................................
76 10893363548 [10.15 GB] [480.56 GBPH]
|*5693817*|*Perf*|1641379| |_Pipeline
write........................................................... 16
10893936670 [10.15 GB] [2282.80 GBPH]
|*5693817*|*Perf*|1641379| |_Open
File................................................................ 26
|*5693817*|*Perf*|1641379| |_FSDM Load
Library........................................................ -
|*5693817*|*Perf*|1641379| |_FindFirstFile
Open....................................................... -
|*5693817*|*Perf*|1641379|
|*5693817*|*Perf*|1641379| Reader Pipeline Modules[Client]
|*5693817*|*Perf*|1641379| |_CVA Wait to received data from
reader.................................... 114
|*5693817*|*Perf*|1641379| |_CVA Buffer
allocation.................................................... 14
|*5693817*|*Perf*|1641379| |_SDT: Receive
Data........................................................ 106
10935613678 [10.18 GB] [Samples - 248844] [Avg - 0.000426] [345.89 GBPH]
|*5693817*|*Perf*|1641379| |_SDT-Head: CRC32
update................................................... 11
10935556142 [10.18 GB] [Samples - 248843] [Avg - 0.000044] [3333.12 GBPH]
|*5693817*|*Perf*|1641379| |_SDT-Head: Network
transfer............................................... 37
10935556006 [10.18 GB] [Samples - 248842] [Avg - 0.000149] [990.93 GBPH]
|*5693817*|*Perf*|1641379| |
_SDT:Stats................................................................
-
|*5693817*|*Perf*|1641379| |_[Compression :
ZIP].................................................... -
|*5693817*|*Perf*|1641379| |_[Buf size :
65536]..................................................... -
|*5693817*|*Perf*|1641379| |_[Buf count :
180]...................................................... -
|*5693817*|*Perf*|1641379| |_[SDT threads :
16]..................................................... -
|*5693817*|*Perf*|1641379| |_[Processor count :
12]................................................. -
|*5693817*|*Perf*|1641379| |_[Thread per Connection :
8]............................................ -
|*5693817*|*Perf*|1641379|
|*5693817*|*Perf*|1641379| Writer Pipeline Modules[MediaAgent]
|*5693817*|*Perf*|1641379| |_[Stream target:
prdjwlcvma02]............................................ -
|*5693817*|*Perf*|1641379| |_SDT-Tail: Wait to receive data from
source............................... 106 10935613678 [10.18
GB] [Samples - 248844] [Avg - 0.000426] [345.89 GBPH]
|*5693817*|*Perf*|1641379| |_SDT-Tail: Writer
Tasks................................................... 46
10935556006 [10.18 GB] [Samples - 248842] [Avg - 0.000185] [797.05 GBPH]
|*5693817*|*Perf*|1641379| |_DSBackup: Update Restart
Info.......................................... -
|*5693817*|*Perf*|1641379| |_DSBackup: Update
Index................................................. -
|*5693817*|*Perf*|1641379| |_DSBackup: Update Restart Info
Index.................................... -
|*5693817*|*Perf*|1641379| |_DSBackup: Media
Write.................................................. 43
10925600398 [10.18 GB] [851.88 GBPH]
|*5693817*|*Perf*|1641379|
|*5693817*|*Perf*|1641379|
-----------------------------------------------------------------------------------
-----------------

@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@
Machine : prdjwlcvma02
File : CVMA.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

2244 4008 01/05 19:07:39 1641379 SERVICE [8509677/0/0 ] Received

CVMA_MAGNETIC_INITVOL_REQ.
2244 102c 01/05 19:07:39 1641379 WORKER [8509677/0/0 ] Created
directory [\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\Folder_07.09.2020_13.13\
CV_MAGNETIC\V_3193405]
2244 102c 01/05 19:07:39 1641379 WORKER [8509677/0/0 ] Revoked delete
permissions for path [\\Prdjwlcvma02\L-Drive$\PRDJWLCVMA02_L\
Folder_07.09.2020_13.13\CV_MAGNETIC\V_3193405].
2244 102c 01/05 19:07:39 1641379 OML [8509677/0/0 ] WRITE OML
returned OML on the media
2244 102c 01/05 19:07:39 1641379 WORKER [8509677/0/0 ] CVMMClient API
for initVolResp from completed