Hello all,
I also have pretty much the same issue. I am trying to perform LCM request
using APPC.
Bellow are relevant infos:
- Input request:
`
{
"input": {
"common-header": {
"timestamp": "2019-05-15T14:04:38.515Z",
"api-ver": "2.00",
"originator-id": "appc",
"request-id": "873aacdf-1d85-4e07-8deb-7815776bbd35.",
"flags": {
"mode": "NORMAL",
"force": "FALSE"
}
},
"action": "Stop",
"action-identifiers": {
"vnf-id": "VNF_demo_steve",
"vserver-id": "c82153dc-8d84-4e69-9673-5e0a60030779"
},
"payload": "{\"vm-id\":
\"http://10.195.194.213:8774/v2.1/7320ec4a5b9d4589ba7c4412ccfd290f/servers/c82153dc-8d84-4e69-9673-5e0a60030779\";,
\"identity-urld\": \"http://10.195.194.213:5000/v3\"}";
}
}
`
- Here are the logs:
2019-05-15T14:04:40,214 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 | AAI
Deprecation - the format of request key is no longer supported. Please rewrite
this key : vnf-id = 'VNF_demo_steve'
2019-05-15T14:04:40,226 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 | Input
- vnf-id : VNF_demo_steve
2019-05-15T14:04:40,226 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 | A&AI
transaction :
2019-05-15T14:04:40,226 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 |
Request Time : 2019-05-15T14:04:40.226Z, Method : GET
2019-05-15T14:04:40,226 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 |
Request URL :
http://aai.ssteve:8443/aai/v14/network/generic-vnfs/generic-vnf/VNF_demo_steve
2019-05-15T14:04:40,227 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 |
Missing requestID. Assigned 489560cb-5f3d-4f9a-af7d-eca558ef360f
2019-05-15T14:04:41,125 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 |
HttpURLConnection result: 200 : OK
2019-05-15T14:04:41,126 | INFO | qtp1130223215-78 | metric
| 221 - org.onap.ccsdk.sli.core.sli-common - 0.3.3 |
2019-05-15T14:04:41,135 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 |
Response code : 200, OK
2019-05-15T14:04:41,135 | INFO | qtp1130223215-78 | AAIService
| 213 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.3.3 |
Response data :
{"vnf-id":"VNF_demo_steve","vnf-name":"VNF_demo_steve","vnf-type":"vm","service-id":"vnf_demo_app","orchestration-status":"running","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1557780683583","model-invariant-id":"VNF_demo_app_steve","model-version-id":"v1.4.0","relationship-list":{"relationship":[{"related-to":"vserver","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v14/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779","relationship-data":[{"relationship-key":"cloud-region.cloud-owner","relationship-value":"CloudOwner"},{"relationship-key":"cloud-region.cloud-region-id","relationship-value":"RegionOne"},{"relationship-key":"tenant.tenant-id","relationship-value":"7320ec4a5b9d4589ba7c4412ccfd290f"},{"relationship-key":"vserver.vserver-id","relationship-value":"c82153dc-8d84-4e69-9673-5e0a60030779"}],"related-to-property":[{"property-key":"vserver.vserver-name","property-value":"viptela_scripts"}]}]}}
2019-05-15T14:04:41,151 | INFO | qtp1130223215-78 | metrics
| 458 - appc-common-bundle - 1.4.4 | APPC0128I Operation "DEFAULT" for
VNF type "null" from Source "appc" with RequestID
"489560cb-5f3d-4f9a-af7d-eca558ef360f" on "A&AI" with action "query" ended in
937 ms with result "COMPLETE"
2019-05-15T14:04:41,152 | INFO | qtp1130223215-78 | RequestValidatorImpl
| 458 - appc-common-bundle - 1.4.4 | AAIResponse: SUCCESS
2019-05-15T14:04:41,209 | INFO | qtp1130223215-78 | WorkFlowManagerImpl
| 458 - appc-common-bundle - 1.4.4 | DG resolver configuration data has
expired - initiating refresh
2019-05-15T14:04:41,256 | INFO | qtp1130223215-78 | WorkFlowManagerImpl
| 458 - appc-common-bundle - 1.4.4 | DG resolver configuration data has
been refreshed successfully
2019-05-15T14:04:41,425 | INFO | qtp1130223215-78 | CommandTask
| 458 - appc-common-bundle - 1.4.4 | AAIService from bundlecontext
2019-05-15T14:04:41,426 | INFO | appc-dispatcher-8 | CommandTask
| 458 - appc-common-bundle - 1.4.4 | Replaced invalid requestID of
123456-1000. New value is 873aacdf-1d85-4e07-8deb-7815776bbd35.
2019-05-15T14:04:41,427 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | Fetching service
logic from data store
2019-05-15T14:04:41,509 | INFO | qtp1130223215-78 | audit
| 458 - appc-common-bundle - 1.4.4 | APPC0090A Operation "Stop" for VNF
type "VNF_demo_steve" from Source "appc" with RequestID
"e4c2b2cd-3220-4dea-b107-23c68c99ea51" was started at "2019-05-15T14:04:40Z"
and ended at "2019-05-15T14:04:41Z" with status code "100"
2019-05-15T14:04:41,510 | INFO | qtp1130223215-78 | metrics
| 458 - appc-common-bundle - 1.4.4 | APPC0128I Operation "DEFAULT" for
VNF type "VNF_demo_steve" from Source "appc" with RequestID
"365fc60a-5a0c-4235-9a23-33dbb849f1c6" on "APPC" with action "Stop" ended in
1475 ms with result "COMPLETE"
2019-05-15T14:04:41,510 | INFO | qtp1130223215-78 | AppcProviderLcm
| 458 - appc-common-bundle - 1.4.4 | Execute of
'ActionIdentifiers{getVnfId=VNF_demo_steve,
getVserverId=c82153dc-8d84-4e69-9673-5e0a60030779, augmentations={}}' finished
with status 100. Reason: ACCEPTED - request accepted
2019-05-15T14:04:41,521 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | About to execute
graph SvcLogicGraph [module=APPC, rpc=Stop_VNF, mode=sync, version=2.0.1,
md5sum=da65b3e486c5575eb48e221aa266b259]
2019-05-15T14:04:41,521 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | About to execute
node # 1 (execute)
2019-05-15T14:04:41,522 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | About to execute
node # 2 (execute)
2019-05-15T14:04:41,522 | ERROR | appc-dispatcher-8 | ExecuteNodeExecutor
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | Could not execute
plugin. SvcLogic status will be set to failure.
java.lang.NullPointerException: null
at org.onap.appc.dg.aai.impl.AAIPluginImpl.readResource(AAIPluginImpl.java:376)
~[?:?]
at org.onap.appc.dg.aai.impl.AAIPluginImpl.readVnf(AAIPluginImpl.java:314)
~[?:?]
at
org.onap.appc.dg.aai.impl.AAIPluginImpl.getVnfHierarchy(AAIPluginImpl.java:182)
~[?:?]
at Proxy3a570115_2dd3_4597_8646_cc44aead7eee.getVnfHierarchy(Unknown Source)
~[?:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
~[?:?]
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
~[?:?]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]
at
org.onap.ccsdk.sli.core.sli.provider.ExecuteNodeExecutor.execute(ExecuteNodeExecutor.java:96)
~[?:?]
at
org.onap.ccsdk.sli.core.sli.provider.SvcLogicServiceImpl.executeNode(SvcLogicServiceImpl.java:188)
~[?:?]
at
org.onap.ccsdk.sli.core.sli.provider.SvcLogicServiceImpl.execute(SvcLogicServiceImpl.java:158)
~[?:?]
at
org.onap.ccsdk.sli.core.sli.provider.SvcLogicServiceImpl.execute(SvcLogicServiceImpl.java:224)
~[?:?]
at
org.onap.ccsdk.sli.core.sli.provider.SvcLogicServiceImpl.execute(SvcLogicServiceImpl.java:202)
~[?:?]
at Proxyde9117c8_cd73_4537_80c1_aa5c45ed624a.execute(Unknown Source) ~[?:?]
at Proxyeff60fee_b1bd_42b9_85fc_f35c9b5c9af9.execute(Unknown Source) ~[?:?]
at
org.onap.appc.workflow.impl.WorkFlowManagerImpl.SVCLogicServiceExecute(WorkFlowManagerImpl.java:252)
~[?:?]
at
org.onap.appc.workflow.impl.WorkFlowManagerImpl.executeWorkflow(WorkFlowManagerImpl.java:155)
~[?:?]
at Proxy5b2a63eb_9bc1_4b37_a4f2_b6d16f7748c6.executeWorkflow(Unknown Source)
~[?:?]
at Proxyb1332071_2957_4fb5_8124_244cba3ef505.executeWorkflow(Unknown Source)
~[?:?]
at org.onap.appc.executor.impl.CommandTask.run(CommandTask.java:116) ~[?:?]
at
org.onap.appc.executionqueue.impl.QueueManager.lambda$enqueueTask$0(QueueManager.java:104)
~[?:?]
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) [?:?]
2019-05-15T14:04:41,536 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | About to execute
node # 3 (block)
2019-05-15T14:04:41,547 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | About to execute
node # 4 (execute)
2019-05-15T14:04:41,548 | INFO | appc-dispatcher-8 | EventSenderDmaapImpl
| 458 - appc-common-bundle - 1.4.4 | Posting Message [123456-1000 -
{"eventHeader":{"eventTime":"Wed May 15 14:04:41 UTC
2019","apiVer":"2.00","eventId":"123456-1000"},"eventStatus":{"code":501,"reason":"Exception
in getting VNF Hierachy"}}]
2019-05-15T14:04:41,552 | INFO | appc-dispatcher-8 | SvcLogicServiceImpl
| 222 - org.onap.ccsdk.sli.core.sli-provider - 0.3.3 | About to execute
node # 5 (return)
2019-05-15T14:04:41,667 | ERROR | appc-dispatcher-8 | HttpDmaapProducerImpl
| 458 - appc-common-bundle - 1.4.4 | Failed to send message to
http://message-router.ssteve:3904/events/APPC-LCM-WRITE. Reason:
message-router.ssteve: Name or service not known
java.net.UnknownHostException: message-router.ssteve: Name or service not known
at java.net.Inet4AddressImpl.lookupAllHostAddr(Native Method) ~[?:?]
at java.net.InetAddress$2.lookupAllHostAddr(InetAddress.java:929) ~[?:?]
at java.net.InetAddress.getAddressesFromNameService(InetAddress.java:1324)
~[?:?]
at java.net.InetAddress.getAllByName0(InetAddress.java:1277) ~[?:?]
at java.net.InetAddress.getAllByName(InetAddress.java:1193) ~[?:?]
at java.net.InetAddress.getAllByName(InetAddress.java:1127) ~[?:?]
at
org.apache.http.impl.conn.SystemDefaultDnsResolver.resolve(SystemDefaultDnsResolver.java:45)
~[?:?]
at
org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:112)
~[?:?]
at
org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:359)
~[?:?]
at
org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:381)
~[?:?]
at
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:237)
~[?:?]
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
~[?:?]
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89) ~[?:?]
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
~[?:?]
at
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
~[?:?]
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
~[?:?]
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
~[?:?]
at
org.onap.appc.adapter.messaging.dmaap.http.HttpDmaapProducerImpl.sendRequest(HttpDmaapProducerImpl.java:99)
~[?:?]
at
org.onap.appc.adapter.messaging.dmaap.http.HttpDmaapProducerImpl.lambda$post$0(HttpDmaapProducerImpl.java:80)
~[?:?]
at java.util.stream.ReferencePipeline$2$1.accept(ReferencePipeline.java:174)
~[?:?]
at java.util.HashMap$KeySpliterator.forEachRemaining(HashMap.java:1553) ~[?:?]
at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:481) ~[?:?]
at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:471)
~[?:?]
at java.util.stream.ReduceOps$ReduceOp.evaluateSequential(ReduceOps.java:708)
~[?:?]
at java.util.stream.AbstractPipeline.evaluate(AbstractPipeline.java:234) ~[?:?]
at java.util.stream.LongPipeline.reduce(LongPipeline.java:438) ~[?:?]
at java.util.stream.LongPipeline.sum(LongPipeline.java:396) ~[?:?]
at java.util.stream.ReferencePipeline.count(ReferencePipeline.java:526) ~[?:?]
at
org.onap.appc.adapter.messaging.dmaap.http.HttpDmaapProducerImpl.post(HttpDmaapProducerImpl.java:81)
~[?:?]
at
org.onap.appc.messageadapter.impl.MessageAdapterImpl.post(MessageAdapterImpl.java:127)
~[?:?]
at
org.onap.appc.requesthandler.impl.AbstractRequestHandlerImpl.postMessageToDMaaP(AbstractRequestHandlerImpl.java:399)
~[?:?]
at
org.onap.appc.requesthandler.impl.AbstractRequestHandlerImpl.onRequestExecutionEnd(AbstractRequestHandlerImpl.java:361)
~[?:?]
at Proxy33348f38_9fe1_4fb1_a952_07eac39b747d.onRequestExecutionEnd(Unknown
Source) ~[?:?]
at Proxyfecac20c_bb05_4747_9ad1_93283e6c881e.onRequestExecutionEnd(Unknown
Source) ~[?:?]
at org.onap.appc.executor.impl.CommandTask.run(CommandTask.java:130) ~[?:?]
at
org.onap.appc.executionqueue.impl.QueueManager.lambda$enqueueTask$0(QueueManager.java:104)
~[?:?]
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) [?:?]
2019-05-15T14:04:41,726 | ERROR | appc-dispatcher-8 |
AbstractRequestHandlerImpl | 458 - appc-common-bundle - 1.4.4 | DMaaP
posting status: false
2019-05-15T14:04:41,774 | INFO | appc-dispatcher-8 | audit
| 458 - appc-common-bundle - 1.4.4 | APPC0090A Operation "Stop" for VNF
type "VNF_demo_steve" from Source "appc" with RequestID
"873aacdf-1d85-4e07-8deb-7815776bbd35" was started at "2019-05-15T14:04:40Z"
and ended at "2019-05-15T14:04:41Z" with status code "401"
2019-05-15T14:04:41,967 | INFO | sshd-SshServer[77ff063]-nio2-thread-1 |
ServerUserAuthService | 153 - org.apache.sshd.core - 1.6.0 | Session
karaf@/127.0.0.1:33320 authenticated
2019-05-15T14:04:43,324 | INFO | Appc-Listener-1 | EventHandlerImpl
| 458 - appc-common-bundle - 1.4.4 | Read 0 messages from APPC-CL as
appcDemoEventListener/4.
2019-05-15T14:04:43,324 | INFO | Appc-Listener-2 | EventHandlerImpl
| 458 - appc-common-bundle - 1.4.4 | Read 0 messages from APPC-LCM-READ
as APPC-EVENT-LISTENER-TEST/780.
2019-05-15T14:04:43,324 | INFO | Appc-Listener-2 | EventHandlerImpl
| 458 - appc-common-bundle - 1.4.4 | Getting up to 10 incoming events
2019-05-15T14:04:43,324 | INFO | Appc-Listener-1 | EventHandlerImpl
| 458 - appc-common-bundle - 1.4.4 | Getting up to 1000 incoming events
2019-05-15T14:04:43,324 | INFO | Appc-Listener-1 | HttpDmaapConsumerImpl
| 458 - appc-common-bundle - 1.4.4 | GET
http://message-router.ssteve:3904/events/APPC-CL/appcDemoEventListener/4?timeout=60000&limit=1000
2019-05-15T14:04:43,324 | INFO | Appc-Listener-2 | HttpDmaapConsumerImpl
| 458 - appc-common-bundle - 1.4.4 | GET
http://message-router.ssteve:3904/events/APPC-LCM-READ/APPC-EVENT-LISTENER-TEST/780?timeout=60000&limit=10
2019-05-15T14:04:43,336 | ERROR | Appc-Listener-2 | HttpDmaapConsumerImpl
| 458 - appc-common-bundle - 1.4.4 | Got exception while querying DMaaP.
Message: message-router.ssteve
java.net.UnknownHostException: message-router.ssteve
at java.net.InetAddress.getAllByName0(InetAddress.java:1281) [?:?]
at java.net.InetAddress.getAllByName(InetAddress.java:1193) [?:?]
at java.net.InetAddress.getAllByName(InetAddress.java:1127) [?:?]
at
org.apache.http.impl.conn.SystemDefaultDnsResolver.resolve(SystemDefaultDnsResolver.java:45)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.conn.DefaultHttpClientConnectionOperator.connect(DefaultHttpClientConnectionOperator.java:112)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.conn.PoolingHttpClientConnectionManager.connect(PoolingHttpClientConnectionManager.java:359)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.execchain.MainClientExec.establishRoute(MainClientExec.java:381)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:237)
[461:appc-dmaap-adapter-bundle:1.4.4]
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[461:appc-dmaap-adapter-bundle:1.4.4]
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[461:appc-dmaap-adapter-bundle:1.4.4]
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:108)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.onap.appc.adapter.messaging.dmaap.http.HttpDmaapConsumerImpl.fetch(HttpDmaapConsumerImpl.java:96)
[461:appc-dmaap-adapter-bundle:1.4.4]
at
org.onap.appc.listener.impl.EventHandlerImpl.getIncomingEvents(EventHandlerImpl.java:161)
[521:org.onap.appc.listener:1.4.4]
at
org.onap.appc.listener.impl.EventHandlerImpl.getIncomingEvents(EventHandlerImpl.java:177)
[521:org.onap.appc.listener:1.4.4]
at org.onap.appc.listener.LCM.impl.ListenerImpl.run(ListenerImpl.java:76)
[521:org.onap.appc.listener:1.4.4]
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) [?:?]
-=-=-=-=-=-=-=-=-=-=-=-
Links: You receive all messages sent to this group.
View/Reply Online (#17038): https://lists.onap.org/g/onap-discuss/message/17038
Mute This Topic: https://lists.onap.org/mt/31222663/21656
Mute #appc: https://lists.onap.org/mk?hashtag=appc&subid=2740164
Group Owner: [email protected]
Unsubscribe: https://lists.onap.org/g/onap-discuss/unsub
[[email protected]]
-=-=-=-=-=-=-=-=-=-=-=-
