2020-11-28 07:28:01.518 DEBUG 3280 --- [enerContainer-1] .e.H.insertHistoricActivityInstanceEvent : ==> Parameters: Event_0u1tflk:dd437401-3142-11eb-bea9-b42e99f30cb2(String), ae764175-3142-11eb-bea9-b42e99f30cb2(String), Process_0rxc5xx(String), Process_0rxc5xx:1:807cd8b4-3142-11eb-bea9-b42e99f30cb2(String), ae764175-3142-11eb-bea9-b42e99f30cb2(String), ae764175-3142-11eb-bea9-b42e99f30cb2(String), ae764175-3142-11eb-bea9-b42e99f30cb2(String), Event_0u1tflk(String), null, null, null, Catch UWWB CreateOffer(String), boundaryMessage(String), null, 2020-11-28 07:28:01.472(Timestamp), 2020-11-28 07:28:01.477(Timestamp), 5(Long), 1(Integer), 9(Long), null, null 2020-11-28 07:28:01.519 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'DELETE'; Entity: 'EventSubscriptionEntity[id=bf9c359d-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.520 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.E.deleteEventSubscription : ==> Preparing: delete from ACT_RU_EVENT_SUBSCR where ID_ = ? and REV_ = ? 2020-11-28 07:28:01.520 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.E.deleteEventSubscription : ==> Parameters: bf9c359d-3142-11eb-bea9-b42e99f30cb2(String), 1(Integer) 2020-11-28 07:28:01.521 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'DELETE'; Entity: 'IdentityLinkEntity[id=bf9caad0-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.521 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.I.deleteIdentityLink : ==> Preparing: delete from ACT_RU_IDENTITYLINK where ID_ = ? 2020-11-28 07:28:01.522 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.I.deleteIdentityLink : ==> Parameters: bf9caad0-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:28:01.522 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'DELETE'; Entity: 'VariableInstanceEntity[id=bb49563a-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.523 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.V.deleteVariableInstance : ==> Preparing: delete from ACT_RU_VARIABLE where ID_ = ? and REV_ = ? 2020-11-28 07:28:01.524 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.V.deleteVariableInstance : ==> Parameters: bb49563a-3142-11eb-bea9-b42e99f30cb2(String), 1(Integer) 2020-11-28 07:28:01.524 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'DELETE'; Entity: 'TaskEntity[id=bf9caacf-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.525 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.TaskEntity.deleteTask : ==> Preparing: delete from ACT_RU_TASK where ID_ = ? and REV_ = ? 2020-11-28 07:28:01.525 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.TaskEntity.deleteTask : ==> Parameters: bf9caacf-3142-11eb-bea9-b42e99f30cb2(String), 1(Integer) 2020-11-28 07:28:01.526 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'DELETE'; Entity: 'ExecutionEntity[id=bf9c359c-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.526 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.E.deleteExecution : ==> Preparing: delete from ACT_RU_EXECUTION where ID_ = ? and REV_ = ? 2020-11-28 07:28:01.527 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.E.deleteExecution : ==> Parameters: bf9c359c-3142-11eb-bea9-b42e99f30cb2(String), 1(Integer) 2020-11-28 07:28:01.528 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'DELETE'; Entity: 'ExecutionEntity[id=ae764175-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.531 DEBUG 3280 --- [enerContainer-1] o.c.b.e.i.p.e.E.deleteExecution : ==> Parameters: ae764175-3142-11eb-bea9-b42e99f30cb2(String), 2(Integer) 2020-11-28 07:28:01.531 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'UPDATE'; Entity: 'HistoricActivityInstanceEventEntity[id=Activity_13b5o7z:bf9c83be-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.534 DEBUG 3280 --- [enerContainer-1] .e.H.updateHistoricActivityInstanceEvent : ==> Preparing: UPDATE ACT_HI_ACTINST SET EXECUTION_ID_ = ?, PROC_DEF_KEY_ = ?, PROC_DEF_ID_ = ?, ACT_ID_ = ?, ACT_NAME_ = ?, ACT_TYPE_ = ?, PARENT_ACT_INST_ID_ = ? , END_TIME_ = ? , DURATION_ = ? , ACT_INST_STATE_ = ? WHERE ID_ = ? 2020-11-28 07:28:01.536 DEBUG 3280 --- [enerContainer-1] .e.H.updateHistoricActivityInstanceEvent : ==> Parameters: bf9c359c-3142-11eb-bea9-b42e99f30cb2(String), Process_0rxc5xx(String), Process_0rxc5xx:1:807cd8b4-3142-11eb-bea9-b42e99f30cb2(String), Activity_13b5o7z(String), Waiting for UWWB CreateOffer(String), userTask(String), ae764175-3142-11eb-bea9-b42e99f30cb2(String), 2020-11-28 07:28:01.44(Timestamp), 49715(Long), 2(Integer), Activity_13b5o7z:bf9c83be-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:28:01.537 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'UPDATE'; Entity: 'HistoricProcessInstanceEventEntity[id=ae764175-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.540 DEBUG 3280 --- [enerContainer-1] p.e.H.updateHistoricProcessInstanceEvent : ==> Preparing: update ACT_HI_PROCINST set PROC_DEF_ID_ = ?, PROC_DEF_KEY_ = ?, BUSINESS_KEY_ = ?, END_ACT_ID_ = ?, DELETE_REASON_ = ?, SUPER_PROCESS_INSTANCE_ID_ = ?, STATE_ = ? , END_TIME_ = ? , DURATION_ = ? where ID_ = ? 2020-11-28 07:28:01.542 DEBUG 3280 --- [enerContainer-1] p.e.H.updateHistoricProcessInstanceEvent : ==> Parameters: Process_0rxc5xx:1:807cd8b4-3142-11eb-bea9-b42e99f30cb2(String), Process_0rxc5xx(String), 123456(String), Event_0u1tflk(String), null, null, COMPLETED(String), 2020-11-28 07:28:01.495(Timestamp), 78542(Long), ae764175-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:28:01.543 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'UPDATE'; Entity: 'HistoricTaskInstanceEventEntity[id=bf9caacf-3142-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:28:01.547 DEBUG 3280 --- [enerContainer-1] .i.p.e.H.updateHistoricTaskInstanceEvent : ==> Preparing: update ACT_HI_TASKINST set EXECUTION_ID_ = ?, PROC_DEF_KEY_ = ?, PROC_DEF_ID_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, DESCRIPTION_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, DELETE_REASON_ = ?, TASK_DEF_KEY_ = ?, PRIORITY_ = ?, DUE_DATE_ = ?, FOLLOW_UP_DATE_ = ?, CASE_INST_ID_ = ?, CASE_DEF_ID_ = ? , END_TIME_ = ? , DURATION_ = ? where ID_ = ? 2020-11-28 07:28:01.550 DEBUG 3280 --- [enerContainer-1] .i.p.e.H.updateHistoricTaskInstanceEvent : ==> Parameters: bf9c359c-3142-11eb-bea9-b42e99f30cb2(String), Process_0rxc5xx(String), Process_0rxc5xx:1:807cd8b4-3142-11eb-bea9-b42e99f30cb2(String), Waiting for UWWB CreateOffer(String), null, null, null, demo(String), deleted(String), Activity_13b5o7z(String), 50(Integer), null, null, null, null, 2020-11-28 07:28:01.463(Timestamp), 49738(Long), bf9caacf-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:28:01.567 DEBUG 3280 --- [enerContainer-1] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- CorrelateMessageCmd ---------------------- 2020-11-28 07:28:42.858 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:28:42.858 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:28:42.858 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:28:42.858 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:28:42.858(Timestamp), 2020-11-28 07:28:42.858(Timestamp), 2020-11-28 07:28:42.858(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:28:42.859 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:29:24.319 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- GetFilterCmd ---------------------- 2020-11-28 07:29:24.319 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:29:24.319 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : ==> Preparing: select * from ACT_RU_FILTER where ID_ = ? 2020-11-28 07:29:24.320 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : ==> Parameters: 805fb3c1-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:29:24.320 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : <== Total: 1 2020-11-28 07:29:24.320 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:29:24.320 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ PERSISTENT FilterEntity[805fb3c1-3142-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:29:24.320 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- GetFilterCmd ---------------------- 2020-11-28 07:29:24.323 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- ExecuteFilterListPageCmd ---------------------- 2020-11-28 07:29:24.323 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:29:24.324 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : ==> Preparing: select * from ACT_RU_FILTER where ID_ = ? 2020-11-28 07:29:24.324 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : ==> Parameters: 805fb3c1-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:29:24.324 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : <== Total: 1 2020-11-28 07:29:24.329 DEBUG 3280 --- [p-nio-80-exec-2] .c.b.e.i.p.e.T.selectTaskByQueryCriteria : ==> Preparing: select distinct RES.REV_, RES.ID_, RES.NAME_, RES.PARENT_TASK_ID_, RES.DESCRIPTION_, RES.PRIORITY_, RES.CREATE_TIME_, RES.OWNER_, RES.ASSIGNEE_, RES.DELEGATION_, RES.EXECUTION_ID_, RES.PROC_INST_ID_, RES.PROC_DEF_ID_, RES.CASE_EXECUTION_ID_, RES.CASE_INST_ID_, RES.CASE_DEF_ID_, RES.TASK_DEF_KEY_, RES.DUE_DATE_, RES.FOLLOW_UP_DATE_, RES.SUSPENSION_STATE_, RES.TENANT_ID_ from ACT_RU_TASK RES WHERE ( 1 = 1 and RES.SUSPENSION_STATE_ = 1 ) order by RES.CREATE_TIME_ desc LIMIT ? OFFSET ? 2020-11-28 07:29:24.329 DEBUG 3280 --- [p-nio-80-exec-2] .c.b.e.i.p.e.T.selectTaskByQueryCriteria : ==> Parameters: 15(Integer), 0(Integer) 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] .c.b.e.i.p.e.T.selectTaskByQueryCriteria : <== Total: 0 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ PERSISTENT FilterEntity[805fb3c1-3142-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- ExecuteFilterListPageCmd ---------------------- 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- ExecuteFilterCountCmd ---------------------- 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : ==> Preparing: select * from ACT_RU_FILTER where ID_ = ? 2020-11-28 07:29:24.330 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : ==> Parameters: 805fb3c1-3142-11eb-bea9-b42e99f30cb2(String) 2020-11-28 07:29:24.331 DEBUG 3280 --- [p-nio-80-exec-2] o.c.b.e.i.p.e.FilterEntity.selectFilter : <== Total: 1 2020-11-28 07:29:24.332 DEBUG 3280 --- [p-nio-80-exec-2] e.i.p.e.T.selectTaskCountByQueryCriteria : ==> Preparing: select count(distinct RES.ID_ ) from ACT_RU_TASK RES WHERE ( 1 = 1 and RES.SUSPENSION_STATE_ = 1 ) 2020-11-28 07:29:24.332 DEBUG 3280 --- [p-nio-80-exec-2] e.i.p.e.T.selectTaskCountByQueryCriteria : ==> Parameters: 2020-11-28 07:29:24.332 DEBUG 3280 --- [p-nio-80-exec-2] e.i.p.e.T.selectTaskCountByQueryCriteria : <== Total: 1 2020-11-28 07:29:24.332 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:29:24.332 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ PERSISTENT FilterEntity[805fb3c1-3142-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:29:24.332 DEBUG 3280 --- [p-nio-80-exec-2] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- ExecuteFilterCountCmd ---------------------- 2020-11-28 07:29:42.869 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:29:42.869 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:29:42.869 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:29:42.869 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:29:42.869 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:29:42.869(Timestamp), 2020-11-28 07:29:42.869(Timestamp), 2020-11-28 07:29:42.869(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:29:42.869 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:29:42.870 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:29:42.870 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:29:42.870 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:29:42.870 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:29:42.870 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:30:42.873 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:30:42.873 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:30:42.873 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:30:42.873 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:30:42.873 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:30:42.873(Timestamp), 2020-11-28 07:30:42.873(Timestamp), 2020-11-28 07:30:42.873(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:30:42.874 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:30:42.874 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:30:42.874 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:30:42.874 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:30:42.874 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:30:42.874 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:31:42.879 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:31:42.879 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:31:42.88(Timestamp), 2020-11-28 07:31:42.88(Timestamp), 2020-11-28 07:31:42.88(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:31:42.880 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:32:42.891 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:32:42.891 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:32:42.891 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:32:42.891 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:32:42.891 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:32:42.891(Timestamp), 2020-11-28 07:32:42.891(Timestamp), 2020-11-28 07:32:42.891(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:32:42.892 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:32:42.892 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:32:42.892 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:32:42.892 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:32:42.892 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:32:42.892 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:33:42.899 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:33:42.899 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:33:42.899 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:33:42.899 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:33:42.899 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:33:42.899(Timestamp), 2020-11-28 07:33:42.899(Timestamp), 2020-11-28 07:33:42.899(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:33:42.900 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:33:42.900 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:33:42.900 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:33:42.900 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:33:42.900 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:33:42.900 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:34:42.912 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:34:42.912 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:34:42.912 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:34:42.912(Timestamp), 2020-11-28 07:34:42.912(Timestamp), 2020-11-28 07:34:42.912(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:34:42.913 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:35:42.914 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:35:42.914 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:35:42.914 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:35:42.914 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:35:42.914 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:35:42.914(Timestamp), 2020-11-28 07:35:42.914(Timestamp), 2020-11-28 07:35:42.914(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:35:42.915 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:35:42.915 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:35:42.915 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:35:42.915 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:35:42.915 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:35:42.915 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:36:42.918 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:36:42.918 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:36:42.918 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:36:42.918 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:36:42.918 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:36:42.918(Timestamp), 2020-11-28 07:36:42.918(Timestamp), 2020-11-28 07:36:42.918(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:36:42.919 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:36:42.919 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:36:42.919 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:36:42.919 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:36:42.919 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:36:42.919 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:37:11.741 INFO 3280 --- [-a45fc7e555c6:1] org.apache.qpid.jms.JmsSession : A JMS MessageProducer has been closed: JmsProducerInfo { ID:c3448f0c-c8dc-481c-9a16-a45fc7e555c6:1:2:1, destination = topic-uwwb } 2020-11-28 07:37:42.927 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:37:42.927 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:37:42.927 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:37:42.927 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:37:42.927 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:37:42.927(Timestamp), 2020-11-28 07:37:42.927(Timestamp), 2020-11-28 07:37:42.927(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:37:42.928 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:37:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:37:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:37:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:37:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:37:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:38:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:38:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:38:42.928 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:38:42.928 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:38:42.928 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:38:42.928(Timestamp), 2020-11-28 07:38:42.928(Timestamp), 2020-11-28 07:38:42.928(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:38:42.929 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:38:42.929 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:38:42.929 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:38:42.929 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:38:42.929 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:38:42.929 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:39:42.933 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:39:42.933 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:39:42.933 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:39:42.933 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:39:42.933 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:39:42.933(Timestamp), 2020-11-28 07:39:42.933(Timestamp), 2020-11-28 07:39:42.933(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:39:42.934 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:39:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:39:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:39:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:39:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:39:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:40:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- MetricsCollectionTask$1 ---------------------- 2020-11-28 07:40:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ PERSISTENT MeterLogEntity[98a9e3e2-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e4-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e3-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e6-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e5-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e7-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98aa0afb-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e8-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98aa0afa-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98a9e3e9-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98aa0afc-3144-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[98aa0afd-3144-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03008 Flush Summary: [ INSERT MeterLogEntity[98a9e3e2-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e3-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e4-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e5-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e6-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e7-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e8-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98a9e3e9-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98aa0afa-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98aa0afb-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98aa0afc-3144-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[98aa0afd-3144-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e2-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Preparing: insert into ACT_RU_METER_LOG (ID_, NAME_, REPORTER_, VALUE_, TIMESTAMP_, MILLISECONDS_) values ( ?, ?, ?, ?, ?, ? ) 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e2-3144-11eb-bea9-b42e99f30cb2(String), root-process-instance-start(String), 192.168.0.154$default(String), 1(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e3-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e3-3144-11eb-bea9-b42e99f30cb2(String), activity-instance-start(String), 192.168.0.154$default(String), 5(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e4-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e4-3144-11eb-bea9-b42e99f30cb2(String), job-acquired-failure(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e5-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e5-3144-11eb-bea9-b42e99f30cb2(String), job-locked-exclusive(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e6-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e6-3144-11eb-bea9-b42e99f30cb2(String), job-execution-rejected(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e7-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e7-3144-11eb-bea9-b42e99f30cb2(String), executed-decision-elements(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e8-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e8-3144-11eb-bea9-b42e99f30cb2(String), activity-instance-end(String), 192.168.0.154$default(String), 5(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98a9e3e9-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98a9e3e9-3144-11eb-bea9-b42e99f30cb2(String), job-successful(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98aa0afa-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98aa0afa-3144-11eb-bea9-b42e99f30cb2(String), job-acquired-success(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98aa0afb-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98aa0afb-3144-11eb-bea9-b42e99f30cb2(String), job-acquisition-attempt(String), 192.168.0.154$default(String), 18(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98aa0afc-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98aa0afc-3144-11eb-bea9-b42e99f30cb2(String), executed-decision-instances(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=98aa0afd-3144-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:40:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: 98aa0afd-3144-11eb-bea9-b42e99f30cb2(String), job-failed(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:40:25.373(Timestamp), 1606545625373(Long) 2020-11-28 07:40:25.377 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- MetricsCollectionTask$1 ---------------------- 2020-11-28 07:40:42.933 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:40:42.933 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:40:42.933 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:40:42.933 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:40:42.933(Timestamp), 2020-11-28 07:40:42.933(Timestamp), 2020-11-28 07:40:42.933(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:40:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:41:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:41:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:41:42.934 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:41:42.934 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:41:42.934 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:41:42.934(Timestamp), 2020-11-28 07:41:42.934(Timestamp), 2020-11-28 07:41:42.934(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:41:42.935 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:41:42.935 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:41:42.935 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:41:42.935 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:41:42.935 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:41:42.935 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:42:42.944 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:42:42.944 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:42:42.944 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:42:42.944 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:42:42.944 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:42:42.944(Timestamp), 2020-11-28 07:42:42.944(Timestamp), 2020-11-28 07:42:42.944(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:42:42.945 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:42:42.945 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:42:42.945 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:42:42.945 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:42:42.945 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:42:42.945 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:43:42.949 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:43:42.949 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:43:42.949 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:43:42.949 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:43:42.949 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:43:42.949(Timestamp), 2020-11-28 07:43:42.949(Timestamp), 2020-11-28 07:43:42.949(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:43:42.949 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:43:42.950 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:43:42.950 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:43:42.950 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:43:42.950 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:43:42.950 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:44:42.961 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:44:42.961 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:44:42.961 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:44:42.961 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:44:42.961 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:44:42.961(Timestamp), 2020-11-28 07:44:42.961(Timestamp), 2020-11-28 07:44:42.961(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:44:42.962 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:44:42.962 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:44:42.962 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:44:42.962 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:44:42.962 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:44:42.962 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:45:42.964 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:45:42.964 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:45:42.964 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:45:42.964 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:45:42.964 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:45:42.964(Timestamp), 2020-11-28 07:45:42.964(Timestamp), 2020-11-28 07:45:42.964(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:45:42.965 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:45:42.965 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:45:42.965 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:45:42.965 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:45:42.965 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:45:42.965 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:46:42.972 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:46:42.972 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:46:42.972 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:46:42.972 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:46:42.972 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:46:42.972(Timestamp), 2020-11-28 07:46:42.972(Timestamp), 2020-11-28 07:46:42.972(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:46:42.973 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:46:42.973 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:46:42.973 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:46:42.973 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:46:42.973 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:46:42.973 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:47:42.981 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:47:42.981 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:47:42.981 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:47:42.981 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:47:42.981 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:47:42.981(Timestamp), 2020-11-28 07:47:42.981(Timestamp), 2020-11-28 07:47:42.981(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:47:42.982 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:47:42.982 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:47:42.982 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:47:42.982 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:47:42.982 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:47:42.982 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:48:42.992 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:48:42.992 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:48:42.992 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:48:42.992 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:48:42.992 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:48:42.992(Timestamp), 2020-11-28 07:48:42.992(Timestamp), 2020-11-28 07:48:42.992(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:48:42.993 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:48:42.993 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:48:42.993 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:48:42.993 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:48:42.993 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:48:42.993 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:49:43.003 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:49:43.003 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:49:43.003 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:49:43.003 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:49:43.003 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:49:43.003(Timestamp), 2020-11-28 07:49:43.003(Timestamp), 2020-11-28 07:49:43.003(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:49:43.004 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:49:43.004 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:49:43.004 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:49:43.004 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:49:43.004 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:49:43.004 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:50:43.013 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:50:43.013 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:50:43.013 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:50:43.013 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:50:43.013 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:50:43.013(Timestamp), 2020-11-28 07:50:43.013(Timestamp), 2020-11-28 07:50:43.013(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:50:43.014 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:50:43.014 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:50:43.014 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:50:43.014 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:50:43.014 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:50:43.014 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:51:43.016 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:51:43.016 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:51:43.016 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:51:43.016 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:51:43.016 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:51:43.016(Timestamp), 2020-11-28 07:51:43.016(Timestamp), 2020-11-28 07:51:43.016(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:51:43.017 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:51:43.017 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:51:43.017 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:51:43.017 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:51:43.017 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:51:43.017 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:52:43.030 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:52:43.030 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:52:43.030 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:52:43.030 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:52:43.030 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:52:43.03(Timestamp), 2020-11-28 07:52:43.03(Timestamp), 2020-11-28 07:52:43.03(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:52:43.031 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:52:43.031 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:52:43.031 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:52:43.031 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:52:43.031 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:52:43.031 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:53:43.04(Timestamp), 2020-11-28 07:53:43.04(Timestamp), 2020-11-28 07:53:43.04(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:53:43.040 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:53:43.041 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:53:43.041 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:53:43.041 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:53:43.041 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:54:43.044 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:54:43.044 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:54:43.044 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:54:43.044 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:54:43.044 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:54:43.044(Timestamp), 2020-11-28 07:54:43.044(Timestamp), 2020-11-28 07:54:43.044(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:54:43.045 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:54:43.045 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:54:43.045 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:54:43.045 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:54:43.045 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:54:43.045 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- MetricsCollectionTask$1 ---------------------- 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ PERSISTENT MeterLogEntity[b11afdf1-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf0-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf4-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf5-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf3-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf2-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdef-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf8-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdee-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf9-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf6-3146-11eb-bea9-b42e99f30cb2] PERSISTENT MeterLogEntity[b11afdf7-3146-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03008 Flush Summary: [ INSERT MeterLogEntity[b11afdee-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdef-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf0-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf1-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf2-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf3-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf4-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf5-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf6-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf7-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf8-3146-11eb-bea9-b42e99f30cb2] INSERT MeterLogEntity[b11afdf9-3146-11eb-bea9-b42e99f30cb2] ] 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdee-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Preparing: insert into ACT_RU_METER_LOG (ID_, NAME_, REPORTER_, VALUE_, TIMESTAMP_, MILLISECONDS_) values ( ?, ?, ?, ?, ?, ? ) 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdee-3146-11eb-bea9-b42e99f30cb2(String), root-process-instance-start(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.374 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdef-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdef-3146-11eb-bea9-b42e99f30cb2(String), activity-instance-start(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf0-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf0-3146-11eb-bea9-b42e99f30cb2(String), job-acquired-failure(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf1-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf1-3146-11eb-bea9-b42e99f30cb2(String), job-locked-exclusive(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf2-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf2-3146-11eb-bea9-b42e99f30cb2(String), job-execution-rejected(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.375 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf3-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf3-3146-11eb-bea9-b42e99f30cb2(String), executed-decision-elements(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf4-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf4-3146-11eb-bea9-b42e99f30cb2(String), activity-instance-end(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf5-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf5-3146-11eb-bea9-b42e99f30cb2(String), job-successful(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf6-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf6-3146-11eb-bea9-b42e99f30cb2(String), job-acquired-success(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf7-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf7-3146-11eb-bea9-b42e99f30cb2(String), job-acquisition-attempt(String), 192.168.0.154$default(String), 15(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf8-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf8-3146-11eb-bea9-b42e99f30cb2(String), executed-decision-instances(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.persistence : ENGINE-03009 SQL operation: 'INSERT'; Entity: 'MeterLogEntity[id=b11afdf9-3146-11eb-bea9-b42e99f30cb2]' 2020-11-28 07:55:25.376 DEBUG 3280 --- [etrics Reporter] o.c.b.e.i.p.e.M.insertMeterLog : ==> Parameters: b11afdf9-3146-11eb-bea9-b42e99f30cb2(String), job-failed(String), 192.168.0.154$default(String), 0(Long), 2020-11-28 07:55:25.374(Timestamp), 1606546525374(Long) 2020-11-28 07:55:25.377 DEBUG 3280 --- [etrics Reporter] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- MetricsCollectionTask$1 ---------------------- 2020-11-28 07:55:43.057 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14012 Job acquisition thread woke up 2020-11-28 07:55:43.057 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13005 Starting command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:55:43.057 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13009 opening new command context 2020-11-28 07:55:43.057 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Preparing: select RES.ID_, RES.REV_, RES.DUEDATE_, RES.PROCESS_INSTANCE_ID_, RES.EXCLUSIVE_ from ACT_RU_JOB RES where (RES.RETRIES_ > 0) and ( RES.DUEDATE_ is null or RES.DUEDATE_ <= ? ) and (RES.LOCK_OWNER_ is null or RES.LOCK_EXP_TIME_ < ?) and RES.SUSPENSION_STATE_ = 1 and ( ( RES.EXCLUSIVE_ = 1 and not exists( select J2.ID_ from ACT_RU_JOB J2 where J2.PROCESS_INSTANCE_ID_ = RES.PROCESS_INSTANCE_ID_ -- from the same proc. inst. and (J2.EXCLUSIVE_ = 1) -- also exclusive and (J2.LOCK_OWNER_ is not null and J2.LOCK_EXP_TIME_ >= ?) -- in progress ) ) or RES.EXCLUSIVE_ = 0 ) LIMIT ? OFFSET ? 2020-11-28 07:55:43.057 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : ==> Parameters: 2020-11-28 07:55:43.057(Timestamp), 2020-11-28 07:55:43.057(Timestamp), 2020-11-28 07:55:43.057(Timestamp), 3(Integer), 0(Integer) 2020-11-28 07:55:43.058 DEBUG 3280 --- [ingJobExecutor]] o.c.b.e.i.p.e.J.selectNextJobsToExecute : <== Total: 0 2020-11-28 07:55:43.058 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13011 closing existing command context 2020-11-28 07:55:43.058 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.persistence : ENGINE-03006 Cache state after flush: [ ] 2020-11-28 07:55:43.058 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.cmd : ENGINE-13006 Finishing command -------------------- AcquireJobsCmd ---------------------- 2020-11-28 07:55:43.058 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14022 Acquired 0 jobs for process engine 'default': [] 2020-11-28 07:55:43.058 DEBUG 3280 --- [ingJobExecutor]] org.camunda.bpm.engine.jobexecutor : ENGINE-14011 Job acquisition thread sleeping for 59999 millis