Answer by Reguel Wermelinger

Reguel Wermelinger — Tue, 17 Dec 2013 08:16:09 -0500

Webservice calls in Xpert.ivy are by default executed with Axis2. To generate debug log outputs in Axis2 the following VM arguments (stackoverflow) have to be set:

-Dorg.apache.commons.logging.Log=org.apache.commons.logging.impl.SimpleLog
-Dorg.apache.commons.logging.simplelog.showdatetime=true
-Dorg.apache.commons.logging.simplelog.log.httpclient.wire=debug
-Dorg.apache.commons.logging.simplelog.log.org.apache.commons.httpclient=debug

Additional VM arguments can be set in Xpert.ivy within the Launch Configuration (*.ilc file). More informations to launch configurations can be found in the Server Guide, Chapter 8.

If the VM arguments are properly set, the output generated by a webservice call could look as follows:

2013/12/17 08:02:00:003 CET [DEBUG] DefaultHttpParams - Set parameter http.connection.timeout = 600000
2013/12/17 08:02:00:003 CET [DEBUG] DefaultHttpParams - Set parameter http.socket.timeout = 600000
2013/12/17 08:02:00:003 CET [DEBUG] DefaultHttpParams - Set parameter http.socket.timeout = 600000
2013/12/17 08:02:00:003 CET [DEBUG] MultiThreadedHttpConnectionManager - HttpConnectionManager.getConnection:  config = HostConfiguration[host=http://zugtstweb:8080], timeout = 0
2013/12/17 08:02:00:003 CET [DEBUG] MultiThreadedHttpConnectionManager - Allocating new connection, hostConfig=HostConfiguration[host=http://zugtstweb:8080]
2013/12/17 08:02:00:003 CET [DEBUG] HttpConnection - Open connection to zugtstweb:8080
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "POST /axis2/services/StockQuoteService.StockQuoteServiceHttpEndpoint/getPrice HTTP/1.1[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] HttpMethodBase - Adding Host request header
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "Content-Type: application/x-www-form-urlencoded; charset=UTF-8[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "SOAPAction: ""[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "User-Agent: Axis2[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "Host: zugtstweb:8080[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "Content-Length: 14[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - >> "[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] content - >> "symbol=myStuff"
2013/12/17 08:02:00:004 CET [DEBUG] EntityEnclosingMethod - Request body sent
2013/12/17 08:02:00:004 CET [DEBUG] header - << "HTTP/1.1 200 OK[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - << "Server: Apache-Coyote/1.1[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - << "Content-Type: application/xml;charset=UTF-8[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - << "Transfer-Encoding: chunked[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] header - << "Date: Tue, 17 Dec 2013 07:02:00 GMT[\r][\n]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "6"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "f"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\r]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\n]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "<"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "n"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "s"
2013/12/17 08:02:00:004 CET [DEBUG] content - << ":getPriceResponse xmlns:ns=""
2013/12/17 08:02:00:004 CET [DEBUG] content - << "http://quickstart.samples/xsd"><ns:return>42.0</ns:return></ns:getPriceResponse>"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\r]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\n]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "0"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\r]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\n]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\r]"
2013/12/17 08:02:00:004 CET [DEBUG] content - << "[\n]"
2013/12/17 08:02:00:004 CET [DEBUG] HttpMethodBase - Resorting to protocol version default close connection policy
2013/12/17 08:02:00:020 CET [DEBUG] HttpMethodBase - Should NOT close connection, using HTTP/1.1
2013/12/17 08:02:00:020 CET [DEBUG] HttpConnection - Releasing connection back to connection manager.
2013/12/17 08:02:00:020 CET [DEBUG] MultiThreadedHttpConnectionManager - Freeing connection, hostConfig=HostConfiguration[host=http://zugtstweb:8080]
2013/12/17 08:02:00:020 CET [DEBUG] IdleConnectionHandler - Adding connection at: 1387263720020
2013/12/17 08:02:00:020 CET [DEBUG] MultiThreadedHttpConnectionManager - Notifying no-one, there are no waiting threads

Answer by Daniel Oechslin

Daniel Oechslin — Mon, 16 Dec 2013 11:26:02 -0500

I mixed request and response. Here the new answer:

    Map<String,Object> map= Request.getCurrentRequest().getParameters();
    Iterator it = map.entrySet().iterator();
    while (it.hasNext()) {
        Map.Entry pairs = (Map.Entry)it.next();
        Ivy.log().debug(pairs.getKey() + " = " + pairs.getValue());
        it.remove();
    }

This should print the all parameters of the request.

Answers to: Log soap request from web service call step

Answer by Reguel Wermelinger

Answer by Daniel Oechslin