error testing atm bpel process, java.lang.reflect.Invocation
Hi,
I don't really understand what is happening here. I am just testing the atm process (ant run-test ) and I get several errors.
[org.jbpm.bpel.integration.jms.RequestListener] could not resume process execution
The server log is here.
2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.variable.def.MessageType#15] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.variable.def.MessageType#15] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.variable.def.MessageType#16] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.variable.def.MessageType#16] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.variable.def.MessageType#17] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.variable.def.MessageType#17] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.bpel.def.ImportsDefinition.messageTypes 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.bpel.def.ImportsDefinition.messageTypes#4] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.bpel.def.ImportsDefinition.messageTypes 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections 2006-10-02 20:36:16,375 DEBUG [org.hibernate.loader.Loader] done loading collection 2006-10-02 20:36:16,375 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.bpel.variable.def.VariableType.propertyAliases#12] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 1) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.SQL] insert into JBPM_TIMER (ID_, NAME_, DUEDATE_, REPEAT_, TRANSITIONNAME_, EXCEPTION_, ISSUSPENDED_, ACTION_, TOKEN_, PROCESSINSTANCE_, TASKINSTANCE_, GRAPHELEMENTTYPE_, GRAPHELEMENT_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.SQL] select propertyal0_.VARIABLETYPE_ as VARIABL10_1_, propertyal0_.id as id1_, propertyal0_.LOCALNAME_ as LOCALNAME11_1_, propertyal0_.NAMESPACE_ as NAMESPACE12_1_, propertyal0_.id as id62_0_, propertyal0_.PROPERTY_ as PROPERTY2_62_0_, propertyal0_.MESSAGE_ as MESSAGE3_62_0_, propertyal0_.PART_ as PART4_62_0_, propertyal0_.TYPELOCALNAME_ as TYPELOCA5_62_0_, propertyal0_.TYPENAMESPACE_ as TYPENAME6_62_0_, propertyal0_.ELEMENTLOCALNAME_ as ELEMENTL7_62_0_, propertyal0_.ELEMENTNAMESPACE_ as ELEMENTN8_62_0_, propertyal0_.QUERY_ as QUERY9_62_0_ from BPEL_PROPERTYALIAS propertyal0_ where propertyal0_.VARIABLETYPE_=? 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 2) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 1) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,375 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.bpel.variable.def.VariableType.propertyAliases#12] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.bpel.wsdl.impl.PropertyAliasImpl#5] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 3 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 2) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.bpel.variable.def.VariableType.propertyAliases#12] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.wsdl.impl.PropertyAliasImpl#5] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.bpel.wsdl.impl.PropertyAliasImpl#5] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.bpel.wsdl.impl.PropertyAliasImpl#5 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.wsdl.impl.PropertyAliasImpl#5] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.bpel.variable.def.VariableType.propertyAliases 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.bpel.variable.def.VariableType.propertyAliases#12] 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.bpel.variable.def.VariableType.propertyAliases 2006-10-02 20:36:16,375 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections 2006-10-02 20:36:16,375 DEBUG [org.hibernate.loader.Loader] done loading collection 2006-10-02 20:36:16,375 ERROR [org.jbpm.bpel.integration.jms.RequestListener] could not resume process execution java.lang.reflect.InvocationTargetException at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.Pick_$$_javassist_159.messageReceived(Pick_$$_javassist_159.java) at org.jbpm.bpel.integration.jms.RequestListener.onMessage(RequestListener.java:135) at org.jboss.mq.SpyMessageConsumer.run(SpyMessageConsumer.java:694) at java.lang.Thread.run(Thread.java:595) Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor166.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.bpel.def.Pick.pickPath(Pick.java:121) at org.jbpm.bpel.def.Pick.messageReceived(Pick.java:87) ... 9 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor160.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.Activity_$$_javassist_11.enter(Activity_$$_javassist_11.java) at org.jbpm.bpel.def.Scope.execute(Scope.java:138) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) ... 16 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor160.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.enter(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.bpel.def.StructuredActivity.enter(StructuredActivity.java:52) ... 25 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor181.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.CompositeActivity_$$_javassist_8.execute(CompositeActivity_$$_javassist_8.java) at org.jbpm.bpel.def.StructuredActivity$Begin.leave(StructuredActivity.java:219) at org.jbpm.graph.def.Node.execute(Node.java:339) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 31 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor166.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.bpel.def.StructuredActivity.execute(StructuredActivity.java:56) ... 39 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) ... 45 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.Activity.leave(Activity.java:171) at org.jbpm.bpel.def.Assign.execute(Assign.java:55) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 52 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.Activity.leave(Activity.java:171) at org.jbpm.bpel.def.Invoke.execute(Invoke.java:45) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 62 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor181.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.CompositeActivity_$$_javassist_8.execute(CompositeActivity_$$_javassist_8.java) at org.jbpm.bpel.def.StructuredActivity$Begin.leave(StructuredActivity.java:219) at org.jbpm.graph.def.Node.execute(Node.java:339) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 72 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor166.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.bpel.def.If.execute(If.java:80) ... 80 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor160.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.enter(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) ... 86 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor166.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.bpel.def.StructuredActivity.execute(StructuredActivity.java:56) at org.jbpm.bpel.def.StructuredActivity$Begin.leave(StructuredActivity.java:219) at org.jbpm.graph.def.Node.execute(Node.java:339) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 93 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) ... 102 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.Activity.leave(Activity.java:171) at org.jbpm.bpel.def.Assign.execute(Assign.java:55) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 109 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor175.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$End_$$_javassist_57.enter(StructuredActivity$End_$$_javassist_57.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.Activity.leave(Activity.java:171) at org.jbpm.bpel.def.Reply.execute(Reply.java:45) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 119 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor165.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$End_$$_javassist_57.leave(StructuredActivity$End_$$_javassist_57.java) at org.jbpm.bpel.def.StructuredActivity.leave(StructuredActivity.java:60) at org.jbpm.bpel.def.StructuredActivity$End.execute(StructuredActivity.java:243) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 129 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor175.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$End_$$_javassist_57.enter(StructuredActivity$End_$$_javassist_57.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.Activity.leave(Activity.java:171) ... 137 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor165.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.CompositeActivity_$$_javassist_8.leave(CompositeActivity_$$_javassist_8.java) at org.jbpm.bpel.def.StructuredActivity$End.execute(StructuredActivity.java:243) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 145 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor165.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$End_$$_javassist_57.leave(StructuredActivity$End_$$_javassist_57.java) at org.jbpm.bpel.def.StructuredActivity.leave(StructuredActivity.java:60) ... 152 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.Activity.leave(Activity.java:171) ... 158 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor181.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.CompositeActivity_$$_javassist_8.execute(CompositeActivity_$$_javassist_8.java) at org.jbpm.bpel.def.StructuredActivity$Begin.leave(StructuredActivity.java:219) at org.jbpm.graph.def.Node.execute(Node.java:339) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 166 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor166.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java) at org.jbpm.bpel.def.StructuredActivity.execute(StructuredActivity.java:56) ... 174 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor160.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.While$Loop_$$_javassist_157.enter(While$Loop_$$_javassist_157.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) ... 180 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor164.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.graph.def.Node_$$_javassist_47.enter(Node_$$_javassist_47.java) at org.jbpm.graph.def.Transition.take(Transition.java:119) at org.jbpm.graph.def.Node.leave(Node.java:383) at org.jbpm.bpel.def.While$Loop.execute(While.java:178) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 187 more Caused by: java.lang.reflect.InvocationTargetException at sun.reflect.GeneratedMethodAccessor181.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:585) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.def.CompositeActivity_$$_javassist_8.execute(CompositeActivity_$$_javassist_8.java) at org.jbpm.bpel.def.StructuredActivity$Begin.leave(StructuredActivity.java:219) at org.jbpm.graph.def.Node.execute(Node.java:339) at org.jbpm.bpel.def.Activity.enter(Activity.java:101) ... 196 more Caused by: java.lang.IllegalAccessException: Class org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer can not access a member of class org.jbpm.bpel.sublang.def.Snippet with modifiers "public" at sun.reflect.Reflection.ensureMemberAccess(Reflection.java:65) at java.lang.reflect.Method.invoke(Method.java:578) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:208) at org.jbpm.bpel.sublang.def.Expression_$$_javassist_94.getText(Expression_$$_javassist_94.java) at org.jbpm.bpel.alarm.Alarm.toString(Alarm.java:145) at java.lang.String.valueOf(String.java:2577) at java.lang.StringBuffer.append(StringBuffer.java:220) at org.jbpm.bpel.alarm.Alarm.createTimer(Alarm.java:93) at org.jbpm.bpel.def.Pick.execute(Pick.java:69) ... 204 more 2006-10-02 20:36:16,375 DEBUG [org.jbpm.bpel.integration.server.SoapHandler] sent request: id=ID:2-115981417637526, destination=QUEUE.testQueue, replyTo=QUEUE.JMS_TQ14, correlationId=null, _$partnerLinkId=2, _$operationName=getBalance, customerId=ernie 2006-10-02 20:36:16,375 DEBUG [org.jbpm.configuration.JbpmContextInfo] creating jbpm context with service factories '[message, integration, scheduler, logging, persistence, authentication]' 2006-10-02 20:36:16,390 DEBUG [org.jbpm.JbpmContext] closing JbpmContext 2006-10-02 20:36:16,390 DEBUG [org.jbpm.JbpmContext] creating JbpmContext 2006-10-02 20:36:16,390 DEBUG [org.jboss.mq.security.SecurityManager] No SecurityMetadadata was available for JMS_TQ14 using default security config 2006-10-02 20:36:16,390 DEBUG [org.jbpm.svc.Services] executing default save operations 2006-10-02 20:36:16,390 DEBUG [org.jbpm.bpel.integration.jms.RequestListener] received request: id=ID:2-115981417637526, destination=QUEUE.testQueue, replyTo=QUEUE.JMS_TQ14, correlationId=null, _$partnerLinkId=2, _$operationName=getBalance, JMSXDeliveryCount=1, customerId=ernie 2006-10-02 20:36:16,390 DEBUG [org.jbpm.bpel.integration.server.SoapHandler] listening for response: JMS_TQ14 2006-10-02 20:36:16,390 DEBUG [org.jbpm.persistence.db.DbPersistenceService] creating hibernate session 2006-10-02 20:36:16,390 DEBUG [org.jbpm.svc.save.HibernateSaveOperation] saving process instance 2006-10-02 20:36:16,390 DEBUG [org.hibernate.impl.SessionImpl] opened session at timestamp: 4750598866493440 2006-10-02 20:36:16,390 DEBUG [org.jbpm.persistence.db.DbPersistenceService] beginning hibernate transaction 2006-10-02 20:36:16,390 DEBUG [org.jbpm.svc.save.SaveLogsOperation] flushing logs to logging service. 2006-10-02 20:36:16,390 DEBUG [org.hibernate.transaction.JDBCTransaction] begin 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.ConnectionManager] opening JDBC connection 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.transaction.JDBCTransaction] current autocommit status: true 2006-10-02 20:36:16,390 DEBUG [org.hibernate.transaction.JDBCTransaction] disabling autocommit 2006-10-02 20:36:16,390 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.graph.exe.Token#32] 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, NODE_, ENTER_, LEAVE_, DURATION_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, 'N') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jbpm.graph.exe.Token#32] 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] select token0_.ID_ as ID1_20_0_, token0_.VERSION_ as VERSION2_20_0_, token0_.NAME_ as NAME3_20_0_, token0_.START_ as START4_20_0_, token0_.END_ as END5_20_0_, token0_.NODEENTER_ as NODEENTER6_20_0_, token0_.NEXTLOGINDEX_ as NEXTLOGI7_20_0_, token0_.ISABLETOREACTIVATEPARENT_ as ISABLETO8_20_0_, token0_.ISTERMINATIONIMPLICIT_ as ISTERMIN9_20_0_, token0_.ISSUSPENDED_ as ISSUSPE10_20_0_, token0_.NODE_ as NODE11_20_0_, token0_.PROCESSINSTANCE_ as PROCESS12_20_0_, token0_.PARENT_ as PARENT13_20_0_, token0_.SUBPROCESSINSTANCE_ as SUBPROC14_20_0_ from JBPM_TOKEN token0_ where token0_.ID_=? 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 2) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 2) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 432 2006-10-02 20:36:16,390 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.loader.Loader] done entity load 2006-10-02 20:36:16,390 ERROR [org.jbpm.bpel.integration.jms.RequestListener] could not resume process execution org.hibernate.ObjectNotFoundException: No row with the given identifier exists: [org.jbpm.graph.exe.Token#32] at org.hibernate.impl.SessionFactoryImpl$1.handleEntityNotFound(SessionFactoryImpl.java:372) at org.hibernate.proxy.AbstractLazyInitializer.checkTargetState(AbstractLazyInitializer.java:79) at org.hibernate.proxy.AbstractLazyInitializer.initialize(AbstractLazyInitializer.java:68) at org.hibernate.proxy.AbstractLazyInitializer.getImplementation(AbstractLazyInitializer.java:111) at org.hibernate.proxy.pojo.javassist.JavassistLazyInitializer.invoke(JavassistLazyInitializer.java:196) at org.jbpm.graph.exe.Token_$$_javassist_61.getProcessInstance(Token_$$_javassist_61.java) at org.jbpm.JbpmContext.addAutoSaveToken(JbpmContext.java:574) at org.jbpm.JbpmContext.loadTokenForUpdate(JbpmContext.java:289) at org.jbpm.bpel.integration.jms.RequestListener.onMessage(RequestListener.java:116) at org.jboss.mq.SpyMessageConsumer.run(SpyMessageConsumer.java:694) at java.lang.Thread.run(Thread.java:595) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.jbpm.JbpmContext] closing JbpmContext 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.jbpm.svc.Services] closing service 'persistence': org.jbpm.bpel.persistence.db.DbPersistenceService@1afcc2c 2006-10-02 20:36:16,390 DEBUG [org.jbpm.persistence.db.DbPersistenceService] rolling back hibernate transaction 2006-10-02 20:36:16,390 DEBUG [org.hibernate.transaction.JDBCTransaction] rollback 2006-10-02 20:36:16,390 DEBUG [org.hibernate.transaction.JDBCTransaction] re-enabling autocommit 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, TRANSITION_, SOURCENODE_, DESTINATIONNODE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'T') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.transaction.JDBCTransaction] rolled back JDBC Connection 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.ConnectionManager] aggressively releasing JDBC connection 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.ConnectionManager] releasing JDBC connection [ (open PreparedStatements: 0, globally: 1) (open ResultSets: 0, globally: 0)] 2006-10-02 20:36:16,390 DEBUG [org.jbpm.persistence.db.DbPersistenceService] closing hibernate session 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 433 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, CHILD_, CLASS_) values (null, ?, ?, ?, ?, ?, 'O') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 434 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, CHILD_, CLASS_) values (null, ?, ?, ?, ?, ?, 'O') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 435 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into BPEL_SCOPEINSTANCE (ID_, STATE_, DEFINITION_, TOKEN_, FAULT_, COMPENSATIONLISTENERCLASS_, COMPENSATIONLISTENER_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, 'S') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 17 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 108 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 436 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, OLDLONGIDCLASS_, OLDLONGIDVALUE_, NEWLONGIDCLASS_, NEWLONGIDVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 437 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into BPEL_MESSAGEVALUE (ID_, TYPE_) values (null, ?) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 54 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 109 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 438 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, OLDLONGIDCLASS_, OLDLONGIDVALUE_, NEWLONGIDCLASS_, NEWLONGIDVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 439 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into BPEL_MESSAGEVALUE (ID_, TYPE_) values (null, ?) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 55 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 110 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 440 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, OLDLONGIDCLASS_, OLDLONGIDVALUE_, NEWLONGIDCLASS_, NEWLONGIDVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 441 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into BPEL_MESSAGEVALUE (ID_, TYPE_) values (null, ?) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 56 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 111 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 442 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, OLDLONGIDCLASS_, OLDLONGIDVALUE_, NEWLONGIDCLASS_, NEWLONGIDVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 443 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] insert into BPEL_MESSAGEVALUE (ID_, TYPE_) values (null, ?) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,390 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 57 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,390 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,390 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 112 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 444 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, OLDLONGIDCLASS_, OLDLONGIDVALUE_, NEWLONGIDCLASS_, NEWLONGIDVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 445 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into BPEL_MESSAGEVALUE (ID_, TYPE_) values (null, ?) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 58 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 113 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 446 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, OLDLONGIDCLASS_, OLDLONGIDVALUE_, NEWLONGIDCLASS_, NEWLONGIDVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 447 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, ELEMENTVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, 'E') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 114 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_LOG (ID_, INDEX_, DATE_, TOKEN_, PARENT_, VARIABLEINSTANCE_, CLASS_) values (null, ?, ?, ?, ?, ?, 'R') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 448 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into BPEL_MESSAGEVALUE (ID_, TYPE_) values (null, ?) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] call identity() 2006-10-02 20:36:16,406 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 59 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.SQL] insert into JBPM_VARIABLEINSTANCE (ID_, NAME_, CONVERTER_, TOKEN_, TOKENVARIABLEMAP_, PROCESSINSTANCE_, LONGIDCLASS_, LONGVALUE_, CLASS_) values (null, ?, ?, ?, ?, ?, ?, ?, 'H') 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1) 2006-10-02 20:36:16,406 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, gl
