Reading STL trace output
In
your Loglist Utility output,
STL trace records have a record type of STRC, enabling you to identify
them quickly. These records have the following format:
ITP35WWI PROGRAM= XXXXXXXX STMT# = YYYYY PROCEDURE= ZZZZZZZZ: trace data| WW | A unique 2-character identifier for each message. |
| XXXXXXXX | The program name as specified on the PROGRAM execution parameter or an STL @PROGRAM statement |
| YYYYY | The number of the STL statement being traced by this STL trace record |
| ZZZZZZZZ | The name of the STL procedure currently being executed |
| trace data | A message that describes the activity for the statement being traced. See WSim Messages and Codes for information about the messages that can appear as trace data in these records. |
Figure 1 displays
sample Loglist Utility output
containing STL trace records along with other types of records. The
following sections explain how to read this output and use it in tracing
program activity.
Figure 1. Sample Loglist Utility Output
with STL Trace Records, part 1 of 2
NETWORK APPCLU/TCPIP/VTAMAPPL DEV/LU/TP START STOP READY RECORD HEADER DATA TERM MESSAGE USER SEQUENCE
NAME NAME NAME TIME TIME TIME TYPE FLAGS LENG TYPE DECK DATA NUMBER
14563706 0002026 11000000 CNSL 0800 000000 74
Workload Simulator (WSim) Version 1, Release 1.0
------------------------------------------------------------------------------------------------------------------------------------
14564335 0002026 11000000 CNSL 0800 000000 13
I SLUECHO,S,L
------------------------------------------------------------------------------------------------------------------------------------
14564379 0002026 11000000 CNSL 0800 000000 51
ITP029I INITIALIZATION COMPLETE FOR NETWORK SLUECHO
------------------------------------------------------------------------------------------------------------------------------------
14564379 0002026 11000000 CNSL 0800 000000 31
ITP006I NETWORK SLUECHO STARTED
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564379 0002026 11000000 STRC 0004 081000 22 E2 LOGON 00 0
ITP3515I PROGRAM=LOGECHO STMT#=00006 PROCEDURE=LOGON : EXECUTION RESUMES
------------------------------------------------------------------------------------------------------------------------------------
14564379 0002026 11000000 CNSL 0800 000000 60
ITP137I SLUECHO SLU -00001 - Logon processing begins...
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564379 0002026 11000000 STRC 0004 081000 22 E2 LOGON 00 1
ITP3518I PROGRAM=LOGECHO STMT#=00008 PROCEDURE=LOGON : EXECUTION INTERRUPTED
ITP3515I PROGRAM=LOGECHO STMT#=00010 PROCEDURE=LOGON : EXECUTION RESUMES
ITP3518I PROGRAM=LOGECHO STMT#=00010 PROCEDURE=LOGON : EXECUTION INTERRUPTED
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 14564430 14564430 +XMIT 8000 880020 34 E2 LOGON 00 4
XMIT INITIATE SELF REQUEST
TH 2C0000010001 FID=2 WHOLE SEGMENT NORMAL FLOW DAF=00 OAF=01 ODAI=0 SEQUENCE=1
RH 0B8000 REQUEST FM DATA-FM HEADER ONLY IN CHAIN RESPONSE TYPE=DEF1
RU 01068101 C4F4C1F3 F2F7F8F2 F308C9E3 D7C5C3C8 D6400000 00 *..a.D4A327823.ITPECHO ... *
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 14564430 14564430 +RECV 8000 080020 12 E2 LOGON 00 5
RECV INITIATE SELF RESPONSE
TH 2C0001000001 FID=2 WHOLE SEGMENT NORMAL FLOW DAF=01 OAF=00 ODAI=0 SEQUENCE=1
RH 8B8000 RESPONSE FM DATA-FM HEADER ONLY IN CHAIN RESPONSE TYPE=DEF1
RU 010681 *..a *
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 0002026 11000000 STRC 0004 081000 22 E2 LOGON 00 6
ITP3514I PROGRAM=LOGECHO STMT#=00008 PROCEDURE=LOGON : ONIN CONDITION NOT MET
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 14564430 14564430 RECV 8000 080000 48 E2 LOGON 00 7
RECV BIND SESSION REQUEST
TH 2D0001010001 FID=2 WHOLE SEGMENT EXPEDITED FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH 6B8000 REQUEST SESSION CONTROL ONLY IN CHAIN RESPONSE TYPE=DEF1
RU 31010303 B1903080 000087C7 00000200 00000000 18500000 7E000008 C9E3D7C5 *..........gG.........&..=...ITPE*
00000020 C3C8D640 000000 *CHO ... *
BIND SESSION FORMAT 0 TYPE=NON-NEGOTIABLE FM PROFILE 3 TS PROFILE 3
PRIMARY PROTOCOLS: RU CHAINING=MULTIPLE REQUEST MODE=IMMEDIATE RESPONSE REQUESTED=DEF OR EXC END BRACKET SENT
SECONDARY PROTOCOLS: RU CHAINING=MULTIPLE REQUEST MODE=IMMEDIATE RESPONSE REQUESTED=EXCEPTION END BRACKET NOT SENT
COMMON PROTOCOLS: SEGMENTS SUPPORTED FM HEADERS NOT ALLOWED BRACKETS RESET BETB BRACKET TERMINATION RULE 1
ALTERNATE CODE SET NOT USED HALF-DUPLEX FLIP-FLOP RECOVERY RESPONSIBILITY=PRIMARY
CONTENTION WINNER=SECONDARY
SECONDARY SEND PACING COUNT=NONE SECONDARY RECEIVE PACING COUNT=NONE ADAPTIVE SESSION PACING NOT SUPPORTED
SECONDARY MAXIMUM RU SEND SIZE=1024 PRIMARY MAXIMUM RU SEND SIZE=1536
PRIMARY SEND PACING COUNT=NONE PRIMARY RECEIVE PACING COUNT=NONE
LU TYPE 2 DEFAULT SCREEN SIZE=024,080 ALTERNATE SCREEN SIZE=NONE
PRIMARY LU NAME=ITPECHO CRYPTOGRAPHIC FIELD=NONEFigure 2. Sample Loglist Utility output
with STL trace records, part 2 of 2
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 0002026 11000000 STRC 0004 081000 22 E2 LOGON 00 8
ITP3514I PROGRAM=LOGECHO STMT#=00008 PROCEDURE=LOGON : ONIN CONDITION NOT MET
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 14564430 14564430 XMIT 8000 880000 10 E2 LOGON 00 9
XMIT BIND SESSION RESPONSE
TH 2D0001010001 FID=2 WHOLE SEGMENT EXPEDITED FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH EB8000 RESPONSE SESSION CONTROL ONLY IN CHAIN RESPONSE TYPE=DEF1
RU 31 *. *
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 14564430 14564430 RECV 8000 080000 10 E2 LOGON 00 10
RECV START DATA TRAFFIC REQUEST
TH 2D0001010001 FID=2 WHOLE SEGMENT EXPEDITED FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH 6B8000 REQUEST SESSION CONTROL ONLY IN CHAIN RESPONSE TYPE=DEF1
RU A0 *. *
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 0002026 11000000 STRC 0004 081000 22 E2 LOGON 00 11
ITP3514I PROGRAM=LOGECHO STMT#=00008 PROCEDURE=LOGON : ONIN CONDITION NOT MET
ITP3515I PROGRAM=LOGECHO STMT#=00011 PROCEDURE=LOGON : EXECUTION RESUMES
ITP3512I PROGRAM=LOGECHO STMT#=00011 PROCEDURE=LOGON : DO WHILE CONDITION MET
ITP3516I PROGRAM=LOGECHO STMT#=00012 PROCEDURE=LOGON : WAIT OR TRANSMIT INTERRUPT BEGINS
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564430 14564432 14564430 XMIT 8000 880000 10 E2 LOGON 00 12
XMIT START DATA TRAFFIC RESPONSE
TH 2D0001010001 FID=2 WHOLE SEGMENT EXPEDITED FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH EB8000 RESPONSE SESSION CONTROL ONLY IN CHAIN RESPONSE TYPE=DEF1
RU A0 *. *
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564482 14564482 14564482 RECV 8000 080000 130 E2 LOGON 00 16
RECV (DATA) REQUEST
TH 2C0001010001 FID=2 WHOLE SEGMENT NORMAL FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH 0380A0 REQUEST FM DATA ONLY IN CHAIN RESPONSE TYPE=DEF1 BEGIN BRACKET
INDICATORS= CHANGE DIRECTION
RU F5C7114E 7F1DF8E6 C5D3C3D6 D4C540E3 D640C9E3 D7C5C3C8 D64B1D60 40C5D5E3 *5G.+".8WELCOME TO ITPECHO..- ENT*
00000020 C5D97EC5 C3C8D640 4040C3D3 C5C1D97E D9C5E2E3 D6D9C540 4040F57E E2E3D9C9 *ER=ECHO CLEAR=RESTORE 5=STRI*
00000040 D5C740D9 C5D7E340 4040F97E D9C5D7C5 C1E31150 50C5D5E3 C5D940C4 C1E3C140 *NG REPT 9=REPEAT.&&ENTER DATA *
00000060 E3D640C5 C3C8D640 C2C5D3D6 E67A11D1 5F1D4013 115D7F1D F0 *TO ECHO BELOW:.J–. ..)".0 *
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564482 0002026 11000000 STRC 0004 081000 55 E2 LOGON 00 17
ITP3514I PROGRAM=LOGECHO STMT#=00008 PROCEDURE=LOGON : ONIN CONDITION MET; LOGGED_ON = ON
ITP3514I PROGRAM=LOGECHO STMT#=00012 PROCEDURE=LOGON : ONIN CONDITION MET; WAIT CONDITION SATISFIED
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564483 14564483 14564480 XMIT 8000 880000 9 E2 LOGON 00 19
XMIT RESPONSE
TH 2C0001010001 FID=2 WHOLE SEGMENT NORMAL FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH 838000 RESPONSE FM DATA ONLY IN CHAIN RESPONSE TYPE=DEF1
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564530 0002026 11000000 STRC 0004 081000 22 E2 LOGON 00 20
ITP3515I PROGRAM=LOGECHO STMT#=00013 PROCEDURE=LOGON : EXECUTION RESUMES
ITP3512I PROGRAM=LOGECHO STMT#=00011 PROCEDURE=LOGON : DO WHILE CONDITION NOT MET
------------------------------------------------------------------------------------------------------------------------------------
14564530 0002026 11000000 CNSL 0800 000000 60
ITP137I SLUECHO SLU -00001 - Logon processing complete.
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564530 0002026 11000000 STRC 0004 081000 22 E2 ECHO 00 22
ITP3519I PROGRAM=SLUECHO STMT#=00003 PROCEDURE=ECHO : EXECUTION CONTINUES
ITP3516I PROGRAM=SLUECHO STMT#=00004 PROCEDURE=ECHO : WAIT OR TRANSMIT INTERRUPT BEGINS
------------------------------------------------------------------------------------------------------------------------------------
SLUECHO APPL1 SLU-1 14564531 14564531 14564530 XMIT 8000 880000 20 E2 ECHO 00 24
XMIT (DATA) REQUEST
TH 2C0001010001 FID=2 WHOLE SEGMENT NORMAL FLOW DAF=01 OAF=01 ODAI=0 SEQUENCE=1
RH 039020 REQUEST FM DATA ONLY IN CHAIN RESPONSE TYPE=EXCP
INDICATORS= CHANGE DIRECTION
RU 7DD1E511 D160C885 939396 *'JV.J-Hello *
------------------------------------------------------------------------------------------------------------------------------------