16:08:21.791 [DMAAP-source-PDPD-CONFIGURATION] INFO  c.a.n.c.c.impl.CambriaConsumerImpl - UEB GET /events/PDPD-CONFIGURATION/c0c3a2ab-c87b-4537-a645-fcb5a64b19b6/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:21.792 [DMAAP-source-PDPD-CONFIGURATION] INFO  c.att.nsa.apiClient.http.HttpClient - GET https://message-router:3905/events/PDPD-CONFIGURATION/c0c3a2ab-c87b-4537-a645-fcb5a64b19b6/dev-policy-drools-pdp-0?timeout=15000&limit=100 (anonymous) ...
16:08:21.797 [DMAAP-source-PDPD-CONFIGURATION] INFO  c.att.nsa.apiClient.http.HttpClient -      --> HTTP/1.1 404 Not Found
16:08:21.797 [DMAAP-source-PDPD-CONFIGURATION] WARN  c.a.n.c.c.impl.CambriaConsumerImpl - Topic not found: /events/PDPD-CONFIGURATION/c0c3a2ab-c87b-4537-a645-fcb5a64b19b6/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:26.441 [DMAAP-source-SDNR-CL-RSP] INFO  c.a.n.c.c.impl.CambriaConsumerImpl - UEB GET /events/SDNR-CL-RSP/532ef0e2-9aee-43eb-b219-44421b835d72/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:26.442 [DMAAP-source-SDNR-CL-RSP] INFO  c.att.nsa.apiClient.http.HttpClient - GET https://message-router:3905/events/SDNR-CL-RSP/532ef0e2-9aee-43eb-b219-44421b835d72/dev-policy-drools-pdp-0?timeout=15000&limit=100 (anonymous) ...
16:08:26.451 [DMAAP-source-SDNR-CL-RSP] INFO  c.att.nsa.apiClient.http.HttpClient -     --> HTTP/1.1 404 Not Found
16:08:26.451 [DMAAP-source-SDNR-CL-RSP] WARN  c.a.n.c.c.impl.CambriaConsumerImpl - Topic not found: /events/SDNR-CL-RSP/532ef0e2-9aee-43eb-b219-44421b835d72/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:26.839 [DMAAP-source-POLICY-PDP-PAP] INFO  c.att.nsa.apiClient.http.HttpClient -  --> HTTP/1.1 200 OK
16:08:26.839 [DMAAP-source-POLICY-PDP-PAP] INFO  c.a.n.c.c.impl.CambriaConsumerImpl - UEB GET /events/POLICY-PDP-PAP/3c9616d6-ee08-4985-9e95-00526c18e581/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:26.839 [DMAAP-source-POLICY-PDP-PAP] INFO  c.att.nsa.apiClient.http.HttpClient - GET https://message-router:3905/events/POLICY-PDP-PAP/3c9616d6-ee08-4985-9e95-00526c18e581/dev-policy-drools-pdp-0?timeout=15000&limit=100 (anonymous) ...
16:08:27.034 [DMAAP-source-APPC-CL] INFO  c.a.n.c.c.impl.CambriaConsumerImpl - UEB GET /events/APPC-CL/871b1156-940a-4bd9-9f8f-cf14fbb74eb5/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:27.034 [DMAAP-source-APPC-CL] INFO  c.att.nsa.apiClient.http.HttpClient - GET https://message-router:3905/events/APPC-CL/871b1156-940a-4bd9-9f8f-cf14fbb74eb5/dev-policy-drools-pdp-0?timeout=15000&limit=100 (anonymous) ...
16:08:27.037 [DMAAP-source-APPC-CL] INFO  c.att.nsa.apiClient.http.HttpClient -         --> HTTP/1.1 404 Not Found
16:08:27.037 [DMAAP-source-APPC-CL] WARN  c.a.n.c.c.impl.CambriaConsumerImpl - Topic not found: /events/APPC-CL/871b1156-940a-4bd9-9f8f-cf14fbb74eb5/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:27.324 [DMAAP-source-APPC-LCM-WRITE] INFO  c.a.n.c.c.impl.CambriaConsumerImpl - UEB GET /events/APPC-LCM-WRITE/e415c36f-1136-450c-8658-ed23b63a9f6f/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:27.324 [DMAAP-source-APPC-LCM-WRITE] INFO  c.att.nsa.apiClient.http.HttpClient - GET https://message-router:3905/events/APPC-LCM-WRITE/e415c36f-1136-450c-8658-ed23b63a9f6f/dev-policy-drools-pdp-0?timeout=15000&limit=100 (anonymous) ...
16:08:27.331 [DMAAP-source-APPC-LCM-WRITE] INFO  c.att.nsa.apiClient.http.HttpClient -  --> HTTP/1.1 404 Not Found
16:08:27.332 [DMAAP-source-APPC-LCM-WRITE] WARN  c.a.n.c.c.impl.CambriaConsumerImpl - Topic not found: /events/APPC-LCM-WRITE/e415c36f-1136-450c-8658-ed23b63a9f6f/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:27.624 [DMAAP-source-DCAE_TOPIC] INFO  c.att.nsa.apiClient.http.HttpClient -      --> HTTP/1.1 200 OK
16:08:27.624 [DMAAP-source-DCAE_TOPIC] INFO  network - [IN|DMAAP|DCAE_TOPIC]
{"closedLoopEventClient":"microservice.stringmatcher","requestID":"c7c6a4aa-bb61-4a15-b831-ba1472dd4a65","target_type":"VNF","AAI":{"generic-vnf.prov-status":"ACTIVE","generic-vnf.is-closed-loop-disabled":"false","vserver.vserver-name":"viptela_scripts","generic-vnf.vnf-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"},"from":"DCAE","closedLoopAlarmStart":1463679805324,"closedLoopEventStatus":"ONSET","closedLoopControlName":"ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a","version":"1.0.2","target":"generic-vnf.vnf-id"}
16:08:27.625 [DMAAP-source-DCAE_TOPIC] INFO  c.a.n.c.c.impl.CambriaConsumerImpl - UEB GET /events/unauthenticated.DCAE_CL_OUTPUT/dcae.policy.shared/dev-policy-drools-pdp-0?timeout=15000&limit=100
16:08:27.625 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT: event=CanonicalOnset(super=Onset(super=VirtualControlLoopEvent(super=ControlLoopEvent(requestId=c7c6a4aa-bb61-4a15-b831-ba1472dd4a65, targetType=VNF, closedLoopControlName=ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a, version=1.0.2, closedLoopEventClient=microservice.stringmatcher, target=generic-vnf.vnf-id, from=DCAE, policyScope=null, policyName=null, policyVersion=null, closedLoopEventStatus=ONSET, additionalEventParams=null), aai={generic-vnf.prov-status=ACTIVE, generic-vnf.is-closed-loop-disabled=false, vserver.vserver-name=viptela_scripts, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, payload=null, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null)))
16:08:27.625 [DMAAP-source-DCAE_TOPIC] INFO  c.att.nsa.apiClient.http.HttpClient - GET https://message-router:3905/events/unauthenticated.DCAE_CL_OUTPUT/dcae.policy.shared/dev-policy-drools-pdp-0?timeout=15000&limit=100 (anonymous) ...
16:08:27.626 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=CDS, operation=modify-config) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.626 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=LOCK, operation=Lock) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.626 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=pseudo/getTargetEntity, operation=pseudo/getTargetEntity) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65||ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:27.627+00:00|2021-03-10T16:08:27.628+00:00|null|null:operational.modifyconfig.EVENT.MANAGER.ACCEPT:1.0.0|dev-policy-drools-pdp-0|COMPLETE|100||||VNF.generic-vnf.vnf-id||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
16:08:27.626 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.e.ControlLoopEventManager - set property AAI/targetEntity=37b008b9-b367-4359-93fd-74d3ce0ee1a0 manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.627 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=pseudo/getTargetEntity, operation=pseudo/getTargetEntity) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.627 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.ACCEPT: manager=Step(actor=LOCK, operation=Lock)
16:08:27.627 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - org.onap.policy.controlloop.VirtualControlLoopNotificationMavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] class loader matches original drools controller rules classloader jdk.internal.loader.ClassLoaders$AppClassLoader@67424e82
16:08:27.628 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:27.628 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113]DELIVER: VirtualControlLoopNotification(aai={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null) FROM MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] TO InlineDmaapTopicSink [userName=null, password=null, getTopicCommInfrastructure()=DMAAP, toString()=InlineBusTopicSink [partitionId=9b9f56d5-5d4d-4df9-82ba-47c7e199c236, alive=true, publisher=CambriaPublisherWrapper []]]
16:08:27.629 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  network - [OUT|DMAAP|POLICY-CL-MGT]
{
  "AAI": {
    "generic-vnf.prov-status": "ACTIVE",
    "vserver.vserver-name": "viptela_scripts",
    "generic-vnf.is-closed-loop-disabled": "false",
    "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
  },
  "closedLoopAlarmStart": 1463679805324,
  "closedLoopControlName": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
  "version": "1.0.2",
  "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
  "closedLoopEventClient": "microservice.stringmatcher",
  "targetType": "VNF",
  "target": "generic-vnf.vnf-id",
  "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]",
  "policyName": "operational.modifyconfig.EVENT.MANAGER.ACCEPT",
  "policyVersion": "1.0.0",
  "notification": "ACTIVE",
  "notificationTime": "2021-03-10 16:08:27.627208+00:00",
  "history": []
}
16:08:27.629 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=LOCK, operation=Lock) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.635 [pool-3-thread-2] INFO  o.o.p.d.s.internal.FeatureLockImpl - lock granted: DistributedLock [state=ACTIVE, resourceId=37b008b9-b367-4359-93fd-74d3ce0ee1a0, ownerKey=c7c6a4aa-bb61-4a15-b831-ba1472dd4a65, holdSec=1214, hostName=dev-policy-drools-pdp-0, uuidString=4621b92c-97a4-4c88-90ee-2ef976dfb023]
16:08:27.637 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 1 msgs to /events/POLICY-CL-MGT. Oldest: 8 ms
16:08:27.637 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ...
16:08:27.638 [pool-3-thread-3] INFO  o.o.p.c.eventmanager.LockData - lock granted on 37b008b9-b367-4359-93fd-74d3ce0ee1a0 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.646 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=LOCK, operation=Lock) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.646 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=AAI, operation=CustomQuery) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.646 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=AAI, operation=Tenant) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.647 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=AAI, operation=Tenant) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.648 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.Tenant: set property AAI/targetEntity=viptela_scripts
16:08:27.648 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.Tenant: start operation attempt 1 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.648 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.Tenant: set timeout to 90000ms for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.648 [Thread-3341] INFO  o.o.p.c.a.p.ControlLoopOperationParams - started operation AAI.Tenant for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.649 [Thread-3341] INFO  o.o.p.c.a.impl.OperationPartial - [OUT|REST|https://aai.onap:8443/aai/v21/search/nodes-query?search-node-type=vserver&filter=vserver-name:EQUALS:viptela_scripts|]
null
16:08:27.649 [Thread-3341] INFO  network - [OUT|REST|https://aai.onap:8443/aai/v21/search/nodes-query?search-node-type=vserver&filter=vserver-name:EQUALS:viptela_scripts]
null
16:08:27.649 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.PREPROCESSOR.STARTED: Step(actor=AAI, operation=Tenant) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.668 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient -      --> HTTP/1.1 200 OK
16:08:27.669 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (32 ms):{"serverTimeMs":0,"count":1}
16:08:27.733 [Thread-3342] INFO  o.o.p.c.a.impl.HttpOperation - AAI.Tenant: response received for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.737 [Thread-3342] INFO  o.o.p.c.a.impl.OperationPartial - [IN|REST|https://aai.onap:8443/aai/v21/search/nodes-query?search-node-type=vserver&filter=vserver-name:EQUALS:viptela_scripts|]
{"result-data":[{"resource-type":"vserver","resource-link":"/aai/v21/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779"}]}
16:08:27.737 [Thread-3342] INFO  network - [IN|REST|https://aai.onap:8443/aai/v21/search/nodes-query?search-node-type=vserver&filter=vserver-name:EQUALS:viptela_scripts]
{"result-data":[{"resource-type":"vserver","resource-link":"/aai/v21/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779"}]}
16:08:27.737 [Thread-3342] INFO  o.o.p.c.a.impl.HttpOperation - AAI.Tenant request succeeded for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.746 [Thread-3345] INFO  o.o.p.c.a.p.ControlLoopOperationParams - completed operation AAI.Tenant outcome=Success for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.747 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=AAI, operation=Tenant) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.747 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.e.ControlLoopEventManager - set property AAI.Tenant.viptela_scripts=org.onap.policy.common.utils.coder.StandardCoderObject@18e85362 manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.747 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=AAI, operation=CustomQuery) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.747 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.CustomQuery: set property AAI/vserver/link=/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779
16:08:27.747 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.CustomQuery: start operation attempt 1 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.747 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.CustomQuery: set timeout to 90000ms for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.748 [Thread-3346] INFO  o.o.p.c.a.p.ControlLoopOperationParams - started operation AAI.CustomQuery for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:27.748 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.PREPROCESSOR.STARTED: Step(actor=AAI, operation=CustomQuery) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:27.748 [Thread-3346] INFO  o.o.p.c.a.impl.OperationPartial - [OUT|REST|https://aai.onap:8443/aai/v21/query?format=resource|]
{
  "query": "query/closed-loop",
  "start": "/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779"
}
16:08:27.748 [Thread-3346] INFO  network - [OUT|REST|https://aai.onap:8443/aai/v21/query?format=resource]
{
  "query": "query/closed-loop",
  "start": "/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779"
}
16:08:27.753 [Thread-3347] INFO  o.o.p.c.a.impl.OperationPartial - not retrying operation AAI.Tenant for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:28.911 [Thread-3348] INFO  o.o.p.c.a.impl.HttpOperation - AAI.CustomQuery: response received for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:28.927 [Thread-3348] INFO  o.o.p.c.a.impl.OperationPartial - [IN|REST|https://aai.onap:8443/aai/v21/query?format=resource|]
{"results":[{"vserver":{"vserver-id":"c82153dc-8d84-4e69-9673-5e0a60030779","vserver-name":"viptela_scripts","vserver-name2":"viptela_scripts","prov-status":"Running","vserver-selflink":"http://10.195.194.213:8774/v2.1/7320ec4a5b9d4589ba7c4412ccfd290f/servers/c82153dc-8d84-4e69-9673-5e0a60030779","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1615390967571","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]}]}}},{"generic-vnf":{"vnf-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0","vnf-name":"vfw-cnf-cds-demo-28-vnf","vnf-type":"vfw_cnf_cds/null","service-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0","prov-status":"ACTIVE","orchestration-status":"Assigned","ipv4-oam-address":"10.195.200.23","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1615390967571","model-invariant-id":"vTEST_VITELA","model-version-id":"vTEST","model-customization-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-list":{"relationship":[{"related-to":"tenant","relationship-label":"org.onap.relationships.inventory.BelongsTo","related-link":"/aai/v21/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f","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"}],"related-to-property":[{"property-key":"tenant.tenant-name","property-value":"nso-vnf"}]},{"related-to":"vserver","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v21/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"}]},{"related-to":"service-instance","relationship-label":"org.onap.relationships.inventory.ComposedOf","related-link":"/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT/service-instances/service-instance/55322ada-145a-492e-b196-0d171539a4b2","relationship-data":[{"relationship-key":"customer.global-customer-id","relationship-value":"DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf"},{"relationship-key":"service-subscription.service-type","relationship-value":"vFWDT"},{"relationship-key":"service-instance.service-instance-id","relationship-value":"55322ada-145a-492e-b196-0d171539a4b2"}],"related-to-property":[{"property-key":"service-instance.service-instance-name","property-value":"Service_Ete_Namea337dd5b-4620-4ea6-bc3f-6edf1124d659"}]}]}}},{"model-ver":{"model-version-id":"vTEST","model-name":"vTEST","model-version":"v1.4.0","resource-version":"1615390948345"}},{"model":{"model-invariant-id":"vTEST_VITELA","model-type":"model_type","resource-version":"1615390948345","model-vers":{"model-ver":[{"model-version-id":"vTEST","model-name":"vTEST","model-version":"v1.4.0","resource-version":"1615390948345"}]}}},{"service-instance":{"service-instance-id":"55322ada-145a-492e-b196-0d171539a4b2","service-instance-name":"Service_Ete_Namea337dd5b-4620-4ea6-bc3f-6edf1124d659","environment-context":"General_Revenue-Bearing","workload-context":"Production","model-invariant-id":"vTEST_VITELA","model-version-id":"vTEST","resource-version":"1615390958668","selflink":"restconf/config/GENERIC-RESOURCE-API:services/service/55322ada-145a-492e-b196-0d171539a4b2/service-data/service-topology/","orchestration-status":"Active","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"org.onap.relationships.inventory.ComposedOf","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]}]}}},{"tenant":{"tenant-id":"7320ec4a5b9d4589ba7c4412ccfd290f","tenant-name":"nso-vnf","resource-version":"1615390958668","vservers":{"vserver":[{"vserver-id":"c82153dc-8d84-4e69-9673-5e0a60030779","vserver-name":"viptela_scripts","vserver-name2":"viptela_scripts","prov-status":"Running","vserver-selflink":"http://10.195.194.213:8774/v2.1/7320ec4a5b9d4589ba7c4412ccfd290f/servers/c82153dc-8d84-4e69-9673-5e0a60030779","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1615390967571","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]}]}}]},"relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"org.onap.relationships.inventory.BelongsTo","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]},{"related-to":"service-subscription","relationship-label":"org.onap.relationships.inventory.Uses","related-link":"/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT","relationship-data":[{"relationship-key":"customer.global-customer-id","relationship-value":"DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf"},{"relationship-key":"service-subscription.service-type","relationship-value":"vFWDT"}]}]}}},{"cloud-region":{"cloud-owner":"CloudOwner","cloud-region-id":"RegionOne","cloud-type":"openstack","owner-defined-type":"owner-defined-type","cloud-region-version":"titanium_cloud","identity-url":"http://10.195.194.213:5000/v2.0","cloud-zone":"CloudZone","sriov-automation":"true","orchestration-disabled":false,"in-maint":false,"resource-version":"1615390937889","tenants":{"tenant":[{"tenant-id":"7320ec4a5b9d4589ba7c4412ccfd290f","tenant-name":"nso-vnf","resource-version":"1615390958668","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"org.onap.relationships.inventory.BelongsTo","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]},{"related-to":"service-subscription","relationship-label":"org.onap.relationships.inventory.Uses","related-link":"/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT","relationship-data":[{"relationship-key":"customer.global-customer-id","relationship-value":"DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf"},{"relationship-key":"service-subscription.service-type","relationship-value":"vFWDT"}]}]}}]}}}]}
16:08:28.927 [Thread-3348] INFO  network - [IN|REST|https://aai.onap:8443/aai/v21/query?format=resource]
{"results":[{"vserver":{"vserver-id":"c82153dc-8d84-4e69-9673-5e0a60030779","vserver-name":"viptela_scripts","vserver-name2":"viptela_scripts","prov-status":"Running","vserver-selflink":"http://10.195.194.213:8774/v2.1/7320ec4a5b9d4589ba7c4412ccfd290f/servers/c82153dc-8d84-4e69-9673-5e0a60030779","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1615390967571","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]}]}}},{"generic-vnf":{"vnf-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0","vnf-name":"vfw-cnf-cds-demo-28-vnf","vnf-type":"vfw_cnf_cds/null","service-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0","prov-status":"ACTIVE","orchestration-status":"Assigned","ipv4-oam-address":"10.195.200.23","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1615390967571","model-invariant-id":"vTEST_VITELA","model-version-id":"vTEST","model-customization-id":"37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-list":{"relationship":[{"related-to":"tenant","relationship-label":"org.onap.relationships.inventory.BelongsTo","related-link":"/aai/v21/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f","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"}],"related-to-property":[{"property-key":"tenant.tenant-name","property-value":"nso-vnf"}]},{"related-to":"vserver","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v21/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"}]},{"related-to":"service-instance","relationship-label":"org.onap.relationships.inventory.ComposedOf","related-link":"/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT/service-instances/service-instance/55322ada-145a-492e-b196-0d171539a4b2","relationship-data":[{"relationship-key":"customer.global-customer-id","relationship-value":"DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf"},{"relationship-key":"service-subscription.service-type","relationship-value":"vFWDT"},{"relationship-key":"service-instance.service-instance-id","relationship-value":"55322ada-145a-492e-b196-0d171539a4b2"}],"related-to-property":[{"property-key":"service-instance.service-instance-name","property-value":"Service_Ete_Namea337dd5b-4620-4ea6-bc3f-6edf1124d659"}]}]}}},{"model-ver":{"model-version-id":"vTEST","model-name":"vTEST","model-version":"v1.4.0","resource-version":"1615390948345"}},{"model":{"model-invariant-id":"vTEST_VITELA","model-type":"model_type","resource-version":"1615390948345","model-vers":{"model-ver":[{"model-version-id":"vTEST","model-name":"vTEST","model-version":"v1.4.0","resource-version":"1615390948345"}]}}},{"service-instance":{"service-instance-id":"55322ada-145a-492e-b196-0d171539a4b2","service-instance-name":"Service_Ete_Namea337dd5b-4620-4ea6-bc3f-6edf1124d659","environment-context":"General_Revenue-Bearing","workload-context":"Production","model-invariant-id":"vTEST_VITELA","model-version-id":"vTEST","resource-version":"1615390958668","selflink":"restconf/config/GENERIC-RESOURCE-API:services/service/55322ada-145a-492e-b196-0d171539a4b2/service-data/service-topology/","orchestration-status":"Active","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"org.onap.relationships.inventory.ComposedOf","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]}]}}},{"tenant":{"tenant-id":"7320ec4a5b9d4589ba7c4412ccfd290f","tenant-name":"nso-vnf","resource-version":"1615390958668","vservers":{"vserver":[{"vserver-id":"c82153dc-8d84-4e69-9673-5e0a60030779","vserver-name":"viptela_scripts","vserver-name2":"viptela_scripts","prov-status":"Running","vserver-selflink":"http://10.195.194.213:8774/v2.1/7320ec4a5b9d4589ba7c4412ccfd290f/servers/c82153dc-8d84-4e69-9673-5e0a60030779","in-maint":false,"is-closed-loop-disabled":false,"resource-version":"1615390967571","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"tosca.relationships.HostedOn","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]}]}}]},"relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"org.onap.relationships.inventory.BelongsTo","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]},{"related-to":"service-subscription","relationship-label":"org.onap.relationships.inventory.Uses","related-link":"/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT","relationship-data":[{"relationship-key":"customer.global-customer-id","relationship-value":"DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf"},{"relationship-key":"service-subscription.service-type","relationship-value":"vFWDT"}]}]}}},{"cloud-region":{"cloud-owner":"CloudOwner","cloud-region-id":"RegionOne","cloud-type":"openstack","owner-defined-type":"owner-defined-type","cloud-region-version":"titanium_cloud","identity-url":"http://10.195.194.213:5000/v2.0","cloud-zone":"CloudZone","sriov-automation":"true","orchestration-disabled":false,"in-maint":false,"resource-version":"1615390937889","tenants":{"tenant":[{"tenant-id":"7320ec4a5b9d4589ba7c4412ccfd290f","tenant-name":"nso-vnf","resource-version":"1615390958668","relationship-list":{"relationship":[{"related-to":"generic-vnf","relationship-label":"org.onap.relationships.inventory.BelongsTo","related-link":"/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0","relationship-data":[{"relationship-key":"generic-vnf.vnf-id","relationship-value":"37b008b9-b367-4359-93fd-74d3ce0ee1a0"}],"related-to-property":[{"property-key":"generic-vnf.vnf-name","property-value":"vfw-cnf-cds-demo-28-vnf"}]},{"related-to":"service-subscription","relationship-label":"org.onap.relationships.inventory.Uses","related-link":"/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT","relationship-data":[{"relationship-key":"customer.global-customer-id","relationship-value":"DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf"},{"relationship-key":"service-subscription.service-type","relationship-value":"vFWDT"}]}]}}]}}}]}
16:08:28.936 [Thread-3348] INFO  o.o.p.c.a.impl.HttpOperation - AAI.CustomQuery request succeeded for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:28.979 [Thread-3351] INFO  o.o.p.c.a.p.ControlLoopOperationParams - completed operation AAI.CustomQuery outcome=Success for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:28.980 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=AAI, operation=CustomQuery) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.980 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.e.ControlLoopEventManager - set property AAI.AaiCqResponse=org.onap.policy.aai.AaiCqResponse@7092bd3a manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.980 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.981 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=AAI, operation=CustomQuery) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.981 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PREPROCESS: Step(actor=AAI, operation=Tenant) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.988 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=AAI, operation=Tenant) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.988 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.Tenant: set property AAI/targetEntity=viptela_scripts
16:08:28.988 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=AAI, operation=CustomQuery) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.988 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - AAI.CustomQuery: set property AAI/vserver/link=/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779
16:08:28.988 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:28.988 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - GUARD.Decision: start operation attempt 1 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:28.989 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - GUARD.Decision: set timeout to 90000ms for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65||ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:29.002+00:00|2021-03-10T16:08:29.002+00:00|null|null:operational.modifyconfig.EVENT.MANAGER.PROCESS.GUARD.OUTCOME:1.0.0|dev-policy-drools-pdp-0|COMPLETE|300|Sending guard query for CDS modify-config|||VNF.generic-vnf.vnf-id||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
16:08:29.001 [Thread-3353] INFO  o.o.p.c.a.impl.OperationPartial - not retrying operation AAI.CustomQuery for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.001 [Thread-3352] INFO  o.o.p.c.a.p.ControlLoopOperationParams - started operation GUARD.Decision for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.001 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.GUARD.OUTCOME: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:29.002 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - org.onap.policy.controlloop.VirtualControlLoopNotificationMavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] class loader matches original drools controller rules classloader jdk.internal.loader.ClassLoaders$AppClassLoader@67424e82
16:08:29.002 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:29.002 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113]DELIVER: VirtualControlLoopNotification(aai={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null) FROM MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] TO InlineDmaapTopicSink [userName=null, password=null, getTopicCommInfrastructure()=DMAAP, toString()=InlineBusTopicSink [partitionId=9b9f56d5-5d4d-4df9-82ba-47c7e199c236, alive=true, publisher=CambriaPublisherWrapper []]]
16:08:29.002 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  network - [OUT|DMAAP|POLICY-CL-MGT]
{
  "AAI": {
    "generic-vnf.prov-status": "ACTIVE",
    "vserver.vserver-name": "viptela_scripts",
    "generic-vnf.is-closed-loop-disabled": "false",
    "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
  },
  "closedLoopAlarmStart": 1463679805324,
  "closedLoopControlName": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
  "version": "1.0.2",
  "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
  "closedLoopEventClient": "microservice.stringmatcher",
  "targetType": "VNF",
  "target": "generic-vnf.vnf-id",
  "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]",
  "policyName": "operational.modifyconfig.EVENT.MANAGER.PROCESS.GUARD.OUTCOME",
  "policyVersion": "1.0.0",
  "notification": "OPERATION",
  "message": "Sending guard query for CDS modify-config",
  "notificationTime": "2021-03-10 16:08:29.001925+00:00",
  "history": []
}
16:08:29.002 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.PREPROCESSOR.STARTED: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:29.003 [Thread-3352] INFO  o.o.p.c.a.impl.OperationPartial - [OUT|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision|]
{
  "ONAPName": "Policy",
  "ONAPComponent": "Drools PDP",
  "ONAPInstance": "Usecases",
  "requestId": "5583b880-16a9-409e-9c41-c9d61014fcb4",
  "action": "guard",
  "resource": {
    "guard": {
      "actor": "CDS",
      "operation": "modify-config",
      "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
      "clname": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
      "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0",
      "generic-vnf.vnf-name": "vfw-cnf-cds-demo-28-vnf",
      "generic-vnf.vnf-type": "vfw_cnf_cds/null",
      "vserver.vserver-id": "c82153dc-8d84-4e69-9673-5e0a60030779",
      "cloud-region.cloud-region-id": "RegionOne"
    }
  }
}
16:08:29.003 [Thread-3352] INFO  network - [OUT|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision]
{
  "ONAPName": "Policy",
  "ONAPComponent": "Drools PDP",
  "ONAPInstance": "Usecases",
  "requestId": "5583b880-16a9-409e-9c41-c9d61014fcb4",
  "action": "guard",
  "resource": {
    "guard": {
      "actor": "CDS",
      "operation": "modify-config",
      "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
      "clname": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
      "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0",
      "generic-vnf.vnf-name": "vfw-cnf-cds-demo-28-vnf",
      "generic-vnf.vnf-type": "vfw_cnf_cds/null",
      "vserver.vserver-id": "c82153dc-8d84-4e69-9673-5e0a60030779",
      "cloud-region.cloud-region-id": "RegionOne"
    }
  }
}
16:08:29.037 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 1 msgs to /events/POLICY-CL-MGT. Oldest: 35 ms
16:08:29.037 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ...
16:08:29.043 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient -      --> HTTP/1.1 200 OK
16:08:29.043 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (6 ms):{"serverTimeMs":0,"count":1}
16:08:29.054 [Thread-3354] INFO  o.o.p.c.a.impl.HttpOperation - GUARD.Decision: response received for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.061 [Thread-3354] INFO  o.o.p.c.a.impl.OperationPartial - [IN|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision|]
{"status":"Permit"}
16:08:29.061 [Thread-3354] INFO  network - [IN|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision]
{"status":"Permit"}
16:08:29.061 [Thread-3354] INFO  o.o.p.c.a.impl.HttpOperation - GUARD.Decision request succeeded for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.066 [Thread-3357] INFO  o.o.p.c.a.p.ControlLoopOperationParams - completed operation GUARD.Decision outcome=Success for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.066 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.GUARD.OUTCOME: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:29.066 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - org.onap.policy.controlloop.VirtualControlLoopNotificationMavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] class loader matches original drools controller rules classloader jdk.internal.loader.ClassLoaders$AppClassLoader@67424e82
16:08:29.066 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:29.066 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113]DELIVER: VirtualControlLoopNotification(aai={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null) FROM MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] TO InlineDmaapTopicSink [userName=null, password=null, getTopicCommInfrastructure()=DMAAP, toString()=InlineBusTopicSink [partitionId=9b9f56d5-5d4d-4df9-82ba-47c7e199c236, alive=true, publisher=CambriaPublisherWrapper []]]
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  network - [OUT|DMAAP|POLICY-CL-MGT]
{
  "AAI": {
    "generic-vnf.prov-status": "ACTIVE",
    "vserver.vserver-name": "viptela_scripts",
    "generic-vnf.is-closed-loop-disabled": "false",
    "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
  },
  "closedLoopAlarmStart": 1463679805324,
  "closedLoopControlName": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
  "version": "1.0.2",
  "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
  "closedLoopEventClient": "microservice.stringmatcher",
  "targetType": "VNF",
  "target": "generic-vnf.vnf-id",
  "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]",
  "policyName": "operational.modifyconfig.EVENT.MANAGER.PROCESS.GUARD.OUTCOME",
  "policyVersion": "1.0.0",
  "notification": "OPERATION",
  "message": "Guard result for CDS modify-config is Permit",
  "notificationTime": "2021-03-10 16:08:29.066399+00:00",
  "history": []
}
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=GUARD, operation=Decision) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.EXECUTE.STEP: Step(actor=CDS, operation=modify-config) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - CDS.modify-config: set property AAI/resourceId/vnf=org.onap.aai.domain.yang.GenericVnf@1a6d89f9[vnfId=37b008b9-b367-4359-93fd-74d3ce0ee1a0, vnfInstanceId=<null>(default), vnfName=vfw-cnf-cds-demo-28-vnf, vnfName2=<null>(default), vnfType=vfw_cnf_cds/null, serviceId=37b008b9-b367-4359-93fd-74d3ce0ee1a0, regionalResourceZone=<null>(default), provStatus=ACTIVE, operationalStatus=<null>(default), licenseKey=<null>(default), equipmentRole=<null>(default), orchestrationStatus=Assigned, vnfPackageName=<null>(default), vnfDiscriptorName=<null>(default), jobId=<null>(default), heatStackId=<null>(default), msoCatalogKey=<null>(default), managementOption=<null>(default), ipv4OamAddress=10.195.200.23, ipv4Loopback0Address=<null>(default), nmLanV6Address=<null>(default), managementV6Address=<null>(default), vcpu=<null>(default), vcpuUnits=<null>(default), vmemory=<null>(default), vmemoryUnits=<null>(default), vdisk=<null>(default), vdiskUnits=<null>(default), nshd=<null>(default), nvm=<null>(default), nnet=<null>(default), inMaint=false, isClosedLoopDisabled=false, resourceVersion=1615390967571, summaryStatus=<null>(default), encryptedAccessFlag=<null>(default), entitlementAssignmentGroupUuid=<null>(default), entitlementResourceUuid=<null>(default), licenseAssignmentGroupUuid=<null>(default), licenseKeyUuid=<null>(default), modelInvariantId=vTEST_VITELA, modelVersionId=vTEST, personaModelVersion=<null>(default), modelCustomizationId=37b008b9-b367-4359-93fd-74d3ce0ee1a0, widgetModelId=<null>(default), widgetModelVersion=<null>(default), asNumber=<null>(default), regionalResourceSubzone=<null>(default), nfType=<null>(default), nfFunction=<null>(default), nfRole=<null>(default), nfNamingCode=<null>(default), selflink=<null>(default), ipv4OamGatewayAddress=<null>(default), ipv4OamGatewayAddressPrefixLength=<null>(default), vlanIdOuter=<null>(default), nmProfileName=<null>(default), softwareVersion=<null>(default), applicationId=<null>(default), relationshipList=org.onap.aai.domain.yang.RelationshipList@e942a3[relationship={org.onap.aai.domain.yang.Relationship@44a11aa0[relatedTo=tenant, relationshipLabel=org.onap.relationships.inventory.BelongsTo, relatedLink=/aai/v21/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f, relationshipData={org.onap.aai.domain.yang.RelationshipData@39ee33d4[relationshipKey=cloud-region.cloud-owner, relationshipValue=CloudOwner],org.onap.aai.domain.yang.RelationshipData@4f3a4751[relationshipKey=cloud-region.cloud-region-id, relationshipValue=RegionOne],org.onap.aai.domain.yang.RelationshipData@58ce84f[relationshipKey=tenant.tenant-id, relationshipValue=7320ec4a5b9d4589ba7c4412ccfd290f]}, relatedToProperty={org.onap.aai.domain.yang.RelatedToProperty@49348644[propertyKey=tenant.tenant-name, propertyValue=nso-vnf]}],org.onap.aai.domain.yang.Relationship@18c7c25[relatedTo=vserver, relationshipLabel=tosca.relationships.HostedOn, relatedLink=/aai/v21/cloud-infrastructure/cloud-regions/cloud-region/CloudOwner/RegionOne/tenants/tenant/7320ec4a5b9d4589ba7c4412ccfd290f/vservers/vserver/c82153dc-8d84-4e69-9673-5e0a60030779, relationshipData={org.onap.aai.domain.yang.RelationshipData@1c11b38a[relationshipKey=cloud-region.cloud-owner, relationshipValue=CloudOwner],org.onap.aai.domain.yang.RelationshipData@7ebdfed7[relationshipKey=cloud-region.cloud-region-id, relationshipValue=RegionOne],org.onap.aai.domain.yang.RelationshipData@13d641e2[relationshipKey=tenant.tenant-id, relationshipValue=7320ec4a5b9d4589ba7c4412ccfd290f],org.onap.aai.domain.yang.RelationshipData@71d96ae9[relationshipKey=vserver.vserver-id, relationshipValue=c82153dc-8d84-4e69-9673-5e0a60030779]}, relatedToProperty={org.onap.aai.domain.yang.RelatedToProperty@60451abb[propertyKey=vserver.vserver-name, propertyValue=viptela_scripts]}],org.onap.aai.domain.yang.Relationship@711f0b22[relatedTo=service-instance, relationshipLabel=org.onap.relationships.inventory.ComposedOf, relatedLink=/aai/v21/business/customers/customer/DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf/service-subscriptions/service-subscription/vFWDT/service-instances/service-instance/55322ada-145a-492e-b196-0d171539a4b2, relationshipData={org.onap.aai.domain.yang.RelationshipData@50e79db2[relationshipKey=customer.global-customer-id, relationshipValue=DemoCust_47417dae-a821-4068-9736-e71c7da0d8bf],org.onap.aai.domain.yang.RelationshipData@6a532b65[relationshipKey=service-subscription.service-type, relationshipValue=vFWDT],org.onap.aai.domain.yang.RelationshipData@220b6456[relationshipKey=service-instance.service-instance-id, relationshipValue=55322ada-145a-492e-b196-0d171539a4b2]}, relatedToProperty={org.onap.aai.domain.yang.RelatedToProperty@2b443d2e[propertyKey=service-instance.service-instance-name, propertyValue=Service_Ete_Namea337dd5b-4620-4ea6-bc3f-6edf1124d659]}]}], lInterfaces=<null>(default), lagInterfaces=<null>(default), vfModules=<null>(default), licenses=<null>(default), entitlements=<null>(default)]
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - CDS.modify-config: set property AAI/service=org.onap.aai.domain.yang.ServiceInstance@47a23ff9[serviceInstanceId=55322ada-145a-492e-b196-0d171539a4b2, serviceInstanceName=Service_Ete_Namea337dd5b-4620-4ea6-bc3f-6edf1124d659, serviceType=<null>(default), serviceRole=<null>(default), serviceRate=<null>(default), serviceLayer=<null>(default), dueDate=<null>(default), endDate=<null>(default), environmentContext=General_Revenue-Bearing, workloadContext=Production, createdAt=<null>(default), updatedAt=<null>(default), description=<null>(default), modelInvariantId=vTEST_VITELA, modelVersionId=vTEST, personaModelVersion=<null>(default), widgetModelId=<null>(default), widgetModelVersion=<null>(default), bandwidthTotal=<null>(default), vhnPortalUrl=<null>(default), serviceInstanceLocationId=<null>(default), resourceVersion=1615390958668, selflink=restconf/config/GENERIC-RESOURCE-API:services/service/55322ada-145a-492e-b196-0d171539a4b2/service-data/service-topology/, orchestrationStatus=Active, inputParameters=<null>(default), relationshipList=org.onap.aai.domain.yang.RelationshipList@34d92c6b[relationship={org.onap.aai.domain.yang.Relationship@4d89c772[relatedTo=generic-vnf, relationshipLabel=org.onap.relationships.inventory.ComposedOf, relatedLink=/aai/v21/network/generic-vnfs/generic-vnf/37b008b9-b367-4359-93fd-74d3ce0ee1a0, relationshipData={org.onap.aai.domain.yang.RelationshipData@472e3ea0[relationshipKey=generic-vnf.vnf-id, relationshipValue=37b008b9-b367-4359-93fd-74d3ce0ee1a0]}, relatedToProperty={org.onap.aai.domain.yang.RelatedToProperty@11a58c4b[propertyKey=generic-vnf.vnf-name, propertyValue=vfw-cnf-cds-demo-28-vnf]}]}], metadata=<null>(default), allottedResources=<null>(default), communicationServiceProfiles=<null>(default), serviceProfiles=<null>(default), sliceProfiles=<null>(default), serviceFunction=<null>(default)]
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - CDS.modify-config: set property event/additionalParams=null
16:08:29.067 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - CDS.modify-config: start operation attempt 1 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65||ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:29.066+00:00|2021-03-10T16:08:29.066+00:00|null|null:operational.modifyconfig.EVENT.MANAGER.PROCESS.GUARD.OUTCOME:1.0.0|dev-policy-drools-pdp-0|COMPLETE|300|Guard result for CDS modify-config is Permit|||VNF.generic-vnf.vnf-id||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65||ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:29.076+00:00|2021-03-10T16:08:29.076+00:00|null|null:operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.STARTED:1.0.0|dev-policy-drools-pdp-0|COMPLETE|300|actor=CDS,operation=modify-config,target=OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA}),subRequestId=0|||VNF.generic-vnf.vnf-id||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
16:08:29.073 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.c.a.impl.OperationPartial - CDS.modify-config: set timeout to 300000ms for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.074 [Thread-3358] INFO  o.o.p.c.a.p.ControlLoopOperationParams - started operation CDS.modify-config for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.075 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.STARTED: Step(actor=CDS, operation=modify-config) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:29.075 [Thread-32] INFO  o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record batch
16:08:29.075 [Thread-32] INFO  o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.076 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - org.onap.policy.controlloop.VirtualControlLoopNotificationMavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] class loader matches original drools controller rules classloader jdk.internal.loader.ClassLoaders$AppClassLoader@67424e82
16:08:29.076 [Thread-3359] INFO  o.o.p.c.a.impl.OperationPartial - not retrying operation GUARD.Decision for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.077 [Thread-32] INFO  o.o.p.c.o.OperationHistoryDataManagerImpl - update operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:29.077 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:29.078 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113]DELIVER: VirtualControlLoopNotification(aai={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null) FROM MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] TO InlineDmaapTopicSink [userName=null, password=null, getTopicCommInfrastructure()=DMAAP, toString()=InlineBusTopicSink [partitionId=9b9f56d5-5d4d-4df9-82ba-47c7e199c236, alive=true, publisher=CambriaPublisherWrapper []]]
16:08:29.078 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  network - [OUT|DMAAP|POLICY-CL-MGT]
{
  "AAI": {
    "generic-vnf.prov-status": "ACTIVE",
    "vserver.vserver-name": "viptela_scripts",
    "generic-vnf.is-closed-loop-disabled": "false",
    "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
  },
  "closedLoopAlarmStart": 1463679805324,
  "closedLoopControlName": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
  "version": "1.0.2",
  "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
  "closedLoopEventClient": "microservice.stringmatcher",
  "targetType": "VNF",
  "target": "generic-vnf.vnf-id",
  "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]",
  "policyName": "operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.STARTED",
  "policyVersion": "1.0.0",
  "notification": "OPERATION",
  "message": "actor=CDS,operation=modify-config,target=OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA}),subRequestId=0",
  "notificationTime": "2021-03-10 16:08:29.076110+00:00",
  "history": []
}
16:08:29.086 [Thread-3358] INFO  o.o.p.c.c.CdsProcessorGrpcClient - CdsProcessorListener started
16:08:29.086 [Thread-3358] INFO  o.o.p.cds.client.CdsProcessorHandler - Processing blueprint(1.0.0:vFW-CDS) for action(1.0.0)
16:08:29.087 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 2 msgs to /events/POLICY-CL-MGT. Oldest: 20 ms
16:08:29.087 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ...
16:08:29.088 [Thread-3358] INFO  network - [OUT|REST|gRPC://cds-blueprints-processor-grpc:9111/]
commonHeader {
  requestId: "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65"
  subRequestId: "0"
  originatorId: "POLICY"
}
actionIdentifiers {
  blueprintName: "vFW-CDS"
  blueprintVersion: "1.0.0"
  actionName: "modify-config"
  mode: "sync"
}
payload {
  fields {
    key: "modify-config-request"
    value {
      struct_value {
        fields {
          key: "modify-config-properties"
          value {
            struct_value {
              fields {
                key: "data"
                value {
                  struct_value {
                    fields {
                      key: "active-streams"
                      value {
                        string_value: "7"
                      }
                    }
                  }
                }
              }
              fields {
                key: "generic-vnf.vnf-id"
                value {
                  string_value: "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
                }
              }
              fields {
                key: "service-instance.service-instance-id"
                value {
                  string_value: "55322ada-145a-492e-b196-0d171539a4b2"
                }
              }
            }
          }
        }
        fields {
          key: "resolution-key"
          value {
            string_value: "27124b9f-9a31-4069-a3f0-58e0502c08ca"
          }
        }
      }
    }
  }
}

16:08:29.095 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient -      --> HTTP/1.1 200 OK
16:08:29.095 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (8 ms):{"serverTimeMs":1,"count":2}
16:08:30.251 [grpc-default-executor-1] INFO  network - [IN|REST|gRPC://cds-blueprints-processor-grpc:9111/]
commonHeader {
  timestamp: "2021-03-10T16:08:29.683Z"
  requestId: "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65"
  subRequestId: "0"
  flag {
  }
  originatorId: "POLICY"
}
actionIdentifiers {
  blueprintName: "vFW-CDS"
  blueprintVersion: "1.0.0"
  actionName: "modify-config"
  mode: "sync"
}
status {
  code: 200
  message: "success"
  eventType: EVENT_COMPONENT_EXECUTED
  timestamp: "Wed Mar 10 16:08:30 GMT 2021"
}
payload {
  fields {
    key: "modify-config-response"
    value {
      struct_value {
      }
    }
  }
}

16:08:30.251 [grpc-default-executor-1] INFO  o.o.p.c.a.cds.CdsActorServiceManager - Received notification from CDS: commonHeader {
  timestamp: "2021-03-10T16:08:29.683Z"
  requestId: "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65"
  subRequestId: "0"
  flag {
  }
  originatorId: "POLICY"
}
actionIdentifiers {
  blueprintName: "vFW-CDS"
  blueprintVersion: "1.0.0"
  actionName: "modify-config"
  mode: "sync"
}
status {
  code: 200
  message: "success"
  eventType: EVENT_COMPONENT_EXECUTED
  timestamp: "Wed Mar 10 16:08:30 GMT 2021"
}
payload {
  fields {
    key: "modify-config-response"
    value {
      struct_value {
      }
    }
  }
}

16:08:30.253 [grpc-default-executor-1] INFO  o.o.p.cds.client.CdsProcessorHandler - Completed blueprint(1.0.0:vFW-CDS) for action(1.0.0)
16:08:30.254 [Thread-3363] INFO  o.o.p.c.a.p.ControlLoopOperationParams - completed operation CDS.modify-config outcome=Success for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:30.254 [Thread-3362] INFO  o.o.p.c.c.CdsProcessorGrpcClient - CdsProcessorListener stopped
16:08:30.256 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.SUCCESS: Step(actor=CDS, operation=modify-config) manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:30.256 [Thread-32] INFO  o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record batch
16:08:30.256 [Thread-32] INFO  o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:30.256 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - org.onap.policy.controlloop.VirtualControlLoopNotificationMavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] class loader matches original drools controller rules classloader jdk.internal.loader.ClassLoaders$AppClassLoader@67424e82
16:08:30.257 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:30.257 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:30.257 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113]DELIVER: VirtualControlLoopNotification(aai={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null) FROM MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] TO InlineDmaapTopicSink [userName=null, password=null, getTopicCommInfrastructure()=DMAAP, toString()=InlineBusTopicSink [partitionId=9b9f56d5-5d4d-4df9-82ba-47c7e199c236, alive=true, publisher=CambriaPublisherWrapper []]]
16:08:30.257 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  network - [OUT|DMAAP|POLICY-CL-MGT]
{
  "AAI": {
    "generic-vnf.prov-status": "ACTIVE",
    "vserver.vserver-name": "viptela_scripts",
    "generic-vnf.is-closed-loop-disabled": "false",
    "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
  },
  "closedLoopAlarmStart": 1463679805324,
  "closedLoopControlName": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
  "version": "1.0.2",
  "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
  "closedLoopEventClient": "microservice.stringmatcher",
  "targetType": "VNF",
  "target": "generic-vnf.vnf-id",
  "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]",
  "policyName": "operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.SUCCESS",
  "policyVersion": "1.0.0",
  "notification": "OPERATION: SUCCESS",
  "message": "actor=CDS,operation=modify-config,target=OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA}),start=2021-03-10T16:08:29.074830Z,end=2021-03-10T16:08:30.254845Z,subRequestId=0,outcome=Success,message=null",
  "notificationTime": "2021-03-10 16:08:30.256547+00:00",
  "history": [
    {
      "actor": "CDS",
      "operation": "modify-config",
      "target": "OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA})",
      "start": 1615392509074,
      "end": 1615392510254,
      "subRequestId": "0",
      "outcome": "Success"
    }
  ]
}
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65|0|ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:29.074+00:00|2021-03-10T16:08:30.254+00:00|1180|null:operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.SUCCESS:1.0.0|dev-policy-drools-pdp-0|COMPLETE|301|Success:null|||VNF.generic-vnf.vnf-id|CDS.modify-config|dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}|||OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA})|INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65|0|ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:29.074+00:00|2021-03-10T16:08:30.254+00:00|1180|null:operational.modifyconfig.EVENT.MANAGER.PROCESS.POLICY.SUCCESS:1.0.0|dev-policy-drools-pdp-0|COMPLETE|301|Success:null|||VNF.generic-vnf.vnf-id|CDS.modify-config|dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}|||OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA})|INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
16:08:30.259 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.LOAD.NEXT.POLICY: manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:30.259 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  org.onap.policy.controlloop - ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a: operational.modifyconfig.EVENT.MANAGER.FINAL: manager=UsecasesEventManager(numOnsets=1, numAbatements=0)
16:08:30.259 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - org.onap.policy.controlloop.VirtualControlLoopNotificationMavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] class loader matches original drools controller rules classloader jdk.internal.loader.ClassLoaders$AppClassLoader@67424e82
16:08:30.259 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] WARN  o.o.policy.drools.stats.PolicyStats - policy transaction contains no elapsed time: Metric(requestId=c7c6a4aa-bb61-4a15-b831-ba1472dd4a65, partner=policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases], invocationId=null, virtualServerName=dev-policy-drools-pdp-0, serverName=dev-policy-drools-pdp-0, serverIpAddress=10.42.9.34, serverFqdn=dev-policy-drools-pdp-0, serviceName=ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a, startTime=2021-03-10T16:08:27.627208Z, endTime=2021-03-10T16:08:30.259619Z, elapsedTime=null, serviceInstanceId=null:operational.modifyconfig.EVENT.MANAGER.FINAL:1.0.0, instanceUuid=null, processKey={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, statusCode=COMPLETE, responseCode=401, responseDescription=null, alertSeverity=null, targetEntity=VNF.generic-vnf.vnf-id, targetServiceName=null, targetVirtualEntity=null, clientIpAddress=microservice.stringmatcher, remoteHost=null, customField1=FINAL_SUCCESS, customField2=null, customField3=null, customField4=null, success=true)
16:08:30.260 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.u.logging.MdcTransactionImpl -
16:08:30.260 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.a.c.f.t.CacheBasedControlLoopMetricsManager - REMOVAL: c7c6a4aa-bb61-4a15-b831-ba1472dd4a65 because of EXPLICIT
16:08:30.260 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  o.o.p.d.c.i.MavenDroolsController - MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113]DELIVER: VirtualControlLoopNotification(aai={generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null) FROM MavenDroolsController [policyContainer=org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:, alive=true, locked=, modelClassLoaderHash=50058113] TO InlineDmaapTopicSink [userName=null, password=null, getTopicCommInfrastructure()=DMAAP, toString()=InlineBusTopicSink [partitionId=9b9f56d5-5d4d-4df9-82ba-47c7e199c236, alive=true, publisher=CambriaPublisherWrapper []]]
16:08:30.260 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO  network - [OUT|DMAAP|POLICY-CL-MGT]
{
  "AAI": {
    "generic-vnf.prov-status": "ACTIVE",
    "vserver.vserver-name": "viptela_scripts",
    "generic-vnf.is-closed-loop-disabled": "false",
    "generic-vnf.vnf-id": "37b008b9-b367-4359-93fd-74d3ce0ee1a0"
  },
  "closedLoopAlarmStart": 1463679805324,
  "closedLoopControlName": "ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a",
  "version": "1.0.2",
  "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a65",
  "closedLoopEventClient": "microservice.stringmatcher",
  "targetType": "VNF",
  "target": "generic-vnf.vnf-id",
  "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]",
  "policyName": "operational.modifyconfig.EVENT.MANAGER.FINAL",
  "policyVersion": "1.0.0",
  "notification": "FINAL: SUCCESS",
  "notificationTime": "2021-03-10 16:08:30.259619+00:00",
  "history": [
    {
      "actor": "CDS",
      "operation": "modify-config",
      "target": "OperationalTarget(targetType=VNF, entityIds={resourceID=vTEST_VITELA})",
      "start": 1615392509074,
      "end": 1615392510254,
      "subRequestId": "0",
      "outcome": "Success"
    }
  ]
}
16:08:30.260 [Thread-32] INFO  o.o.p.c.o.OperationHistoryDataManagerImpl - update operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
16:08:30.260 [pool-3-thread-5] INFO  o.o.p.d.s.internal.FeatureLockImpl - releasing lock: DistributedLock [state=ACTIVE, resourceId=37b008b9-b367-4359-93fd-74d3ce0ee1a0, ownerKey=c7c6a4aa-bb61-4a15-b831-ba1472dd4a65, holdSec=1214, hostName=dev-policy-drools-pdp-0, uuidString=4621b92c-97a4-4c88-90ee-2ef976dfb023]
16:08:30.261 [Thread-3364] INFO  o.o.p.c.a.impl.OperationPartial - not retrying operation CDS.modify-config for c7c6a4aa-bb61-4a15-b831-ba1472dd4a65
c7c6a4aa-bb61-4a15-b831-ba1472dd4a65||ControlLoop-vFirewall-d0a1dfc6-94f5-4fd4-a5b5-4630b438850a|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:08:27.627+00:00|2021-03-10T16:08:30.259+00:00|2632|null:operational.modifyconfig.EVENT.MANAGER.FINAL:1.0.0|dev-policy-drools-pdp-0|COMPLETE|401||||VNF.generic-vnf.vnf-id||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.stringmatcher|{generic-vnf.prov-status=ACTIVE, vserver.vserver-name=viptela_scripts, generic-vnf.is-closed-loop-disabled=false, generic-vnf.vnf-id=37b008b9-b367-4359-93fd-74d3ce0ee1a0}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases|
16:08:30.287 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 2 msgs to /events/POLICY-CL-MGT. Oldest: 30 ms
16:08:30.287 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ...
16:08:30.295 [pool-8-thread-1] INFO  c.att.nsa.apiClient.http.HttpClient -      --> HTTP/1.1 200 OK
16:08:30.295 [pool-8-thread-1] INFO  c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (8 ms):{"serverTimeMs":0,"count":2}