2019-05-07 11:40:49,444 | INFO | qtp1570595379-69 | RequestValidatorImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | AAIService from bundlecontext 2019-05-07 11:40:49,486 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | AAI Deprecation - the format of request key is no longer supported. Please rewrite this key : vnf-id = 'Stability-Test-VM3' 2019-05-07 11:40:49,488 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - vnf-id : Stability-Test-VM3 2019-05-07 11:40:49,488 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | A&AI transaction : 2019-05-07 11:40:49,489 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request Time : 2019-05-07T11:40:49.488Z, Method : GET 2019-05-07 11:40:49,489 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request URL : https://aai.onap:8443/aai/v14/network/generic-vnfs/generic-vnf/Stability-Test-VM3 2019-05-07 11:40:49,491 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Missing requestID. Assigned 0603a81b-50ef-472f-b6ef-9d6910e31896 2019-05-07 11:40:49,879 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | HttpURLConnection result: 200 : OK 2019-05-07 11:40:49,880 | INFO | qtp1570595379-69 | metric | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | 2019-05-07 11:40:49,883 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response code : 200, OK 2019-05-07 11:40:49,884 | INFO | qtp1570595379-69 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response data : {"vnf-id":"Stability-Test-VM3","vnf-name":"Stability-Test-VM3","vnf-type":"vTESTVM","service-id":"vTESTVMAPPC","orchestration-status":"running","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1551782519164","model-invariant-id":"vTESTVM","model-version-id":"vTESTVM","relationship-list":{"relationship":[{"related-to":"vserver","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v14/cloud-infrastructure/cloud-regions/cloud-region/pod25/RegionOne/tenants/tenant/cf142fb96ce24770946c0f7117c0aeb3/vservers/vserver/3b3d77e0-a79d-4c10-bfac-1b3914af1a14","relationship-data":[{"relationship-key":"cloud-region.cloud-owner","relationship-value":"pod25"},{"relationship-key":"cloud-region.cloud-region-id","relationship-value":"RegionOne"},{"relationship-key":"tenant.tenant-id","relationship-value":"cf142fb96ce24770946c0f7117c0aeb3"},{"relationship-key":"vserver.vserver-id","relationship-value":"3b3d77e0-a79d-4c10-bfac-1b3914af1a14"}],"related-to-property":[{"property-key":"vserver.vserver-name","property-value":"Stability-Test-VM3"}]}]}} 2019-05-07 11:40:49,891 | INFO | qtp1570595379-69 | metrics | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | APPC0128I Operation "DEFAULT" for VNF type "null" from Source "appc" with RequestID "0603a81b-50ef-472f-b6ef-9d6910e31896" on "A&AI" with action "query" ended in 404 ms with result "COMPLETE" 2019-05-07 11:40:49,891 | INFO | qtp1570595379-69 | RequestValidatorImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | AAIResponse: SUCCESS 2019-05-07 11:40:49,937 | INFO | qtp1570595379-69 | CommandTask | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | AAIService from bundlecontext 2019-05-07 11:40:49,939 | INFO | ppc-dispatcher-1 | CommandTask | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Replaced invalid requestID of 3. New value is e811dfbe-8970-4ab8-a03c-215ba78e5202. 2019-05-07 11:40:49,940 | INFO | ppc-dispatcher-1 | SvcLogicServiceImpl | 206 - org.onap.ccsdk.sli.core.sli-provider - 0.4.1 | Fetching service logic from data store 2019-05-07 11:40:49,942 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute graph SvcLogicGraph [module=APPC, rpc=DGOrchestrator, mode=sync, version=4.0.0, md5sum=0dee53d30a45ac3a692d17f030f87587] 2019-05-07 11:40:49,942 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 1 (block) 2019-05-07 11:40:49,942 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 2 (call) 2019-05-07 11:40:49,944 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute graph SvcLogicGraph [module=APPC, rpc=setInputParams, mode=sync, version=4.0.0, md5sum=3c064381db25f6cbfa8bf197cbbb4891] 2019-05-07 11:40:49,944 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 1 (block) 2019-05-07 11:40:49,945 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 2 (set) 2019-05-07 11:40:49,946 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 3 (set) 2019-05-07 11:40:49,947 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 4 (execute) 2019-05-07 11:40:49,955 | INFO | qtp1570595379-69 | TransactionRecorderImpl | 464 - transaction-recorder - 1.5.0.SNAPSHOT | No of Rows in Transactions Table with TRANSACTION_ID: null~ddd4c76b-154b-44fb-bedc-9b8c4694cf18 and REQUEST_ID 3 is: 0 2019-05-07 11:40:49,970 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (failure)} 2019-05-07 11:40:49,970 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (failure)} 2019-05-07 11:40:49,970 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 6 (execute) 2019-05-07 11:40:49,971 | INFO | ppc-dispatcher-1 | JSONTool | 483 - org.onap.sdnc.config.generator - 1.5.0.SNAPSHOT | Added property: vm-id: http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:49,971 | INFO | ppc-dispatcher-1 | JSONTool | 483 - org.onap.sdnc.config.generator - 1.5.0.SNAPSHOT | Added property: skip-hypervisor-check: true 2019-05-07 11:40:49,971 | INFO | ppc-dispatcher-1 | JSONTool | 483 - org.onap.sdnc.config.generator - 1.5.0.SNAPSHOT | Added property: identity-url: http://10.12.25.2:5000/v3 2019-05-07 11:40:49,972 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:49,972 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:49,972 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 11 (set) 2019-05-07 11:40:49,973 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 12 (switch) 2019-05-07 11:40:49,974 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 14 (set) 2019-05-07 11:40:49,974 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 15 (switch) 2019-05-07 11:40:49,974 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 18 (switch) 2019-05-07 11:40:49,974 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (3b3d77e0-a79d-4c10-bfac-1b3914af1a14) not found, keys are { ("") (Other)} 2019-05-07 11:40:49,975 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 19 (block) 2019-05-07 11:40:49,975 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 20 (set) 2019-05-07 11:40:49,975 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 30 (record) 2019-05-07 11:40:49,976 | WARN | ppc-dispatcher-1 | SvcLogicClassResolver | 206 - org.onap.ccsdk.sli.core.sli-provider - 0.4.1 | Could not find service reference object for class onap.ccsdk.sli.core.sli.recording.Slf4jRecorder 2019-05-07 11:40:49,976 | WARN | ppc-dispatcher-1 | RecordNodeExecutor | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | Could not find SvcLogicRecorder object for plugin onap.ccsdk.sli.core.sli.recording.Slf4jRecorder 2019-05-07 11:40:49,976 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 31 (get-resource) 2019-05-07 11:40:49,978 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 33 (set) 2019-05-07 11:40:49,978 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 34 (call) 2019-05-07 11:40:49,979 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute graph SvcLogicGraph [module=APPC, rpc=GetAaiInfo, mode=sync, version=4.0.0, md5sum=e5eb6b8c09b70a964680bd20e0e37d08] 2019-05-07 11:40:49,980 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 1 (block) 2019-05-07 11:40:49,980 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 2 (execute) 2019-05-07 11:40:49,981 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | Received getVnfInfo call with params : {responsePrefix=tmp.vnfInfo, vnfId=Stability-Test-VM3} 2019-05-07 11:40:49,981 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - vnf-id : Stability-Test-VM3 2019-05-07 11:40:49,982 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | A&AI transaction : 2019-05-07 11:40:49,982 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request Time : 2019-05-07T11:40:49.982Z, Method : GET 2019-05-07 11:40:49,982 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request URL : https://aai.onap:8443/aai/v14/network/generic-vnfs/generic-vnf/Stability-Test-VM3 2019-05-07 11:40:50,001 | INFO | qtp1570595379-69 | audit | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | APPC0090A Operation "Stop" for VNF type "Stability-Test-VM3" from Source "1" with RequestID "8617bbde-de1a-4057-9b78-8c9528a090d8" was started at "2019-05-07T11:40:49Z" and ended at "2019-05-07T11:40:50Z" with status code "100" 2019-05-07 11:40:50,002 | INFO | qtp1570595379-69 | metrics | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | APPC0128I Operation "DEFAULT" for VNF type "Stability-Test-VM3" from Source "appc" with RequestID "2c718d7d-ac53-489d-bc06-84a539adf501" on "APPC" with action "Stop" ended in 636 ms with result "COMPLETE" 2019-05-07 11:40:50,003 | INFO | qtp1570595379-69 | AppcProviderLcm | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Execute of 'ActionIdentifiers{getVnfId=Stability-Test-VM3, getVserverId=3b3d77e0-a79d-4c10-bfac-1b3914af1a14, augmentations={}}' finished with status 100. Reason: ACCEPTED - request accepted 2019-05-07 11:40:50,082 | INFO | Appc-Listener-1 | HttpDmaapConsumerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Got 0 messages from DMaaP 2019-05-07 11:40:50,082 | INFO | Appc-Listener-1 | EventHandlerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Read 0 messages from APPC-CL as appcDemoEventListener/992. 2019-05-07 11:40:50,083 | INFO | Appc-Listener-1 | EventHandlerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Getting up to 1000 incoming events 2019-05-07 11:40:50,083 | INFO | Appc-Listener-1 | HttpDmaapConsumerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | GET http://message-router.onap:3904/events/APPC-CL/appcDemoEventListener/992?timeout=60000&limit=1000 2019-05-07 11:40:50,195 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | HttpURLConnection result: 200 : OK 2019-05-07 11:40:50,195 | INFO | ppc-dispatcher-1 | metric | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | 2019-05-07 11:40:50,197 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response code : 200, OK 2019-05-07 11:40:50,198 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response data : {"vnf-id":"Stability-Test-VM3","vnf-name":"Stability-Test-VM3","vnf-type":"vTESTVM","service-id":"vTESTVMAPPC","orchestration-status":"running","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1551782519164","model-invariant-id":"vTESTVM","model-version-id":"vTESTVM","relationship-list":{"relationship":[{"related-to":"vserver","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v14/cloud-infrastructure/cloud-regions/cloud-region/pod25/RegionOne/tenants/tenant/cf142fb96ce24770946c0f7117c0aeb3/vservers/vserver/3b3d77e0-a79d-4c10-bfac-1b3914af1a14","relationship-data":[{"relationship-key":"cloud-region.cloud-owner","relationship-value":"pod25"},{"relationship-key":"cloud-region.cloud-region-id","relationship-value":"RegionOne"},{"relationship-key":"tenant.tenant-id","relationship-value":"cf142fb96ce24770946c0f7117c0aeb3"},{"relationship-key":"vserver.vserver-id","relationship-value":"3b3d77e0-a79d-4c10-bfac-1b3914af1a14"}],"related-to-property":[{"property-key":"vserver.vserver-name","property-value":"Stability-Test-VM3"}]}]}} 2019-05-07 11:40:50,213 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | AAIResponse: SUCCESS 2019-05-07 11:40:50,213 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | RELLEN 1 2019-05-07 11:40:50,213 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | RELATEDTO vserver 2019-05-07 11:40:50,214 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-owner 2019-05-07 11:40:50,214 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE pod25 2019-05-07 11:40:50,215 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-region-id 2019-05-07 11:40:50,215 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE RegionOne 2019-05-07 11:40:50,215 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY tenant.tenant-id 2019-05-07 11:40:50,216 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE cf142fb96ce24770946c0f7117c0aeb3 2019-05-07 11:40:50,216 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY vserver.vserver-id 2019-05-07 11:40:50,218 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:50,218 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | RELATEDTO vserver 2019-05-07 11:40:50,220 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-owner 2019-05-07 11:40:50,220 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE pod25 2019-05-07 11:40:50,220 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-region-id 2019-05-07 11:40:50,221 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE RegionOne 2019-05-07 11:40:50,221 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY tenant.tenant-id 2019-05-07 11:40:50,221 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE cf142fb96ce24770946c0f7117c0aeb3 2019-05-07 11:40:50,222 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | RELATEDTO vserver 2019-05-07 11:40:50,222 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-owner 2019-05-07 11:40:50,223 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE pod25 2019-05-07 11:40:50,223 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | RELATEDTO vserver 2019-05-07 11:40:50,223 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-owner 2019-05-07 11:40:50,224 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE pod25 2019-05-07 11:40:50,224 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC KEY cloud-region.cloud-region-id 2019-05-07 11:40:50,224 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | GENERIC VALUE RegionOne 2019-05-07 11:40:50,225 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | VSERVER KEYS 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 cf142fb96ce24770946c0f7117c0aeb3 pod25 RegionOne 2019-05-07 11:40:50,225 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | VMCOUNT FROM VNF INFO 1 2019-05-07 11:40:50,225 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | Recieved getIdentityUrl call with params : {cloudRegionId=RegionOne, responsePrefix=tmp.vnfInfo, cloudOwner=pod25} 2019-05-07 11:40:50,226 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - cloud-region-id : RegionOne 2019-05-07 11:40:50,227 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - cloud-owner : pod25 2019-05-07 11:40:50,227 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | A&AI transaction : 2019-05-07 11:40:50,227 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request Time : 2019-05-07T11:40:50.227Z, Method : GET 2019-05-07 11:40:50,276 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request URL : https://aai.onap:8443/aai/v14/cloud-infrastructure/cloud-regions/cloud-region/pod25/RegionOne 2019-05-07 11:40:50,519 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | HttpURLConnection result: 200 : OK 2019-05-07 11:40:50,520 | INFO | ppc-dispatcher-1 | metric | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | 2019-05-07 11:40:50,521 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response code : 200, OK 2019-05-07 11:40:50,522 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response data : {"cloud-owner":"pod25","cloud-region-id":"RegionOne","cloud-type":"openstack","owner-defined-type":"owner-defined-type","cloud-region-version":"titanium_cloud","identity-url":"http://10.12.25.2:5000/v3","cloud-zone":"cloud zone","complex-name":"complex name","sriov-automation":"true","resource-version":"1551782372731"} 2019-05-07 11:40:50,573 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | AAIResponse: SUCCESS 2019-05-07 11:40:50,575 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | IdentityUrl: http://10.12.25.2:5000/v3 2019-05-07 11:40:50,575 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | Prefix for getIdentityUrl: tmp.vnfInfo.cloud-region.identity-url 2019-05-07 11:40:50,575 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | getVnfInfo Successful 2019-05-07 11:40:50,576 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:50,576 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:50,576 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 7 (set) 2019-05-07 11:40:50,585 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 8 (switch) 2019-05-07 11:40:50,585 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 9 (execute) 2019-05-07 11:40:50,586 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | getVserverInfo()::Retrieving vm and vnfc information for vserver:{responsePrefix=tmp.vnfInfo, vserverId=3b3d77e0-a79d-4c10-bfac-1b3914af1a14} 2019-05-07 11:40:50,602 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | setVmParams()::setVmParamsVM level action:3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:50,603 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | setVmParams():::vserver details::0:3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:50,603 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | setVmParams()::tenantId=cf142fb96ce24770946c0f7117c0aeb3 cloudOwner=pod25 cloudRegiodId= RegionOne 2019-05-07 11:40:50,603 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | setVmParams()::setVmParamsVM level action:{cloudRegionId=RegionOne, tenantId=cf142fb96ce24770946c0f7117c0aeb3, cloudOwner=pod25, vserverId=3b3d77e0-a79d-4c10-bfac-1b3914af1a14, vserver-selflink=null} 2019-05-07 11:40:50,604 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | Received getVmInfo call with params : {cloudRegionId=RegionOne, responsePrefix=tmp.vnfInfo, tenantId=cf142fb96ce24770946c0f7117c0aeb3, cloudOwner=pod25, vserverId=3b3d77e0-a79d-4c10-bfac-1b3914af1a14, vserver-selflink=null} 2019-05-07 11:40:50,609 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - vserver-id : 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:50,609 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - tenant-id : cf142fb96ce24770946c0f7117c0aeb3 2019-05-07 11:40:50,611 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - cloud-region-id : RegionOne 2019-05-07 11:40:50,611 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Input - cloud-owner : pod25 2019-05-07 11:40:50,612 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | A&AI transaction : 2019-05-07 11:40:50,612 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request Time : 2019-05-07T11:40:50.612Z, Method : GET 2019-05-07 11:40:50,613 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Request URL : https://aai.onap:8443/aai/v14/cloud-infrastructure/cloud-regions/cloud-region/pod25/RegionOne/tenants/tenant/cf142fb96ce24770946c0f7117c0aeb3/vservers/vserver/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:50,972 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | HttpURLConnection result: 200 : OK 2019-05-07 11:40:50,975 | INFO | ppc-dispatcher-1 | metric | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | 2019-05-07 11:40:50,979 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response code : 200, OK 2019-05-07 11:40:50,980 | INFO | ppc-dispatcher-1 | AAIService | 463 - org.onap.ccsdk.sli.adaptors.aai-service-provider - 0.4.1 | Response data : {"vserver-id":"3b3d77e0-a79d-4c10-bfac-1b3914af1a14","vserver-name":"Stability-Test-VM3","vserver-name2":"Stability-Test-VM3","prov-status":"Running","vserver-selflink":"http://10.12.25.2:8774/v2.1/466979b815b5415ba14ada713e6e1846/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1552483915769","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v14/network/generic-vnfs/generic-vnf/Stability-Test-VM3","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"Stability-Test-VM3"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"Stability-Test-VM3"}]}]}} 2019-05-07 11:40:51,026 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | AAIResponse: SUCCESS 2019-05-07 11:40:51,029 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | RELLEN1 2019-05-07 11:40:51,031 | INFO | ppc-dispatcher-1 | AaiService | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | VSERVERNAME Stability-Test-VM3 HAS NUM VNFCS = 0 2019-05-07 11:40:51,032 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | getVnfcFunctionCodeForVserver()::vnfcName=null 2019-05-07 11:40:51,040 | INFO | ppc-dispatcher-1 | AAIResourceNode | 496 - org.onap.appc.aai.client - 1.5.0.SNAPSHOT | getVserverInfo()::vnfc Name is blank, not setting vnfc info !!!! 2019-05-07 11:40:51,041 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:51,044 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:51,045 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 19 (switch) 2019-05-07 11:40:51,046 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 32 (block) 2019-05-07 11:40:51,049 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 33 (return) 2019-05-07 11:40:51,049 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:51,050 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (not-found) (failure)} 2019-05-07 11:40:51,050 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 39 (switch) 2019-05-07 11:40:51,050 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 42 (block) 2019-05-07 11:40:51,050 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 43 (return) 2019-05-07 11:40:51,051 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 44 (get-resource) 2019-05-07 11:40:51,053 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (failure)} 2019-05-07 11:40:51,053 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (failure)} 2019-05-07 11:40:51,053 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 47 (switch) 2019-05-07 11:40:51,054 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (N) not found, keys are { (Y) (Other)} 2019-05-07 11:40:51,054 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 58 (block) 2019-05-07 11:40:51,054 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 59 (execute) 2019-05-07 11:40:51,082 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (failure)} 2019-05-07 11:40:51,086 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (failure)} 2019-05-07 11:40:51,087 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 5 (block) 2019-05-07 11:40:51,087 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 6 (execute) 2019-05-07 11:40:51,087 | INFO | ppc-dispatcher-1 | ConfigResourceNode | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | Received getCapability call with params : {caplevel=vm, checkCapability=Stop, vServerId=3b3d77e0-a79d-4c10-bfac-1b3914af1a14, vnf-type=vTESTVM} 2019-05-07 11:40:51,088 | INFO | ppc-dispatcher-1 | DGGeneralDBService | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | getCapability Query String : select max(internal_version) as maxInternalVersion, artifact_name as artifactName from ASDC_ARTIFACTS where artifact_name in (select artifact_name from ASDC_REFERENCE where vnf_type= $vnf-type and file_category = 'capability' ) 2019-05-07 11:40:51,175 | INFO | ppc-dispatcher-1 | ConfigResourceNode | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | getCapability::returned from DB::+cap 2019-05-07 11:40:51,176 | INFO | ppc-dispatcher-1 | ConfigResourceNode | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | From DB = {"capabilities":{"vnfc":[],"vm":[],"vf-module":[],"vnf":["HealthCheck","AllAction","ConfigModify"]}} 2019-05-07 11:40:51,177 | INFO | ppc-dispatcher-1 | ConfigResourceNode | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | capabilities = {"vnfc":[],"vm":[],"vf-module":[],"vnf":["HealthCheck","AllAction","ConfigModify"]} 2019-05-07 11:40:51,177 | INFO | ppc-dispatcher-1 | ConfigResourceNode | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | subCapabilities = vm : [] 2019-05-07 11:40:51,178 | INFO | ppc-dispatcher-1 | ConfigResourceNode | 484 - appc-config-data-services - 1.5.0.SNAPSHOT | getCapability Successful 2019-05-07 11:40:51,178 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 10 (block) 2019-05-07 11:40:51,178 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 11 (switch) 2019-05-07 11:40:51,179 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (None) not found, keys are { (Not-Supported) (Supported) (Other)} 2019-05-07 11:40:51,179 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 12 (block) 2019-05-07 11:40:51,179 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 13 (switch) 2019-05-07 11:40:51,179 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 14 (block) 2019-05-07 11:40:51,180 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 15 (set) 2019-05-07 11:40:51,180 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 16 (execute) 2019-05-07 11:40:51,353 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vnf-id and Value = Stability-Test-VM3 2019-05-07 11:40:51,354 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.message and Value = Stability-Test-VM3 : Stop: {"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"} 2019-05-07 11:40:51,354 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= VMNAME0 and Value = 2019-05-07 11:40:51,355 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.action-identifiers.service-instance-id and Value = 2019-05-07 11:40:51,355 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.cloud-region.identity-url and Value = http://10.12.25.2:5000/v3 2019-05-07 11:40:51,355 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vnf-type and Value = 2019-05-07 11:40:51,355 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.status and Value = success 2019-05-07 11:40:51,356 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= controller-template-id and Value = 2019-05-07 11:40:51,356 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.action-identifiers.vnf-id and Value = Stability-Test-VM3 2019-05-07 11:40:51,356 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vnf.orchestration-status and Value = running 2019-05-07 11:40:51,356 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= payload and Value = {"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"} 2019-05-07 11:40:51,357 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= APPC.action-level.ACTION-LEVEL and Value = vm 2019-05-07 11:40:51,357 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vnf.vnf-name and Value = Stability-Test-VM3 2019-05-07 11:40:51,357 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= originator-id and Value = 1 2019-05-07 11:40:51,358 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.action-identifiers.vserver-id and Value = 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:51,358 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= action-identifier and Value = vserver-id 2019-05-07 11:40:51,358 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.common-header.request-id and Value = 3 2019-05-07 11:40:51,358 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= request-id and Value = 3 2019-05-07 11:40:51,359 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.payload and Value = {"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"} 2019-05-07 11:40:51,359 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.convertnode.status and Value = success 2019-05-07 11:40:51,359 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= action-level and Value = vm 2019-05-07 11:40:51,359 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.action-identifiers.vnfc-name and Value = 2019-05-07 11:40:51,360 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= VNFCTYPE and Value = 2019-05-07 11:40:51,360 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vnfc-type and Value = 2019-05-07 11:40:51,360 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vserver-id and Value = 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:51,360 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vm[0].cloud-owner and Value = pod25 2019-05-07 11:40:51,361 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= response-prefix and Value = tmp.flowControl. 2019-05-07 11:40:51,361 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vnf-name and Value = Stability-Test-VM3 2019-05-07 11:40:51,361 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= status and Value = success 2019-05-07 11:40:51,361 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vm-id and Value = http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:51,362 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vnfc-name and Value = 2019-05-07 11:40:51,362 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= api-ver and Value = 2019-05-07 11:40:51,368 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.common-header.sub-request-id and Value = 1 2019-05-07 11:40:51,368 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vnf.vnf-type and Value = vTESTVM 2019-05-07 11:40:51,368 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vm[0].tenant-id and Value = cf142fb96ce24770946c0f7117c0aeb3 2019-05-07 11:40:51,369 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vm[0].vserver-id and Value = 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:40:51,369 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= req-vf-module-id and Value = 2019-05-07 11:40:51,369 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vnf-host-ip-address and Value = 2019-05-07 11:40:51,370 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= vf-module-id and Value = 2019-05-07 11:40:51,373 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.action-identifiers.vf-module-id and Value = 2019-05-07 11:40:51,374 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.common-header.originator-id and Value = 1 2019-05-07 11:40:51,374 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= isBwcMode and Value = false 2019-05-07 11:40:51,374 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= VMLENGTH and Value = 2019-05-07 11:40:51,374 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= identity-url and Value = http://10.12.25.2:5000/v3 2019-05-07 11:40:51,375 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= VNFCLENGTH and Value = 2019-05-07 11:40:51,375 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.common-header.api-ver and Value = 2.05 2019-05-07 11:40:51,375 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= configurationblock and Value = 2019-05-07 11:40:51,375 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vm[0].cloud-region-id and Value = RegionOne 2019-05-07 11:40:51,376 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= capabilities and Value = None 2019-05-07 11:40:51,376 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= service-instance-id and Value = 2019-05-07 11:40:51,376 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= isTemplateDriven.TEMPLATE and Value = N 2019-05-07 11:40:51,376 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= aai-vnf-type and Value = vTESTVM 2019-05-07 11:40:51,377 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.common-header.timestamp and Value = 2019-05-07T09:26:04.244Z 2019-05-07 11:40:51,377 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= currentGraph and Value = SvcLogicGraph [module=APPC, rpc=DGOrchestrator, mode=sync, version=4.0.0, md5sum=0dee53d30a45ac3a692d17f030f87587] 2019-05-07 11:40:51,377 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= CONFIGTYPE and Value = 2019-05-07 11:40:51,377 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= tmp.vnfInfo.vm-count and Value = 1 2019-05-07 11:40:51,378 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= skip-hypervisor-check and Value = true 2019-05-07 11:40:51,378 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= request-action and Value = Stop 2019-05-07 11:40:51,378 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= input.action and Value = Stop 2019-05-07 11:40:51,379 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= configuration-params and Value = 2019-05-07 11:40:51,379 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= VNFCNAME0 and Value = 2019-05-07 11:40:51,379 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | GraphExecutor.execute Setting Key= template-name and Value = 2019-05-07 11:40:51,379 | INFO | ppc-dispatcher-1 | SvcLogicServiceImpl | 206 - org.onap.ccsdk.sli.core.sli-provider - 0.4.1 | Fetching service logic from data store 2019-05-07 11:40:51,381 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute graph SvcLogicGraph [module=APPC, rpc=VM_Stop, mode=sync, version=2.0.0, md5sum=5cf73aa33e365cb0a3fd596d60afc895] 2019-05-07 11:40:51,381 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 1 (execute) 2019-05-07 11:40:51,427 | INFO | ppc-dispatcher-1 | ProviderOperation | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | APPC0056I APPC IAAS Adapter stop server requested 2019-05-07 11:40:51,458 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 39 * Sending client request on thread appc-dispatcher-1 39 > GET http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 39 > Accept: application/json 39 > X-Auth-Token: gAAAAABc0W3N4aT-s22STuv5luMdw5ffAH9n6dDv0WdawoeTiG4IxJiD036aZOttAqS-DJbTDH02l0_iV8LxUPm0TbHxfkJ02CBfsRsSKP7vbM3b7jInbic0xEoSif03Vc4rrLkTU2z5LGchDWw8DjfFu52iHoD4lustynooeKJeXIs1AMOJNO0 2019-05-07 11:40:52,037 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 39 * Client response received on thread appc-dispatcher-1 39 < 200 39 < Content-Length: 2165 39 < Content-Type: application/json 39 < Date: Tue, 07 May 2019 11:40:42 GMT 39 < X-Compute-Request-Id: req-f721d889-63bd-4ffe-833a-11f0bfa07090 39 < X-Openstack-Request-Id: req-f721d889-63bd-4ffe-833a-11f0bfa07090 {"server": {"OS-EXT-STS:task_state": null, "addresses": {"appc-multicloud-integration": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:bf:40:2a", "version": 4, "addr": "10.10.5.18", "OS-EXT-IPS:type": "fixed"}], "external": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:8f:9f:e9", "version": 4, "addr": "10.12.6.130", "OS-EXT-IPS:type": "fixed"}], "oam_onap_LH2Z": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:bf:3f:c8", "version": 4, "addr": "10.0.0.18", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "rel": "self"}, {"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "rel": "bookmark"}], "image": {"id": "4acbda90-f7d9-4c73-a0e1-a69953d1b24a", "links": [{"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/images/4acbda90-f7d9-4c73-a0e1-a69953d1b24a", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-EXT-SRV-ATTR:instance_name": "instance-00004716", "OS-SRV-USG:launched_at": "2019-05-07T11:38:38.000000", "flavor": {"id": "1a0d1e0a-9e6d-4dfc-a0d8-73aa2ef794e6", "links": [{"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/flavors/1a0d1e0a-9e6d-4dfc-a0d8-73aa2ef794e6", "rel": "bookmark"}]}, "id": "3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "security_groups": [{"name": "onap_sg_LH2Z"}, {"name": "default"}, {"name": "onap_sg_LH2Z"}, {"name": "default"}, {"name": "onap_sg_LH2Z"}, {"name": "default"}], "user_id": "6a87d2aa015c4fc5b6a6f08a9a15b006", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "status": "ACTIVE", "updated": "2019-05-07T11:38:44Z", "hostId": "6f2bed50c8bf36b85d7ddfc4d894bce2c5e4716c04aa6b2d55456d4f", "OS-EXT-SRV-ATTR:host": "compute-12", "OS-SRV-USG:terminated_at": null, "key_name": "onap_key_NhWI", "OS-EXT-SRV-ATTR:hypervisor_hostname": "compute-12", "name": "Stability-Test-VM3", "created": "2018-03-09T19:25:02Z", "tenant_id": "cf142fb96ce24770946c0f7117c0aeb3", "os-extended-volumes:volumes_attached": [], "metadata": {}}} 2019-05-07 11:40:52,074 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 40 * Sending client request on thread appc-dispatcher-1 40 > POST http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14/action 40 > Accept: application/json 40 > Content-Type: application/json 40 > X-Auth-Token: gAAAAABc0W3N4aT-s22STuv5luMdw5ffAH9n6dDv0WdawoeTiG4IxJiD036aZOttAqS-DJbTDH02l0_iV8LxUPm0TbHxfkJ02CBfsRsSKP7vbM3b7jInbic0xEoSif03Vc4rrLkTU2z5LGchDWw8DjfFu52iHoD4lustynooeKJeXIs1AMOJNO0 { "os-stop" : { } } 2019-05-07 11:40:52,244 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 40 * Client response received on thread appc-dispatcher-1 40 < 202 40 < Content-Length: 0 40 < Content-Type: application/json 40 < Date: Tue, 07 May 2019 11:40:42 GMT 40 < Openstack-Api-Version: compute 2.1 40 < Vary: OpenStack-API-Version, X-OpenStack-Nova-API-Version 40 < Wrs-Header: true 40 < X-Compute-Request-Id: req-d23e1554-da6c-4bb7-8624-5c4ac931b546 40 < X-Openstack-Nova-Api-Version: 2.1 40 < X-Openstack-Request-Id: req-d23e1554-da6c-4bb7-8624-5c4ac931b546 2019-05-07 11:40:52,262 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 41 * Sending client request on thread appc-dispatcher-1 41 > GET http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 41 > Accept: application/json 41 > X-Auth-Token: gAAAAABc0W3N4aT-s22STuv5luMdw5ffAH9n6dDv0WdawoeTiG4IxJiD036aZOttAqS-DJbTDH02l0_iV8LxUPm0TbHxfkJ02CBfsRsSKP7vbM3b7jInbic0xEoSif03Vc4rrLkTU2z5LGchDWw8DjfFu52iHoD4lustynooeKJeXIs1AMOJNO0 2019-05-07 11:40:52,459 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 41 * Client response received on thread appc-dispatcher-1 41 < 200 41 < Content-Length: 2175 41 < Content-Type: application/json 41 < Date: Tue, 07 May 2019 11:40:43 GMT 41 < X-Compute-Request-Id: req-a3e93c9a-f49c-47c2-9b0c-4a40aad8d32a 41 < X-Openstack-Request-Id: req-a3e93c9a-f49c-47c2-9b0c-4a40aad8d32a {"server": {"OS-EXT-STS:task_state": "powering-off", "addresses": {"appc-multicloud-integration": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:bf:40:2a", "version": 4, "addr": "10.10.5.18", "OS-EXT-IPS:type": "fixed"}], "external": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:8f:9f:e9", "version": 4, "addr": "10.12.6.130", "OS-EXT-IPS:type": "fixed"}], "oam_onap_LH2Z": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:bf:3f:c8", "version": 4, "addr": "10.0.0.18", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "rel": "self"}, {"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "rel": "bookmark"}], "image": {"id": "4acbda90-f7d9-4c73-a0e1-a69953d1b24a", "links": [{"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/images/4acbda90-f7d9-4c73-a0e1-a69953d1b24a", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "active", "OS-EXT-SRV-ATTR:instance_name": "instance-00004716", "OS-SRV-USG:launched_at": "2019-05-07T11:38:38.000000", "flavor": {"id": "1a0d1e0a-9e6d-4dfc-a0d8-73aa2ef794e6", "links": [{"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/flavors/1a0d1e0a-9e6d-4dfc-a0d8-73aa2ef794e6", "rel": "bookmark"}]}, "id": "3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "security_groups": [{"name": "onap_sg_LH2Z"}, {"name": "default"}, {"name": "onap_sg_LH2Z"}, {"name": "default"}, {"name": "onap_sg_LH2Z"}, {"name": "default"}], "user_id": "6a87d2aa015c4fc5b6a6f08a9a15b006", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "progress": 0, "OS-EXT-STS:power_state": 1, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "status": "ACTIVE", "updated": "2019-05-07T11:40:42Z", "hostId": "6f2bed50c8bf36b85d7ddfc4d894bce2c5e4716c04aa6b2d55456d4f", "OS-EXT-SRV-ATTR:host": "compute-12", "OS-SRV-USG:terminated_at": null, "key_name": "onap_key_NhWI", "OS-EXT-SRV-ATTR:hypervisor_hostname": "compute-12", "name": "Stability-Test-VM3", "created": "2018-03-09T19:25:02Z", "tenant_id": "cf142fb96ce24770946c0f7117c0aeb3", "os-extended-volumes:volumes_attached": [], "metadata": {}}} 2019-05-07 11:41:00,672 | INFO | Appc-Listener-2 | HttpDmaapConsumerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Got 0 messages from DMaaP 2019-05-07 11:41:00,673 | INFO | Appc-Listener-2 | EventHandlerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Read 0 messages from APPC-LCM-READ as APPC-EVENT-LISTENER-TEST/895. 2019-05-07 11:41:00,673 | INFO | Appc-Listener-2 | EventHandlerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | Getting up to 10 incoming events 2019-05-07 11:41:00,673 | INFO | Appc-Listener-2 | HttpDmaapConsumerImpl | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | GET http://message-router.onap:3904/events/APPC-LCM-READ/APPC-EVENT-LISTENER-TEST/895?timeout=60000&limit=10 2019-05-07 11:41:12,473 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 42 * Sending client request on thread appc-dispatcher-1 42 > GET http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 42 > Accept: application/json 42 > X-Auth-Token: gAAAAABc0W3N4aT-s22STuv5luMdw5ffAH9n6dDv0WdawoeTiG4IxJiD036aZOttAqS-DJbTDH02l0_iV8LxUPm0TbHxfkJ02CBfsRsSKP7vbM3b7jInbic0xEoSif03Vc4rrLkTU2z5LGchDWw8DjfFu52iHoD4lustynooeKJeXIs1AMOJNO0 2019-05-07 11:41:13,194 | INFO | ppc-dispatcher-1 | Keystone | 478 - appc-iaas-adapter - 1.5.0.SNAPSHOT | 42 * Client response received on thread appc-dispatcher-1 42 < 200 42 < Content-Length: 2152 42 < Content-Type: application/json 42 < Date: Tue, 07 May 2019 11:41:03 GMT 42 < X-Compute-Request-Id: req-ba348b30-d826-4c1d-9b18-9efcdd3319d5 42 < X-Openstack-Request-Id: req-ba348b30-d826-4c1d-9b18-9efcdd3319d5 {"server": {"OS-EXT-STS:task_state": null, "addresses": {"appc-multicloud-integration": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:bf:40:2a", "version": 4, "addr": "10.10.5.18", "OS-EXT-IPS:type": "fixed"}], "external": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:8f:9f:e9", "version": 4, "addr": "10.12.6.130", "OS-EXT-IPS:type": "fixed"}], "oam_onap_LH2Z": [{"OS-EXT-IPS-MAC:mac_addr": "fa:16:3e:bf:3f:c8", "version": 4, "addr": "10.0.0.18", "OS-EXT-IPS:type": "fixed"}]}, "links": [{"href": "http://10.12.25.2:8774/v2/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "rel": "self"}, {"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "rel": "bookmark"}], "image": {"id": "4acbda90-f7d9-4c73-a0e1-a69953d1b24a", "links": [{"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/images/4acbda90-f7d9-4c73-a0e1-a69953d1b24a", "rel": "bookmark"}]}, "OS-EXT-STS:vm_state": "stopped", "OS-EXT-SRV-ATTR:instance_name": "instance-00004716", "OS-SRV-USG:launched_at": "2019-05-07T11:38:38.000000", "flavor": {"id": "1a0d1e0a-9e6d-4dfc-a0d8-73aa2ef794e6", "links": [{"href": "http://10.12.25.2:8774/cf142fb96ce24770946c0f7117c0aeb3/flavors/1a0d1e0a-9e6d-4dfc-a0d8-73aa2ef794e6", "rel": "bookmark"}]}, "id": "3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "security_groups": [{"name": "onap_sg_LH2Z"}, {"name": "default"}, {"name": "onap_sg_LH2Z"}, {"name": "default"}, {"name": "onap_sg_LH2Z"}, {"name": "default"}], "user_id": "6a87d2aa015c4fc5b6a6f08a9a15b006", "OS-DCF:diskConfig": "MANUAL", "accessIPv4": "", "accessIPv6": "", "OS-EXT-STS:power_state": 4, "OS-EXT-AZ:availability_zone": "nova", "config_drive": "", "status": "SHUTOFF", "updated": "2019-05-07T11:40:47Z", "hostId": "6f2bed50c8bf36b85d7ddfc4d894bce2c5e4716c04aa6b2d55456d4f", "OS-EXT-SRV-ATTR:host": "compute-12", "OS-SRV-USG:terminated_at": null, "key_name": "onap_key_NhWI", "OS-EXT-SRV-ATTR:hypervisor_hostname": "compute-12", "name": "Stability-Test-VM3", "created": "2018-03-09T19:25:02Z", "tenant_id": "cf142fb96ce24770946c0f7117c0aeb3", "os-extended-volumes:volumes_attached": [], "metadata": {}}} 2019-05-07 11:41:13,201 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 2 (switch) 2019-05-07 11:41:13,205 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 3 (block) 2019-05-07 11:41:13,295 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 4 (set) 2019-05-07 11:41:13,296 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 5 (return) 2019-05-07 11:41:13,299 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vm[0].tenant-id -- cf142fb96ce24770946c0f7117c0aeb3 2019-05-07 11:41:13,300 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = STOP_STATUS -- SUCCESS 2019-05-07 11:41:13,301 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = VNFCTYPE -- 2019-05-07 11:41:13,302 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.payload -- {"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"} 2019-05-07 11:41:13,303 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vnf-name -- Stability-Test-VM3 2019-05-07 11:41:13,303 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = action-identifier -- vserver-id 2019-05-07 11:41:13,304 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vnf-host-ip-address -- 2019-05-07 11:41:13,304 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = success-message -- APPC0136I Operation StopServer succeed for http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:41:13,304 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = request-action -- Stop 2019-05-07 11:41:13,304 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vnf-id -- Stability-Test-VM3 2019-05-07 11:41:13,305 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = isTemplateDriven.TEMPLATE -- N 2019-05-07 11:41:13,305 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.status -- success 2019-05-07 11:41:13,305 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vnfc-name -- 2019-05-07 11:41:13,306 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = currentGraph -- SvcLogicGraph [module=APPC, rpc=VM_Stop, mode=sync, version=2.0.0, md5sum=5cf73aa33e365cb0a3fd596d60afc895] 2019-05-07 11:41:13,306 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.action-identifiers.vnfc-name -- 2019-05-07 11:41:13,306 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vserver-id -- 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:41:13,307 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = SvcLogic.status -- success 2019-05-07 11:41:13,307 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = isBwcMode -- false 2019-05-07 11:41:13,307 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = error-code -- 400 2019-05-07 11:41:13,307 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = VMNAME0 -- 2019-05-07 11:41:13,308 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.common-header.request-id -- 3 2019-05-07 11:41:13,309 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = skip-hypervisor-check -- true 2019-05-07 11:41:13,309 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = error-message -- Success 2019-05-07 11:41:13,310 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = response-prefix -- tmp.flowControl. 2019-05-07 11:41:13,311 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = aai-vnf-type -- vTESTVM 2019-05-07 11:41:13,311 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.action-identifiers.vnf-id -- Stability-Test-VM3 2019-05-07 11:41:13,311 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = CONFIGTYPE -- 2019-05-07 11:41:13,311 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vm[0].cloud-region-id -- RegionOne 2019-05-07 11:41:13,312 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = APPC.action-level.ACTION-LEVEL -- vm 2019-05-07 11:41:13,312 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vnf.vnf-type -- vTESTVM 2019-05-07 11:41:13,312 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.action-identifiers.vf-module-id -- 2019-05-07 11:41:13,312 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vnf.orchestration-status -- running 2019-05-07 11:41:13,313 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = capabilities -- None 2019-05-07 11:41:13,313 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.action-identifiers.service-instance-id -- 2019-05-07 11:41:13,313 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.common-header.sub-request-id -- 1 2019-05-07 11:41:13,314 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vm[0].vserver-id -- 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:41:13,314 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vm-count -- 1 2019-05-07 11:41:13,314 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = configuration-params -- 2019-05-07 11:41:13,314 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.cloud-region.identity-url -- http://10.12.25.2:5000/v3 2019-05-07 11:41:13,315 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = template-name -- 2019-05-07 11:41:13,315 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vf-module-id -- 2019-05-07 11:41:13,315 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = request-id -- 3 2019-05-07 11:41:13,315 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = status -- success 2019-05-07 11:41:13,316 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = configurationblock -- 2019-05-07 11:41:13,316 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.common-header.api-ver -- 2.05 2019-05-07 11:41:13,316 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = controller-template-id -- 2019-05-07 11:41:13,326 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = action-level -- vm 2019-05-07 11:41:13,332 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = error_code -- 200 2019-05-07 11:41:13,333 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.convertnode.status -- success 2019-05-07 11:41:13,333 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = VNFCLENGTH -- 2019-05-07 11:41:13,333 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = identity-url -- http://10.12.25.2:5000/v3 2019-05-07 11:41:13,334 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vnf.vnf-name -- Stability-Test-VM3 2019-05-07 11:41:13,334 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = originator-id -- 1 2019-05-07 11:41:13,334 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vnf-type -- 2019-05-07 11:41:13,335 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.common-header.timestamp -- 2019-05-07T09:26:04.244Z 2019-05-07 11:41:13,335 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.message -- Stability-Test-VM3 : Stop: {"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"} 2019-05-07 11:41:13,335 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = req-vf-module-id -- 2019-05-07 11:41:13,336 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.common-header.originator-id -- 1 2019-05-07 11:41:13,336 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = tmp.vnfInfo.vm[0].cloud-owner -- pod25 2019-05-07 11:41:13,336 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = VMLENGTH -- 2019-05-07 11:41:13,336 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = VNFCNAME0 -- 2019-05-07 11:41:13,337 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vnfc-type -- 2019-05-07 11:41:13,337 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.action-identifiers.vserver-id -- 3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:41:13,338 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = vm-id -- http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14 2019-05-07 11:41:13,342 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = api-ver -- 2019-05-07 11:41:13,342 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = service-instance-id -- 2019-05-07 11:41:13,342 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = input.action -- Stop 2019-05-07 11:41:13,343 | INFO | ppc-dispatcher-1 | GraphExecutor | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | NEW KEY = payload -- {"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"} 2019-05-07 11:41:13,343 | INFO | ppc-dispatcher-1 | FlowControlNode | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | Handling Response for transaction Id 1 2019-05-07 11:41:13,343 | INFO | ppc-dispatcher-1 | DefaultResponseHandler | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | Transaction Input params Transaction [transactionId=1, action=Stop, actionLevel=vm, actionIdentifier=null, parameters=null, executionType=graph, uId=null, statusCode=400, pswd=null, executionEndPoint=null, executionModule=APPC, executionRPC=VM_Stop, state=null, precheck=null, payload={"vm-id":"http://10.12.25.2:8774/v2.1/cf142fb96ce24770946c0f7117c0aeb3/servers/3b3d77e0-a79d-4c10-bfac-1b3914af1a14", "identity-url": "http://10.12.25.2:5000/v3","skip-hypervisor-check":"true"}, responses=[Response [responseCode=null, responseMessage=null, responseAction=ResponseAction [wait=null, retry=null, jump=null, ignore=false, stop=true, intermediateMessage=false], responseActionHanlder=null]], status=success] 2019-05-07 11:41:13,344 | INFO | ppc-dispatcher-1 | FlowControlNode | 489 - org.onap.appc.flow.controller - 1.5.0.SNAPSHOT | Executed all the transaction successfully 2019-05-07 11:41:13,344 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 19 (block) 2019-05-07 11:41:13,344 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 20 (set) 2019-05-07 11:41:13,345 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 41 (switch) 2019-05-07 11:41:13,345 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 42 (block) 2019-05-07 11:41:13,345 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 43 (set) 2019-05-07 11:41:13,346 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 46 (set) 2019-05-07 11:41:13,353 | INFO | ppc-dispatcher-1 | SvcLogicServiceImplBase | 380 - wrap_file__opt_opendaylight_system_org_onap_ccsdk_sli_core_sli-provider-base_0.4.1_sli-provider-base-0.4.1.jar - 0.0.0 | About to execute node # 47 (execute) 2019-05-07 11:41:13,365 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (success) not found, keys are { (failure)} 2019-05-07 11:41:13,366 | INFO | ppc-dispatcher-1 | SvcLogicExprListener | 205 - org.onap.ccsdk.sli.core.sli-common - 0.4.1 | Outcome (Other) not found, keys are { (failure)} 2019-05-07 11:41:13,426 | INFO | ppc-dispatcher-1 | TransactionRecorderImpl | 464 - transaction-recorder - 1.5.0.SNAPSHOT | No of Rows in Transactions Table with TRANSACTION_ID: null~ddd4c76b-154b-44fb-bedc-9b8c4694cf18 and REQUEST_ID 3 is: 0 2019-05-07 11:41:13,472 | INFO | ppc-dispatcher-1 | audit | 429 - appc-common-bundle - 1.5.0.SNAPSHOT | APPC0090A Operation "App-C IaaS Adapter:Stop" for VNF type "Stability-Test-VM3" from Source "appc" with RequestID "e811dfbe-8970-4ab8-a03c-215ba78e5202" was started at "2019-05-07T11:40:49Z" and ended at "2019-05-07T11:41:13Z" with status code "400"