








	*SMF Tools*

CICS Elapsed Time and CPU Time Report
from SMF 110 Subype 1 Records

It's easy to report on SMF data!

We have a low-cost 4GL report writer especially for SMF files. It's called Spectrum SMF Writer.

Spectrum SMF Writer handles the difficult SMF record parsing for you automatically. You just specify which fields you want to see.

Spectrum SMF Writer also converts the arcane date and time fields and reformats them into an attractive report.

Plus Spectrum SMF Writer even exports SMF data as comma delimited files to use on your PC.

The SMF report below was created with Spectrum SMF Writer, the low-cost 4GL report writer for SMF files.

In this report we show timing statistics for each execution of a CICS transaction. To do this, we select just the SMF type 110 subtype 1 (CICS Monitoring) records. (Note: if your 110 records are "compressed," you should decompress them before passing them to Spectrum SMF Writer.) We "normalize" the performance segments in those records. That means, in essence, that Spectrum SMF Writer steps through each performance section (within a single record) processing each section as if it was a separate record. The normalization logic is handled automatically for you.

From these SMF records, we print the CICS transaction name and the CICS "program" name under which it executed. We print the transaction's start and stop time. (Notice that Spectrum SMF Writer takes the raw 8-byte STCK times and automatically reformats them for you as hour, minutes and seconds - right down to a millionth of a second.)

We also compute the elapsed time by subtracting the stop time from the start time. (It's actually a bit more complicated than that. We also took into account the start and stop dates, in case the transaction spans midnight.) We also print the value found in the SMF records for CPU time, dispatch time and suspend time. These values are not filled in for all records.

Finally we sort the report by transaction name, CICS name and date/time. We break and space 1 line between different transactions.

All of this useful CICS performance information with just a few lines of code!
Why not install a Spectrum SMF Writer trial right now and see your own CICS transaction times!

These Spectrum SMF Writer Statements:

INPUT:  SMF110S1
        NORMWHEN(SMF110RTY=110 & SMF110STY=1 & SMF110S1_MNCL=3
                 AND SMF110S1_MNRVN = X'0660') /* CICS TS 4.1 */
        NORMSMF(SMF110S1_MNDRA)

INCLUDEIF: SMF110RTY = 110 AND SMF110STY = 1 AND SMF110S1_MNCL=3

COLUMNS:
     SMF110DTE('SMF|LOG|DATE')
     SMF110TME('SMF|LOG|TIME' TP'99:99:99')
     SMF110S1_MNJBN('CICS|JOBNAME')
     SMF110S1_TRAN('TRAN')
     SMF110S1_START
     SMF110S1_STOP
     SMF110S1_RESPONSE_TIME(8, 'ELAPSED|TIME')
     SMF110S1_USRDISPT('DISPATCH|TIME')
     SMF110S1_USRCPUT('CPU|TIME')
     SMF110S1_SUSPTIME('SUSPEND|TIME')

TITLE: 'SMF 110 SUBTYPE 1 CICS MONITORING DATA'
TITLE: 'TRANSACTION TIMING INFORMATION'
TITLE: 'SORTED BY CICS TRANSACTION'

SORT: SMF110S1_TRAN(1 NOTOTALS) SMF110S1_MNJBN

Produce This SMF Report:

                                          SMF 110 SUBTYPE 1 CICS MONITORING DATA
                                              TRANSACTION TIMING INFORMATION
                                        SORTED BY CICS TRANSACTION AND CICS REGION

  SMF      SMF
  LOG      LOG           CICS      SMF110S1        SMF110S1      ELAPSED      DISPATCH           CPU           SUSPEND
  DATE     TIME   TRAN JOBNAME       START           STOP          TIME         TIME            TIME            TIME
________ ________ ____ ________ _______________ _______________ __________ _______________ _______________ _____________

12/30/09 00:34:27 CEMT CICSVIBK 23:19:26.698238 23:34:26.901300  15:00.203 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 00:22:54 CISE CICSVATM 23:22:54.054290 23:52:54.197499  30:00.143 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:50:53 CISE CICSVCTG 00:05:52.764595 00:35:52.645543  29:59.881 00:00:00.000000 00:00:00.000000 00:29:59.8809

12/29/09 23:53:29 CISM CICLUTIN 23:08:28.457983 23:23:28.657409  15:00.199 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/29/09 23:45:38 CISM CICSVWLK 22:45:37.704110 23:15:37.584835  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 01:20:53 CISR CICSVCTG 00:35:52.645252 00:50:52.851959  15:00.207 00:00:00.000000 00:00:00.000000 00:15:00.2067

12/30/09 00:21:06 CKTI CICSEAI  23:06:06.372819 23:36:06.254236  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/29/09 23:52:54 COIE CICSVATM 22:52:54.177421 23:22:54.054167  29:59.877 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 01:30:38 COIE CICSVWLK 00:45:37.488736 01:00:37.691469  15:00.202 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/29/09 23:52:52 COI0 CICSVTIN 23:20:32.758513 23:50:32.901407  30:00.142 00:00:00.000000 00:00:00.000000 00:30:00.1428
12/30/09 00:52:39 COI0 CICSVTIN 00:20:32.782153 00:50:32.667259  29:59.885 00:00:00.000000 00:00:00.000000 00:29:59.8851
12/30/09 00:30:16 COI0 CICSVWRS 23:59:08.538353 00:29:08.419178  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 01:15:17 COI0 CICSVWRS 00:59:08.299732 01:14:08.502507  15:00.203 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 00:52:54 CSKL CICSVATM 23:52:54.197580 00:22:54.077883  29:59.880 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 01:00:38 CSNC CICSVWLK 00:15:37.607961 00:45:37.488564  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 00:27:32 CSNE CICSVBKT 23:38:16.277222 00:08:16.158398  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 00:51:06 CSSY CICSEAI  00:06:06.134042 00:21:06.336672  15:00.203 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/29/09 23:57:38 CSSY CICSVCRD 23:25:46.020150 23:55:45.901009  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:27:39 CSSY CICSVCRD 23:55:45.901009 00:25:46.043884  30:00.143 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:23:00 CSSY CICSVTIN 23:50:32.901126 00:20:32.782049  29:59.881 00:00:00.000000 00:00:00.000000 00:29:59.8809
12/30/09 01:07:43 CSSY CICSVTIN 00:50:32.667043 01:05:32.867509  15:00.201 00:00:00.000000 00:00:00.000000 00:15:00.2004
12/30/09 00:00:07 CSSY CICSVWRS 23:29:08.395441 23:59:08.538121  30:00.143 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:45:37 CSSY CICSVWLK 23:45:37.726665 00:15:37.607789  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/30/09 01:23:29 CSTP CICLUTIN 00:23:28.680285 00:53:28.561157  29:59.881 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 01:00:45 CSTP CICSVCRD 00:25:46.043924 00:55:45.928358  29:59.884 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 01:30:45 CSTP CICSVCRD 00:55:45.928358 01:25:46.067593  30:00.140 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/29/09 23:46:30 NA10 CICSVWRS 23:45:09.415186 23:45:09.417277      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/29/09 23:57:55 NA10 CICSVWRS 23:56:42.786625 23:56:42.788684      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:09:00 NA10 CICSVWRS 00:07:46.010429 00:07:46.012820      0.003 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:11:33 NA10 CICSVWRS 00:10:16.743547 00:10:16.745772      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:14:06 NA10 CICSVWRS 00:12:47.475928 00:12:47.478273      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:16:40 NA10 CICSVWRS 00:15:18.208815 00:15:18.211266      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:28:05 NA10 CICSVWRS 00:26:51.579611 00:26:51.581779      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:36:36 NA10 CICSVWRS 00:35:24.072558 00:35:24.074685      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:39:09 NA10 CICSVWRS 00:37:54.803954 00:37:54.806177      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:41:43 NA10 CICSVWRS 00:40:25.537018 00:40:25.539253      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000
12/30/09 00:44:16 NA10 CICSVWRS 00:42:56.269533 00:42:56.271769      0.002 00:00:00.000000 00:00:00.000000 00:00:00.0000

12/29/09 23:45:50 NA20 CICSVTIN 23:38:47.471498 23:38:47.476270      0.005 00:00:00.001843 00:00:00.001778 00:00:00.0029
12/29/09 23:50:33 NA20 CICSVTIN 23:46:19.671154 23:46:19.675617      0.005 00:00:00.001718 00:00:00.001708 00:00:00.0027
12/30/09 00:00:24 NA20 CICSVTIN 23:53:21.721659 23:53:21.726867      0.005 00:00:00.001815 00:00:00.001812 00:00:00.0033
12/30/09 00:07:56 NA20 CICSVTIN 00:00:53.919979 00:00:53.924962      0.005 00:00:00.001821 00:00:00.001815 00:00:00.0031
12/30/09 00:15:28 NA20 CICSVTIN 00:08:26.118493 00:08:26.123417      0.005 00:00:00.001948 00:00:00.001831 00:00:00.0029
12/30/09 00:20:33 NA20 CICSVTIN 00:15:58.316783 00:15:58.324343      0.007 00:00:00.001817 00:00:00.001803 00:00:00.0057
12/30/09 00:30:33 NA20 CICSVTIN 00:23:30.519874 00:23:30.524807      0.005 00:00:00.001896 00:00:00.001815 00:00:00.0030
12/30/09 00:38:05 NA20 CICSVTIN 00:31:02.713904 00:31:02.718752      0.005 00:00:00.001805 00:00:00.001768 00:00:00.0030
12/30/09 00:45:37 NA20 CICSVTIN 00:38:34.912368 00:38:34.917524      0.006 00:00:00.001834 00:00:00.001804 00:00:00.0033
12/30/09 00:50:33 NA20 CICSVTIN 00:46:07.113059 00:46:07.117908      0.005 00:00:00.001818 00:00:00.001817 00:00:00.0030
12/30/09 01:00:11 NA20 CICSVTIN 00:53:09.164768 00:53:09.169786      0.005 00:00:00.001802 00:00:00.001798 00:00:00.0032
12/30/09 01:05:33 NA20 CICSVTIN 01:00:41.360650 01:00:41.366474      0.005 00:00:00.001834 00:00:00.001799 00:00:00.0039
12/30/09 01:15:16 NA20 CICSVTIN 01:08:13.559012 01:08:13.563552      0.005 00:00:00.001847 00:00:00.001838 00:00:00.0026
12/30/09 01:22:48 NA20 CICSVTIN 01:15:45.758016 01:15:45.762949      0.005 00:00:00.001797 00:00:00.001794 00:00:00.0031
12/30/09 01:30:20 NA20 CICSVTIN 01:23:17.955831 01:23:17.961375      0.005 00:00:00.001818 00:00:00.001804 00:00:00.0037
...

See Other Sample SMF Reports

CICS Elapsed Time and CPU Time Report from SMF 110 Subype 1 Records

It's easy to report on SMF data!

These Spectrum SMF Writer Statements:

Produce This SMF Report:

CICS Elapsed Time and CPU Time Report
from SMF 110 Subype 1 Records