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

    Double ws request using jbossws client

    carlo tomei Newbie

      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