My case is like this:
I start process which retrieve collection of data.
For each data it runs a process.
Each process has human task, then 2 scripts tasks and then another human task.
The user completes the first task, the the other two should be executed, then it should reach the 2nd human task.
However, it reaches it (I debug the CommandBasedWSHumanTaskHandler execute) method but does not save it to db!
(the log is only until the line after client.addTask(task, content, null))
The Begin lookup task is not being saved in DB!
Hibernate: select hibernate_sequence.nextval from dual
BEFORE RULEFLOW VARIABLE CHANGED mediaList=[] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW VARIABLE CHANGED mediaList=[] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW VARIABLE CHANGED requestData=com.mycompany.process.mediaacq.data.RequestData@fe1a1f process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW VARIABLE CHANGED requestData=com.mycompany.process.mediaacq.data.RequestData@fe1a1f process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW STARTED process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW NODE TRIGGERED node:StartProcess[id=1] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW NODE TRIGGERED node:Find Material[id=8] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
starting
com.mycompany.process.mediaacq.data.RequestData@fe1a1f
BEFORE RULEFLOW VARIABLE CHANGED mediaList=[com.mycompany.process.mediaacq.data.MaterialData@c0bef2, com.mycompany.process.mediaacq.data.MaterialData@38293c] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW VARIABLE CHANGED mediaList=[com.mycompany.process.mediaacq.data.MaterialData@c0bef2, com.mycompany.process.mediaacq.data.MaterialData@38293c] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW NODE TRIGGERED node:Gateway[id=6] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW NODE TRIGGERED node:Set process variables[id=9] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
starting set process variables
BEFORE RULEFLOW VARIABLE CHANGED processInfo=com.mycompany.process.mediaacq.data.ProcessInfo@50cb21 process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW VARIABLE CHANGED processInfo=com.mycompany.process.mediaacq.data.ProcessInfo@50cb21 process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW NODE TRIGGERED node:Start process for material[id=10] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
Hibernate: select hibernate_sequence.nextval from dual
BEFORE RULEFLOW VARIABLE CHANGED materialData=com.mycompany.process.mediaacq.data.MaterialData@c0bef2 process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW VARIABLE CHANGED materialData=com.mycompany.process.mediaacq.data.MaterialData@c0bef2 process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW VARIABLE CHANGED processInfo=com.mycompany.process.mediaacq.data.ProcessInfo@50cb21 process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW VARIABLE CHANGED processInfo=com.mycompany.process.mediaacq.data.ProcessInfo@50cb21 process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW STARTED process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:StartProcess[id=1] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:File or Tape[id=2] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:Wait for material[id=3] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
Hibernate: select hibernate_sequence.nextval from dual
[2011:03:68 18:03:509:debug] Message receieved on server : RegisterForEventRequest
[2011:03:68 18:03:561:debug] Arguments : [org.jbpm.task.event.TaskEventKey@5af5753, false, com.mycompany.handler.OnMediaHumanTaskHandler]
[2011:03:68 18:03:716:debug] Message receieved on server : RegisterForEventRequest
[2011:03:68 18:03:717:debug] Arguments : [org.jbpm.task.event.TaskEventKey@1b5b41c7, false, com.mycompany.handler.OnMediaHumanTaskHandler]
[2011:03:68 18:03:717:debug] Message receieved on server : RegisterForEventRequest
[2011:03:68 18:03:717:debug] Arguments : [org.jbpm.task.event.TaskEventKey@1afe70a4, false, com.mycompany.handler.OnMediaHumanTaskHandler]
[2011:03:68 18:03:718:debug] Message receieved on server : AddTaskRequest
[2011:03:68 18:03:718:debug] Arguments : [org.jbpm.task.Task@b8f1bd26, null]
[2011:03:68 18:03:22:debug] Message receieved on client : AddTaskResponse
[2011:03:68 18:03:23:debug] Arguments : [598]
[2011:03:68 18:03:941:debug] Message receieved on server : QueryTaskByWorkItemId
[2011:03:68 18:03:941:debug] Arguments : [597]
[2011:03:68 18:03:139:debug] Message receieved on client : QueryTaskByWorkItemIdResponse
[2011:03:68 18:03:139:debug] Arguments : [org.jbpm.task.Task@11fcf57e]
AFTER RULEFLOW NODE TRIGGERED node:Wait for material[id=3] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW NODE TRIGGERED node:File or Tape[id=2] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW NODE TRIGGERED node:StartProcess[id=1] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW STARTED process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
Hibernate: select hibernate_sequence.nextval from dual
BEFORE RULEFLOW VARIABLE CHANGED materialData=com.mycompany.process.mediaacq.data.MaterialData@38293c process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW VARIABLE CHANGED materialData=com.mycompany.process.mediaacq.data.MaterialData@38293c process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW VARIABLE CHANGED processInfo=com.mycompany.process.mediaacq.data.ProcessInfo@50cb21 process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW VARIABLE CHANGED processInfo=com.mycompany.process.mediaacq.data.ProcessInfo@50cb21 process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW STARTED process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:StartProcess[id=1] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:File or Tape[id=2] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:Wait for material[id=3] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
Hibernate: select hibernate_sequence.nextval from dual
[2011:03:68 18:03:343:debug] Message receieved on server : RegisterForEventRequest
[2011:03:68 18:03:343:debug] Arguments : [org.jbpm.task.event.TaskEventKey@5af5753, false, com.mycompany.handler.OnMediaHumanTaskHandler]
[2011:03:68 18:03:345:debug] Message receieved on server : RegisterForEventRequest
[2011:03:68 18:03:345:debug] Arguments : [org.jbpm.task.event.TaskEventKey@1b5b41c7, false, com.mycompany.handler.OnMediaHumanTaskHandler]
[2011:03:68 18:03:347:debug] Message receieved on server : RegisterForEventRequest
[2011:03:68 18:03:347:debug] Arguments : [org.jbpm.task.event.TaskEventKey@1afe70a4, false, com.mycompany.handler.OnMediaHumanTaskHandler]
[2011:03:68 18:03:351:debug] Message receieved on server : AddTaskRequest
[2011:03:68 18:03:351:debug] Arguments : [org.jbpm.task.Task@8a226e2, null]
[2011:03:68 18:03:375:debug] Message receieved on client : AddTaskResponse
[2011:03:68 18:03:375:debug] Arguments : [602]
[2011:03:68 18:03:99:debug] Message receieved on server : QueryTaskByWorkItemId
[2011:03:68 18:03:99:debug] Arguments : [601]
[2011:03:68 18:03:186:debug] Message receieved on client : QueryTaskByWorkItemIdResponse
[2011:03:68 18:03:186:debug] Arguments : [org.jbpm.task.Task@91ac959a]
AFTER RULEFLOW NODE TRIGGERED node:Wait for material[id=3] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW NODE TRIGGERED node:File or Tape[id=2] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW NODE TRIGGERED node:StartProcess[id=1] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
AFTER RULEFLOW STARTED process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW NODE TRIGGERED node:End[id=2] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
BEFORE RULEFLOW COMPLETED process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW COMPLETED process:Media Acquiring[id=com.mycompany.MediaAcquiring]
Hibernate: select processins0_.InstanceId as col_0_0_ from ProcessInstanceInfo processins0_ where ? in (select eventtypes1_.element from EventTypes eventtypes1_ where processins0_.InstanceId=eventtypes1_.InstanceId)
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW NODE TRIGGERED node:End[id=2] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW NODE TRIGGERED node:Start process for material[id=10] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW NODE TRIGGERED node:Set process variables[id=9] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW NODE TRIGGERED node:Gateway[id=6] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW NODE TRIGGERED node:Find Material[id=8] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
null process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW NODE TRIGGERED node:StartProcess[id=1] process:Media Acquiring[id=com.mycompany.MediaAcquiring]
AFTER RULEFLOW STARTED process:Media Acquiring[id=com.mycompany.MediaAcquiring]
[2011:03:68 18:03:889:debug] Message receieved on server : OperationRequest
[2011:03:68 18:03:890:debug] Arguments : [Start, 602, ONAIR]
[2011:03:68 18:03:890:debug] Command receieved on server was operation of type: Start
[2011:03:68 18:03:945:debug] Message receieved on server : OperationRequest
[2011:03:68 18:03:945:debug] Arguments : [Complete, 602, ONAIR, null, null]
[2011:03:68 18:03:945:debug] Command receieved on server was operation of type: Complete
MessagingTaskEventListener.taskCompleted 602
Found generic listeners: 2
[2011:03:68 18:03:981:debug] Message receieved on client : EventTriggerResponse
[2011:03:68 18:03:981:debug] Arguments : [org.jbpm.task.event.EventPayload@fcaad2]
[2011:03:68 18:03:02:debug] Message receieved on client : OperationResponse
[2011:03:68 18:03:02:debug] Arguments : []
[2011:03:68 18:03:02:debug] Message receieved on client : OperationResponse
[2011:03:68 18:03:02:debug] Arguments : []
[2011:03:68 18:03:06:debug] Message receieved on client : EventTriggerResponse
[2011:03:68 18:03:06:debug] Arguments : [org.jbpm.task.event.EventPayload@1bb11df]
[2011:03:68 18:03:07:debug] Message receieved on server : GetTaskRequest
[2011:03:68 18:03:08:debug] Arguments : [602]
[2011:03:68 18:03:51:debug] Message receieved on server : GetTaskRequest
[2011:03:68 18:03:51:debug] Arguments : [602]
[2011:03:68 18:03:87:debug] Message receieved on client : GetTaskResponse
[2011:03:68 18:03:87:debug] Arguments : [org.jbpm.task.Task@45e0814e]
Notification of completed task 601
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:Create kids media (tape)[id=4] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
creating kids media for tape
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:Create adult media (file)[id=5] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
creating adult media for tape
null process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
BEFORE RULEFLOW NODE TRIGGERED node:Begin lookup [id=7] process:Media Acquiring[id=com.mycompany.MediaAcquiring-processMaterial]
Hibernate: select hibernate_sequence.nextval from dual