mount not taking

classic Classic list List threaded Threaded
27 messages Options
12
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

mount not taking

Hercules390 - Mvs mailing list
I am doing thousands of automatic IPLs
and runs of IEFBR14.

Just now I hit a weird problem.

The mount command executed but
failed to produce a mount message.

Normally I see this:

20:09:55 10.09.55 STC   17  $HASP100 MOUNT    ON STCINRDR
20:09:55 10.09.55 STC   17  $HASP373 MOUNT    STARTED
20:09:55 10.09.55 STC   17  IEF403I MOUNT - STARTED - TIME=10.09.55
20:09:55 10.09.55 STC   17 *IEF233A M 401,PCTOMF,,MOUNT,401

but this time I saw:

20:10:31 10.10.30 STC   17  $HASP100 MOUNT    ON STCINRDR
20:10:31 10.10.30 STC   17  $HASP373 MOUNT    STARTED
20:10:31 10.10.30 STC   17  IEF403I MOUNT - STARTED - TIME=10.10.30
20:10:31 10.10.31 STC    9  IEF404I MVS380SS - ENDED - TIME=10.10.31
20:10:31 10.10.31 STC    9  $HASP395 MVS380SS ENDED
20:10:31 10.10.31 STC    9  $HASP150 MVS380SS ON PRINTER2        59 LINES
20:10:31 10.10.31           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:31 10.10.31 STC    9  $HASP250 MVS380SS IS PURGED
20:10:31 10.10.31 STC   17  IEF404I MOUNT - ENDED - TIME=10.10.31
20:10:31 10.10.31 STC   17  $HASP395 MOUNT    ENDED
20:10:31 10.10.31 STC   17  $HASP150 MOUNT    ON PRINTER2        18 LINES
20:10:31 10.10.31           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:31 10.10.31 STC   17  $HASP250 MOUNT    IS PURGED
21:14:02 /(001F) d t
21:14:02 11.14.02           IEE136I  TIME=11.14.02 DATE=17.093


Here is my MVS380SS proc:

//MVS380SS PROC P='ADD,SIZE=64',
// Q='V 401,ONLINE',
// R='M 401,VOL=(NL,PCTOMF)'
//S1 EXEC PGM=BSPOSCMD,PARM='&Q'
//S2 EXEC PGM=SVC120I,PARM='&P'
//S3 EXEC PGM=BSPOSCMD,PARM='&R'

It is started by this in JES2PARM:

$VS,'V 400-402,ONLINE'
$VS,'X SVC120I,PARM=''ADD,SIZE=64'''
$VS,'S MVS380SS'

Full log below.

System is still running if you have any
tests to suggest.

Thanks. Paul.




20:10:18 Hercules Version 3.07:380-4.x
20:10:18 (c)Copyright 1999-2010 by Roger Bowler, Jan Jaeger, and others
20:10:18 Built on Dec  7 2016 at 12:43:32
20:10:18 Build information:
20:10:18   Windows (MSVC) build for AMD64
20:10:18   Modes: S/370 S/380 ESA/390 z/Arch
20:10:18   Max CPU Engines: 8
20:10:18   Using fthreads instead of pthreads
20:10:18   Dynamic loading support
20:10:18   Using shared libraries
20:10:18   HTTP Server support
20:10:18   No SIGABEND handler
20:10:18   Regular Expressions support
20:10:18   Automatic Operator support
20:10:18   Machine dependent assists: cmpxchg1 cmpxchg4 cmpxchg8
20:10:18 Running on PAUL-DELL Windows_NT-6.2 AMD64 MP=8
20:10:18 HHCHD018I Loadable module directory is hercules
20:10:18 Crypto module loaded (c) Copyright Bernard van der Helm, 2003-2010
20:10:18   Active: Message Security Assist
20:10:18           Message Security Assist Extension 1
20:10:18           Message Security Assist Extension 2
20:10:18 HHCPN210I Default Allowed AUTOMOUNT directory = "c:\mvs380\"
20:10:18 HHCCF065I Hercules: tid=00003290, pid=19624, pgid=19624, priority=8
20:10:18 HHCHT001I HTTP listener thread started: tid=00004660, pid=19624
20:10:18 HHCHT013I Using HTTPROOT directory "c:\herc380\html\"
20:10:18 HHCHT006I Waiting for HTTP requests on port 8081
20:10:18 HHCSD004I Device 000C bound to socket 127.0.0.1:3505
20:10:18 HHCSD020I Socketdevice listener thread started: tid=00002020, pid=19624
20:10:18 HHCCF083I conf/mvs380.conf Including conf/mvs380_DASD.conf at 123 .
20:10:18 HHCDA004I opening dasd/mvsres.148 readonly
20:10:18 HHCDA020I dasd/mvsres.148 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/sort01.131 readonly
20:10:18 HHCDA020I dasd/sort01.131 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort02.132 readonly
20:10:18 HHCDA020I dasd/sort02.132 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort03.133 readonly
20:10:18 HHCDA020I dasd/sort03.133 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort04.134 readonly
20:10:18 HHCDA020I dasd/sort04.134 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort05.135 readonly
20:10:18 HHCDA020I dasd/sort05.135 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort06.136 readonly
20:10:18 HHCDA020I dasd/sort06.136 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/work00.140 readonly
20:10:18 HHCDA020I dasd/work00.140 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA020I dasd/hasp00.152 cyls=404 heads=19 tracks=7676 trklen=13312
20:10:18 HHCDA020I dasd/page00.160 cyls=698 heads=12 tracks=8376 trklen=8704
20:10:18 HHCDA020I dasd/page01.161 cyls=698 heads=12 tracks=8376 trklen=8704
20:10:18 HHCDA020I dasd/page02.162 cyls=698 heads=12 tracks=8376 trklen=8704
20:10:18 HHCDA020I dasd/work01.170 cyls=962 heads=12 tracks=11544 trklen=35840
20:10:18 HHCDA004I opening dasd/work02.180 readonly
20:10:18 HHCDA020I dasd/work02.180 cyls=886 heads=15 tracks=13290 trklen=47616
20:10:18 HHCDA004I opening dasd/work03.190 readonly
20:10:18 HHCDA020I dasd/work03.190 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/mvscat.191 readonly
20:10:18 HHCDA020I dasd/mvscat.191 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/seasik.192 readonly
20:10:18 HHCDA020I dasd/seasik.192 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA020I dasd/mvssrc.193 cyls=2226 heads=15 tracks=33390 trklen=56832
20:10:18 HHCDA002E 01B4:File not found or invalid 'dasd/pub004.1b4'
20:10:18 HHCCF044E Initialization failed for device 01B4
20:10:18 HHCDA004I opening dasd/pub000.240 readonly
20:10:18 HHCDA020I dasd/pub000.240 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/mvsdlb.248 readonly
20:10:18 HHCDA020I dasd/mvsdlb.248 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/pub002.280 readonly
20:10:18 HHCDA020I dasd/pub002.280 cyls=1772 heads=15 tracks=26580 trklen=47616
20:10:18 HHCDA004I opening dasd/tmptmp.2b1 readonly
20:10:18 HHCDA020I dasd/tmptmp.2b1 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/xmit90.2b2 readonly
20:10:18 HHCDA020I dasd/xmit90.2b2 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/start1.150 readonly
20:10:18 HHCDA020I dasd/start1.150 cyls=808 heads=19 tracks=15352 trklen=13312
20:10:18 HHCDA004I opening dasd/spool0.151 readonly
20:10:18 HHCDA020I dasd/spool0.151 cyls=808 heads=19 tracks=15352 trklen=13312
20:10:18 HHCDA004I opening dasd/cbt000.340 readonly
20:10:18 HHCDA020I dasd/cbt000.340 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/cbt001.341 readonly
20:10:18 HHCDA020I dasd/cbt001.341 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/cbt002.342 readonly
20:10:18 HHCDA020I dasd/cbt002.342 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/cbtcat.343 readonly
20:10:18 HHCDA020I dasd/cbtcat.343 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/src000.348 readonly
20:10:18 HHCDA020I dasd/src000.348 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/src001.349 readonly
20:10:18 HHCDA020I dasd/src001.349 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/src002.34a readonly
20:10:18 HHCDA020I dasd/src002.34a cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/srccat.34b readonly
20:10:18 HHCDA020I dasd/srccat.34b cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/smp001.149 readonly
20:10:18 HHCDA020I dasd/smp001.149 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/smp002.14a readonly
20:10:18 HHCDA020I dasd/smp002.14a cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/smp003.14b readonly
20:10:18 HHCDA020I dasd/smp003.14b cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/smp004.14c readonly
20:10:18 HHCDA020I dasd/smp004.14c cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/js2sp0.700 readonly
20:10:18 HHCDA020I dasd/js2sp0.700 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/dlicat.138 readonly
20:10:18 HHCDA020I dasd/dlicat.138 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dlisys.139 readonly
20:10:18 HHCDA020I dasd/dlisys.139 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dli000.13a readonly
20:10:18 HHCDA020I dasd/dli000.13a cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dli001.13b readonly
20:10:18 HHCDA020I dasd/dli001.13b cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dli002.13c readonly
20:10:18 HHCDA020I dasd/dli002.13c cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/js3sp0.710 readonly
20:10:18 HHCDA020I dasd/js3sp0.710 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/js3res.711 readonly
20:10:18 HHCDA020I dasd/js3res.711 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCTE001I Console connection thread started: tid=00004CF0, pid=19624
20:10:18 HHCTE003I Waiting for console connection on port 3270
20:10:18 HHCTA066I 0400: option 'level' accepted.
20:10:18 HHCTA066I 0401: option 'level' accepted.
20:10:18 HHCTA066I 0402: option 'level' accepted.
20:10:18 HHCTA066I 0403: option 'level' accepted.
20:10:18 HHCCP002I CPU0000 thread started: tid=00005304, pid=19624, priority=0
20:10:18 HHCCP003I CPU0000 architecture mode S/380
20:10:18 HHCPN001I Control panel thread started: tid=00003290, pid=19624
20:10:18 HHCTT002I Timer thread started: tid=00004D64, pid=19624, priority=-20
20:10:18 HHCPN008I Script file processing started using file "conf/auto_run.rc"
20:10:18 devlist
20:10:18 0:000C 3505 127.0.0.1:3505 sockdev ascii trunc eof
20:10:18      (no one currently connected)
20:10:18 0:000D 3525 pch/pch00d.txt ascii crlf
20:10:18 0:000E 1403 prt/prt00e.txt crlf noclear
20:10:18 0:000F 1403 prt/prt00f.txt crlf noclear
20:10:18 0:001F 3215 *syscons cmdpref(/)
20:10:18 0:00C0 3270  
20:10:18 0:00C1 3270  
20:10:18 0:00C2 3270  
20:10:18 0:00C7 3287  
20:10:18 0:010C 3505 jcl/dummy eof
20:10:18 0:0131 2314 dasd/sort01.131 [200 cyls] open
20:10:18 0:0132 2314 dasd/sort02.132 [200 cyls] open
20:10:18 0:0133 2314 dasd/sort03.133 [200 cyls] open
20:10:18 0:0134 2314 dasd/sort04.134 [200 cyls] open
20:10:18 0:0135 2314 dasd/sort05.135 [200 cyls] open
20:10:18 0:0136 2314 dasd/sort06.136 [200 cyls] open
20:10:18 0:0138 2314 dasd/dlicat.138 [200 cyls] open
20:10:18 0:0139 2314 dasd/dlisys.139 [200 cyls] open
20:10:18 0:013A 2314 dasd/dli000.13a [200 cyls] open
20:10:18 0:013B 2314 dasd/dli001.13b [200 cyls] open
20:10:18 0:013C 2314 dasd/dli002.13c [200 cyls] open
20:10:18 0:0140 3350 dasd/work00.140 [560 cyls] open
20:10:18 0:0148 3350 dasd/mvsres.148 [560 cyls] open
20:10:18 0:0149 3350 dasd/smp001.149 [560 cyls] open
20:10:18 0:014A 3350 dasd/smp002.14a [560 cyls] open
20:10:18 0:014B 3350 dasd/smp003.14b [560 cyls] open
20:10:18 0:014C 3350 dasd/smp004.14c [560 cyls] open
20:10:18 0:0150 3330 dasd/start1.150 [808 cyls] open
20:10:18 0:0151 3330 dasd/spool0.151 [808 cyls] open
20:10:18 0:0152 3330 dasd/hasp00.152 [404 cyls] open
20:10:18 0:0160 3340 dasd/page00.160 [698 cyls] open
20:10:18 0:0161 3340 dasd/page01.161 [698 cyls] open
20:10:18 0:0162 3340 dasd/page02.162 [698 cyls] open
20:10:18 0:0170 3375 dasd/work01.170 [962 cyls] open
20:10:18 0:01A0 3380 dasd/work02.180 [886 cyls] open
20:10:18 0:01B0 3390 dasd/work03.190 [1114 cyls] open
20:10:18 0:01B1 3390 dasd/mvscat.191 [1114 cyls] open
20:10:18 0:01B2 3390 dasd/seasik.192 [1114 cyls] open
20:10:18 0:01B3 3390 dasd/mvssrc.193 [2226 cyls] open
20:10:18 0:01C7 3287  
20:10:18 0:0240 3350 dasd/pub000.240 [560 cyls] open
20:10:18 0:0248 3350 dasd/mvsdlb.248 [560 cyls] open
20:10:18 0:02A0 3380 dasd/pub002.280 [1772 cyls] open
20:10:18 0:02B1 3390 dasd/tmptmp.2b1 [1114 cyls] open
20:10:18 0:02B2 3390 dasd/xmit90.2b2 [1114 cyls] open
20:10:18 0:030E 1403 log/hardcopy.log crlf noclear
20:10:18 0:0340 3350 dasd/cbt000.340 [555 cyls] open
20:10:18 0:0341 3350 dasd/cbt001.341 [555 cyls] open
20:10:18 0:0342 3350 dasd/cbt002.342 [555 cyls] open
20:10:18 0:0343 3350 dasd/cbtcat.343 [555 cyls] open
20:10:18 0:0348 3350 dasd/src000.348 [555 cyls] open
20:10:18 0:0349 3350 dasd/src001.349 [555 cyls] open
20:10:18 0:034A 3350 dasd/src002.34a [555 cyls] open
20:10:18 0:034B 3350 dasd/srccat.34b [555 cyls] open
20:10:18 0:0400 3420 *
20:10:18 0:0401 3420 *
20:10:18 0:0402 3420 *
20:10:18 0:0403 3420 *
20:10:18 0:0700 3350 dasd/js2sp0.700 [555 cyls] open
20:10:18 0:0710 3350 dasd/js3sp0.710 [555 cyls] open
20:10:18 0:0711 3350 dasd/js3res.711 [555 cyls] open
20:10:18 logopt timestamp
20:10:18 HHCPN197I Log option set: TIMESTAMP
20:10:18 panrate 1000
20:10:18 # really need to wait for MVS380SS, but if building MVS/380
20:10:18 # or some other stuffup with parms, need to trigger on an
20:10:18 # alternative. First one hit will clear targets.
20:10:18 # If we get a prompt for a device, cancel it
20:10:18 hao tgt IEF238D
20:10:18 HHCAO016I Target placed at index 0
20:10:18 hao cmd script conf/cancel.rc
20:10:18 HHCAO020I Command placed at index 0
20:10:18 hao tgt HASP395 DYNAMASK
20:10:18 HHCAO016I Target placed at index 1
20:10:18 hao cmd script conf/subjobs_slow.rc
20:10:18 HHCAO020I Command placed at index 1
20:10:18 hao tgt HASP373 MVS380SS
20:10:18 HHCAO016I Target placed at index 2
20:10:18 hao cmd script conf/subjobs_fast.rc
20:10:18 HHCAO020I Command placed at index 2
20:10:18 hao tgt HASP436 REPLY
20:10:18 HHCAO016I Target placed at index 3
20:10:18 hao cmd script conf/replyy.rc
20:10:18 HHCAO020I Command placed at index 3
20:10:18 devinit 402 tapes/mftopc.het
20:10:18 HHCTA004I 0402: tapes/mftopc.het is a Hercules Emulated Tape file
20:10:18 HHCPN098I Device 0:0402 initialized
20:10:18 devinit 403 tapes/awstap.aws
20:10:18 HHCTA004I 0403: tapes/awstap.aws is a Hercules Emulated Tape file
20:10:18 HHCPN098I Device 0:0403 initialized
20:10:18 # co will give you a cold start of JES2 which is useful in
20:10:18 # automated runs
20:10:18 /(001F) r 00,clpa,cmd=co
20:10:18 #/r 00,clpa
20:10:18 ipl 148
20:10:18 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:10:18 HHCAO001I Hercules Automatic Operator thread started;
20:10:18           tid=0000531C, pri=0, pid=19624
20:10:18 IEA101A SPECIFY SYSTEM PARMS FOR MVS/380 1.2 v03.8 TK3+
20:10:18 HHCCD001I Readahead thread 1 started: tid=00003E5C, pid=19624
20:10:18 HHCCD001I Readahead thread 2 started: tid=00005068, pid=19624
20:10:18 IEA940I THE FOLLOWING PAGE DATA SETS ARE IN USE
20:10:18  PLPA ... SYS1.PAGELPA
20:10:19  COMMON . SYS1.PAGECSA
20:10:19  DUPLEX . SYS1.DUPLEX
20:10:19  LOCAL .. SYS1.PAGEL01
20:10:19  LOCAL .. SYS1.PAGEL02
20:10:19  LOCAL .. SYS1.PAGEL03
20:10:19 HHCCD002I Writer thread 1 started: tid=0000213C, pid=19624
20:10:19 HHCCD002I Writer thread 2 started: tid=00004654, pid=19624
20:10:19 HHCCD003I Garbage collector thread started: tid=0000467C, pid=19624
20:10:24 *IEE362A SMF ENTER DUMP FOR SYS1.MANY ON MVSRES
20:10:24  IEE360I SMF NOW RECORDING ON SYS1.MANX ON MVSRES TIME=10.10.24
20:10:24  IGF992I  MIH INIT COMPLETE, PRI=000300, SEC=000015
20:10:24  IEF677I WARNING MESSAGE(S) FOR JOB JES2     ISSUED
20:10:25 *00 $HASP436 REPLY Y OR N TO CONFIRM CHECKPOINT RECORD CHANGE
20:10:25 HHCAO003I Firing command: 'script conf/replyy.rc'
20:10:25 script conf/replyy.rc
20:10:25 HHCPN011I Pausing SCRIPT file processing for 1 seconds...
20:10:26 HHCPN012I Resuming SCRIPT file processing...
20:10:26 /(001F) reply 00,y
20:10:26 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:10:26  IEE600I REPLY TO 00 IS;SUPPRESSED
20:10:26  $HASP493 JES2 COLD-START IS IN PROGRESS
20:10:28  $HASP412 MAXIMUM OF 1   READER(S)  EXCEEDED
20:10:28 10.10.28           IEE041I THE SYSTEM LOG IS NOW ACTIVE
20:10:28 10.10.28           IEE305I X        COMMAND INVALID
20:10:28 10.10.28           IEE450I 10.10.28 UNIT STATUS 154
20:10:28  UNIT TYPE STATUS  VOLSER VOLSTATE   UNIT TYPE STATUS  VOLSER VOLSTATE
20:10:28  400  3400 O                  /REMOV 401  3400 O                  /REMOV
20:10:28  402  3400 O                  /REMOV
20:10:28 10.10.28           IEE302I 00E      ONLINE
20:10:28 10.10.28           IEE302I 00F      ONLINE
20:10:28 10.10.28           IEE313I 30E     UNIT REF INVALID
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           IEE305I X        COMMAND INVALID
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP160 PRINTER1 INACTIVE - CLASS=AP
20:10:28 10.10.28           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:28 10.10.28           $HASP160 PUNCH1   INACTIVE - CLASS=B
20:10:28 10.10.28 STC    2  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    2  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    2  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    3  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    3  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    3  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    4  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    4  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    4  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    5  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    5  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    5  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    6  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    6  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    6  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    7  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    7  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    7  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    8  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    8  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    8  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    9  $HASP100 MVS380SS ON STCINRDR
20:10:28 10.10.28 STC    9  $HASP373 MVS380SS STARTED
20:10:28 HHCAO003I Firing command: 'script conf/subjobs_fast.rc'
20:10:28 script conf/subjobs_fast.rc
20:10:28 # Now we know that MVS380SS has started.
20:10:28 # When it issues a mount request, we can continue
20:10:28 hao clear
20:10:28 HHCAO022I All automatic operation rules cleared
20:10:28 hao tgt IEF233A
20:10:28 HHCAO016I Target placed at index 0
20:10:28 hao cmd script conf/subjobs2.rc
20:10:28 HHCAO020I Command placed at index 0
20:10:28 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:10:28 10.10.28 STC    9  IEF403I MVS380SS - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC   10  $HASP100 DYNAMASK ON STCINRDR
20:10:28 10.10.28 STC   10  $HASP373 DYNAMASK STARTED
20:10:28 10.10.28 STC   10  IEF403I DYNAMASK - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC   11  $HASP100 BSPPILOT ON STCINRDR
20:10:28 10.10.28 STC   11  $HASP373 BSPPILOT STARTED
20:10:28 10.10.28 STC   11  IEF403I BSPPILOT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC   12  $HASP100 BSPSETPF ON STCINRDR
20:10:28 10.10.28 STC   13  $HASP100 LOGRECI  ON STCINRDR
20:10:28 10.10.28           $HASP309    INIT  1 INACTIVE ******** C=A
20:10:29 10.10.28           $HASP309    INIT  2 INACTIVE ******** C=BA
20:10:29 10.10.28           $HASP309    INIT  3 INACTIVE ******** C=CBA
20:10:29 10.10.28           $HASP309    INIT  4 INACTIVE ******** C=SHB
20:10:29 10.10.28           $HASP309    INIT  5 INACTIVE ******** C=SBA
20:10:29 10.10.28           $HASP309    INIT  6 INACTIVE ******** C=S
20:10:29 10.10.28           $HASP309    INIT  7 INACTIVE ******** C=E
20:10:29 10.10.29 STC   14  $HASP100 NET      ON STCINRDR
20:10:29 10.10.29 STC   12  $HASP373 BSPSETPF STARTED
20:10:29 10.10.29 STC   12  IEF403I BSPSETPF - STARTED - TIME=10.10.29
20:10:29 10.10.29 STC   13  $HASP373 LOGRECI  STARTED
20:10:29 10.10.29 STC   13  IEF403I LOGRECI - STARTED - TIME=10.10.29
20:10:29 10.10.29 STC   14  $HASP373 NET      STARTED
20:10:29 10.10.29 STC   14  IEF403I NET - STARTED - TIME=10.10.29
20:10:29 10.10.29           IEE302I 401      ONLINE
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAK   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAK   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST110I  NETWORK SOLICITOR STARTED
20:10:29 10.10.29 STC   14  IST093I  APPLJRP  ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLPIES ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLPIAD ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLTSO  ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLTEST ACTIVE
20:10:30 10.10.30 STC   14  IST093I  LCLMAJRP ACTIVE
20:10:30 10.10.30 STC   14  IST093I  LCLMAJ00 ACTIVE
20:10:30 10.10.30 STC   15  $HASP100 TSO      ON STCINRDR
20:10:30 10.10.30 STC   15  $HASP373 TSO      STARTED
20:10:30 10.10.30 STC   15  IEF403I TSO - STARTED - TIME=10.10.30
20:10:30 10.10.30 STC   16  $HASP100 JRP      ON STCINRDR
20:10:30 10.10.30           $HASP000 OK
20:10:30 10.10.30 STC   16  $HASP373 JRP      STARTED
20:10:30 10.10.30 STC   16  IEF403I JRP - STARTED - TIME=10.10.30
20:10:30 10.10.30           $HASP000 OK
20:10:30 10.10.30 STC   14  IST020I  VTAM INITIALIZATION COMPLETE
20:10:30 10.10.30 STC   14  IEA000I 0C0,IOE,05,0200,400000000001,,,NET     ,10.10.30
20:10:30 10.10.30 STC   14  IEA000I 0C1,IOE,05,0200,400000000001,,,NET     ,10.10.30
20:10:30 10.10.30 STC   14  IEA000I 0C2,IOE,05,0200,400000000001,,,NET     ,10.10.30
20:10:30 10.10.30 STC   10  DMSK00I DYNAMASK DONE ****
20:10:30 10.10.30 STC   10  DMSK06I  START
20:10:30 10.10.30 STC   10  IEF404I DYNAMASK - ENDED - TIME=10.10.30
20:10:30 10.10.30 STC   10  $HASP395 DYNAMASK ENDED
20:10:30 10.10.30 STC   10  $HASP150 DYNAMASK ON PRINTER2        30 LINES
20:10:30 10.10.30 STC   10 *$HASP190 DYNAMASK SETUP -- PRINTER2 -- F = 0001 -- C = 6    -- T = SN
20:10:30 10.10.30           $HASP000 OK
20:10:30 10.10.30           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:30 10.10.30 STC   10  $HASP250 DYNAMASK IS PURGED
20:10:30 10.10.30 STC   12  BSPSP91I - Parms passed: NOREPLYU
20:10:30 10.10.30 STC   12  BSPSP93I - PFK definitions will be updated in memory
20:10:30 10.10.30 STC   12  BSPSP22I - Dataset processed: SYS1.PARMLIB
20:10:30 10.10.30 STC   12  BSPSP23I - on volume MVSRES
20:10:30 10.10.30 STC   12  BSPSP21I - Member being processed: SETPFK01
20:10:30 10.10.30 STC   12  +BSPSP98I - Member processed, LASTCC=0000
20:10:30 10.10.30 STC   12  +BSPSP21I - Member being processed: SETPFK02
20:10:30 10.10.30 STC   12  +BSPSP98I - Member processed, LASTCC=0000
20:10:30 10.10.30 STC   12  +BSPSP99I - End of processing, MAXRC=0000
20:10:30 10.10.30 STC   12  IEF404I BSPSETPF - ENDED - TIME=10.10.30
20:10:30 10.10.30 STC   12  $HASP395 BSPSETPF ENDED
20:10:30 10.10.30 STC   12  $HASP150 BSPSETPF ON PRINTER2        34 LINES
20:10:30 10.10.30           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:30 10.10.30 STC   12  $HASP250 BSPSETPF IS PURGED
20:10:30 10.10.30 STC   13  IFC001I  D=3350 N=0B F=01AB0000 L=01AC0005 S=01AB000002 DIP COMPLETE
20:10:30 10.10.30 STC   13  IEF404I LOGRECI - ENDED - TIME=10.10.30
20:10:30 10.10.30 STC   13  $HASP395 LOGRECI  ENDED
20:10:30 10.10.30 STC   13  $HASP150 LOGRECI  ON PRINTER2        20 LINES
20:10:30 10.10.30           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:30 10.10.30 STC   13  $HASP250 LOGRECI  IS PURGED
20:10:30 10.10.30 STC    9  BSPGM40I -   200 MiB MAINSIZE memory available
20:10:30 10.10.30 STC    9  BSPGM41I -     2 Partitions of    64 MiB each
20:10:30 10.10.30 STC    9  BSPGM78I SVC 120 EP=00FE2DC8; MVS/380
20:10:30 10.10.30 STC   15  IKT007I TCAS ACCEPTING LOGONS
20:10:30 10.10.30 STC   15  IKT005I TCAS IS INITIALIZED
20:10:30 10.10.30 STC   16  JRPI101 INITIALIZATION COMPLETE
20:10:30 10.10.30 STC   16 *01 JRP100I - ENTER ICLOSE TO SHUT DOWN
20:10:31 10.10.30 STC   17  $HASP100 MOUNT    ON STCINRDR
20:10:31 10.10.30 STC   17  $HASP373 MOUNT    STARTED
20:10:31 10.10.30 STC   17  IEF403I MOUNT - STARTED - TIME=10.10.30
20:10:31 10.10.31 STC    9  IEF404I MVS380SS - ENDED - TIME=10.10.31
20:10:31 10.10.31 STC    9  $HASP395 MVS380SS ENDED
20:10:31 10.10.31 STC    9  $HASP150 MVS380SS ON PRINTER2        59 LINES
20:10:31 10.10.31           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:31 10.10.31 STC    9  $HASP250 MVS380SS IS PURGED
20:10:31 10.10.31 STC   17  IEF404I MOUNT - ENDED - TIME=10.10.31
20:10:31 10.10.31 STC   17  $HASP395 MOUNT    ENDED
20:10:31 10.10.31 STC   17  $HASP150 MOUNT    ON PRINTER2        18 LINES
20:10:31 10.10.31           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:31 10.10.31 STC   17  $HASP250 MOUNT    IS PURGED
21:14:02 /(001F) d t
21:14:02 11.14.02           IEE136I  TIME=11.14.02 DATE=17.093
21:31:50 HHCTE009I Client 127.0.0.1 connected to 3270 device 0:00C0
21:31:54 11.31.54 TSU    1  $HASP100 HERC01   ON TSOINRDR
21:31:54 11.31.54 TSU    1  $HASP373 HERC01   STARTED
21:31:54 11.31.54 TSU    1  IEF125I HERC01 - LOGGED ON - TIME=11.31.54
21:31:54 11.31.54          *IEE362A SMF ENTER DUMP FOR SYS1.MANX ON MVSRES
21:31:54 11.31.54           IEE361I SMF DATA LOST - SYS1.MANY NOT AVAILABLE TIME=11.31.54
21:31:55 11.31.54 STC   18  $HASP100 SMFDAILY ON STCINRDR
21:31:55 11.31.55 STC   18  $HASP373 SMFDAILY STARTED
21:31:55 11.31.55 STC   18  IEF403I SMFDAILY - STARTED - TIME=11.31.55
21:31:55 11.31.55 STC   18  IEF453I SMFDAILY - JOB FAILED - JCL ERROR - TIME=11.31.55
21:31:55 11.31.55 STC   18  $HASP395 SMFDAILY ENDED
21:31:55 11.31.55 STC   18  $HASP150 SMFDAILY ON PRINTER2        25 LINES
21:31:55 11.31.55           IEE132I START COMMAND DEVICE ALLOCATION ERROR
21:31:55 11.31.55           $HASP160 PRINTER2 INACTIVE - CLASS=Z
21:31:55 11.31.55 STC   18  $HASP250 SMFDAILY IS PURGED
21:34:22 11.34.22 TSU    1  IEFACTRT - Stepname  Procstep  Program   Retcode
21:34:22 11.34.22 TSU    1  IEF126I HERC01 - LOGGED OFF - TIME=11.34.22
21:34:22 11.34.22 TSU    1  $HASP395 HERC01   ENDED
21:34:22 11.34.22 TSU    1  $HASP150 HERC01   ON PRINTER2       127 LINES
21:34:22 11.34.22           $HASP160 PRINTER2 INACTIVE - CLASS=Z
21:34:22 11.34.22 TSU    1  $HASP250 HERC01   IS PURGED
21:34:28 HHCTE014I 3270 device 00C0 client 127.0.0.1 connection reset
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
For good measure/comparison, here is
the run immediately prior, which worked
fine.

BFN. Paul.




20:09:44 Hercules Version 3.07:380-4.x
20:09:44 (c)Copyright 1999-2010 by Roger Bowler, Jan Jaeger, and others
20:09:44 Built on Dec  7 2016 at 12:43:32
20:09:44 Build information:
20:09:44   Windows (MSVC) build for AMD64
20:09:44   Modes: S/370 S/380 ESA/390 z/Arch
20:09:44   Max CPU Engines: 8
20:09:44   Using fthreads instead of pthreads
20:09:44   Dynamic loading support
20:09:44   Using shared libraries
20:09:44   HTTP Server support
20:09:44   No SIGABEND handler
20:09:44   Regular Expressions support
20:09:44   Automatic Operator support
20:09:44   Machine dependent assists: cmpxchg1 cmpxchg4 cmpxchg8
20:09:44 Running on PAUL-DELL Windows_NT-6.2 AMD64 MP=8
20:09:44 HHCHD018I Loadable module directory is hercules
20:09:44 Crypto module loaded (c) Copyright Bernard van der Helm, 2003-2010
20:09:44   Active: Message Security Assist
20:09:44           Message Security Assist Extension 1
20:09:44           Message Security Assist Extension 2
20:09:44 HHCPN210I Default Allowed AUTOMOUNT directory = "c:\mvs380\"
20:09:44 HHCCF065I Hercules: tid=0000438C, pid=9596, pgid=9596, priority=8
20:09:44 HHCHT001I HTTP listener thread started: tid=00001D28, pid=9596
20:09:44 HHCHT013I Using HTTPROOT directory "c:\herc380\html\"
20:09:44 HHCHT006I Waiting for HTTP requests on port 8081
20:09:44 HHCSD004I Device 000C bound to socket 127.0.0.1:3505
20:09:44 HHCSD020I Socketdevice listener thread started: tid=00002788, pid=9596
20:09:44 HHCCF083I conf/mvs380.conf Including conf/mvs380_DASD.conf at 123 .
20:09:44 HHCDA004I opening dasd/mvsres.148 readonly
20:09:44 HHCDA020I dasd/mvsres.148 cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/sort01.131 readonly
20:09:44 HHCDA020I dasd/sort01.131 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/sort02.132 readonly
20:09:44 HHCDA020I dasd/sort02.132 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/sort03.133 readonly
20:09:44 HHCDA020I dasd/sort03.133 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/sort04.134 readonly
20:09:44 HHCDA020I dasd/sort04.134 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/sort05.135 readonly
20:09:44 HHCDA020I dasd/sort05.135 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/sort06.136 readonly
20:09:44 HHCDA020I dasd/sort06.136 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/work00.140 readonly
20:09:44 HHCDA020I dasd/work00.140 cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA020I dasd/hasp00.152 cyls=404 heads=19 tracks=7676 trklen=13312
20:09:44 HHCDA020I dasd/page00.160 cyls=698 heads=12 tracks=8376 trklen=8704
20:09:44 HHCDA020I dasd/page01.161 cyls=698 heads=12 tracks=8376 trklen=8704
20:09:44 HHCDA020I dasd/page02.162 cyls=698 heads=12 tracks=8376 trklen=8704
20:09:44 HHCDA020I dasd/work01.170 cyls=962 heads=12 tracks=11544 trklen=35840
20:09:44 HHCDA004I opening dasd/work02.180 readonly
20:09:44 HHCDA020I dasd/work02.180 cyls=886 heads=15 tracks=13290 trklen=47616
20:09:44 HHCDA004I opening dasd/work03.190 readonly
20:09:44 HHCDA020I dasd/work03.190 cyls=1114 heads=15 tracks=16710 trklen=56832
20:09:44 HHCDA004I opening dasd/mvscat.191 readonly
20:09:44 HHCDA020I dasd/mvscat.191 cyls=1114 heads=15 tracks=16710 trklen=56832
20:09:44 HHCDA004I opening dasd/seasik.192 readonly
20:09:44 HHCDA020I dasd/seasik.192 cyls=1114 heads=15 tracks=16710 trklen=56832
20:09:44 HHCDA020I dasd/mvssrc.193 cyls=2226 heads=15 tracks=33390 trklen=56832
20:09:44 HHCDA002E 01B4:File not found or invalid 'dasd/pub004.1b4'
20:09:44 HHCCF044E Initialization failed for device 01B4
20:09:44 HHCDA004I opening dasd/pub000.240 readonly
20:09:44 HHCDA020I dasd/pub000.240 cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/mvsdlb.248 readonly
20:09:44 HHCDA020I dasd/mvsdlb.248 cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/pub002.280 readonly
20:09:44 HHCDA020I dasd/pub002.280 cyls=1772 heads=15 tracks=26580 trklen=47616
20:09:44 HHCDA004I opening dasd/tmptmp.2b1 readonly
20:09:44 HHCDA020I dasd/tmptmp.2b1 cyls=1114 heads=15 tracks=16710 trklen=56832
20:09:44 HHCDA004I opening dasd/xmit90.2b2 readonly
20:09:44 HHCDA020I dasd/xmit90.2b2 cyls=1114 heads=15 tracks=16710 trklen=56832
20:09:44 HHCDA004I opening dasd/start1.150 readonly
20:09:44 HHCDA020I dasd/start1.150 cyls=808 heads=19 tracks=15352 trklen=13312
20:09:44 HHCDA004I opening dasd/spool0.151 readonly
20:09:44 HHCDA020I dasd/spool0.151 cyls=808 heads=19 tracks=15352 trklen=13312
20:09:44 HHCDA004I opening dasd/cbt000.340 readonly
20:09:44 HHCDA020I dasd/cbt000.340 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/cbt001.341 readonly
20:09:44 HHCDA020I dasd/cbt001.341 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/cbt002.342 readonly
20:09:44 HHCDA020I dasd/cbt002.342 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/cbtcat.343 readonly
20:09:44 HHCDA020I dasd/cbtcat.343 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/src000.348 readonly
20:09:44 HHCDA020I dasd/src000.348 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/src001.349 readonly
20:09:44 HHCDA020I dasd/src001.349 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/src002.34a readonly
20:09:44 HHCDA020I dasd/src002.34a cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/srccat.34b readonly
20:09:44 HHCDA020I dasd/srccat.34b cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/smp001.149 readonly
20:09:44 HHCDA020I dasd/smp001.149 cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/smp002.14a readonly
20:09:44 HHCDA020I dasd/smp002.14a cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/smp003.14b readonly
20:09:44 HHCDA020I dasd/smp003.14b cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/smp004.14c readonly
20:09:44 HHCDA020I dasd/smp004.14c cyls=560 heads=30 tracks=16800 trklen=19456
20:09:44 HHCDA004I opening dasd/js2sp0.700 readonly
20:09:44 HHCDA020I dasd/js2sp0.700 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/dlicat.138 readonly
20:09:44 HHCDA020I dasd/dlicat.138 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/dlisys.139 readonly
20:09:44 HHCDA020I dasd/dlisys.139 cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/dli000.13a readonly
20:09:44 HHCDA020I dasd/dli000.13a cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/dli001.13b readonly
20:09:44 HHCDA020I dasd/dli001.13b cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/dli002.13c readonly
20:09:44 HHCDA020I dasd/dli002.13c cyls=200 heads=20 tracks=4000 trklen=7680
20:09:44 HHCDA004I opening dasd/js3sp0.710 readonly
20:09:44 HHCDA020I dasd/js3sp0.710 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCDA004I opening dasd/js3res.711 readonly
20:09:44 HHCDA020I dasd/js3res.711 cyls=555 heads=30 tracks=16650 trklen=19456
20:09:44 HHCTE001I Console connection thread started: tid=00001850, pid=9596
20:09:44 HHCTE003I Waiting for console connection on port 3270
20:09:44 HHCTA066I 0400: option 'level' accepted.
20:09:44 HHCTA066I 0401: option 'level' accepted.
20:09:44 HHCTA066I 0402: option 'level' accepted.
20:09:44 HHCTA066I 0403: option 'level' accepted.
20:09:44 HHCCP002I CPU0000 thread started: tid=00004300, pid=9596, priority=0
20:09:44 HHCCP003I CPU0000 architecture mode S/380
20:09:44 HHCTT002I Timer thread started: tid=00004E34, pid=9596, priority=-20
20:09:44 HHCPN001I Control panel thread started: tid=0000438C, pid=9596
20:09:44 HHCPN008I Script file processing started using file "conf/auto_run.rc"
20:09:44 devlist
20:09:44 0:000C 3505 127.0.0.1:3505 sockdev ascii trunc eof
20:09:44      (no one currently connected)
20:09:44 0:000D 3525 pch/pch00d.txt ascii crlf
20:09:44 0:000E 1403 prt/prt00e.txt crlf noclear
20:09:44 0:000F 1403 prt/prt00f.txt crlf noclear
20:09:44 0:001F 3215 *syscons cmdpref(/)
20:09:44 0:00C0 3270  
20:09:44 0:00C1 3270  
20:09:44 0:00C2 3270  
20:09:44 0:00C7 3287  
20:09:44 0:010C 3505 jcl/dummy eof
20:09:44 0:0131 2314 dasd/sort01.131 [200 cyls] open
20:09:44 0:0132 2314 dasd/sort02.132 [200 cyls] open
20:09:44 0:0133 2314 dasd/sort03.133 [200 cyls] open
20:09:44 0:0134 2314 dasd/sort04.134 [200 cyls] open
20:09:44 0:0135 2314 dasd/sort05.135 [200 cyls] open
20:09:44 0:0136 2314 dasd/sort06.136 [200 cyls] open
20:09:44 0:0138 2314 dasd/dlicat.138 [200 cyls] open
20:09:44 0:0139 2314 dasd/dlisys.139 [200 cyls] open
20:09:44 0:013A 2314 dasd/dli000.13a [200 cyls] open
20:09:44 0:013B 2314 dasd/dli001.13b [200 cyls] open
20:09:44 0:013C 2314 dasd/dli002.13c [200 cyls] open
20:09:44 0:0140 3350 dasd/work00.140 [560 cyls] open
20:09:44 0:0148 3350 dasd/mvsres.148 [560 cyls] open
20:09:44 0:0149 3350 dasd/smp001.149 [560 cyls] open
20:09:44 0:014A 3350 dasd/smp002.14a [560 cyls] open
20:09:44 0:014B 3350 dasd/smp003.14b [560 cyls] open
20:09:44 HHCAO001I Hercules Automatic Operator thread started;
20:09:44           tid=00004E40, pri=0, pid=9596
20:09:44 0:014C 3350 dasd/smp004.14c [560 cyls] open
20:09:44 0:0150 3330 dasd/start1.150 [808 cyls] open
20:09:44 0:0151 3330 dasd/spool0.151 [808 cyls] open
20:09:44 0:0152 3330 dasd/hasp00.152 [404 cyls] open
20:09:44 0:0160 3340 dasd/page00.160 [698 cyls] open
20:09:44 0:0161 3340 dasd/page01.161 [698 cyls] open
20:09:44 0:0162 3340 dasd/page02.162 [698 cyls] open
20:09:44 0:0170 3375 dasd/work01.170 [962 cyls] open
20:09:44 0:01A0 3380 dasd/work02.180 [886 cyls] open
20:09:44 0:01B0 3390 dasd/work03.190 [1114 cyls] open
20:09:44 0:01B1 3390 dasd/mvscat.191 [1114 cyls] open
20:09:44 0:01B2 3390 dasd/seasik.192 [1114 cyls] open
20:09:44 0:01B3 3390 dasd/mvssrc.193 [2226 cyls] open
20:09:44 0:01C7 3287  
20:09:44 0:0240 3350 dasd/pub000.240 [560 cyls] open
20:09:44 0:0248 3350 dasd/mvsdlb.248 [560 cyls] open
20:09:44 0:02A0 3380 dasd/pub002.280 [1772 cyls] open
20:09:44 0:02B1 3390 dasd/tmptmp.2b1 [1114 cyls] open
20:09:44 0:02B2 3390 dasd/xmit90.2b2 [1114 cyls] open
20:09:44 0:030E 1403 log/hardcopy.log crlf noclear
20:09:44 0:0340 3350 dasd/cbt000.340 [555 cyls] open
20:09:44 0:0341 3350 dasd/cbt001.341 [555 cyls] open
20:09:44 0:0342 3350 dasd/cbt002.342 [555 cyls] open
20:09:44 0:0343 3350 dasd/cbtcat.343 [555 cyls] open
20:09:44 0:0348 3350 dasd/src000.348 [555 cyls] open
20:09:44 0:0349 3350 dasd/src001.349 [555 cyls] open
20:09:44 0:034A 3350 dasd/src002.34a [555 cyls] open
20:09:44 0:034B 3350 dasd/srccat.34b [555 cyls] open
20:09:44 0:0400 3420 *
20:09:44 0:0401 3420 *
20:09:44 0:0402 3420 *
20:09:44 0:0403 3420 *
20:09:44 0:0700 3350 dasd/js2sp0.700 [555 cyls] open
20:09:44 0:0710 3350 dasd/js3sp0.710 [555 cyls] open
20:09:44 0:0711 3350 dasd/js3res.711 [555 cyls] open
20:09:44 logopt timestamp
20:09:44 HHCPN197I Log option set: TIMESTAMP
20:09:44 panrate 1000
20:09:44 # really need to wait for MVS380SS, but if building MVS/380
20:09:44 # or some other stuffup with parms, need to trigger on an
20:09:44 # alternative. First one hit will clear targets.
20:09:44 # If we get a prompt for a device, cancel it
20:09:44 hao tgt IEF238D
20:09:44 HHCAO016I Target placed at index 0
20:09:44 hao cmd script conf/cancel.rc
20:09:44 HHCAO020I Command placed at index 0
20:09:44 hao tgt HASP395 DYNAMASK
20:09:44 HHCAO016I Target placed at index 1
20:09:44 hao cmd script conf/subjobs_slow.rc
20:09:44 HHCAO020I Command placed at index 1
20:09:44 hao tgt HASP373 MVS380SS
20:09:44 HHCAO016I Target placed at index 2
20:09:44 hao cmd script conf/subjobs_fast.rc
20:09:44 HHCAO020I Command placed at index 2
20:09:44 hao tgt HASP436 REPLY
20:09:44 HHCAO016I Target placed at index 3
20:09:44 hao cmd script conf/replyy.rc
20:09:44 HHCAO020I Command placed at index 3
20:09:44 devinit 402 tapes/mftopc.het
20:09:44 HHCTA004I 0402: tapes/mftopc.het is a Hercules Emulated Tape file
20:09:44 HHCPN098I Device 0:0402 initialized
20:09:44 devinit 403 tapes/awstap.aws
20:09:44 HHCTA004I 0403: tapes/awstap.aws is a Hercules Emulated Tape file
20:09:44 HHCPN098I Device 0:0403 initialized
20:09:44 # co will give you a cold start of JES2 which is useful in
20:09:44 # automated runs
20:09:44 /(001F) r 00,clpa,cmd=co
20:09:44 #/r 00,clpa
20:09:44 ipl 148
20:09:44 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:09:44 IEA101A SPECIFY SYSTEM PARMS FOR MVS/380 1.2 v03.8 TK3+
20:09:44 HHCCD001I Readahead thread 1 started: tid=00003A34, pid=9596
20:09:44 HHCCD001I Readahead thread 2 started: tid=00004E94, pid=9596
20:09:44 HHCCD002I Writer thread 1 started: tid=00002C1C, pid=9596
20:09:44 HHCCD003I Garbage collector thread started: tid=00001A5C, pid=9596
20:09:44 IEA940I THE FOLLOWING PAGE DATA SETS ARE IN USE
20:09:44  PLPA ... SYS1.PAGELPA
20:09:44  COMMON . SYS1.PAGECSA
20:09:44  DUPLEX . SYS1.DUPLEX
20:09:44  LOCAL .. SYS1.PAGEL01
20:09:44  LOCAL .. SYS1.PAGEL02
20:09:44  LOCAL .. SYS1.PAGEL03
20:09:45 HHCCD002I Writer thread 2 started: tid=0000175C, pid=9596
20:09:49 *IEE362A SMF ENTER DUMP FOR SYS1.MANY ON MVSRES
20:09:49  IEE360I SMF NOW RECORDING ON SYS1.MANX ON MVSRES TIME=10.09.49
20:09:49  IGF992I  MIH INIT COMPLETE, PRI=000300, SEC=000015
20:09:50  IEF677I WARNING MESSAGE(S) FOR JOB JES2     ISSUED
20:09:50 *00 $HASP436 REPLY Y OR N TO CONFIRM CHECKPOINT RECORD CHANGE
20:09:50 HHCAO003I Firing command: 'script conf/replyy.rc'
20:09:50 script conf/replyy.rc
20:09:50 HHCPN011I Pausing SCRIPT file processing for 1 seconds...
20:09:51 HHCPN012I Resuming SCRIPT file processing...
20:09:51 /(001F) reply 00,y
20:09:51 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:09:51  IEE600I REPLY TO 00 IS;SUPPRESSED
20:09:51  $HASP493 JES2 COLD-START IS IN PROGRESS
20:09:53  $HASP412 MAXIMUM OF 1   READER(S)  EXCEEDED
20:09:53 10.09.53           IEE041I THE SYSTEM LOG IS NOW ACTIVE
20:09:53 10.09.53           IEE305I X        COMMAND INVALID
20:09:53 10.09.53           IEE450I 10.09.53 UNIT STATUS 155
20:09:53  UNIT TYPE STATUS  VOLSER VOLSTATE   UNIT TYPE STATUS  VOLSER VOLSTATE
20:09:53  400  3400 O                  /REMOV 401  3400 O                  /REMOV
20:09:53  402  3400 O                  /REMOV
20:09:53 10.09.53           IEE302I 00E      ONLINE
20:09:53 10.09.53           IEE302I 00F      ONLINE
20:09:53 10.09.53           IEE313I 30E     UNIT REF INVALID
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           IEE305I X        COMMAND INVALID
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP000 OK
20:09:53 10.09.53           $HASP160 PRINTER1 INACTIVE - CLASS=AP
20:09:53 10.09.53           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:53 10.09.53           $HASP160 PUNCH1   INACTIVE - CLASS=B
20:09:53 10.09.53 STC    2  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    2  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    2  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    3  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    3  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    3  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    4  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    4  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    4  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    5  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    5  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    5  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    6  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    6  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    6  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    7  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    7  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    7  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    8  $HASP100 INIT     ON STCINRDR
20:09:53 10.09.53 STC    8  $HASP373 INIT     STARTED
20:09:53 10.09.53 STC    8  IEF403I INIT - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC    9  $HASP100 MVS380SS ON STCINRDR
20:09:53 10.09.53 STC    9  $HASP373 MVS380SS STARTED
20:09:53 HHCAO003I Firing command: 'script conf/subjobs_fast.rc'
20:09:53 script conf/subjobs_fast.rc
20:09:53 # Now we know that MVS380SS has started.
20:09:53 # When it issues a mount request, we can continue
20:09:53 hao clear
20:09:53 HHCAO022I All automatic operation rules cleared
20:09:53 hao tgt IEF233A
20:09:53 HHCAO016I Target placed at index 0
20:09:53 hao cmd script conf/subjobs2.rc
20:09:53 HHCAO020I Command placed at index 0
20:09:53 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:09:53 10.09.53 STC    9  IEF403I MVS380SS - STARTED - TIME=10.09.53
20:09:53 10.09.53 STC   10  $HASP100 DYNAMASK ON STCINRDR
20:09:54 10.09.54 STC   11  $HASP100 BSPPILOT ON STCINRDR
20:09:54 10.09.54           $HASP309    INIT  1 INACTIVE ******** C=A
20:09:54 10.09.54           $HASP309    INIT  2 INACTIVE ******** C=BA
20:09:54 10.09.54           $HASP309    INIT  3 INACTIVE ******** C=CBA
20:09:54 10.09.54           $HASP309    INIT  4 INACTIVE ******** C=SHB
20:09:54 10.09.54           $HASP309    INIT  5 INACTIVE ******** C=SBA
20:09:54 10.09.54           $HASP309    INIT  6 INACTIVE ******** C=S
20:09:54 10.09.54           $HASP309    INIT  7 INACTIVE ******** C=E
20:09:54 10.09.54 STC   12  $HASP100 BSPSETPF ON STCINRDR
20:09:54 10.09.54 STC   10  $HASP373 DYNAMASK STARTED
20:09:54 10.09.54 STC   10  IEF403I DYNAMASK - STARTED - TIME=10.09.54
20:09:54 10.09.54 STC   11  $HASP373 BSPPILOT STARTED
20:09:54 10.09.54 STC   11  IEF403I BSPPILOT - STARTED - TIME=10.09.54
20:09:54 10.09.54 STC   12  $HASP373 BSPSETPF STARTED
20:09:54 10.09.54 STC   12  IEF403I BSPSETPF - STARTED - TIME=10.09.54
20:09:54 10.09.54 STC   13  $HASP100 LOGRECI  ON STCINRDR
20:09:54 10.09.54 STC   14  $HASP100 NET      ON STCINRDR
20:09:54 10.09.54 STC   13  $HASP373 LOGRECI  STARTED
20:09:54 10.09.54 STC   13  IEF403I LOGRECI - STARTED - TIME=10.09.54
20:09:54 10.09.54 STC   14  $HASP373 NET      STARTED
20:09:54 10.09.54 STC   14  IEF403I NET - STARTED - TIME=10.09.54
20:09:54 10.09.54           IEE302I 401      ONLINE
20:09:54 10.09.54 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:09:54 10.09.54 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:09:54 10.09.54 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:09:54 10.09.54 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:09:54 10.09.54 STC   14  IST025I  BLDL FAILED FOR IEDIAK   IN VTAMLIB
20:09:54 10.09.54 STC   14  IST025I  BLDL FAILED FOR IEDIAK   IN VTAMLIB
20:09:54 10.09.54 STC   14  IST110I  NETWORK SOLICITOR STARTED
20:09:54 10.09.54 STC   14  IST093I  APPLJRP  ACTIVE
20:09:54 10.09.54 STC   14  IST093I  APPLPIES ACTIVE
20:09:54 10.09.54 STC   14  IST093I  APPLPIAD ACTIVE
20:09:54 10.09.54 STC   14  IST093I  APPLTSO  ACTIVE
20:09:54 10.09.54 STC   14  IST093I  APPLTEST ACTIVE
20:09:54 10.09.54 STC   14  IST093I  LCLMAJRP ACTIVE
20:09:54 10.09.54 STC   14  IST093I  LCLMAJ00 ACTIVE
20:09:54 10.09.54 STC   15  $HASP100 TSO      ON STCINRDR
20:09:54 10.09.54 STC   15  $HASP373 TSO      STARTED
20:09:54 10.09.54 STC   15  IEF403I TSO - STARTED - TIME=10.09.54
20:09:54 10.09.54 STC   16  $HASP100 JRP      ON STCINRDR
20:09:54 10.09.54           $HASP000 OK
20:09:54 10.09.54           $HASP000 OK
20:09:54 10.09.54 STC   16  $HASP373 JRP      STARTED
20:09:54 10.09.54 STC   16  IEF403I JRP - STARTED - TIME=10.09.54
20:09:54 10.09.54 STC   14  IST020I  VTAM INITIALIZATION COMPLETE
20:09:54 10.09.54 STC   14  IEA000I 0C0,IOE,05,0200,400000000001,,,NET     ,10.09.54
20:09:54 10.09.54 STC   14  IEA000I 0C1,IOE,05,0200,400000000001,,,NET     ,10.09.54
20:09:54 10.09.54 STC   14  IEA000I 0C2,IOE,05,0200,400000000001,,,NET     ,10.09.54
20:09:54 10.09.54 STC   10  DMSK00I DYNAMASK DONE ****
20:09:54 10.09.54 STC   10  DMSK06I  START
20:09:55 10.09.54 STC   10  IEF404I DYNAMASK - ENDED - TIME=10.09.54
20:09:55 10.09.54 STC   10  $HASP395 DYNAMASK ENDED
20:09:55 10.09.54 STC   10  $HASP150 DYNAMASK ON PRINTER2        30 LINES
20:09:55 10.09.54           $HASP000 OK
20:09:55 10.09.54           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:55 10.09.54 STC   10  $HASP250 DYNAMASK IS PURGED
20:09:55 10.09.55 STC    9  BSPGM40I -   200 MiB MAINSIZE memory available
20:09:55 10.09.55 STC    9  BSPGM41I -     2 Partitions of    64 MiB each
20:09:55 10.09.55 STC   12  BSPSP91I - Parms passed: NOREPLYU
20:09:55 10.09.55 STC   12  BSPSP93I - PFK definitions will be updated in memory
20:09:55 10.09.55 STC   12  BSPSP22I - Dataset processed: SYS1.PARMLIB
20:09:55 10.09.55 STC   12  BSPSP23I - on volume MVSRES
20:09:55 10.09.55 STC   12  BSPSP21I - Member being processed: SETPFK01
20:09:55 10.09.55 STC   12  +BSPSP98I - Member processed, LASTCC=0000
20:09:55 10.09.55 STC   12  +BSPSP21I - Member being processed: SETPFK02
20:09:55 10.09.55 STC   12  +BSPSP98I - Member processed, LASTCC=0000
20:09:55 10.09.55 STC   12  +BSPSP99I - End of processing, MAXRC=0000
20:09:55 10.09.55 STC   12  IEF404I BSPSETPF - ENDED - TIME=10.09.55
20:09:55 10.09.55 STC   12  $HASP395 BSPSETPF ENDED
20:09:55 10.09.55 STC   12  $HASP150 BSPSETPF ON PRINTER2        34 LINES
20:09:55 10.09.55           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:55 10.09.55 STC   12  $HASP250 BSPSETPF IS PURGED
20:09:55 10.09.55 STC   13  IFC001I  D=3350 N=0B F=01AB0000 L=01AC0005 S=01AB000002 DIP COMPLETE
20:09:55 10.09.55 STC   13  IEF404I LOGRECI - ENDED - TIME=10.09.55
20:09:55 10.09.55 STC   13  $HASP395 LOGRECI  ENDED
20:09:55 10.09.55 STC   13  $HASP150 LOGRECI  ON PRINTER2        20 LINES
20:09:55 10.09.55           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:55 10.09.55 STC   13  $HASP250 LOGRECI  IS PURGED
20:09:55 10.09.55 STC    9  BSPGM78I SVC 120 EP=00FE2EB8; MVS/380
20:09:55 10.09.55 STC   15  IKT007I TCAS ACCEPTING LOGONS
20:09:55 10.09.55 STC   15  IKT005I TCAS IS INITIALIZED
20:09:55 10.09.55 STC   16  JRPI101 INITIALIZATION COMPLETE
20:09:55 10.09.55 STC   16 *01 JRP100I - ENTER ICLOSE TO SHUT DOWN
20:09:55 10.09.55 STC   17  $HASP100 MOUNT    ON STCINRDR
20:09:55 10.09.55 STC   17  $HASP373 MOUNT    STARTED
20:09:55 10.09.55 STC   17  IEF403I MOUNT - STARTED - TIME=10.09.55
20:09:55 10.09.55 STC   17 *IEF233A M 401,PCTOMF,,MOUNT,401
20:09:55 HHCAO003I Firing command: 'script conf/subjobs2.rc'
20:09:55 script conf/subjobs2.rc
20:09:55 hao clear
20:09:55 HHCAO022I All automatic operation rules cleared
20:09:55 # If we get a prompt for a device, cancel it
20:09:55 hao tgt IEF238D
20:09:55 HHCAO016I Target placed at index 0
20:09:55 hao cmd script conf/cancel.rc
20:09:55 HHCAO020I Command placed at index 0
20:09:55 # If we get a prompt for a catalogue password, answer it
20:09:55 hao tgt IEC301A
20:09:55 HHCAO016I Target placed at index 1
20:09:55 hao cmd script conf/secret.rc
20:09:55 HHCAO020I Command placed at index 1
20:09:55 devinit 401 tapes/pctomf.tdf
20:09:55 HHCTA004I 0401: tapes/pctomf.tdf is a Optical Media Attachment (OMA) tape
20:09:55 HHCPN098I Device 0:0401 initialized
20:09:55 # When we see TERMHERC get purged, our job is done
20:09:55 hao tgt HASP250 TERMHERC
20:09:55 HHCAO016I Target placed at index 2
20:09:55 hao cmd script conf/termherc.rc
20:09:55 HHCAO020I Command placed at index 2
20:09:55 # Now submit the job
20:09:55 devinit 00c jcl/hercauto.jcl eof
20:09:55 HHCSD007I Device 000C unbound from socket 127.0.0.1:3505
20:09:55 HHCSD022I Socketdevice listener thread terminated
20:09:55 HHCPN098I Device 0:000C initialized
20:09:55 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:09:55 10.09.55 JOB    1  $HASP100 HERC01A  ON READER1
20:09:55 10.09.55 JOB    2  $HASP100 TERMHERC ON READER1
20:09:55 10.09.55 JOB    1  $HASP373 HERC01A  STARTED - INIT  3 - CLASS C - SYS BSP1
20:09:55 10.09.55 JOB    1  IEF403I HERC01A - STARTED - TIME=10.09.55
20:09:55 10.09.55 STC    9  IEF404I MVS380SS - ENDED - TIME=10.09.55
20:09:55 10.09.55 STC    9  $HASP395 MVS380SS ENDED
20:09:55 10.09.55 STC    9  $HASP150 MVS380SS ON PRINTER2        59 LINES
20:09:55 10.09.55           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:55 10.09.55 STC    9  $HASP250 MVS380SS IS PURGED
20:09:55 10.09.55 STC   17  IEF404I MOUNT - ENDED - TIME=10.09.55
20:09:55 10.09.55 STC   17  $HASP395 MOUNT    ENDED
20:09:55 10.09.55 STC   17  $HASP150 MOUNT    ON PRINTER2        18 LINES
20:09:55 10.09.55           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:55 10.09.55 STC   17  $HASP250 MOUNT    IS PURGED
20:09:55 10.09.55 JOB    1  IEFACTRT - Stepname  Procstep  Program   Retcode
20:09:55 10.09.55 JOB    1  HERC01A    IEFBR14             IEFBR14   RC= 0000
20:09:55 10.09.55 JOB    1  IEF404I HERC01A - ENDED - TIME=10.09.55
20:09:55 10.09.55 JOB    1  $HASP395 HERC01A  ENDED
20:09:55 10.09.55 JOB    1  $HASP150 HERC01A  ON PRINTER1        30 LINES
20:09:55 10.09.55 JOB    1 *$HASP190 HERC01A  SETUP -- PRINTER1 -- F = 0001 -- C = 6    -- T = SN
20:09:55 10.09.55           $HASP000 OK
20:09:55 10.09.55           $HASP160 PRINTER1 INACTIVE - CLASS=AP
20:09:55 10.09.55 JOB    1  $HASP250 HERC01A  IS PURGED
20:09:55 10.09.55          *IEE362A SMF ENTER DUMP FOR SYS1.MANX ON MVSRES
20:09:55 10.09.55           IEE361I SMF DATA LOST - SYS1.MANY NOT AVAILABLE TIME=10.09.55
20:09:55 10.09.55 STC   18  $HASP100 SMFDAILY ON STCINRDR
20:09:55 10.09.55 STC   18  $HASP373 SMFDAILY STARTED
20:09:56 10.09.55 STC   18  IEF403I SMFDAILY - STARTED - TIME=10.09.55
20:09:56 10.09.56 JOB    2  $HASP373 TERMHERC STARTED - INIT  3 - CLASS C - SYS BSP1
20:09:56 10.09.56 JOB    2  IEF403I TERMHERC - STARTED - TIME=10.09.56
20:09:56 10.09.56 JOB    2  IEFACTRT - Stepname  Procstep  Program   Retcode
20:09:56 10.09.56 JOB    2  TERMHERC   S1                  IEBGENER  RC= 0000
20:09:56 10.09.56 JOB    2  IEF404I TERMHERC - ENDED - TIME=10.09.56
20:09:56 10.09.56 JOB    2  $HASP395 TERMHERC ENDED
20:09:56 10.09.56 STC   18  IEF453I SMFDAILY - JOB FAILED - JCL ERROR - TIME=10.09.56
20:09:56 10.09.56 STC   18  $HASP395 SMFDAILY ENDED
20:09:56 10.09.56 STC   18  $HASP150 SMFDAILY ON PRINTER2        25 LINES
20:09:56 10.09.56           IEE132I START COMMAND DEVICE ALLOCATION ERROR
20:09:56 10.09.56           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:56 10.09.56 STC   18  $HASP250 SMFDAILY IS PURGED
20:09:56 10.09.56           $HASP309    INIT  3 INACTIVE ******** C=CBA
20:09:56 10.09.56 JOB    2  $HASP150 TERMHERC ON PRINTER1         1 LINE
20:09:56 10.09.56 JOB    2  $HASP150 TERMHERC ON PRINTER2        44 LINES
20:09:56 10.09.56           $HASP160 PRINTER1 INACTIVE - CLASS=AP
20:09:56 10.09.56           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:09:56 10.09.56 JOB    2  $HASP250 TERMHERC IS PURGED
20:09:56 HHCAO003I Firing command: 'script conf/termherc.rc'
20:09:56 script conf/termherc.rc
20:09:56 # we put in an unnecessary "stopall" to prevent Hercules
20:09:56 # (3.07 and 3.12 and 4.0 rc0 at least) from randomly
20:09:56 # hanging in the "quit" command in about 5% of runs.
20:09:56 # It is still unclear why Hercules is hanging
20:09:56 stopall
20:09:56 quit
20:09:56 HHCIN900I Begin Hercules shutdown
20:09:56 HHCIN901I Releasing configuration
20:09:56 HHCCP008I CPU0000 thread ended: tid=00004300, pid=9596
20:09:56 HHCCF047I Subchannel 0:0000 detached
20:09:56 HHCCF047I Subchannel 0:0001 detached
20:09:56 HHCTT003I Timer thread ended
20:09:56 HHCCF047I Subchannel 0:0002 detached
20:09:56 HHCCF047I Subchannel 0:0003 detached
20:09:56 HHCCF047I Subchannel 0:0004 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   18772039   2%   21       1116      83      41    1158     2123
20:09:56 HHCCD214I                                                         154       22
20:09:56 HHCCD215I dasd/mvsres.148
20:09:56 HHCCD216I [0]   15179178   0%    1 ro     932       0      20
20:09:56 HHCCD217I shadow/mvsres_*.148
20:09:56 HHCCD218I [1]    3592861   7%   20        184      83      21
20:09:56 HHCCF047I Subchannel 0:0005 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/sort01.131
20:09:56 HHCCD216I [0]      34336   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/sort01_*.131
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0006 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/sort02.132
20:09:56 HHCCD216I [0]      34336   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/sort02_*.132
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0007 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/sort03.133
20:09:56 HHCCD216I [0]      34336   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/sort03_*.133
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0008 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/sort04.134
20:09:56 HHCCD216I [0]      34336   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/sort04_*.134
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0009 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/sort05.135
20:09:56 HHCCD216I [0]      34336   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/sort05_*.135
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:000A detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/sort06.136
20:09:56 HHCCD216I [0]      34336   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/sort06_*.136
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:000B detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   51828709   0%   72          1       0       2       0        1
20:09:56 HHCCD215I dasd/work00.140
20:09:56 HHCCD216I [0]     141963   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/work00_*.140
20:09:56 HHCCD218I [1]   51686746   0%   71          0       0       1
20:09:56 HHCCF047I Subchannel 0:000C detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    5692530  15%    4          1       0       1       0        1
20:09:56 HHCCD215I dasd/hasp00.152
20:09:56 HHCCD216I [0]    5692530  15%    4 rw       1       0       1
20:09:56 HHCCF047I Subchannel 0:000D detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   14388909  40%  376       1821    1391      10    1060     2361
20:09:56 HHCCD214I                                                         520       57
20:09:56 HHCCD215I dasd/page00.160
20:09:56 HHCCD216I [0]   14388909  40%  376 rw    1821    1391      10
20:09:56 HHCCF047I Subchannel 0:000E detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    3444953   4%   42         45     433      32      36       56
20:09:56 HHCCD214I                                                          25        1
20:09:56 HHCCD215I dasd/page01.161
20:09:56 HHCCD216I [0]    3444953   4%   42 rw      45     433      32
20:09:56 HHCCF047I Subchannel 0:000F detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    3301952   6%   96         43      66       7      31       53
20:09:56 HHCCD214I                                                          21        3
20:09:56 HHCCD215I dasd/page02.162
20:09:56 HHCCD216I [0]    3301952   6%   96 rw      43      66       7
20:09:56 HHCCF047I Subchannel 0:0010 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   53776727   0%  114          1       0       1       0        1
20:09:56 HHCCD215I dasd/work01.170
20:09:56 HHCCD216I [0]   53776727   0%  114 rw       1       0       1
20:09:56 HHCCF047I Subchannel 0:0011 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   68116677   1%  581          1       0       2       0        1
20:09:56 HHCCD215I dasd/work02.180
20:09:56 HHCCD216I [0]     113799   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/work02_*.180
20:09:56 HHCCD218I [1]   68002878   1%  580          0       0       1
20:09:56 HHCCF047I Subchannel 0:0012 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   59274083   7%  586          1       0       2       0        1
20:09:56 HHCCD215I dasd/work03.190
20:09:56 HHCCD216I [0]     142257   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/work03_*.190
20:09:56 HHCCD218I [1]   59131826   7%  585          0       0       1
20:09:56 HHCCF047I Subchannel 0:0013 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   15480003   0%    1         17       1       5      15       31
20:09:56 HHCCD214I                                                           2        1
20:09:56 HHCCD215I dasd/mvscat.191
20:09:56 HHCCD216I [0]    3658727   0%    1 ro       8       0       3
20:09:56 HHCCD217I shadow/mvscat_*.191
20:09:56 HHCCD218I [1]   11821276   0%    0          9       1       2
20:09:56 HHCCF047I Subchannel 0:0014 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   99779712  19% 1447          1       0       2       0        1
20:09:56 HHCCD215I dasd/seasik.192
20:09:56 HHCCD216I [0]   16164960   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/seasik_*.192
20:09:56 HHCCD218I [1]   83614752  23% 1447          0       0       1
20:09:56 HHCCF047I Subchannel 0:0015 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]  168333900   4% 5454          1       0       1       0        1
20:09:56 HHCCD215I dasd/mvssrc.193
20:09:56 HHCCD216I [0]  168333900   4% 5454 rw       1       0       1
20:09:56 HHCCF047I Subchannel 0:0016 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   44376153   2%   13         30       0      10     110      135
20:09:56 HHCCD214I                                                           5        1
20:09:56 HHCCD215I dasd/pub000.240
20:09:56 HHCCD216I [0]    7591138   3%    1 ro      13       0       6
20:09:56 HHCCD217I shadow/pub000_*.240
20:09:56 HHCCD218I [1]   36785015   2%   12         17       0       4
20:09:56 HHCCF047I Subchannel 0:0017 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   25795118   0%    3          5      28       5       1        6
20:09:56 HHCCD215I dasd/mvsdlb.248
20:09:56 HHCCD216I [0]   25471539   0%    1 ro       3       0       3
20:09:56 HHCCD217I shadow/mvsdlb_*.248
20:09:56 HHCCD218I [1]     323579  43%    2          2      28       2
20:09:56 HHCCF047I Subchannel 0:0018 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]  102443308  14%  326          1       0       2       0        1
20:09:56 HHCCD215I dasd/pub002.280
20:09:56 HHCCD216I [0]    2171954  29%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/pub002_*.280
20:09:56 HHCCD218I [1]  100271354  13%  325          0       0       1
20:09:56 HHCCF047I Subchannel 0:0019 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]     143545   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/tmptmp.2b1
20:09:56 HHCCD216I [0]     142257   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/tmptmp_*.2b1
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:001A detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]     143545   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/xmit90.2b2
20:09:56 HHCCD216I [0]     142257   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/xmit90_*.2b2
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:001B detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    6099280   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/start1.150
20:09:56 HHCCD216I [0]    6098016   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/start1_*.150
20:09:56 HHCCD218I [1]       1264   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:001C detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    4595678   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/spool0.151
20:09:56 HHCCD216I [0]    4594414   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/spool0_*.151
20:09:56 HHCCD218I [1]       1264   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:001D detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   50622559   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/cbt000.340
20:09:56 HHCCD216I [0]   50621271   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/cbt000_*.340
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:001E detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   53836805   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/cbt001.341
20:09:56 HHCCD216I [0]   53835517   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/cbt001_*.341
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:001F detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   46082980   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/cbt002.342
20:09:56 HHCCD216I [0]   46081692   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/cbt002_*.342
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0020 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   27241782   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/cbtcat.343
20:09:56 HHCCD216I [0]   27240494   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/cbtcat_*.343
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0021 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   46003602   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/src000.348
20:09:56 HHCCD216I [0]   46002314   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/src000_*.348
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0022 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   45707725   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/src001.349
20:09:56 HHCCD216I [0]   45706437   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/src001_*.349
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0023 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]   44403653   0%    0          1       0       1       0        1
20:09:56 HHCCD215I dasd/src002.34a
20:09:56 HHCCD216I [0]   44402365   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/src002_*.34a
20:09:56 HHCCD218I [1]       1288   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:0024 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    6321674   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/srccat.34b
20:09:56 HHCCD216I [0]    6310767   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/srccat_*.34b
20:09:56 HHCCD218I [1]      10907   7%    1          0       0       0
20:09:56 HHCCF047I Subchannel 0:0025 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    1118801  11%    2          1       0       2       0        1
20:09:56 HHCCD215I dasd/smp001.149
20:09:56 HHCCD216I [0]     774654   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/smp001_*.149
20:09:56 HHCCD218I [1]     344147  38%    2          0       0       1
20:09:56 HHCCF047I Subchannel 0:0026 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    1621663  24%   12          1       0       2       0        1
20:09:56 HHCCD215I dasd/smp002.14a
20:09:56 HHCCD216I [0]     856740   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/smp002_*.14a
20:09:56 HHCCD218I [1]     764923  51%   12          0       0       1
20:09:56 HHCCF047I Subchannel 0:0027 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    1077383  19%    3          1       0       2       0        1
20:09:56 HHCCD215I dasd/smp003.14b
20:09:56 HHCCD216I [0]     592555   0%    0 ro       1       0       1
20:09:56 HHCCD217I shadow/smp003_*.14b
20:09:56 HHCCD218I [1]     484828  42%    3          0       0       1
20:09:56 HHCCF047I Subchannel 0:0028 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    3437923  36%    4          1       0       2       0        1
20:09:56 HHCCD215I dasd/smp004.14c
20:09:56 HHCCD216I [0]    2916431  35%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/smp004_*.14c
20:09:56 HHCCD218I [1]     521492  41%    3          0       0       1
20:09:56 HHCCF047I Subchannel 0:0029 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD211I                                                  readaheads   misses
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    4658061   4%  239        121     336      62     766      861
20:09:56 HHCCD214I                                                          28       18
20:09:56 HHCCD215I dasd/js2sp0.700
20:09:56 HHCCD216I [0]     142291   0%    1 ro       0       0       1
20:09:56 HHCCD217I shadow/js2sp0_*.700
20:09:56 HHCCD218I [1]    4515770   5%  238        121     336      61
20:09:56 HHCCF047I Subchannel 0:002A detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]     715081   0%    1          1       0       2       0        1
20:09:56 HHCCD215I dasd/dlicat.138
20:09:56 HHCCD216I [0]      36630   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/dlicat_*.138
20:09:56 HHCCD218I [1]     678451   0%    0          0       0       1
20:09:56 HHCCF047I Subchannel 0:002B detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]     880289   0%    1          1       0       2       0        1
20:09:56 HHCCD215I dasd/dlisys.139
20:09:56 HHCCD216I [0]      36630   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/dlisys_*.139
20:09:56 HHCCD218I [1]     843659   0%    0          0       0       1
20:09:56 HHCCF047I Subchannel 0:002C detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      43495   0%    1          1       0       2       0        1
20:09:56 HHCCD215I dasd/dli000.13a
20:09:56 HHCCD216I [0]      36630   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/dli000_*.13a
20:09:56 HHCCD218I [1]       6865   0%    0          0       0       1
20:09:56 HHCCF047I Subchannel 0:002D detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      46911   0%    1          1       0       2       0        1
20:09:56 HHCCD215I dasd/dli001.13b
20:09:56 HHCCD216I [0]      36630   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/dli001_*.13b
20:09:56 HHCCD218I [1]      10281   0%    0          0       0       1
20:09:56 HHCCF047I Subchannel 0:002E detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]      37718   0%    1          1       0       1       0        1
20:09:56 HHCCD215I dasd/dli002.13c
20:09:56 HHCCD216I [0]      36630   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/dli002_*.13c
20:09:56 HHCCD218I [1]       1088   0%    0          0       0       0
20:09:56 HHCCF047I Subchannel 0:002F detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]    1233964   0%    1          1       0       2       0        1
20:09:56 HHCCD215I dasd/js3sp0.710
20:09:56 HHCCD216I [0]     141965   0%    1 ro       1       0       1
20:09:56 HHCCD217I shadow/js3sp0_*.710
20:09:56 HHCCD218I [1]    1091999   0%    0          0       0       1
20:09:56 HHCCF047I Subchannel 0:0030 detached
20:09:56 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
20:09:56 HHCCD212I --------------------------------------------------------------------
20:09:56 HHCCD213I [*]     951745   0%    2          1       0       2       0        1
20:09:56 HHCCD215I dasd/js3res.711
20:09:56 HHCCD216I [0]     142093   0%    2 ro       1       0       1
20:09:56 HHCCD217I shadow/js3res_*.711
20:09:56 HHCCD218I [1]     809652   0%    0          0       0       1
20:09:56 HHCCD011I Readahead thread 2 stopping: tid=00004E94, pid=9596
20:09:56 HHCCD011I Readahead thread 1 stopping: tid=00003A34, pid=9596
20:09:56 HHCCD013I Garbage collector thread stopping: tid=00001A5C, pid=9596
20:09:56 HHCCD012I Writer thread 1 stopping: tid=00002C1C, pid=9596
20:09:56 HHCCD012I Writer thread 2 stopping: tid=0000175C, pid=9596
20:09:56 HHCCF047I Subchannel 0:0031 detached
20:09:56 HHCCF047I Subchannel 0:0032 detached
20:09:56 HHCCF047I Subchannel 0:0033 detached
20:09:56 HHCCF047I Subchannel 0:0034 detached
20:09:56 HHCCF047I Subchannel 0:0035 detached
20:09:56 HHCCF047I Subchannel 0:0036 detached
20:09:56 HHCTE004I Console connection thread terminated
20:09:56 HHCCF047I Subchannel 0:0037 detached
20:09:56 HHCCF047I Subchannel 0:0038 detached
20:09:56 HHCCF047I Subchannel 0:0039 detached
20:09:56 HHCCF047I Subchannel 0:003A detached
20:09:56 HHCCF047I Subchannel 0:003B detached
20:09:56 HHCCF047I Subchannel 0:003C detached
20:09:56 HHCIN902I Configuration release complete
20:09:56 HHCIN903I Calling termination routines
20:09:56 HHCHD900I Begin shutdown sequence
20:09:56 HHCHD901I Calling panel_cleanup
20:09:56 HHCHD902I panel_cleanup complete
20:09:56 HHCHD901I Calling console_shutdown
20:09:56 HHCHD902I console_shutdown complete
20:09:56 HHCHD901I Calling term_sockdev
20:09:56 HHCHD902I term_sockdev complete
20:09:56 HHCHD901I Calling hdl_term
20:09:56 HHCHD950I Begin HDL termination sequence
20:09:56 HHCHD951I Calling module *Hercules cleanup routine
20:09:56 HHCHD952I Module *Hercules cleanup complete
20:09:56 HHCHD959I HDL Termination sequence complete
20:09:56 HHCHD902I hdl_term complete
20:09:56 HHCHD901I Calling logger_term
20:09:56 HHCLG014I logger thread terminating
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
Ok, I decided to issue my own mount
command to see what happened.

Everything worked, but I got this
strange message:

18:58:49 08.58.49 STC   19  IEE135I ERROR - REQUESTED DEVICE RESERVED

Full log below.

I'm wondering if, in MVS380SS, I should
just issue the mount command twice.

Or maybe I should have tried another
V ONLINE. I'll see if I can reproduce the
problem so that I can do another
V ONLINE.

BFN. Paul.



20:10:18 Hercules Version 3.07:380-4.x
20:10:18 (c)Copyright 1999-2010 by Roger Bowler, Jan Jaeger, and others
20:10:18 Built on Dec  7 2016 at 12:43:32
20:10:18 Build information:
20:10:18   Windows (MSVC) build for AMD64
20:10:18   Modes: S/370 S/380 ESA/390 z/Arch
20:10:18   Max CPU Engines: 8
20:10:18   Using fthreads instead of pthreads
20:10:18   Dynamic loading support
20:10:18   Using shared libraries
20:10:18   HTTP Server support
20:10:18   No SIGABEND handler
20:10:18   Regular Expressions support
20:10:18   Automatic Operator support
20:10:18   Machine dependent assists: cmpxchg1 cmpxchg4 cmpxchg8
20:10:18 Running on PAUL-DELL Windows_NT-6.2 AMD64 MP=8
20:10:18 HHCHD018I Loadable module directory is hercules
20:10:18 Crypto module loaded (c) Copyright Bernard van der Helm, 2003-2010
20:10:18   Active: Message Security Assist
20:10:18           Message Security Assist Extension 1
20:10:18           Message Security Assist Extension 2
20:10:18 HHCPN210I Default Allowed AUTOMOUNT directory = "c:\mvs380\"
20:10:18 HHCCF065I Hercules: tid=00003290, pid=19624, pgid=19624, priority=8
20:10:18 HHCHT001I HTTP listener thread started: tid=00004660, pid=19624
20:10:18 HHCHT013I Using HTTPROOT directory "c:\herc380\html\"
20:10:18 HHCHT006I Waiting for HTTP requests on port 8081
20:10:18 HHCSD004I Device 000C bound to socket 127.0.0.1:3505
20:10:18 HHCSD020I Socketdevice listener thread started: tid=00002020, pid=19624
20:10:18 HHCCF083I conf/mvs380.conf Including conf/mvs380_DASD.conf at 123 .
20:10:18 HHCDA004I opening dasd/mvsres.148 readonly
20:10:18 HHCDA020I dasd/mvsres.148 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/sort01.131 readonly
20:10:18 HHCDA020I dasd/sort01.131 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort02.132 readonly
20:10:18 HHCDA020I dasd/sort02.132 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort03.133 readonly
20:10:18 HHCDA020I dasd/sort03.133 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort04.134 readonly
20:10:18 HHCDA020I dasd/sort04.134 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort05.135 readonly
20:10:18 HHCDA020I dasd/sort05.135 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/sort06.136 readonly
20:10:18 HHCDA020I dasd/sort06.136 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/work00.140 readonly
20:10:18 HHCDA020I dasd/work00.140 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA020I dasd/hasp00.152 cyls=404 heads=19 tracks=7676 trklen=13312
20:10:18 HHCDA020I dasd/page00.160 cyls=698 heads=12 tracks=8376 trklen=8704
20:10:18 HHCDA020I dasd/page01.161 cyls=698 heads=12 tracks=8376 trklen=8704
20:10:18 HHCDA020I dasd/page02.162 cyls=698 heads=12 tracks=8376 trklen=8704
20:10:18 HHCDA020I dasd/work01.170 cyls=962 heads=12 tracks=11544 trklen=35840
20:10:18 HHCDA004I opening dasd/work02.180 readonly
20:10:18 HHCDA020I dasd/work02.180 cyls=886 heads=15 tracks=13290 trklen=47616
20:10:18 HHCDA004I opening dasd/work03.190 readonly
20:10:18 HHCDA020I dasd/work03.190 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/mvscat.191 readonly
20:10:18 HHCDA020I dasd/mvscat.191 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/seasik.192 readonly
20:10:18 HHCDA020I dasd/seasik.192 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA020I dasd/mvssrc.193 cyls=2226 heads=15 tracks=33390 trklen=56832
20:10:18 HHCDA002E 01B4:File not found or invalid 'dasd/pub004.1b4'
20:10:18 HHCCF044E Initialization failed for device 01B4
20:10:18 HHCDA004I opening dasd/pub000.240 readonly
20:10:18 HHCDA020I dasd/pub000.240 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/mvsdlb.248 readonly
20:10:18 HHCDA020I dasd/mvsdlb.248 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/pub002.280 readonly
20:10:18 HHCDA020I dasd/pub002.280 cyls=1772 heads=15 tracks=26580 trklen=47616
20:10:18 HHCDA004I opening dasd/tmptmp.2b1 readonly
20:10:18 HHCDA020I dasd/tmptmp.2b1 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/xmit90.2b2 readonly
20:10:18 HHCDA020I dasd/xmit90.2b2 cyls=1114 heads=15 tracks=16710 trklen=56832
20:10:18 HHCDA004I opening dasd/start1.150 readonly
20:10:18 HHCDA020I dasd/start1.150 cyls=808 heads=19 tracks=15352 trklen=13312
20:10:18 HHCDA004I opening dasd/spool0.151 readonly
20:10:18 HHCDA020I dasd/spool0.151 cyls=808 heads=19 tracks=15352 trklen=13312
20:10:18 HHCDA004I opening dasd/cbt000.340 readonly
20:10:18 HHCDA020I dasd/cbt000.340 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/cbt001.341 readonly
20:10:18 HHCDA020I dasd/cbt001.341 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/cbt002.342 readonly
20:10:18 HHCDA020I dasd/cbt002.342 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/cbtcat.343 readonly
20:10:18 HHCDA020I dasd/cbtcat.343 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/src000.348 readonly
20:10:18 HHCDA020I dasd/src000.348 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/src001.349 readonly
20:10:18 HHCDA020I dasd/src001.349 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/src002.34a readonly
20:10:18 HHCDA020I dasd/src002.34a cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/srccat.34b readonly
20:10:18 HHCDA020I dasd/srccat.34b cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/smp001.149 readonly
20:10:18 HHCDA020I dasd/smp001.149 cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/smp002.14a readonly
20:10:18 HHCDA020I dasd/smp002.14a cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/smp003.14b readonly
20:10:18 HHCDA020I dasd/smp003.14b cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/smp004.14c readonly
20:10:18 HHCDA020I dasd/smp004.14c cyls=560 heads=30 tracks=16800 trklen=19456
20:10:18 HHCDA004I opening dasd/js2sp0.700 readonly
20:10:18 HHCDA020I dasd/js2sp0.700 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/dlicat.138 readonly
20:10:18 HHCDA020I dasd/dlicat.138 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dlisys.139 readonly
20:10:18 HHCDA020I dasd/dlisys.139 cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dli000.13a readonly
20:10:18 HHCDA020I dasd/dli000.13a cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dli001.13b readonly
20:10:18 HHCDA020I dasd/dli001.13b cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/dli002.13c readonly
20:10:18 HHCDA020I dasd/dli002.13c cyls=200 heads=20 tracks=4000 trklen=7680
20:10:18 HHCDA004I opening dasd/js3sp0.710 readonly
20:10:18 HHCDA020I dasd/js3sp0.710 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCDA004I opening dasd/js3res.711 readonly
20:10:18 HHCDA020I dasd/js3res.711 cyls=555 heads=30 tracks=16650 trklen=19456
20:10:18 HHCTE001I Console connection thread started: tid=00004CF0, pid=19624
20:10:18 HHCTE003I Waiting for console connection on port 3270
20:10:18 HHCTA066I 0400: option 'level' accepted.
20:10:18 HHCTA066I 0401: option 'level' accepted.
20:10:18 HHCTA066I 0402: option 'level' accepted.
20:10:18 HHCTA066I 0403: option 'level' accepted.
20:10:18 HHCCP002I CPU0000 thread started: tid=00005304, pid=19624, priority=0
20:10:18 HHCCP003I CPU0000 architecture mode S/380
20:10:18 HHCPN001I Control panel thread started: tid=00003290, pid=19624
20:10:18 HHCTT002I Timer thread started: tid=00004D64, pid=19624, priority=-20
20:10:18 HHCPN008I Script file processing started using file "conf/auto_run.rc"
20:10:18 devlist
20:10:18 0:000C 3505 127.0.0.1:3505 sockdev ascii trunc eof
20:10:18      (no one currently connected)
20:10:18 0:000D 3525 pch/pch00d.txt ascii crlf
20:10:18 0:000E 1403 prt/prt00e.txt crlf noclear
20:10:18 0:000F 1403 prt/prt00f.txt crlf noclear
20:10:18 0:001F 3215 *syscons cmdpref(/)
20:10:18 0:00C0 3270  
20:10:18 0:00C1 3270  
20:10:18 0:00C2 3270  
20:10:18 0:00C7 3287  
20:10:18 0:010C 3505 jcl/dummy eof
20:10:18 0:0131 2314 dasd/sort01.131 [200 cyls] open
20:10:18 0:0132 2314 dasd/sort02.132 [200 cyls] open
20:10:18 0:0133 2314 dasd/sort03.133 [200 cyls] open
20:10:18 0:0134 2314 dasd/sort04.134 [200 cyls] open
20:10:18 0:0135 2314 dasd/sort05.135 [200 cyls] open
20:10:18 0:0136 2314 dasd/sort06.136 [200 cyls] open
20:10:18 0:0138 2314 dasd/dlicat.138 [200 cyls] open
20:10:18 0:0139 2314 dasd/dlisys.139 [200 cyls] open
20:10:18 0:013A 2314 dasd/dli000.13a [200 cyls] open
20:10:18 0:013B 2314 dasd/dli001.13b [200 cyls] open
20:10:18 0:013C 2314 dasd/dli002.13c [200 cyls] open
20:10:18 0:0140 3350 dasd/work00.140 [560 cyls] open
20:10:18 0:0148 3350 dasd/mvsres.148 [560 cyls] open
20:10:18 0:0149 3350 dasd/smp001.149 [560 cyls] open
20:10:18 0:014A 3350 dasd/smp002.14a [560 cyls] open
20:10:18 0:014B 3350 dasd/smp003.14b [560 cyls] open
20:10:18 0:014C 3350 dasd/smp004.14c [560 cyls] open
20:10:18 0:0150 3330 dasd/start1.150 [808 cyls] open
20:10:18 0:0151 3330 dasd/spool0.151 [808 cyls] open
20:10:18 0:0152 3330 dasd/hasp00.152 [404 cyls] open
20:10:18 0:0160 3340 dasd/page00.160 [698 cyls] open
20:10:18 0:0161 3340 dasd/page01.161 [698 cyls] open
20:10:18 0:0162 3340 dasd/page02.162 [698 cyls] open
20:10:18 0:0170 3375 dasd/work01.170 [962 cyls] open
20:10:18 0:01A0 3380 dasd/work02.180 [886 cyls] open
20:10:18 0:01B0 3390 dasd/work03.190 [1114 cyls] open
20:10:18 0:01B1 3390 dasd/mvscat.191 [1114 cyls] open
20:10:18 0:01B2 3390 dasd/seasik.192 [1114 cyls] open
20:10:18 0:01B3 3390 dasd/mvssrc.193 [2226 cyls] open
20:10:18 0:01C7 3287  
20:10:18 0:0240 3350 dasd/pub000.240 [560 cyls] open
20:10:18 0:0248 3350 dasd/mvsdlb.248 [560 cyls] open
20:10:18 0:02A0 3380 dasd/pub002.280 [1772 cyls] open
20:10:18 0:02B1 3390 dasd/tmptmp.2b1 [1114 cyls] open
20:10:18 0:02B2 3390 dasd/xmit90.2b2 [1114 cyls] open
20:10:18 0:030E 1403 log/hardcopy.log crlf noclear
20:10:18 0:0340 3350 dasd/cbt000.340 [555 cyls] open
20:10:18 0:0341 3350 dasd/cbt001.341 [555 cyls] open
20:10:18 0:0342 3350 dasd/cbt002.342 [555 cyls] open
20:10:18 0:0343 3350 dasd/cbtcat.343 [555 cyls] open
20:10:18 0:0348 3350 dasd/src000.348 [555 cyls] open
20:10:18 0:0349 3350 dasd/src001.349 [555 cyls] open
20:10:18 0:034A 3350 dasd/src002.34a [555 cyls] open
20:10:18 0:034B 3350 dasd/srccat.34b [555 cyls] open
20:10:18 0:0400 3420 *
20:10:18 0:0401 3420 *
20:10:18 0:0402 3420 *
20:10:18 0:0403 3420 *
20:10:18 0:0700 3350 dasd/js2sp0.700 [555 cyls] open
20:10:18 0:0710 3350 dasd/js3sp0.710 [555 cyls] open
20:10:18 0:0711 3350 dasd/js3res.711 [555 cyls] open
20:10:18 logopt timestamp
20:10:18 HHCPN197I Log option set: TIMESTAMP
20:10:18 panrate 1000
20:10:18 # really need to wait for MVS380SS, but if building MVS/380
20:10:18 # or some other stuffup with parms, need to trigger on an
20:10:18 # alternative. First one hit will clear targets.
20:10:18 # If we get a prompt for a device, cancel it
20:10:18 hao tgt IEF238D
20:10:18 HHCAO016I Target placed at index 0
20:10:18 hao cmd script conf/cancel.rc
20:10:18 HHCAO020I Command placed at index 0
20:10:18 hao tgt HASP395 DYNAMASK
20:10:18 HHCAO016I Target placed at index 1
20:10:18 hao cmd script conf/subjobs_slow.rc
20:10:18 HHCAO020I Command placed at index 1
20:10:18 hao tgt HASP373 MVS380SS
20:10:18 HHCAO016I Target placed at index 2
20:10:18 hao cmd script conf/subjobs_fast.rc
20:10:18 HHCAO020I Command placed at index 2
20:10:18 hao tgt HASP436 REPLY
20:10:18 HHCAO016I Target placed at index 3
20:10:18 hao cmd script conf/replyy.rc
20:10:18 HHCAO020I Command placed at index 3
20:10:18 devinit 402 tapes/mftopc.het
20:10:18 HHCTA004I 0402: tapes/mftopc.het is a Hercules Emulated Tape file
20:10:18 HHCPN098I Device 0:0402 initialized
20:10:18 devinit 403 tapes/awstap.aws
20:10:18 HHCTA004I 0403: tapes/awstap.aws is a Hercules Emulated Tape file
20:10:18 HHCPN098I Device 0:0403 initialized
20:10:18 # co will give you a cold start of JES2 which is useful in
20:10:18 # automated runs
20:10:18 /(001F) r 00,clpa,cmd=co
20:10:18 #/r 00,clpa
20:10:18 ipl 148
20:10:18 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:10:18 HHCAO001I Hercules Automatic Operator thread started;
20:10:18           tid=0000531C, pri=0, pid=19624
20:10:18 IEA101A SPECIFY SYSTEM PARMS FOR MVS/380 1.2 v03.8 TK3+
20:10:18 HHCCD001I Readahead thread 1 started: tid=00003E5C, pid=19624
20:10:18 HHCCD001I Readahead thread 2 started: tid=00005068, pid=19624
20:10:18 IEA940I THE FOLLOWING PAGE DATA SETS ARE IN USE
20:10:18  PLPA ... SYS1.PAGELPA
20:10:19  COMMON . SYS1.PAGECSA
20:10:19  DUPLEX . SYS1.DUPLEX
20:10:19  LOCAL .. SYS1.PAGEL01
20:10:19  LOCAL .. SYS1.PAGEL02
20:10:19  LOCAL .. SYS1.PAGEL03
20:10:19 HHCCD002I Writer thread 1 started: tid=0000213C, pid=19624
20:10:19 HHCCD002I Writer thread 2 started: tid=00004654, pid=19624
20:10:19 HHCCD003I Garbage collector thread started: tid=0000467C, pid=19624
20:10:24 *IEE362A SMF ENTER DUMP FOR SYS1.MANY ON MVSRES
20:10:24  IEE360I SMF NOW RECORDING ON SYS1.MANX ON MVSRES TIME=10.10.24
20:10:24  IGF992I  MIH INIT COMPLETE, PRI=000300, SEC=000015
20:10:24  IEF677I WARNING MESSAGE(S) FOR JOB JES2     ISSUED
20:10:25 *00 $HASP436 REPLY Y OR N TO CONFIRM CHECKPOINT RECORD CHANGE
20:10:25 HHCAO003I Firing command: 'script conf/replyy.rc'
20:10:25 script conf/replyy.rc
20:10:25 HHCPN011I Pausing SCRIPT file processing for 1 seconds...
20:10:26 HHCPN012I Resuming SCRIPT file processing...
20:10:26 /(001F) reply 00,y
20:10:26 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:10:26  IEE600I REPLY TO 00 IS;SUPPRESSED
20:10:26  $HASP493 JES2 COLD-START IS IN PROGRESS
20:10:28  $HASP412 MAXIMUM OF 1   READER(S)  EXCEEDED
20:10:28 10.10.28           IEE041I THE SYSTEM LOG IS NOW ACTIVE
20:10:28 10.10.28           IEE305I X        COMMAND INVALID
20:10:28 10.10.28           IEE450I 10.10.28 UNIT STATUS 154
20:10:28  UNIT TYPE STATUS  VOLSER VOLSTATE   UNIT TYPE STATUS  VOLSER VOLSTATE
20:10:28  400  3400 O                  /REMOV 401  3400 O                  /REMOV
20:10:28  402  3400 O                  /REMOV
20:10:28 10.10.28           IEE302I 00E      ONLINE
20:10:28 10.10.28           IEE302I 00F      ONLINE
20:10:28 10.10.28           IEE313I 30E     UNIT REF INVALID
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           IEE305I X        COMMAND INVALID
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP000 OK
20:10:28 10.10.28           $HASP160 PRINTER1 INACTIVE - CLASS=AP
20:10:28 10.10.28           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:28 10.10.28           $HASP160 PUNCH1   INACTIVE - CLASS=B
20:10:28 10.10.28 STC    2  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    2  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    2  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    3  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    3  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    3  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    4  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    4  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    4  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    5  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    5  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    5  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    6  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    6  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    6  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    7  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    7  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    7  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    8  $HASP100 INIT     ON STCINRDR
20:10:28 10.10.28 STC    8  $HASP373 INIT     STARTED
20:10:28 10.10.28 STC    8  IEF403I INIT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC    9  $HASP100 MVS380SS ON STCINRDR
20:10:28 10.10.28 STC    9  $HASP373 MVS380SS STARTED
20:10:28 HHCAO003I Firing command: 'script conf/subjobs_fast.rc'
20:10:28 script conf/subjobs_fast.rc
20:10:28 # Now we know that MVS380SS has started.
20:10:28 # When it issues a mount request, we can continue
20:10:28 hao clear
20:10:28 HHCAO022I All automatic operation rules cleared
20:10:28 hao tgt IEF233A
20:10:28 HHCAO016I Target placed at index 0
20:10:28 hao cmd script conf/subjobs2.rc
20:10:28 HHCAO020I Command placed at index 0
20:10:28 HHCPN013I EOF reached on SCRIPT file. Processing complete.
20:10:28 10.10.28 STC    9  IEF403I MVS380SS - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC   10  $HASP100 DYNAMASK ON STCINRDR
20:10:28 10.10.28 STC   10  $HASP373 DYNAMASK STARTED
20:10:28 10.10.28 STC   10  IEF403I DYNAMASK - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC   11  $HASP100 BSPPILOT ON STCINRDR
20:10:28 10.10.28 STC   11  $HASP373 BSPPILOT STARTED
20:10:28 10.10.28 STC   11  IEF403I BSPPILOT - STARTED - TIME=10.10.28
20:10:28 10.10.28 STC   12  $HASP100 BSPSETPF ON STCINRDR
20:10:28 10.10.28 STC   13  $HASP100 LOGRECI  ON STCINRDR
20:10:28 10.10.28           $HASP309    INIT  1 INACTIVE ******** C=A
20:10:29 10.10.28           $HASP309    INIT  2 INACTIVE ******** C=BA
20:10:29 10.10.28           $HASP309    INIT  3 INACTIVE ******** C=CBA
20:10:29 10.10.28           $HASP309    INIT  4 INACTIVE ******** C=SHB
20:10:29 10.10.28           $HASP309    INIT  5 INACTIVE ******** C=SBA
20:10:29 10.10.28           $HASP309    INIT  6 INACTIVE ******** C=S
20:10:29 10.10.28           $HASP309    INIT  7 INACTIVE ******** C=E
20:10:29 10.10.29 STC   14  $HASP100 NET      ON STCINRDR
20:10:29 10.10.29 STC   12  $HASP373 BSPSETPF STARTED
20:10:29 10.10.29 STC   12  IEF403I BSPSETPF - STARTED - TIME=10.10.29
20:10:29 10.10.29 STC   13  $HASP373 LOGRECI  STARTED
20:10:29 10.10.29 STC   13  IEF403I LOGRECI - STARTED - TIME=10.10.29
20:10:29 10.10.29 STC   14  $HASP373 NET      STARTED
20:10:29 10.10.29 STC   14  IEF403I NET - STARTED - TIME=10.10.29
20:10:29 10.10.29           IEE302I 401      ONLINE
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAE   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAK   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST025I  BLDL FAILED FOR IEDIAK   IN VTAMLIB
20:10:29 10.10.29 STC   14  IST110I  NETWORK SOLICITOR STARTED
20:10:29 10.10.29 STC   14  IST093I  APPLJRP  ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLPIES ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLPIAD ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLTSO  ACTIVE
20:10:29 10.10.29 STC   14  IST093I  APPLTEST ACTIVE
20:10:30 10.10.30 STC   14  IST093I  LCLMAJRP ACTIVE
20:10:30 10.10.30 STC   14  IST093I  LCLMAJ00 ACTIVE
20:10:30 10.10.30 STC   15  $HASP100 TSO      ON STCINRDR
20:10:30 10.10.30 STC   15  $HASP373 TSO      STARTED
20:10:30 10.10.30 STC   15  IEF403I TSO - STARTED - TIME=10.10.30
20:10:30 10.10.30 STC   16  $HASP100 JRP      ON STCINRDR
20:10:30 10.10.30           $HASP000 OK
20:10:30 10.10.30 STC   16  $HASP373 JRP      STARTED
20:10:30 10.10.30 STC   16  IEF403I JRP - STARTED - TIME=10.10.30
20:10:30 10.10.30           $HASP000 OK
20:10:30 10.10.30 STC   14  IST020I  VTAM INITIALIZATION COMPLETE
20:10:30 10.10.30 STC   14  IEA000I 0C0,IOE,05,0200,400000000001,,,NET     ,10.10.30
20:10:30 10.10.30 STC   14  IEA000I 0C1,IOE,05,0200,400000000001,,,NET     ,10.10.30
20:10:30 10.10.30 STC   14  IEA000I 0C2,IOE,05,0200,400000000001,,,NET     ,10.10.30
20:10:30 10.10.30 STC   10  DMSK00I DYNAMASK DONE ****
20:10:30 10.10.30 STC   10  DMSK06I  START
20:10:30 10.10.30 STC   10  IEF404I DYNAMASK - ENDED - TIME=10.10.30
20:10:30 10.10.30 STC   10  $HASP395 DYNAMASK ENDED
20:10:30 10.10.30 STC   10  $HASP150 DYNAMASK ON PRINTER2        30 LINES
20:10:30 10.10.30 STC   10 *$HASP190 DYNAMASK SETUP -- PRINTER2 -- F = 0001 -- C = 6    -- T = SN
20:10:30 10.10.30           $HASP000 OK
20:10:30 10.10.30           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:30 10.10.30 STC   10  $HASP250 DYNAMASK IS PURGED
20:10:30 10.10.30 STC   12  BSPSP91I - Parms passed: NOREPLYU
20:10:30 10.10.30 STC   12  BSPSP93I - PFK definitions will be updated in memory
20:10:30 10.10.30 STC   12  BSPSP22I - Dataset processed: SYS1.PARMLIB
20:10:30 10.10.30 STC   12  BSPSP23I - on volume MVSRES
20:10:30 10.10.30 STC   12  BSPSP21I - Member being processed: SETPFK01
20:10:30 10.10.30 STC   12  +BSPSP98I - Member processed, LASTCC=0000
20:10:30 10.10.30 STC   12  +BSPSP21I - Member being processed: SETPFK02
20:10:30 10.10.30 STC   12  +BSPSP98I - Member processed, LASTCC=0000
20:10:30 10.10.30 STC   12  +BSPSP99I - End of processing, MAXRC=0000
20:10:30 10.10.30 STC   12  IEF404I BSPSETPF - ENDED - TIME=10.10.30
20:10:30 10.10.30 STC   12  $HASP395 BSPSETPF ENDED
20:10:30 10.10.30 STC   12  $HASP150 BSPSETPF ON PRINTER2        34 LINES
20:10:30 10.10.30           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:30 10.10.30 STC   12  $HASP250 BSPSETPF IS PURGED
20:10:30 10.10.30 STC   13  IFC001I  D=3350 N=0B F=01AB0000 L=01AC0005 S=01AB000002 DIP COMPLETE
20:10:30 10.10.30 STC   13  IEF404I LOGRECI - ENDED - TIME=10.10.30
20:10:30 10.10.30 STC   13  $HASP395 LOGRECI  ENDED
20:10:30 10.10.30 STC   13  $HASP150 LOGRECI  ON PRINTER2        20 LINES
20:10:30 10.10.30           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:30 10.10.30 STC   13  $HASP250 LOGRECI  IS PURGED
20:10:30 10.10.30 STC    9  BSPGM40I -   200 MiB MAINSIZE memory available
20:10:30 10.10.30 STC    9  BSPGM41I -     2 Partitions of    64 MiB each
20:10:30 10.10.30 STC    9  BSPGM78I SVC 120 EP=00FE2DC8; MVS/380
20:10:30 10.10.30 STC   15  IKT007I TCAS ACCEPTING LOGONS
20:10:30 10.10.30 STC   15  IKT005I TCAS IS INITIALIZED
20:10:30 10.10.30 STC   16  JRPI101 INITIALIZATION COMPLETE
20:10:30 10.10.30 STC   16 *01 JRP100I - ENTER ICLOSE TO SHUT DOWN
20:10:31 10.10.30 STC   17  $HASP100 MOUNT    ON STCINRDR
20:10:31 10.10.30 STC   17  $HASP373 MOUNT    STARTED
20:10:31 10.10.30 STC   17  IEF403I MOUNT - STARTED - TIME=10.10.30
20:10:31 10.10.31 STC    9  IEF404I MVS380SS - ENDED - TIME=10.10.31
20:10:31 10.10.31 STC    9  $HASP395 MVS380SS ENDED
20:10:31 10.10.31 STC    9  $HASP150 MVS380SS ON PRINTER2        59 LINES
20:10:31 10.10.31           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:31 10.10.31 STC    9  $HASP250 MVS380SS IS PURGED
20:10:31 10.10.31 STC   17  IEF404I MOUNT - ENDED - TIME=10.10.31
20:10:31 10.10.31 STC   17  $HASP395 MOUNT    ENDED
20:10:31 10.10.31 STC   17  $HASP150 MOUNT    ON PRINTER2        18 LINES
20:10:31 10.10.31           $HASP160 PRINTER2 INACTIVE - CLASS=Z
20:10:31 10.10.31 STC   17  $HASP250 MOUNT    IS PURGED
21:14:02 /(001F) d t
21:14:02 11.14.02           IEE136I  TIME=11.14.02 DATE=17.093
21:31:50 HHCTE009I Client 127.0.0.1 connected to 3270 device 0:00C0
21:31:54 11.31.54 TSU    1  $HASP100 HERC01   ON TSOINRDR
21:31:54 11.31.54 TSU    1  $HASP373 HERC01   STARTED
21:31:54 11.31.54 TSU    1  IEF125I HERC01 - LOGGED ON - TIME=11.31.54
21:31:54 11.31.54          *IEE362A SMF ENTER DUMP FOR SYS1.MANX ON MVSRES
21:31:54 11.31.54           IEE361I SMF DATA LOST - SYS1.MANY NOT AVAILABLE TIME=11.31.54
21:31:55 11.31.54 STC   18  $HASP100 SMFDAILY ON STCINRDR
21:31:55 11.31.55 STC   18  $HASP373 SMFDAILY STARTED
21:31:55 11.31.55 STC   18  IEF403I SMFDAILY - STARTED - TIME=11.31.55
21:31:55 11.31.55 STC   18  IEF453I SMFDAILY - JOB FAILED - JCL ERROR - TIME=11.31.55
21:31:55 11.31.55 STC   18  $HASP395 SMFDAILY ENDED
21:31:55 11.31.55 STC   18  $HASP150 SMFDAILY ON PRINTER2        25 LINES
21:31:55 11.31.55           IEE132I START COMMAND DEVICE ALLOCATION ERROR
21:31:55 11.31.55           $HASP160 PRINTER2 INACTIVE - CLASS=Z
21:31:55 11.31.55 STC   18  $HASP250 SMFDAILY IS PURGED
21:34:22 11.34.22 TSU    1  IEFACTRT - Stepname  Procstep  Program   Retcode
21:34:22 11.34.22 TSU    1  IEF126I HERC01 - LOGGED OFF - TIME=11.34.22
21:34:22 11.34.22 TSU    1  $HASP395 HERC01   ENDED
21:34:22 11.34.22 TSU    1  $HASP150 HERC01   ON PRINTER2       127 LINES
21:34:22 11.34.22           $HASP160 PRINTER2 INACTIVE - CLASS=Z
21:34:22 11.34.22 TSU    1  $HASP250 HERC01   IS PURGED
21:34:28 HHCTE014I 3270 device 00C0 client 127.0.0.1 connection reset
21:44:32 HHCTE009I Client 127.0.0.1 connected to 3270 device 0:00C0
21:44:34 11.44.34 TSU    2  $HASP100 HERC01   ON TSOINRDR
21:44:34 11.44.34 TSU    2  $HASP373 HERC01   STARTED
21:44:34 11.44.34 TSU    2  IEF125I HERC01 - LOGGED ON - TIME=11.44.34
21:48:59 11.48.59 TSU    2  IEFACTRT - Stepname  Procstep  Program   Retcode
21:48:59 11.48.59 TSU    2  IEF126I HERC01 - LOGGED OFF - TIME=11.48.59
21:48:59 11.48.59 TSU    2  $HASP395 HERC01   ENDED
21:48:59 11.48.59 TSU    2  $HASP150 HERC01   ON PRINTER2       124 LINES
21:48:59 11.48.59           $HASP160 PRINTER2 INACTIVE - CLASS=Z
21:48:59 11.48.59 TSU    2  $HASP250 HERC01   IS PURGED
21:49:02 HHCTE014I 3270 device 00C0 client 127.0.0.1 connection reset
18:58:49 /(001F) m 401,VOL=(NL,PCTOMF)
18:58:49 08.58.49 STC   19  $HASP100 MOUNT    ON STCINRDR
18:58:49 08.58.49 STC   19  $HASP373 MOUNT    STARTED
18:58:49 08.58.49 STC   19  IEF403I MOUNT - STARTED - TIME=08.58.49
18:58:49 08.58.49 STC   19 *IEF233A M 401,PCTOMF,,MOUNT,401
18:58:49 HHCAO003I Firing command: 'script conf/subjobs2.rc'
18:58:49 script conf/subjobs2.rc
18:58:49 hao clear
18:58:49 HHCAO022I All automatic operation rules cleared
18:58:49 # If we get a prompt for a device, cancel it
18:58:49 hao tgt IEF238D
18:58:49 08.58.49 STC   19  IEE135I ERROR - REQUESTED DEVICE RESERVED
18:58:49 HHCAO016I Target placed at index 0
18:58:49 hao cmd script conf/cancel.rc
18:58:49 HHCAO020I Command placed at index 0
18:58:49 # If we get a prompt for a catalogue password, answer it
18:58:49 hao tgt IEC301A
18:58:49 HHCAO016I Target placed at index 1
18:58:49 hao cmd script conf/secret.rc
18:58:49 HHCAO020I Command placed at index 1
18:58:49 devinit 401 tapes/pctomf.tdf
18:58:49 HHCTA004I 0401: tapes/pctomf.tdf is a Optical Media Attachment (OMA) tape
18:58:49 HHCPN098I Device 0:0401 initialized
18:58:49 # When we see TERMHERC get purged, our job is done
18:58:49 hao tgt HASP250 TERMHERC
18:58:49 HHCAO016I Target placed at index 2
18:58:49 hao cmd script conf/termherc.rc
18:58:49 HHCAO020I Command placed at index 2
18:58:49 # Now submit the job
18:58:49 devinit 00c jcl/hercauto.jcl eof
18:58:49 HHCSD007I Device 000C unbound from socket 127.0.0.1:3505
18:58:49 HHCSD022I Socketdevice listener thread terminated
18:58:49 HHCPN098I Device 0:000C initialized
18:58:49 HHCPN013I EOF reached on SCRIPT file. Processing complete.
18:58:49 08.58.49 JOB    1  $HASP100 HERC01A  ON READER1
18:58:49 08.58.49 JOB    2  $HASP100 TERMHERC ON READER1
18:58:49 08.58.49 STC   19  IEF404I MOUNT - ENDED - TIME=08.58.49
18:58:49 08.58.49 STC   19  $HASP395 MOUNT    ENDED
18:58:49 08.58.49 STC   19  $HASP150 MOUNT    ON PRINTER2        19 LINES
18:58:49 08.58.49           $HASP160 PRINTER2 INACTIVE - CLASS=Z
18:58:49 08.58.49 STC   19  $HASP250 MOUNT    IS PURGED
18:58:49 08.58.49 JOB    1  $HASP373 HERC01A  STARTED - INIT  3 - CLASS C - SYS BSP1
18:58:49 08.58.49 JOB    1  IEF403I HERC01A - STARTED - TIME=08.58.49
18:58:49 08.58.49 JOB    1  IEFACTRT - Stepname  Procstep  Program   Retcode
18:58:49 08.58.49 JOB    1  HERC01A    IEFBR14             IEFBR14   RC= 0000
18:58:49 08.58.49 JOB    1  IEF404I HERC01A - ENDED - TIME=08.58.49
18:58:49 08.58.49 JOB    1  $HASP395 HERC01A  ENDED
18:58:49 08.58.49 JOB    1  $HASP150 HERC01A  ON PRINTER1        30 LINES
18:58:49 08.58.49 JOB    1 *$HASP190 HERC01A  SETUP -- PRINTER1 -- F = 0001 -- C = 6    -- T = SN
18:58:49 08.58.49           $HASP000 OK
18:58:49 08.58.49           $HASP160 PRINTER1 INACTIVE - CLASS=AP
18:58:49 08.58.49 JOB    1  $HASP250 HERC01A  IS PURGED
18:58:49 08.58.49 JOB    2  $HASP373 TERMHERC STARTED - INIT  3 - CLASS C - SYS BSP1
18:58:49 08.58.49 JOB    2  IEF403I TERMHERC - STARTED - TIME=08.58.49
18:58:49 08.58.49 JOB    2  IEFACTRT - Stepname  Procstep  Program   Retcode
18:58:49 08.58.49 JOB    2  TERMHERC   S1                  IEBGENER  RC= 0000
18:58:49 08.58.49 JOB    2  IEF404I TERMHERC - ENDED - TIME=08.58.49
18:58:49 08.58.49 JOB    2  $HASP395 TERMHERC ENDED
18:58:49 08.58.49           $HASP309    INIT  3 INACTIVE ******** C=CBA
18:58:49 08.58.49 JOB    2  $HASP150 TERMHERC ON PRINTER2        44 LINES
18:58:49 08.58.49 JOB    2  $HASP150 TERMHERC ON PRINTER1         1 LINE
18:58:49 08.58.49           $HASP160 PRINTER1 INACTIVE - CLASS=AP
18:58:49 08.58.49           $HASP160 PRINTER2 INACTIVE - CLASS=Z
18:58:49 08.58.49 JOB    2  $HASP250 TERMHERC IS PURGED
18:58:49 HHCAO003I Firing command: 'script conf/termherc.rc'
18:58:49 script conf/termherc.rc
18:58:49 # we put in an unnecessary "stopall" to prevent Hercules
18:58:49 # (3.07 and 3.12 and 4.0 rc0 at least) from randomly
18:58:49 # hanging in the "quit" command in about 5% of runs.
18:58:49 # It is still unclear why Hercules is hanging
18:58:49 stopall
18:58:49 quit
18:58:49 HHCIN900I Begin Hercules shutdown
18:58:49 HHCIN901I Releasing configuration
18:58:49 HHCCP008I CPU0000 thread ended: tid=00005304, pid=19624
18:58:49 HHCTT003I Timer thread ended
18:58:49 HHCCF047I Subchannel 0:0000 detached
18:58:49 HHCCF047I Subchannel 0:0001 detached
18:58:49 HHCCF047I Subchannel 0:0002 detached
18:58:49 HHCCF047I Subchannel 0:0003 detached
18:58:49 HHCCF047I Subchannel 0:0004 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   18492094   0%    1       1333   58555     211  233837   234954
18:58:49 HHCCD214I                                                         225       74
18:58:49 HHCCD215I dasd/mvsres.148
18:58:49 HHCCD216I [0]   15179178   0%    1 ro    1064       0      36
18:58:49 HHCCD217I shadow/mvsres_*.148
18:58:49 HHCCD218I [1]    3312916   0%    0        269   58555     175
18:58:49 HHCCF047I Subchannel 0:0005 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/sort01.131
18:58:49 HHCCD216I [0]      34336   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/sort01_*.131
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0006 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/sort02.132
18:58:49 HHCCD216I [0]      34336   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/sort02_*.132
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0007 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/sort03.133
18:58:49 HHCCD216I [0]      34336   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/sort03_*.133
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0008 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/sort04.134
18:58:49 HHCCD216I [0]      34336   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/sort04_*.134
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0009 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/sort05.135
18:58:49 HHCCD216I [0]      34336   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/sort05_*.135
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:000A detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      35424   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/sort06.136
18:58:49 HHCCD216I [0]      34336   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/sort06_*.136
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:000B detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   51373349   0%    1          9   21502      65       4        9
18:58:49 HHCCD214I                                                           4        1
18:58:49 HHCCD215I dasd/work00.140
18:58:49 HHCCD216I [0]     141963   0%    1 ro       5       0       1
18:58:49 HHCCD217I shadow/work00_*.140
18:58:49 HHCCD218I [1]   51231386   0%    0          4   21502      64
18:58:49 HHCCF047I Subchannel 0:000C detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    4830691   0%    0          3    1579       8       0        3
18:58:49 HHCCD215I dasd/hasp00.152
18:58:49 HHCCD216I [0]    4830691   0%    0 rw       3    1579       8
18:58:49 HHCCF047I Subchannel 0:000D detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    8703777   0%    0       2154  638315     626    6917     8473
18:58:49 HHCCD214I                                                         598      105
18:58:49 HHCCD215I dasd/page00.160
18:58:49 HHCCD216I [0]    8703777   0%    0 rw    2154  638315     626
18:58:49 HHCCF047I Subchannel 0:000E detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    3242482   0%    1        287  672870      32    5800     5994
18:58:49 HHCCD214I                                                          93       39
18:58:49 HHCCD215I dasd/page01.161
18:58:49 HHCCD216I [0]    3242482   0%    1 rw     287  672870      32
18:58:49 HHCCF047I Subchannel 0:000F detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    3146929   0%    1        259  285926      19    7653     7808
18:58:49 HHCCD214I                                                         104       32
18:58:49 HHCCD215I dasd/page02.162
18:58:49 HHCCD216I [0]    3146929   0%    1 rw     259  285926      19
18:58:49 HHCCF047I Subchannel 0:0010 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   53459324   0%    0         12   17337     438      17       24
18:58:49 HHCCD214I                                                           5        4
18:58:49 HHCCD215I dasd/work01.170
18:58:49 HHCCD216I [0]   53459324   0%    0 rw      12   17337     438
18:58:49 HHCCF047I Subchannel 0:0011 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   66888956   0%    1         17   12164     618      30       39
18:58:49 HHCCD214I                                                           9        4
18:58:49 HHCCD215I dasd/work02.180
18:58:49 HHCCD216I [0]     113799   0%    1 ro       8       0       1
18:58:49 HHCCD217I shadow/work02_*.180
18:58:49 HHCCD218I [1]   66775157   0%    0          9   12164     617
18:58:49 HHCCF047I Subchannel 0:0012 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   54710897   0%    1         13   10334     417      19       25
18:58:49 HHCCD214I                                                           7        3
18:58:49 HHCCD215I dasd/work03.190
18:58:49 HHCCD216I [0]     142257   0%    1 ro       8       0       1
18:58:49 HHCCD217I shadow/work03_*.190
18:58:49 HHCCD218I [1]   54568640   0%    0          5   10334     416
18:58:49 HHCCF047I Subchannel 0:0013 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   15480003   0%    1         27       1       5      89      113
18:58:49 HHCCD214I                                                           7        2
18:58:49 HHCCD215I dasd/mvscat.191
18:58:49 HHCCD216I [0]    3658727   0%    1 ro      15       0       3
18:58:49 HHCCD217I shadow/mvscat_*.191
18:58:49 HHCCD218I [1]   11821276   0%    0         12       1       2
18:58:49 HHCCF047I Subchannel 0:0014 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   99779712  19% 1447          1       0       2       0        1
18:58:49 HHCCD215I dasd/seasik.192
18:58:49 HHCCD216I [0]   16164960   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/seasik_*.192
18:58:49 HHCCD218I [1]   83614752  23% 1447          0       0       1
18:58:49 HHCCF047I Subchannel 0:0015 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]  168333900   4% 5454          1       0       1       0        1
18:58:49 HHCCD215I dasd/mvssrc.193
18:58:49 HHCCD216I [0]  168333900   4% 5454 rw       1       0       1
18:58:49 HHCCF047I Subchannel 0:0016 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   43468775   0%    1        227   18686     214  131687   131822
18:58:49 HHCCD214I                                                          99       36
18:58:49 HHCCD215I dasd/pub000.240
18:58:49 HHCCD216I [0]    7591138   3%    1 ro     107       0      16
18:58:49 HHCCD217I shadow/pub000_*.240
18:58:49 HHCCD218I [1]   35877637   0%    0        120   18686     198
18:58:49 HHCCF047I Subchannel 0:0017 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   25806186   0%    5         75  128813       6   33236    33276
18:58:49 HHCCD214I                                                          35       18
18:58:49 HHCCD215I dasd/mvsdlb.248
18:58:49 HHCCD216I [0]   25471539   0%    1 ro       5       0       3
18:58:49 HHCCD217I shadow/mvsdlb_*.248
18:58:49 HHCCD218I [1]     334647  44%    4         70  128813       3
18:58:49 HHCCF047I Subchannel 0:0018 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]  102443308  14%  326          1       0       2       0        1
18:58:49 HHCCD215I dasd/pub002.280
18:58:49 HHCCD216I [0]    2171954  29%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/pub002_*.280
18:58:49 HHCCD218I [1]  100271354  13%  325          0       0       1
18:58:49 HHCCF047I Subchannel 0:0019 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]     143545   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/tmptmp.2b1
18:58:49 HHCCD216I [0]     142257   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/tmptmp_*.2b1
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:001A detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]     143545   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/xmit90.2b2
18:58:49 HHCCD216I [0]     142257   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/xmit90_*.2b2
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:001B detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    6099280   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/start1.150
18:58:49 HHCCD216I [0]    6098016   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/start1_*.150
18:58:49 HHCCD218I [1]       1264   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:001C detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    4595678   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/spool0.151
18:58:49 HHCCD216I [0]    4594414   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/spool0_*.151
18:58:49 HHCCD218I [1]       1264   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:001D detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   50622559   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/cbt000.340
18:58:49 HHCCD216I [0]   50621271   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/cbt000_*.340
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:001E detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   53836805   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/cbt001.341
18:58:49 HHCCD216I [0]   53835517   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/cbt001_*.341
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:001F detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   46082980   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/cbt002.342
18:58:49 HHCCD216I [0]   46081692   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/cbt002_*.342
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0020 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   27241782   0%    0          7       0       1       2        5
18:58:49 HHCCD214I                                                           4        2
18:58:49 HHCCD215I dasd/cbtcat.343
18:58:49 HHCCD216I [0]   27240494   0%    0 ro       7       0       1
18:58:49 HHCCD217I shadow/cbtcat_*.343
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0021 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   46003602   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/src000.348
18:58:49 HHCCD216I [0]   46002314   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/src000_*.348
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0022 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   45707725   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/src001.349
18:58:49 HHCCD216I [0]   45706437   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/src001_*.349
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0023 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]   44403653   0%    0          1       0       1       0        1
18:58:49 HHCCD215I dasd/src002.34a
18:58:49 HHCCD216I [0]   44402365   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/src002_*.34a
18:58:49 HHCCD218I [1]       1288   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:0024 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    6321674   0%    1          2       0       1       0        2
18:58:49 HHCCD214I                                                           0        1
18:58:49 HHCCD215I dasd/srccat.34b
18:58:49 HHCCD216I [0]    6310767   0%    0 ro       2       0       1
18:58:49 HHCCD217I shadow/srccat_*.34b
18:58:49 HHCCD218I [1]      10907   7%    1          0       0       0
18:58:49 HHCCF047I Subchannel 0:0025 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    1118801  11%    2          2       0       3       0        2
18:58:49 HHCCD215I dasd/smp001.149
18:58:49 HHCCD216I [0]     774654   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/smp001_*.149
18:58:49 HHCCD218I [1]     344147  38%    2          1       0       2
18:58:49 HHCCF047I Subchannel 0:0026 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    1621663  24%   12          2       0       2       0        2
18:58:49 HHCCD214I                                                           0        1
18:58:49 HHCCD215I dasd/smp002.14a
18:58:49 HHCCD216I [0]     856740   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/smp002_*.14a
18:58:49 HHCCD218I [1]     764923  51%   12          1       0       1
18:58:49 HHCCF047I Subchannel 0:0027 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    1077383  19%    3          2       0       3       0        2
18:58:49 HHCCD215I dasd/smp003.14b
18:58:49 HHCCD216I [0]     592555   0%    0 ro       1       0       1
18:58:49 HHCCD217I shadow/smp003_*.14b
18:58:49 HHCCD218I [1]     484828  42%    3          1       0       2
18:58:49 HHCCF047I Subchannel 0:0028 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    3437923  36%    4          2       0       3       0        2
18:58:49 HHCCD215I dasd/smp004.14c
18:58:49 HHCCD216I [0]    2916431  35%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/smp004_*.14c
18:58:49 HHCCD218I [1]     521492  41%    3          1       0       2
18:58:49 HHCCF047I Subchannel 0:0029 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD211I                                                  readaheads   misses
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    4456337   0%   15        183  358770      93     918     1060
18:58:49 HHCCD214I                                                          45       26
18:58:49 HHCCD215I dasd/js2sp0.700
18:58:49 HHCCD216I [0]     142291   0%    1 ro       0       0       2
18:58:49 HHCCD217I shadow/js2sp0_*.700
18:58:49 HHCCD218I [1]    4314046   0%   14        183  358770      91
18:58:49 HHCCF047I Subchannel 0:002A detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]     715081   0%    1          2       0       2       0        2
18:58:49 HHCCD215I dasd/dlicat.138
18:58:49 HHCCD216I [0]      36630   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/dlicat_*.138
18:58:49 HHCCD218I [1]     678451   0%    0          1       0       1
18:58:49 HHCCF047I Subchannel 0:002B detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]     880289   0%    1          1       0       2       0        1
18:58:49 HHCCD215I dasd/dlisys.139
18:58:49 HHCCD216I [0]      36630   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/dlisys_*.139
18:58:49 HHCCD218I [1]     843659   0%    0          0       0       1
18:58:49 HHCCF047I Subchannel 0:002C detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      43495   0%    1          1       0       2       0        1
18:58:49 HHCCD215I dasd/dli000.13a
18:58:49 HHCCD216I [0]      36630   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/dli000_*.13a
18:58:49 HHCCD218I [1]       6865   0%    0          0       0       1
18:58:49 HHCCF047I Subchannel 0:002D detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      46911   0%    1          1       0       2       0        1
18:58:49 HHCCD215I dasd/dli001.13b
18:58:49 HHCCD216I [0]      36630   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/dli001_*.13b
18:58:49 HHCCD218I [1]      10281   0%    0          0       0       1
18:58:49 HHCCF047I Subchannel 0:002E detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]      37718   0%    1          1       0       1       0        1
18:58:49 HHCCD215I dasd/dli002.13c
18:58:49 HHCCD216I [0]      36630   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/dli002_*.13c
18:58:49 HHCCD218I [1]       1088   0%    0          0       0       0
18:58:49 HHCCF047I Subchannel 0:002F detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]    1233964   0%    1          2       0       2       0        2
18:58:49 HHCCD215I dasd/js3sp0.710
18:58:49 HHCCD216I [0]     141965   0%    1 ro       1       0       1
18:58:49 HHCCD217I shadow/js3sp0_*.710
18:58:49 HHCCD218I [1]    1091999   0%    0          1       0       1
18:58:49 HHCCF047I Subchannel 0:0030 detached
18:58:49 HHCCD210I           size free  nbr st   reads  writes l2reads    hits switches
18:58:49 HHCCD212I --------------------------------------------------------------------
18:58:49 HHCCD213I [*]     951745   0%    2          1       0       2       0        1
18:58:49 HHCCD215I dasd/js3res.711
18:58:49 HHCCD216I [0]     142093   0%    2 ro       1       0       1
18:58:49 HHCCD217I shadow/js3res_*.711
18:58:49 HHCCD218I [1]     809652   0%    0          0       0       1
18:58:49 HHCCD011I Readahead thread 2 stopping: tid=00005068, pid=19624
18:58:49 HHCCD011I Readahead thread 1 stopping: tid=00003E5C, pid=19624
18:58:49 HHCCD013I Garbage collector thread stopping: tid=0000467C, pid=19624
18:58:49 HHCCD012I Writer thread 1 stopping: tid=0000213C, pid=19624
18:58:49 HHCCD012I Writer thread 2 stopping: tid=00004654, pid=19624
18:58:49 HHCCF047I Subchannel 0:0031 detached
18:58:49 HHCCF047I Subchannel 0:0032 detached
18:58:49 HHCCF047I Subchannel 0:0033 detached
18:58:49 HHCCF047I Subchannel 0:0034 detached
18:58:49 HHCCF047I Subchannel 0:0035 detached
18:58:49 HHCCF047I Subchannel 0:0036 detached
18:58:49 HHCCF047I Subchannel 0:0037 detached
18:58:49 HHCTE004I Console connection thread terminated
18:58:49 HHCCF047I Subchannel 0:0038 detached
18:58:49 HHCCF047I Subchannel 0:0039 detached
18:58:49 HHCCF047I Subchannel 0:003A detached
18:58:49 HHCCF047I Subchannel 0:003B detached
18:58:49 HHCCF047I Subchannel 0:003C detached
18:58:49 HHCIN902I Configuration release complete
18:58:49 HHCIN903I Calling termination routines
18:58:49 HHCHD900I Begin shutdown sequence
18:58:49 HHCHD901I Calling panel_cleanup
18:58:50 HHCHD902I panel_cleanup complete
18:58:50 HHCHD901I Calling console_shutdown
18:58:50 HHCHD902I console_shutdown complete
18:58:50 HHCHD901I Calling term_sockdev
18:58:50 HHCHD902I term_sockdev complete
18:58:50 HHCHD901I Calling hdl_term
18:58:50 HHCHD950I Begin HDL termination sequence
18:58:50 HHCHD951I Calling module *Hercules cleanup routine
18:58:50 HHCHD952I Module *Hercules cleanup complete
18:58:50 HHCHD959I HDL Termination sequence complete
18:58:50 HHCHD902I hdl_term complete
18:58:50 HHCHD901I Calling logger_term
18:58:50 HHCLG014I logger thread terminating
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
I did another experiment on a normal run:


22:43:47 12.43.47 STC   17  $HASP100 MOUNT    ON STCINRDR
22:43:47 12.43.47 STC   17  $HASP373 MOUNT    STARTED
22:43:47 12.43.47 STC   17  IEF403I MOUNT - STARTED - TIME=12.43.47
22:43:47 12.43.47 STC   17 *IEF233A M 401,PCTOMF,,MOUNT,401
22:43:47 12.43.47 STC    9  IEF404I MVS380SS - ENDED - TIME=12.43.47
22:43:47 12.43.47 STC    9  $HASP395 MVS380SS ENDED
22:43:47 12.43.47 STC    9  $HASP150 MVS380SS ON PRINTER2        59 LINES
22:43:47 12.43.47           $HASP160 PRINTER2 INACTIVE - CLASS=Z
22:43:47 12.43.47 STC    9  $HASP250 MVS380SS IS PURGED
22:43:47 12.43.47 STC   17  IEF404I MOUNT - ENDED - TIME=12.43.47
22:43:47 12.43.47 STC   17  $HASP395 MOUNT    ENDED
22:43:47 12.43.47 STC   17  $HASP150 MOUNT    ON PRINTER2        18 LINES
22:43:47 12.43.47           $HASP160 PRINTER2 INACTIVE - CLASS=Z
22:43:47 12.43.47 STC   17  $HASP250 MOUNT    IS PURGED
22:44:40 /(001F) m 401,VOL=(NL,PCTOMF)
22:44:40 12.44.40 STC   18  $HASP100 MOUNT    ON STCINRDR
22:44:40 12.44.40 STC   18  $HASP373 MOUNT    STARTED
22:44:40 12.44.40 STC   18  IEF403I MOUNT - STARTED - TIME=12.44.40
22:44:40 12.44.40 STC   18 *IEF233A M 401,PCTOMF,,MOUNT,401
22:44:40 12.44.40 STC   18  IEE135I ERROR - REQUESTED DEVICE RESERVED
22:44:40 12.44.40 STC   18  IEF404I MOUNT - ENDED - TIME=12.44.40
22:44:40 12.44.40 STC   18  $HASP395 MOUNT    ENDED
22:44:40 12.44.40 STC   18  $HASP150 MOUNT    ON PRINTER2        19 LINES
22:44:40 12.44.40           $HASP160 PRINTER2 INACTIVE - CLASS=Z
22:44:40 12.44.40 STC   18  $HASP250 MOUNT    IS PURGED
22:45:10 /(001F) v 401,online
22:45:10 12.45.10           IEE302I 401      ONLINE
22:46:18 quit


So issuing a second identical mount
command causes the mount command
(IEF233A) to be reissued.

Varying the device online does not.

I have a theory that in the (rare) bad
run, it is actually a Hercules bug
dropping a console message.

Next time it happens I'll just try
doing the devinit even without
the mount message, and see if
it works.

BFN. Paul.
 
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
 - - - In [hidden email], <kerravon86@...> wrote:
> Ok, I decided to issue my own mount
>command to see what happened.

Why?  The mount command wasn't needed and had
already ran anyway.

Even without a /d u,tape,online command, the device
was already changed from removable to reserved and
a volume serial number was associated with it.

>Everything worked, but I got this
>strange message:
>18:58:49 08.58.49 STC 19 IEE135I ERROR - REQUESTED DEVICE RESERVED
 
Nothing worked.

The device was already reserved from the first mount
command that had worked perfectly.
The first mount command was so perfect that the tape
devinit worked after the  step allocated the tape drive but
before a message to mount the tape was issued.
The message wasn't needed or wanted.
There was nothing for the operator to do.
You could change the IEEVMPCR from UNIT=SYSDA
UNIT=(SYSDA,,DEFER) to try to get more times where
you don't get a message telling the operator to put the
tape on the tape drive.

>Full log below.

I snipped it.  The whole thing.  Nothing left.

>I'm wondering if, in MVS380SS, I should
>just issue the mount command twice.
 
How would your double reserve work.
Does that mean that two unloads or vary offline
commands would be need to change the tape
drive back to removable?

No it wouldn't.

>Or maybe I should have tried another
>V ONLINE. I'll see if I can reproduce the
>problem so that I can do another
>V ONLINE.
>BFN. Paul
 - - - console log snipped - - -

For an SL tape, a vary online would cause the system
to recognize that the volume was on the tape drive.
For an NL tape, a vary online would cause the system
to unload the tape.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
 - - - In [hidden email], <kerravon86@...> wrote:
 - - - beginning snipped - - -
 22:43:47 12.43.47 STC 17 *IEF233A M 401,PCTOMF,,MOUNT,401
 - - - middle snipped - - -
 > So issuing a second identical mount
 >command causes the mount command
 >(IEF233A) to be reissued.
 
IEF233A is not a mount command.

IEF233A is a message to the operator saying that a
needed tape or dasd volume is not on the device but
needs to be put on the device.
The last letter, "A", says it-is-needed-now instead of
"E" that requests eventual action.
 - - - the rest was too silly to not snip so it's gone - - -
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
---In [hidden email], <somitcw@...> wrote :

> The first mount command was so perfect that the tape
> devinit worked after the step allocated the tape drive but
> before a message to mount the tape was issued.

I have no idea what you are talking
about. Here is the full log:

https://groups.yahoo.com/neo/groups/H390-MVS/conversations/messages/17229

And here is the relevant mount:

 20:10:31 10.10.30 STC 17 $HASP100 MOUNT ON STCINRDR
 20:10:31 10.10.30 STC 17 $HASP373 MOUNT STARTED
 20:10:31 10.10.30 STC 17 IEF403I MOUNT - STARTED - TIME=10.10.30
 20:10:31 10.10.31 STC 9 IEF404I MVS380SS - ENDED - TIME=10.10.31
 20:10:31 10.10.31 STC 9 $HASP395 MVS380SS ENDED
 20:10:31 10.10.31 STC 9 $HASP150 MVS380SS ON PRINTER2 59 LINES
 20:10:31 10.10.31 $HASP160 PRINTER2 INACTIVE - CLASS=Z
 20:10:31 10.10.31 STC 9 $HASP250 MVS380SS IS PURGED
 20:10:31 10.10.31 STC 17 IEF404I MOUNT - ENDED - TIME=10.10.31
 20:10:31 10.10.31 STC 17 $HASP395 MOUNT ENDED
 20:10:31 10.10.31 STC 17 $HASP150 MOUNT ON PRINTER2 18 LINES
 20:10:31 10.10.31 $HASP160 PRINTER2 INACTIVE - CLASS=Z
 20:10:31 10.10.31 STC 17 $HASP250 MOUNT IS PURGED


> The message wasn't needed or wanted.
> There was nothing for the operator to do.

There is indeed something that the
operator needs to do - MOUNT THE
ACTUAL TAPE.

He's not going to do that unless he
sees the IEF233A message.

I have run thousands and thousands
of tests, and in almost every single
one, the IEF233A message appears
as expected.

Why do you think this rare exception,
where the IEF233A message does
not appear, is correct?

BFN. Paul.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
On 4/4/2017 5:04 AM, [hidden email] [H390-MVS] wrote:
> 20:10:28 10.10.28           IEE305I X        COMMAND INVALID

You got two of these. In the OS/360 days, the operator would issue a "S
X" to drive (de)allocation. e.g., to unload a disk or tape volume. In
MVS IBM dropped the ball, and required "S DEALLOC" instead. Most
installations assigned an alias of X to the DEALLOC procedure
(SYS1.PROCLIB). If you don't want to do that, just copy the DEALLOC PROC
and save it as X.

Gerhard Postpischil
Bradford, VT

---
This email has been checked for viruses by AVG.
http://www.avg.com

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
 - - - In [hidden email], <gerhardp@...> wrote:
> On 4/4/2017 5:04 AM, kerravon86@... mailto:kerravon86@... [H390-MVS] wrote:
>> 20:10:28 10.10.28 IEE305I X COMMAND INVALID
 - - - remainder snipped - - -

That was done to comment out a command.
Paul just inserted an "X" in front of it.

I normally insert "D T" in front but others might insert
LOG 'X' or other innocuous command.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
 - - - In [hidden email], <kerravon86@...> wrote:  - - - beginning snipped - - -
 > Why do you think this rare exception,
>where the IEF233A message does
>not appear, is correct?
 >BFN. Paul.
 

 When a tape volume or disk volume is already on the device,
 MVS does not and should not tell the operator to try to put
 a second copy of the volume on top of the tape or disk
 volume that is already there.  The devices were not designed
 to hold two volumes in a space designed for one.
 

 When a tape or disk volume is not on a tape or disk device
 when needed, it is at that time that MVS should tell the
 operator to put the tape or disk volume in the spot designed
 for that tape or disk volume.
 

 Instead of you confusing yourself with mount commands,
 why not leave the device empty until the system says to
 put the tape volume on the tape device and then do the
 devinit ?

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
---In [hidden email], <somitcw@...> wrote :

>> Why do you think this rare exception,
>> where the IEF233A message does
>> not appear, is correct?

> When a tape volume or disk volume is already on the device,
> MVS does not and should not tell the operator to try to put
> a second copy of the volume on top of the tape or disk
> volume that is already there.  The devices were not designed
> to hold two volumes in a space designed for one.

The tape is *not* already on the device.

> When a tape or disk volume is not on a tape or disk device
> when needed, it is at that time that MVS should tell the
> operator to put the tape or disk volume in the spot designed
> for that tape or disk volume.

Yes that's my exact situation. There is no
tape on the device, so the operator needs
to mount it with devinit.

> Instead of you confusing yourself with mount commands,

I'm confused with what you are saying.
The mount commands are straightforward.

> why not leave the device empty until the system says to
> put the tape volume on the tape device and then do the
> devinit ?

That is exactly what I am doing.

But in this very rare situation (1 in thousands),
the operator was not prompted to mount the
tape.

That is the mystery I am trying to solve,
and my best guess at the moment is
that it is a Hercules bug, similar to
the problem where commands entered
by Hercules not being recognized by
MVS.

BFN. Paul.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
- - - In [hidden email], <kerravon86@...> wrote:
> But in this very rare situation (1 in thousands),
>the operator was not prompted to mount the
>tape.
 - - - ending snipped - - -

Does the /d u,tape,online that you didn't do show that
the tape was not on the tape drive?

It doesn't show that or anything else so the tape must
be on the tape drive.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
---In [hidden email], <somitcw@...> wrote :

> Does the /d u,tape,online that you didn't do show that
> the tape was not on the tape drive?

I will do that next time the problem occurs.

> It doesn't show that or anything else so the tape must
> be on the tape drive.

It's impossible for the tape to be on
the tape drive, because there was
no devinit. I provided the entire log,
and there was NO DEVINIT!!!

BFN. Paul.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
---In [hidden email], <somitcw@...> wrote :

> That was done to comment out a command.
> Paul just inserted an "X" in front of it.

More correctly I just changed the "S"
to an "X" so that I didn't need to
insert anything.

> I normally insert "D T" in front but others might insert
> LOG 'X' or other innocuous command.

I prefer the S <> X so that I don't need
to figure out how to get the TSO editor
to insert characters and delete
characters. Currently I only know how
to do the default which is overwrite
everything.

Maybe I'll pick up that technique in
the next 10+ years of using TK-based
systems.

BFN. Paul.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
---In [hidden email], <kerravon86@...> wrote :

>> Does the /d u,tape,online that you didn't do show that
>> the tape was not on the tape drive?

> I will do that next time the problem occurs.

Also, is there a command to find out
what tape mounts are pending?

There has been no reoccurrence in
the last 4000 runs. I suspect that
the problem only occurs once every
40,000 runs or similar.

Thanks. Paul.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
 - - - In [hidden email], <kerravon86@...> wrote:
> - - - In [hidden email] mailto:[hidden email],
><kerravon86@...> wrote :
>>> Does the /d u,tape,online that you didn't do show that
>>> the tape was not on the tape drive?
>> I will do that next time the problem occurs.
> Also, is there a command to find out
>what tape mounts are pending?

Yes.

For tape, it's: /d u,tape,online or /d u,tape,online,,999
should do it?

For disk, it's: /d u,dasd,online,,999 should do it?

The difference that you may have more than 100 disk volumes
but probably never have more than 100 tape drives.

You should also do a: /d r,l

> There has been no reoccurrence in
>the last 4000 runs. I suspect that
>the problem only occurs once every
>40,000 runs or similar.
> Thanks. Paul.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
---In [hidden email], <somitcw@...> wrote :

>> Also, is there a command to find out
>> what tape mounts are pending?

> For tape, it's: /d u,tape,online or /d u,tape,online,,999
> should do it?

> You should also do a: /d r,l

Those are fairly crappy displays (see
below). I thought in my ACO days on
MVS/XA circa 1987 it was possible to
show devices and pending volsers. Not
just everything.

BFN. Paul.




02:05:16 16.05.16 STC   17 *IEF233A M 401,PCTOMF,,MOUNT,401
...
02:05:16 /(001F) d r,l
...
02:05:16 16.05.16           IEE110I 16.05.16 PENDING REQUESTS 305
02:05:16  SUMMARY:  1 REPLY ID  1 READY UNIT
02:05:16     01,JRP100I - ENTER ICLOSE TO SHUT DOWN
02:05:16   READY UNIT:  401
...
02:05:17 /(001F) d u,tape,online
...
02:05:17 16.05.17           IEE450I 16.05.17 UNIT STATUS 317
02:05:17  UNIT TYPE STATUS  VOLSER VOLSTATE   UNIT TYPE STATUS  VOLSER VOLSTATE
02:05:17  400  3400 O-NRD              /REMOV 401  3400 O-MTP   PCTOMF PRIV/RSERV
02:05:17  402  3400 O       MFTOPC     /REMOV 403  3400 O       AWSTAP     /REMOV

Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
- - - In [hidden email], <kerravon86@...> wrote:
- - - beginning and old notes snipped - - -
> 02:05:17 /(001F) d u,tape,online
> 02:05:17 16.05.17 IEE450I 16.05.17 UNIT STATUS 317
> 02:05:17
> UNIT TYPE STATUS VOLSER VOLSTATE
> UNIT TYPE STATUS VOLSER VOLSTATE
> 02:05:17
> 400 3400 O-NRD /REMOV

Online, not-ready, not public, private, nor storage, removable so not-mounted

> 401 3400 O-MTP PCTOMF PRIV/RSERV

Online mount-pending( waiting on the operator ), volser, private, mounted reserved

> 02:05:17
> 402 3400 O MFTOPC /REMOV

Online, volser, not public, private, nor storage, removable so not-mounted

> 403 3400 O AWSTAP /REMOV

Same as the previous tape drive.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
In reply to this post by Hercules390 - Mvs mailing list
On 5 April 2017 at 12:17, [hidden email] wrote:
>> You should also do a: /d r,l
>
> Those are fairly crappy displays (see below). I thought in my ACO days on
> MVS/XA circa 1987 it was possible to show devices and pending volsers. Not
> just everything.

> 02:05:16 16.05.16 STC   17 *IEF233A M 401,PCTOMF,,MOUNT,401
> ...
> 02:05:16 /(001F) d r,l
> ...
> 02:05:16 16.05.16           IEE110I 16.05.16 PENDING REQUESTS 305
> 02:05:16  SUMMARY:  1 REPLY ID  1 READY UNIT
> 02:05:16     01,JRP100I - ENTER ICLOSE TO SHUT DOWN
> 02:05:16   READY UNIT:  401

Surely the original IEF233A mount message is sitting, unrolled, at the top
of the screen.
...
02:05:17 /(001F) d u,tape,online
...
02:05:17 16.05.17           IEE450I 16.05.17 UNIT STATUS 317
02:05:17  UNIT TYPE STATUS  VOLSER VOLSTATE   UNIT TYPE STATUS  VOLSER
VOLSTATE
02:05:17  400  3400 O-NRD              /REMOV 401  3400 O-MTP   PCTOMF
PRIV/RSERV
02:05:17  402  3400 O       MFTOPC     /REMOV 403  3400 O       AWSTAP
 /REMOV

Why do say it's crappy? Looks fairly clear to me.

Tony H.
Reply | Threaded
Open this post in threaded view
|  
Report Content as Inappropriate

Re: mount not taking

Hercules390 - Mvs mailing list
 - - - In [hidden email], <tharminc@...> wrote:
> On 5 April 2017 at 12:17, kerravon86@... mailto:kerravon86@... wrote:
>>> You should also do a: /d r,l
>> Those are fairly crappy displays (see below). I thought in my ACO days on
>>MVS/XA circa 1987 it was possible to show devices and pending volsers.
>>Not just everything.
* * * * * * * * * *
>> 02:05:16 16.05.16 STC   17 *IEF233A M 401,PCTOMF,,MOUNT,401
* * * * * * * * * *
>> 02:05:16 /(001F) d r,l
>> 02:05:16 16.05.16           IEE110I 16.05.16 PENDING REQUESTS 305
>> 02:05:16  SUMMARY:  1 REPLY ID  1 READY UNIT
>> 02:05:16     01,JRP100I - ENTER ICLOSE TO SHUT DOWN
>> 02:05:16   READY UNIT:  401

Why are no "READY UNITs" listed for "READY UNIT" but one
NOT-READY UNIT is listed instead?

> Surely the original IEF233A mount message is sitting, unrolled, at the top of the screen.
.
It's there clear as day.  Not on a CRT console, but it is there.

>> 02:05:17 /(001F) d u,tape,online
>>...
>> 02:05:17 16.05.17           IEE450I 16.05.17 UNIT STATUS 317
>> 02:05:17  UNIT TYPE STATUS  VOLSER VOLSTATE   UNIT TYPE STATUS  VOLSER VOLSTATE
>> 02:05:17  400  3400 O-NRD              /REMOV 401  3400 O-MTP   PCTOMF PRIV/RSERV
>> 02:05:17  402  3400 O       MFTOPC     /REMOV 403  3400 O       AWSTAP     /REMOV
> Why do say it's crappy? Looks fairly clear to me.
> Tony H.

I don't like twu devices on a line but if I remember to
look for the second half, the display is readable.

P.S. Thanks for explaining the reason that "ORG ," is dangerous.
I had never thought that some code before mine could have set
the location counter past my code and back so the "ORG ,"
could.have made a mess.
12
Loading...