henryyan
10/11/2012 - 2:01 PM

Activiti执行输出的log

Activiti执行输出的log

2012-10-11 22:00:45,390 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ResourceEntity.insertResource] - ==> Parameters: 15(String), modify-apply.form(String), java.io.ByteArrayInputStream@4d12ee4f(ByteArrayInputStream), 9(String), false(Boolean)
2012-10-11 22:00:45,392 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ResourceEntity.insertResource] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,392 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ResourceEntity.insertResource] - ==>  Preparing: insert into ACT_GE_BYTEARRAY(ID_, REV_, NAME_, BYTES_, DEPLOYMENT_ID_, GENERATED_) values (?, 1, ?, ?, ?, ?) 
2012-10-11 22:00:45,392 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ResourceEntity.insertResource] - ==> Parameters: 16(String), report-back.form(String), java.io.ByteArrayInputStream@8fd9b4d(ByteArrayInputStream), 9(String), false(Boolean)
2012-10-11 22:00:45,393 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.insertProcessDefinition] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,393 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.insertProcessDefinition] - ==>  Preparing: insert into ACT_RE_PROCDEF(ID_, REV_, CATEGORY_, NAME_, KEY_, VERSION_, DEPLOYMENT_ID_, RESOURCE_NAME_, DGRM_RESOURCE_NAME_, HAS_START_FORM_KEY_, SUSPENSION_STATE_) values (?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?) 
2012-10-11 22:00:45,393 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.insertProcessDefinition] - ==> Parameters: leave-formkey:1:17(String), http://www.kafeitu.me/demo/activiti/leave(String), 请假流程-外部表单(String), leave-formkey(String), 1(Integer), 9(String), leave-formkey.bpmn20.xml(String), leave-formkey.png(String), true(Boolean), 1(Integer)
2012-10-11 22:00:45,537 [main] DEBUG [me.kafeitu.demo.activiti.service.oa.leave.LeaveWorkflowService] - save entity: me.kafeitu.demo.activiti.entity.oa.Leave@2ced55ec
2012-10-11 22:00:45,551 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectLatestProcessDefinitionByKey] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,551 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectLatestProcessDefinitionByKey] - ==>  Preparing: select * from ACT_RE_PROCDEF where KEY_ = ? and VERSION_ = (select max(VERSION_) from ACT_RE_PROCDEF where KEY_ = ?) 
2012-10-11 22:00:45,551 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectLatestProcessDefinitionByKey] - ==> Parameters: leave(String), leave(String)
2012-10-11 22:00:45,610 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,610 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==>  Preparing: select * from ACT_HI_ACTINST HAI WHERE HAI.EXECUTION_ID_ = ? and HAI.ACT_ID_ = ? and HAI.END_TIME_ is null LIMIT ? OFFSET ? 
2012-10-11 22:00:45,610 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==> Parameters: 18(String), startevent1(String), 1(Integer), 0(Integer)
2012-10-11 22:00:45,630 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.insertExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,630 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.insertExecution] - ==>  Preparing: insert into ACT_RU_EXECUTION (ID_, REV_, PROC_INST_ID_, BUSINESS_KEY_, PROC_DEF_ID_, ACT_ID_, IS_ACTIVE_, IS_CONCURRENT_, IS_SCOPE_,IS_EVENT_SCOPE_, PARENT_ID_, SUPER_EXEC_, SUSPENSION_STATE_, CACHED_ENT_STATE_) values ( ?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,631 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.insertExecution] - ==> Parameters: 18(String), 18(String), 1(String), leave:1:4(String), deptLeaderAudit(String), true(Boolean), false(Boolean), true(Boolean), false(Boolean), null, null, 1(Integer), 2(Integer)
2012-10-11 22:00:45,632 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,632 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ==>  Preparing: insert into ACT_RU_VARIABLE (ID_, REV_, TYPE_, NAME_, PROC_INST_ID_, EXECUTION_ID_, TASK_ID_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,633 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ==> Parameters: 19(String), string(String), applyUserId(String), 18(String), 18(String), null, null, null, null, kafeitu(String), null
2012-10-11 22:00:45,634 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,634 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==>  Preparing: insert into ACT_HI_DETAIL (ID_, TYPE_, PROC_INST_ID_, EXECUTION_ID_, ACT_INST_ID_, TASK_ID_, NAME_, REV_, VAR_TYPE_, TIME_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 'VariableUpdate', ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,635 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==> Parameters: 20(String), 18(String), 18(String), null, null, applyUserId(String), 0(Integer), string(String), 2012-10-11 22:00:45.555(Timestamp), null, null, null, kafeitu(String), null
2012-10-11 22:00:45,636 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.insertHistoricProcessInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,636 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.insertHistoricProcessInstance] - ==>  Preparing: insert into ACT_HI_PROCINST ( ID_, PROC_INST_ID_, BUSINESS_KEY_, PROC_DEF_ID_, START_TIME_, END_TIME_, DURATION_, START_USER_ID_, START_ACT_ID_, END_ACT_ID_, SUPER_PROCESS_INSTANCE_ID_, DELETE_REASON_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,637 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.insertHistoricProcessInstance] - ==> Parameters: 18(String), 18(String), 1(String), leave:1:4(String), 2012-10-11 22:00:45.611(Timestamp), null, null, kafeitu(String), startevent1(String), null, null, null
2012-10-11 22:00:45,638 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,638 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,639 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 21(String), leave:1:4(String), 18(String), 18(String), startevent1(String), Start(String), startEvent(String), null, 2012-10-11 22:00:45.611(Timestamp), 2012-10-11 22:00:45.627(Timestamp), 16(Long)
2012-10-11 22:00:45,639 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,639 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,640 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 22(String), leave:1:4(String), 18(String), 18(String), deptLeaderAudit(String), 部门领导审批(String), userTask(String), null, 2012-10-11 22:00:45.627(Timestamp), null, null
2012-10-11 22:00:45,641 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,641 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ==>  Preparing: insert into ACT_RU_TASK (ID_, REV_, NAME_, PARENT_TASK_ID_, DESCRIPTION_, PRIORITY_, CREATE_TIME_, OWNER_, ASSIGNEE_, DELEGATION_, EXECUTION_ID_, PROC_INST_ID_, PROC_DEF_ID_, TASK_DEF_KEY_, DUE_DATE_) values (?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,641 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ==> Parameters: 23(String), 部门领导审批(String), null, null, 50(Integer), 2012-10-11 22:00:45.627(Timestamp), null, null, null, 18(String), 18(String), leave:1:4(String), deptLeaderAudit(String), null
2012-10-11 22:00:45,642 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,642 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ==>  Preparing: insert into ACT_HI_TASKINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, NAME_, PARENT_TASK_ID_, DESCRIPTION_, OWNER_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_, DELETE_REASON_, TASK_DEF_KEY_, PRIORITY_, DUE_DATE_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,643 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ==> Parameters: 23(String), leave:1:4(String), 18(String), 18(String), 部门领导审批(String), null, null, null, null, 2012-10-11 22:00:45.627(Timestamp), null, null, null, deptLeaderAudit(String), 50(Integer), null
2012-10-11 22:00:45,644 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.insertIdentityLink] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,644 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.insertIdentityLink] - ==>  Preparing: insert into ACT_RU_IDENTITYLINK (ID_, REV_, TYPE_, USER_ID_, GROUP_ID_, TASK_ID_, PROC_DEF_ID_) values (?, 1, ?, ?, ?, ?, ?) 
2012-10-11 22:00:45,644 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.insertIdentityLink] - ==> Parameters: 24(String), candidate(String), null, deptLeader(String), 23(String), null
2012-10-11 22:00:45,645 [main] DEBUG [me.kafeitu.demo.activiti.service.oa.leave.LeaveWorkflowService] - start process of {key=leave, bkey=1, pid=18, variables={}}
2012-10-11 22:00:45,650 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectProcessInstanceByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,650 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectProcessInstanceByQueryCriteria] - ==>  Preparing: select E.* from ACT_RU_EXECUTION E inner join ACT_RE_PROCDEF P on E.PROC_DEF_ID_ = P.ID_ WHERE E.PARENT_ID_ is null and E.BUSINESS_KEY_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,650 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectProcessInstanceByQueryCriteria] - ==> Parameters: 1(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,663 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,663 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ==>  Preparing: select distinct T.* from ACT_RU_TASK T inner join ACT_RU_IDENTITYLINK I on I.TASK_ID_ = T.ID_ WHERE T.ASSIGNEE_ is null and I.TYPE_ = 'candidate' and ( I.GROUP_ID_ IN ( ? ) ) LIMIT ? OFFSET ? 
2012-10-11 22:00:45,664 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ==> Parameters: deptLeader(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,672 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,672 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==>  Preparing: select * from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,672 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==> Parameters: 23(String)
2012-10-11 22:00:45,674 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,674 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==>  Preparing: select * from ACT_HI_TASKINST where ID_ = ? 
2012-10-11 22:00:45,674 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==> Parameters: 23(String)
2012-10-11 22:00:45,676 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,676 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==>  Preparing: select * from ACT_RE_PROCDEF where ID_ = ? 
2012-10-11 22:00:45,676 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==> Parameters: leave:1:4(String)
2012-10-11 22:00:45,678 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,678 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==>  Preparing: select * from ACT_RU_EXECUTION where ID_ = ? 
2012-10-11 22:00:45,678 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==> Parameters: 18(String)
2012-10-11 22:00:45,682 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,682 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==>  Preparing: select * from ACT_HI_ACTINST HAI WHERE HAI.EXECUTION_ID_ = ? and HAI.ACT_ID_ = ? and HAI.END_TIME_ is null LIMIT ? OFFSET ? 
2012-10-11 22:00:45,682 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==> Parameters: 18(String), deptLeaderAudit(String), 1(Integer), 0(Integer)
2012-10-11 22:00:45,684 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.updateTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,685 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.updateTask] - ==>  Preparing: update ACT_RU_TASK SET REV_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, PRIORITY_ = ?, CREATE_TIME_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, DELEGATION_ = ?, EXECUTION_ID_ = ?, DESCRIPTION_ = ?, DUE_DATE_ = ? where ID_= ? and REV_ = ? 
2012-10-11 22:00:45,685 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.updateTask] - ==> Parameters: 2(Integer), 部门领导审批(String), null, 50(Integer), 2012-10-11 22:00:45.627(Timestamp), null, leaderuser(String), null, 18(String), null, null, 23(String), 1(Integer)
2012-10-11 22:00:45,687 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,687 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==>  Preparing: update ACT_HI_ACTINST set EXECUTION_ID_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ? where ID_ = ? 
2012-10-11 22:00:45,688 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==> Parameters: 18(String), leaderuser(String), null, null, 22(String)
2012-10-11 22:00:45,689 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,689 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==>  Preparing: update ACT_HI_TASKINST set EXECUTION_ID_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, DESCRIPTION_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ?, DELETE_REASON_ = ?, TASK_DEF_KEY_ = ?, PRIORITY_ = ?, DUE_DATE_ = ? where ID_ = ? 
2012-10-11 22:00:45,690 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==> Parameters: 18(String), 部门领导审批(String), null, null, null, leaderuser(String), null, null, null, deptLeaderAudit(String), 50(Integer), null, 23(String)
2012-10-11 22:00:45,693 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,693 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==>  Preparing: select * from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,694 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==> Parameters: 23(String)
2012-10-11 22:00:45,697 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,697 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==>  Preparing: select * from ACT_RU_EXECUTION where ID_ = ? 
2012-10-11 22:00:45,697 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==> Parameters: 18(String)
2012-10-11 22:00:45,700 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,701 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ==>  Preparing: select * from ACT_RU_VARIABLE where EXECUTION_ID_ = ? and TASK_ID_ is null 
2012-10-11 22:00:45,701 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ==> Parameters: 18(String)
2012-10-11 22:00:45,704 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,704 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==>  Preparing: select * from ACT_HI_ACTINST HAI WHERE HAI.EXECUTION_ID_ = ? and HAI.ACT_ID_ = ? and HAI.END_TIME_ is null LIMIT ? OFFSET ? 
2012-10-11 22:00:45,705 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==> Parameters: 18(String), deptLeaderAudit(String), 1(Integer), 0(Integer)
2012-10-11 22:00:45,708 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,708 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==>  Preparing: select * from ACT_RE_PROCDEF where ID_ = ? 
2012-10-11 22:00:45,708 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==> Parameters: leave:1:4(String)
2012-10-11 22:00:45,711 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,711 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ==>  Preparing: select * from ACT_RU_TASK where PARENT_TASK_ID_ = ? 
2012-10-11 22:00:45,711 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ==> Parameters: 23(String)
2012-10-11 22:00:45,713 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,713 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ==>  Preparing: select * from ACT_RU_IDENTITYLINK where TASK_ID_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,713 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ==> Parameters: 23(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,716 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,716 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ==>  Preparing: select * from ACT_RU_VARIABLE where TASK_ID_ = ? 
2012-10-11 22:00:45,716 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ==> Parameters: 23(String)
2012-10-11 22:00:45,718 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,718 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==>  Preparing: select * from ACT_HI_TASKINST where ID_ = ? 
2012-10-11 22:00:45,719 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==> Parameters: 23(String)
2012-10-11 22:00:45,722 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,722 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ==>  Preparing: select distinct T.* from ACT_RU_TASK T where T.EXECUTION_ID_ = ? 
2012-10-11 22:00:45,722 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ==> Parameters: 18(String)
2012-10-11 22:00:45,726 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,726 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ==>  Preparing: insert into ACT_RU_VARIABLE (ID_, REV_, TYPE_, NAME_, PROC_INST_ID_, EXECUTION_ID_, TASK_ID_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,727 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ==> Parameters: 25(String), boolean(String), deptLeaderPass(String), 18(String), 18(String), null, null, null, 1(Long), null, null
2012-10-11 22:00:45,729 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,729 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==>  Preparing: insert into ACT_HI_DETAIL (ID_, TYPE_, PROC_INST_ID_, EXECUTION_ID_, ACT_INST_ID_, TASK_ID_, NAME_, REV_, VAR_TYPE_, TIME_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 'VariableUpdate', ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,730 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==> Parameters: 26(String), 18(String), 18(String), 22(String), null, deptLeaderPass(String), 0(Integer), boolean(String), 2012-10-11 22:00:45.703(Timestamp), null, null, 1(Long), null, null
2012-10-11 22:00:45,731 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,731 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,732 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 27(String), leave:1:4(String), 18(String), 18(String), exclusivegateway5(String), Exclusive Gateway(String), exclusiveGateway(String), null, 2012-10-11 22:00:45.725(Timestamp), 2012-10-11 22:00:45.725(Timestamp), 0(Long)
2012-10-11 22:00:45,733 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,733 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,734 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 28(String), leave:1:4(String), 18(String), 18(String), hrAudit(String), 人事审批(String), userTask(String), null, 2012-10-11 22:00:45.726(Timestamp), null, null
2012-10-11 22:00:45,735 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,735 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ==>  Preparing: insert into ACT_RU_TASK (ID_, REV_, NAME_, PARENT_TASK_ID_, DESCRIPTION_, PRIORITY_, CREATE_TIME_, OWNER_, ASSIGNEE_, DELEGATION_, EXECUTION_ID_, PROC_INST_ID_, PROC_DEF_ID_, TASK_DEF_KEY_, DUE_DATE_) values (?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,735 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ==> Parameters: 29(String), 人事审批(String), null, null, 50(Integer), 2012-10-11 22:00:45.726(Timestamp), null, null, null, 18(String), 18(String), leave:1:4(String), hrAudit(String), null
2012-10-11 22:00:45,736 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,737 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ==>  Preparing: insert into ACT_HI_TASKINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, NAME_, PARENT_TASK_ID_, DESCRIPTION_, OWNER_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_, DELETE_REASON_, TASK_DEF_KEY_, PRIORITY_, DUE_DATE_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,737 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ==> Parameters: 29(String), leave:1:4(String), 18(String), 18(String), 人事审批(String), null, null, null, null, 2012-10-11 22:00:45.726(Timestamp), null, null, null, hrAudit(String), 50(Integer), null
2012-10-11 22:00:45,738 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.insertIdentityLink] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,738 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.insertIdentityLink] - ==>  Preparing: insert into ACT_RU_IDENTITYLINK (ID_, REV_, TYPE_, USER_ID_, GROUP_ID_, TASK_ID_, PROC_DEF_ID_) values (?, 1, ?, ?, ?, ?, ?) 
2012-10-11 22:00:45,739 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.insertIdentityLink] - ==> Parameters: 30(String), candidate(String), null, hr(String), 29(String), null
2012-10-11 22:00:45,740 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,740 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==>  Preparing: update ACT_HI_ACTINST set EXECUTION_ID_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ? where ID_ = ? 
2012-10-11 22:00:45,740 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==> Parameters: 18(String), leaderuser(String), 2012-10-11 22:00:45.725(Timestamp), 98(Long), 22(String)
2012-10-11 22:00:45,741 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.updateExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,741 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.updateExecution] - ==>  Preparing: update ACT_RU_EXECUTION set REV_ = ?, PROC_DEF_ID_ = ?, ACT_ID_ = ?, IS_ACTIVE_ = ?, IS_CONCURRENT_ = ?, IS_SCOPE_ = ?, IS_EVENT_SCOPE_ = ?, PARENT_ID_ = ?, SUPER_EXEC_ = ?, SUSPENSION_STATE_ = ?, CACHED_ENT_STATE_ = ? where ID_ = ? and REV_ = ? 
2012-10-11 22:00:45,742 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.updateExecution] - ==> Parameters: 2(Integer), leave:1:4(String), hrAudit(String), true(Boolean), false(Boolean), true(Boolean), false(Boolean), null, null, 1(Integer), 2(Integer), 18(String), 1(Integer)
2012-10-11 22:00:45,743 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,743 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==>  Preparing: update ACT_HI_TASKINST set EXECUTION_ID_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, DESCRIPTION_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ?, DELETE_REASON_ = ?, TASK_DEF_KEY_ = ?, PRIORITY_ = ?, DUE_DATE_ = ? where ID_ = ? 
2012-10-11 22:00:45,744 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==> Parameters: 18(String), 部门领导审批(String), null, null, null, leaderuser(String), 2012-10-11 22:00:45.722(Timestamp), 95(Long), completed(String), deptLeaderAudit(String), 50(Integer), null, 23(String)
2012-10-11 22:00:45,745 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.deleteIdentityLink] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,745 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.deleteIdentityLink] - ==>  Preparing: delete from ACT_RU_IDENTITYLINK where ID_ = ? 
2012-10-11 22:00:45,745 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.deleteIdentityLink] - ==> Parameters: 24(String)
2012-10-11 22:00:45,746 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,746 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ==>  Preparing: delete from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,747 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ==> Parameters: 23(String)
2012-10-11 22:00:45,748 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,749 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ==>  Preparing: select distinct T.* from ACT_RU_TASK T inner join ACT_RU_IDENTITYLINK I on I.TASK_ID_ = T.ID_ WHERE T.ASSIGNEE_ is null and I.TYPE_ = 'candidate' and ( I.GROUP_ID_ IN ( ? ) ) LIMIT ? OFFSET ? 
2012-10-11 22:00:45,749 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ==> Parameters: hr(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,751 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,751 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==>  Preparing: select * from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,751 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==> Parameters: 29(String)
2012-10-11 22:00:45,753 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,753 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==>  Preparing: select * from ACT_HI_TASKINST where ID_ = ? 
2012-10-11 22:00:45,753 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==> Parameters: 29(String)
2012-10-11 22:00:45,755 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,755 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==>  Preparing: select * from ACT_RE_PROCDEF where ID_ = ? 
2012-10-11 22:00:45,755 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==> Parameters: leave:1:4(String)
2012-10-11 22:00:45,756 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,756 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==>  Preparing: select * from ACT_RU_EXECUTION where ID_ = ? 
2012-10-11 22:00:45,757 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==> Parameters: 18(String)
2012-10-11 22:00:45,759 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,759 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==>  Preparing: select * from ACT_HI_ACTINST HAI WHERE HAI.EXECUTION_ID_ = ? and HAI.ACT_ID_ = ? and HAI.END_TIME_ is null LIMIT ? OFFSET ? 
2012-10-11 22:00:45,759 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==> Parameters: 18(String), hrAudit(String), 1(Integer), 0(Integer)
2012-10-11 22:00:45,761 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.updateTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,761 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.updateTask] - ==>  Preparing: update ACT_RU_TASK SET REV_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, PRIORITY_ = ?, CREATE_TIME_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, DELEGATION_ = ?, EXECUTION_ID_ = ?, DESCRIPTION_ = ?, DUE_DATE_ = ? where ID_= ? and REV_ = ? 
2012-10-11 22:00:45,762 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.updateTask] - ==> Parameters: 2(Integer), 人事审批(String), null, 50(Integer), 2012-10-11 22:00:45.726(Timestamp), null, hruser(String), null, 18(String), null, null, 29(String), 1(Integer)
2012-10-11 22:00:45,763 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,763 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==>  Preparing: update ACT_HI_ACTINST set EXECUTION_ID_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ? where ID_ = ? 
2012-10-11 22:00:45,763 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==> Parameters: 18(String), hruser(String), null, null, 28(String)
2012-10-11 22:00:45,764 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,764 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==>  Preparing: update ACT_HI_TASKINST set EXECUTION_ID_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, DESCRIPTION_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ?, DELETE_REASON_ = ?, TASK_DEF_KEY_ = ?, PRIORITY_ = ?, DUE_DATE_ = ? where ID_ = ? 
2012-10-11 22:00:45,765 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==> Parameters: 18(String), 人事审批(String), null, null, null, hruser(String), null, null, null, hrAudit(String), 50(Integer), null, 29(String)
2012-10-11 22:00:45,766 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,766 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==>  Preparing: select * from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,767 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==> Parameters: 29(String)
2012-10-11 22:00:45,768 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,768 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==>  Preparing: select * from ACT_RU_EXECUTION where ID_ = ? 
2012-10-11 22:00:45,768 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==> Parameters: 18(String)
2012-10-11 22:00:45,770 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,770 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ==>  Preparing: select * from ACT_RU_VARIABLE where EXECUTION_ID_ = ? and TASK_ID_ is null 
2012-10-11 22:00:45,770 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ==> Parameters: 18(String)
2012-10-11 22:00:45,773 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,773 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==>  Preparing: select * from ACT_HI_ACTINST HAI WHERE HAI.EXECUTION_ID_ = ? and HAI.ACT_ID_ = ? and HAI.END_TIME_ is null LIMIT ? OFFSET ? 
2012-10-11 22:00:45,773 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==> Parameters: 18(String), hrAudit(String), 1(Integer), 0(Integer)
2012-10-11 22:00:45,775 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,775 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==>  Preparing: select * from ACT_RE_PROCDEF where ID_ = ? 
2012-10-11 22:00:45,775 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==> Parameters: leave:1:4(String)
2012-10-11 22:00:45,776 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,776 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ==>  Preparing: select * from ACT_RU_TASK where PARENT_TASK_ID_ = ? 
2012-10-11 22:00:45,777 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ==> Parameters: 29(String)
2012-10-11 22:00:45,778 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,778 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ==>  Preparing: select * from ACT_RU_IDENTITYLINK where TASK_ID_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,778 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ==> Parameters: 29(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,779 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,779 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ==>  Preparing: select * from ACT_RU_VARIABLE where TASK_ID_ = ? 
2012-10-11 22:00:45,779 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ==> Parameters: 29(String)
2012-10-11 22:00:45,780 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,780 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==>  Preparing: select * from ACT_HI_TASKINST where ID_ = ? 
2012-10-11 22:00:45,781 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==> Parameters: 29(String)
2012-10-11 22:00:45,782 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,782 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ==>  Preparing: select distinct T.* from ACT_RU_TASK T where T.EXECUTION_ID_ = ? 
2012-10-11 22:00:45,783 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ==> Parameters: 18(String)
2012-10-11 22:00:45,785 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,785 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ==>  Preparing: insert into ACT_RU_VARIABLE (ID_, REV_, TYPE_, NAME_, PROC_INST_ID_, EXECUTION_ID_, TASK_ID_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,785 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.insertVariableInstance] - ==> Parameters: 31(String), boolean(String), hrPass(String), 18(String), 18(String), null, null, null, 1(Long), null, null
2012-10-11 22:00:45,787 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,787 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==>  Preparing: insert into ACT_HI_DETAIL (ID_, TYPE_, PROC_INST_ID_, EXECUTION_ID_, ACT_INST_ID_, TASK_ID_, NAME_, REV_, VAR_TYPE_, TIME_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 'VariableUpdate', ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,788 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==> Parameters: 32(String), 18(String), 18(String), 28(String), null, hrPass(String), 0(Integer), boolean(String), 2012-10-11 22:00:45.772(Timestamp), null, null, 1(Long), null, null
2012-10-11 22:00:45,789 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,789 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,790 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 33(String), leave:1:4(String), 18(String), 18(String), exclusivegateway6(String), Exclusive Gateway(String), exclusiveGateway(String), null, 2012-10-11 22:00:45.784(Timestamp), 2012-10-11 22:00:45.784(Timestamp), 0(Long)
2012-10-11 22:00:45,791 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,791 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,792 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 34(String), leave:1:4(String), 18(String), 18(String), reportBack(String), 销假(String), userTask(String), kafeitu(String), 2012-10-11 22:00:45.784(Timestamp), null, null
2012-10-11 22:00:45,793 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,793 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ==>  Preparing: insert into ACT_RU_TASK (ID_, REV_, NAME_, PARENT_TASK_ID_, DESCRIPTION_, PRIORITY_, CREATE_TIME_, OWNER_, ASSIGNEE_, DELEGATION_, EXECUTION_ID_, PROC_INST_ID_, PROC_DEF_ID_, TASK_DEF_KEY_, DUE_DATE_) values (?, 1, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,793 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.insertTask] - ==> Parameters: 35(String), 销假(String), null, null, 50(Integer), 2012-10-11 22:00:45.784(Timestamp), null, kafeitu(String), null, 18(String), 18(String), leave:1:4(String), reportBack(String), null
2012-10-11 22:00:45,794 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,794 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ==>  Preparing: insert into ACT_HI_TASKINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, NAME_, PARENT_TASK_ID_, DESCRIPTION_, OWNER_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_, DELETE_REASON_, TASK_DEF_KEY_, PRIORITY_, DUE_DATE_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,795 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.insertHistoricTaskInstance] - ==> Parameters: 35(String), leave:1:4(String), 18(String), 18(String), 销假(String), null, null, null, kafeitu(String), 2012-10-11 22:00:45.784(Timestamp), null, null, null, reportBack(String), 50(Integer), null
2012-10-11 22:00:45,796 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,796 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==>  Preparing: update ACT_HI_ACTINST set EXECUTION_ID_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ? where ID_ = ? 
2012-10-11 22:00:45,796 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==> Parameters: 18(String), hruser(String), 2012-10-11 22:00:45.784(Timestamp), 58(Long), 28(String)
2012-10-11 22:00:45,797 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.updateExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,798 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.updateExecution] - ==>  Preparing: update ACT_RU_EXECUTION set REV_ = ?, PROC_DEF_ID_ = ?, ACT_ID_ = ?, IS_ACTIVE_ = ?, IS_CONCURRENT_ = ?, IS_SCOPE_ = ?, IS_EVENT_SCOPE_ = ?, PARENT_ID_ = ?, SUPER_EXEC_ = ?, SUSPENSION_STATE_ = ?, CACHED_ENT_STATE_ = ? where ID_ = ? and REV_ = ? 
2012-10-11 22:00:45,798 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.updateExecution] - ==> Parameters: 3(Integer), leave:1:4(String), reportBack(String), true(Boolean), false(Boolean), true(Boolean), false(Boolean), null, null, 1(Integer), 2(Integer), 18(String), 2(Integer)
2012-10-11 22:00:45,799 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,799 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==>  Preparing: update ACT_HI_TASKINST set EXECUTION_ID_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, DESCRIPTION_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ?, DELETE_REASON_ = ?, TASK_DEF_KEY_ = ?, PRIORITY_ = ?, DUE_DATE_ = ? where ID_ = ? 
2012-10-11 22:00:45,800 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==> Parameters: 18(String), 人事审批(String), null, null, null, hruser(String), 2012-10-11 22:00:45.782(Timestamp), 56(Long), completed(String), hrAudit(String), 50(Integer), null, 29(String)
2012-10-11 22:00:45,800 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.deleteIdentityLink] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,801 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.deleteIdentityLink] - ==>  Preparing: delete from ACT_RU_IDENTITYLINK where ID_ = ? 
2012-10-11 22:00:45,801 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.deleteIdentityLink] - ==> Parameters: 30(String)
2012-10-11 22:00:45,801 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,801 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ==>  Preparing: delete from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,802 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ==> Parameters: 29(String)
2012-10-11 22:00:45,804 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,804 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ==>  Preparing: select distinct T.* from ACT_RU_TASK T WHERE T.ASSIGNEE_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,804 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTaskByQueryCriteria] - ==> Parameters: kafeitu(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,809 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,809 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==>  Preparing: select * from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,810 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTask] - ==> Parameters: 35(String)
2012-10-11 22:00:45,812 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,812 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==>  Preparing: select * from ACT_RU_EXECUTION where ID_ = ? 
2012-10-11 22:00:45,812 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecution] - ==> Parameters: 18(String)
2012-10-11 22:00:45,814 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,814 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ==>  Preparing: select * from ACT_RU_VARIABLE where EXECUTION_ID_ = ? and TASK_ID_ is null 
2012-10-11 22:00:45,814 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByExecutionId] - ==> Parameters: 18(String)
2012-10-11 22:00:45,817 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,817 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==>  Preparing: select * from ACT_HI_ACTINST HAI WHERE HAI.EXECUTION_ID_ = ? and HAI.ACT_ID_ = ? and HAI.END_TIME_ is null LIMIT ? OFFSET ? 
2012-10-11 22:00:45,818 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.selectHistoricActivityInstancesByQueryCriteria] - ==> Parameters: 18(String), reportBack(String), 1(Integer), 0(Integer)
2012-10-11 22:00:45,820 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,820 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==>  Preparing: select * from ACT_RE_PROCDEF where ID_ = ? 
2012-10-11 22:00:45,820 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ProcessDefinitionEntity.selectProcessDefinitionById] - ==> Parameters: leave:1:4(String)
2012-10-11 22:00:45,860 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectProcessInstanceByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,860 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectProcessInstanceByQueryCriteria] - ==>  Preparing: select E.* from ACT_RU_EXECUTION E inner join ACT_RE_PROCDEF P on E.PROC_DEF_ID_ = P.ID_ WHERE E.PARENT_ID_ is null and E.ID_ = ? and E.PROC_INST_ID_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,861 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectProcessInstanceByQueryCriteria] - ==> Parameters: 18(String), 18(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,867 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,867 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ==>  Preparing: select * from ACT_RU_VARIABLE where TASK_ID_ = ? 
2012-10-11 22:00:45,867 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.selectVariablesByTaskId] - ==> Parameters: 35(String)
2012-10-11 22:00:45,871 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,871 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ==>  Preparing: select * from ACT_RU_TASK where PARENT_TASK_ID_ = ? 
2012-10-11 22:00:45,872 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByParentTaskId] - ==> Parameters: 35(String)
2012-10-11 22:00:45,873 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,873 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ==>  Preparing: select * from ACT_RU_IDENTITYLINK where TASK_ID_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,873 [main] DEBUG [org.activiti.engine.impl.persistence.entity.IdentityLinkEntity.selectIdentityLinksByTask] - ==> Parameters: 35(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,874 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,874 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==>  Preparing: select * from ACT_HI_TASKINST where ID_ = ? 
2012-10-11 22:00:45,874 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.selectHistoricTaskInstance] - ==> Parameters: 35(String)
2012-10-11 22:00:45,876 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,876 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ==>  Preparing: select distinct T.* from ACT_RU_TASK T where T.EXECUTION_ID_ = ? 
2012-10-11 22:00:45,876 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.selectTasksByExecutionId] - ==> Parameters: 18(String)
2012-10-11 22:00:45,878 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.selectHistoricProcessInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,878 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.selectHistoricProcessInstance] - ==>  Preparing: select * from ACT_HI_PROCINST where PROC_INST_ID_ = ? 
2012-10-11 22:00:45,878 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.selectHistoricProcessInstance] - ==> Parameters: 18(String)
2012-10-11 22:00:45,880 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecutionsByParentExecutionId] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,880 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecutionsByParentExecutionId] - ==>  Preparing: select * from ACT_RU_EXECUTION where PARENT_ID_ = ? LIMIT ? OFFSET ? 
2012-10-11 22:00:45,880 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.selectExecutionsByParentExecutionId] - ==> Parameters: 18(String), 2147483647(Integer), 0(Integer)
2012-10-11 22:00:45,881 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,882 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==>  Preparing: insert into ACT_HI_DETAIL (ID_, TYPE_, PROC_INST_ID_, EXECUTION_ID_, ACT_INST_ID_, TASK_ID_, NAME_, REV_, VAR_TYPE_, TIME_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 'VariableUpdate', ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,882 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==> Parameters: 37(String), 18(String), 18(String), 34(String), null, realityEndTime(String), 0(Integer), date(String), 2012-10-11 22:00:45.816(Timestamp), null, null, 1337821200000(Long), null, null
2012-10-11 22:00:45,883 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,883 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==>  Preparing: insert into ACT_HI_DETAIL (ID_, TYPE_, PROC_INST_ID_, EXECUTION_ID_, ACT_INST_ID_, TASK_ID_, NAME_, REV_, VAR_TYPE_, TIME_, BYTEARRAY_ID_, DOUBLE_, LONG_ , TEXT_, TEXT2_) values ( ?, 'VariableUpdate', ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,884 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricDetailEntity.insertHistoricVariableUpdate] - ==> Parameters: 39(String), 18(String), 18(String), 34(String), null, realityStartTime(String), 0(Integer), date(String), 2012-10-11 22:00:45.819(Timestamp), null, null, 1337662800000(Long), null, null
2012-10-11 22:00:45,885 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,885 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==>  Preparing: insert into ACT_HI_ACTINST ( ID_, PROC_DEF_ID_, PROC_INST_ID_, EXECUTION_ID_, ACT_ID_, ACT_NAME_, ACT_TYPE_, ASSIGNEE_, START_TIME_, END_TIME_, DURATION_ ) values ( ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ? ) 
2012-10-11 22:00:45,885 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.insertHistoricActivityInstance] - ==> Parameters: 40(String), leave:1:4(String), 18(String), 18(String), endevent1(String), End(String), endEvent(String), null, 2012-10-11 22:00:45.877(Timestamp), 2012-10-11 22:00:45.878(Timestamp), 1(Long)
2012-10-11 22:00:45,886 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,886 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==>  Preparing: update ACT_HI_ACTINST set EXECUTION_ID_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ? where ID_ = ? 
2012-10-11 22:00:45,886 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricActivityInstanceEntity.updateHistoricActivityInstance] - ==> Parameters: 18(String), kafeitu(String), 2012-10-11 22:00:45.877(Timestamp), 93(Long), 34(String)
2012-10-11 22:00:45,887 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.updateHistoricProcessInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,887 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.updateHistoricProcessInstance] - ==>  Preparing: update ACT_HI_PROCINST set PROC_DEF_ID_ = ?, START_TIME_ = ?, END_TIME_ = ?, DURATION_ = ?, END_ACT_ID_ = ?, DELETE_REASON_ = ? where ID_ = ? 
2012-10-11 22:00:45,888 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.updateHistoricProcessInstance] - ==> Parameters: leave:1:4(String), 2012-10-11 22:00:45.611(Timestamp), 2012-10-11 22:00:45.879(Timestamp), 268(Long), endevent1(String), null, 18(String)
2012-10-11 22:00:45,889 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,889 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==>  Preparing: update ACT_HI_TASKINST set EXECUTION_ID_ = ?, NAME_ = ?, PARENT_TASK_ID_ = ?, DESCRIPTION_ = ?, OWNER_ = ?, ASSIGNEE_ = ?, END_TIME_ = ?, DURATION_ = ?, DELETE_REASON_ = ?, TASK_DEF_KEY_ = ?, PRIORITY_ = ?, DUE_DATE_ = ? where ID_ = ? 
2012-10-11 22:00:45,890 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricTaskInstanceEntity.updateHistoricTaskInstance] - ==> Parameters: 18(String), 销假(String), null, null, null, kafeitu(String), 2012-10-11 22:00:45.876(Timestamp), 92(Long), completed(String), reportBack(String), 50(Integer), null, 35(String)
2012-10-11 22:00:45,891 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,891 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ==>  Preparing: delete from ACT_RU_TASK where ID_ = ? 
2012-10-11 22:00:45,891 [main] DEBUG [org.activiti.engine.impl.persistence.entity.TaskEntity.deleteTask] - ==> Parameters: 35(String)
2012-10-11 22:00:45,892 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,892 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ==>  Preparing: delete from ACT_RU_VARIABLE where ID_ = ? 
2012-10-11 22:00:45,892 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ==> Parameters: 19(String)
2012-10-11 22:00:45,893 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,893 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ==>  Preparing: delete from ACT_RU_VARIABLE where ID_ = ? 
2012-10-11 22:00:45,893 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ==> Parameters: 31(String)
2012-10-11 22:00:45,894 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,894 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ==>  Preparing: delete from ACT_RU_VARIABLE where ID_ = ? 
2012-10-11 22:00:45,894 [main] DEBUG [org.activiti.engine.impl.persistence.entity.VariableInstanceEntity.deleteVariableInstance] - ==> Parameters: 25(String)
2012-10-11 22:00:45,895 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.deleteExecution] - ooo Using Connection [Transaction-aware proxy for target Connection [org.hibernate.engine.jdbc.internal.proxy.ConnectionProxyHandler@49e61582[valid=true]]]
2012-10-11 22:00:45,895 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.deleteExecution] - ==>  Preparing: delete from ACT_RU_EXECUTION where ID_ = ? 
2012-10-11 22:00:45,895 [main] DEBUG [org.activiti.engine.impl.persistence.entity.ExecutionEntity.deleteExecution] - ==> Parameters: 18(String)
2012-10-11 22:00:45,925 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.selectHistoricProcessInstanceCountByQueryCriteria] - ooo Using Connection [Transaction-aware proxy for target Connection  from DataSource [org.springframework.jdbc.datasource.SimpleDriverDataSource@3ab6a5fb]]
2012-10-11 22:00:45,926 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.selectHistoricProcessInstanceCountByQueryCriteria] - ==>  Preparing: select count(*) from ACT_HI_PROCINST HPI WHERE HPI.PROC_DEF_ID_ like ? and HPI.END_TIME_ is not NULL 
2012-10-11 22:00:45,926 [main] DEBUG [org.activiti.engine.impl.persistence.entity.HistoricProcessInstanceEntity.selectHistoricProcessInstanceCountByQueryCriteria] - ==> Parameters: leave:%:%(String)
2012-10-11 22:00:45,929 [main] INFO  [org.springframework.test.context.transaction.TransactionalTestExecutionListener] - Rolled back transaction after test execution for test context [[TestContext@3aae43b8 testClass = LeaveWorkflowServiceTest, testInstance = me.kafeitu.demo.activiti.service.oa.leave.LeaveWorkflowServiceTest@6e00321, testMethod = testAllPass@LeaveWorkflowServiceTest, testException = [null], mergedContextConfiguration = [MergedContextConfiguration@5ced6f0d testClass = LeaveWorkflowServiceTest, locations = '{classpath:/applicationContext.xml}', classes = '{}', activeProfiles = '{test}', contextLoader = 'org.springframework.test.context.support.DelegatingSmartContextLoader']]]
2012-10-11 22:00:45,937 [Thread-5] INFO  [org.springframework.context.support.GenericApplicationContext] - Closing org.springframework.context.support.GenericApplicationContext@351563ff: startup date [Thu Oct 11 22:00:38 CST 2012]; root of context hierarchy
2012-10-11 22:00:45,938 [Thread-5] INFO  [org.springframework.beans.factory.support.DefaultListableBeanFactory] - Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@73367e28: defining beans [workflowProcessDefinitionService,workflowTraceService,afterModifyApplyContentProcessor,leaveManager,leaveWorkflowService,reportBackEndProcessor,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,entityManagerFactory,hibernateJpaVendorAdapter,leaveDao,org.springframework.data.repository.core.support.RepositoryInterfaceAwareBeanPostProcessor#0,org.springframework.dao.annotation.PersistenceExceptionTranslationPostProcessor#0,org.springframework.orm.jpa.support.PersistenceAnnotationBeanPostProcessor#0,transactionManager,org.springframework.aop.config.internalAutoProxyCreator,org.springframework.transaction.annotation.AnnotationTransactionAttributeSource#0,org.springframework.transaction.interceptor.TransactionInterceptor#0,org.springframework.transaction.config.internalTransactionAdvisor,processEngineConfiguration,processEngine,repositoryService,runtimeService,formService,identityService,taskService,historyService,managementService,org.springframework.context.support.PropertySourcesPlaceholderConfigurer#0,dataSource,me.kafeitu.modules.test.data.DataInitializer#0,org.springframework.context.annotation.ConfigurationClassPostProcessor$ImportAwareBeanPostProcessor#0]; root of factory hierarchy
2012-10-11 22:00:45,944 [Thread-5] INFO  [org.springframework.orm.jpa.LocalContainerEntityManagerFactoryBean] - Closing JPA EntityManagerFactory for persistence unit 'defaultPU'