The most important events in the program units of the applications are contained in the TRACE2 evaluation list in sequence. Since the evaluation is not broken down into columns for the UTM tasks as in the TRACE list, the TRACE2 list can show any number of tasks. In addition to the entries of the TRACE evaluation, TRACE2 also contains important data for performance analysis.
The entries in the evaluation are sorted in chronological order. The TIME STAMP column contains the time stamp of the event (in microseconds).
The TRACE2 evaluation list records the following events and data:
Start of a program unit as an entry
strt >>> tacwithTransaction code of the program unit
TAC class
Current I/O and CPU stamp (in milliseconds)
Wait time of message BCAM
Wait time of job in the TAC class
All UTM function calls with operation code and modification, plus the information:
KCMF for KCMF-relevant calls
KCRN for KCRN-relevant calls
KCLT for PADM and DADM calls
For PEND calls with KCOM = ER/FR/RS and for the database calls FITA and CATA, the transaction ID (SC,VC,TC, and VN) for the assignment to the aborted service.
If KCRCCC
!=0, the return codes KCRCDC and KCRRCC and the transaction ID (SC,VC,TC and VN).
All database calls, if the UTM application is coordinating with the DB system. The calls to the database system are logged with an entry DBCL opcode db-time.
opcode and db-time mean the same as in the TRACE list.
If SESCOS is activated at the DBH side, the information
SESCOS-INFO -->xyttttnnnmmmmmmmm is also logged for calls to the SESAM database system. The values enable the assignment of TRACE2 lists and SESCOS evaluation. See also the description of the fields for the TRACE list on "TRACE: TASK SPECIFIC TRACES".End of the program unit as an entry
WAIT end<<<<withCPU utilization in the program unit in microseconds in the “CPU” column
Number I/Os in the program unit in the “I/O” column
- At the end of column "TRACE" the first two bytes of the last bourse announcement are rendered as four characters representing the sedecimal value of the announcement.
This information allows the system service a better analysis and diagnosis of possible performance problems.
Example:
TIME STAMP | TRACE | ID TSN |
16:39:25.002 | WAIT end<<<<< 45C5 | 4 " |
A logical processor number supplied by KDCMON.
Each number thus identifies a CPU. The number is indicated in the “ID” column. On multiprocessor systems, you can thereby determine which UTM tasks run in parallel.
The structure elements <<<<<< in the list make it easier to read the entries.
The TRACE2 evaluation list has the following format.
TIME
STAMP | TRACE | ID TSN | SC VC TC
VN|KCRN CPU |KCMF I/O |TACCLASS Q.TIME |BCAM W.TIME 10:48:08.353714| DBCL USRC 205| 1 8E8R | | | | | 10:48:08.353761| CONT 31 -------------| 1 8E85 | 1 192108 1 31| | | | 10:48:08.353900| WAIT -------------| 1 " | | | | | 10:48:08.353905+
WAIT end <<<<< 45C5+ 1 "
|<<<<<<<<<<<<<<<<<<<<<<<<<<<<|
+621 | +2
|<<<<<<<<<<<<<<<
|<<<<<<<<<<< 10:48:08.353925|
DBCL USRC 2065| 1 8E8R | |
| | | 10:48:08.356002| DBCL USRC 205| 1 " | | | | | 10:48:08.356213|
DBCL USRC 3| 1 " | |
| | | 10:48:08.356219+
DBCL USRC 3370+ 1 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.357268|
strt >>>RDUDS | 1 8E85 | 1 192110 1 32|
40758 | 98786 | 0 * 0 | 0 10:48:08.357321|
INIT -------------| 1 " | |
| | | 10:48:08.357335|
MGET -------------| 1 " | |
| | | 10:48:08.357355+
DBCL USRC 8+ 1 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.357370|
DBCL USRC 307| 1 " | |
| | | 10:48:08.357685|
DBCL USRC 2| 1 " | |
| | | 10:48:08.357690|
DBCL USRC 121| 1 " | |
| | | 10:48:08.357818+
DBCL USRC 1+ 1 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.357822|
DBCL USRC 2| 1 " | |
| | | 10:48:08.357835|
MPUT NT -------------| 1 " | |
| | | 10:48:08.357850|
MPUT NT -------------| 1 " | |
| | | 10:48:08.357859+
MPUT NT -------------+ 1 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.357868|
PEND FI -------------| 1 " | |
| | | 10:48:08.358905|
strt >>>WRUDS | 1 8E83 | 1 192111 1 33|
41722 | 100624 | 0 * 0 | 0 10:48:08.358975|
INIT -------------| 1 " | |
| | | 10:48:08.358986+
MGET -------------+ 1 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.359007|
DBCL USRC 7| 1 " | |
| | | 10:48:08.359018|
DBCL USRC 707| 1 " | |
| | | 10:48:08.359214|
strt >>>WRUDS | 2 8E86 | 1 192112 1 30|
40893 | 98246 | 0 * 0 | 0 10:48:08.359274+
INIT -------------+ 2 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.359286|
MGET -------------| 2 " | |
| | | 10:48:08.359304|
DBCL USRC 6| 2 " | |
| | | 10:48:08.359317|
DBCL USRC 173| 2 " | |
| | | 10:48:08.359496+
DBCL USRC 1+ 2 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.359500|
DBCL USRC 41| 2 " | |
| | | 10:48:08.359544|
DBCL USRC 1188| 2 " | |
| | | 10:48:08.359614| RSET -------------| 1 8E8R | 1 192109 1 34| | | | 10:48:08.359653+
DBCL CATA 192+ 1 " + 1 192109 1 34+
----------- +-------------+------------------+----------- 10:48:08.359734|
DBCL USRC 1| 1 8E83 | |
| | | 10:48:08.359738|
DBCL USRC 129| 1 " | |
| | | 10:48:08.359850|
DBCL EDVG 87| 1 8E8R | |
| | | 10:48:08.359872+
DBCL USRC 2389+ 1 8E83
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.359945| CONT 4C -------------| 1 8E8R | 1 192109 1 34| | | | 10:48:08.359992|
DBCL USRC 5| 1 " | |
| | | 10:48:08.360002|
DBCL USRC 251| 1 " | |
| | | 10:48:08.360262+
DBCL USRC 111+ 1 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.360378|
DBCL USRC 34| 1 " | |
| | | 10:48:08.360417| DBCL USRC 2| 1 " | | | | | 10:48:08.360423| DBCL USRC 4689| 1 " | | | | | 10:48:08.360740+
DBCL USRC 2+ 2 8E86
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.360745| DBCL USRC 2| 2 " | | | | | 10:48:08.360757| MPUT NT -------------| 2 " | | | | | 10:48:08.360777| MPUT NT -------------| 2 " | | | | | 10:48:08.360785+
MPUT NT -------------+ 2 "
+----------------------------+-------------+-------------+------------------+-----------
10:48:08.360795| PEND FI -------------| 2 " | | | | | 10:48:08.361494| DBCL FITA 574| 1 8E85 | 1 192110 1 32| | | | 10:48:08.362080| CONT 31 -------------| 1 " | | | | |