[Remoting] - Joss.remoting.Client: invoke called, but our invoker is disc
by bertrand.njiipwo
Hello,
i have the following problem. I hope hier in the remoting forum i can find help. For Background information see the other thread: http://www.jboss.com/index.html?module=bb&op=viewtopic&t=98256
I have a BPEL process invoking my deployed service. The remote service receive the request and process it. It generate a response and send (try to send) it back to the running bpel BPEL process. Wright know the connection with the invoker is losed see:
anonymous wrote : 2007-01-04 20:24:29,948 DEBUG [org.jbpm.bpel.relation.jms.RelationContext] initialized partner reference: instance=org.jbpm.bpel.service.exe.PartnerLinkInstance@ae8542[name=SchufaLink,id=0], reference=org.jbpm.bpel.service.exe.wsa.WsaEndpointReference@19f410[address=USE_ACTUAL_URI_FROM_CATALOG,portType={urn:samples:schufa}SchufaWS,service={urn:samples:schufa}SchufaWSService]
| 2007-01-04 20:24:30,138 DEBUG [org.jboss.ws.server.ServiceEndpoint] WSDL request, using host: milaine
| 2007-01-04 20:24:30,178 DEBUG [org.jboss.ws.server.WSDLRequestHandler] Mapping import from 'schufa.wsdl' to 'http://milaine:8080/schufa/schufaWS?wsdl&resource=schufa.wsdl'
| 2007-01-04 20:24:30,278 DEBUG [org.jboss.ws.server.ServiceEndpoint] WSDL request, using host: milaine
| 2007-01-04 20:24:30,368 DEBUG [org.jbpm.bpel.relation.jms.RelationContext] selected partner port: instance=org.jbpm.bpel.service.exe.PartnerLinkInstance@ae8542[name=SchufaLink,id=0], port=SchufaService
| 2007-01-04 20:24:30,408 INFO [STDOUT] PortConsumer: AddresseLocation configure to: http://127.0.0.1:8081/creditprocess/schufa
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.wsdl.impl.OperationImpl#82
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.wsdl.impl.OperationImpl#82
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.bpel.wsdl.impl.OperationImpl#82 is null
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
| 2007-01-04 20:24:30,419 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-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.bpel.wsdl.impl.OperationImpl#82
| 2007-01-04 20:24:30,429 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.wsdl.impl.OperationImpl: Is element with key org.jbpm.bpel.wsdl.impl.OperationImpl#81 expired?: false
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.loader.Loader] done entity load
| 2007-01-04 20:24:30,429 INFO [STDOUT] JmsRelationService: Requested Operation by invoker: validCustomer
| 2007-01-04 20:24:30,439 DEBUG [org.jbpm.bpel.relation.jms.JmsRelationService] invoking: partnerLink=54, operation=validCustomer, input={_surname=[_surname: null], _firstname=[_firstname: null]}
| 2007-01-04 20:24:30,459 INFO [STDOUT] PortConsumer: Builded SOAPMessage: org.jboss.ws.soap.SOAPMessageImpl@e28347
| 2007-01-04 20:24:30,619 DEBUG [org.jboss.ws.soap.SOAPConnectionImpl] Get locator for: http://127.0.0.1:8081/creditprocess/schufa
|
| 2007-01-04 20:24:30,949 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-04 20:24:30,949 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] connect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@a76306
| 2007-01-04 20:24:30,959 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with SOAPAction : "urn:samples:schufa:validCustomer"
| 2007-01-04 20:24:30,959 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with Content-Type : text/xml; charset=UTF-8
| 2007-01-04 20:25:00,612 INFO [STDOUT] ================================================
| 2007-01-04 20:25:00,612 INFO [STDOUT] Schufa web service B: Start method validCustomer
| 2007-01-04 20:25:00,612 INFO [STDOUT] - SchufaWSB.isCustomerRegistrated()...
| 2007-01-04 20:25:00,612 INFO [STDOUT] - Is customer "Bertrand", "Njipwo" known?
| 2007-01-04 20:25:00,682 INFO [STDOUT] - Result: true
| 2007-01-04 20:25:00,682 INFO [STDOUT] - ...SchufaWSB.isCustomerRegistrated() done.
| 2007-01-04 20:25:00,682 INFO [STDOUT] - SchufaWSB.isCustomerAuthorized()...
| 2007-01-04 20:25:00,682 INFO [STDOUT] - Is customer "Bertrand", "Njipwo" authorized for credits?
| 2007-01-04 20:25:00,712 INFO [STDOUT] - Result: true
| 2007-01-04 20:25:00,712 INFO [STDOUT] - ...SchufaWSB.isCustomerAuthorized() done.
| 2007-01-04 20:25:00,712 INFO [STDOUT] - Customer "Bertrand", "Njipwo" is known and authorized for credits.
| 2007-01-04 20:25:00,712 INFO [STDOUT] Schufa web service B: End method validCustomer
| 2007-01-04 20:25:00,712 INFO [STDOUT] ==============================================
|
| 2007-01-04 20:25:01,273 DEBUG [org.jboss.ws.binding.soap.SOAPMessageUnMarshaller] getMimeHeaders from: {Content-Length=[398], 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=[Thu, 04 Jan 2007 19:25:00 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-04 20:25:01,914 DEBUG [org.jboss.ws.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8]
| 2007-01-04 20:25:01,944 DEBUG
|
| [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.Node.exceptionHandlers#463
| ..
| .....
| .......
| ..........
But the generated SOAP Message (Response) are well received by the Jbossws components org.jboss.remoting.transport.http.HTTPClientInvoker
and org.jboss.ws.soap.MessageFactoryImp:
Response Message of remote Host:
| HTTP/1.1 200 OK
| Server: Apache-Coyote/1.1
| X-Powered-By: Servlet 2.4; JBoss-4.0.4.GA (build: CVSTag=JBoss_4_0_4_GA date=200605151000)/Tomcat-5.
| 5
| Content-Type: text/xml;charset=utf-8
| Content-Length: 398
| Date: Wed, 03 Jan 2007 21:10:10 GMT
|
| <?xml version="1.0" encoding="UTF-8"?>
| <soapenv:Envelope xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/" xmlns:xsd="http://www.w3.org/2001/XMLSchema"
| xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
| <soapenv:Body>
| <validCustomerResponse xmlns="urn:samples:schufa">
| <validCustomerReturn>true</validCustomerReturn>
| </validCustomerResponse>
| </soapenv:Body>
| </soapenv:Envelope>
I think hier the running process instance can't access the generated SOAP Response routed and when the jbpm.bpel module try to access the expected SOAP Message (see following Error log) by reading the RPCBody elements in the output elements, the collection object are not initialised and therefore this cause a NullPointerException.
anonymous wrote :
| 2007-01-04 20:25:02,044 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
| 2007-01-04 20:25:02,044 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,044 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
| 2007-01-04 20:25:02,044 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-04 20:25:02,054 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.Node.exceptionHandlers
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Node.exceptionHandlers#462
| 2007-01-04 20:25:02,054 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Node.exceptionHandlers: Is element with key org.jbpm.graph.def.Node.exceptionHandlers#463 expired?: false
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.Node.exceptionHandlers
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.loader.Loader] done loading collection
| 2007-01-04 20:25:02,054 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$$8b85477d.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:345)
| at org.jbpm.bpel.service.consumer.PortConsumer.readMessage(PortConsumer.java:313)
| at org.jbpm.bpel.service.consumer.PortConsumer.call(PortConsumer.java:107)
| at org.jbpm.bpel.relation.jms.JmsRelationService.invoke(JmsRelationService.java:145)
| at org.jbpm.bpel.def.Invoke.execute(Invoke.java:28)
| at org.jbpm.bpel.def.Activity.enter(Activity.java:83)
| ... 21 more
| 2007-01-04 20:25:02,064 ERROR [org.jbpm.bpel.relation.jms.StartListener] could not start process instance
| 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.bpel.relation.jms.StartListener.onMessage(StartListener.java:98)
| 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.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$$8b85477d.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)
| ... 2 more
| 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:345)
| at org.jbpm.bpel.service.consumer.PortConsumer.readMessage(PortConsumer.java:313)
| at org.jbpm.bpel.service.consumer.PortConsumer.call(PortConsumer.java:107)
| at org.jbpm.bpel.relation.jms.JmsRelationService.invoke(JmsRelationService.java:145)
| at org.jbpm.bpel.def.Invoke.execute(Invoke.java:28)
| at org.jbpm.bpel.def.Activity.enter(Activity.java:83)
| ... 21 more
| 2007-01-04 20:25:02,214 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
|
At this point of time i don't know if this issue is related to the use of wrong jbossws bibliotheke instead of axis one and why i can't access the generated SOAP Response within the bpel process after the jbossws create a new message and failed to initialyse his contain by copying his message parts.
Any one has an idee what is wrong hier?
Thanks for any suggestion.
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=3998063#3998063
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=3998063
19 years, 3 months
[JBoss jBPM] - Re: invoke called, but our invoker is disconnected
by bertrand.njiipwo
Hello community,
After have resolved the problem with the soap:address which was always overritten see: http://www.jboss.com/index.html?module=bb&op=viewtopic&t=97346 i'm still stucking.
I have a BPEL process invoking my deployed service. The remote service receive the request and process it. It generate a response and send (try to send) it back to the running bpel BPEL process. Wright know the connection with the invoker is losed see:
anonymous wrote : 2007-01-04 20:24:29,948 DEBUG [org.jbpm.bpel.relation.jms.RelationContext] initialized partner reference: instance=org.jbpm.bpel.service.exe.PartnerLinkInstance@ae8542[name=SchufaLink,id=0], reference=org.jbpm.bpel.service.exe.wsa.WsaEndpointReference@19f410[address=USE_ACTUAL_URI_FROM_CATALOG,portType={urn:samples:schufa}SchufaWS,service={urn:samples:schufa}SchufaWSService]
| 2007-01-04 20:24:30,138 DEBUG [org.jboss.ws.server.ServiceEndpoint] WSDL request, using host: milaine
| 2007-01-04 20:24:30,178 DEBUG [org.jboss.ws.server.WSDLRequestHandler] Mapping import from 'schufa.wsdl' to 'http://milaine:8080/schufa/schufaWS?wsdl&resource=schufa.wsdl'
| 2007-01-04 20:24:30,278 DEBUG [org.jboss.ws.server.ServiceEndpoint] WSDL request, using host: milaine
| 2007-01-04 20:24:30,368 DEBUG [org.jbpm.bpel.relation.jms.RelationContext] selected partner port: instance=org.jbpm.bpel.service.exe.PartnerLinkInstance@ae8542[name=SchufaLink,id=0], port=SchufaService
| 2007-01-04 20:24:30,408 INFO [STDOUT] PortConsumer: AddresseLocation configure to: http://127.0.0.1:8081/creditprocess/schufa
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.impl.SessionImpl] initializing proxy: [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.bpel.wsdl.impl.OperationImpl#82
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.EhCache] key: org.jbpm.bpel.wsdl.impl.OperationImpl#82
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.EhCache] Element for org.jbpm.bpel.wsdl.impl.OperationImpl#82 is null
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.loader.Loader] loading entity: [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
| 2007-01-04 20:24:30,419 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-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.loader.Loader] result row: EntityKey[org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.engine.TwoPhaseLoad] resolving associations for [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,419 DEBUG [org.hibernate.engine.TwoPhaseLoad] adding entity to second-level cache: [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.bpel.wsdl.impl.OperationImpl#82
| 2007-01-04 20:24:30,429 DEBUG [net.sf.ehcache.Cache] org.jbpm.bpel.wsdl.impl.OperationImpl: Is element with key org.jbpm.bpel.wsdl.impl.OperationImpl#81 expired?: false
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.engine.TwoPhaseLoad] done materializing entity [org.jbpm.bpel.wsdl.impl.OperationImpl#82]
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
| 2007-01-04 20:24:30,429 DEBUG [org.hibernate.loader.Loader] done entity load
| 2007-01-04 20:24:30,429 INFO [STDOUT] JmsRelationService: Requested Operation by invoker: validCustomer
| 2007-01-04 20:24:30,439 DEBUG [org.jbpm.bpel.relation.jms.JmsRelationService] invoking: partnerLink=54, operation=validCustomer, input={_surname=[_surname: null], _firstname=[_firstname: null]}
| 2007-01-04 20:24:30,459 INFO [STDOUT] PortConsumer: Builded SOAPMessage: org.jboss.ws.soap.SOAPMessageImpl@e28347
| 2007-01-04 20:24:30,619 DEBUG [org.jboss.ws.soap.SOAPConnectionImpl] Get locator for: http://127.0.0.1:8081/creditprocess/schufa
|
| 2007-01-04 20:24:30,949 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-04 20:24:30,949 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] connect called for: org.jboss.remoting.transport.http.HTTPClientInvoker@a76306
| 2007-01-04 20:24:30,959 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with SOAPAction : "urn:samples:schufa:validCustomer"
| 2007-01-04 20:24:30,959 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with Content-Type : text/xml; charset=UTF-8
| 2007-01-04 20:25:00,612 INFO [STDOUT] ================================================
| 2007-01-04 20:25:00,612 INFO [STDOUT] Schufa web service B: Start method validCustomer
| 2007-01-04 20:25:00,612 INFO [STDOUT] - SchufaWSB.isCustomerRegistrated()...
| 2007-01-04 20:25:00,612 INFO [STDOUT] - Is customer "Bertrand", "Njipwo" known?
| 2007-01-04 20:25:00,682 INFO [STDOUT] - Result: true
| 2007-01-04 20:25:00,682 INFO [STDOUT] - ...SchufaWSB.isCustomerRegistrated() done.
| 2007-01-04 20:25:00,682 INFO [STDOUT] - SchufaWSB.isCustomerAuthorized()...
| 2007-01-04 20:25:00,682 INFO [STDOUT] - Is customer "Bertrand", "Njipwo" authorized for credits?
| 2007-01-04 20:25:00,712 INFO [STDOUT] - Result: true
| 2007-01-04 20:25:00,712 INFO [STDOUT] - ...SchufaWSB.isCustomerAuthorized() done.
| 2007-01-04 20:25:00,712 INFO [STDOUT] - Customer "Bertrand", "Njipwo" is known and authorized for credits.
| 2007-01-04 20:25:00,712 INFO [STDOUT] Schufa web service B: End method validCustomer
| 2007-01-04 20:25:00,712 INFO [STDOUT] ==============================================
|
| 2007-01-04 20:25:01,273 DEBUG [org.jboss.ws.binding.soap.SOAPMessageUnMarshaller] getMimeHeaders from: {Content-Length=[398], 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=[Thu, 04 Jan 2007 19:25:00 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-04 20:25:01,914 DEBUG [org.jboss.ws.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8]
| 2007-01-04 20:25:01,944 DEBUG
|
| [org.hibernate.cache.NonstrictReadWriteCache] Cache lookup: org.jbpm.graph.def.Node.exceptionHandlers#463
| ..
| .....
| .......
| ..........
But the generated SOAP Message (Response) are well received by the Jbossws components org.jboss.remoting.transport.http.HTTPClientInvoker
and org.jboss.ws.soap.MessageFactoryImp:
Response Message of remote Host:
| HTTP/1.1 200 OK
| Server: Apache-Coyote/1.1
| X-Powered-By: Servlet 2.4; JBoss-4.0.4.GA (build: CVSTag=JBoss_4_0_4_GA date=200605151000)/Tomcat-5.
| 5
| Content-Type: text/xml;charset=utf-8
| Content-Length: 398
| Date: Wed, 03 Jan 2007 21:10:10 GMT
|
| <?xml version="1.0" encoding="UTF-8"?>
| <soapenv:Envelope xmlns:soapenv="http://schemas.xmlsoap.org/soap/envelope/" xmlns:xsd="http://www.w3.org/2001/XMLSchema"
| xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance">
| <soapenv:Body>
| <validCustomerResponse xmlns="urn:samples:schufa">
| <validCustomerReturn>true</validCustomerReturn>
| </validCustomerResponse>
| </soapenv:Body>
| </soapenv:Envelope>
I think hier the running process instance can't access the generated SOAP Response routed and when the jbpm.bpel module try to access the expected SOAP Message (see following Error log) by reading the RPCBody elements in the output elements, the collection object are not initialised and therefore this cause a NullPointerException.
anonymous wrote :
| 2007-01-04 20:25:02,044 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Cache miss
| 2007-01-04 20:25:02,044 DEBUG [org.hibernate.loader.Loader] loading collection: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,044 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open PreparedStatement (open PreparedStatements: 0, globally: 0)
| 2007-01-04 20:25:02,044 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-04 20:25:02,054 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to open ResultSet (open ResultSets: 0, globally: 0)
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.loader.Loader] result set contains (possibly empty) collection: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close ResultSet (open ResultSets: 1, globally: 1)
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.jdbc.AbstractBatcher] about to close PreparedStatement (open PreparedStatements: 1, globally: 1)
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections were found in result set for role: org.jbpm.graph.def.Node.exceptionHandlers
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] Caching collection: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.cache.NonstrictReadWriteCache] Caching: org.jbpm.graph.def.Node.exceptionHandlers#462
| 2007-01-04 20:25:02,054 DEBUG [net.sf.ehcache.Cache] org.jbpm.graph.def.Node.exceptionHandlers: Is element with key org.jbpm.graph.def.Node.exceptionHandlers#463 expired?: false
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] collection fully initialized: [org.jbpm.graph.def.Node.exceptionHandlers#462]
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.CollectionLoadContext] 1 collections initialized for role: org.jbpm.graph.def.Node.exceptionHandlers
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.engine.StatefulPersistenceContext] initializing non-lazy collections
| 2007-01-04 20:25:02,054 DEBUG [org.hibernate.loader.Loader] done loading collection
| 2007-01-04 20:25:02,054 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$$8b85477d.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:345)
| at org.jbpm.bpel.service.consumer.PortConsumer.readMessage(PortConsumer.java:313)
| at org.jbpm.bpel.service.consumer.PortConsumer.call(PortConsumer.java:107)
| at org.jbpm.bpel.relation.jms.JmsRelationService.invoke(JmsRelationService.java:145)
| at org.jbpm.bpel.def.Invoke.execute(Invoke.java:28)
| at org.jbpm.bpel.def.Activity.enter(Activity.java:83)
| ... 21 more
| 2007-01-04 20:25:02,064 ERROR [org.jbpm.bpel.relation.jms.StartListener] could not start process instance
| 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.bpel.relation.jms.StartListener.onMessage(StartListener.java:98)
| 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.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$$8b85477d.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)
| ... 2 more
| 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:345)
| at org.jbpm.bpel.service.consumer.PortConsumer.readMessage(PortConsumer.java:313)
| at org.jbpm.bpel.service.consumer.PortConsumer.call(PortConsumer.java:107)
| at org.jbpm.bpel.relation.jms.JmsRelationService.invoke(JmsRelationService.java:145)
| at org.jbpm.bpel.def.Invoke.execute(Invoke.java:28)
| at org.jbpm.bpel.def.Activity.enter(Activity.java:83)
| ... 21 more
| 2007-01-04 20:25:02,214 DEBUG [org.jbpm.JbpmContext] closing JbpmContext
|
At this point of time i don't know if this issue is related to the use of wrong jbossws bibliotheke instead of axis one and why i can't access the generated SOAP Response within the bpel process after the jbossws create a new message and failed to initialyse his contain by copying his message parts.
Any one has an idee what is wrong hier?
Thanks for any suggestion.
Bertrand Njipwo
View the original post : http://www.jboss.com/index.html?module=bb&op=viewtopic&p=3998056#3998056
Reply to the post : http://www.jboss.com/index.html?module=bb&op=posting&mode=reply&p=3998056
19 years, 3 months