16:11:27.194 [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:11:27.195 [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:11:27.202 [DMAAP-source-APPC-CL] INFO c.att.nsa.apiClient.http.HttpClient - --> HTTP/1.1 404 Not Found 16:11:27.203 [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:11:27.469 [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:11:27.469 [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:11:27.475 [DMAAP-source-APPC-LCM-WRITE] INFO c.att.nsa.apiClient.http.HttpClient - --> HTTP/1.1 404 Not Found 16:11:27.475 [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:11:31.989 [DMAAP-source-DCAE_TOPIC] INFO c.att.nsa.apiClient.http.HttpClient - --> HTTP/1.1 200 OK 16:11:31.992 [DMAAP-source-DCAE_TOPIC] INFO network - [IN|DMAAP|DCAE_TOPIC] {"closedLoopEventClient":"microservice.pmsh","requestID":"c7c6a4aa-bb61-4a15-b831-ba1472dd4a56","target_type":"PNF","AAI":{"pnf.pnf-name":"pnf300"},"from":"DCAE","closedLoopAlarmStart":1463679805324,"closedLoopEventStatus":"ONSET","closedLoopControlName":"ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b","version":"1.0.2","target":"pnf.pnf-name","additionalEventParams":{"nfName":"pnf300","administrativeState":"UNLOCKED","subscriptionName":"demo-subscription","fileBasedGP":"15","fileLocation":"test/pmx.xml"}} 16:11:31.993 [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:11:31.993 [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:11:31.993 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT: event=CanonicalOnset(super=Onset(super=VirtualControlLoopEvent(super=ControlLoopEvent(requestId=c7c6a4aa-bb61-4a15-b831-ba1472dd4a56, targetType=PNF, closedLoopControlName=ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b, version=1.0.2, closedLoopEventClient=microservice.pmsh, target=pnf.pnf-name, from=DCAE, policyScope=null, policyName=null, policyVersion=null, closedLoopEventStatus=ONSET, additionalEventParams={nfName=pnf300, administrativeState=UNLOCKED, subscriptionName=demo-subscription, fileBasedGP=15, fileLocation=test/pmx.xml}), aai={pnf.pnf-name=pnf300}, payload=null, closedLoopAlarmStart=2016-05-19T17:43:25.324Z, closedLoopAlarmEnd=null))) 16:11:31.994 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PREPROCESS: Step(actor=CDS, operation=create-subscription) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:31.994 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PREPROCESS: Step(actor=LOCK, operation=Lock) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:31.994 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PREPROCESS: Step(actor=pseudo/getTargetEntity, operation=pseudo/getTargetEntity) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:31.995 [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=pnf300 manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:31.996 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.EXECUTE.STEP: Step(actor=pseudo/getTargetEntity, operation=pseudo/getTargetEntity) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:31.996 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.ACCEPT: manager=Step(actor=LOCK, operation=Lock) 16:11:31.996 [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:11:31.996 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:31.996 [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={pnf.pnf-name=pnf300}, 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:11:31.997 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO network - [OUT|DMAAP|POLICY-CL-MGT] { "AAI": { "pnf.pnf-name": "pnf300" }, "closedLoopAlarmStart": 1463679805324, "closedLoopControlName": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b", "version": "1.0.2", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "closedLoopEventClient": "microservice.pmsh", "targetType": "PNF", "target": "pnf.pnf-name", "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]", "policyName": "operational.pnf.createsubscription.EVENT.MANAGER.ACCEPT", "policyVersion": "1.0.0", "notification": "ACTIVE", "notificationTime": "2021-03-10 16:11:31.996264+00:00", "history": [] } 16:11:31.997 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.EXECUTE.STEP: Step(actor=LOCK, operation=Lock) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) c7c6a4aa-bb61-4a15-b831-ba1472dd4a56||ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:31.996+00:00|2021-03-10T16:11:31.996+00:00|null|null:operational.pnf.createsubscription.EVENT.MANAGER.ACCEPT:1.0.0|dev-policy-drools-pdp-0|COMPLETE|100||||PNF.pnf.pnf-name||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| 16:11:32.001 [pool-3-thread-2] INFO o.o.p.d.s.internal.FeatureLockImpl - lock granted: DistributedLock [state=ACTIVE, resourceId=pnf300, ownerKey=c7c6a4aa-bb61-4a15-b831-ba1472dd4a56, holdSec=1214, hostName=dev-policy-drools-pdp-0, uuidString=4621b92c-97a4-4c88-90ee-2ef976dfb023] 16:11:32.001 [pool-3-thread-2] INFO o.o.p.c.eventmanager.LockData - lock granted on pnf300 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.002 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=LOCK, operation=Lock) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.003 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PREPROCESS: Step(actor=AAI, operation=Pnf) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.004 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PREPROCESS: Step(actor=pseudo/getTargetEntity, operation=pseudo/getTargetEntity) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.005 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.EXECUTE.STEP: Step(actor=pseudo/getTargetEntity, operation=pseudo/getTargetEntity) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.005 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.EXECUTE.STEP: Step(actor=AAI, operation=Pnf) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.005 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - AAI.Pnf: set property AAI/targetEntity=pnf300 16:11:32.006 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - AAI.Pnf: start operation attempt 1 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.006 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - AAI.Pnf: set timeout to 90000ms for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.006 [Thread-3371] INFO o.o.p.c.a.p.ControlLoopOperationParams - started operation AAI.Pnf for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.006 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.PREPROCESSOR.STARTED: Step(actor=AAI, operation=Pnf) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.007 [Thread-3371] INFO o.o.p.c.a.impl.OperationPartial - [OUT|REST|https://aai.onap:8443/aai/v21/network/pnfs/pnf/pnf300?depth=0|] null 16:11:32.007 [Thread-3371] INFO network - [OUT|REST|https://aai.onap:8443/aai/v21/network/pnfs/pnf/pnf300?depth=0] null 16:11:32.038 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 1 msgs to /events/POLICY-CL-MGT. Oldest: 41 ms 16:11:32.038 [pool-8-thread-1] INFO c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ... 16:11:32.080 [pool-8-thread-1] INFO c.att.nsa.apiClient.http.HttpClient - --> HTTP/1.1 200 OK 16:11:32.080 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (42 ms):{"serverTimeMs":0,"count":1} 16:11:32.142 [Thread-3372] INFO o.o.p.c.a.impl.HttpOperation - AAI.Pnf: response received for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.147 [Thread-3372] INFO o.o.p.c.a.impl.OperationPartial - [IN|REST|https://aai.onap:8443/aai/v21/network/pnfs/pnf/pnf300?depth=0|] {"pnf-name":"pnf300","pnf-name2-source":"example-pnf-name2-source-val-99275","pnf-id":"927b2580-36d9-4f13-8421-3c9d43b7a57e","equip-type":"example-equip-type-val-20348","equip-vendor":"example-equip-vendor-val-52182","equip-model":"example-equip-model-val-8370","management-option":"example-management-option-val-72881","orchestration-status":"Active","ipaddress-v4-oam":"10.12.6.161","in-maint":false,"ipaddress-v6-oam":"","resource-version":"1615388858275"} 16:11:32.147 [Thread-3372] INFO network - [IN|REST|https://aai.onap:8443/aai/v21/network/pnfs/pnf/pnf300?depth=0] {"pnf-name":"pnf300","pnf-name2-source":"example-pnf-name2-source-val-99275","pnf-id":"927b2580-36d9-4f13-8421-3c9d43b7a57e","equip-type":"example-equip-type-val-20348","equip-vendor":"example-equip-vendor-val-52182","equip-model":"example-equip-model-val-8370","management-option":"example-management-option-val-72881","orchestration-status":"Active","ipaddress-v4-oam":"10.12.6.161","in-maint":false,"ipaddress-v6-oam":"","resource-version":"1615388858275"} 16:11:32.147 [Thread-3372] INFO o.o.p.c.a.impl.HttpOperation - AAI.Pnf request succeeded for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.153 [Thread-3375] INFO o.o.p.c.a.p.ControlLoopOperationParams - completed operation AAI.Pnf outcome=Success for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.153 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=AAI, operation=Pnf) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.153 [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.Pnf.pnf300=org.onap.policy.common.utils.coder.StandardCoderObject@16ccfab8 manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.153 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PREPROCESS: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.154 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.EXECUTE.STEP: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.154 [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-ba1472dd4a56 16:11:32.157 [Thread-3376] INFO o.o.p.c.a.impl.OperationPartial - not retrying operation AAI.Pnf for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.157 [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-ba1472dd4a56 16:11:32.157 [Thread-3377] INFO o.o.p.c.a.p.ControlLoopOperationParams - started operation GUARD.Decision for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.158 [Thread-3377] 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": "5ed48947-bb0a-4f7e-a2b7-11cafbbd92aa", "action": "guard", "resource": { "guard": { "actor": "CDS", "operation": "create-subscription", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "clname": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b" } } } 16:11:32.159 [Thread-3377] INFO network - [OUT|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision] { "ONAPName": "Policy", "ONAPComponent": "Drools PDP", "ONAPInstance": "Usecases", "requestId": "5ed48947-bb0a-4f7e-a2b7-11cafbbd92aa", "action": "guard", "resource": { "guard": { "actor": "CDS", "operation": "create-subscription", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "clname": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b" } } } 16:11:32.159 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.GUARD.OUTCOME: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.159 [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:11:32.159 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:32.159 [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={pnf.pnf-name=pnf300}, 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:11:32.160 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO network - [OUT|DMAAP|POLICY-CL-MGT] { "AAI": { "pnf.pnf-name": "pnf300" }, "closedLoopAlarmStart": 1463679805324, "closedLoopControlName": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b", "version": "1.0.2", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "closedLoopEventClient": "microservice.pmsh", "targetType": "PNF", "target": "pnf.pnf-name", "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]", "policyName": "operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.GUARD.OUTCOME", "policyVersion": "1.0.0", "notification": "OPERATION", "message": "Sending guard query for CDS create-subscription", "notificationTime": "2021-03-10 16:11:32.159378+00:00", "history": [] } 16:11:32.160 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.PREPROCESSOR.STARTED: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) c7c6a4aa-bb61-4a15-b831-ba1472dd4a56||ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:32.159+00:00|2021-03-10T16:11:32.159+00:00|null|null:operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.GUARD.OUTCOME:1.0.0|dev-policy-drools-pdp-0|COMPLETE|300|Sending guard query for CDS create-subscription|||PNF.pnf.pnf-name||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| 16:11:32.196 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 1 msgs to /events/POLICY-CL-MGT. Oldest: 36 ms 16:11:32.197 [pool-8-thread-1] INFO c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ... 16:11:32.203 [pool-8-thread-1] INFO c.att.nsa.apiClient.http.HttpClient - --> HTTP/1.1 200 OK 16:11:32.203 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (7 ms):{"serverTimeMs":0,"count":1} 16:11:32.216 [Thread-3378] INFO o.o.p.c.a.impl.HttpOperation - GUARD.Decision: response received for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.218 [Thread-3378] INFO o.o.p.c.a.impl.OperationPartial - [IN|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision|] {"status":"Permit"} 16:11:32.218 [Thread-3378] INFO network - [IN|REST|https://policy-xacml-pdp:6969/policy/pdpx/v1/decision] {"status":"Permit"} 16:11:32.218 [Thread-3378] INFO o.o.p.c.a.impl.HttpOperation - GUARD.Decision request succeeded for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.219 [Thread-3381] INFO o.o.p.c.a.p.ControlLoopOperationParams - completed operation GUARD.Decision outcome=Success for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.220 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.GUARD.OUTCOME: Step(actor=GUARD, operation=Decision) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.220 [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:11:32.220 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:32.220 [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={pnf.pnf-name=pnf300}, 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:11:32.220 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO network - [OUT|DMAAP|POLICY-CL-MGT] { "AAI": { "pnf.pnf-name": "pnf300" }, "closedLoopAlarmStart": 1463679805324, "closedLoopControlName": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b", "version": "1.0.2", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "closedLoopEventClient": "microservice.pmsh", "targetType": "PNF", "target": "pnf.pnf-name", "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]", "policyName": "operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.GUARD.OUTCOME", "policyVersion": "1.0.0", "notification": "OPERATION", "message": "Guard result for CDS create-subscription is Permit", "notificationTime": "2021-03-10 16:11:32.220209+00:00", "history": [] } 16:11:32.221 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.DISCARD.OUTCOME: Step(actor=GUARD, operation=Decision) Success manager=UsecasesEventManager(numOnsets=1, numAbatements=0) c7c6a4aa-bb61-4a15-b831-ba1472dd4a56||ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:32.220+00:00|2021-03-10T16:11:32.220+00:00|null|null:operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.GUARD.OUTCOME:1.0.0|dev-policy-drools-pdp-0|COMPLETE|300|Guard result for CDS create-subscription is Permit|||PNF.pnf.pnf-name||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| 16:11:32.230 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.EXECUTE.STEP: Step(actor=CDS, operation=create-subscription) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.230 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - CDS.create-subscription: set property AAI/pnf=org.onap.policy.common.utils.coder.StandardCoderObject@16ccfab8 16:11:32.230 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - CDS.create-subscription: set property event/additionalParams={nfName=pnf300, administrativeState=UNLOCKED, subscriptionName=demo-subscription, fileBasedGP=15, fileLocation=test/pmx.xml} 16:11:32.230 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - CDS.create-subscription: start operation attempt 1 for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.231 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.c.a.impl.OperationPartial - CDS.create-subscription: set timeout to 300000ms for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.231 [Thread-3383] INFO o.o.p.c.a.p.ControlLoopOperationParams - started operation CDS.create-subscription for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.231 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.POLICY.STARTED: Step(actor=CDS, operation=create-subscription) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:32.232 [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:11:32.233 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:32.233 [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={pnf.pnf-name=pnf300}, 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:11:32.233 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO network - [OUT|DMAAP|POLICY-CL-MGT] { "AAI": { "pnf.pnf-name": "pnf300" }, "closedLoopAlarmStart": 1463679805324, "closedLoopControlName": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b", "version": "1.0.2", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "closedLoopEventClient": "microservice.pmsh", "targetType": "PNF", "target": "pnf.pnf-name", "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]", "policyName": "operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.POLICY.STARTED", "policyVersion": "1.0.0", "notification": "OPERATION", "message": "actor=CDS,operation=create-subscription,target=OperationalTarget(targetType=PNF, entityIds=null),subRequestId=0", "notificationTime": "2021-03-10 16:11:32.232166+00:00", "history": [] } 16:11:32.232 [Thread-32] INFO o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record batch 16:11:32.240 [Thread-32] INFO o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.241 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 2 msgs to /events/POLICY-CL-MGT. Oldest: 21 ms 16:11:32.241 [pool-8-thread-1] INFO c.att.nsa.apiClient.http.HttpClient - POST https://message-router:3905/events/POLICY-CL-MGT (anonymous) ... 16:11:32.243 [Thread-32] INFO o.o.p.c.o.OperationHistoryDataManagerImpl - update operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.271 [pool-8-thread-1] INFO c.att.nsa.apiClient.http.HttpClient - --> HTTP/1.1 200 OK 16:11:32.271 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - cambria reply ok (30 ms):{"serverTimeMs":0,"count":2} c7c6a4aa-bb61-4a15-b831-ba1472dd4a56||ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:32.232+00:00|2021-03-10T16:11:32.233+00:00|null|null:operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.POLICY.STARTED:1.0.0|dev-policy-drools-pdp-0|COMPLETE|300|actor=CDS,operation=create-subscription,target=OperationalTarget(targetType=PNF, entityIds=null),subRequestId=0|||PNF.pnf.pnf-name||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| 16:11:32.282 [Thread-3382] INFO o.o.p.c.a.impl.OperationPartial - not retrying operation GUARD.Decision for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:32.313 [Thread-3383] INFO o.o.p.c.c.CdsProcessorGrpcClient - CdsProcessorListener started 16:11:32.313 [Thread-3383] INFO o.o.p.cds.client.CdsProcessorHandler - Processing blueprint(1.0.0:pm_control) for action(1.0.0) 16:11:32.323 [Thread-3383] INFO network - [OUT|REST|gRPC://cds-blueprints-processor-grpc:9111/] commonHeader { requestId: "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56" subRequestId: "0" originatorId: "POLICY" } actionIdentifiers { blueprintName: "pm_control" blueprintVersion: "1.0.0" actionName: "create-subscription" mode: "sync" } payload { fields { key: "create-subscription-request" value { struct_value { fields { key: "create-subscription-properties" value { struct_value { fields { key: "administrativeState" value { string_value: "UNLOCKED" } } fields { key: "data" value { string_value: "" } } fields { key: "fileBasedGP" value { string_value: "15" } } fields { key: "fileLocation" value { string_value: "test/pmx.xml" } } fields { key: "nfName" value { string_value: "pnf300" } } fields { key: "pnf.equip-model" value { string_value: "example-equip-model-val-8370" } } fields { key: "pnf.equip-type" value { string_value: "example-equip-type-val-20348" } } fields { key: "pnf.equip-vendor" value { string_value: "example-equip-vendor-val-52182" } } fields { key: "pnf.in-maint" value { string_value: "false" } } fields { key: "pnf.ipaddress-v4-oam" value { string_value: "10.12.6.161" } } fields { key: "pnf.ipaddress-v6-oam" value { string_value: "" } } fields { key: "pnf.management-option" value { string_value: "example-management-option-val-72881" } } fields { key: "pnf.orchestration-status" value { string_value: "Active" } } fields { key: "pnf.pnf-id" value { string_value: "927b2580-36d9-4f13-8421-3c9d43b7a57e" } } fields { key: "pnf.pnf-name" value { string_value: "pnf300" } } fields { key: "pnf.pnf-name2-source" value { string_value: "example-pnf-name2-source-val-99275" } } fields { key: "pnf.resource-version" value { string_value: "1615388858275" } } fields { key: "subscriptionName" value { string_value: "demo-subscription" } } } } } fields { key: "resolution-key" value { string_value: "dea3d2b8-1d8c-4bb6-9473-fdb4aaf00c0c" } } } } } } 16:11:33.039 [grpc-default-executor-0] INFO network - [IN|REST|gRPC://cds-blueprints-processor-grpc:9111/] commonHeader { timestamp: "2021-03-10T16:11:32.454Z" requestId: "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56" subRequestId: "0" flag { } originatorId: "POLICY" } actionIdentifiers { blueprintName: "pm_control" blueprintVersion: "1.0.0" actionName: "create-subscription" mode: "sync" } status { code: 500 errorMessage: "sdnc-oam" message: "failure" timestamp: "Wed Mar 10 16:11:33 GMT 2021" } payload { fields { key: "create-subscription-request" value { struct_value { fields { key: "create-subscription-properties" value { struct_value { fields { key: "administrativeState" value { string_value: "UNLOCKED" } } fields { key: "data" value { string_value: "" } } fields { key: "fileBasedGP" value { string_value: "15" } } fields { key: "fileLocation" value { string_value: "test/pmx.xml" } } fields { key: "nfName" value { string_value: "pnf300" } } fields { key: "pnf.equip-model" value { string_value: "example-equip-model-val-8370" } } fields { key: "pnf.equip-type" value { string_value: "example-equip-type-val-20348" } } fields { key: "pnf.equip-vendor" value { string_value: "example-equip-vendor-val-52182" } } fields { key: "pnf.in-maint" value { string_value: "false" } } fields { key: "pnf.ipaddress-v4-oam" value { string_value: "10.12.6.161" } } fields { key: "pnf.ipaddress-v6-oam" value { string_value: "" } } fields { key: "pnf.management-option" value { string_value: "example-management-option-val-72881" } } fields { key: "pnf.orchestration-status" value { string_value: "Active" } } fields { key: "pnf.pnf-id" value { string_value: "927b2580-36d9-4f13-8421-3c9d43b7a57e" } } fields { key: "pnf.pnf-name" value { string_value: "pnf300" } } fields { key: "pnf.pnf-name2-source" value { string_value: "example-pnf-name2-source-val-99275" } } fields { key: "pnf.resource-version" value { string_value: "1615388858275" } } fields { key: "subscriptionName" value { string_value: "demo-subscription" } } } } } fields { key: "resolution-key" value { string_value: "dea3d2b8-1d8c-4bb6-9473-fdb4aaf00c0c" } } } } } } 16:11:33.039 [grpc-default-executor-0] INFO o.o.p.c.a.cds.CdsActorServiceManager - Received notification from CDS: commonHeader { timestamp: "2021-03-10T16:11:32.454Z" requestId: "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56" subRequestId: "0" flag { } originatorId: "POLICY" } actionIdentifiers { blueprintName: "pm_control" blueprintVersion: "1.0.0" actionName: "create-subscription" mode: "sync" } status { code: 500 errorMessage: "sdnc-oam" message: "failure" timestamp: "Wed Mar 10 16:11:33 GMT 2021" } payload { fields { key: "create-subscription-request" value { struct_value { fields { key: "create-subscription-properties" value { struct_value { fields { key: "administrativeState" value { string_value: "UNLOCKED" } } fields { key: "data" value { string_value: "" } } fields { key: "fileBasedGP" value { string_value: "15" } } fields { key: "fileLocation" value { string_value: "test/pmx.xml" } } fields { key: "nfName" value { string_value: "pnf300" } } fields { key: "pnf.equip-model" value { string_value: "example-equip-model-val-8370" } } fields { key: "pnf.equip-type" value { string_value: "example-equip-type-val-20348" } } fields { key: "pnf.equip-vendor" value { string_value: "example-equip-vendor-val-52182" } } fields { key: "pnf.in-maint" value { string_value: "false" } } fields { key: "pnf.ipaddress-v4-oam" value { string_value: "10.12.6.161" } } fields { key: "pnf.ipaddress-v6-oam" value { string_value: "" } } fields { key: "pnf.management-option" value { string_value: "example-management-option-val-72881" } } fields { key: "pnf.orchestration-status" value { string_value: "Active" } } fields { key: "pnf.pnf-id" value { string_value: "927b2580-36d9-4f13-8421-3c9d43b7a57e" } } fields { key: "pnf.pnf-name" value { string_value: "pnf300" } } fields { key: "pnf.pnf-name2-source" value { string_value: "example-pnf-name2-source-val-99275" } } fields { key: "pnf.resource-version" value { string_value: "1615388858275" } } fields { key: "subscriptionName" value { string_value: "demo-subscription" } } } } } fields { key: "resolution-key" value { string_value: "dea3d2b8-1d8c-4bb6-9473-fdb4aaf00c0c" } } } } } } 16:11:33.041 [grpc-default-executor-0] INFO o.o.p.cds.client.CdsProcessorHandler - Completed blueprint(1.0.0:pm_control) for action(1.0.0) 16:11:33.043 [Thread-3387] INFO o.o.p.c.a.p.ControlLoopOperationParams - completed operation CDS.create-subscription outcome=Failure for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:33.044 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.FINAL.FAILURE.ACCEPTED: Step(actor=CDS, operation=create-subscription) manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:33.044 [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:11:33.044 [Thread-32] INFO o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record batch 16:11:33.047 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:33.047 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:33.047 [Thread-3386] INFO o.o.p.c.c.CdsProcessorGrpcClient - CdsProcessorListener stopped 16:11:33.047 [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={pnf.pnf-name=pnf300}, 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:11:33.047 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO network - [OUT|DMAAP|POLICY-CL-MGT] { "AAI": { "pnf.pnf-name": "pnf300" }, "closedLoopAlarmStart": 1463679805324, "closedLoopControlName": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b", "version": "1.0.2", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "closedLoopEventClient": "microservice.pmsh", "targetType": "PNF", "target": "pnf.pnf-name", "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]", "policyName": "operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.FINAL.FAILURE.ACCEPTED", "policyVersion": "1.0.0", "notification": "OPERATION: FAILURE", "message": "actor=CDS,operation=create-subscription,target=OperationalTarget(targetType=PNF, entityIds=null),start=2021-03-10T16:11:32.231274Z,end=2021-03-10T16:11:33.043783Z,subRequestId=0,outcome=Failure,message=null", "notificationTime": "2021-03-10 16:11:33.044745+00:00", "history": [ { "actor": "CDS", "operation": "create-subscription", "target": "OperationalTarget(targetType=PNF, entityIds=null)", "start": 1615392692231, "end": 1615392693043, "subRequestId": "0", "outcome": "Failure" } ] } 16:11:33.047 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.LOAD.NEXT.POLICY: manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:33.047 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO org.onap.policy.controlloop - ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b: operational.pnf.createsubscription.EVENT.MANAGER.FINAL: manager=UsecasesEventManager(numOnsets=1, numAbatements=0) 16:11:33.047 [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:11:33.047 [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-ba1472dd4a56, 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-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b, startTime=2021-03-10T16:11:31.996264Z, endTime=2021-03-10T16:11:33.047542Z, elapsedTime=null, serviceInstanceId=null:operational.pnf.createsubscription.EVENT.MANAGER.FINAL:1.0.0, instanceUuid=null, processKey={pnf.pnf-name=pnf300}, statusCode=ERROR, responseCode=400, responseDescription=null, alertSeverity=null, targetEntity=PNF.pnf.pnf-name, targetServiceName=null, targetVirtualEntity=null, clientIpAddress=microservice.pmsh, remoteHost=null, customField1=FINAL_FAILURE, customField2=null, customField3=null, customField4=null, success=false) 16:11:33.047 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO o.o.p.d.u.logging.MdcTransactionImpl - 16:11:33.047 [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-ba1472dd4a56 because of EXPLICIT 16:11:33.047 [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={pnf.pnf-name=pnf300}, 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:11:33.045 [Thread-32] INFO o.o.p.c.o.OperationHistoryDataManagerImpl - store operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:33.050 [Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases] INFO network - [OUT|DMAAP|POLICY-CL-MGT] { "AAI": { "pnf.pnf-name": "pnf300" }, "closedLoopAlarmStart": 1463679805324, "closedLoopControlName": "ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b", "version": "1.0.2", "requestId": "c7c6a4aa-bb61-4a15-b831-ba1472dd4a56", "closedLoopEventClient": "microservice.pmsh", "targetType": "PNF", "target": "pnf.pnf-name", "from": "policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]", "policyName": "operational.pnf.createsubscription.EVENT.MANAGER.FINAL", "policyVersion": "1.0.0", "notification": "FINAL: FAILURE", "notificationTime": "2021-03-10 16:11:33.047542+00:00", "history": [ { "actor": "CDS", "operation": "create-subscription", "target": "OperationalTarget(targetType=PNF, entityIds=null)", "start": 1615392692231, "end": 1615392693043, "subRequestId": "0", "outcome": "Failure" } ] } 16:11:33.050 [pool-3-thread-3] INFO o.o.p.d.s.internal.FeatureLockImpl - releasing lock: DistributedLock [state=ACTIVE, resourceId=pnf300, ownerKey=c7c6a4aa-bb61-4a15-b831-ba1472dd4a56, holdSec=1214, hostName=dev-policy-drools-pdp-0, uuidString=4621b92c-97a4-4c88-90ee-2ef976dfb023] 16:11:33.054 [Thread-32] INFO o.o.p.c.o.OperationHistoryDataManagerImpl - update operation history record for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 c7c6a4aa-bb61-4a15-b831-ba1472dd4a56|0|ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:32.231+00:00|2021-03-10T16:11:33.043+00:00|812|null:operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.FINAL.FAILURE.ACCEPTED:1.0.0|dev-policy-drools-pdp-0|ERROR|302|Failure:null|||PNF.pnf.pnf-name|CDS.create-subscription|dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}|||OperationalTarget(targetType=PNF, entityIds=null)|INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| c7c6a4aa-bb61-4a15-b831-ba1472dd4a56|0|ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:32.231+00:00|2021-03-10T16:11:33.043+00:00|812|null:operational.pnf.createsubscription.EVENT.MANAGER.PROCESS.FINAL.FAILURE.ACCEPTED:1.0.0|dev-policy-drools-pdp-0|ERROR|302|Failure:null|||PNF.pnf.pnf-name|CDS.create-subscription|dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}|||OperationalTarget(targetType=PNF, entityIds=null)|INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| c7c6a4aa-bb61-4a15-b831-ba1472dd4a56||ControlLoop-pnf-d0a1dfc6-94f5-4fd4-a5b5-4630b438851b|policy:usecases:[org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases]|2021-03-10T16:11:31.996+00:00|2021-03-10T16:11:33.047+00:00|1051|null:operational.pnf.createsubscription.EVENT.MANAGER.FINAL:1.0.0|dev-policy-drools-pdp-0|ERROR|400||||PNF.pnf.pnf-name||dev-policy-drools-pdp-0||dev-policy-drools-pdp-0|microservice.pmsh|{pnf.pnf-name=pnf300}||||INFO|Session org.onap.policy.drools-applications.controlloop.common:controller-usecases:1.8.1-SNAPSHOT:usecases| 16:11:33.068 [Thread-3388] INFO o.o.p.c.a.impl.OperationPartial - operation CDS.create-subscription no retries for c7c6a4aa-bb61-4a15-b831-ba1472dd4a56 16:11:33.093 [pool-8-thread-1] INFO c.a.n.c.c.i.CambriaSimplerBatchPublisher - sending 2 msgs to /events/POLICY-CL-MGT. Oldest: 46 ms 1