jesjobwait

[wfs]

Hi I am not sure if I understand this option in right.
I have a job who is submitting from windows a jcl to z/OS sftp (V7.x) and if the job is finished, I like to read the protocol from JES (SDSF)
if I use jesjobwait in different situations
1. without jesjobwait I get no protocol from jes.
2. with jesjobwait without parms I get a protocol
3. if I change my submitted Job to wait 180 sec and change my jesjobwait to 200.1
I get following information
Benutzername „FZI0071“ wird verwendet.
Authentifikation mit zuvor eingegebenem Passwort.
Authentifiziert.
Die Sitzung wird gestartet …
Sitzung gestartet.
Aktive Sitzung: [1] FZI0071@10.23.131.33
-r--r--r-- 0 ncsysin 0 Dec 30 0:00:00 1899 ?mode=jesincsysin
D--------- 0 0 ..
-r--r--r-- 0 FZI0071 0 Dec 30 0:00:00 1899 jesowner=FZI0071
D--------- 0 0 ..
-r--r--r-- 0 0 Dec 30 0:00:00 1899 mode=text
D--------- 0 0 ..
-r--r--r-- 0 t=200.1 0 Dec 30 0:00:00 1899 jesjobwait=200.1
D--------- 0 0 ..
//-JES.INTRDR
SFTP009.txt | 1 KB | 0,0 KB/s | binary | 100%
---------- 0 0 Dec 30 0:00:00 1899 .zos_spool
Drwxrwxrwx 0 0 Dec 30 0:00:00 1899 SFTPS1
D--------- 0 0 ..
Der entfernte Rechner sendet seit mehr als 15 Sekunden keine Daten mehr. Es wird weiter gewartet …
Hinweis: Wenn das Problem weiterhin besteht, versuchen Sie, die Option „Puffergröße der Verbindung optimieren“ abzuschalten.
Warnung: Der Abbruch dieses Vorganges beendet die Verbindung!
A(b)brechen (60 s):
Zeitüberschreitung beim Warten auf Antwort vom Server.
Fehler beim Wechseln in Verzeichnis „sftps1“.
Sitzung „FZI0071@10.23.131.33“ geschlossen.
Keine Sitzung

my jcl for submitting and read looks like this:
open sftp://FZI0071:xxxxxxx#@10.23.131.33/ -hostkey="any-key"
ls /+mode=jesincsysin
ls /+jesowner=FZI0071
ls /+mode=text
ls /+jesjobwait=200.1
cd //-JES.INTRDR
put SFTP009.txt sftps1
ls
cd sftps1
ls
get all job_sftps1_out.txt
ls //FZI0071
get //FZI0071.SMF11902.PS1 test.ausgabe
exit

my job SFTP009.txt looks like this:
//IDC00010 JOB '01,5900,9000',FZI0071,CLASS=Y,MSGCLASS=S,
// NOTIFY=&SYSUID
//*
//********************************************************************
//* DOC: SELECT RECORDTYP1 119 SUBTYPE 2 TERMINATION RECORDS ***
//********************************************************************
//*
//S1 SET RMFREC=FZI0071.SMF11902.PS1
//DELETE EXEC PGM=IEFBR14
//DEL1 DD DSN=&RMFREC,
// DISP=(MOD,DELETE,DELETE),UNIT=SYSDA,
// DCB=(RECFM=VB,LRECL=32760,BLKSIZE=32760),
// SPACE=(CYL,(10,10))
//SELECT EXEC PGM=IFASMFDP
//SYSPRINT DD SYSOUT=A
//IN DD DSN=TCIP.SMF119.PS1(0),DISP=SHR
//OUT DD DSN=&RMFREC,
// DISP=(MOD,CATLG),UNIT=SYSDA,
// DCB=(RECFM=VB,LRECL=32760,BLKSIZE=32760),
// SPACE=(CYL,(10,10))
//SYSIN DD *
INDD(IN,OPTIONS(DUMP))
OUTDD(OUT,TYPE(119(2)))
DATE(2022001,2022365)
REPORTOPTS(SUBTYPE)
/*
//SLEEP EXEC PGM=BPXBATCH,REGION=100M,
// PARM='PGM /bin/sleep 180'
//STDOUT DD SYSOUT=*
//STDERR DD SYSOUT=*

regards

Wolfgang

[dovetail]

Hi,

It's difficult in your Windows sftp output to see what commands are associated with which output.
I have attempted to do this below.

Code: Select all

> open sftp://FZI0071:xxxxxxx#@10.23.131.33/ -hostkey="any-key"
> ls /+mode=jesincsysin    ### This is an error. should be ls /+jesincsysin
-r--r--r-- 0 ncsysin 0 Dec 30 0:00:00 1899 ?mode=jesincsysin

> ls /+jesowner=FZI0071
-r--r--r-- 0 FZI0071 0 Dec 30 0:00:00 1899 jesowner=FZI0071

> ls /+mode=text
-r--r--r-- 0 0 Dec 30 0:00:00 1899 mode=text

> ls /+jesjobwait=200.1
-r--r--r-- 0 t=200.1 0 Dec 30 0:00:00 1899 jesjobwait=200.1

> cd //-JES.INTRDR
//-JES.INTRDR

> put SFTP009.txt sftps1
SFTP009.txt | 1 KB | 0,0 KB/s | binary | 100%

> ls
---------- 0 0 Dec 30 0:00:00 1899 .zos_spool
Drwxrwxrwx 0 0 Dec 30 0:00:00 1899 SFTPS1

> cd sftps1
The remote computer has not sent any data for more than 15 seconds. Still waiting...
...
A(b)break (60 s): 
Timed out waiting for a response from the server.

> ls
> get all job_sftps1_out.txt
> ls //FZI0071
> get //FZI0071.SMF11902.PS1 test.ausgabe
> exit

As you can see, I have translated what I believe is the output from the "cd sftps1" command.
This is the one that is waiting up to 200 seconds for the job to complete.
What is happening is that your client has a 60 second timeout. You will need to find a way to change this value if you want this to work.

The Co:Z SFTP server is functioning properly.

[wfs]

Hi I have changed th client timeout, but indepentently on client timeout,
the jesjobwait did some misterical actions.
If we use 90.2 it works fine and brings all protocols, if we do mor than 90.2 as esample 91.2 we get an timeout.
If wqe use 90.2 he brings us twice messages from jesjobwait, if we uses 91 he brings one message and timeout.

in this log you will see different runs:
==== 92 ===== Jobdauer 170 Sekunden

10.11.05 JOB48658 $HASP100 PGMDELAY ON INTRDR SPF FROM STC48646 FZI51038
10.11.05 JOB48658 IRR010I USERID FZI5103 IS ASSIGNED TO THIS JOB.
DatasetHandlerÝT¨: VsamImpl(SYS00005).close(), rc/reas=0/0x0000, rbar=JOB48658&
DatasetHandlerÝT¨: -> freeDD(SYS00005)
DatasetHandlerÝT¨: <- freeDD(rc=0)
DatasetHandlerÝT¨: <- close(true)
ZosDatasetÝT¨: <- close(rc=0)
DatasetHandlerÝT¨: -> close()
DatasetHandlerÝT¨: <- close(true)
Ý10:11:05.621790¨ debug3: request 4612: sent status 0
Ý10:11:05.621848¨ sent status Success
Ý10:11:05.624277¨ debug3: Type: SSH2_FXP_SETSTAT
Ý10:11:05.624346¨ debug1: request 4105: setstat name "//-JES.INTRDR/sftps1"
Ý10:11:05.624383¨ debug3: request 4105: sent status 0
Ý10:11:05.624408¨ sent status Success
Ý10:11:05.666610¨ debug3: Type: SSH2_FXP_REALPATH
Ý10:11:05.666675¨ debug3: request 4880: realpath
Ý10:11:05.666704¨ realpath "//-JES.INTRDR/sftps1"
ZosDatasetResolverÝI¨: open failed: job "-JES.INTRDR.SFTPS1" did not complete within (waitSecs.pollInterva)=(92.2)
Ý10:12:37.668798¨ debug1: request 4880: sent names count 1
Ý10:12:37.668958¨ debug1: read eof
Ý10:12:37.668991¨ session closed for local user FZI5103 from Ý10.127.91.118¨


==== 91 ===== Jobdauer 170 Sekunden

10.28.21 JOB48665 $HASP100 PGMDELAY ON INTRDR SPF FROM STC48662 FZI51035
10.28.21 JOB48665 IRR010I USERID FZI5103 IS ASSIGNED TO THIS JOB.
DatasetHandlerÝT¨: VsamImpl(SYS00005).close(), rc/reas=0/0x0000, rbar=JOB48665&
DatasetHandlerÝT¨: -> freeDD(SYS00005)
DatasetHandlerÝT¨: <- freeDD(rc=0)
DatasetHandlerÝT¨: <- close(true)
ZosDatasetÝT¨: <- close(rc=0)
DatasetHandlerÝT¨: -> close()
DatasetHandlerÝT¨: <- close(true)
Ý10:28:21.354106¨ debug3: request 4612: sent status 0
Ý10:28:21.354218¨ sent status Success
Ý10:28:21.365018¨ debug3: Type: SSH2_FXP_SETSTAT
Ý10:28:21.365075¨ debug1: request 4105: setstat name "//-JES.INTRDR/sftps1"
Ý10:28:21.365108¨ debug3: request 4105: sent status 0
Ý10:28:21.365133¨ sent status Success
Ý10:28:21.414871¨ debug3: Type: SSH2_FXP_REALPATH
Ý10:28:21.414933¨ debug3: request 4880: realpath
Ý10:28:21.414961¨ realpath "//-JES.INTRDR/sftps1"
ZosDatasetResolverÝI¨: open failed: job "-JES.INTRDR.SFTPS1" did not complete within (waitSecs.pollInterva)=(91.2)
Ý10:29:52.416419¨ debug1: request 4880: sent names count 1
Ý10:29:52.416603¨ debug1: read eof
Ý10:29:52.416637¨ session closed for local user FZI5103 from Ý10.127.91.118¨


==== 90 ===== Jobdauer 170 Sekunden

DatasetHandlerÝT¨: -> close()
10.15.05 JOB48659 $HASP100 PGMDELAY ON INTRDR SPF FROM STC48647 FZI51039
10.15.05 JOB48659 IRR010I USERID FZI5103 IS ASSIGNED TO THIS JOB.
DatasetHandlerÝT¨: VsamImpl(SYS00005).close(), rc/reas=0/0x0000, rbar=JOB48659&
DatasetHandlerÝT¨: -> freeDD(SYS00005)
DatasetHandlerÝT¨: <- freeDD(rc=0)
DatasetHandlerÝT¨: <- close(true)
ZosDatasetÝT¨: <- close(rc=0)
DatasetHandlerÝT¨: -> close()
DatasetHandlerÝT¨: <- close(true)
Ý10:15:05.465567¨ debug3: request 4612: sent status 0
Ý10:15:05.465628¨ sent status Success
Ý10:15:05.469622¨ debug3: Type: SSH2_FXP_SETSTAT
Ý10:15:05.469675¨ debug1: request 4105: setstat name "//-JES.INTRDR/sftps1"
Ý10:15:05.469708¨ debug3: request 4105: sent status 0
Ý10:15:05.469733¨ sent status Success
Ý10:15:05.510627¨ debug3: Type: SSH2_FXP_REALPATH
Ý10:15:05.510685¨ debug3: request 4880: realpath
Ý10:15:05.510713¨ realpath "//-JES.INTRDR/sftps1"
ZosDatasetResolverÝI¨: open failed: job "-JES.INTRDR.SFTPS1" did not complete within (waitSecs.pollInterva)=(90.2)
Ý10:16:35.512438¨ debug1: request 4880: sent names count 1
Ý10:16:35.668612¨ debug3: Type: SSH2_FXP_LSTAT
Ý10:16:35.668685¨ debug3: request 5127: lstat
Ý10:16:35.668718¨ lstat name "//-JES.INTRDR.SFTPS1"
Ý10:16:35.668743¨ debug3: zos_process_stat: name=//-JES.INTRDR.SFTPS1, id=5127, extended=0
Ý10:17:55.684394¨ debug1: request 5127: sent attrib have 0x4
Ý10:17:56.130382¨ debug3: Type: SSH2_FXP_OPENDIR
Ý10:17:56.130480¨ debug3: request 5387: opendir
Ý10:17:56.130512¨ opendir "//-JES.INTRDR.SFTPS1"


==== 90 ===== Jobdauer 190 Sekunden

11.37.13 JOB48673 $HASP100 PGMDELAY ON INTRDR SPF FROM STC48669 FZI51038
11.37.13 JOB48673 IRR010I USERID FZI5103 IS ASSIGNED TO THIS JOB.
DatasetHandlerÝT¨: VsamImpl(SYS00005).close(), rc/reas=0/0x0000, rbar=JOB48673&
DatasetHandlerÝT¨: -> freeDD(SYS00005)
DatasetHandlerÝT¨: <- freeDD(rc=0)
DatasetHandlerÝT¨: <- close(true)
ZosDatasetÝT¨: <- close(rc=0)
DatasetHandlerÝT¨: -> close()
DatasetHandlerÝT¨: <- close(true)
Ý11:37:13.164245¨ debug3: request 4612: sent status 0
Ý11:37:13.164302¨ sent status Success
Ý11:37:13.167816¨ debug3: Type: SSH2_FXP_SETSTAT
Ý11:37:13.167865¨ debug1: request 4105: setstat name "//-JES.INTRDR/sftps1"
Ý11:37:13.167895¨ debug3: request 4105: sent status 0
Ý11:37:13.167923¨ sent status Success
Ý11:37:13.207930¨ debug3: Type: SSH2_FXP_REALPATH
Ý11:37:13.207996¨ debug3: request 4880: realpath
Ý11:37:13.208025¨ realpath "//-JES.INTRDR/sftps1"
ZosDatasetResolverÝI¨: open failed: job "-JES.INTRDR.SFTPS1" did not complete within (waitSecs.pollInterva)=(90.2)
Ý11:38:43.209627¨ debug1: request 4880: sent names count 1
Ý11:38:43.319053¨ debug3: Type: SSH2_FXP_LSTAT
Ý11:38:43.319118¨ debug3: request 5127: lstat
Ý11:38:43.319147¨ lstat name "//-JES.INTRDR.SFTPS1"
Ý11:38:43.319180¨ debug3: zos_process_stat: name=//-JES.INTRDR.SFTPS1, id=5127, extended=0
ZosDatasetResolverÝI¨: open failed: job "-JES.INTRDR.SFTPS1" did not complete within (waitSecs.pollInterva)=(90.2)
Ý11:40:13.321438¨ debug3: request 5127: sent status 2
Ý11:40:13.321839¨ sent status No such file
Ý11:40:13.425113¨ debug1: read eof
Ý11:40:13.425195¨ session closed for local user FZI5103 from Ý10.127.91.118¨


[u]this is my jesjob [/u]
//PGMDELAY JOB '03,5900',SPF,MSGCLASS=S,CLASS=9
//* LINES=(100,WARNING)
//* MAIN DEADLINE=(0856,0,10/16/2017)
//* *UQ ALLOW DEAKTIVIERT
//DELAY1 EXEC PGM=PGMDELAY,PARM='00020000',REGION=50M
//* PARM='00010000' DELAY 1 MINUTES
//* PARM='00020000' DELAY 2 MINUTES
//* PARM='00002000' DELAY 20 SEKUNDEN
//DELAY2 EXEC PGM=PGMDELAY,PARM='00003500',REGION=50M
//DELAY3 EXEC PGM=PGMDELAY,PARM='00003500',REGION=50M


next is my script for sftp startet during a batch job in windows:
open sftp://FZI5103:xxxxxxxx@10.23.131.33/ -hostkey="ssh-rsa 2048 vVpAA9xY7d1rb+nGqlW/2zSqAe+nESSc4lvif0QIFPM"
ls /+mode=jesincsysin
ls /+jesowner=FZI5103,jesjobwait=90.2
# Ausgabe der Optionen
ls /+
cd //-JES.INTRDR
put pgmdelay.txt sftps1
cd sftps1
ls
get all pgmdelay_out.txt
exit

[wfs]

I saw there was a german word inside 'Jobdauer' it means job duration

[dovetail]

This message means that the job did not complete within 92 seconds. You can see from the timestamps that that time has expired.
Note: the time will need to include the time that the job waits before it runs as well as the time it takes to run. Beyond this, I don't understand what youa re asking.

Ý10:11:05.666704¨ realpath "//-JES.INTRDR/sftps1"
ZosDatasetResolverÝI¨: open failed: job "-JES.INTRDR.SFTPS1" did not complete within (waitSecs.pollInterva)=(92.2)
Ý10:12:37.668798¨ debug1: request 4880: sent names count 1