Step 1 - No message on the DMAAP topic Acceptedvv770d@pod-onap-01-vjhost:~$ curl http://10.12.7.7:30227/events/unauthenticated.SEC_FAULT_OUTPUT/vv/1 [] Step 2 - Simulate a FAULT event into VES Collector vv770d@pod-onap-01-vjhost:~curl -i -u 'sample1:sample1' -X POST -d @fault.txt --header "Content-Type: application/json" http://10.12.7.7:30235//eventListener/v5 -k HTTP/1.1 202 Content-Type: application/json Content-Length: 8 Date: Wed, 24 Oct 2018 18:50:22 GMT Accepted Step 3 - Verify message output into SEC_FAULT_OUTPUT topci vv770d@pod-onap-01-vjhost:http://10.12.7.7:30227/events/unauthenticated.SEC_FAULT_OUTPUT/vv/1er/v5 -k ["{\"event\":{\"commonEventHeader\":{\"startEpochMicrosec\":1517517969993851,\"sourceId\":\"6e138fd1-8900-4e90-aef4-c44ec7c49a78\",\"eventId\":\"Fault_vDns_SvcDown_127.0.0.1\",\"nfcNamingCode\":\"DNS\",\"reportingEntityId\":\"6e138fd1-8900-4e90-aef4-c44ec7c49a78\",\"internalHeaderFields\":{\"collectorTimeStamp\":\"Wed, 10 24 2018 06:50:22 UTC\"},\"eventType\":\"applicationVnf\",\"priority\":\"High\",\"version\":3,\"reportingEntityName\":\"pocnjafx41\",\"sequence\":3,\"domain\":\"fault\",\"lastEpochMicrosec\":1517517969993851,\"eventName\":\"Fault_vDns_SvcDown\",\"sourceName\":\"pocnjafx41\",\"nfNamingCode\":\"FAPN\"},\"faultFields\":{\"eventSeverity\":\"MAJOR\",\"alarmCondition\":\"AFX services input down alarm\",\"faultFieldsVersion\":2.1,\"eventCategory\":\"routing\",\"specificProblem\":\"\",\"alarmInterfaceA\":\"\",\"eventSourceType\":\"router\",\"vfStatus\":\"Active\"}}}"]vv770d@pod-onap-01-vjhost:~$ curl http://10.12.7.7:30227/events/unauthenticated.SEC_FAULT_OUT-i -u 'sample1:sample1' -X POST -d @fault.txt --header "Content-Type: application/json" http://10.12.7.7:30235//eventListener/v5 -k HTTP/1.1 202 Content-Type: application/json Content-Length: 8 Date: Wed, 24 Oct 2018 18:51:35 GMT Step 4 - Holmes rules should pick up this message and process 2018-10-24 18:50:22 749 DEBUG [org.apache.http.impl.conn.PoolingHttpClientConnectionManager][Thread-15] invocationID:{InvocationID} - Connection [id: 82][route: {}->http://message-router.onap:3904] can be kept alive indefinitely 2018-10-24 18:50:22 749 DEBUG [org.apache.http.impl.conn.PoolingHttpClientConnectionManager][Thread-15] invocationID:{InvocationID} - Connection released: [id: 82][route: {}->http://message-router.onap:3904][total kept alive: 1; route allocated: 1 of 2; total allocated: 1 of 200] 2018-10-24 18:50:22 749 DEBUG [org.apache.http.impl.execchain.MainClientExec][Thread-15] invocationID:{InvocationID} - Cancelling request execution 2018-10-24 18:50:22 755 TRACE [org.skife.jdbi.v2.DBI][Thread-15] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@ab41898] obtained in 0 millis 2018-10-24 18:50:22 773 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - simple execute, handler=org.postgresql.jdbc.PgStatement$StatementResultHandler@17da9917, maxRows=0, fetchSize=0, flags=81 2018-10-24 18:50:22 773 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - FE=> Parse(stmt=null,query="/* AlarmInfoDao.addAlarm */ INSERT INTO ALARM_INFO (EVENTID,EVENTNAME,STARTEPOCHMICROSEC,SOURCEID,SOURCENAME,ALARMISCLEARED,ROOTFLAG,LASTEPOCHMICROSEC) VALUES ($1,$2,$3,$4,$5,$6,$7,$8) RETURNING *",oids={1043,1043,20,1043,1043,23,23,20}) 2018-10-24 18:50:22 773 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - FE=> Bind(stmt=null,portal=null,$1=<'Fault_vDns_SvcDown_127.0.0.1'>,type=VARCHAR,$2=<'Fault_vDns_SvcDown'>,type=VARCHAR,$3=<1517517969993851>,type=INT8,$4=<'6e138fd1-8900-4e90-aef4-c44ec7c49a78'>,type=VARCHAR,$5=<'pocnjafx41'>,type=VARCHAR,$6=<0>,type=INT4,$7=<0>,type=INT4,$8=<1517517969993851>,type=INT8) 2018-10-24 18:50:22 774 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - FE=> Describe(portal=null) 2018-10-24 18:50:22 774 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - FE=> Execute(portal=null,limit=0) 2018-10-24 18:50:22 774 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - FE=> Sync 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - <=BE ParseComplete [null] 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - <=BE BindComplete [unnamed] 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - <=BE RowDescription(8) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(eventid,VARCHAR,65535,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(eventname,VARCHAR,65535,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(alarmiscleared,INT2,2,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(rootflag,INT2,2,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(startepochmicrosec,INT8,8,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(lastepochmicrosec,INT8,8,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(sourceid,VARCHAR,65535,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - Field(sourcename,VARCHAR,65535,T) 2018-10-24 18:50:22 781 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - <=BE DataRow(len=126) 2018-10-24 18:50:22 782 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - <=BE CommandStatus(INSERT 0 1) 2018-10-24 18:50:22 782 TRACE [org.postgresql.core.v3.QueryExecutorImpl][Thread-15] invocationID:{InvocationID} - <=BE ReadyForQuery(I) 2018-10-24 18:50:22 782 TRACE [org.skife.jdbi.v2.DBI][Thread-15] invocationID:{InvocationID} - statement:[/* AlarmInfoDao.addAlarm */ INSERT INTO ALARM_INFO (EVENTID,EVENTNAME,STARTEPOCHMICROSEC,SOURCEID,SOURCENAME,ALARMISCLEARED,ROOTFLAG,LASTEPOCHMICROSEC) VALUES (?,?,?,?,?,?,?,?)] took 9 millis 2018-10-24 18:50:22 782 DEBUG [javax.management.mbeanserver][Thread-15] invocationID:{InvocationID} - ObjectName = metrics:name=org.onap.holmes.engine.db.AlarmInfoDao.addAlarm 2018-10-24 18:50:22 782 DEBUG [javax.management.mbeanserver][Thread-15] invocationID:{InvocationID} - name = metrics:name=org.onap.holmes.engine.db.AlarmInfoDao.addAlarm 2018-10-24 18:50:22 782 DEBUG [javax.management.mbeanserver][Thread-15] invocationID:{InvocationID} - Send create notification of object metrics:name=org.onap.holmes.engine.db.AlarmInfoDao.addAlarm 2018-10-24 18:50:22 782 DEBUG [javax.management.mbeanserver][Thread-15] invocationID:{InvocationID} - JMX.mbean.registered metrics:name=org.onap.holmes.engine.db.AlarmInfoDao.addAlarm 2018-10-24 18:50:22 784 TRACE [org.postgresql.jdbc.PgConnection][Thread-15] invocationID:{InvocationID} - getString columnIndex: 1 2018-10-24 18:50:22 784 TRACE [org.skife.jdbi.v2.DBI][Thread-15] invocationID:{InvocationID} - Handle [org.skife.jdbi.v2.BasicHandle@ab41898] released 2018-10-24 18:50:22 786 TRACE [org.drools.core.reteoo.EntryPointNode][Thread-15] invocationID:{InvocationID} - Insert [fact 0:1:1910279501:496382052:1:DEFAULT:NON_TRAIT:org.onap.holmes.common.api.stat.VesAlarm:org.onap.holmes.common.api.stat.VesAlarm@1d963064] 2018-10-24 18:50:22 788 TRACE [org.drools.core.common.DefaultAgenda][Thread-15] invocationID:{InvocationID} - State was INACTIVE is now FIRING_ALL_RULES 2018-10-24 18:50:22 788 TRACE [org.drools.core.common.DefaultAgenda][Thread-15] invocationID:{InvocationID} - Starting Fire All Rules 2018-10-24 18:50:22 813 TRACE [org.drools.core.reteoo.SegmentMemory][Thread-15] invocationID:{InvocationID} - LinkNode notify=true nmask=1 smask=1 spos=0 rules=[RuleMem root_has_child_handle_Rule] 2018-10-24 18:50:22 825 TRACE [org.drools.core.reteoo.BetaNode][Thread-15] invocationID:{InvocationID} - BetaNode stagedInsertWasEmpty=true 2018-10-24 18:50:22 826 TRACE [org.drools.core.reteoo.SegmentMemory][Thread-15] invocationID:{InvocationID} - LinkNode notify=true nmask=2 smask=3 spos=0 rules=[RuleMem root_has_child_handle_Rule] 2018-10-24 18:50:22 826 TRACE [org.drools.core.reteoo.PathMemory][Thread-15] invocationID:{InvocationID} - LinkSegment smask=1 rmask=1 name=root_has_child_handle_Rule 2018-10-24 18:50:22 993 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_333,335,814989927) 2018-10-24 18:50:22 993 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_333,335,814989927) has been shutdown 2018-10-24 18:50:22 993 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_332,334,323666672) 2018-10-24 18:50:22 993 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_332,334,323666672) has been shutdown 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_331,333,1228756726) 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_331,333,1228756726) has been shutdown 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_330,332,1475486686) 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_330,332,1475486686) has been shutdown 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_318,320,2080439878) 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_318,320,2080439878) has been shutdown 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_314,316,322663138) 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_314,316,322663138) has been shutdown 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_303,305,406734525) 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_303,305,406734525) has been shutdown 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_232,234,121149710) 2018-10-24 18:50:22 994 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_232,234,121149710) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_228,230,1022531757) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_228,230,1022531757) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_224,226,958423398) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_224,226,958423398) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_220,222,1580477582) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_220,222,1580477582) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_213,215,1634080469) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_213,215,1634080469) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_209,211,1707579346) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_209,211,1707579346) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_202,204,84130332) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_202,204,84130332) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_198,200,1992088617) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_198,200,1992088617) has been shutdown 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - Shutdown ServiceLocator ServiceLocatorImpl(__HK2_Generated_191,193,1981133368) 2018-10-24 18:50:22 995 DEBUG [org.jvnet.hk2.logger][Finalizer] invocationID:{InvocationID} - ServiceLocator ServiceLocatorImpl(__HK2_Generated_191,193,1981133368) has been shutdown 2018-10-24 18:50:23 008 TRACE [org.drools.core.reteoo.PathMemory][Thread-15] invocationID:{InvocationID} - LinkRule name=root_has_child_handle_Rule 2018-10-24 18:50:23 008 TRACE [org.drools.core.reteoo.PathMemory][Thread-15] invocationID:{InvocationID} - Queue RuleAgendaItem [Activation rule=root_has_child_handle_Rule, act#=0, salience=150, tuple=null] 2018-10-24 18:50:23 008 TRACE [org.drools.core.util.BinaryHeapQueue][Thread-15] invocationID:{InvocationID} - Queue Added 1 [Activation rule=root_has_child_handle_Rule, act#=0, salience=150, tuple=null] 2018-10-24 18:50:23 008 TRACE [org.drools.core.common.DefaultAgenda][Thread-15] invocationID:{InvocationID} - Added root_has_child_handle_Rule to eager evaluation list. 2018-10-24 18:50:23 008 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - Rule[name=root_has_child_handle_Rule] segments=1 TupleSets[hasInsert=true, hasDelete=false, hasUpdate=false] 2018-10-24 18:50:23 008 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - 1 [JoinNode(17) - [ClassObjectType class=org.onap.holmes.common.api.stat.VesAlarm]] TupleSets[hasInsert=true, hasDelete=false, hasUpdate=false] 2018-10-24 18:50:23 008 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - Segment 0 2018-10-24 18:50:23 008 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - 1 [JoinNode(17) - [ClassObjectType class=org.onap.holmes.common.api.stat.VesAlarm]] TupleSets[hasInsert=true, hasDelete=false, hasUpdate=false] 2018-10-24 18:50:23 008 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - rightTuples TupleSets[hasInsert=true, hasDelete=false, hasUpdate=false] 2018-10-24 18:50:23 009 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - 2 [RuleTerminalNode(18): rule=root_has_child_handle_Rule] TupleSets[hasInsert=false, hasDelete=false, hasUpdate=false] 2018-10-24 18:50:23 009 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - Segment 0 2018-10-24 18:50:23 009 TRACE [org.drools.core.phreak.RuleNetworkEvaluator][Thread-15] invocationID:{InvocationID} - 2 [RuleTerminalNode(18): rule=root_has_child_handle_Rule] TupleSets[hasInsert=false, hasDelete=false, hasUpdate=false] 2018-10-24 18:50:23 009 TRACE [org.drools.core.phreak.RuleExecutor][Thread-15] invocationID:{InvocationID} - Removing RuleAgendaItem [Activation rule=root_has_child_handle_Rule, act#=0, salience=150, tuple=null] 2018-10-24 18:50:23 009 TRACE [org.drools.core.util.BinaryHeapQueue][Thread-15] invocationID:{InvocationID} - Queue Removed 1 [Activation rule=root_has_child_handle_Rule, act#=0, salience=150, tuple=null] 2018-10-24 18:50:23 009 TRACE [org.drools.core.common.DefaultAgenda][Thread-15] invocationID:{InvocationID} - State was FIRING_ALL_RULES is now HALTING 2018-10-24 18:50:23 009 TRACE [org.drools.core.common.DefaultAgenda][Thread-15] invocationID:{InvocationID} - State was HALTING is now INACTIVE 2018-10-24 18:50:23 009 TRACE [org.drools.core.common.DefaultAgenda][Thread-15] invocationID:{InvocationID} - Ending Fire All Rules