Have you compared reports from SMF with what you see in the JOBLOG? Have you verified with SAS+MXG or SAS+MICS or some other SMF process that can generate reports. If you have a scheduling software (like CA Workload Automation aka ESP) it can also show your run times. Compare those against the JOBLOG
Lizette > >We upgraded from z/os v1.11 to v1.13 on our z10 about 2 weeks ago. Ops >reported that all batch jobs are taking longer 'wall' time. Researching the >cause, we Looked at job step termination stats. We discovered that, on z/os >v1.13, all steps are reporting about 50 times the number of service units as >were reported on z/os v1.11. While TCB & SRB cpu times seem equivalent (no >dramatic increase on v1.13), the elapsed time has increased roughly by a >factor of 2 and the "SERV" value is dramatically higher for each step. A job >that took 30 mins now takes 75 mins. We first thought it may be a problem >unique to SORT (SyncSort) but even the Control-R step (as well as non-sort >steps in other jobs) is 30x higher. > >We have opened a PMR with IBM, but I thought I would see if anyone else has >observed anything like this when migrating from z/os v1.11 to v1.13, or may >have some idea where to look for an explanation. Our service coefficients >have not changed. Any comments would be welcome. > >From WLM (same on v1.11 and v1.13): >***** begin WLM screen paste ***** >Enter or change the Service Coefficients: > >CPU . . . . . . . . . . . . . 1.0 (0.0-99.9) >IOC . . . . . . . . . . . . . 0.5 (0.0-99.9) >MSO . . . . . . . . . . . . . 0.0001 (0.0000-99.9999) >SRB . . . . . . . . . . . . . 1.0 (0.0-99.9) > >Enter or change the service definition options: > >I/O priority management . . . . . . . . YES (Yes or No) >Dynamic alias tuning management . . . . YES (Yes or No) >***** end of WLM screen paste ******** > >Job run on z/os v1.13: >01.37.16 JOB05818 ---- THURSDAY, 26 JUL 2012 ---- > >01.37.16 JOB05818 ICH70001I CTMPUSR LAST ACCESS AT 01:37:14 ON THURSDAY, >JULY 26, 2012 >01.37.16 JOB05818 $HASP373 PR#2500 STARTED - INIT 4 - CLASS T - SYS SYSP > >01.37.16 JOB05818 IEF403I PR#2500 - STARTED - TIME=01.37.16 > >01.37.18 JOB05818 - -----TIMINGS >(MINS.)------ -----PAGING COUNTS---- >01.37.18 JOB05818 -STEPNAME PROCSTEP RC EXCP CONN TCB SRB >CLOCK SERV WORKLOAD PAGE SWAP VIO SWAPS >01.37.18 JOB05818 -NONCAT2 CONTROLR 00 597 617 .00 .00 > .0 3306 BATPRD 0 0 0 0 >01.37.19 JOB05818 -PR#2500 STEP00 00 37 32 .00 .00 > .0 82 BATPRD 0 0 0 0 >01.37.21 JOB05818 -PR#2500 SORT01 00 1436 501 .00 .00 > .0 24350 BATPRD 0 0 0 0 >01.37.24 JOB05818 -PR#2500 SORT02 00 2656 947 .00 .00 > .0 44752 BATPRD 0 0 0 0 >01.37.25 JOB05818 -PR#2500 SORT03 00 462 163 .00 .00 > .0 27606 BATPRD 0 0 0 0 >01.37.28 JOB05818 -PR#2500 SORT04 00 1400 512 .02 .00 > .0 130652 BATPRD 0 0 0 0 >01.38.03 JOB05818 -PR#2500 SORT05 00 17075 6394 .26 .01 > .5 7699795 BATPRD 0 0 0 0 >02.53.03 JOB05818 -PR#2500 STEP05 00 961K 313K .67 .09 > 74.9 2173651 BATPRD 0 0 0 0 >02.53.03 JOB05818 IEF404I PR#2500 - ENDED - TIME=02.53.03 > >02.53.03 JOB05818 -PR#2500 ENDED. NAME- TOTAL TCB CPU >TIME= .98 TOTAL ELAPSED TIME= 75.7 >*********** end of job step stats ************** >Same Job run on z/os v1.11: >01.45.41 JOB02442 ---- FRIDAY, 13 JUL 2012 ---- > >01.45.41 JOB02442 ICH70001I CTMPUSR LAST ACCESS AT 01:45:41 ON FRIDAY, JULY >13, 2012 >01.45.41 JOB02442 $HASP373 PR#2500 STARTED - INIT 31 - CLASS T - SYS SYSP > >01.45.41 JOB02442 IEF403I PR#2500 - STARTED - TIME=01.45.41 > >01.45.45 JOB02442 - -----TIMINGS >(MINS.)------ -----PAGING COUNTS---- >01.45.45 JOB02442 -STEPNAME PROCSTEP RC EXCP CONN TCB SRB >CLOCK SERV WORKLOAD PAGE SWAP VIO SWAPS >01.45.45 JOB02442 -NONCAT2 CONTROLR 00 599 470 .00 .00 > .0 127 BATPRD 0 0 0 0 >01.45.47 JOB02442 -PR#2500 STEP00 00 47 38 .00 .00 > .0 BATPRD 0 0 0 0 >01.45.55 JOB02442 -PR#2500 SORT01 00 1558 619 .00 .00 > .1 6845 BATPRD 0 0 0 0 >01.46.01 JOB02442 -PR#2500 SORT02 00 2885 1058 .00 .00 > .1 12080 BATPRD 0 0 0 0 >01.46.09 JOB02442 -PR#2500 SORT03 00 496 186 .00 .00 > .1 218 BATPRD 0 0 0 0 >01.46.29 JOB02442 -PR#2500 SORT04 00 1518 614 .02 .00 > .3 621 BATPRD 0 0 0 0 >01.50.18 JOB02442 -PR#2500 SORT05 00 18893 7856 .30 .00 > 3.8 8114 BATPRD 0 0 0 0 >02.18.29 JOB02442 -PR#2500 STEP05 00 1066K 370K .68 .08 > 28.1 150650 BATPRD 0 0 0 0 >02.18.29 JOB02442 IEF404I PR#2500 - ENDED - TIME=02.18.29 > >02.18.29 JOB02442 -PR#2500 ENDED. NAME- TOTAL TCB CPU >TIME= 1.03 TOTAL ELAPSED TIME= 32.7 > > >Sorry for the lack of formatting here. >TIA, >-Jim ---------------------------------------------------------------------- For IBM-MAIN subscribe / signoff / archive access instructions, send email to [email protected] with the message: INFO IBM-MAIN
