16:03:50.891 [4614.4614] <4> ProcessContext: ProcessName:[nbrepo], FipsMode:[DISABLED], Username:[root], IsServiceAdmin:[0], UserID:[0], GroupID:[0]
16:03:50.891 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3259] Comparing name1:[localhost] name2:[nbprimary]
16:03:50.891 [4614.4614] <2> vnet_pcache_init_table: [vnet_private.c:206] starting cache size 200 0xc8
16:03:50.891 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1851] found in file cache NAME=localhost SVC=NULL
16:03:50.891 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1851] found in file cache NAME=nbprimary SVC=NULL
16:03:50.891 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:50.891 [4614.4614] <2> init_resilient_cache: [vnet_nbrntd.c:2964] Initialize resilient cache. 0 0x0
16:03:50.891 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:50.891 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3384] matched as locals NAME1=localhost NAME2=nbprimary
16:03:50.891 [4614.4614] <4> initialize: The volume hosting [/usr/openv/var/global/repo] has [403861] MB space available, [77.037643] percent of the disk.
16:03:50.891 [4614.4614] <4> initialize: Initialized nbrepo on master server nbprimary.
16:03:50.891 [4614.4614] <4> addPackagesCmd: Supplied .sja file [nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja] exists and is accessible to this nbrepo process.
16:03:50.892 [4614.4614] <4> NbRepoHelper::verifySja: Trying to get Pubkey [/usr/openv/var/global/vxupdate/pubkey.pem]
16:03:50.892 [4614.4614] <4> NbRepoHelper::verifySja: Public key found. Proceeding with verification
16:03:51.365 [4614.4614] <4> NbRepoHelper::verifySja: Signature of SJA file [nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja] is verified.
16:03:51.365 [4614.4614] <4> addPackagesCmd: Copying package from [nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja] to VxUpdate repo location [/usr/openv/var/global/repo/nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja].
16:03:51.365 [4614.4614] <4> NbRepoHelper::verifySja: Trying to get Pubkey [/usr/openv/var/global/vxupdate/pubkey.pem]
16:03:51.365 [4614.4614] <4> NbRepoHelper::verifySja: Public key found. Proceeding with verification
16:03:51.832 [4614.4614] <4> NbRepoHelper::verifySja: Signature of SJA file [nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja] is verified.
16:03:51.832 [4614.4614] <2> NbRepoHelper::copySja: Package size: [177] MB.
16:03:51.832 [4614.4614] <2> NbRepoHelper::copySja: Total disk space: [524238] MB.
16:03:51.832 [4614.4614] <2> NbRepoHelper::copySja: Free disk space: [403861] MB.
16:03:51.832 [4614.4614] <2> NbRepoHelper::copySja: Percent free: [77.037643].
16:03:51.832 [4614.4614] <2> NbRepoHelper::copySja: Required disk space: [8040] MB.
16:03:52.113 [4614.4614] <4> NbRepoHelper::copySja: Copied package file [nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja] to [/usr/openv/var/global/repo/nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja].
16:03:52.113 [4614.4614] <4> addPackagesCmd: Copy successful.
16:03:52.113 [4614.4614] <4> NbRepoHelper::getJSONRequestBody: Package details in JSON format: {"data": {"type": "newPackageAttributes", "attributes": {"packageFilePathName": "/usr/openv/var/global/repo/nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja"}}}
16:03:52.113 [4614.4614] <2> NBClientCURL::NBClientCURL: Curl initialized successfully.
16:03:52.113 [4614.4614] <2> NBClientCURL::NBClientCURL: Performing curl_easy_init()
16:03:52.113 [4614.4614] <2> NBClientCURL::NBClientCURL: Skipping local setting
16:03:52.113 [4614.4614] <2> NBClientCURL::NBClientCURL: Disabling any proxy option set in the environment.
16:03:52.113 [4614.4614] <2> NBClientCURL::NBClientCURL: Setting default CURL timeout = [300] seconds
16:03:52.113 [4614.4614] <2> NBClientCURL::NBClientCURL: Disable signal during name resolution.
16:03:52.113 [4614.4614] <2> getCrlCheckLevelFromConfig: crlCheckFlag value read from bpconf : 1
16:03:52.113 [4614.4614] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: ENABLE_NBCURL_VERBOSE is not set
16:03:52.113 [4614.4614] <2> readJsonMapFileBaseEx: Reading certmapinfo
16:03:52.114 [4614.4614] <2> readJsonMapFileBaseEx: Reading json mapping file [/usr/openv/var/vxss/certmapinfo.json]
16:03:52.114 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3259] Comparing name1:[localhost] name2:[nbprimary]
16:03:52.114 [4614.4614] <2> isServerPresent: Server [nbprimary] is present in mapping file
16:03:52.114 [4614.4614] <2> getCaSupportedByThisHost: Domain [dce67a52-13fb-4691-ad37-db123f68ecaa] supports only NBCA
16:03:52.114 [4614.4614] <2> NBClientCURL::getCATypeToUse: getCaSupportedByThisHost: CA type to use: [NBCA]
16:03:52.114 [4614.4614] <2> getCertDataByCAtypeExEx: Returning NBCA certificate details
16:03:52.114 [4614.4614] <2> getCertPath: Host Certificate path is [/usr/openv/var/vxss/credentials/34d87bdd-2894-40f3-80bb-e353a668c3fe]
16:03:52.114 [4614.4614] <2> getCertDataByCAtypeExEx: Private key file path is not included in the response.
16:03:52.114 [4614.4614] <2> getCACertPath: CA Cert path is [/usr/openv/var/webtruststore/cacert.pem]
16:03:52.114 [4614.4614] <2> NBClientCURL::getCATypeToUse: getCertDataByCAtype passed: CA type to use: [NBCA]
16:03:52.114 [4614.4614] <2> NBClientCURL::setCurlTLSParams: reqCAType [0] forMutualAuth [0] skipPKIInfo [0]
16:03:52.114 [4614.4614] <2> NBClientCURL::populatePKIArtifacts: Populating m_certPath from pkiArtifacts
16:03:52.114 [4614.4614] <2> NBClientCURL::populatePKIArtifacts: pkiArtifacts.certPath [/usr/openv/var/vxss/credentials/34d87bdd-2894-40f3-80bb-e353a668c3fe].
16:03:52.114 [4614.4614] <2> NBClientCURL::populatePKIArtifacts: caTrustPath is [/usr/openv/var/webtruststore/cacert.pem].
16:03:52.114 [4614.4614] <2> NBClientCURL::setSNIHeader(CA_USAGE): Setting SNI header for [NBCA]
16:03:52.114 [4614.4614] <2> NBClientCURL::setFSPKIArtifacts: m_CACertPath: [/usr/openv/var/webtruststore/cacert.pem]
16:03:52.114 [4614.4614] <2> NBClientCURL::setFSPKIArtifacts: Setting NBCA CA certificate
16:03:52.114 [4614.4614] <2> NBClientCURL:::setTrustedCertInMemory: Reading data from file [/usr/openv/var/webtruststore/cacert.pem]
16:03:52.114 [4614.4614] <2> readPEMDataFromFile: Reading data from file [/usr/openv/var/webtruststore/cacert.pem]
16:03:52.114 [4614.4614] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_CONNECTIONS is not set
16:03:52.114 [4614.4614] <2> NBClientCURL::nbcurl_bpconf_keyvalue_state: DISABLE_NBCURL_PREFERRED_NETWORKS is not set
16:03:52.114 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3259] Comparing name1:[localhost] name2:[nbprimary]
16:03:52.115 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] found in cache NAME=localhost SVC=NULL
16:03:52.115 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] found in cache NAME=nbprimary SVC=NULL
16:03:52.115 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:52.115 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:52.115 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3384] matched as locals NAME1=localhost NAME2=nbprimary
16:03:52.115 [4614.4614] <2> NBClientCURL::setupCurlOperationTimeout: Setting CURL timeout = [480] seconds
16:03:52.115 [4614.4614] <2> curlSendRequest: Sending curl request with URI : netbackup/deployment/packages.
16:03:52.115 [4614.4614] <2> curlSendRequest: Request body: {"data": {"type": "newPackageAttributes", "attributes": {"packageFilePathName": "/usr/openv/var/global/repo/nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja"}}}.
16:03:52.115 [4614.4614] <2> NBClientCURL::GetOrPostWithCache: (no-cache)Request not served from cache hence invoking URL: https://nbprimary:1556/netbackup/deployment/packages
16:03:52.115 [4614.4614] <2> NBClientCURL::GetOrPostWithCache: POSTing to URL https://nbprimary:1556/netbackup/deployment/packages
16:03:52.115 [4614.4614] <2> NBClientCURL::GetOrPostWithCache: Sending payload ...
16:03:52.115 [4614.4614] <2> NBClientCURL::setPostOptions: setting curl post options ...
16:03:52.115 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3259] Comparing name1:[localhost] name2:[nbrpimary]
16:03:52.115 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] found in cache NAME=localhost SVC=NULL
16:03:52.115 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] found in cache NAME=nbprimary SVC=NULL
16:03:52.115 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:52.115 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:52.115 [4614.4614] <2> vnet_same_host_and_update: [vnet_addrinfo.c:3384] matched as locals NAME1=localhost NAME2=nbprimary
16:03:52.115 [4614.4614] <2> NBClientCURL::performCurlOperation: curl instance parameters: CA Cert Path [/usr/openv/var/webtruststore/cacert.pem] Key File Path [] Cert Path [/usr/openv/var/vxss/credentials/34d87bdd-2894-40f3-80bb-e353a668c3fe] CRL Path [] Session Type [1] Server [nbprimary] Certificate Type [1]
16:03:52.115 [4614.4614] <2> LoginWithCertManager::getLocalToken: tokenIssueTimeSec = 1671545863
16:03:52.115 [4614.4614] <2> LoginWithCertManager::getLocalToken: tokenExpTimeSec = 1671632263
16:03:52.115 [4614.4614] <2> LoginWithCertManager::getLocalToken: tokenLastFailTimeSec = 0
16:03:52.115 [4614.4614] <2> LoginWithCertManager::isJWTRefreshRequired: tokenExpTimeSec = 1671632263, timeNow = 1671548632, (tokenExpTimeSec-timeNow) = 83631
16:03:52.115 [4614.4614] <2> LoginWithCertManager::isJWTRefreshRequired: Token-refresh IS NOT required
16:03:52.115 [4614.4614] <2> NBClientCURL::performCurlOperation: Obtained login token
16:03:52.115 [4614.4614] <2> nbclnt_curl_prefnet::reset: Returning VN_STATUS_SUCCESS
16:03:52.115 [4614.4614] <2> NBClientCURL::connectToPBXExchange: Connecting to [nbprimary].
16:03:52.116 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1851] found in file cache NAME=nbprimary SVC=1556
16:03:52.116 [4614.4614] <2> nbclnt_curl_prefnet::resolv_host_service: Returning VN_STATUS_SUCCESS
16:03:52.116 [4614.4614] <2> vnet_cached_getaddrinfo_and_update: [vnet_addrinfo.c:1691] found in cache NAME=xx.xx.xxx.xxx SVC=NULL
16:03:52.116 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:52.116 [4614.4614] <2> vnet_get_peer_sock_names: ignoring getpeername() failed: 107, fd: 4
16:03:52.116 [4614.4614] <2> vnet_is_local_host: [vnet_addrinfo.c:406] NAME: xx.xx.xxx.xxx IP: xx.xx.xxx.xxx PORT: 0 updating local cache flags to VN_IS_LOCAL_YES
16:03:52.116 [4614.4614] <2> vnet_get_pref_netconnection: [vnet_addrinfo.c:5513] Local [strong] check, using interface  ANY
16:03:52.116 [4614.4614] <2> nbclnt_curl_prefnet::usable_prefnet_settings: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::helper_connect: Connecting to [xx.xx.xxx.xxx]:[1556].
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::helper_connect: NON-Blocking connect in progress. Watch WRITE.
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::helper_connect: New sockfd is [4].
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::helper_connect: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::tryeach_iface_connect: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::establish_initial_connection: Returning rc
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::nbio_connect: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::bio_connect: RC [0] STAT [-1] MAXFD [4] TIMEOUT [30].
16:03:52.117 [4614.4614] <2> vnet_proxy_get_peer_sock_names: setting connection_info json as json_null
16:03:52.117 [4614.4614] <2> vnet_proxy_get_peer_sock_names: setting connection_info json as json_null
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::helper_check_connect_status: nbcurl CONNECT FROM xx.xx.xxx.xxx.58318 TO xx.xx.xxx.xxx.1556 fd = 4
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::helper_check_connect_status: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::nbio_connect: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::bio_connect: RC [0] STAT [0] MAXFD [0] TIMEOUT [-1].
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::bio_connect: Returning VN_STATUS_SUCCESS
16:03:52.117 [4614.4614] <2> nbclnt_curl_prefnet::operator std::string(): resolved hostname => IP [nbprimary:1556:xx.xx.xxx.xxx].
16:03:52.117 [4614.4614] <2> NBClientCURL::resetCurlResolver: Hostname resolution set to [nbprimary:1556:xx.xx.xxx.xxx].
16:03:52.119 [4614.4614] <2> nbclnt_curl_prefnet::operator vnet_socket_t(): Returning this->m_fd
16:03:52.119 [4614.4614] <2> nbclnt_curl_prefnet::operator vnet_socket_t(): Returning this->m_fd
16:03:52.232 [4614.4614] <4> NBClientCURL::performCurlOperation: HTTP response header : HTTP/1.1 409  Strict-Transport-Security: max-age=31536000;includeSubDomains X-Frame-Options: DENY X-Content-Type-Options: nosniff X-XSS-Protection: 1; mode=block Cache-Control: no-cache,no-store Pragma: no-cache Content-Security-Policy: default-src 'none' antiClickJackingOption: DENY X-Request-ID: C4D360E37067A2AE Content-Type: application/vnd.netbackup+json;version=7.0;charset=UTF-8 Transfer-Encoding: chunked Date: Tue, 20 Dec 2022 15:03:52 GMT
16:03:52.232 [4614.4614] <2> NBClientCURL::performCurlOperation: Fetched data = [xxxxx], httpcode = 409
16:03:52.232 [4614.4614] <2> curlSendRequest: Http response body: {"errorCode":7218,"errorMessage":"The package already exists in the package repository.","attributeErrors":{},"fileUploadErrors":[],"errorDetails":["Package [nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja] already exists in the repository."]}
16:03:52.232 [4614.4614] <2> curlSendRequest: Actual http response code: 409 , expected http response code: 201
16:03:52.232 [4614.4614] <16> parseJSONErrorResponse: Error code returned by server is : 7218.
16:03:52.232 [4614.4614] <16> parseJSONErrorResponse: Error message returned by server: The package already exists in the package repository..
16:03:52.232 [4614.4614] <16> addPackagesCmd: Failed to add deployment package metadata. Error code returned from curlSendRequest: 7218.
16:03:52.232 [4614.4614] <4> addPackagesCmd: Removing copy of package from [/usr/openv/var/global/repo/nbeeb.client_4086374.1_10.0.0.1_redhat_x64.sja].
16:03:52.232 [4614.4614] <2> NBClientCURL:~NBClientCURL: Performing curl_easy_cleanup()
16:03:52.232 [4614.4614] <2> nbclnt_curl_prefnet::reset: Returning VN_STATUS_SUCCESS
16:03:52.232 [4614.4614] <16> main: Package already exists in the repository.
16:03:52.232 [4614.4614] <4> main: nbrepo exit status: [7218]