[JBoss jBPM] - error testing atm bpel process, java.lang.reflect.Invocation - jboss-user

Monday, 2 October 2006

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

View the original post :
http://www.jboss.com/index.html?module=bb&op=viewtopic&p=3975585#...

Reply to the post :
http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&a...

2026

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[JBoss jBPM] - error testing atm bpel process, java.lang.reflect.Invocation