0 Replies Latest reply on Sep 15, 2008 5:50 AM by againc

Double ws request using jbossws client

againc Sep 15, 2008 5:50 AM

Hi all,

I have a problem using WebServices proxy clients generated with wsconsume. Looks the the exposed ws is called 3 times 1 to download the wsdl(OK) and 2 consecutive calls to the the exposed method.
the implemented code it's right as you can see following:

URL webServiceURL = null;
Vector beanList = null;
ConfigurationWrapper conf = ConfigurationWrapper.getInstance();

try {

webServiceURL = new URL(conf.getVehicleCountServiceEndPoint());
}

catch (MalformedURLException e) {

e.printStackTrace();
log(e);
}
ObjectFactory of = new ObjectFactory();
GetCountProxyService service = new GetCountProxyService(webServiceURL, new QName("http://www.infomobility.com/IB", "getCountProxyService"));

try {

GetCountProxyPortType port = service.getGetCountProxyPort();

GetCountProxyReq req = of.createGetCountProxyReq();
req.setHEADER(beanHeader);
req.setCUSTOMER(beanBodyCustomer);
req.setSERVICE(beanBodyService);
GetCountProxyRes result = port.getCountProxy(req);
logger.info("############# STA WA CALL IB VehicleCount "+System.currentTimeMillis()+" #############");
result = port.getCountProxy(req);
logger.info("############# END WA CALL IB VehicleCount "+System.currentTimeMillis()+" #############");
if (null != result) {

beanBodyResult = result.getMESSAGERESULT();
beanHeaderResult = result.getHEADER();
beanIndexResult = result.getINDEX();

beanList = new Vector();
beanList.add(beanHeaderResult);
beanList.add(beanBodyResult);
beanList.add(beanIndexResult);
}

}
catch (Exception e) {
error("", e);
}

return beanList;

The logs that you can see in the code are printed just 1 time a call in the log files.
the Log:

2008-09-15 11:16:23,864 DEBUG [org.jboss.ws.core.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8]
2008-09-15 11:16:23,866 DEBUG [org.jboss.remoting.InvokerRegistry] removed org.jboss.remoting.transport.http.HTTPClientInvoker@7b28dc from registry
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.jaxws.handler.MessageContextJAXWS] Begin response processing
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@42058f (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@17accc9 (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] unbindResponseMessage: {http://www.infomobility.com/IB}getCountProxy
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.EndpointInvocation] setReturnValue: org.jboss.ws.core.soap.SOAPBodyElementDoc
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.CommonClient] Handler modified body payload, unbind message again
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] unbindResponseMessage: {http://www.infomobility.com/IB}getCountProxy
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.EndpointInvocation] setReturnValue: org.jboss.ws.core.soap.SOAPBodyElementDoc
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.EndpointInvocation] getReturnValue
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] -----------------------------------
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from XML_VALID to OBJECT_VALID
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.soap.XMLContent] getObjectValue [xmlType={http://www.infomobility.com/IB}>getCountProxy_Res,javaType=class it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes]
2008-09-15 11:16:23,866 DEBUG [org.jboss.ws.core.jaxws.JAXBDeserializer] deserialize: [xmlName={http://www.infomobility.com/IB}getCountProxy_Res,xmlType={http://www.infomobility.com/IB}>getCountProxy_Res]
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.jaxws.JAXBDeserializer] deserialized: it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.soap.XMLContent] objectValue: it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] -----------------------------------
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: org.jboss.ws.core.soap.SOAPBodyElementDoc -> it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close
2008-09-15 11:16:23,901 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close
2008-09-15 11:16:23,902 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@17accc9 (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:16:23,902 INFO [it.telecomitalia.vt.wa.log.GenericLogInterface] ############# STA WA CALL IB VehicleCount 1221470183902 #############
2008-09-15 11:16:23,903 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@c3455c (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:16:23,903 DEBUG [org.jboss.ws.core.EndpointInvocation] setRequestParamValue: [name={http://www.infomobility.com/IB}getCountProxy_Req,value=it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyReq]
2008-09-15 11:16:23,903 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] bindRequestMessage: {http://www.infomobility.com/IB}getCountProxy
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.EndpointInvocation] getRequestParamValue: {http://www.infomobility.com/IB}getCountProxy_Req
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyReq -> it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyReq
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: []
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: []
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] Create a handler executor: []
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.client.RemotingConnectionImpl] Get locator for: [addr=http://ip-fe-alsb-ms-01-1:8101/getCountProxy,props={javax.xml.ws.service.endpoint.address=http://ip-fe-alsb-ms-01-1:8101/getCountProxy}]
2008-09-15 11:16:23,904 DEBUG [org.jboss.remoting.MicroRemoteClientInvoker] org.jboss.remoting.transport.http.HTTPClientInvoker@1feaee5 connecting
2008-09-15 11:16:23,904 DEBUG [org.jboss.remoting.MicroRemoteClientInvoker] org.jboss.remoting.transport.http.HTTPClientInvoker@1feaee5 connected
2008-09-15 11:16:23,904 DEBUG [org.jboss.ws.core.client.RemotingConnectionImpl] Remoting metadata: {HEADER={SOAPAction="soapgetCountProxy", Content-Type=text/xml; charset=UTF-8}, NoThrowOnError=true}
2008-09-15 11:16:23,905 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with SOAPAction : "soapgetCountProxy"
2008-09-15 11:16:23,905 DEBUG [org.jboss.remoting.transport.http.HTTPClientInvoker] Setting request header with Content-Type : text/xml; charset=UTF-8
2008-09-15 11:16:23,905 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] -----------------------------------
2008-09-15 11:16:23,905 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from OBJECT_VALID to XML_VALID
2008-09-15 11:16:23,905 DEBUG [org.jboss.ws.core.soap.ObjectContent] getXMLFragment from Object [xmlType={http://www.infomobility.com/IB}>getCountProxy_Req,javaType=class it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyReq]
2008-09-15 11:16:23,905 DEBUG [org.jboss.ws.core.jaxws.JAXBSerializer] serialize: [xmlName={http://www.infomobility.com/IB}getCountProxy_Req,xmlType={http://www.infomobility.com/IB}>getCountProxy_Req]
2008-09-15 11:16:23,906 DEBUG [org.jboss.ws.core.jaxws.JAXBSerializer] serialized: <getCountProxy_Req xmlns="http://www.infomobility.com/IB"><T_SYS>WA</T_SYS><R_SYS>IB</R_SYS><ID_CUSTOMER>85</ID_CUSTOMER><ID_SERVICE>VTT</ID_SERVICE></getCountProxy_Req>
2008-09-15 11:16:23,906 DEBUG [org.jboss.ws.core.soap.ObjectContent] xmlFragment: [source=<getCountProxy_Req xmlns="http://www.infomobility.com/IB"><T_SYS>WA</T_SYS><R_SYS>IB</R_SYS><ID_CUSTOMER>85</ID_CUSTOMER><ID_SERVICE>VTT</ID_SERVICE></getCountProxy_Req>]
2008-09-15 11:16:23,906 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] -----------------------------------
2008-09-15 11:16:25,628 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - first pass <Mon, 15 Sep 2008 11:16:25>
2008-09-15 11:16:25,628 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] StatusModule: first pass
2008-09-15 11:16:25,629 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_3] - TORecoveryModule - first pass
2008-09-15 11:16:25,629 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.firstpass] Local XARecoveryModule - first pass
2008-09-15 11:16:35,639 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] Periodic recovery - second pass <Mon, 15 Sep 2008 11:16:35>
2008-09-15 11:16:35,639 DEBUG [com.arjuna.ats.arjuna.logging.arjLogger] AtomicActionRecoveryModule: Second pass
2008-09-15 11:16:35,639 DEBUG [com.arjuna.ats.txoj.logging.txojLoggerI18N] [com.arjuna.ats.internal.txoj.recovery.TORecoveryModule_6] - TORecoveryModule - second pass
2008-09-15 11:16:35,639 DEBUG [com.arjuna.ats.jta.logging.loggerI18N] [com.arjuna.ats.internal.jta.recovery.info.secondpass] Local XARecoveryModule - second pass
2008-09-15 11:17:19,736 DEBUG [org.jboss.ws.core.soap.SOAPMessageUnMarshaller] getMimeHeaders from: {X-Powered-By=[Servlet/2.4 JSP/2.0], Content-Length=[1114], ResponseCodeMessage=OK, Date=[Mon, 15 Sep 2008 09:16:30 GMT], Content-Type=[text/xml; charset=utf-8], HEADER={SOAPAction="soapgetCountProxy", Content-Type=text/xml; charset=UTF-8}, NoThrowOnError=true, ResponseCode=200}
2008-09-15 11:17:19,738 DEBUG [org.jboss.ws.core.soap.MessageFactoryImpl] createMessage: [contentType=text/xml; charset=utf-8]
2008-09-15 11:17:19,739 DEBUG [org.jboss.remoting.InvokerRegistry] removed org.jboss.remoting.transport.http.HTTPClientInvoker@1feaee5 from registry
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.jaxws.handler.MessageContextJAXWS] Begin response processing
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@c3455c (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] pushMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@18c6c98 (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] unbindResponseMessage: {http://www.infomobility.com/IB}getCountProxy
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.EndpointInvocation] setReturnValue: org.jboss.ws.core.soap.SOAPBodyElementDoc
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.CommonClient] Handler modified body payload, unbind message again
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.jaxws.binding.SOAP11BindingJAXWS] unbindResponseMessage: {http://www.infomobility.com/IB}getCountProxy
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.EndpointInvocation] setReturnValue: org.jboss.ws.core.soap.SOAPBodyElementDoc
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.EndpointInvocation] getReturnValue
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] -----------------------------------
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] Transitioning from XML_VALID to OBJECT_VALID
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.soap.XMLContent] getObjectValue [xmlType={http://www.infomobility.com/IB}>getCountProxy_Res,javaType=class it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes]
2008-09-15 11:17:19,740 DEBUG [org.jboss.ws.core.jaxws.JAXBDeserializer] deserialize: [xmlName={http://www.infomobility.com/IB}getCountProxy_Res,xmlType={http://www.infomobility.com/IB}>getCountProxy_Res]
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.jaxws.JAXBDeserializer] deserialized: it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.soap.XMLContent] objectValue: it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.soap.SOAPContentElement] -----------------------------------
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.EndpointInvocation] transformPayloadValue: org.jboss.ws.core.soap.SOAPBodyElementDoc -> it.telecomitalia.vt.wa.ws.proxyclient.vehiclecount.GetCountProxyRes
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close
2008-09-15 11:17:19,741 DEBUG [org.jboss.ws.core.jaxws.handler.HandlerChainExecutor] close
2008-09-15 11:17:19,742 DEBUG [org.jboss.ws.core.soap.MessageContextAssociation] popMessageContext: org.jboss.ws.core.jaxws.handler.SOAPMessageContextJAXWS@18c6c98 (Thread ajp-10.35.67.68-8009-2)
2008-09-15 11:17:19,742 INFO [it.telecomitalia.vt.wa.log.GenericLogInterface] ############# END WA CALL IB VehicleCount 1221470239742 #############
2008-09-15 11:17:19,743 INFO [it.telecomitalia.vt.wa.action.common.CommonAbstractAction] ############################## END IB CALL VehicleCount 1221470239743 ##############################

I accept any ideas.
Thanks.

Carlo