[jboss-user] [JBossWS] - invoke called, but our invoker is disconnected

Tuesday, 2 January 2007

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:

anonymous wrote : 
  | 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()
  | 	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()
  | 	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
  | 
  | 

Thanks in advance

Bertrand

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

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

2026

2025

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

2007

2006

[jboss-user] [JBossWS] - invoke called, but our invoker is disconnected