Re: #appc Unable to execute stop lcm operation from APPC #appc


Steve Siani <alphonse.steve.siani.djissitchi@...>
 

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) [?:?]
 

Join onap-discuss@lists.onap.org to automatically receive all group messages.