4 Replies Latest reply on Oct 9, 2006 2:51 PM by Alejandro Guizar

    error testing atm bpel process, java.lang.reflect.Invocation

    Jose Juan Avila Newbie

      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


        • 1. Re: error testing atm bpel process, java.lang.reflect.Invoca
          Jose Juan Avila Newbie

          HI again.

          The problem is not solved.
          I have no run the "test terminal" and I manage to connect and log-on without problems.

          the problem starts when I introduce the name ex: "ernie"

          10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#330]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#331]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#332]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 1)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.bpel.def.Pick.onAlarms#47]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#333]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: null, EntityKey[org.jbpm.bpel.alarm.Alarm#2]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#334]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#335]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#336]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#337]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.context.exe.VariableInstance#338]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 2)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 2)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.bpel.def.Pick.onAlarms#47]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#329]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.alarm.Alarm#2]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#329]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.variable.exe.ElementInstance#330]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.bpel.alarm.Alarm#2]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.variable.exe.ElementInstance#330]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Action#2
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#331]
          2006-10-02 21:30:06,625 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Action: Is element with key org.jbpm.graph.def.Action#2 expired?: false
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.alarm.Alarm#2]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#331]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.bpel.def.Pick.onAlarms
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.variable.exe.ElementInstance#332]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.bpel.def.Pick.onAlarms#47]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.bpel.def.Pick.onAlarms
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.variable.exe.ElementInstance#332]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] done loading collection
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#333]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#333]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#334]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.sublang.def.Expression#21]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.sublang.def.Snippet#21
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#334]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.sublang.def.Snippet#21
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#335]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.bpel.sublang.def.Snippet#21 is null
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jbpm.bpel.sublang.def.Expression#21]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#335]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#336]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#336]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#337]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.SQL]
           select
           expression0_.ID_ as ID1_46_0_,
           expression0_.TEXT_ as TEXT3_46_0_,
           expression0_.LANGUAGE_ as LANGUAGE4_46_0_
           from
           BPEL_SCRIPT expression0_
           where
           expression0_.ID_=?
           and expression0_.CLASS_='e'
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#337]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.context.exe.variableinstance.HibernateLongInstance#338]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.context.exe.variableinstance.HibernateLongInstance#338]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.context.exe.TokenVariableMap.variableInstances
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.context.exe.TokenVariableMap.variableInstances#39]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.context.exe.TokenVariableMap.variableInstances
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] done loading collection
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.integration.exe.PartnerLinkInstance#45]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.bpel.sublang.def.Expression#21]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.integration.exe.PartnerLinkInstance#45
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.integration.exe.PartnerLinkInstance#45
          2006-10-02 21:30:06,625 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.integration.exe.PartnerLinkInstance: Is element with key org.jbpm.bpel.integration.exe.PartnerLinkInstance#45 expired?: false
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache hit
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.sublang.def.Expression#21]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.bpel.sublang.def.Expression#21]
          2006-10-02 21:30:06,625 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.bpel.sublang.def.Snippet#21
          2006-10-02 21:30:06,640 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.sublang.def.Snippet: Is element with key org.jbpm.bpel.sublang.def.Snippet#2 expired?: false
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.sublang.def.Expression#21]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] done entity load
          2006-10-02 21:30:06,640 DEBUG [org.jbpm.bpel.integration.jms.IntegrationControl] added outstanding request: receiver=org.jbpm.bpel.integration.def.Receiver@26a0ba[partnerLink=atm,operation=status,messageExchange=<null>,activity=OnEvent(1293ce0),id=9], token=Token(/normalFlow/connectionUnit/events), request=org.jbpm.bpel.integration.jms.OutstandingRequest@147813b[replyQueue=JMS_TQ4,correlationID=ID:1-11598174065937]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.variable.def.VariableDefinition#18]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.variable.def.VariableDefinition#18
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.variable.def.VariableDefinition#18
          2006-10-02 21:30:06,640 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.variable.def.VariableDefinition: Is element with key org.jbpm.bpel.variable.def.VariableDefinition#18 expired?: false
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache hit
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.event.def.AbstractSaveEventListener] executing identity-insert immediately
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.variable.exe.MessageValue#173]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.variable.exe.MessageValue#173
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.variable.exe.MessageValue#173
          2006-10-02 21:30:06,640 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.variable.exe.MessageValue: Is element with key org.jbpm.bpel.variable.exe.MessageValue#173 expired?: false
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache hit
          2006-10-02 21:30:06,640 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 21:30:06,640 WARN [org.hibernate.engine.StatefulPersistenceContext.ProxyWarnLog] Narrowing proxy to class org.jbpm.bpel.variable.def.MessageType - this operation breaks ==
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.bpel.variable.exe.MessageValue.parts#173]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.SQL]
           select
           parts0_.MSGINSTANCE_ as MSGINSTA1_0_,
           parts0_.VALUE_ as VALUE2_0_,
           parts0_.NAME_ as NAME3_0_
           from
           BPEL_MSGVALUE_PART parts0_
           where
           parts0_.MSGINSTANCE_=?
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.bpel.variable.exe.MessageValue.parts#173]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] result row:
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.bpel.variable.exe.MessageValue.parts#173]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 1)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.SQL]
           call identity()
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.id.IdentifierGeneratorFactory] Natively generated identity: 11
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 2)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.bpel.variable.exe.MessageValue.parts
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.bpel.variable.exe.MessageValue.parts#173]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.bpel.variable.exe.MessageValue.parts
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] done loading collection
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.variable.def.MessageType#9]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jbpm.bpel.variable.def.MessageType#9]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.SQL]
           select
           messagetyp0_.ID_ as ID1_43_0_,
           messagetyp0_.MESSAGE_ as MESSAGE3_43_0_
           from
           BPEL_VARIABLETYPE messagetyp0_
           where
           messagetyp0_.ID_=?
           and messagetyp0_.CLASS_='M'
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.bpel.variable.def.MessageType#9]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.variable.def.MessageType#9]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.variable.def.MessageType#9]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.loader.Loader] done entity load
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.wsdl.impl.MessageImpl#7]
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.wsdl.impl.MessageImpl#7
          2006-10-02 21:30:06,640 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.wsdl.impl.MessageImpl#7
          2006-10-02 21:30:06,640 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.wsdl.impl.MessageImpl: Is element with key org.jbpm.bpel.wsdl.impl.MessageImpl#7 expired?: false
          2006-10-02 21:30:06,640 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.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.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.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.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)
           ... 17 more
          Caused by: 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.StructuredActivity$Begin_$$_javassist_123.enter(StructuredActivity$Begin_$$_javassist_123.java)
           at org.jbpm.bpel.def.StructuredActivity.enter(StructuredActivity.java:52)
           ... 27 more
          Caused by: 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.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)
           ... 34 more
          Caused by: 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.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java)
           at org.jbpm.bpel.def.StructuredActivity.execute(StructuredActivity.java:56)
           ... 43 more
          Caused by: 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.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)
           ... 50 more
          Caused by: 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.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)
           ... 58 more
          Caused by: 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.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)
           ... 69 more
          Caused by: 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.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)
           ... 80 more
          Caused by: 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.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java)
           at org.jbpm.bpel.def.If.execute(If.java:80)
           ... 89 more
          Caused by: 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.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)
           ... 96 more
          Caused by: 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.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)
           ... 104 more
          Caused by: 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.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)
           ... 114 more
          Caused by: 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.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)
           ... 122 more
          Caused by: 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.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)
           ... 133 more
          Caused by: 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.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)
           ... 144 more
          Caused by: 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.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)
           ... 153 more
          Caused by: 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.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)
           ... 162 more
          Caused by: 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.StructuredActivity$End_$$_javassist_57.leave(StructuredActivity$End_$$_javassist_57.java)
           at org.jbpm.bpel.def.StructuredActivity.leave(StructuredActivity.java:60)
           ... 170 more
          Caused by: 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.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)
           ... 177 more
          Caused by: 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.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)
           ... 186 more
          Caused by: 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.StructuredActivity$Begin_$$_javassist_123.leave(StructuredActivity$Begin_$$_javassist_123.java)
           at org.jbpm.bpel.def.StructuredActivity.execute(StructuredActivity.java:56)
           ... 195 more
          Caused by: 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.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)
           ... 202 more
          Caused by: 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.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)
           ... 210 more
          Caused by: 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.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)
           ... 220 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)
           ... 229 more
          
          


          • 2. Re: error testing atm bpel process, java.lang.reflect.Invoca
            Alejandro Guizar Master

            Hi,

            Please describe the steps to reproduce the problem. Do not forget your product version numbers.

            -Alejandro

            • 3. Re: error testing atm bpel process, java.lang.reflect.Invoca
              Jose Juan Avila Newbie

              Hi,

              I am using :jboss-4.0.4.ga (server all).
              jbpm-bpel-1.1.Beta2
              jbossws-1.0.3

              Do you need any more information?

              The problem is that i think I have changed something else because I had some ERRORS.

              I have followed the steps in the user guide. ant deploy-process, ant generate-service , etc.... and then ant run-test


              If i had to begin from the scratch what should i install (and versions), in order to reproduce your environment ?

              • 4. Re: error testing atm bpel process, java.lang.reflect.Invoca
                Alejandro Guizar Master

                Well, I successfully tested with:

                Windows XP SP2
                Sun JDK 1.4.2_10, 1.5.0_07
                JBoss 4.0.4.GA, 4.0.3SP1, default configuration
                JBossWS 1.0.3.GA
                jBPM BPEL 1.1.Beta2

                I haven't tried the all configuration. Please try with the default and let me know whether the problem appears there too.