Solved

SAP HANA | UNABLE TO ESTABLISH CONNCTIVITY WITH THESE INSTANCE PROPERTIES


Userlevel 2
Badge +6
HELLO GUYS.The SAP Hana backup was running perfectly.But, unexpected error started with the following message."UNABLE TO ESTABLISH CONNECTIVITY WITH THESE INTANCE PROPERTIES FOR [xx]. [INSTANCE PROPERTIES VALIDATION FAILED. PLEASE CHECK DB CREDENTIALS.]"I contacted the SAP HANA team. I was told that DATABASE is online and operational. And the user "backup" has the credentials OK. I asked for a password reset.However, none of this resolved. Error persists.
icon

Best answer by Carlos Moreira 6 July 2022, 16:45

View original

21 replies

Userlevel 5
Badge +12

Hi @Carlos Moreira 

Could you please capture ClHanaAgent.log on the HANA client? This will hold little more detail on why it’s failing.

 

Thanks,

Sunil-

Userlevel 2
Badge +6

Hello Sunil

Thank You So Much

 

File    : ClHanaAgent.log
@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@@

17581 44ad 07/05 02:00:11 4998 ::Initialize1() - m_totalBytesDiff=[524288000].
17581 44ad 07/05 02:00:11 4998 ::Initialize1() - secondsToWait=30 mode=8001 m_backup=8001.
17581 44ad 07/05 02:00:11 4998 OraBaseContext::Initialize() - request for job: 4998
17581 44ad 07/05 02:00:11 4998 ::GetSectionName() - Default OracleAgent
17581 44ad 07/05 02:00:11 4998 OraBaseContext::setJobModes() - m_oraObjNameList = [B F A F]
17581 44ad 07/05 02:00:11 4998 OraBaseContext::InitAppMgrForBackup() - appId = 110
17581 44ad 07/05 02:00:11 4998 OraBaseContext::InitAppMgrForBackup() - instance:11
17581 44ad 07/05 02:00:11 4998 OraBaseContext::InitAppMgrForBackup() - backup clientId:30
17581 44ad 07/05 02:00:11 4998 InitializeBrowser() - no browser for HANA
17581 44ad 07/05 02:00:11 4998 ::Initialize1() - Done
17581 44ad 07/05 02:00:11 4998 ::JobObjectInitialize() - [4998] getting the new m_jobObject for CommServer=<commvault_server.x..com>
17581 44ad 07/05 02:00:11 4998 ::DbJobObject() - new m_jobObjectB
17581 44ad 07/05 02:00:11 4998 ::JobObjectInitialize() - new OraJobObject created
17581 44ad 07/05 02:00:11 4998 JM Client  CVJobClient::initialize(): Got remote host [commvault_server.x..com].
17581 44ad 07/05 02:00:11 4998 JM Client  CVBkpJobClient::init(): Initializing job object with token [4998:4:1:0:0:32808].
17581 44ad 07/05 02:00:11 4998 ::Initialize2() - Initialize2: jobMode=802D SAP iDa...
17581 44ad 07/05 02:00:11 4998 ::JobControlInitialize() - [4998] getting the new m_jobControl for CommServer=<commvault_server.x..com>...
17581 44ad 07/05 02:00:11 4998 ::JobControlInitialize() - m_jobControl.init(CONTROL) 4998:4:1:0:0:32808...
17581 44ad 07/05 02:00:11 4998 Init() - Initializing job control [token=4998:4:1:0:0:32808,cn=server_client], serverName [commvault_server.x..com], ControlFlag [1], Job Id [4998]
17581 44ad 07/05 02:00:11 4998 setupControl() - Created SemName : 4998_17581
17581 44ad 07/05 02:00:11 4998 Init() - Created control semaphore : 4998
17581 44ad 07/05 02:00:11 4998 createControlThread() - Call to create thread, 4998
17581 44ad 07/05 02:00:11 4998 Init() - Created control thread, jid : 4998
17581 44b0 07/05 02:00:11 4998 controlThread() - Going to wait on semaphore 4998_17581
17581 44ad 07/05 02:00:11 4998 registerJob() - Started Session , 4998
17581 44ad 07/05 02:00:11 4998 CVJobCtrlClient::registerJob() - Sent Message to CVD, 4998
17581 44ad 07/05 02:00:11 4998 CVJobCtrlClient::registerJob() Successfully received acknowledgement for job registration.
17581 44ad 07/05 02:00:11 4998 registerJob() - End Session, 4998
17581 44ad 07/05 02:00:11 4998 JOBRESCACHEDIR: job 4998 creating '/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998'
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - m_clientName=server_client hostName=server_client clientId=30
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - instanceName=<LDS> id=11
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - ClientNameList=<26>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - osUserName=<ldsadm>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - hdbsqldir=</usr/sap/LDS/HDB20/exe>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::SwitchUser() - Change user=ldsadm, gid=1004, uid=1021
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::SwitchUser() - Current uid = [0]gid = [1004]
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR=[ls -d  /usr/sap/LDS/HDB20/*/sec]
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR returned a single value. Using it as SECUDIR path: [/usr/sap/LDS/HDB20/server_client/sec]
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::setEnvironment() - Not setting HANA environment variables as nSetHANAEnv is not set
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - Current uid = [1021]gid = [1004]
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - Change user=ldsadm, gid=1004, uid=1021
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - Not calling initgroups, setuid, setgid for Non Root User
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - dbUserName=<commvault>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - ClientHostName=<server_client.x..com>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
17581 44ad 07/05 02:00:11 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::createHdbStoreKey() - ClientID list = <26>
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::runPopenCmd() - Cmd=[/usr/sap/LDS/HDB20/exe/sapcontrol -nr 20 -function GetSystemInstanceList 2>&1].
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::runPopenCmd() - Command output [05.07.2022 02:00:11
GetSystemInstanceList
OK
hostname, instanceNr, httpPort, httpsPort, startPriority, features, dispstatus
server_client, 20, 52013, 52014, 0.3, HDB|HDB_WORKER, GREEN
]
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::getHANAHosts() - server_client  HDB|HDB_WORKER  GREEN
17581 44ad 07/05 02:00:11 4998 ClHanaAgent::getHanaNodeListForStoreKey() - getHanaNodeListForStoreKey [/usr/sap/LDS/HDB20/exe/hdbnsutil -sr_state --sapcontrol=1 2>&1].
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [unhandled ltt exception: exception 2050005: Error loading shared library libhdbnspy : libhdbnspy.so: cannot open shared object file: No such file or directory]
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::getHanaNodeListForStoreKey() - []
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [failed. trace file nameserver_server_client.00000.000.trc may contain more error details.]
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::getHanaNodeListForStoreKey() - Nodelist for creating HDB store key is [server_client:32013,server_client:32015].
17581 44ad 07/05 02:00:12 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbuserstore List BACKUP 2>&1
17581 44ad 07/05 02:00:12 4998 CvProcess::system() - Command completed with rc=0
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::ExecuteDataBackup() - <HDB version info:
>
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::ExecuteDataBackup() - <  version:             2.00.061.00.1644229038
>
17581 44ad 07/05 02:00:12 4998 ClHanaAgent::ExecuteDataBackup() - hdbStoreKey=<BACKUP>
17581 44ad 07/05 02:00:12 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'" 2>&1
17581 44ad 07/05 02:00:12 4998 CvProcess::system() - Command completed with rc=3
17581 44ad 07/05 02:00:12 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
17581 44ad 07/05 02:00:12 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:1:0:0:32808]
17581 44ad 07/05 02:00:13 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
17581 44ad 07/05 02:00:13 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
17581 44ad 07/05 02:00:13 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:1:0:0:32808]
17581 44ad 07/05 02:00:13 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:1:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [1]. Current AttemptNo of job [2].].]
17581 44ad 07/05 02:00:13 4998 ::complete() - m_jobObjectB->complete failed
17581 44ad 07/05 02:00:13 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,PHASE) failed.
17581 44ad 07/05 02:00:13 4998 JM Client  CVJobClient::setPendingCause(). same messageid is being sent for the same job id within a short time period. so dropping the JPR
17581 44ad 07/05 02:00:13 4998 ::ExitHere() - enter: phase=17
17581 44ad 07/05 02:00:13 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
17581 44ad 07/05 02:00:13 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,JOB)
17581 44ad 07/05 02:00:13 4998 BKP CALLED COMPLETE (JOB Status::FAIL), 4998. Token [4998:4:1:0:0:32808]
17581 44ad 07/05 02:00:13 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:1:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [1]. Current AttemptNo of job [2].].]
17581 44ad 07/05 02:00:13 4998 ::complete() - m_jobObjectB->complete failed
17581 44ad 07/05 02:00:13 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,JOB) failed.
17581 44ad 07/05 02:00:13 4998 ::NotifyCommServeJobComplete() - First error is already set to [jobObject.complete(FAIL,PHASE) failed.]
17581 44ad 07/05 02:00:13 4998 ::ExitHere() - m_firstErrDescr = [jobObject.complete(FAIL,PHASE) failed.]
17581 44ad 07/05 02:00:13 4998 ::Finalize() - enter Parent=true
17581 44ad 07/05 02:00:13 4998 ::Finalize() - m_jobControl->finishedWorkWithoutExit() ...
17581 44ad 07/05 02:00:13 4998 finishedWorkWithOutExit() - Release control semaphore 4998_17581
17581 44ad 07/05 02:00:13 4998 finishedWorkWithOutExit() - Waiting for control thread to Quit, 4998
17581 44b0 07/05 02:00:13 4998 controlThread() - Normal termination, Job Id 4998
17581 44b0 07/05 02:00:13 4998 controlThread() - Send unregister message to CVD, Job Id 4998
17581 44b0 07/05 02:00:13 4998 unRegisterProcess() - Send Message to CVD 4998
17581 44b0 07/05 02:00:13 4998 unRegisterProcess() - Started Session 4998
17581 44b0 07/05 02:00:13 4998 unRegisterProcess() - Send Message Successfully 4998
17581 44b0 07/05 02:00:13 4998 controlThread() - Close control semaphore, Job Id 4998
17581 44b0 07/05 02:00:13 4998 controlThread() - Control thread done, Job Id 4998
17581 44ad 07/05 02:00:13 4998 ::Finalize() - delete m_jobControl ...
17581 44ad 07/05 02:00:13 4998 ::CloseJobResultsFile() - [4998] going to m_buResults.closeFile...
17581 44ad 07/05 02:00:13 4998 ::CloseJobResultsFile() - [4998] m_buResults.closeFile() & cleanUp() succeeded.
17581 44ad 07/05 02:00:13 4998 ::Finalize() - [4998] delete m_jobObject.
17581 44ad 07/05 02:00:13 4998 ::~DbJobObject() - m_jobObjectB->terminateAllSessions()...
17581 44ad 07/05 02:00:13 4998 ::~DbJobObject() - After m_jobObject deletion.
17581 44ad 07/05 02:00:13 4998 ::Finalize() - delete m_event ...
22990 59ce 07/05 02:21:06 4998 ::Initialize1() - m_totalBytesDiff=[524288000].
22990 59ce 07/05 02:21:06 4998 ::Initialize1() - secondsToWait=30 mode=8001 m_backup=8001.
22990 59ce 07/05 02:21:06 4998 OraBaseContext::Initialize() - request for job: 4998
22990 59ce 07/05 02:21:06 4998 ::GetSectionName() - Default OracleAgent
22990 59ce 07/05 02:21:06 4998 OraBaseContext::setJobModes() - m_oraObjNameList = [B F A F]
22990 59ce 07/05 02:21:06 4998 OraBaseContext::InitAppMgrForBackup() - appId = 110
22990 59ce 07/05 02:21:06 4998 OraBaseContext::InitAppMgrForBackup() - instance:11
22990 59ce 07/05 02:21:06 4998 OraBaseContext::InitAppMgrForBackup() - backup clientId:30
22990 59ce 07/05 02:21:06 4998 InitializeBrowser() - no browser for HANA
22990 59ce 07/05 02:21:06 4998 ::Initialize1() - Done
22990 59ce 07/05 02:21:06 4998 ::JobObjectInitialize() - [4998] getting the new m_jobObject for CommServer=<commvault_server.x..com>
22990 59ce 07/05 02:21:06 4998 ::DbJobObject() - new m_jobObjectB
22990 59ce 07/05 02:21:06 4998 ::JobObjectInitialize() - new OraJobObject created
22990 59ce 07/05 02:21:06 4998 JM Client  CVJobClient::initialize(): Got remote host [commvault_server.x..com].
22990 59ce 07/05 02:21:06 4998 JM Client  CVBkpJobClient::init(): Initializing job object with token [4998:4:2:0:0:32808].
22990 59ce 07/05 02:21:06 4998 ::Initialize2() - Initialize2: jobMode=802D SAP iDa...
22990 59ce 07/05 02:21:06 4998 ::JobControlInitialize() - [4998] getting the new m_jobControl for CommServer=<commvault_server.x..com>...
22990 59ce 07/05 02:21:06 4998 ::JobControlInitialize() - m_jobControl.init(CONTROL) 4998:4:2:0:0:32808...
22990 59ce 07/05 02:21:06 4998 Init() - Initializing job control [token=4998:4:2:0:0:32808,cn=server_client], serverName [commvault_server.x..com], ControlFlag [1], Job Id [4998]
22990 59ce 07/05 02:21:06 4998 setupControl() - Created SemName : 4998_22990
22990 59ce 07/05 02:21:06 4998 Init() - Created control semaphore : 4998
22990 59ce 07/05 02:21:06 4998 createControlThread() - Call to create thread, 4998
22990 59ce 07/05 02:21:06 4998 Init() - Created control thread, jid : 4998
22990 59d0 07/05 02:21:06 4998 controlThread() - Going to wait on semaphore 4998_22990
22990 59ce 07/05 02:21:06 4998 registerJob() - Started Session , 4998
22990 59ce 07/05 02:21:06 4998 CVJobCtrlClient::registerJob() - Sent Message to CVD, 4998
22990 59ce 07/05 02:21:06 4998 CVJobCtrlClient::registerJob() Successfully received acknowledgement for job registration.
22990 59ce 07/05 02:21:06 4998 registerJob() - End Session, 4998
22990 59ce 07/05 02:21:06 4998 JOBRESCACHEDIR: opened index cache [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998]
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - m_clientName=server_client hostName=server_client clientId=30
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - instanceName=<LDS> id=11
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - ClientNameList=<26>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - osUserName=<ldsadm>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - hdbsqldir=</usr/sap/LDS/HDB20/exe>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::SwitchUser() - Change user=ldsadm, gid=1004, uid=1021
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::SwitchUser() - Current uid = [0]gid = [1004]
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR=[ls -d  /usr/sap/LDS/HDB20/*/sec]
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR returned a single value. Using it as SECUDIR path: [/usr/sap/LDS/HDB20/server_client/sec]
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::setEnvironment() - Not setting HANA environment variables as nSetHANAEnv is not set
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - Current uid = [1021]gid = [1004]
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - Change user=ldsadm, gid=1004, uid=1021
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - Not calling initgroups, setuid, setgid for Non Root User
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - dbUserName=<commvault>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - ClientHostName=<server_client.x..com>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
22990 59ce 07/05 02:21:06 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::createHdbStoreKey() - ClientID list = <26>
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::runPopenCmd() - Cmd=[/usr/sap/LDS/HDB20/exe/sapcontrol -nr 20 -function GetSystemInstanceList 2>&1].
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::runPopenCmd() - Command output [05.07.2022 02:21:06
GetSystemInstanceList
OK

Userlevel 2
Badge +6

hostname, instanceNr, httpPort, httpsPort, startPriority, features, dispstatus
server_client, 20, 52013, 52014, 0.3, HDB|HDB_WORKER, GREEN
]
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::getHANAHosts() - server_client  HDB|HDB_WORKER  GREEN
22990 59ce 07/05 02:21:06 4998 ClHanaAgent::getHanaNodeListForStoreKey() - getHanaNodeListForStoreKey [/usr/sap/LDS/HDB20/exe/hdbnsutil -sr_state --sapcontrol=1 2>&1].
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [unhandled ltt exception: exception 2050005: Error loading shared library libhdbnspy : libhdbnspy.so: cannot open shared object file: No such file or directory]
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::getHanaNodeListForStoreKey() - []
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [failed. trace file nameserver_server_client.00000.000.trc may contain more error details.]
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::getHanaNodeListForStoreKey() - Nodelist for creating HDB store key is [server_client:32013,server_client:32015].
22990 59ce 07/05 02:21:07 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbuserstore List BACKUP 2>&1
22990 59ce 07/05 02:21:07 4998 CvProcess::system() - Command completed with rc=0
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::ExecuteDataBackup() - <HDB version info:
>
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::ExecuteDataBackup() - <  version:             2.00.061.00.1644229038
>
22990 59ce 07/05 02:21:07 4998 ClHanaAgent::ExecuteDataBackup() - hdbStoreKey=<BACKUP>
22990 59ce 07/05 02:21:07 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'" 2>&1
22990 59ce 07/05 02:21:07 4998 CvProcess::system() - Command completed with rc=3
22990 59ce 07/05 02:21:07 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
22990 59ce 07/05 02:21:07 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:2:0:0:32808]
22990 59ce 07/05 02:21:07 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
22990 59ce 07/05 02:21:07 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
22990 59ce 07/05 02:21:07 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:2:0:0:32808]
22990 59ce 07/05 02:21:07 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:2:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [2]. Current AttemptNo of job [3].].]
22990 59ce 07/05 02:21:07 4998 ::complete() - m_jobObjectB->complete failed
22990 59ce 07/05 02:21:07 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,PHASE) failed.
22990 59ce 07/05 02:21:07 4998 JM Client  CVJobClient::setPendingCause(). same messageid is being sent for the same job id within a short time period. so dropping the JPR
22990 59ce 07/05 02:21:07 4998 ::ExitHere() - enter: phase=17
22990 59ce 07/05 02:21:07 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
22990 59ce 07/05 02:21:07 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,JOB)
22990 59ce 07/05 02:21:07 4998 BKP CALLED COMPLETE (JOB Status::FAIL), 4998. Token [4998:4:2:0:0:32808]
22990 59ce 07/05 02:21:08 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:2:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [2]. Current AttemptNo of job [3].].]
22990 59ce 07/05 02:21:08 4998 ::complete() - m_jobObjectB->complete failed
22990 59ce 07/05 02:21:08 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,JOB) failed.
22990 59ce 07/05 02:21:08 4998 ::NotifyCommServeJobComplete() - First error is already set to [jobObject.complete(FAIL,PHASE) failed.]
22990 59ce 07/05 02:21:08 4998 ::ExitHere() - m_firstErrDescr = [jobObject.complete(FAIL,PHASE) failed.]
22990 59ce 07/05 02:21:08 4998 ::Finalize() - enter Parent=true
22990 59ce 07/05 02:21:08 4998 ::Finalize() - m_jobControl->finishedWorkWithoutExit() ...
22990 59ce 07/05 02:21:08 4998 finishedWorkWithOutExit() - Release control semaphore 4998_22990
22990 59ce 07/05 02:21:08 4998 finishedWorkWithOutExit() - Waiting for control thread to Quit, 4998
22990 59d0 07/05 02:21:08 4998 controlThread() - Normal termination, Job Id 4998
22990 59d0 07/05 02:21:08 4998 controlThread() - Send unregister message to CVD, Job Id 4998
22990 59d0 07/05 02:21:08 4998 unRegisterProcess() - Send Message to CVD 4998
22990 59d0 07/05 02:21:08 4998 unRegisterProcess() - Started Session 4998
22990 59d0 07/05 02:21:08 4998 unRegisterProcess() - Send Message Successfully 4998
22990 59d0 07/05 02:21:08 4998 controlThread() - Close control semaphore, Job Id 4998
22990 59d0 07/05 02:21:08 4998 controlThread() - Control thread done, Job Id 4998
22990 59ce 07/05 02:21:08 4998 ::Finalize() - delete m_jobControl ...
22990 59ce 07/05 02:21:08 4998 ::CloseJobResultsFile() - [4998] going to m_buResults.closeFile...
22990 59ce 07/05 02:21:08 4998 ::CloseJobResultsFile() - [4998] m_buResults.closeFile() & cleanUp() succeeded.
22990 59ce 07/05 02:21:08 4998 ::Finalize() - [4998] delete m_jobObject.
22990 59ce 07/05 02:21:08 4998 ::~DbJobObject() - m_jobObjectB->terminateAllSessions()...
22990 59ce 07/05 02:21:08 4998 ::~DbJobObject() - After m_jobObject deletion.
22990 59ce 07/05 02:21:08 4998 ::Finalize() - delete m_event ...
27087 69cf 07/05 02:41:09 4998 ::Initialize1() - m_totalBytesDiff=[524288000].
27087 69cf 07/05 02:41:09 4998 ::Initialize1() - secondsToWait=30 mode=8001 m_backup=8001.
27087 69cf 07/05 02:41:09 4998 OraBaseContext::Initialize() - request for job: 4998
27087 69cf 07/05 02:41:09 4998 ::GetSectionName() - Default OracleAgent
27087 69cf 07/05 02:41:09 4998 OraBaseContext::setJobModes() - m_oraObjNameList = [B F A F]
27087 69cf 07/05 02:41:09 4998 OraBaseContext::InitAppMgrForBackup() - appId = 110
27087 69cf 07/05 02:41:09 4998 OraBaseContext::InitAppMgrForBackup() - instance:11
27087 69cf 07/05 02:41:09 4998 OraBaseContext::InitAppMgrForBackup() - backup clientId:30
27087 69cf 07/05 02:41:09 4998 InitializeBrowser() - no browser for HANA
27087 69cf 07/05 02:41:09 4998 ::Initialize1() - Done
27087 69cf 07/05 02:41:09 4998 ::JobObjectInitialize() - [4998] getting the new m_jobObject for CommServer=<commvault_server.x..com>
27087 69cf 07/05 02:41:09 4998 ::DbJobObject() - new m_jobObjectB
27087 69cf 07/05 02:41:09 4998 ::JobObjectInitialize() - new OraJobObject created
27087 69cf 07/05 02:41:09 4998 JM Client  CVJobClient::initialize(): Got remote host [commvault_server.x..com].
27087 69cf 07/05 02:41:09 4998 JM Client  CVBkpJobClient::init(): Initializing job object with token [4998:4:3:0:0:32808].
27087 69cf 07/05 02:41:09 4998 ::Initialize2() - Initialize2: jobMode=802D SAP iDa...
27087 69cf 07/05 02:41:09 4998 ::JobControlInitialize() - [4998] getting the new m_jobControl for CommServer=<commvault_server.x..com>...
27087 69cf 07/05 02:41:09 4998 ::JobControlInitialize() - m_jobControl.init(CONTROL) 4998:4:3:0:0:32808...
27087 69cf 07/05 02:41:09 4998 Init() - Initializing job control [token=4998:4:3:0:0:32808,cn=server_client], serverName [commvault_server.x..com], ControlFlag [1], Job Id [4998]
27087 69cf 07/05 02:41:09 4998 setupControl() - Created SemName : 4998_27087
27087 69cf 07/05 02:41:09 4998 Init() - Created control semaphore : 4998
27087 69cf 07/05 02:41:09 4998 createControlThread() - Call to create thread, 4998
27087 69cf 07/05 02:41:09 4998 Init() - Created control thread, jid : 4998
27087 69d1 07/05 02:41:09 4998 controlThread() - Going to wait on semaphore 4998_27087
27087 69cf 07/05 02:41:09 4998 registerJob() - Started Session , 4998
27087 69cf 07/05 02:41:09 4998 CVJobCtrlClient::registerJob() - Sent Message to CVD, 4998
27087 69cf 07/05 02:41:09 4998 CVJobCtrlClient::registerJob() Successfully received acknowledgement for job registration.
27087 69cf 07/05 02:41:09 4998 registerJob() - End Session, 4998
27087 69cf 07/05 02:41:09 4998 JOBRESCACHEDIR: opened index cache [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998]
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - m_clientName=server_client hostName=server_client clientId=30
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - instanceName=<LDS> id=11
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - ClientNameList=<26>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - osUserName=<ldsadm>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - hdbsqldir=</usr/sap/LDS/HDB20/exe>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::SwitchUser() - Change user=ldsadm, gid=1004, uid=1021
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::SwitchUser() - Current uid = [0]gid = [1004]
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR=[ls -d  /usr/sap/LDS/HDB20/*/sec]
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR returned a single value. Using it as SECUDIR path: [/usr/sap/LDS/HDB20/server_client/sec]
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::setEnvironment() - Not setting HANA environment variables as nSetHANAEnv is not set
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - Current uid = [1021]gid = [1004]
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - Change user=ldsadm, gid=1004, uid=1021
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - Not calling initgroups, setuid, setgid for Non Root User
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - dbUserName=<commvault>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - ClientHostName=<server_client.x..com>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
27087 69cf 07/05 02:41:09 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::createHdbStoreKey() - ClientID list = <26>
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::runPopenCmd() - Cmd=[/usr/sap/LDS/HDB20/exe/sapcontrol -nr 20 -function GetSystemInstanceList 2>&1].
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::runPopenCmd() - Command output [05.07.2022 02:41:09
GetSystemInstanceList
OK
hostname, instanceNr, httpPort, httpsPort, startPriority, features, dispstatus
server_client, 20, 52013, 52014, 0.3, HDB|HDB_WORKER, GREEN
]
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::getHANAHosts() - server_client  HDB|HDB_WORKER  GREEN
27087 69cf 07/05 02:41:09 4998 ClHanaAgent::getHanaNodeListForStoreKey() - getHanaNodeListForStoreKey [/usr/sap/LDS/HDB20/exe/hdbnsutil -sr_state --sapcontrol=1 2>&1].
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [unhandled ltt exception: exception 2050005: Error loading shared library libhdbnspy : libhdbnspy.so: cannot open shared object file: No such file or directory]
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::getHanaNodeListForStoreKey() - []
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [failed. trace file nameserver_server_client.00000.000.trc may contain more error details.]
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::getHanaNodeListForStoreKey() - Nodelist for creating HDB store key is [server_client:32013,server_client:32015].
27087 69cf 07/05 02:41:10 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbuserstore List BACKUP 2>&1
27087 69cf 07/05 02:41:10 4998 CvProcess::system() - Command completed with rc=0
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::ExecuteDataBackup() - <HDB version info:
>
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::ExecuteDataBackup() - <  version:             2.00.061.00.1644229038
>
27087 69cf 07/05 02:41:10 4998 ClHanaAgent::ExecuteDataBackup() - hdbStoreKey=<BACKUP>
27087 69cf 07/05 02:41:10 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'" 2>&1
27087 69cf 07/05 02:41:10 4998 CvProcess::system() - Command completed with rc=3
27087 69cf 07/05 02:41:10 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
27087 69cf 07/05 02:41:10 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:3:0:0:32808]
27087 69cf 07/05 02:41:11 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
27087 69cf 07/05 02:41:11 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
27087 69cf 07/05 02:41:11 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:3:0:0:32808]
27087 69cf 07/05 02:41:11 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:3:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [3]. Current AttemptNo of job [4].].]
27087 69cf 07/05 02:41:11 4998 ::complete() - m_jobObjectB->complete failed
27087 69cf 07/05 02:41:11 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,PHASE) failed.
27087 69cf 07/05 02:41:11 4998 JM Client  CVJobClient::setPendingCause(). same messageid is being sent for the same job id within a short time period. so dropping the JPR
27087 69cf 07/05 02:41:11 4998 ::ExitHere() - enter: phase=17
27087 69cf 07/05 02:41:11 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
27087 69cf 07/05 02:41:11 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,JOB)
27087 69cf 07/05 02:41:11 4998 BKP CALLED COMPLETE (JOB Status::FAIL), 4998. Token [4998:4:3:0:0:32808]
27087 69cf 07/05 02:41:11 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:3:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [3]. Current AttemptNo of job [4].].]
27087 69cf 07/05 02:41:11 4998 ::complete() - m_jobObjectB->complete failed
27087 69cf 07/05 02:41:11 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,JOB) failed.
27087 69cf 07/05 02:41:11 4998 ::NotifyCommServeJobComplete() - First error is already set to [jobObject.complete(FAIL,PHASE) failed.]
27087 69cf 07/05 02:41:11 4998 ::ExitHere() - m_firstErrDescr = [jobObject.complete(FAIL,PHASE) failed.]
27087 69cf 07/05 02:41:11 4998 ::Finalize() - enter Parent=true
27087 69cf 07/05 02:41:11 4998 ::Finalize() - m_jobControl->finishedWorkWithoutExit() ...
27087 69cf 07/05 02:41:11 4998 finishedWorkWithOutExit() - Release control semaphore 4998_27087
27087 69cf 07/05 02:41:11 4998 finishedWorkWithOutExit() - Waiting for control thread to Quit, 4998
27087 69d1 07/05 02:41:11 4998 controlThread() - Normal termination, Job Id 4998
27087 69d1 07/05 02:41:11 4998 controlThread() - Send unregister message to CVD, Job Id 4998
27087 69d1 07/05 02:41:11 4998 unRegisterProcess() - Send Message to CVD 4998
27087 69d1 07/05 02:41:11 4998 unRegisterProcess() - Started Session 4998
27087 69d1 07/05 02:41:11 4998 unRegisterProcess() - Send Message Successfully 4998
27087 69d1 07/05 02:41:11 4998 controlThread() - Close control semaphore, Job Id 4998
27087 69d1 07/05 02:41:11 4998 controlThread() - Control thread done, Job Id 4998
27087 69cf 07/05 02:41:11 4998 ::Finalize() - delete m_jobControl ...
27087 69cf 07/05 02:41:11 4998 ::CloseJobResultsFile() - [4998] going to m_buResults.closeFile...
27087 69cf 07/05 02:41:11 4998 ::CloseJobResultsFile() - [4998] m_buResults.closeFile() & cleanUp() succeeded.
27087 69cf 07/05 02:41:11 4998 ::Finalize() - [4998] delete m_jobObject.
27087 69cf 07/05 02:41:11 4998 ::~DbJobObject() - m_jobObjectB->terminateAllSessions()...
27087 69cf 07/05 02:41:11 4998 ::~DbJobObject() - After m_jobObject deletion.
27087 69cf 07/05 02:41:11 4998 ::Finalize() - delete m_event ...
30647 77b7 07/05 03:01:12 4998 ::Initialize1() - m_totalBytesDiff=[524288000].
30647 77b7 07/05 03:01:12 4998 ::Initialize1() - secondsToWait=30 mode=8001 m_backup=8001.
30647 77b7 07/05 03:01:12 4998 OraBaseContext::Initialize() - request for job: 4998
30647 77b7 07/05 03:01:12 4998 ::GetSectionName() - Default OracleAgent
30647 77b7 07/05 03:01:12 4998 OraBaseContext::setJobModes() - m_oraObjNameList = [B F A F]
30647 77b7 07/05 03:01:12 4998 OraBaseContext::InitAppMgrForBackup() - appId = 110
30647 77b7 07/05 03:01:12 4998 OraBaseContext::InitAppMgrForBackup() - instance:11
30647 77b7 07/05 03:01:12 4998 OraBaseContext::InitAppMgrForBackup() - backup clientId:30
30647 77b7 07/05 03:01:12 4998 InitializeBrowser() - no browser for HANA
30647 77b7 07/05 03:01:12 4998 ::Initialize1() - Done
30647 77b7 07/05 03:01:12 4998 ::JobObjectInitialize() - [4998] getting the new m_jobObject for CommServer=<commvault_server.x..com>
30647 77b7 07/05 03:01:12 4998 ::DbJobObject() - new m_jobObjectB
30647 77b7 07/05 03:01:12 4998 ::JobObjectInitialize() - new OraJobObject created
30647 77b7 07/05 03:01:12 4998 JM Client  CVJobClient::initialize(): Got remote host [commvault_server.x..com].
30647 77b7 07/05 03:01:12 4998 JM Client  CVBkpJobClient::init(): Initializing job object with token [4998:4:4:0:0:32808].
30647 77b7 07/05 03:01:12 4998 ::Initialize2() - Initialize2: jobMode=802D SAP iDa...
30647 77b7 07/05 03:01:12 4998 ::JobControlInitialize() - [4998] getting the new m_jobControl for CommServer=<commvault_server.x..com>...
30647 77b7 07/05 03:01:12 4998 ::JobControlInitialize() - m_jobControl.init(CONTROL) 4998:4:4:0:0:32808...
30647 77b7 07/05 03:01:12 4998 Init() - Initializing job control [token=4998:4:4:0:0:32808,cn=server_client], serverName [commvault_server.x..com], ControlFlag [1], Job Id [4998]
30647 77b7 07/05 03:01:12 4998 setupControl() - Created SemName : 4998_30647
30647 77b7 07/05 03:01:12 4998 Init() - Created control semaphore : 4998
30647 77b7 07/05 03:01:12 4998 createControlThread() - Call to create thread, 4998
30647 77b7 07/05 03:01:12 4998 Init() - Created control thread, jid : 4998
30647 77d5 07/05 03:01:12 4998 controlThread() - Going to wait on semaphore 4998_30647
30647 77b7 07/05 03:01:12 4998 registerJob() - Started Session , 4998
30647 77b7 07/05 03:01:12 4998 CVJobCtrlClient::registerJob() - Sent Message to CVD, 4998
30647 77b7 07/05 03:01:12 4998 CVJobCtrlClient::registerJob() Successfully received acknowledgement for job registration.
30647 77b7 07/05 03:01:12 4998 registerJob() - End Session, 4998
30647 77b7 07/05 03:01:12 4998 JOBRESCACHEDIR: opened index cache [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998]
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - m_clientName=server_client hostName=server_client clientId=30
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - instanceName=<LDS> id=11
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - ClientNameList=<26>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - osUserName=<ldsadm>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - hdbsqldir=</usr/sap/LDS/HDB20/exe>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::SwitchUser() - Change user=ldsadm, gid=1004, uid=1021
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::SwitchUser() - Current uid = [0]gid = [1004]
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR=[ls -d  /usr/sap/LDS/HDB20/*/sec]
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR returned a single value. Using it as SECUDIR path: [/usr/sap/LDS/HDB20/server_client/sec]
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::setEnvironment() - Not setting HANA environment variables as nSetHANAEnv is not set
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - Current uid = [1021]gid = [1004]
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - Change user=ldsadm, gid=1004, uid=1021
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - Not calling initgroups, setuid, setgid for Non Root User
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - dbUserName=<commvault>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - ClientHostName=<server_client.x..com>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
30647 77b7 07/05 03:01:12 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::createHdbStoreKey() - ClientID list = <26>
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::runPopenCmd() - Cmd=[/usr/sap/LDS/HDB20/exe/sapcontrol -nr 20 -function GetSystemInstanceList 2>&1].
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::runPopenCmd() - Command output [05.07.2022 03:01:12
GetSystemInstanceList
OK
hostname, instanceNr, httpPort, httpsPort, startPriority, features, dispstatus
server_client, 20, 52013, 52014, 0.3, HDB|HDB_WORKER, GREEN
]
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::getHANAHosts() - server_client  HDB|HDB_WORKER  GREEN
30647 77b7 07/05 03:01:12 4998 ClHanaAgent::getHanaNodeListForStoreKey() - getHanaNodeListForStoreKey [/usr/sap/LDS/HDB20/exe/hdbnsutil -sr_state --sapcontrol=1 2>&1].
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [unhandled ltt exception: exception 2050005: Error loading shared library libhdbnspy : libhdbnspy.so: cannot open shared object file: No such file or directory]
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::getHanaNodeListForStoreKey() - []
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [failed. trace file nameserver_server_client.00000.000.trc may contain more error details.]
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::getHanaNodeListForStoreKey() - Nodelist for creating HDB store key is [server_client:32013,server_client:32015].
30647 77b7 07/05 03:01:13 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbuserstore List BACKUP 2>&1
30647 77b7 07/05 03:01:13 4998 CvProcess::system() - Command completed with rc=0
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::ExecuteDataBackup() - <HDB version info:
>
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::ExecuteDataBackup() - <  version:             2.00.061.00.1644229038
>
30647 77b7 07/05 03:01:13 4998 ClHanaAgent::ExecuteDataBackup() - hdbStoreKey=<BACKUP>
30647 77b7 07/05 03:01:13 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'" 2>&1
30647 77b7 07/05 03:01:13 4998 CvProcess::system() - Command completed with rc=3
30647 77b7 07/05 03:01:14 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
30647 77b7 07/05 03:01:14 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:4:0:0:32808]
30647 77b7 07/05 03:01:14 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
30647 77b7 07/05 03:01:14 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
30647 77b7 07/05 03:01:14 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:4:0:0:32808]
30647 77b7 07/05 03:01:14 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:4:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [4]. Current AttemptNo of job [5].].]
30647 77b7 07/05 03:01:14 4998 ::complete() - m_jobObjectB->complete failed
30647 77b7 07/05 03:01:14 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,PHASE) failed.
30647 77b7 07/05 03:01:14 4998 JM Client  CVJobClient::setPendingCause(). same messageid is being sent for the same job id within a short time period. so dropping the JPR
30647 77b7 07/05 03:01:14 4998 ::ExitHere() - enter: phase=17
30647 77b7 07/05 03:01:14 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
30647 77b7 07/05 03:01:14 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,JOB)
30647 77b7 07/05 03:01:14 4998 BKP CALLED COMPLETE (JOB Status::FAIL), 4998. Token [4998:4:4:0:0:32808]
30647 77b7 07/05 03:01:14 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:4:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [4]. Current AttemptNo of job [5].].]
30647 77b7 07/05 03:01:14 4998 ::complete() - m_jobObjectB->complete failed
30647 77b7 07/05 03:01:14 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,JOB) failed.
30647 77b7 07/05 03:01:14 4998 ::NotifyCommServeJobComplete() - First error is already set to [jobObject.complete(FAIL,PHASE) failed.]
30647 77b7 07/05 03:01:14 4998 ::ExitHere() - m_firstErrDescr = [jobObject.complete(FAIL,PHASE) failed.]
30647 77b7 07/05 03:01:14 4998 ::Finalize() - enter Parent=true
30647 77b7 07/05 03:01:14 4998 ::Finalize() - m_jobControl->finishedWorkWithoutExit() ...
30647 77b7 07/05 03:01:14 4998 finishedWorkWithOutExit() - Release control semaphore 4998_30647
30647 77b7 07/05 03:01:14 4998 finishedWorkWithOutExit() - Waiting for control thread to Quit, 4998
30647 77d5 07/05 03:01:14 4998 controlThread() - Normal termination, Job Id 4998
30647 77d5 07/05 03:01:14 4998 controlThread() - Send unregister message to CVD, Job Id 4998
30647 77d5 07/05 03:01:14 4998 unRegisterProcess() - Send Message to CVD 4998
30647 77d5 07/05 03:01:14 4998 unRegisterProcess() - Started Session 4998
30647 77d5 07/05 03:01:14 4998 unRegisterProcess() - Send Message Successfully 4998
30647 77d5 07/05 03:01:14 4998 controlThread() - Close control semaphore, Job Id 4998
30647 77d5 07/05 03:01:14 4998 controlThread() - Control thread done, Job Id 4998
30647 77b7 07/05 03:01:14 4998 ::Finalize() - delete m_jobControl ...
30647 77b7 07/05 03:01:14 4998 ::CloseJobResultsFile() - [4998] going to m_buResults.closeFile...
30647 77b7 07/05 03:01:14 4998 ::CloseJobResultsFile() - [4998] m_buResults.closeFile() & cleanUp() succeeded.
30647 77b7 07/05 03:01:14 4998 ::Finalize() - [4998] delete m_jobObject.
30647 77b7 07/05 03:01:14 4998 ::~DbJobObject() - m_jobObjectB->terminateAllSessions()...
30647 77b7 07/05 03:01:14 4998 ::~DbJobObject() - After m_jobObject deletion.
30647 77b7 07/05 03:01:14 4998 ::Finalize() - delete m_event ...
2459 99b 07/05 03:21:15 4998 ::Initialize1() - m_totalBytesDiff=[524288000].
2459 99b 07/05 03:21:15 4998 ::Initialize1() - secondsToWait=30 mode=8001 m_backup=8001.
2459 99b 07/05 03:21:15 4998 OraBaseContext::Initialize() - request for job: 4998
2459 99b 07/05 03:21:15 4998 ::GetSectionName() - Default OracleAgent
2459 99b 07/05 03:21:15 4998 OraBaseContext::setJobModes() - m_oraObjNameList = [B F A F]
2459 99b 07/05 03:21:15 4998 OraBaseContext::InitAppMgrForBackup() - appId = 110
2459 99b 07/05 03:21:15 4998 OraBaseContext::InitAppMgrForBackup() - instance:11
2459 99b 07/05 03:21:15 4998 OraBaseContext::InitAppMgrForBackup() - backup clientId:30
2459 99b 07/05 03:21:15 4998 InitializeBrowser() - no browser for HANA
2459 99b 07/05 03:21:15 4998 ::Initialize1() - Done
2459 99b 07/05 03:21:15 4998 ::JobObjectInitialize() - [4998] getting the new m_jobObject for CommServer=<commvault_server.x..com>
2459 99b 07/05 03:21:15 4998 ::DbJobObject() - new m_jobObjectB
2459 99b 07/05 03:21:15 4998 ::JobObjectInitialize() - new OraJobObject created
2459 99b 07/05 03:21:15 4998 JM Client  CVJobClient::initialize(): Got remote host [commvault_server.x..com].
2459 99b 07/05 03:21:15 4998 JM Client  CVBkpJobClient::init(): Initializing job object with token [4998:4:5:0:0:32808].
2459 99b 07/05 03:21:15 4998 ::Initialize2() - Initialize2: jobMode=802D SAP iDa...
2459 99b 07/05 03:21:15 4998 ::JobControlInitialize() - [4998] getting the new m_jobControl for CommServer=<commvault_server.x..com>...
2459 99b 07/05 03:21:15 4998 ::JobControlInitialize() - m_jobControl.init(CONTROL) 4998:4:5:0:0:32808...
2459 99b 07/05 03:21:15 4998 Init() - Initializing job control [token=4998:4:5:0:0:32808,cn=server_client], serverName [commvault_server.x..com], ControlFlag [1], Job Id [4998]
2459 99b 07/05 03:21:15 4998 setupControl() - Created SemName : 4998_2459
2459 99b 07/05 03:21:15 4998 Init() - Created control semaphore : 4998
2459 99b 07/05 03:21:15 4998 createControlThread() - Call to create thread, 4998
2459 99b 07/05 03:21:15 4998 Init() - Created control thread, jid : 4998
2459 99d 07/05 03:21:15 4998 controlThread() - Going to wait on semaphore 4998_2459
2459 99b 07/05 03:21:15 4998 registerJob() - Started Session , 4998
2459 99b 07/05 03:21:15 4998 CVJobCtrlClient::registerJob() - Sent Message to CVD, 4998
2459 99b 07/05 03:21:15 4998 CVJobCtrlClient::registerJob() Successfully received acknowledgement for job registration.
2459 99b 07/05 03:21:15 4998 registerJob() - End Session, 4998
2459 99b 07/05 03:21:15 4998 JOBRESCACHEDIR: opened index cache [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998]
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - m_clientName=server_client hostName=server_client clientId=30
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - instanceName=<LDS> id=11
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - ClientNameList=<26>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - osUserName=<ldsadm>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - hdbsqldir=</usr/sap/LDS/HDB20/exe>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::SwitchUser() - Change user=ldsadm, gid=1004, uid=1021
2459 99b 07/05 03:21:15 4998 ClHanaAgent::SwitchUser() - Current uid = [0]gid = [1004]
2459 99b 07/05 03:21:15 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR=[ls -d  /usr/sap/LDS/HDB20/*/sec]
2459 99b 07/05 03:21:15 4998 ClHanaAgent::getSecuDirPath() - Command to find SECUDIR returned a single value. Using it as SECUDIR path: [/usr/sap/LDS/HDB20/server_client/sec]
2459 99b 07/05 03:21:15 4998 ClHanaAgent::setEnvironment() - Not setting HANA environment variables as nSetHANAEnv is not set
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - Current uid = [1021]gid = [1004]
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - Change user=ldsadm, gid=1004, uid=1021
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - Not calling initgroups, setuid, setgid for Non Root User
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - dbUserName=<commvault>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - ClientHostName=<server_client.x..com>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::ExecuteDataBackup() - db Node Number=<20>
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setHdbUserStoreKeyInfo() - User store key not set by user. Will use [BACKUP] as key.
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setSSLConnectString() - SSL not configured.
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setTraceLogDir() - Got CommCellId as 0, using default value of 2.
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setTraceLogDir() - jobResultDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998].
2459 99b 07/05 03:21:15 4998 HdbSqlHelper::setTraceLogDir() - traceLogDir was empty. Setting it to [/opt/commvault/iDataAgent/jobResults/CV_JobResults/2/0/4998/HANATrace].
2459 99b 07/05 03:21:15 4998 ClHanaAgent::createHdbStoreKey() - ClientID list = <26>
2459 99b 07/05 03:21:15 4998 ClHanaAgent::runPopenCmd() - Cmd=[/usr/sap/LDS/HDB20/exe/sapcontrol -nr 20 -function GetSystemInstanceList 2>&1].
2459 99b 07/05 03:21:15 4998 ClHanaAgent::runPopenCmd() - Command output [05.07.2022 03:21:15
GetSystemInstanceList
OK
hostname, instanceNr, httpPort, httpsPort, startPriority, features, dispstatus
server_client, 20, 52013, 52014, 0.3, HDB|HDB_WORKER, GREEN
]
2459 99b 07/05 03:21:15 4998 ClHanaAgent::getHANAHosts() - server_client  HDB|HDB_WORKER  GREEN
2459 99b 07/05 03:21:15 4998 ClHanaAgent::getHanaNodeListForStoreKey() - getHanaNodeListForStoreKey [/usr/sap/LDS/HDB20/exe/hdbnsutil -sr_state --sapcontrol=1 2>&1].
2459 99b 07/05 03:21:16 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [unhandled ltt exception: exception 2050005: Error loading shared library libhdbnspy : libhdbnspy.so: cannot open shared object file: No such file or directory]
2459 99b 07/05 03:21:16 4998 ClHanaAgent::getHanaNodeListForStoreKey() - []
2459 99b 07/05 03:21:16 4998 ClHanaAgent::getHanaNodeListForStoreKey() - [failed. trace file nameserver_server_client.00000.000.trc may contain more error details.]
2459 99b 07/05 03:21:16 4998 ClHanaAgent::getHanaNodeListForStoreKey() - Nodelist for creating HDB store key is [server_client:32013,server_client:32015].
2459 99b 07/05 03:21:16 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbuserstore List BACKUP 2>&1
2459 99b 07/05 03:21:16 4998 CvProcess::system() - Command completed with rc=0
2459 99b 07/05 03:21:16 4998 ClHanaAgent::ExecuteDataBackup() - <HDB version info:
>
2459 99b 07/05 03:21:16 4998 ClHanaAgent::ExecuteDataBackup() - <  version:             2.00.061.00.1644229038
>
2459 99b 07/05 03:21:16 4998 ClHanaAgent::ExecuteDataBackup() - hdbStoreKey=<BACKUP>
2459 99b 07/05 03:21:16 4998 CvProcess::system() - /usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'" 2>&1
2459 99b 07/05 03:21:16 4998 CvProcess::system() - Command completed with rc=3
2459 99b 07/05 03:21:16 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
2459 99b 07/05 03:21:16 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:5:0:0:32808]
2459 99b 07/05 03:21:16 4998 GetChannelParamFileName() - Channel Parameter File : /opt/commvault/Base/Temp/cv_channel_info4998
2459 99b 07/05 03:21:16 4998 ::NotifyCommServeJobComplete() - Notify CommServe: jobObject.complete(FAIL,PHASE)
2459 99b 07/05 03:21:16 4998 BKP CALLED COMPLETE (PHASE Status::FAIL), 4998. Token [4998:4:5:0:0:32808]
2459 99b 07/05 03:21:17 4998 JM Client  JobClient: Jobmanager replied with an error code of [3] in the acknowledgement message. Error [Invalid token [4998:4:5:0:0:32808] received for the job complete message. Error:[The job token is for an older attempt than that of the job. AttemptNo in client token [5]. Current AttemptNo of job [6].].]
2459 99b 07/05 03:21:17 4998 ::complete() - m_jobObjectB->complete failed
2459 99b 07/05 03:21:17 4998 ::NotifyCommServeJobComplete() - jobObject.complete(FAIL,PHASE) failed.
 

Userlevel 5
Badge +12

Hi @Carlos Moreira 

Can you please run this command on the HANA server as ldsadm user and see if it’s running fine? This is the query that’s failing when run from Commvault.

 

/usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'"

Userlevel 2
Badge +6

Hi @Sunil 

 

SAP HANA team, asked if the command you mention.It has to be all this. How are these parameters?

/usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'"

Userlevel 5
Badge +12

Hi @Sunil 

 

SAP HANA team, asked if the command you mention.It has to be all this. How are these parameters?

/usr/sap/LDS/HDB20/exe/hdbsql   -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'"

This command is all it is. The parameters are the exactly same parameters we use from Commvault.

 

Thanks,

Sunil-

Userlevel 2
Badge +6

Ok @Sunil , 

 

I will let them know. Once you have the result. I already send you..Does this user "ldsadm" have to have permissions in OS, as root?
Userlevel 5
Badge +12

Hi @Carlos Moreira 

ldsadm is the OS user for the HANA instance LDS. This need not have root level permissions.

But all HANA commands/queries need to be run as this user.

 

Thanks,

Sunil-

Userlevel 2
Badge +6

hello @Sunil 

 

Team SAP Hana. Run the command.

 

Result was this.

___________________________________________________________________________________

ldsadm@gcppsmpdb01ler:/usr/sap/LDS/HDB20> /usr/sap/LDS/HDB20/exe/hdbsql -t -U BACKUP -d SYSTEMDB -C "SELECT LAYER_NAME, VALUE FROM M_INIFILE_CONTENTS WHERE file_name = 'global.ini' AND SECTION = 'multidb' AND KEY = 'mode'"
* 10: authentication failed SQLSTATE: 28000

 

 

Userlevel 5
Badge +12

Hi @Carlos Moreira 

The error clearly shows it’s an authentication error. 

* 10: authentication failed SQLSTATE: 28000

I suspect the username, password you’ve configured in the instance properties dialog aren’t correct. You need to either correct that, or ask your SAP HANA Team to create an HDB store key and use the key instead of user name/password.

 

Thanks,

Sunil-

Userlevel 2
Badge +6

Hi @Sunil 

Thank you very much for your help and service. 
You are a very caring person.

________________________________________________________________________

SAP HANA Team, mentioned that it has not changed or changed username and password. I believe someone may have messed with permissions.Do you also suspect that they changed the user's permissions?

 

 

Userlevel 5
Badge +12

Hi @Carlos Moreira 

It doesn’t look like it’s with the permissions. Is the SAP HANA DB username really “commvault”? If you think they are correct, you can retype the username and password in the instance properties and click Ok. If that doesn’t work, I will try to help you live.

 

Thanks,

Sunil-

 

Userlevel 2
Badge +6

Oi@Sunil 

 

Sim, o usuário é de fato commvault. E a senha é a mesma.
Userlevel 5
Badge +12

Oi@Sunil 

 

Sim, o usuário é de fato commvault. E a senha é a mesma.

Did you try retyping the credentials then?

 

Thanks,

Sunil-

 

Userlevel 2
Badge +6
Yes i typed...
Userlevel 5
Badge +12

Hi @Carlos Moreira 

We need to take a look at the environment at this point. I’d request you to open a support ticket to check further.

 

Thanks,

Sunil-

Userlevel 2
Badge +6

Hello @Sunil 

 

The troubleshooting sent by you, solved the problem.

👍🏻

Thanks for your time.

Regards✋🏼

Userlevel 5
Badge +12

Hello @Sunil 

 

The troubleshooting sent by you, solved the problem.

👍🏻

Thanks for your time.

Regards✋🏼

Hi @Carlos Moreira 

Great to know. Thanks for the update.

 

Thanks,

Sunil-

Userlevel 5
Badge +12

Hi @Carlos Moreira 

May I know what was the issue though?

 

Thanks,

Sunil-

 

Userlevel 2
Badge +6

hi @Sunil 

 

SAP HANA TEAM, 

used this knowledge base

_______________________________________________________

https://userapps.support.sap.com/sap/support/knowledge/en/3044240

Userlevel 5
Badge +12

hi @Sunil 

 

SAP HANA TEAM, 

used this knowledge base

_______________________________________________________

https://userapps.support.sap.com/sap/support/knowledge/en/3044240

Wonderful. Thanks for letting me know.

Reply