Hello @all,


first all my Best wishes for 2007!.

I was facing the probem on invoking a web service deployed on axis 1.1.
I could partially solve (I'll post the solution in this thread later ).

Now i can invoke the deployed web service and it generates the expected output. The problem will be now to feedback the response to the running BPEL process in the PortConsumer - PortProvider Relation. because the effectiv call was routed to a non ws4ee-web-service (runnning axis service) some informations are losed at that time.

My opinion is that i'll need to reinit a PortProvider object with the response of generated by the service call.

I'll appreciate any suggestion to solve the following error i'm getting after the invoke operation:

2007-01-02 15:46:34,822 DEBUG [org.hibernate.loader.Loader] done entity load
2007-01-02 15:46:34,822 DEBUG [org.jbpm.bpel.relation.jms.RelationContext] initialized partner reference: instance=org.jbpm.bpel.service.exe.PartnerLinkInstance@1620d92[name=SchufaLink,id=0], reference=org.jbpm.bpel.service.exe.wsa.WsaEndpointReference@1110f31[address=http://localhost:8081/creditprocess/schufa,portType={urn:samples:schufa}SchufaWS,service={urn:samples:schufa}SchufaWSService]
2007-01-02 15:46:34,992 DEBUG [org.jboss.ws.server.ServiceEndpoint] WSDL request, using host: milaine
2007-01-02 15:46:35,032 DEBUG [org.jboss.ws.server.WSDLRequestHandler] Mapping import from 'schufa.wsdl' to 'http://milaine:8080/schufa/schufaWS?wsdl&resource=schufa.wsdl'
2007-01-02 15:46:35,032 DEBUG [org.jboss.ws.server.WSDLRequestHandler] Mapping address from 'http://milaine:8080/schufa/schufaWS' to 'http://milaine:8080/schufa/schufaWS'
2007-01-02 15:46:35,112 DEBUG [org.jboss.ws.server.ServiceEndpoint] WSDL request, using host: milaine
2007-01-02 15:46:35,162 DEBUG [org.jbpm.bpel.relation.jms.RelationContext] selected partner port: instance=org.jbpm.bpel.service.exe.PartnerLinkInstance@1620d92[name=SchufaLink,id=0], port=SchufaPort
2007-01-02 15:46:35,182 INFO [STDOUT] PortConsumer: AddresseLocation configure to: http://milaine:8080/schufa/schufaWS
2007-01-02 15:46:35,182 INFO [STDOUT] PortConsumer: Swapping URL-Address to :http://127.0.0.1:8081/creditprocess/schufa
2007-01-02 15:46:35,182 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.wsdl.impl.OperationImpl#62]
2007-01-02 15:46:35,182 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.wsdl.impl.OperationImpl#62
2007-01-02 15:46:35,182 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.wsdl.impl.OperationImpl#62
2007-01-02 15:46:35,182 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.bpel.wsdl.impl.OperationImpl#62 is null
2007-01-02 15:46:35,192 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
2007-01-02 15:46:35,192 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jbpm.bpel.wsdl.impl.OperationImpl#62]
2007-01-02 15:46:35,192 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-01-02 15:46:35,192 DEBUG [org.hibernate.SQL] select operationi0_.id as id60_0_, operationi0_.NAME_ as NAME2_60_0_, operationi0_.STYLE_ as STYLE3_60_0_, operationi0_.INPUT_ as INPUT4_60_0_, operationi0_.OUTPUT_ as OUTPUT5_60_0_ from WSDL_OPERATION operationi0_ where operationi0_.id=?
2007-01-02 15:46:35,192 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2007-01-02 15:46:35,192 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.bpel.wsdl.impl.OperationImpl#62]
2007-01-02 15:46:35,192 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2007-01-02 15:46:35,192 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-01-02 15:46:35,192 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.wsdl.impl.OperationImpl#62]
2007-01-02 15:46:35,192 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.bpel.wsdl.impl.OperationImpl#62]
2007-01-02 15:46:35,192 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.bpel.wsdl.impl.OperationImpl#62
2007-01-02 15:46:35,192 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.wsdl.impl.OperationImpl: Is element with key org.jbpm.bpel.wsdl.impl.OperationImpl#61 expired?: false
2007-01-02 15:46:35,192 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.wsdl.impl.OperationImpl#62]
2007-01-02 15:46:35,192 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2007-01-02 15:46:35,192 DEBUG [org.hibernate.loader.Loader] done entity load
2007-01-02 15:46:35,192 DEBUG [org.jbpm.bpel.relation.jms.JmsRelationService] invoking: partnerLink=43, operation=validCustomer, input={_surname=[_surname: null], _firstname=[_firstname: null]}
2007-01-02 15:46:35,223 INFO [STDOUT] PortConsumer: Builded SOAPMessage: org.jboss.ws.soap.SOAPMessageImpl@5e305f
2007-01-02 15:46:35,383 DEBUG [org.jboss.ws.soap.SOAPConnectionImpl] Get locator for: http://127.0.0.1:8081/creditprocess/schufa
2007-01-02 15:46:35,673 DEBUG [org.jboss.remoting.Client] invoke called, but our invoker is disconnected, discarding and fetching another fresh invoker for: InvokerLocator [http://127.0.0.1:8081/creditprocess/schufa]
2007-01-02 15:46:35,683 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] connect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@142db11
2007-01-02 15:46:35,683 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with SOAPAction : "urn:samples:schufa:validCustomer"
2007-01-02 15:46:35,683 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with Content-Type : text/xml; charset=UTF-8
2007-01-02 15:46:59,007 INFO [STDOUT]

================================================
2007-01-02 15:46:59,027 INFO [STDOUT] Schufa web service : Start method validCustomer
2007-01-02 15:46:59,147 INFO [STDOUT] Schufa web service : End method validCustomer
2007-01-02 15:46:59,147 INFO [STDOUT] ==============================================

2007-01-02 15:46:59,437 DEBUG [org.jboss.ws.binding.soap.SOAPMessageUnMarshaller] getMimeHeaders from: {Content-Length=[399], X-Powered-By=[Servlet 2.4; JBoss-4.0.4.GA (build: CVSTag=JBoss_4_0_4_GA date=200605151000)/Tomcat-5.5], ResponseCodeMessage=OK, null=[HTTP/1.1 200 OK], Date=[Tue, 02 Jan 2007 14:46:59 GMT], Content-Type=[text/xml;charset=utf-8], Server=[Apache-Coyote/1.1], HEADER={SOAPAction="urn:samples:schufa:validCustomer", Content-Type=text/xml; charset=UTF-8}, ResponseCode=200}
2007-01-02 15:46:59,768 DEBUG [org.jboss.ws.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8]
2007-01-02 15:46:59,788 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.Node.exceptionHandlers#341
2007-01-02 15:46:59,788 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.graph.def.Node.exceptionHandlers#341
2007-01-02 15:46:59,788 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.graph.def.Node.exceptionHandlers#341 is null
2007-01-02 15:46:59,788 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
2007-01-02 15:46:59,788 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.Node.exceptionHandlers#341]
2007-01-02 15:46:59,788 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-01-02 15:46:59,788 DEBUG [org.hibernate.SQL] select exceptionh0_.NODE_ as NODE9_1_, exceptionh0_.ID_ as ID1_1_, exceptionh0_.GRAPHELEMENTINDEX_ as GRAPHELE6_1_, exceptionh0_.ID_ as ID1_3_0_, exceptionh0_.EXCEPTIONCLASSNAME_ as EXCEPTIO2_3_0_, exceptionh0_.TYPE_ as TYPE3_3_0_, exceptionh0_.GRAPHELEMENT_ as GRAPHELE4_3_0_ from JBPM_EXCEPTIONHANDLER exceptionh0_ where exceptionh0_.NODE_=?
2007-01-02 15:46:59,818 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2007-01-02 15:46:59,818 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.Node.exceptionHandlers#341]
2007-01-02 15:46:59,818 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2007-01-02 15:46:59,818 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-01-02 15:46:59,818 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,818 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.Node.exceptionHandlers#341]
2007-01-02 15:46:59,818 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Node.exceptionHandlers#341
2007-01-02 15:46:59,818 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Node.exceptionHandlers: Is element with key org.jbpm.graph.def.Node.exceptionHandlers#341 expired?: false
2007-01-02 15:46:59,818 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.Node.exceptionHandlers#341]
2007-01-02 15:46:59,818 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,818 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2007-01-02 15:46:59,818 DEBUG [org.hibernate.loader.Loader] done loading collection
2007-01-02 15:46:59,818 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.Node.exceptionHandlers#336
2007-01-02 15:46:59,818 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.graph.def.Node.exceptionHandlers#336
2007-01-02 15:46:59,818 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.graph.def.Node.exceptionHandlers#336 is null
2007-01-02 15:46:59,818 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
2007-01-02 15:46:59,818 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.Node.exceptionHandlers#336]
2007-01-02 15:46:59,818 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-01-02 15:46:59,818 DEBUG [org.hibernate.SQL] select exceptionh0_.NODE_ as NODE9_1_, exceptionh0_.ID_ as ID1_1_, exceptionh0_.GRAPHELEMENTINDEX_ as GRAPHELE6_1_, exceptionh0_.ID_ as ID1_3_0_, exceptionh0_.EXCEPTIONCLASSNAME_ as EXCEPTIO2_3_0_, exceptionh0_.TYPE_ as TYPE3_3_0_, exceptionh0_.GRAPHELEMENT_ as GRAPHELE4_3_0_ from JBPM_EXCEPTIONHANDLER exceptionh0_ where exceptionh0_.NODE_=?
2007-01-02 15:46:59,828 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2007-01-02 15:46:59,828 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.Node.exceptionHandlers#336]
2007-01-02 15:46:59,828 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2007-01-02 15:46:59,828 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-01-02 15:46:59,828 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,828 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.Node.exceptionHandlers#336]
2007-01-02 15:46:59,828 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Node.exceptionHandlers#336
2007-01-02 15:46:59,828 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Node.exceptionHandlers: Is element with key org.jbpm.graph.def.Node.exceptionHandlers#341 expired?: false
2007-01-02 15:46:59,828 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.Node.exceptionHandlers#336]
2007-01-02 15:46:59,828 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,828 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2007-01-02 15:46:59,828 DEBUG [org.hibernate.loader.Loader] done loading collection
2007-01-02 15:46:59,828 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.Node.exceptionHandlers#335
2007-01-02 15:46:59,828 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.graph.def.Node.exceptionHandlers#335
2007-01-02 15:46:59,828 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.graph.def.Node.exceptionHandlers#335 is null
2007-01-02 15:46:59,828 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
2007-01-02 15:46:59,828 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.Node.exceptionHandlers#335]
2007-01-02 15:46:59,828 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-01-02 15:46:59,828 DEBUG [org.hibernate.SQL] select exceptionh0_.NODE_ as NODE9_1_, exceptionh0_.ID_ as ID1_1_, exceptionh0_.GRAPHELEMENTINDEX_ as GRAPHELE6_1_, exceptionh0_.ID_ as ID1_3_0_, exceptionh0_.EXCEPTIONCLASSNAME_ as EXCEPTIO2_3_0_, exceptionh0_.TYPE_ as TYPE3_3_0_, exceptionh0_.GRAPHELEMENT_ as GRAPHELE4_3_0_ from JBPM_EXCEPTIONHANDLER exceptionh0_ where exceptionh0_.NODE_=?
2007-01-02 15:46:59,828 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2007-01-02 15:46:59,828 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.Node.exceptionHandlers#335]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.graph.def.ExceptionHandler#25]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.loader.Loader] found row of collection: [org.jbpm.graph.def.Node.exceptionHandlers#335]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2007-01-02 15:46:59,838 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.graph.def.ExceptionHandler#25]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.graph.def.ExceptionHandler#25]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.ExceptionHandler#25
2007-01-02 15:46:59,838 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.ExceptionHandler: Is element with key org.jbpm.graph.def.ExceptionHandler#25 expired?: false
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.graph.def.ExceptionHandler#25]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.Node.exceptionHandlers#335]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Node.exceptionHandlers#335
2007-01-02 15:46:59,838 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Node.exceptionHandlers: Is element with key org.jbpm.graph.def.Node.exceptionHandlers#341 expired?: false
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.Node.exceptionHandlers#335]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,838 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2007-01-02 15:46:59,838 DEBUG [org.hibernate.loader.Loader] done loading collection
2007-01-02 15:46:59,838 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21
2007-01-02 15:46:59,838 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21
2007-01-02 15:46:59,838 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21 is null
2007-01-02 15:46:59,838 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
2007-01-02 15:46:59,838 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21]
2007-01-02 15:46:59,838 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-01-02 15:46:59,838 DEBUG [org.hibernate.SQL] select exceptionh0_.PROCESSDEFINITION_ as PROCESSD8_1_, exceptionh0_.ID_ as ID1_1_, exceptionh0_.GRAPHELEMENTINDEX_ as GRAPHELE6_1_, exceptionh0_.ID_ as ID1_3_0_, exceptionh0_.EXCEPTIONCLASSNAME_ as EXCEPTIO2_3_0_, exceptionh0_.TYPE_ as TYPE3_3_0_, exceptionh0_.GRAPHELEMENT_ as GRAPHELE4_3_0_ from JBPM_EXCEPTIONHANDLER exceptionh0_ where exceptionh0_.PROCESSDEFINITION_=?
2007-01-02 15:46:59,848 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2007-01-02 15:46:59,848 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21]
2007-01-02 15:46:59,848 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2007-01-02 15:46:59,848 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-01-02 15:46:59,848 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.ProcessDefinition.exceptionHandlers
2007-01-02 15:46:59,848 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21]
2007-01-02 15:46:59,848 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21
2007-01-02 15:46:59,848 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.ProcessDefinition.exceptionHandlers: Is element with key org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21 expired?: false
2007-01-02 15:46:59,848 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.ProcessDefinition.exceptionHandlers#21]
2007-01-02 15:46:59,848 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.ProcessDefinition.exceptionHandlers
2007-01-02 15:46:59,848 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2007-01-02 15:46:59,848 DEBUG [org.hibernate.loader.Loader] done loading collection
2007-01-02 15:46:59,848 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.Node.exceptionHandlers#340
2007-01-02 15:46:59,848 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.graph.def.Node.exceptionHandlers#340
2007-01-02 15:46:59,848 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.graph.def.Node.exceptionHandlers#340 is null
2007-01-02 15:46:59,848 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
2007-01-02 15:46:59,848 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.Node.exceptionHandlers#340]
2007-01-02 15:46:59,848 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
2007-01-02 15:46:59,848 DEBUG [org.hibernate.SQL] select exceptionh0_.NODE_ as NODE9_1_, exceptionh0_.ID_ as ID1_1_, exceptionh0_.GRAPHELEMENTINDEX_ as GRAPHELE6_1_, exceptionh0_.ID_ as ID1_3_0_, exceptionh0_.EXCEPTIONCLASSNAME_ as EXCEPTIO2_3_0_, exceptionh0_.TYPE_ as TYPE3_3_0_, exceptionh0_.GRAPHELEMENT_ as GRAPHELE4_3_0_ from JBPM_EXCEPTIONHANDLER exceptionh0_ where exceptionh0_.NODE_=?
2007-01-02 15:46:59,888 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
2007-01-02 15:46:59,888 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.Node.exceptionHandlers#340]
2007-01-02 15:46:59,888 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
2007-01-02 15:46:59,888 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
2007-01-02 15:46:59,888 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,888 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.Node.exceptionHandlers#340]
2007-01-02 15:46:59,888 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Node.exceptionHandlers#340
2007-01-02 15:46:59,888 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Node.exceptionHandlers: Is element with key org.jbpm.graph.def.Node.exceptionHandlers#341 expired?: false
2007-01-02 15:46:59,888 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.Node.exceptionHandlers#340]
2007-01-02 15:46:59,888 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.Node.exceptionHandlers
2007-01-02 15:46:59,888 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
2007-01-02 15:46:59,888 DEBUG [org.hibernate.loader.Loader] done loading collection
2007-01-02 15:46:59,888 DEBUG [org.jbpm.bpel.relation.jms.StartListener] caught exception while passing control to process, searching for handler
org.jbpm.graph.def.DelegationException
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:349)
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:343)
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:343)
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:343)
at org.jbpm.bpel.def.Activity.enter(Activity.java:88)
at org.jbpm.graph.def.Transition.take(Transition.java:119)
at org.jbpm.graph.def.Node.leave(Node.java:382)
at org.jbpm.bpel.def.Activity.leave(Activity.java:148)
at org.jbpm.bpel.def.ProcessInstanceStarter.visit(ProcessInstanceStarter.java:43)
at org.jbpm.bpel.def.Receive.accept(Receive.java:64)
at org.jbpm.bpel.def.Receive$$FastClassByCGLIB$$516412f0.invoke(<generated>)
at net.sf.cglib.proxy.MethodProxy.invoke(MethodProxy.java:149)
at org.hibernate.proxy.pojo.cglib.CGLIBLazyInitializer.intercept(CGLIBLazyInitializer.java:163)
at org.jbpm.bpel.def.Receive$$EnhancerByCGLIB$$7e8cc0f8.accept(<generated>)
at org.jbpm.bpel.def.ProcessInstanceStarter.visit(ProcessInstanceStarter.java:82)
at org.jbpm.bpel.def.Sequence.accept(Sequence.java:84)
at org.jbpm.bpel.def.ProcessInstanceStarter.visit(ProcessInstanceStarter.java:29)
at org.jbpm.bpel.def.BpelDefinition.messageReceived(BpelDefinition.java:110)
at org.jbpm.bpel.relation.jms.StartListener.onMessage(StartListener.java:94)
at org.jboss.mq.SpyMessageConsumer.run(SpyMessageConsumer.java:694)
at java.lang.Thread.run(Thread.java:595)
Caused by: org.jbpm.graph.def.DelegationException
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:349)
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:343)
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:343)
at org.jbpm.graph.def.GraphElement.raiseException(GraphElement.java:343)
at org.jbpm.bpel.def.Activity.enter(Activity.java:88)
at org.jbpm.graph.def.Transition.take(Transition.java:119)
at org.jbpm.graph.def.Node.leave(Node.java:382)
at org.jbpm.bpel.def.Activity.leave(Activity.java:148)
at org.jbpm.bpel.def.Assign.execute(Assign.java:41)
at org.jbpm.bpel.def.Activity.enter(Activity.java:83)
... 16 more
Caused by: java.lang.NullPointerException
at org.jbpm.bpel.xml.util.XmlUtil.copyAttributes(XmlUtil.java:520)
at org.jbpm.bpel.xml.util.XmlUtil.copy(XmlUtil.java:485)
at org.jbpm.bpel.service.consumer.PortConsumer.readRpcBody(PortConsumer.java:344)
at org.jbpm.bpel.service.consumer.PortConsumer.readMessage(PortConsumer.java:312)
at org.jbpm.bpel.service.consumer.PortConsumer.call(PortConsumer.java:106)
at org.jbpm.bpel.relation.jms.JmsRelationService.invoke(JmsRelationService.java:143)
at org.jbpm.bpel.def.Invoke.execute(Invoke.java:28)
at org.jbpm.bpel.def.Activity.enter(Activity.java:83)
... 21 more

2007-01-02 15:47:00,068 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
2007-01-02 15:47:00,068 DEBUG [org.jbpm.svc.Services] closing service 'persistence': org.jbpm.persistence.db.DbPersistenceService@59aa86
2007-01-02 15:47:00,068 DEBUG [org.jbpm.persistence.db.DbPersistenceService] rolling back hibernate transaction
2007-01-02 15:47:00,068 DEBUG [org.hibernate.transaction.JDBCTransaction] rollback