nav[aria-label="Primary Navigation"] { padding: 0; & ul { list-style: none; width: 100%; display: flex; flex-direction: row; justify-content: start; align-items: start; gap: 30px; padding: 0; & li { margin: 0; } & ul li { list-style: none; } } }

As we transition to the new Hyland Community, some post links may be temporarily disrupted as we finalize redirects. We apologize for the inconvenience and are working to resolve this as quickly as possible. Details.

Timeout error

partizano

Hi,

I have process, that execute long operation during start. After starting the process and waiting for 90 seconds I got error: "connection closed". Tell please, where I can setup this timeout parameter?

__
Thanks, Alexander

Find more posts tagged with

Comments

ronald_van_kuij

What does the logging say? Probably a database connection timeout

partizano

There are no errors in log-files. I just got response from server:

Server Apache-Coyote/1.1
Content-Length 0
Date Mon, 01 Aug 2011 09:51:59 GMT
Connection close

I create test-process and got the same result. Process is:

<br><?xml version="1.0" encoding="UTF-8"?><br><definitions xmlns="<a href="http://www.omg.org/spec/BPMN/20100524/MODEL" rel="nofollow noopener noreferrer">http://www.omg.org/spec/BPMN/20100524/MODEL</a>" xmlns:xsi="<a href="http://www.w3.org/2001/XMLSchema-instance" rel="nofollow noopener noreferrer">http://www.w3.org/2001/XMLSchema-instance</a>" xmlns:activiti="<a href="http://activiti.org/bpmn" rel="nofollow noopener noreferrer">http://activiti.org/bpmn</a>" xmlns:bpmndi="<a href="http://www.omg.org/spec/BPMN/20100524/DI" rel="nofollow noopener noreferrer">http://www.omg.org/spec/BPMN/20100524/DI</a>" xmlns:smileysurprised:mgdc="<a href="http://www.omg.org/spec/DD/20100524/DC" rel="nofollow noopener noreferrer">http://www.omg.org/spec/DD/20100524/DC</a>" xmlns:smileysurprised:mgdi="<a href="http://www.omg.org/spec/DD/20100524/DI" rel="nofollow noopener noreferrer">http://www.omg.org/spec/DD/20100524/DI</a>" typeLanguage="<a href="http://www.w3.org/2001/XMLSchema" rel="nofollow noopener noreferrer">http://www.w3.org/2001/XMLSchema</a>" expressionLanguage="<a href="http://www.w3.org/1999/XPath" rel="nofollow noopener noreferrer">http://www.w3.org/1999/XPath</a>" targetNamespace="<a href="http://www.activiti.org/test" rel="nofollow noopener noreferrer">http://www.activiti.org/test</a>"><br>&nbsp; <process id="Temp" name="temp"><br>&nbsp;&nbsp;&nbsp; <documentation>Place documentation for the 'temp' process here.</documentation><br>&nbsp;&nbsp;&nbsp; <startEvent id="startevent1" name="Start"></startEvent><br>&nbsp;&nbsp;&nbsp; <userTask id="usertask1" name="User Task" activiti:assignee="kermit"></userTask><br>&nbsp;&nbsp;&nbsp; <endEvent id="endevent1" name="End"></endEvent><br>&nbsp;&nbsp;&nbsp; <sequenceFlow id="flow1" name="" sourceRef="startevent1" targetRef="servicetask1"></sequenceFlow><br>&nbsp;&nbsp;&nbsp; <sequenceFlow id="flow2" name="" sourceRef="usertask1" targetRef="endevent1"></sequenceFlow><br>&nbsp;&nbsp;&nbsp; <serviceTask id="servicetask1" name="Service Task" activiti:class="Temp"></serviceTask><br>&nbsp;&nbsp;&nbsp; <sequenceFlow id="flow3" name="" sourceRef="servicetask1" targetRef="usertask1"></sequenceFlow><br>&nbsp; </process><br></definitions><br>

<br>public class Temp implements JavaDelegate {<br><br>&nbsp;&nbsp;&nbsp; @Override<br>&nbsp;&nbsp;&nbsp; public void execute(DelegateExecution execution) throws Exception {<br>&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; int i = 0;<br>&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; Random random = new Random();<br>&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; while (true){<br>&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; i += Math.abs(random.nextInt());<br>&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; }<br>&nbsp;&nbsp;&nbsp; }<br>}<br>

Help, please…

__
Alexander

ronald_van_kuij

Sorry, this is way to little information… how do you start this process? rest? Than it can be an http timeout that you need to configure in your servlet engine.

partizano

I started the process from Activiti Explorer. After 90 seconds I got the following:
[attachment=0]123.JPG[/attachment]

In the Fire bug I can see following request and response:

Request:

Host localhost:8080<br>User-Agent Mozilla/5.0 (Windows NT 5.1; rv:5.0) Gecko/20100101 Firefox/5.0<br>Accept text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8<br>Accept-Language ru-ru,ru;q=0.8,en-us;q=0.5,en;q=0.3<br>Accept-Encoding gzip, deflate<br>Accept-Charset windows-1251,utf-8;q=0.7,*;q=0.7<br>Connection keep-alive<br>X-Requested-With XMLHttpRequest<br>Content-Type application/json; charset=UTF-8<br>Referer <a href="http://localhost:8080/activiti-explorer/processes" rel="nofollow noopener noreferrer">http://localhost:8080/activiti-explorer/processes</a><br>Content-Length 44<br>Cookie alfLogin=1312198345; JSESSIONID=7741B8EE046E546BDA4C4BEB920090CD; alfUsername2=cmVmZXJlbmNl

Response:
Server Apache-Coyote/1.1<br>Content-Length 0<br>Date Mon, 01 Aug 2011 11:47:35 GMT<br>Connection close
__
Alexander

123_0.JPG

ronald_van_kuij

There is a 500 server error, so there must be something in the logging of the server…. If you cannot provide that, we will be unable to help you… again, sorry

partizano

Hi, Ronald! Thanks for the reply.

I don't know why my log don't contains exceptions, although firebug shows 500 server error…
If I start the process using REST-API, it starts fine. If I start the process from Activiti explorer, it raise 500 server error (always after 90 seconds), but it continue to work (!) and after some period of time it starts succesfully! I also don't know, why this happens…

To provide you with more information, I list my catalina.***.log here. Server raise 500 error at 12:03, so I list only this part of log:

02.08.2011 12:03:00 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:00 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:00 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:00 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:00 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:00 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:00 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:05 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:05 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:05 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:05 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:05 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:05 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:05 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:10 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:10 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:10 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:10 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:10 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:10 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:10 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:14 com.myCompany.reference.DBController getPurposeRef<br>INFO: Finished. Working time = 57 seconds<br>02.08.2011 12:03:14 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask5'<br>02.08.2011 12:03:14 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27550] takes transition (servicetask5)&ndash;flow68&ndash;>(usertask5)<br>02.08.2011 12:03:14 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27550] executes Activity(usertask5): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:14 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27551] takes transition (parallelgateway1)&ndash;flow31&ndash;>(servicetask6)<br>02.08.2011 12:03:14 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27551] executes Activity(servicetask6): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:14 com.myCompany.reference.DBController getValRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:15 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:15 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:15 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:15 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:15 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:15 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:16 com.myCompany.reference.DBController getValRef<br>INFO: Finished. Working time = 1 seconds<br>02.08.2011 12:03:17 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask6'<br>02.08.2011 12:03:17 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27551] takes transition (servicetask6)&ndash;flow30&ndash;>(usertask6)<br>02.08.2011 12:03:17 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27551] executes Activity(usertask6): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:17 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27552] takes transition (parallelgateway1)&ndash;flow35&ndash;>(servicetask7)<br>02.08.2011 12:03:17 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27552] executes Activity(servicetask7): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:17 com.myCompany.reference.DBController getPeriodRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:20 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:20 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:20 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:20 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:20 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:20 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:23 com.myCompany.reference.DBController getPeriodRef<br>INFO: Finished. Working time = 5 seconds<br>02.08.2011 12:03:23 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask7'<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27552] takes transition (servicetask7)&ndash;flow36&ndash;>(usertask7)<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27552] executes Activity(usertask7): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27553] takes transition (parallelgateway1)&ndash;flow38&ndash;>(servicetask8)<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27553] executes Activity(servicetask8): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:23 com.myCompany.reference.DBController getTradeOrgRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:23 com.myCompany.reference.DBController getTradeOrgRef<br>INFO: Finished. Working time = 0 seconds<br>02.08.2011 12:03:23 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask8'<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27553] takes transition (servicetask8)&ndash;flow39&ndash;>(usertask8)<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27553] executes Activity(usertask8): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27554] takes transition (parallelgateway1)&ndash;flow43&ndash;>(servicetask9)<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27554] executes Activity(servicetask9): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:23 com.myCompany.reference.DBController getAssuranceCompanyRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:23 com.myCompany.reference.DBController getAssuranceCompanyRef<br>INFO: Finished. Working time = 0 seconds<br>02.08.2011 12:03:23 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask9'<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27554] takes transition (servicetask9)&ndash;flow44&ndash;>(usertask9)<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27554] executes Activity(usertask9): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27555] takes transition (parallelgateway1)&ndash;flow48&ndash;>(servicetask10)<br>02.08.2011 12:03:23 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27555] executes Activity(servicetask10): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:23 com.myCompany.reference.DBController getPaymentTypeRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:24 com.myCompany.reference.DBController getPaymentTypeRef<br>INFO: Finished. Working time = 0 seconds<br>02.08.2011 12:03:24 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask10'<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27555] takes transition (servicetask10)&ndash;flow49&ndash;>(usertask10)<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27555] executes Activity(usertask10): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27556] takes transition (parallelgateway1)&ndash;flow53&ndash;>(servicetask11)<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27556] executes Activity(servicetask11): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:24 com.myCompany.reference.DBController getAssuranceKindRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:24 com.myCompany.reference.DBController getAssuranceKindRef<br>INFO: Finished. Working time = 0 seconds<br>02.08.2011 12:03:24 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask11'<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27556] takes transition (servicetask11)&ndash;flow54&ndash;>(usertask11)<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27556] executes Activity(usertask11): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27557] takes transition (parallelgateway1)&ndash;flow61&ndash;>(servicetask12)<br>02.08.2011 12:03:24 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27557] executes Activity(servicetask12): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:24 com.myCompany.reference.DBController getAssuranceContractValRef<br>INFO: Started&hellip;<br>02.08.2011 12:03:25 com.myCompany.reference.DBController getAssuranceContractValRef<br>INFO: Finished. Working time = 0 seconds<br>02.08.2011 12:03:25 org.activiti.engine.impl.bpmn.behavior.BpmnActivityBehavior performOutgoingBehavior<br>FINE: Leaving activity 'servicetask12'<br>02.08.2011 12:03:25 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27557] takes transition (servicetask12)&ndash;flow60&ndash;>(usertask12)<br>02.08.2011 12:03:25 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27557] executes Activity(usertask12): org.activiti.engine.impl.bpmn.behavior.UserTaskActivityBehavior<br>02.08.2011 12:03:25 org.activiti.engine.impl.pvm.runtime.AtomicOperationTransitionNotifyListenerTake execute<br>FINE: ConcurrentExecution[27558] takes transition (parallelgateway1)&ndash;flow63&ndash;>(servicetask13)<br>02.08.2011 12:03:25 org.activiti.engine.impl.pvm.runtime.AtomicOperationActivityExecute execute<br>FINE: ConcurrentExecution[27558] executes Activity(servicetask13): org.activiti.engine.impl.bpmn.helper.ClassDelegate<br>02.08.2011 12:03:25 com.myCompany.reference.DBController getRatesReference<br>INFO: Started&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:25 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:25 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:25 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:25 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:25 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:25 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:30 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:30 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:30 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:30 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:30 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:30 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:30 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:35 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:35 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:35 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:35 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:35 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:35 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:35 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:40 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:40 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:40 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:40 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:40 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:40 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:40 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:45 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:45 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:45 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:45 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:45 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:45 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:45 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:50 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:50 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:50 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:50 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:50 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:50 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:50 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis<br>02.08.2011 12:03:55 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread woke up<br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting AcquireJobsCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:55 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:55 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; AcquireJobsCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; starting GetUnlockedTimersByDuedateCmd &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:55 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: flush summary:<br>02.08.2011 12:03:55 org.activiti.engine.impl.db.DbSqlSession flush<br>FINE: now executing flush&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committing&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: committing the ibatis sql session&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.cfg.standalone.StandaloneMybatisTransactionContext commit<br>FINE: firing event committed&hellip;<br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE: &mdash; GetUnlockedTimersByDuedateCmd finished &mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&mdash;&ndash;<br>02.08.2011 12:03:55 org.activiti.engine.impl.interceptor.LogInterceptor execute<br>FINE:&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp;&nbsp; <br>02.08.2011 12:03:55 org.activiti.engine.impl.jobexecutor.JobAcquisitionThread run<br>FINE: job acquisition thread sleeping for 5000 millis

If you have any idea, why this happens, let me know, please. Thanks.

__
Alexander

jbarrez

How long is your logic supposed to execute?

As Ronald said, it is probably related to a database timeout setting.

ronald_van_kuij

Or maybe a http timeout