Analyzing the trace data

The trace data in the trace.log file in WebSphere® Application Server includes the time and sequence of method calls by the base classes and additional classes based on your configuration of the logging and tracing setting.

About this task

The trace output format consists of:

a timestamp
a thread ID
an abbreviated short name of the logging component
an event type indicator
the class that issued the message or trace event
additional text message

The following trace entries demonstrate the information that is logged when WebSphere Application Server is properly configured for the IMS TM resource adapter.

RAIMSTM is the short name for the IMS TM resource adapter.
Each time a major event takes place, information is logged for its entrance and exit.

[7/5/11 14:24:04:968 PDT] 00000012 RAIMSTM       2 com.ibm.ims.ico.IMSTCPIPAdapter generateClientID() Entering method.
[7/5/11 14:24:04:968 PDT] 00000012 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter generateClientID()         LocalPort = [3107]
[7/5/11 14:24:04:968 PDT] 00000012 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter generateClientID()         IP Address = [9030020219]
[7/5/11 14:24:04:968 PDT] 00000013 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter generateClientID()         Generated ID  = [HWSY6N4P]
[7/5/11 14:24:04:968 PDT] 00000013 RAIMSTM       < com.ibm.ims.ico.IMSTCPIPAdapter generateClientID() Exiting method.
[7/5/11 14:24:04:968 PDT] 00000013 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter connect() <-- [com.ibm.ims.ico.IMSTCPIPAdapter@1eca167.connect()]

If the connection to the IMS host system is successful, the trace shows the message is sent to IMS and the client ID is generated.

[7/5/11 14:24:04:968 PDT] 00000013 RAIMSTM       2 com.ibm.connector2.ims.ico.inbound.IMSInboundUtil .sendIMSMessage() Entering method.
[7/5/11 14:24:04:968 PDT] 00000013 RAIMSTM       3 com.ibm.connector2.ims.ico.inbound.IMSInboundUtil .sendIMSMessage() Mode is: MODE_RECEIVE_ASYNCOUTPUT_AUTO
[7/5/11 14:24:04:968 PDT] 00000012 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter generateClientID()         Generated ID  = [HWSYEW06]
[7/5/11 14:24:04:968 PDT] 00000012 RAIMSTM       < com.ibm.ims.ico.IMSTCPIPAdapter generateClientID() Exiting method.
[7/5/11 14:24:04:968 PDT] 00000012 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter connect() <-- [com.ibm.ims.ico.IMSTCPIPAdapter@1ee8e00.connect()]

The following trace entries demonstrate a failed connection to IMS (host dev555.vmec.ibm.com; port 9999). An ICO0003E message is logged. When the connection fails, no message is sent.

connect() ->  [com.ibm.ims.ico.IMSTCPIPAdapter@2037bc2.connect(): HostName=dev555.vmec.ibm.com PortNumber=9999] SocketTimeout = [0]
[7/5/11 14:24:16:734 PDT] 00000021 RAIMSTM       E com.ibm.connector2.ims.ico.IMSTCPIPManagedConnection connect() ICO0003E: com.ibm.connector2.ims.ico.IMSTCPIPManagedConnection@2037bae.connect() error. 
         Failed to connect to host [dev555.vmec.ibm.com], port [9999]. [java.net.UnknownHostException: dev555.vmec.ibm.com]  
         Explanation=The IMS TM resource adapter was unable to connect to the host and port combination. java_exception indicates the reason for the failure to connect.  
         UserAction=Examine the exception to determine the reason for the failure to connect to the host.
[7/5/11 14:24:16:734 PDT] 00000021 RAIMSTM       2 com.ibm.connector2.ims.ico.IMSManagedConnection errorOccurred(Exception) Entering method.
[7/5/11 14:24:16:734 PDT] 00000021 ConnectionEve W   J2CA0206W: A connection error occurred.  To help determine the problem, enable the Diagnose Connection Usage option on the Connection Factory or Data Source.
[7/5/11 14:24:16:734 PDT] 00000021 ConnectionEve A   J2CA0056I: The Connection Manager received a fatal connection error from the Resource Adapter for resource

With a successful connection, when the message is sent, the trace data shows the buffer that is sent. This information is the same information you see in an IMS Connect recorder trace, unless you have customized exit routine.

 [7/5/11 14:24:04:984 PDT] 00000012 RAIMSTM       2 com.ibm.ims.ico.IMSTCPIPAdapter send(byte[]) Entering method.
[7/5/11 14:24:04:984 PDT] 0000000b ApplicationMg A   WSVR0221I: Application started: query
[7/5/11 14:24:04:984 PDT] 0000000b CompositionUn A   WSVR0191I: Composition unit WebSphere:cuname=query in BLA WebSphere:blaname=query started.
[7/5/11 14:24:04:984 PDT] 00000013 RAIMSTM       3 com.ibm.ims.ico.IMSTCPIPAdapter send(byte[]) Buffer sent:
	[
		000001f2 001c0100 5cc8e6e2 d1c1e55c |...2. ..*HWSJAV*| : 16
		00000000 c0ff0000 c8e6e2e8 f6d5f4d7 |....{...HWSY6N4P| : 32
		01100000 28004040 40404040 4040a0e0 |......        µ\| : 48
		00000000 00000000 00000000 00010000 |................| : 64
		00480240 01104040 40404040 40400000 |.ç. ..        ..| : 80
		00000000 00000000 00000000 00000000 |................| : 96
		00000000 00000000 00000000 00000000 |................| : 112
		00000000 00000000 00000000 00004040 |..............  | : 128
		40404040 40400000 006ac614 09024040 |      ...¦F...  | : 144
		40404040 40400903 40404040 40404040 |      ..        | : 160
		00000000 00000000 00000000 00000000 |................| : 176
		00000000 00000000 00000000 00000000 |................| : 192
		00000000 00000000 00000000 00000000 |................| : 208
		00000000 00000000 00000000 00000000 |................| : 224
		00000000 00000000 00000000 00000000 |................| : 240
		00000100 0000c9d4 e2f14040 4040c8e6 |......IMS1    HW| : 256
		e2e8f6d5 f4d70000 00000000 00000000 |SY6N4P..........| : 272
		00000000 00000000 00000000 00000000 |................| : 288
		00000000 00004040 40404040 40401040 |......        . | : 304
		00000000 00004040 40404040 40400000 |......        ..| : 320
		00000300 00000000 00000000 0000e3d4 |..............TM| : 336
		d9c1e3d7 f4400000 00000000 00000000 |RATP4 ..........| : 352
		00000000 00000000 00000000 00000000 |................| : 368
		00000000 00000000 00000000 00000000 |................| : 384
		00000000 00000000 00000000 00000000 |................| : 400
		00000000 00000000 00000000 00000000 |................| : 416
		00000000 00000000 00000000 00000000 |................| : 432
		00000000 00000000 00000000 00000000 |................| : 448
		00000000 00000000 00000000 00000000 |................| : 464
		00000000 00000000 00000000 00000000 |................| : 480
		00000000 00000000 00000000 00000000 |................| : 496
		0000                                |..|
	]
[7/5/11 14:24:04:984 PDT] 00000013 RAIMSTM       < com.ibm.ims.ico.IMSTCPIPAdapter send(byte[]) Exiting method.

For more information about how to work with trace, see IBM® Documentation for WebSphere Application Server.