-
Bug
-
Resolution: Duplicate
-
Major
-
None
-
7.11.1.GA
-
with this sample
activate
org.kie.prometheus.server.ext.disabled=false
and
<logger category="org.kie.server.services.prometheus">
<level name="DEBUG"/>
</logger>
with this sample https://github.com/gautric/bpmn-pattern/blob/master/bpmn-pattern-process/src/main/resources/net/a/g/jbpm/pattern/AsyncTestProcess.bpmn activate org.kie.prometheus.server.ext.disabled =false and < logger category = "org.kie.server.services.prometheus" > < level name = "DEBUG" /> </ logger >
-
2022 Week 05-07 (from Jan 31), 2022 Week 08-10 (from Feb 21), 2022 Week 11-13 (from Mar 14), 2022 Week 14-16 (from Apr 4)
As you can see into this log
18:09:05,368 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (default task-1) Before Node left event: ==>[ProcessNodeLeft(nodeId=3; id=0; nodeName=Executer Process Async; processName=AsyncTestProcess; processId=AsyncTestProcess)]
18:09:30,661 INFO [net.a.g.jbpm.pattern.listener.AsyncListener] (default task-1) beforeJobScheduled(AsynchronousJobEvent AsynchronousJobEventImpl [job=RequestInfo{id=null, time=Mon Nov 29 18:09:05 CET 2021, status=QUEUED, commandName=org.jbpm.process.core.async.AsyncSignalEventCommand, message=Ready to execute, owner=null, key=null, requestData=[B@4946d0f, responseData=null, error=[]}, failed=false, exception=null]
18:09:30,677 DEBUG [org.jbpm.executor.impl.ExecutorImpl] (default task-1) Sending JMS message to trigger job execution for job 1
18:09:30,807 DEBUG [org.jbpm.executor.impl.ExecutorImpl] (default task-1) Scheduled request for Command: org.jbpm.process.core.async.AsyncSignalEventCommand - requestId: 1 with 3 retries
18:09:33,170 DEBUG [org.kie.server.services.prometheus.PrometheusJobListener] (default task-1) After job scheduled event: AsynchronousJobEventImpl [job=RequestInfo{id=1, time=Mon Nov 29 18:09:05 CET 2021, status=QUEUED, commandName=org.jbpm.process.core.async.AsyncSignalEventCommand, message=Ready to execute, owner=null, key=null, requestData=[B@4946d0f, responseData=null, error=[]}, failed=false, exception=null]
18:09:33,170 INFO [net.a.g.jbpm.pattern.listener.AsyncListener] (default task-1) afterJobScheduled(AsynchronousJobEvent AsynchronousJobEventImpl [job=RequestInfo{id=1, time=Mon Nov 29 18:09:05 CET 2021, status=QUEUED, commandName=org.jbpm.process.core.async.AsyncSignalEventCommand, message=Ready to execute, owner=null, key=null, requestData=[B@4946d0f, responseData=null, error=[]}, failed=false, exception=null]
18:09:33,174 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (default task-1) After process started event: ==>[ProcessStarted(name=AsyncTestProcess; id=AsyncTestProcess)]
18:09:38,124 DEBUG [org.jbpm.executor.impl.jms.JmsAvailableJobsExecutor] (Thread-4 (ActiveMQ-client-global-threads)) received Text message with content 1
18:09:38,215 DEBUG [org.jbpm.executor.impl.jms.JmsAvailableJobsExecutor] (Thread-4 (ActiveMQ-client-global-threads)) Found following job request for id 1 : RequestInfo{id=1, time=Mon Nov 29 18:09:38 CET 2021, status=RUNNING, commandName=org.jbpm.process.core.async.AsyncSignalEventCommand, message=Ready to execute, owner=null, key=null, requestData=[B@4cb3631f, responseData=null, error=[]}
18:09:38,218 DEBUG [org.jbpm.executor.impl.jms.JmsAvailableJobsExecutor] (Thread-4 (ActiveMQ-client-global-threads)) Request with id 1 is in valid state, processing...
18:09:57,784 DEBUG [org.jbpm.executor.impl.AbstractAvailableJobsExecutor] (Thread-4 (ActiveMQ-client-global-threads)) Processing Request Id: 1, status RUNNING command org.jbpm.process.core.async.AsyncSignalEventCommand
18:09:57,874 INFO [stdout] (Thread-4 (ActiveMQ-client-global-threads)) Async Process Pattern AsyncTestProcess 2021-11-29T17:08:57.888Z t
18:09:57,874 INFO [stdout] (Thread-4 (ActiveMQ-client-global-threads)) Async Process Pattern true
18:09:57,875 INFO [stdout] (Thread-4 (ActiveMQ-client-global-threads)) Async Process Pattern 123
18:09:57,877 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (Thread-4 (ActiveMQ-client-global-threads)) Before Node left event: ==>[ProcessNodeLeft(nodeId=1; id=2; nodeName=Task Async; processName=AsyncTestProcess; processId=AsyncTestProcess)]
18:09:57,878 INFO [stdout] (Thread-4 (ActiveMQ-client-global-threads)) Async Process Pattern 2 AsyncTestProcess 2021-11-29T17:08:57.888Z t
18:09:57,879 INFO [stdout] (Thread-4 (ActiveMQ-client-global-threads)) Async Process Pattern 2 true
18:09:57,879 INFO [stdout] (Thread-4 (ActiveMQ-client-global-threads)) Async Process Pattern 2 123
18:09:57,880 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (Thread-4 (ActiveMQ-client-global-threads)) Before Node left event: ==>[ProcessNodeLeft(nodeId=2; id=4; nodeName=Task Sync; processName=AsyncTestProcess; processId=AsyncTestProcess)]
18:09:57,882 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (Thread-4 (ActiveMQ-client-global-threads)) Before Node left event: ==>[ProcessNodeLeft(nodeId=4; id=5; nodeName=Process Async Executé; processName=AsyncTestProcess; processId=AsyncTestProcess)]
18:09:57,894 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (Thread-4 (ActiveMQ-client-global-threads)) After process completed event: ==>[ProcessCompleted(name=AsyncTestProcess; id=AsyncTestProcess)]
18:09:57,907 DEBUG [org.kie.server.services.prometheus.PrometheusProcessEventListener] (Thread-4 (ActiveMQ-client-global-threads)) Process Instance duration: 52.733s
18:09:57,963 DEBUG [org.jbpm.executor.impl.concurrent.PrioritisedScheduledThreadPoolExecutor] (Thread-4 (ActiveMQ-client-global-threads)) Request job 1 has been completed number of jobs in the pool 0
we has been called correctly afterJobScheduled but
beforeJobExecuted and afterJobExecuted are never called at all.
