PNF csar download fails in CDS for PNF use case. VNF use case is working.

 

 

aseaudi 8:31 PM
i get SDNC fail to deploy after distribution
i see the following logs in SDNC listner and message router

SDNC DMAAP LISTENER:
INFO 2019-05-09 00:31:36.444 +0000 SdncDmaapConsumer - PciChangesFromPolicyToSDNRDmaapConsumer received ResponseCode: null
INFO 2019-05-09 00:31:36.444 +0000 SdncDmaapConsumer - PciChangesFromPolicyToSDNRDmaapConsumer received ResponseMessage: java.net.ConnectException: Connection refused (Connection refused)
INFO 2019-05-09 00:31:36.444 +0000 SdncDmaapConsumer - No data received from fetch. Pausing 5000 ms before retry
ERROR 2019-05-09 00:31:36.773 +0000 MRConsumerImpl - exception:
javax.ws.rs.ProcessingException: java.net.ConnectException: Connection refused (Connection refused)
INFO 2019-05-09 00:31:36.774 +0000 SdncDmaapConsumer - ANRChangesFromPolicyToSDNRDmaapConsumer received ResponseCode: null
INFO 2019-05-09 00:31:36.774 +0000 SdncDmaapConsumer - ANRChangesFromPolicyToSDNRDmaapConsumer received ResponseMessage: java.net.ConnectException: Connection refused (Connection refused)
INFO 2019-05-09 00:31:36.774 +0000 SdncDmaapConsumer - No data received from fetch. Pausing 5000 ms before retry

MESSAGE ROUTER:
2019-05-09 00:30:32,840|||qtp89387388-546201||||INFO||||||| AUTH-LOG(10.42.239.163): Client date is not in acceptable range of server date. Client:1455832657000, Server: 1557361832840, Threshold: 6000000000.
2019-05-09 00:30:32,840|||qtp89387388-546428||||INFO||||||| AUTH-LOG(10.42.239.163): Client date is not in acceptable range of server date. Client:1455832657000, Server: 1557361832840, Threshold: 6000000000.
2019-05-09 00:30:32,840|||qtp89387388-546201||||INFO||||||| AUTH-LOG(10.42.239.163): Client date is not in acceptable range of server date. Client:1455832657000, Server: 1557361832840, Threshold: 600000.
2019-05-09 00:30:32,840|||qtp89387388-546428||||INFO||||||| AUTH-LOG(10.42.239.163): Client date is not in acceptable range of server date. Client:1455832657000, Server: 1557361832840, Threshold: 600000.
what does this mean : Client date is not in acceptable range of server date. Client:1455832657000, Server: 1557361832840, Threshold: 6000000000.

 

SDNC UEB LISTENER:

DEBUG 2019-05-09 12:41:16.932 +0000 CachedDataSource - Obtained connection <sdnctldb01>: ProxyConnection[PooledConnection[org.mariadb.jdbc.MariaDbConnection@14f8edd7]]
DEBUG 2019-05-09 12:41:16.932 +0000 CachedDataSource - SQL Statement: SELECT * from SERVICE_MODEL_TO_VF_MODEL_MAPPING where service_uuid = "d66a19bd-4834-42be-90e7-9a8a80f9237d" AND vf_customization_uuid = "c1194d1a-c9f5-4cdc-9658-58f633da1b41";
DEBUG 2019-05-09 12:41:16.940 +0000 CachedDataSource - SQL FAILURE. time(ms): 8
ERROR 2019-05-09 12:41:16.941 +0000 CachedDataSource - <sdnctldb01> Failed to execute: SELECT * from SERVICE_MODEL_TO_VF_MODEL_MAPPING where service_uuid = "d66a19bd-4834-42be-90e7-9a8a80f9237d" AND vf_customization_uuid = "c1194d1a-c9f5-4cdc-9658-58f633da1b41"; with no arguments.
java.sql.SQLSyntaxErrorException: (conn=36124) Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.get(ExceptionMapper.java:158)
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.getException(ExceptionMapper.java:118)
at org.mariadb.jdbc.MariaDbStatement.executeExceptionEpilogue(MariaDbStatement.java:235)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeInternal(MariaDbPreparedStatementClient.java:224)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.execute(MariaDbPreparedStatementClient.java:159)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeQuery(MariaDbPreparedStatementClient.java:173)
at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.apache.tomcat.jdbc.pool.StatementFacade$StatementProxy.invoke(StatementFacade.java:114)
at com.sun.proxy.$Proxy5.executeQuery(Unknown Source)
at org.onap.ccsdk.sli.core.dblib.CachedDataSource.executePreparedStatement(CachedDataSource.java:181)
at org.onap.ccsdk.sli.core.dblib.CachedDataSource.getData(CachedDataSource.java:139)
at org.onap.ccsdk.sli.core.dblib.DBResourceManager.requestDataNoRecovery(DBResourceManager.java:548)
at org.onap.ccsdk.sli.core.dblib.DBResourceManager.getData(DBResourceManager.java:462)
at org.onap.ccsdk.sli.northbound.uebclient.SdncBaseModel.cleanUpExistingToscaData(SdncBaseModel.java:1058)
at org.onap.ccsdk.sli.northbound.uebclient.SdncPNFModel.insertPNFModelData(SdncPNFModel.java:83)
at org.onap.ccsdk.sli.northbound.uebclient.SdncPNFModel.insertData(SdncPNFModel.java:76)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.processToscaCsar(SdncUebCallback.java:772)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleToscaArtifact(SdncUebCallback.java:631)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleSuccessfulDownload(SdncUebCallback.java:550)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleArtifact(SdncUebCallback.java:508)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.activateCallback(SdncUebCallback.java:308)
at org.onap.sdc.impl.NotificationConsumer.run(NotificationConsumer.java:71)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.sql.SQLException: Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
Query is: SELECT * from SERVICE_MODEL_TO_VF_MODEL_MAPPING where service_uuid = "d66a19bd-4834-42be-90e7-9a8a80f9237d" AND vf_customization_uuid = "c1194d1a-c9f5-4cdc-9658-58f633da1b41";
at org.mariadb.jdbc.internal.util.LogQueryTool.exceptionWithQuery(LogQueryTool.java:146)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.executeQuery(AbstractQueryProtocol.java:217)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeInternal(MariaDbPreparedStatementClient.java:218)
... 27 more
ERROR 2019-05-09 12:41:16.942 +0000 DBResourceManager - Generated alarm: sdnctldb01 - (conn=36124) Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
DEBUG 2019-05-09 12:41:16.942 +0000 DBResourceManager - >> getData : sdnctldb01 10 miliseconds.
ERROR 2019-05-09 12:41:16.942 +0000 SdncBaseModel - Could not clean up existing SERVICE_MODEL_TO_VF_MODEL_MAPPING for "d66a19bd-4834-42be-90e7-9a8a80f9237d" and "c1194d1a-c9f5-4cdc-9658-58f633da1b41"
java.sql.SQLSyntaxErrorException: (conn=36124) Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.get(ExceptionMapper.java:158)
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.getException(ExceptionMapper.java:118)
at org.mariadb.jdbc.MariaDbStatement.executeExceptionEpilogue(MariaDbStatement.java:235)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeInternal(MariaDbPreparedStatementClient.java:224)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.execute(MariaDbPreparedStatementClient.java:159)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeQuery(MariaDbPreparedStatementClient.java:173)
at sun.reflect.GeneratedMethodAccessor4.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.apache.tomcat.jdbc.pool.StatementFacade$StatementProxy.invoke(StatementFacade.java:114)
at com.sun.proxy.$Proxy5.executeQuery(Unknown Source)
at org.onap.ccsdk.sli.core.dblib.CachedDataSource.executePreparedStatement(CachedDataSource.java:181)
at org.onap.ccsdk.sli.core.dblib.CachedDataSource.getData(CachedDataSource.java:139)
at org.onap.ccsdk.sli.core.dblib.DBResourceManager.requestDataNoRecovery(DBResourceManager.java:548)
at org.onap.ccsdk.sli.core.dblib.DBResourceManager.getData(DBResourceManager.java:462)
at org.onap.ccsdk.sli.northbound.uebclient.SdncBaseModel.cleanUpExistingToscaData(SdncBaseModel.java:1058)
at org.onap.ccsdk.sli.northbound.uebclient.SdncPNFModel.insertPNFModelData(SdncPNFModel.java:83)
at org.onap.ccsdk.sli.northbound.uebclient.SdncPNFModel.insertData(SdncPNFModel.java:76)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.processToscaCsar(SdncUebCallback.java:772)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleToscaArtifact(SdncUebCallback.java:631)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleSuccessfulDownload(SdncUebCallback.java:550)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleArtifact(SdncUebCallback.java:508)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.activateCallback(SdncUebCallback.java:308)
at org.onap.sdc.impl.NotificationConsumer.run(NotificationConsumer.java:71)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.sql.SQLException: Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
Query is: SELECT * from SERVICE_MODEL_TO_VF_MODEL_MAPPING where service_uuid = "d66a19bd-4834-42be-90e7-9a8a80f9237d" AND vf_customization_uuid = "c1194d1a-c9f5-4cdc-9658-58f633da1b41";
at org.mariadb.jdbc.internal.util.LogQueryTool.exceptionWithQuery(LogQueryTool.java:146)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.executeQuery(AbstractQueryProtocol.java:217)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeInternal(MariaDbPreparedStatementClient.java:218)
... 27 more
INFO 2019-05-09 12:41:16.942 +0000 SdncVFModel - Call insertToscaData for VF_MODEL where customization_uuid = "c1194d1a-c9f5-4cdc-9658-58f633da1b41"
DEBUG 2019-05-09 12:41:16.942 +0000 SdncBaseModel - insertToscaData: INSERT into VF_MODEL (customization_uuid, model_yaml, vendor, name, vendor_version, invariant_uuid, uuid, version) values ("c1194d1a-c9f5-4cdc-9658-58f633da1b41", "null", "v", "pnf-test-2", "r", "96e969b6-b62b-4c11-b1f0-bf4d9ee74260", "d2cbf405-9da2-4385-bcf1-7382b70601a2", "1.0");
DEBUG 2019-05-09 12:41:16.942 +0000 CachedDataSource - Obtained connection <sdnctldb01>: ProxyConnection[PooledConnection[org.mariadb.jdbc.MariaDbConnection@14f8edd7]]
DEBUG 2019-05-09 12:41:16.946 +0000 CachedDataSource - SQL SUCCESS. rows returned: 0, time(ms): 4
DEBUG 2019-05-09 12:41:16.946 +0000 DBResourceManager - writeData processing time : sdnctldb01 4 miliseconds.
DEBUG 2019-05-09 12:41:16.946 +0000 SdncBaseModel - insertToscaData: INSERT into SERVICE_MODEL_TO_VF_MODEL_MAPPING (service_uuid, vf_uuid, vf_customization_uuid, service_invariant_uuid) values ("d66a19bd-4834-42be-90e7-9a8a80f9237d", "d2cbf405-9da2-4385-bcf1-7382b70601a2", "c1194d1a-c9f5-4cdc-9658-58f633da1b41", "c64b44f1-48b9-4551-af80-87ec1e9e8608");
DEBUG 2019-05-09 12:41:16.947 +0000 CachedDataSource - Obtained connection <sdnctldb01>: ProxyConnection[PooledConnection[org.mariadb.jdbc.MariaDbConnection@14f8edd7]]
DEBUG 2019-05-09 12:41:16.948 +0000 CachedDataSource - SQL FAILURE. time(ms): 1
ERROR 2019-05-09 12:41:16.949 +0000 CachedDataSource - <sdnctldb01> Failed to execute: INSERT into SERVICE_MODEL_TO_VF_MODEL_MAPPING (service_uuid, vf_uuid, vf_customization_uuid, service_invariant_uuid) values ("d66a19bd-4834-42be-90e7-9a8a80f9237d", "d2cbf405-9da2-4385-bcf1-7382b70601a2", "c1194d1a-c9f5-4cdc-9658-58f633da1b41", "c64b44f1-48b9-4551-af80-87ec1e9e8608"); with no arguments.
java.sql.SQLSyntaxErrorException: (conn=36124) Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.get(ExceptionMapper.java:158)
at org.mariadb.jdbc.internal.util.exceptions.ExceptionMapper.getException(ExceptionMapper.java:118)
at org.mariadb.jdbc.MariaDbStatement.executeExceptionEpilogue(MariaDbStatement.java:235)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeInternal(MariaDbPreparedStatementClient.java:224)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.execute(MariaDbPreparedStatementClient.java:159)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeUpdate(MariaDbPreparedStatementClient.java:192)
at sun.reflect.GeneratedMethodAccessor3.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.apache.tomcat.jdbc.pool.StatementFacade$StatementProxy.invoke(StatementFacade.java:114)
at com.sun.proxy.$Proxy5.executeUpdate(Unknown Source)
at org.onap.ccsdk.sli.core.dblib.CachedDataSource.executeUpdatePreparedStatement(CachedDataSource.java:243)
at org.onap.ccsdk.sli.core.dblib.CachedDataSource.writeData(CachedDataSource.java:155)
at org.onap.ccsdk.sli.core.dblib.DBResourceManager.writeDataNoRecovery(DBResourceManager.java:629)
at org.onap.ccsdk.sli.core.dblib.DBResourceManager.writeData(DBResourceManager.java:582)
at org.onap.ccsdk.sli.northbound.uebclient.SdncBaseModel.insertToscaData(SdncBaseModel.java:1007)
at org.onap.ccsdk.sli.northbound.uebclient.SdncPNFModel.insertPNFModelData(SdncPNFModel.java:94)
at org.onap.ccsdk.sli.northbound.uebclient.SdncPNFModel.insertData(SdncPNFModel.java:76)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.processToscaCsar(SdncUebCallback.java:772)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleToscaArtifact(SdncUebCallback.java:631)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleSuccessfulDownload(SdncUebCallback.java:550)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.handleArtifact(SdncUebCallback.java:508)
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.activateCallback(SdncUebCallback.java:308)
at org.onap.sdc.impl.NotificationConsumer.run(NotificationConsumer.java:71)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
Caused by: java.sql.SQLException: Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
Query is: INSERT into SERVICE_MODEL_TO_VF_MODEL_MAPPING (service_uuid, vf_uuid, vf_customization_uuid, service_invariant_uuid) values ("d66a19bd-4834-42be-90e7-9a8a80f9237d", "d2cbf405-9da2-4385-bcf1-7382b70601a2", "c1194d1a-c9f5-4cdc-9658-58f633da1b41", "c64b44f1-48b9-4551-af80-87ec1e9e8608");
at org.mariadb.jdbc.internal.util.LogQueryTool.exceptionWithQuery(LogQueryTool.java:146)
at org.mariadb.jdbc.internal.protocol.AbstractQueryProtocol.executeQuery(AbstractQueryProtocol.java:217)
at org.mariadb.jdbc.MariaDbPreparedStatementClient.executeInternal(MariaDbPreparedStatementClient.java:218)
... 27 more
ERROR 2019-05-09 12:41:16.949 +0000 DBResourceManager - Generated alarm: sdnctldb01 - (conn=36124) Table 'sdnctl.SERVICE_MODEL_TO_VF_MODEL_MAPPING' doesn't exist
DEBUG 2019-05-09 12:41:16.949 +0000 DBResourceManager - writeData processing time : sdnctldb01 3 miliseconds.
ERROR 2019-05-09 12:41:16.950 +0000 SdncBaseModel - Could not insert Tosca data into the database
ERROR 2019-05-09 12:41:16.950 +0000 SdncVFModel - Could not insert Tosca CSAR data into the VF_MODEL table
INFO 2019-05-09 12:41:16.950 +0000 SdncUebCallback - Update to SDN-C failed
INFO 2019-05-09 12:41:16.950 +0000 DistributionClientImpl - DistributionClient - sendDeploymentStatus
INFO 2019-05-09 12:41:16.950 +0000 DistributionClientImpl - DistributionClient - sendStatus
DEBUG 2019-05-09 12:41:16.952 +0000 DistributionClientImpl - after create publisher server list [message-router.onap, message-router.onap]
DEBUG 2019-05-09 12:41:16.952 +0000 DistributionClientImpl - try to send status {
"distributionID": "9d349b0a-e042-427f-a107-79d74d8e14da",
"consumerID": "sdc-COpenSource-Env11-sdnc-dockero",
"timestamp": 1557405676950,
"artifactURL": "/sdc/v1/catalog/services/ServicePnfTest/1.0/artifacts/service-ServicePnfTest-csar.csar",
"status": "DEPLOY_ERROR"
}
INFO 2019-05-09 12:41:17.053 +0000 CambriaSimplerBatchPublisher - sending 1 msgs to /events/SDC-DISTR-STATUS-TOPIC-AUTO. Oldest: 100 ms
WARN 2019-05-09 12:41:17.053 +0000 HttpClient - POST http://message-router.onap:3904/events/SDC-DISTR-STATUS-TOPIC-AUTO will send credentials over a clear channel.
INFO 2019-05-09 12:41:17.053 +0000 HttpClient - POST http://message-router.onap:3904/events/SDC-DISTR-STATUS-TOPIC-AUTO (as FXvkC1iAdWOqcC7O) ...
DEBUG 2019-05-09 12:41:17.053 +0000 RequestAddCookies - CookieSpec selected: default
DEBUG 2019-05-09 12:41:17.053 +0000 RequestAuthCache - Auth cache not set in the context
DEBUG 2019-05-09 12:41:17.053 +0000 PoolingHttpClientConnectionManager - Connection request: [route: {}->http://message-router.onap:3904][total kept alive: 0; route allocated: 0 of 32; total allocated: 0 of 32]
DEBUG 2019-05-09 12:41:17.053 +0000 PoolingHttpClientConnectionManager - Connection leased: [id: 222][route: {}->http://message-router.onap:3904][total kept alive: 0; route allocated: 1 of 32; total allocated: 1 of 32]
DEBUG 2019-05-09 12:41:17.054 +0000 MainClientExec - Opening connection {}->http://message-router.onap:3904
DEBUG 2019-05-09 12:41:17.054 +0000 DefaultHttpClientConnectionOperator - Connecting to message-router.onap/10.43.98.42:3904
DEBUG 2019-05-09 12:41:17.056 +0000 DefaultHttpClientConnectionOperator - Connection established 10.42.133.233:46310<->10.43.98.42:3904
DEBUG 2019-05-09 12:41:17.056 +0000 MainClientExec - Executing request POST /events/SDC-DISTR-STATUS-TOPIC-AUTO HTTP/1.1
DEBUG 2019-05-09 12:41:17.056 +0000 MainClientExec - Target auth state: UNCHALLENGED
DEBUG 2019-05-09 12:41:17.056 +0000 MainClientExec - Proxy auth state: UNCHALLENGED
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> POST /events/SDC-DISTR-STATUS-TOPIC-AUTO HTTP/1.1
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> Content-Type: application/cambria
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> X-CambriaDate: Thu, 09 May 2019 12:41:17 GMT
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> X-CambriaAuth: FXvkC1iAdWOqcC7O:qOHRO79YpXPNpELeRjyj9v+3mkk=
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> Accept: application/json
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> Content-Length: 303
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> Host: message-router.onap:3904
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> Connection: Keep-Alive
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> User-Agent: Apache-HttpClient/4.5.4 (Java/1.8.0_201)
DEBUG 2019-05-09 12:41:17.056 +0000 headers - http-outgoing-222 >> Accept-Encoding: gzip,deflate
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "POST /events/SDC-DISTR-STATUS-TOPIC-AUTO HTTP/1.1[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "Content-Type: application/cambria[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "X-CambriaDate: Thu, 09 May 2019 12:41:17 GMT[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "X-CambriaAuth: FXvkC1iAdWOqcC7O:qOHRO79YpXPNpELeRjyj9v+3mkk=[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "Accept: application/json[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "Content-Length: 303[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "Host: message-router.onap:3904[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "Connection: Keep-Alive[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "User-Agent: Apache-HttpClient/4.5.4 (Java/1.8.0_201)[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "Accept-Encoding: gzip,deflate[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "[\r][\n]"
DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "14.281.MyPartitionKey

{[\n]" DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> " "distributionID": "9d349b0a-e042-427f-a107-79d74d8e14da",[\n]" DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> " "consumerID": "sdc-COpenSource-Env11-sdnc-dockero",[\n]" DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> " "timestamp": 1557405676950,[\n]" DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> " "artifactURL": "/sdc/v1/catalog/services/ServicePnfTest/1.0/artifacts/service-ServicePnfTest-csar.csar",[\n]" DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> " "status": "DEPLOY_ERROR"[\n]" DEBUG 2019-05-09 12:41:17.056 +0000 wire - http-outgoing-222 >> "}

[\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "HTTP/1.1 200 OK[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "Date: Thu, 09 May 2019 12:41:17 GMT[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "Content-Type: application/json[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "Accept: application/json[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "Accept-Encoding: gzip,deflate[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "breadcrumbId: ID-onap-dmaap-message-router-0-1557318348479-0-266066[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "User-Agent: Apache-HttpClient/4.5.4 (Java/1.8.0_201)[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "X-CambriaAuth: FXvkC1iAdWOqcC7O:qOHRO79YpXPNpELeRjyj9v+3mkk=[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "X-CambriaDate: Thu, 09 May 2019 12:41:17 GMT[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "X-CSI-Internal-WriteableRequest: true[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "Content-Length: 41[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "Server: Jetty(9.4.z-SNAPSHOT)[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "[\r][\n]"
DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "

{[\n]" DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << " "serverTimeMs": 0,[\n]" DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << " "count": 1[\n]" DEBUG 2019-05-09 12:41:17.065 +0000 wire - http-outgoing-222 << "}

"
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << HTTP/1.1 200 OK
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << Date: Thu, 09 May 2019 12:41:17 GMT
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << Content-Type: application/json
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << Accept: application/json
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << Accept-Encoding: gzip,deflate
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << breadcrumbId: ID-onap-dmaap-message-router-0-1557318348479-0-266066
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << User-Agent: Apache-HttpClient/4.5.4 (Java/1.8.0_201)
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << X-CambriaAuth: FXvkC1iAdWOqcC7O:qOHRO79YpXPNpELeRjyj9v+3mkk=
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << X-CambriaDate: Thu, 09 May 2019 12:41:17 GMT
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << X-CSI-Internal-WriteableRequest: true
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << Content-Length: 41
DEBUG 2019-05-09 12:41:17.065 +0000 headers - http-outgoing-222 << Server: Jetty(9.4.z-SNAPSHOT)
DEBUG 2019-05-09 12:41:17.065 +0000 MainClientExec - Connection can be kept alive indefinitely
INFO 2019-05-09 12:41:17.066 +0000 HttpClient - --> HTTP/1.1 200 OK
DEBUG 2019-05-09 12:41:17.066 +0000 HttpClient - Consuming the entity.
DEBUG 2019-05-09 12:41:17.066 +0000 PoolingHttpClientConnectionManager - Connection [id: 222][route: {}->http://message-router.onap:3904] can be kept alive indefinitely
DEBUG 2019-05-09 12:41:17.066 +0000 DefaultManagedHttpClientConnection - http-outgoing-222: set socket timeout to 0
DEBUG 2019-05-09 12:41:17.066 +0000 PoolingHttpClientConnectionManager - Connection released: [id: 222][route: {}->http://message-router.onap:3904][total kept alive: 1; route allocated: 1 of 32; total allocated: 1 of 32]
INFO 2019-05-09 12:41:17.066 +0000 CambriaSimplerBatchPublisher - cambria reply ok (13 ms):{"serverTimeMs":0,"count":1}
ERROR 2019-05-09 12:41:17.953 +0000 NotificationConsumer - Error exception occured when fetching with Cambria Client:null
DEBUG 2019-05-09 12:41:17.953 +0000 NotificationConsumer - Error exception occured when fetching with Cambria Client:null
java.lang.NullPointerException
at org.onap.ccsdk.sli.northbound.uebclient.SdncUebCallback.activateCallback(SdncUebCallback.java:314)
at org.onap.sdc.impl.NotificationConsumer.run(NotificationConsumer.java:71)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:308)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:180)
at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:294)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
INFO 2019-05-09 12:41:41.502 +0000 CambriaConsumerImpl - UEB GET /events/SDC-DISTR-NOTIF-TOPIC-AUTO/sdc-OpenSource-Env1-sdnc-dockero/sdc-COpenSource-Env11-sdnc-dockero
WARN 2019-05-09 12:41:41.502 +0000 HttpClient - GET http://message-router.onap:3904/events/SDC-DISTR-NOTIF-TOPIC-AUTO/sdc-OpenSource-Env1-sdnc-dockero/sdc-COpenSource-Env11-sdnc-dockero will send credentials over a clear channel.
INFO 2019-05-09 12:41:41.503 +0000 HttpClient - GET http://message-router.onap:3904/events/SDC-DISTR-NOTIF-TOPIC-AUTO/sdc-OpenSource-Env1-sdnc-dockero/sdc-COpenSource-Env11-sdnc-dockero (as FXvkC1iAdWOqcC7O) ...
DEBUG 2019-05-09 12:41:41.503 +0000 RequestAddCookies - CookieSpec selected: default
DEBUG 2019-05-09 12:41:41.503 +0000 RequestAuthCache - Auth cache not set in the context
DEBUG 2019-05-09 12:41:41.503 +0000 PoolingHttpClientConnectionManager - Connection request: [route: {}->http://message-router.onap:3904][total kept alive: 1; route allocated: 1 of 32; total allocated: 1 of 32]
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 << "[read] I/O error: Read timed out"
DEBUG 2019-05-09 12:41:41.504 +0000 PoolingHttpClientConnectionManager - Connection leased: [id: 2][route: {}->http://message-router.onap:3904][total kept alive: 0; route allocated: 1 of 32; total allocated: 1 of 32]
DEBUG 2019-05-09 12:41:41.504 +0000 DefaultManagedHttpClientConnection - http-outgoing-2: set socket timeout to 15000
DEBUG 2019-05-09 12:41:41.504 +0000 MainClientExec - Executing request GET /events/SDC-DISTR-NOTIF-TOPIC-AUTO/sdc-OpenSource-Env1-sdnc-dockero/sdc-COpenSource-Env11-sdnc-dockero HTTP/1.1
DEBUG 2019-05-09 12:41:41.504 +0000 MainClientExec - Target auth state: UNCHALLENGED
DEBUG 2019-05-09 12:41:41.504 +0000 MainClientExec - Proxy auth state: UNCHALLENGED
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> GET /events/SDC-DISTR-NOTIF-TOPIC-AUTO/sdc-OpenSource-Env1-sdnc-dockero/sdc-COpenSource-Env11-sdnc-dockero HTTP/1.1
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> X-CambriaDate: Thu, 09 May 2019 12:41:41 GMT
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> X-CambriaAuth: FXvkC1iAdWOqcC7O:9hd8QVyTU067YxTlFhLL+bKtF2Q=
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> Accept: application/json
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> Host: message-router.onap:3904
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> Connection: Keep-Alive
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> User-Agent: Apache-HttpClient/4.5.4 (Java/1.8.0_201)
DEBUG 2019-05-09 12:41:41.504 +0000 headers - http-outgoing-2 >> Accept-Encoding: gzip,deflate
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "GET /events/SDC-DISTR-NOTIF-TOPIC-AUTO/sdc-OpenSource-Env1-sdnc-dockero/sdc-COpenSource-Env11-sdnc-dockero HTTP/1.1[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "X-CambriaDate: Thu, 09 May 2019 12:41:41 GMT[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "X-CambriaAuth: FXvkC1iAdWOqcC7O:9hd8QVyTU067YxTlFhLL+bKtF2Q=[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "Accept: application/json[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "Host: message-router.onap:3904[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "Connection: Keep-Alive[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "User-Agent: Apache-HttpClient/4.5.4 (Java/1.8.0_201)[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "Accept-Encoding: gzip,deflate[\r][\n]"
DEBUG 2019-05-09 12:41:41.504 +0000 wire - http-outgoing-2 >> "[\r][\n]"
^C
bash-4.4#

 

aseaudi 8:43 PM

after repeating the tests, the SDNC deploy error happens only with PNF, while VNF is deployed OK in SDNC