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


Brian Freeman
 

Looks like your kubernetes dns isnt working

 

 

message-router.ssteve isnt resolving – assuming you used ssteve as your namespace or something ?

 

Surprised the first aai query worked but the second did not.

 

 

Brian

 

 

From: onap-discuss@... <onap-discuss@...> On Behalf Of Steve Siani
Sent: Wednesday, May 15, 2019 10:12 AM
To: PLATANIA, MARCO <platania@...>; onap-discuss@...
Subject: Re: [onap-discuss] #appc Unable to execute stop lcm operation from APPC

 

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"

        },

    }


`


- 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.