CICS® PA provides reports and extracts to help you analyze and tune the performance of your CICS systems and applications:
Report Forms allow you to tailor the format of reports and extracts, for example, to specify which fields, the order of columns, and the sort sequence.
Selection Criteria enable you to filter your reporting, for example to include data only for a particular transaction ID, and only for a specific period of time.
The following sections provide some examples of using CICS PA reports in CICS performance analysis and tuning. For further information, see the CICS Performance Analyzer for z/OS Report Reference (SC34-6308).
The Performance List report provides a detailed list of CMF performance class records. Any CMF field can be included in the report.
You can tailor the report format to provide specific information to meet your needs. For example, you can request:
The sample report in Figure 16 shows IMS DBCTL activity for each transaction. To produce such a report, you need to collect IMS DBCTL statistics in your CMF performance records by specifying macro DFH$MCTD in your MCT definition. The DBCTL information that you can then request includes:
CICS Performance Analyzer
Performance List
LIST0001 Printed at 13:56:47 3/01/2003 Data from 15:58:48 2/19/2003 APPLID CICSP1
Analysis of Transaction IMS DBCTL Usage
Tran PSB Response UserCPU IMS Reqs IMS Wait IMS Wait SchedElp PoolWt IC Wt DBIOEl PILockEl ThredCPU DLI DBIO
Time Time Time Count Time Time Time Time Time Time Calls Call
DLI1 PSB001 5.9288 2.1340 3 1.5556 5 1.0004 .0000 .0000 .0023 .0000 .0041 2 1
DLI2 PSB002 3.5302 2.1659 3 .2359 5 .0010 .0000 .0000 .0017 .0000 .0289 2 1
DLI3 PSB003 3.4382 2.1744 3 .5010 5 .0010 .0000 .0000 .0018 .0000 .0289 2 1
DLI4 PSB004 1.0711 .0428 2 .7553 4 .0024 .0000 .0000 .0000 .0000 .0299 1 0
DLI5 PSB005 .2516 .0118 2 .2319 4 .0010 .0000 .0000 .0000 .0000 .0318 1 0
DLI6 PSB006 .3658 .0117 2 .3478 4 .0011 .0000 .0000 .0000 .0000 .0327 1 0
DLI2 PSB002 91.8213 1.8717 2 14.8960 4 .0010 .0000 .0000 .0000 .0000 .0286 1 0
DLI3 PSB003 156.501 1.9866 2 18.3825 4 .0055 .0000 .0000 .0019 .0000 .0298 1 1
DLI5 PSB005 233.355 1.9771 2 21.3535 4 .0049 .0000 .0000 .0000 .0000 .0293 1 0
DLI1 PSB001 95.2870 1.9511 2 21.4463 4 .0050 .0000 .0000 .0018 .0000 .0288 1 1
The Performance List Extended report provides a detailed list of CMF performance class records, similar to the Performance List report, but in addition it allows you to specify sorting criteria. Any CMF field can be included in the report.
The sorting capability helps to highlight problems. For example, the sample report in Figure 17 has been sorted by transaction ID in ascending sequence, then response time in descending sequence with a limit of 20, and the format has been tailored to include DB2 statistics. This enables you to quickly analyze response time problems by identifying:
CICS Performance Analyzer
Performance List Extended
LSTX0001 Printed at 15:00:28 3/01/2003 Data from 10:07:42 2/28/2003 to 16:41:05 2/28/2003
Bad DB2 transaction response time
Tran Response Userid Program Stop Dispatch UserCPU Suspend DispWt DB2ConWt DB2ThdWt DB2 DB2SQLWt SockWt
Time Time Time Time Time Time Time Time Reqs Time Time
CRD4 114.574 JOHN CORD04P 12:26:25.765 4.9961 4.6084 109.578 3.7039 .0000 90.2326 9178 19.3442 .0000
CRD4 95.2259 STEVE CORD04P 12:26:04.243 5.1529 4.6320 90.0730 9.0971 .0000 .0000 8436 90.0727 .0000
CRD4 94.8672 CHRIS CORD04P 12:26:04.954 5.0842 4.6390 89.7829 8.0275 .0000 .0000 8574 89.7826 .0000
CRD4 93.6422 SHIRLEY CORD04P 12:26:01.425 5.1434 4.6228 88.4988 8.7084 .0000 .0000 8465 88.4984 .0000
CRD4 81.5987 DAVID CORD04P 12:22:21.938 4.9596 4.5885 76.6391 6.4075 .0000 .0000 8335 76.6388 .0000
CRD4 81.2668 KATH CORD04P 12:22:22.820 4.9766 4.5806 76.2901 6.3358 .0000 .0000 9346 76.2898 .0000
CRD4 80.0224 MIKE CORD04P 12:22:18.958 5.2067 4.6592 74.8158 6.0739 .0000 .0000 8690 74.8154 .0000
CRD4 38.3645 JAMES CORD04P 12:16:12.420 5.0326 4.6100 33.3319 5.4501 .0000 .0000 9124 33.3315 .0000
. . .
CRD5 102.066 JOHN CORD05P 12:22:44.565 4.8183 4.4576 97.2478 4.4576 .0000 76.4557 6573 20.7892 .0000
CRD5 36.3721 CHRIS CORD05P 12:16:22.814 5.0605 4.5812 31.3116 4.4883 .0000 .0000 9102 31.3103 .0000
CRD5 23.2860 DAVID CORD05P 12:12:04.661 5.4456 4.6209 17.8404 3.9595 .0000 .0000 8221 17.7935 .0000
CRD5 1.0671 SHIRLEY CORD05P 11:49:21.077 .4447 .0405 .6223 .0037 .0000 .0000 1 .6192 .0000
CRD5 .6346 MIKE CORD05P 11:43:43.859 .1315 .0443 .5032 .3209 .0000 .0000 1 .1821 .0000
. . .
The Performance Summary report provides a summary of the CMF performance class records and allows you to specify sorting criteria. In addition, Clock and Count type fields can be summarized statistically. You can request any of: the average, minimum, maximum, total, or standard deviation. Any CMF field, including user-defined EMPs, can be included in the report.
You can tailor the report format to provide specific information to meet your needs. For example, the sample report in Figure 18 shows transaction activity over time. The CMF records are sorted by transaction stop time, then transaction ID, and the report summarizes the activity for each 15 minute time interval (you can specify the time interval anywhere from 1 second to 24 hours). The Task Count (#Tasks) shows the number of transactions processed during the interval.
CICS Performance Analyzer
Performance Summary
SUMM0001 Printed at 18:14:19 3/01/2003 Data from 15:00:02 10/30/2002 to 16:00:28 10/30/2002
Transaction Summary by Time of Day
Avg Max Avg Avg Avg Avg Avg Avg Avg Avg Avg
Stop Tran #Tasks Response Response Dispatch UserCPU Suspend DispWait FC Wait FCAMRq IR Wait SC24UHWM SC31UHWM
Interval Time Time Time Time Time Time Time Time
15:00:00 FINA 201 .1743 .3789 .0030 .0029 .1713 .0053 .0125 18 .0000 0 88360
15:00:00 ORDR 199 .1666 .3674 .0029 .0028 .1637 .0056 .0134 18 .0000 0 88356
15:00:00 STOK 230 .0062 .0145 .0026 .0025 .0036 .0005 .0030 18 .0000 0 88352
. . .
-------------------------------------------------------------------------------------------------------------------
15:00:00 8903 .0473 .6318 .0013 .0013 .0460 .0015 .0035 7 .0000 0 69261
. . .
15:45:00 FINA 89 .1533 .3164 .0031 .0028 .1502 .0049 .0122 18 .0000 0 88354
15:45:00 ORDR 103 .0062 .0141 .0026 .0025 .0036 .0004 .0031 18 .0000 0 88352
15:45:00 STOK 108 .0062 .0206 .0026 .0025 .0035 .0004 .0029 18 .0000 0 88352
. . .
-------------------------------------------------------------------------------------------------------------------
15:45:00 4489 .0476 .6584 .0014 .0013 .0463 .0016 .0035 7 .0000 0 69842
. . .
Selection Criteria provide a powerful mechanism for filtering the data. The sample report in Figure 19 summarizes the activity of transactions using the Web Interface. Transactions are only reported if they were active in the report period, their transaction ID matches the mask WB*, and they performed at least one Web request.
CICS Performance Analyzer
Performance Summary
SUMM0002 Printed at 8:06:34 2/08/2003 Data from 15:04:02 10/30/2002 to 15:07:28 10/30/2002
Summary of Transaction Web Activity
Avg Avg Avg Avg Avg Avg Avg Avg Avg Avg Avg Max
Tran #Tasks Response Dispatch UserCPU Suspend DispWait WBChrIn WBChrOut WBRCV WBRepoRd WBRepoWr WBSEND WB Total
Time Time Time Count Time
WBA1 137 .2912 .2022 .0022 0 .0027 5647 1637 1 2 1 1 2
WBA2 156 .2918 .2026 .0022 0 .0026 4803 921 1 2 2 1 3
WBH1 144 .1904 .1022 .0022 0 .0029 5237 1643 1 1 0 1 2
WBH2 690 .1619 .0030 .0029 1 .0049 8932 2476 1 1 0 1 2
WBT1 412 .4430 .0051 .0049 2 .0134 4750 1728 2 4 3 2 7
WBT2 395 .4451 .0053 .0050 2 .0134 6710 1923 2 3 2 2 6
WBW1 269 .3233 .0036 .0036 2 .0101 14373 6734 1 2 2 1 3
WBW2 438 .3058 .0034 .0032 1 .0091 5266 4326 1 2 0 1 3
WBW3 249 .3257 .0037 .0036 1 .0106 7192 6127 1 2 2 1 4
WBW4 407 .3058 .0033 .0032 2 .0097 9127 7910 1 1 0 1 3
. . .
The Performance Totals report gives a comprehensive resource usage analysis of your CICS system. It can be used to gain a system-wide perspective of CICS system performance. Alternatively, you can use Selection Criteria to narrow down the scope of the report, such as "Show me resource usage for a particular group of transaction IDs".
The report has four parts:
CICS Performance Analyzer
Performance Totals
TOTL0001 Printed at 7:48:49 2/28/2003 Data from 11:10:52 2/24/2003 to 11:34:12 2/24/2003
Dispatched Time CPU Time
DD HH:MM:SS Secs DD HH:MM:SS Secs
Total Elapsed Run Time 00:23:20 1400
From Selected Performance Records
QR Dispatch/CPU Time 00:00:14 14 00:00:08 8
MS Dispatch/CPU Time 00:00:16 16 00:00:01 1
-------- ----- -------- ------
Total (QR + MS) 00:00:30 30 00:00:09 9
L8 CPU Time 00:00:00 0
J8 CPU Time 00:00:00 0
S8 CPU Time 00:00:00 0
-------- ----- -------- ------
Total (L8 + J8 + S8) 00:00:00 0 00:00:00 0
-------- ----- -------- ------
Total CICS TCB Time 00:00:30 30 00:00:09 9
Total Performance Records (Type C) 0
Total Performance Records (Type D) 14
Total Performance Records (Type F) 0
Total Performance Records (Type S) 0
Total Performance Records (Type T) 676
-------- --------
Total Performance Records (Selected) 690 Total Performance Records 690
...... C O U N T S ...... ............. T I M E .........
From Selected Performance Records Total Avg/Task Max/Task Total Avg/Task Max/Task
Dispatch Time 20664 29.9 7681 31 .044 12.677
CPU Time 9 .013 3.168
RLS CPU (SRB) Time 0 .000 .000
Suspend Time 20650 29.9 7681 3685 5.341 1102.221
Dispatch Wait Time 19974 28.9 7680 4 .006 .920
Dispatch Wait Time (QR Mode) 18919 27.4 7680 2 .002 .660
Response (-TCWait for Type C) 0 .000 .000
Response (All Selected Tasks) 3716 5.385 1102.234
QR Dispatch Time 19595 28.4 7681 14 .021 6.796
MS Dispatch Time 1000 1.4 93 16 .024 5.881
RO Dispatch Time
QR CPU Time 8 .011 2.692
MS CPU Time 1 .002 .476
RO CPU Time
L8 CPU Time 0 .000 .001
J8 CPU Time 0 .000 .000
S8 CPU Time 0 .000 .000
...... C O U N T S ...... ........... T I M E ...........
From Selected Performance Records Total Avg/Task Max/Task Total Avg/Task Max/Task
FCWAIT File I/O wait time 671 1.0 283 4 .006 1.809
RLSWAIT RLS File I/O wait time 1 .0 1 0 .000 .069
TSWAIT VSAM TS I/O wait time 33 .0 2 0 .000 .017
TSSHWAIT Asynchronous Shared TS wait time 0 .0 0 0 .000 .000
JCWAIT Journal I/O wait time 473 .7 12 15 .022 1.755
TDWAIT VSAM transient data I/O wait time 0 .0 0 0 .000 .000
IRWAIT MRO link wait time 369 .5 28 98 .142 65.789
CFDTWAIT CF Data Table access requests wait time 0 .0 0 0 .000 .000
CFDTSYNC CF Data Table syncpoint wait time 0 .0 0 0 .000 .000
RUNTRWAI BTS run Process/Activity wait time 16 .0 2 1 .002 .448
SYNCDLY SYNCPOINT parent request wait time 32 .0 3 4 .006 .686
RMITIME Resource Manager Interface (RMI) elapsed time 30 .0 1 41 .060 2.178
RMISUSP Resource Manager Interface (RMI) suspend time 117 .2 6 41 .060 2.177
. . .
TCMSGIN1 Messages received count 424 .6 37
TCCHRIN1 Terminal characters received count 2968 4.3 274
TCMSGOU1 Messages sent count 426 .6 37
TCCHROU1 Terminal characters sent count 250456 363.0 29616
TCMSGIN2 Messages received from LU6.1 0 .0 0
TCCHRIN2 LU6.1 characters received count 8537 12.4 4329
TCMSGOU2 Messages sent to LU6.1 0 .0 0
TCCHROU2 LU6.1 characters sent count 0 .0 0
TCALLOC TCTTE ALLOCATE requests 7 .0 1
TCM62IN2 LU6.2 messages received count 0 .0 0
TCC62IN2 LU6.2 characters received count 0 .0 0
TCM62OU2 LU6.2 messages sent count 0 .0 0
TCC62OU2 LU6.2 characters sent count 0 .0 0
FCADD File ADD requests 92 .1 6
FCBROWSE File Browse requests 3416 5.0 2387
. . .
The Wait Analysis report provides a breakdown of wait activity by transaction ID (or other ordering fields). You can specify up to three sort fields to determine the sort order of the report and enable the data to be aggregated. You can see at a glance which CICS resources are causing your transactions to be suspended. This report can help you to quickly identify the possible source of a performance response time problem.
A Recap report is always produced and provides an overview of system-wide wait time. All CMF suspend components are reported in descending wait time order ensuring that the primary cause of system-wide task wait is at the top of the list.
The Recap report shows all wait clocks, even clocks that accumulated no wait time. This allows you to see at a glance:
Figure 21 shows part of the Wait Analysis report and Figure 22 shows the Wait Analysis Recap report.
CICS Performance Analyzer
Wait Analysis Report
WAIT0001 Printed at 16:02:13 8/06/2003 Data from 08:06:06 8/05/2003 to 08:13:33 8/05/2003
------------------------------------------------------------------------------------------------------------------------------------
Tran=CATA Start=08:00:00 Program=CATAPROG Interval=08:00:00
Summary Data -------- Time --------- ------ Count ------ ------ Ratio ------
Total Average Total Average
# Tasks 1
Response Time 0.0038 0.0038
Dispatch Time 0.0022 0.0022 3 3.0 59.5% of Response
CPU Time 0.0016 0.0016 3 3.0 70.0% of Dispatch
Suspend Wait Time 0.0015 0.0015 3 3.0 40.0% of Response
Dispatch Wait Time 0.0000 0.0000 2 2.0 1.1% of Suspend
Resource Manager Interface (RMI) elapsed time 0.0001 0.0001 4 4.0 2.1% of Response
Resource Manager Interface (RMI) suspend time 0.0000 0.0000 0 0.0 0.0% of Suspend
Suspend Detail ------------------- Suspend Time ------------------- ----- Count -----
Total Average %age Graph Total Average
N/A Other Wait Time 0.0014 0.0014 92.6% |****************** 2 2.0
DSPDELAY First dispatch wait time 0.0001 0.0001 7.4% |* 1 1.0
------------------------------------------------------------------------------------------------------------------------------------
Tran=XVOJ Start=08:00:00 Program=XVOJPROG Interval=08:00:00
Summary Data -------- Time --------- ------ Count ------ ------ Ratio ------
Total Average Total Average
# Tasks 261
Response Time 28.1101 0.1077
Dispatch Time 3.2940 0.0126 10578 40.5 11.7% of Response
CPU Time 2.4824 0.0095 10578 40.5 75.4% of Dispatch
Suspend Wait Time 24.8144 0.0951 10578 40.5 88.3% of Response
Dispatch Wait Time 2.9375 0.0113 10317 39.5 11.8% of Suspend
Resource Manager Interface (RMI) elapsed time 17.0496 0.0653 11365 43.5 60.7% of Response
Resource Manager Interface (RMI) suspend time 16.8430 0.0645 10255 39.3 67.9% of Suspend
Suspend Detail ------------------- Suspend Time ------------------- ----- Count -----
Total Average %age Graph Total Average
IMSWAIT IMS (DBCTL) wait time 13.6869 0.0524 55.2% |*********** 9781 37.5
DSPDELAY First dispatch wait time 4.8588 0.0186 19.6% |*** 261 1.0
TCLDELAY > First dispatch TCLSNAME wait time 4.7523 0.0182 19.2% |*** 56 0.2
IRIOWTT MRO link wait time 3.0935 0.0119 12.5% |** 59 0.2
DB2WAIT DB2 SQL/IFI wait time 3.0747 0.0118 12.4% |** 389 1.5
N/A Other Wait Time 0.0828 0.0003 0.3% | 86 0.3
LMDELAY Lock Manager (LM) wait time 0.0177 0.0001 0.1% | 2 0.0
CICS Performance Analyzer
Wait Analysis Recap Report
WAIT0001 Printed at 16:02:13 8/06/2003 Data from 08:06:06 8/05/2003 to 08:13:33 8/05/2003
--------- Time -------- ------ Ratio ------
Total Average
# Tasks 11768
Response Time 2156.6275 0.1833
Dispatch Time 136.3500 0.0116 6.3% of Response
CPU Time 76.7092 0.0065 56.3% of Dispatch
Suspend Wait Time 2020.1995 0.1717 93.7% of Response
Dispatch Wait Time 52.9988 0.0045 2.6% of Suspend
Resource Manager Interface (RMI) elapsed time 847.5371 0.0720 39.3% of Response
Resource Manager Interface (RMI) suspend time 842.6671 0.0716 41.7% of Suspend
------------------- Suspend Time ------------------- Field Availability
Total Average %age Graph Present Missing
IRIOWTT MRO link wait time 835.9785 0.0710 41.4% |******** 11768 0
IMSWAIT IMS (DBCTL) wait time 477.9522 0.0406 23.7% |**** 11768 0
WTEXWAIT External ECB wait time 292.1129 0.0248 14.5% |** 11768 0
ICDELAY Interval Control (IC) wait time 275.9447 0.0234 13.7% |** 11768 0
DB2WAIT DB2 SQL/IFI wait time 70.8436 0.0060 3.5% | 11768 0
DSPDELAY First dispatch wait time 52.3120 0.0044 2.6% | 11768 0
TCLDELAY > First dispatch TCLSNAME wait time 46.5026 0.0040 2.3% | 11768 0
MXTDELAY > First dispatch MXT wait time 0.0000 N/C 0.0% | 11768 0
FCIOWTT File I/O wait time 8.1584 0.0007 0.4% | 11768 0
N/A Other Wait Time 3.0880 0.0003 0.2% |
LU62WTT LU6.2 wait time 2.7382 0.0002 0.1% | 11768 0
WTCEWAIT CICS ECB wait time 0.5165 0.0000 0.0% | 11768 0
LMDELAY Lock Manager (LM) wait time 0.4619 0.0000 0.0% | 11768 0
TDIOWTT VSAM transient data I/O wait time 0.0530 0.0000 0.0% | 11768 0
GVUPWAIT Give up control wait time 0.0396 0.0000 0.0% | 11768 0
TCIOWTT Terminal wait for input time 0.0001 0.0000 0.0% | 11768 0
RQRWAIT Request Receiver wait Time 0.0000 0.0000 0.0% | 0 11768
TSIOWTT VSAM TS I/O wait time 0.0000 N/C 0.0% | 11768 0
ENQDELAY Local Enqueue wait time 0.0000 N/C 0.0% | 11768 0
DB2CONWT DB2 Connection wait time 0.0000 N/C 0.0% | 11768 0
DB2RDYQW DB2 Thread wait time 0.0000 N/C 0.0% | 11768 0
*Total* (All Suspend wait events) 2020.1995 0.1717 100.0% |********************
The Cross-System Work report accepts CMF performance class records from a single CICS system or multiple CICS systems and correlates the data by network unit-of-work (UOW) ID. Each line on the report is a single CMF record. Records that are part of the same network UOW appear together with a blank line between. Each print line has sufficient information to find the corresponding record(s) in the Performance List report.
Figure 23 shows a sample Cross-System Work report. The Request Types are:
CICS Performance Analyzer
Cross-System Work
CROS0001 Printed at 7:08:18 2/25/2003 Data from 11:10:51 1/24/2003 to 11:34:13 1/24/2003
Request Fcty Conn UOW R Response A
Tran Userid SC TranType Term LUName Type Program T/Name Name NETName Seq APPLID Task T Stop Time Time B
STOC ROBERT U U R AP: UKO0STOC UKHEADQU.UKOS23A 1 CICSP1 242 T 11:19:41.001 .7984
RED1 ROBERT U U R AP: UKO0RED1 UKHEADQU.UKOS23A 1 CICSP1 241 T 11:19:40.337 .1479
SAL1 ROBERT TP U T12A LU0123C AP: UKO0SAL1 T/S23C UKHEADQU.UKOS23A 1 CICSP1 239 T 11:19:40.334 .1835
RUPD JAMES TO U L23A LU0123C TR:JTC1 T/L23A UKHEADQU.UKOS23A 1 CICSP3 364 T 11:22:36.066 .002 9
AUPD CHRIS TO U R11 LYK7Z1V1 AP: UKOUAALL S/L23A CJB3 UKHEADQU.UKOS23A 1 CICSP1 192 T 11:22:36.066 .0013
CRD2 DAVE TO U 0006 TCP00006 AP: CORD02P T/0006 P390.TCP00006 1 CICS53T1 53 T 11:22:55.091 1.4707
CSMI CICSUSR TO UM #AAK CICS53T1 FS:F--- DFHMIRS T/#AAK 53T1 P390.TCP00006 1 CICS53A1 43 T 11:22:55.07
The Exception List report provides a detailed list of CMF exception class records, showing two types of information:
Figure 24 shows a sample Exception List report.
CICS Performance Analyzer
Exception List
XLST0001 Printed at 8:26:51 2/16/2003 Data from 08:08:37 2/16/2003
Tran Service Report Task Exp ..... Time ..... Current Resource Except
Tran Term LUName Userid SC Class Class Class No Seq Start Elapsed Program Type Resource ID Type
ABRW P045 IG2ZP045 CHRIS TP ABSERVC1 ABREPTC1 834 1 08:08:37 10.189 DFHSABRW FILE FILEA STRING
ABRW S205 IGCS205 BRUCE TP ABSERVC1 ABREPTS1 835 1 08:08:47 7.245 DFHSABRW FILE FILEA STRING
ABRW S220 IGCS220 SHIRLEY TP ABSERVC1 ABREPTS1 837 1 08:08:52 2.996 DFHSABRW FILE FILEA STRING
CECI S220 IGCS220 SHIRLEY TO CISERVC2 CIREPTS2 1151 1 08:12:10 .005 DFHECID TEMPSTOR CACA BUFFER
CECI S220 IGCS220 SHIRLEY TO CISERVC2 CIREPTS2 1151 2 08:12:10 .002 DFHECID TEMPSTOR CACA BUFFER
CECI P045 IG2ZP045 MIKE TO CISERVC2 CIREPTS2 1149 1 08:12:10 .004 DFHECID TEMPSTOR LONGTSNAME BUFFER
CECI P045 IG2ZP045 MIKE TO CISERVC2 CIREPTS2 1149 2 08:12:10 .004 DFHECID TEMPSTOR LONGTSNAME BUFFER
CP00 0001 TCP00001 CICSUSER TO 1238 1 14:53:19 4.103 CPAT00 TEMPSTOR CPATSQ WAIT
CP00 0001 TCP00001 CICSUSER TO 1247 1 14:55:15 24.509 CPAT00 TEMPSTOR CPATSQ WAIT
The Exception Summary report summarizes the CMF exception class records by transaction ID.
Figure 25 shows a sample Exception Summary report. It gives the average and total number of exceptions for each transaction ID according to the following exception conditions:
CICS Performance Analyzer
Exception Summary
XSUM0001 Printed at 8:26:51 2/17/2003 Data from 08:08:37 2/16/2003 to 08:12:36 2/16/2003
Tran Total TS-Buffer-Wait TS-String-Wait Pool-Buffr-Wait Pool-Strng-Wait File-Strng-Wait ..Temp Storage. ..Main Storage.
ID Excepts Average Count Average Count Average Count Average Count Average Count Average Count Average Count
ABRW 3 6.810 3
CEBR 16 .003 16
CECI 257 .006 256 .003 1
------- ------- ------- ------- ------- ------- ------- ------- ------- ------- ------- ------- ------- ------- -------
TOTAL 276 .006 256 .003 17 6.810 3
The Transaction Resource Usage reports are produced from CMF performance class and transaction resource class data. Currently, File and Temporary Storage usage are the only types of transaction resource data available.
There are three reports in this category:
The Transaction File Usage Summary report like that in Figure 26 provides a summary of File usage by transaction ID. For each transaction ID, it gives Transaction Identification and File Control statistics followed by a breakdown of file usage for each file used by the transaction.
CICS Performance Analyzer
Transaction File Usage Summary
FILE0001 Printed at 11:00:52 7/26/2003 Data from 07:30:47 5/29/2003 to 08:35:48 5/29/2003 APPLID CICSPA1
********************** FC Calls ********************* ******** I/O Waits ******* AccMeth
Tran #Tasks Get Put Browse Add Delete Total File RLS CFDT Requests
---- ------- -------- -------- -------- -------- -------- -------- -------- -------- -------- --------
STOK 9 Elapse Avg .2452 .0000 .0000
Max 1.5718 .0000 .0000
Count Avg 48 0 506 2 1 568 65 0 0 595
Max 369 7 4354 9 4 4739 426 0 0 4925
********************** FC Calls ********************* ******** I/O Waits ******* AccMeth
File #Tasks Get Put Browse Add Delete Total File RLS CFDT Requests
---------------- ------- -------- -------- -------- -------- -------- -------- -------- -------- -------- --------
STOCKF1 9 Elapse Avg .1907 .0045 .0170 .0154 .0094 .2544 .2452 .0000 .0000
Max 1.4601 .0110 .1195 .0458 .0358 1.6370 1.5718 .0000 .0000
Count Avg 48 0 506 2 1 568 65 0 0 595
Max 369 2 4354 8 4 4739 426 0 0 4925
STOCKF2 9 Elapse Avg .0261 .0054 .0036 .0113 .0068 .0712 .0690 .0000 .0000
Max .0352 .0065 .0042 .0176 .0098 .1029 .0837 .0000 .0000
Count Avg 0 0 12 0 0 13 1 0 0 34
Max 0 0 15 0 0 17 2 0 0 765
The Transaction Temporary Storage Usage Summary report like that in Figure 27 summarizes transactions that use temporary storage queues. The report consists of Transaction Identification and Temporary Storage statistics from the CMF performance class records. In addition, there is one sub-section for each TSQueue that the transaction has used from the CMF transaction resource class records.
CICS Performance Analyzer
Transaction Temporary Storage Usage Summary
TEMP0001 Printed at 11:00:52 7/26/2003 Data from 07:30:47 5/29/2003 to 08:35:48 5/29/2003 APPLID CICSPA1
************* TS Calls ************* *** I/O Waits ***
Tran #Tasks Get Put_Aux Put_Main Total TS Shr_TS
---- ------- --------- -------- -------- -------- -------- --------
CECI 3 Elapse Avg .0000 .0139
Max .0000 .0139
Count Avg 2 0 6 8 0 10
Max 3 0 12 12 0 17
************** TS Calls *********** *** I/O Waits *** ******** TS Item *********
TSQueue #Tasks Get Put_Aux Put_Main Total TS Shr_TS Get Put_Aux Put_Main
---------------- ------- -------- -------- -------- -------- -------- -------- -------- -------- --------
TS_Queue1 2 Elapse Avg .0104 .0000 .0002 .0106 .0000 .0139
Max .0104 .0000 .0002 .0104 .0000 .0139
Count Avg 2 0 6 8 0 10 56 44 378
Max 3 0 12 12 0 17 Length 112 88 756
TS_Queue2 1 Elapse Avg .0104 .0000 .0002 .0000 .0000 .0139
Max .0104 .0000 .0002 .0000 .0000 .0139
Count Avg 2 0 6 8 0 104 56 44 378
Max 2 0 6 8 0 104 Length 112 88 756
Total 2 Elapse Avg .0104 .0000 .0002 .0000 .0000 .0139
Max .0104 .0000 .0002 .0104 .0000 .0139
Count Avg 2 0 6 8 0 10 56 44 378
Max 3 0 12 12 0 17 Length 112 88 756
************* TS Calls ************* *** I/O Waits ***
Tran #Tasks Get Put_Aux Put_Main Total TS Shr_TS
---- ------- --------- -------- -------- -------- -------- --------
CEDA 9 Elapse Avg .0000 .0139
Max .0000 .0139
Count Avg 48 0 506 2 1 568
Max 369 2 4354 8 4 4739
************** TS Calls *********** *** I/O Waits *** ******** TS Item *********
TSQueue #Tasks Get Put_Aux Put_Main Total TS Shr_TS Get Put_Aux Put_Main
---------------- ------- -------- -------- -------- -------- -------- -------- -------- -------- --------
TS_Queue3 9 Elapse Avg .0104 .0000 .0002 .0106 .0000 .0139
Max .0104 .0000 .0002 .0104 .0000 .0139
Count Avg 2 0 6 8 0 10 56 44 378
Max 3 0 12 12 0 17 Length 112 88 756
The DB2 Report processes CICS CMF records and DB2 accounting records to produce a consolidated and detailed view of DB2 usage by your CICS systems. It enables you to view CICS and DB2 resource usage statistics together in a single report.
The DB2 List report in Figure 28 shows detailed information of DB2 activity for each transaction. The DB2 Summary report summarizes the DB2 activity by transaction and program within APPLID.
The DB2 Report matches CMF Performance records with DB2 accounting records by Network unit-of-work id. Your CICS-DB2 resources must be defined with ACCOUNTREC(TASK) or ACCOUNTREC(UOW) for matching to occur. See the CICS DB2 Guide for more information on accounting for DB2 resources and the setup required.
CICS Performance Analyzer
DB2 - List
DB2R0001 Printed at 14:22:11 2/05/2003 Data from 15:41:19 1/12/2003 to 16:19:15 1/12/2003
Tran/ Userid/ Program/ UOW R ..DB2 Wait Time.. DB2 User CPU Response A
SSID Authid Planname APPLID Task Seq T Term LUName Connect Thread ReqCnt Time Start Time Stop Time Time B
CRD8 CICSUSER CORD08P CICPAOR1 53 2 T <AAK CICPTOR1 .0000 .0000 22 .0185 15:49:40.023 15:49:40.105 .0827
CRD5 CICSUSER CORD05P CICPAOR1 52 2 T <AAK CICPTOR1 .0000 .0000 12 .0137 15:49:39.960 15:49:40.016 .0566
CRDD CICSUSER CORD13P CICPTOR1 45 1 T 0013 TCP00013 N/A N/A 0 .0390 15:49:39.521 15:49:40.121 .6006
DB2P CICSUSER CPAPLAN CICPAOR1 52 Thread Identification ID=POOLCRD50001 NETName=P390.TCP00013 UOWID=1F7D3A6472BA
Begin Time: 15:49:39.969 1/12/03 End Time: 15:49:40.007 1/12/03
Class1: Thread Time Elapsed= .0379 CPU= .019536
Class2: In-DB2 Time Elapsed= .0184 CPU= .014040
Class3: Suspend Time Total = N/P I/O= N/P Lock/Latch= N/P Other= N/P
Buffer Manager Summary GtPgRq= 2 SyPgUp= 0
Locking Summary Suspnd= 0 DeadLk= 0 TmeOut= 0 MxPgLk= 1
SQL DML Query/Update Sel= 0 Ins= 0 Upd= 0 Del= 0
SQL DML 'Other' Des= 0 Pre= 0 Ope= 1 Fet= 10 Clo= 1
DB2P CICSUSER CPAPLAN CICPAOR1 53 Thread Identification ID=POOLCRD50001 NETName=P390.TCP00013 UOWID=1F7D3A6472BA
Begin Time: 15:49:40.032 1/12/03 End Time: 15:49:40.097 1/12/03
Class1: Thread Time Elapsed= .0654 CPU= .031185
Class2: In-DB2 Time Elapsed= .0231 CPU= .021452
Class3: Suspend Time Total = N/P I/O= N/P Lock/Latch= N/P Other= N/P
Buffer Manager Summary GtPgRq= 2 SyPgUp= 0
Locking Summary Suspnd= 0 DeadLk= 0 TmeOut= 0 MxPgLk= 1
SQL DML Query/Update Sel= 0 Ins= 0 Upd= 0 Del= 0
SQL DML 'Other' Des= 0 Pre= 0 Ope= 1 Fet= 20 Clo= 1
CICS Performance Analyzer
DB2 - Summary
DB2R0001 Printed at 14:22:11 2/05/2003 Data from 15:41:19 1/12/2003 to 16:19:15 1/12/2003 APPLID CICPAOR1
Avg Max Avg Max Avg Max Avg Max Avg Max
Tran/ Program/ #Tasks/ DB2ConWt DB2ConWt DB2ThdWt DB2ThdWt DB2Rqst DB2Rqst UserCPU UserCPU Response Response #Abends
SSID Planname #Threads Time Time Time Time Count Count Time Time Time Time
CRD5 CORD05P 6 .0000 .0000 .0000 .0000 16.0 24 .016544 .021648 .0721 .0942 0
DB2P CPAPLAN 6 Thread Utilization Entry= 0 Pool= 6 Command= 0
Class1: Thread Time Avg: Elapsed= .0534 CPU= .024245
Max: Elapsed= .0733 CPU= .033569
Class2: In-DB2 Time Avg: Elapsed= .0189 CPU= .016890
Max: Elapsed= .0236 CPU= .022496
Class3: Suspend Time Avg: Total = N/P I/O= N/P Lock/Latch= N/P Other= N/P
Max: Total = N/P I/O= N/P Lock/Latch= N/P Other= N/P
Buffer Manager Summary Avg: GtPgRq= 2.0 SyPgUp= .0
Max: GtPgRq= 2 SyPgUp= 0
Locking Summary Avg: Suspnd= .0 DeadLk= .0 TmeOut= .0 MxPgLk= 1.0
Max: Suspnd= 0 DeadLk= 0 TmeOut= 0 MxPgLk= 1
SQL DML Query/Update Avg: Sel= .0 Ins= .0 Upd= .0 Del= .0
Max: Sel= 0 Ins= 0 Upd= 0 Del= 0
SQL DML 'Other' Avg: Des= .0 Pre= .0 Ope= 1.3 Fet= 13.3 Clo= 1.3
Max: Des= 0 Pre= 0 Ope= 2 Fet= 20 Clo= 2
The WebSphere MQ report processes WebSphere MQ SMF accounting (SMF 116) records to produce a detailed view of WebSphere MQ usage by your CICS systems.
The WebSphere MQ List reports display, depending on the WebSphere MQ accounting traces that are active, details about transactions, WebSphere MQ queues that were referenced, WebSphere MQ global (not transaction-specific or queue-specific) statistics and WebSphere queue-specific commands issued by transactions. These can be sorted and aggregated by any one of the following:
WebSphere MQ accounting records are produced when the Accounting Trace component of WebSphere MQ is activated. If the MQ accounting trace is active, CLASS(1) subtype 0 records are always produced, but subtypes 1 and 2 are only produced if CLASS(3) is specified when the trace is activated. You can request reports for either Class 1 or Class 3 data.
The WebSphere MQ Class 1 List report like that in Figure 29 provides a detailed list of MQ accounting class 1 records.
CICS Performance Analyzer
WebSphere MQ Class 1 List
MQ000001 Printed at 14:42:16 8/13/2003 Data from 14:50:34 07/13/2003
SSID APPLID Tran Time Task CPU -------------- GET Counts ------------- ------------- PUTx Counts -------------
<=99 <=999 <=9999 >=10000 <=99 <=999 <=9999 >=10000
MQMD CICS53A1 CKCN 14:50:34.88 35 0.000747 0 0 0 0 0 0 0 0
MQMD CICS53A1 MQA1 14:51:13.27 41 0.064342 0 0 0 0 60 0 0 0
MQMD CICS53A1 CKTI 14:51:24.52 37 0.001541 0 0 0 0 0 0 0 0
The WebSphere MQ Class 1 Summary report like that in Figure 30 provides a summary of MQ accounting class 1 records.
CICS Performance Analyzer
WebSphere MQ Class 1 Summary
MQ000003 Printed at 14:42:16 8/13/2003 Data from 14:50:34 07/13/2003 to 14:51:24 07/13/2003
----- Average ----- --------- Average GET Counts --------- -------- Average PUTx Counts ---------
SSID APPLID TRAN Count CPU Calls <=99 <=999 <=9999 >=10000 <=99 <=999 <=9999 >=10000
MQMD CICS53A1 CKCN 1 0.000747 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0
MQMD CICS53A1 CKTI 1 0.001541 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0 0.0
MQMD CICS53A1 MQA1 1 0.064342 60.0 0.0 0.0 0.0 0.0 60.0 0.0 0.0 0.0
The WebSphere MQ Class 3 List report like that in Figure 31 provides a detailed list of MQ accounting class 3 records.
CICS Performance Analyzer
WebSphere MQ Class 3 List
MQ000002 Printed at 14:42:16 8/13/2003 Data from 14:51:13 07/13/2003
SSID: MQMD APPLID: CICS53A1 Tran: MQA1 Task: 41 UserID: CICSUSER NetName: N/A UOWID: N/A
Channel: Channel Connection: Start: 07/13/2003 14:51:13.12
Other Total Calls 1 Avg Elapsed 0.018721 Avg CPU 0.000258
#Old Pages 120 #New Pages 0
Queue: CPPX.MQS520.TEST.TEMPQUEUE.060
QType: LOCAL IType: NONE GDisp: Q_MGR Date: 07/13/2003 Time: 14:51:13 P/Set No: 4 BufferPool No: 3
First Opened: 07/13/2003 14:51:13.25 Last Closed: 07/13/2003 14:51:13.25 CF Structure Name:
Count Elapsed CPU Susp Elp JnlWrt Elp PS Req's PS Rd Elp Expired Page Skip Msgs Skip
_________ _________ _________ _________ __________ _________ _________ _________ _________ _________
OPEN 1 0.000332 0.000327
CLOSE 1 0.000113 0.000112
PUT 1 0.000567 0.000560 0.000000 0.000000 0.0 0.000000
PUT Total Bytes 10 #PUT w/Data 1 Min Msg Size 10 Max Msg Siz 10
Queue: CPPX.MQS520.TEST.TEMPQUEUE.059
QType: LOCAL IType: NONE GDisp: Q_MGR Date: 07/13/2003 Time: 14:51:13 P/Set No: 4 BufferPool No: 3
First Opened: 07/13/2003 14:51:13.25 Last Closed: 07/13/2003 14:51:13.25 CF Structure Name:
Count Elapsed CPU Susp Elp JnlWrt Elp PS Req's PS Rd Elp Expired Page Skip Msgs Skip
_________ _________ _________ _________ __________ _________ _________ _________ _________ _________
OPEN 1 0.000271 0.000267
CLOSE 1 0.000113 0.000112
PUT 1 0.000507 0.000500 0.000000 0.000000 0.0 0.000000
PUT Total Bytes 10 #PUT w/Data 1 Min Msg Size 10 Max Msg Siz 10
The WebSphere MQ Class 3 Summary report provides a summary of MQ accounting class 3 records. Figure 32 shows an example of the report sorted by transaction ID, then queue name.
CICS Performance Analyzer
WebSphere MQ Class 3 Summary (By TRAN,QUEUE)
MQ000006 Printed at 14:42:16 8/13/2003 Data from 14:50:34 07/13/2003 to 14:51:24 07/13/2003
SSID: MQMD APPLID: CICS53A1 Tran: CKTI Threads: 1
Other Avg Count 1.0 Avg Elapsed 0.000895 Avg CPU 0.000370
SSID: MQMD APPLID: CICS53A1 Tran: MQA1 Threads: 1
Other Avg Count 1.0 Avg Elapsed 0.018721 Avg CPU 0.000258
Avg #Old Pages 120.0 Avg #New Pages 0.0
Queue: CPPX.MQS520.TEST.TEMPQUEUE.001
QType: LOCAL IType: NONE GDisp: Q_MGR QCount: 1
Count Elapsed CPU Susp Elp JnlWrt Elp PS Req's PS Rd Elp Expired Page Skip Msgs Skip
_________ _________ _________ _________ __________ _________ _________ _________ _________ _________
OPEN 1.0 0.000480 0.000472
CLOSE 1.0 0.000122 0.000121
PUT 1.0 0.000657 0.000562 0.000000 0.000000 0.0 0.000000 0.0 0.0 0.0
PUT Avg Bytes 10.0 Avg #PUT w/Data 1.0 Min Msg Size 10 Max Msg Size 10
The System Logger Report processes System Logger records to provide information on the System Logger logstreams and coupling facility structures that are used by CICS Transaction Server for logging, recovery and backout operations. The report can assist with measuring the effects of tuning changes and identifying logstream or structure performance problems.
The System Logger List report shows information on logstream writes, deletes, and events, as well as Structure Alter events for each SMF recording interval. The System Logger Summary report summarizes logstream and structure statistics so you can measure Logger performance over a longer period of time. Sample System Logger reports are shown in Figure 33.
These reports, when used in conjunction with the CICS Logger reports produced from the standard CICS statistics reporting utilities, provide a comprehensive analysis of the logstream activity for all your CICS systems.
CICS Performance Analyzer
System Logger Report - List
LOGR0001 Printed at 9:30:09 2/11/2003 Data from 7:00:40:14 1/20/2003 to 9:59:40:16 1/20/2003
Logstream name Structure name Flag Interval expired at MVSID Level
IYOT1.DFHLOG LOG_JG Staging 09:00:00:00 1/20/2003 MV55 SP6.0.8
----------- IXGWRITES ---------- ---------------- DELETIONS -----------------
Bytes Bytes
Average Bytes With Without After Int Stor
Total Buffer Writn to DASD DASD Offload w/o DASD
Count Bytes Size Int Stor Write Write w. DASD Write
-------- -------- -------- -------- -------- -------- -------- --------
11248 4348827 386 6768128 0 9327 0 3348643
--------------------------------- EVENTS ---------------------------------------------------------------
Demand Demand Minimum Maximum
Staging DASD Staging Entry Struct Init'd Block Block
Offloads Threshld Shifts Full Full Full Offloads Length Length
-------- -------- -------- -------- -------- -------- -------- -------- --------
3 0 0 0 0 0 0 116 1422
---------------------- EVENTS -------------------------- -------------- DASD Writes -----------------
Struct Struct
Rebuilds Rebuilds Total
Type1 Type2 Type3 Init'd Complt'd Count Bytes Average Waits
-------- -------- -------- -------- -------- -------- -------- -------- --------
11216 32 0 0 0 0 0 0 0
Logstream name Structure name MVSID Level
*ALTER* LOG_JG MV55 SP6.0.8
------------ STRUCTURE ALTER ----------------
SMF record timestamp 9:36:38:05 1/20/2003
Current Offloads Current Targeted Struct Log Log
Bytes Avg Avg Size Data Streams
Written Bufsz Bufsz (Blocks) Writes Connectd
-------- -------- -------- -------- -------- -------- --------
0 2 768 768 5056 0 0
CICS Performance Analyzer
System Logger Report - Logstream Summary
LOGR0001 Printed at 9:30:09 2/11/2003 Data from 7:00:40:14 1/20/2003 to 9:59:40:16 1/20/2003
Logstream name Structure name Start of Interval End of Interval Interval MVSID
IYOT1.IYO1.DFHJ03 *DASDONLY* 06:45:00:00 1/20/2003 09:00:00:00 1/20/2003 02:15:00 MV55
----------- IXGWRITES ---------- ---------------- DELETIONS -----------------
No. No. Bytes Bytes
Bytes With Without After Int Stor
Total Avg Writn to DASD DASD Offload w/o DASD
No. Bytes Bytes Int Stor Write Write w. DASD Write
-------- -------- -------- -------- -------- -------- -------- --------
Total 45 2506582 55702 2543616 20 0 1130496 0
Rate(/Sec) 0 309 314 0 0 140 0
Minimum 45 2506582 2543616 20 0 1130496 0
Maximum 45 2506582 2543616 20 0 1130496 0
---------------------------------- EVENTS ---------------------------------------------------
Demand Demand
Staging DASD Block Staging Entry Struct Init'd
Offloads Threshld Shifts Length Full Full Full Offloads
-------- -------- -------- -------- -------- -------- -------- --------
Total 2 6 6 0 0 0 0
Rate(/Sec) 0 0 0 0 0 0 0
Minimum 2 0 6 16998 0 0 0 0
Maximum 2 0 6 65372 0 0 0 0
---------------------- EVENTS -------------------------- -------------- DASD Writes -----------------
Struct Struct
Rebuilds Rebuilds Total
Type1 Type2 Type3 Init'd Complt'd No. Bytes Avg Waits
-------- -------- -------- -------- -------- -------- -------- -------- --------
Total 0 0 0 0 0 8 1114992 0 0
Rate(/Sec) 0 0 0 0 0 0 138 0
Minimum 0 0 0 0 0 8 1114992 0
Maximum 0 0 0 0 0 8 1114992 0
Historical Database (HDB) is a facility that allows you to manage performance data for your CICS transactions. HDBs save performance data in data sets that are managed from the dialog. The type of information and level of detail contained in an HDB is determined by user-defined templates.
There are two types of HDB:
You can run reports against your HDB or export the HDB data to DB2 tables.
Summary tables contain data exported from a Summary HDB. Summary tables are the most commonly used for performance reporting.
Here is an example of a simple SQL query that lists selected fields in a Summary table:
SELECT TRAN,
INT(TASKCNT) AS TASKCNT,
DEC(RESPONSE_TIME,8,2) AS RESPONSE_TIME,
DEC(CPU_TIME,8,2) AS CPU_TIME,
DEC(SUSPEND_TIME,8,2) AS SUSPEND_TIME,
DEC(DISPATCH_TIME,8,2) AS DISPATCH_TIME
FROM CICSPA.CICSP1H
This query produces output like the following:
RESPONSE CPU SUSPEND DISPATCH
TRAN TASKCNT TIME TIME TIME TIME
---- ----------- ----------- ----------- ----------- -----------
CSOL 1 1887.43 16.00 9.00 16.00
CSMT 1 1887.22 16.00 9.00 16.00
FICX 1 0.00 1.00 1.00 1.00
SU4B 1 0.07 625.00 625.00 625.00
CWBG 1 0.00 1.00 1.00 1.00
BIC2 1 0.00 1.00 1.00 1.00
BIC2 1 0.00 1.00 1.00 1.00
AP77 1 1.17 3969.00 3969.00 3969.00
CAMA 1 0.01 25.00 25.00 25.00
CKPT 4 0.56 2313.00 2313.00 2313.00
CM99 1 0.01 1.00 1.00 1.00
CNA7 9 0.47 180.00 180.00 180.00
CNB0 3 0.17 891.00 891.00 891.00
For more information about CICS Performance Analyzer for z/OS®, see the CICS Performance Analyzer for z/OS Report Reference (SC34-6308).