4 Replies Latest reply on Oct 31, 2007 11:59 AM by Peter Brewer

    jBPM timer doesnt fire

    Peter Brewer Novice

      I'm trying to get a simple jbpm timer to fire in JBoss 4.2.1GA, Seam 1.2.1.GA (using jbpm 3.1.4) with JSF RI 1.2 and RichFaces 3.1.1:

       <task-node name="timerTest">
       <event type="node-enter">
       <action expression="#{timerTestWorkflow.logNodeEnter}" />
       </event>
       <event type="node-leave">
       <action expression="#{timerTestWorkflow.logNodeLeave}" />
       </event>
       <task name="timerTestTask" description="TimerTestTask">
       <assignment actor-id="admin" />
       <event type="task-create">
       <action expression="#{timerTestWorkflow.logTaskCreate}" />
       </event>
       <event type="timer-create">
       <action expression="#{timerTestWorkflow.logTimerCreate}" />
       </event>
       <timer duedate="1 minute" transition="finished">
       <action expression="#{timerTestWorkflow.logTimerFired}" />
       </timer>
       </task>
       <transition name="finished" to="end" />
       </task-node>
      


      When running the process, everything appears to work except for the firing of the timer. I can see a record in the JBPM_TIMER table with a duedate scheduled for a minutes time, but the minute passes without a log message, the task doesn't close and there's no exception in the logs.

      Here is the log:
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.configuration.JbpmContextInfo] creating jbpm context with service factories '[message, scheduler, authentication, persistence, logging]'
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.JbpmContext] creating JbpmContext
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.configuration.JbpmContextInfo] creating jbpm context with service factories '[message, scheduler, authentication, persistence, logging]'
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.JbpmContext] creating JbpmContext
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.persistence.db.DbPersistenceServiceFactory] creating persistence service
      2007-10-31 11:11:45,364 DEBUG [org.jbpm.persistence.db.DbPersistenceService] creating hibernate session
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.graph.def.GraphElement] event 'process-start' on 'ProcessDefinition(timerTest)' for 'Token(/)'
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.graph.def.GraphElement] event 'before-signal' on 'StartState(start)' for 'Token(/)'
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-leave' on 'StartState(start)' for 'Token(/)'
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.graph.def.GraphElement] event 'transition' on 'Transition()' for 'Token(/)'
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.graph.def.GraphElement] event 'node-enter' on 'TaskNode(timerTest)' for 'Token(/)'
      2007-10-31 11:11:45,411 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.graph.node.TaskNode - this operation breaks ==
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.graph.def.GraphElement] executing action '#{timerTestWorkflow.logNodeEnter}'
      2007-10-31 11:11:45,411 DEBUG [org.jbpm.jpdl.el.impl.BeanMethod] invoking expression method 'logNodeEnter'
      2007-10-31 11:11:45,411 INFO [uk.co.iblocks.midas.workflow.TimerTestWorkflow] logNodeEnter
      2007-10-31 11:11:45,426 DEBUG [org.jbpm.graph.def.GraphElement] event 'task-create' on 'Task(timerTestTask)' for 'Token(/)'
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.graph.def.GraphElement] executing action 'CreateTimerAction(845640)'
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.graph.def.GraphElement] event 'timer-create' on 'Task(timerTestTask)' for 'Token(/)'
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.graph.def.GraphElement] executing action '#{timerTestWorkflow.logTimerCreate}'
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.jpdl.el.impl.BeanMethod] invoking expression method 'logTimerCreate'
      2007-10-31 11:11:45,457 INFO [uk.co.iblocks.midas.workflow.TimerTestWorkflow] logTimerCreate
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.db.SchedulerSession] saving timer
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.graph.def.GraphElement] executing action '#{timerTestWorkflow.logTaskCreate}'
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.jpdl.el.impl.BeanMethod] invoking expression method 'logTaskCreate'
      2007-10-31 11:11:45,457 INFO [uk.co.iblocks.midas.workflow.TimerTestWorkflow] logTaskCreate
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.graph.def.GraphElement] event 'task-assign' on 'Task(timerTestTask)' for 'Token(/)'
      2007-10-31 11:11:45,457 DEBUG [org.jbpm.graph.def.GraphElement] event 'after-signal' on 'StartState(start)' for 'Token(/)'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,520 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] executing default save operations
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service.
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.save.CascadeSaveOperation] cascading save of 'org.jbpm.graph.exe.ProcessInstance@dfd28b'
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@1d90252
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.persistence.db.DbPersistenceService] flushing hibernate session
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.persistence.db.DbPersistenceService] closing hibernate session
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] closing service 'scheduler': org.jbpm.scheduler.db.DbSchedulerService@223279
      2007-10-31 11:11:45,535 DEBUG [org.jbpm.svc.Services] closing service 'logging': org.jbpm.logging.db.DbLoggingService@11fa600
      


      If you need any further information, please let me know e.g. config files etc

      If anyone has any suggests or comments they would gratefully be received, this problem has been bugging me for ages!

      Thanks in advance,
      Pete.

        • 1. Re: jBPM timer doesnt fire
          Pete Muir Master

          Seam doesn't have support for JBPM timers in 1.2.1 and hence the EL expression won't call your Seam component. As to what the process does when the timer executes, thats a jbpm question.

          • 2. Re: jBPM timer doesnt fire
            Peter Brewer Novice

            Ah, ok thanks, I've changed the timer definition to:

             <timer duedate="1 minute" transition="finished">
             <action class="uk.co.iblocks.midas.workflow.TimerTestWorkflow" />
             </timer>
            

            which implements the ActionHandler and simply outputs a debug message. But the behaviour is the same: the timer is created but nothing happens when it's supposed to fire.

            1. Are you saying this is something I should query with the jbpm forum, or is this a seam and jbpm configuration issue?

            2. Will Seam 2.0 fully support timers?

            Many thanks, Pete.

            • 3. Re: jBPM timer doesnt fire
              Pete Muir Master

              1) Yes

              • 4. Re: jBPM timer doesnt fire
                Peter Brewer Novice

                I've discovered that I'm missing a scheduler service. jBPM 3.1.4 provides a SchedulerServlet that I had trouble getting to work out of the box, so I created a Seam component that starts the scheduler instead.

                This helps to some extent with Seam 1.2.1.GA integration, but be aware that since there is no interception around timer executions you cannot use seam components in the workflow.

                I think I'll wait for Seam 2 GA. For anyone that's interested, here's my seam component:

                package uk.co.iblocks.iflow.jbpm;
                
                import java.util.Date;
                import java.util.LinkedList;
                import java.util.List;
                
                import org.jboss.seam.Component;
                import org.jboss.seam.ScopeType;
                import org.jboss.seam.annotations.Create;
                import org.jboss.seam.annotations.Destroy;
                import org.jboss.seam.annotations.Install;
                import org.jboss.seam.annotations.Logger;
                import org.jboss.seam.annotations.Name;
                import org.jboss.seam.annotations.Scope;
                import org.jboss.seam.annotations.Startup;
                import org.jboss.seam.core.Jbpm;
                import org.jboss.seam.log.Log;
                import org.jboss.seam.log.Logging;
                import org.jbpm.scheduler.exe.Timer;
                import org.jbpm.scheduler.impl.SchedulerHistoryLog;
                import org.jbpm.scheduler.impl.SchedulerListener;
                import org.jbpm.scheduler.impl.SchedulerThread;
                
                @Name("jbpmScheduler")
                @Scope(ScopeType.APPLICATION)
                @Startup(depends={"org.jboss.seam.core.jbpm"})
                @Install(dependencies="org.jboss.seam.core.jbpm")
                public class JbpmScheduler {
                
                 private class HistoryListener implements SchedulerListener {
                
                 private Log executionLog = Logging.getLog(getClass()) ;
                
                 public void timerExecuted(Date date, Timer timer) {
                 executionLog.debug("Executed timer: id=#0, task=#1", timer.getId(), timer.getName()) ;
                 historyLogs.add(new SchedulerHistoryLog(date, timer));
                 if (historyLogs.size()>historyMaxSize) {
                 historyLogs.removeLast();
                 }
                 }
                 }
                
                 private SchedulerThread schedulerThread = null ;
                 private LinkedList<SchedulerHistoryLog> historyLogs = new LinkedList<SchedulerHistoryLog>();
                 private int interval = 5000;
                 private int historyMaxSize = 50 ;
                
                 @Logger
                 private Log log ;
                
                 @Create
                 public void startup() {
                 log.info("Starting the jBPM scheduler");
                 Jbpm jbpm = (Jbpm) Component.getInstance("jbpm") ;
                 schedulerThread = new SchedulerThread(jbpm.getJbpmConfiguration());
                 schedulerThread.setInterval(interval);
                 schedulerThread.addListener(new HistoryListener());
                 schedulerThread.start();
                 log.info("jBPM scheduler has started.");
                 }
                
                 @Destroy
                 public void shutdown() {
                 if (isRunning()) {
                 log.info("Stopping the jBPM scheduler.");
                 schedulerThread.quit() ;
                 schedulerThread = null;
                 } else {
                 log.debug("jBPM Scheduler can't be stopped because it was not running.");
                 }
                 }
                
                 public boolean isRunning() {
                 return ( (schedulerThread!=null)
                 && (schedulerThread.isAlive()) );
                 }
                
                 public List<SchedulerHistoryLog> getSchedulerHistoryLogs() {
                 return historyLogs;
                 }
                
                 public void clearSchedulerHistoryLogs() {
                 historyLogs.clear();
                 }
                
                 public int getHistoryMaxSize() {
                 return historyMaxSize;
                 }
                 public void setHistoryMaxSize(int historyMaxSize) {
                 this.historyMaxSize = historyMaxSize;
                 }
                 public int getInterval() {
                 return interval;
                 }
                 public void setInterval(int interval) {
                 this.interval = interval;
                 }
                
                 public SchedulerThread getSchedulerThread() {
                 return schedulerThread;
                 }
                
                }
                


                Cheers, Pete