Skip to content

[Bug] zos_jobs_submit failed without any explanation when having a JCL Error #624

@vlodeibm

Description

@vlodeibm

Bug description

When using zos_jobs_submit to submit a JCL on which one we have an JCL Error, with have this message :
module_stderr: Shared connection to 9.30.222.103 closed.
module_stdout: |-
Traceback (most recent call last):
File "/u/vlode/.ansible/tmp/ansible-tmp-1675089822.939972-3762-210145075551644/AnsiballZ_zos_job_submit.py", line 100, in
_ansiballz_main()
File "/u/vlode/.ansible/tmp/ansible-tmp-1675089822.939972-3762-210145075551644/AnsiballZ_zos_job_submit.py", line 92, in _ansiballz_main
invoke_module(zipped_mod, temp_path, ANSIBALLZ_PARAMS)
File "/u/vlode/.ansible/tmp/ansible-tmp-1675089822.939972-3762-210145075551644/AnsiballZ_zos_job_submit.py", line 40, in invoke_module
runpy.run_module(mod_name='ansible_collections.ibm.ibm_zos_core.plugins.modules.zos_job_submit', init_globals=dict(_module_fqn='ansible_collections.ibm.ibm_zos_core.plugins.modules.zos_job_submit', _modlib_path=modlib_path),
File "/var/usr/lpp/IBM/cyp/v3r10/pyz/lib/python3.10/runpy.py", line 224, in run_module
return _run_module_code(code, init_globals, run_name, mod_spec)
File "/var/usr/lpp/IBM/cyp/v3r10/pyz/lib/python3.10/runpy.py", line 96, in _run_module_code
_run_code(code, mod_globals, init_globals,
File "/var/usr/lpp/IBM/cyp/v3r10/pyz/lib/python3.10/runpy.py", line 86, in _run_code
exec(code, run_globals)
File "/tmp/ansible_ibm.ibm_zos_core.zos_job_submit_payload_czn4l_ba/ansible_ibm.ibm_zos_core.zos_job_submit_payload.zip/ansible_collections/ibm/ibm_zos_core/plugins/modules/zos_job_submit.py", line 907, in
File "/tmp/ansible_ibm.ibm_zos_core.zos_job_submit_payload_czn4l_ba/ansible_ibm.ibm_zos_core.zos_job_submit_payload.zip/ansible_collections/ibm/ibm_zos_core/plugins/modules/zos_job_submit.py", line 903, in main
File "/tmp/ansible_ibm.ibm_zos_core.zos_job_submit_payload_czn4l_ba/ansible_ibm.ibm_zos_core.zos_job_submit_payload.zip/ansible_collections/ibm/ibm_zos_core/plugins/modules/zos_job_submit.py", line 854, in run_module
File "/tmp/ansible_ibm.ibm_zos_core.zos_job_submit_payload_czn4l_ba/ansible_ibm.ibm_zos_core.zos_job_submit_payload.zip/ansible_collections/ibm/ibm_zos_core/plugins/modules/zos_job_submit.py", line 656, in assert_valid_return_code
main.SubmitJCLError
msg: |-
MODULE FAILURE
See stdout/stderr for the exact error

In 1.3.6, we had all the output of the job (= zos_jobs_output) with the JCL Error :
jobs:
- class: A
content_type: JOB
ddnames:
- byte_count: '1079'
content:
- '1 J E S 2 J O B L O G -- S Y S T E M I
P O 1 -- N O D E T I V L P 0 2 '
- '0 '
- ' 10.29.26 JOB09018 ---- MONDAY, 30 JAN 2023 ----'
- ' 10.29.26 JOB09018 IRR010I USERID VLODE IS ASSIGNED TO THIS
JOB.'
- ' 10.29.26 JOB09018 ICH70001I VLODE LAST ACCESS AT 10:29:07
ON MONDAY, JANUARY 30, 2023'
- ' 10.29.26 JOB09018 $HASP373 FREE STARTED - INIT F - CLASS
A - SYS IPO1'
- ' 10.29.26 JOB09018 - --TIMINGS
(MINS.)-- ----PAGING COUNTS---'
- ' 10.29.26 JOB09018 -JOBNAME STEPNAME PROCSTEP RC EXCP CONN TCB SRB CLOCK SERV PG PAGE SWAP VIO
SWAPS'
- ' 10.29.26 JOB09018 -FREE STEP1 FLUSH 0 0 .00 .00 .0 0 0 0 0 0 0'
- ' 10.29.26 JOB09018 IEF453I FREE - JOB FAILED - JCL ERROR'
- ' 10.29.26 JOB09018 -FREE ENDED. NAME- TOTAL
TCB CPU TIME= .00 TOTAL ELAPSED TIME= .0'
- ' 10.29.26 JOB09018 $HASP395 FREE ENDED'
- 0------ JES2 JOB STATISTICS ------
- '- 30 JAN 2023 JOB EXECUTION DATE'
- '- 11 CARDS READ'
- '- 47 SYSOUT PRINT RECORDS'
- '- 0 SYSOUT PUNCH RECORDS'
- '- 6 SYSOUT SPOOL KBYTES'
- '- 0.00 MINUTES EXECUTION TIME'
- ' 1 //FREE JOB ''FREE'',MSGLEVEL=(1,1),MSGCLASS=R,NOTIFY=&SYSUID JOB09018'
- ' IEFC653I SUBSTITUTION JCL - ''FREE'',MSGLEVEL=(1,1),MSGCLASS=R,NOTIFY=VLODE'
- ' 2 //JOBLIB DD DISP=SHR,DSN=NAZARE.WDEPLOY.BNPP.LOADBA '
- ' 3 // DD DISP=SHR,DSN=NAZARE.WDEPLOY.BNPP.LOADSP '
- ' //* '
- ' 4 //STEP1 EXEC PGM=IDCAMS '
- ' 5 //SYSPRINT DD SYSOUT=* '
- ' 6 //SYSOUT DD SYSOUT=* '
- ' /* '
- ' 7 //SYSIN DD * '
- ' ICH70001I VLODE LAST ACCESS AT 10:29:07 ON MONDAY, JANUARY
30, 2023'
- ' IEFA111I FREE IS USING THE FOLLOWING JOB RELATED SETTINGS:'
- ' SWA=BELOW,TIOT SIZE=32K,DSENQSHR=DISALLOW,GDGBIAS=JOB'
- ' IEFA107I FREE STEP1 JOBLIB - DATA SET NAZARE.WDEPLOY.BNPP.LOADBA
NOT FOUND'
- ' IEF272I FREE STEP1 - STEP WAS NOT EXECUTED.'
- ' IEF373I STEP/STEP1 /START 2023030.1029'
- ' IEF032I STEP/STEP1 /STOP 2023030.1029 '
- ' CPU: 0 HR 00 MIN 00.00 SEC SRB: 0 HR 00
MIN 00.00 SEC '
- ' VIRT: 0K SYS: 0K EXT: 0K SYS: 0K'
- ' ATB- REAL: 1044K SLOTS: 0K'
- ' VIRT- ALLOC: 11M SHRD: 0M'
- ' IEF375I JOB/FREE /START 2023030.1029'
- ' IEF033I JOB/FREE /STOP 2023030.1029 '
- ' CPU: 0 HR 00 MIN 00.00 SEC SRB: 0 HR 00
MIN 00.00 SEC '
ddname: JESMSGLG
id: '2'
procstep: ''
record_count: '19'
stepname: JES2
- byte_count: '502'
content:
- '1 J E S 2 J O B L O G -- S Y S T E M I
P O 1 -- N O D E T I V L P 0 2 '
- '0 '
- ' 10.29.26 JOB09018 ---- MONDAY, 30 JAN 2023 ----'
- ' 10.29.26 JOB09018 IRR010I USERID VLODE IS ASSIGNED TO THIS
JOB.'
- ' 10.29.26 JOB09018 ICH70001I VLODE LAST ACCESS AT 10:29:07
ON MONDAY, JANUARY 30, 2023'
- ' 10.29.26 JOB09018 $HASP373 FREE STARTED - INIT F - CLASS
A - SYS IPO1'
- ' 10.29.26 JOB09018 - --TIMINGS
(MINS.)-- ----PAGING COUNTS---'
- ' 10.29.26 JOB09018 -JOBNAME STEPNAME PROCSTEP RC EXCP CONN TCB SRB CLOCK SERV PG PAGE SWAP VIO
SWAPS'
- ' 10.29.26 JOB09018 -FREE STEP1 FLUSH 0 0 .00 .00 .0 0 0 0 0 0 0'
- ' 10.29.26 JOB09018 IEF453I FREE - JOB FAILED - JCL ERROR'
- ' 10.29.26 JOB09018 -FREE ENDED. NAME- TOTAL
TCB CPU TIME= .00 TOTAL ELAPSED TIME= .0'
- ' 10.29.26 JOB09018 $HASP395 FREE ENDED'
- 0------ JES2 JOB STATISTICS ------
- '- 30 JAN 2023 JOB EXECUTION DATE'
- '- 11 CARDS READ'
- '- 47 SYSOUT PRINT RECORDS'
- '- 0 SYSOUT PUNCH RECORDS'
- '- 6 SYSOUT SPOOL KBYTES'
- '- 0.00 MINUTES EXECUTION TIME'
- ' 1 //FREE JOB ''FREE'',MSGLEVEL=(1,1),MSGCLASS=R,NOTIFY=&SYSUID JOB09018'
- ' IEFC653I SUBSTITUTION JCL - ''FREE'',MSGLEVEL=(1,1),MSGCLASS=R,NOTIFY=VLODE'
- ' 2 //JOBLIB DD DISP=SHR,DSN=NAZARE.WDEPLOY.BNPP.LOADBA '
- ' 3 // DD DISP=SHR,DSN=NAZARE.WDEPLOY.BNPP.LOADSP '
- ' //* '
- ' 4 //STEP1 EXEC PGM=IDCAMS '
- ' 5 //SYSPRINT DD SYSOUT=* '
- ' 6 //SYSOUT DD SYSOUT=* '
- ' /* '
- ' 7 //SYSIN DD * '
- ' ICH70001I VLODE LAST ACCESS AT 10:29:07 ON MONDAY, JANUARY
30, 2023'
- ' IEFA111I FREE IS USING THE FOLLOWING JOB RELATED SETTINGS:'
- ' SWA=BELOW,TIOT SIZE=32K,DSENQSHR=DISALLOW,GDGBIAS=JOB'
- ' IEFA107I FREE STEP1 JOBLIB - DATA SET NAZARE.WDEPLOY.BNPP.LOADBA
NOT FOUND'
- ' IEF272I FREE STEP1 - STEP WAS NOT EXECUTED.'
- ' IEF373I STEP/STEP1 /START 2023030.1029'
- ' IEF032I STEP/STEP1 /STOP 2023030.1029 '
- ' CPU: 0 HR 00 MIN 00.00 SEC SRB: 0 HR 00
MIN 00.00 SEC '
- ' VIRT: 0K SYS: 0K EXT: 0K SYS: 0K'
- ' ATB- REAL: 1044K SLOTS: 0K'
- ' VIRT- ALLOC: 11M SHRD: 0M'
- ' IEF375I JOB/FREE /START 2023030.1029'
- ' IEF033I JOB/FREE /STOP 2023030.1029 '
- ' CPU: 0 HR 00 MIN 00.00 SEC SRB: 0 HR 00
MIN 00.00 SEC '
ddname: JESJCL
id: '3'
procstep: ''
record_count: '10'
stepname: JES2
- byte_count: '878'
content:
- '1 J E S 2 J O B L O G -- S Y S T E M I
P O 1 -- N O D E T I V L P 0 2 '
- '0 '
- ' 10.29.26 JOB09018 ---- MONDAY, 30 JAN 2023 ----'
- ' 10.29.26 JOB09018 IRR010I USERID VLODE IS ASSIGNED TO THIS
JOB.'
- ' 10.29.26 JOB09018 ICH70001I VLODE LAST ACCESS AT 10:29:07
ON MONDAY, JANUARY 30, 2023'
- ' 10.29.26 JOB09018 $HASP373 FREE STARTED - INIT F - CLASS
A - SYS IPO1'
- ' 10.29.26 JOB09018 - --TIMINGS
(MINS.)-- ----PAGING COUNTS---'
- ' 10.29.26 JOB09018 -JOBNAME STEPNAME PROCSTEP RC EXCP CONN TCB SRB CLOCK SERV PG PAGE SWAP VIO
SWAPS'
- ' 10.29.26 JOB09018 -FREE STEP1 FLUSH 0 0 .00 .00 .0 0 0 0 0 0 0'
- ' 10.29.26 JOB09018 IEF453I FREE - JOB FAILED - JCL ERROR'
- ' 10.29.26 JOB09018 -FREE ENDED. NAME- TOTAL
TCB CPU TIME= .00 TOTAL ELAPSED TIME= .0'
- ' 10.29.26 JOB09018 $HASP395 FREE ENDED'
- 0------ JES2 JOB STATISTICS ------
- '- 30 JAN 2023 JOB EXECUTION DATE'
- '- 11 CARDS READ'
- '- 47 SYSOUT PRINT RECORDS'
- '- 0 SYSOUT PUNCH RECORDS'
- '- 6 SYSOUT SPOOL KBYTES'
- '- 0.00 MINUTES EXECUTION TIME'
- ' 1 //FREE JOB ''FREE'',MSGLEVEL=(1,1),MSGCLASS=R,NOTIFY=&SYSUID JOB09018'
- ' IEFC653I SUBSTITUTION JCL - ''FREE'',MSGLEVEL=(1,1),MSGCLASS=R,NOTIFY=VLODE'
- ' 2 //JOBLIB DD DISP=SHR,DSN=NAZARE.WDEPLOY.BNPP.LOADBA '
- ' 3 // DD DISP=SHR,DSN=NAZARE.WDEPLOY.BNPP.LOADSP '
- ' //* '
- ' 4 //STEP1 EXEC PGM=IDCAMS '
- ' 5 //SYSPRINT DD SYSOUT=* '
- ' 6 //SYSOUT DD SYSOUT=* '
- ' /* '
- ' 7 //SYSIN DD * '
- ' ICH70001I VLODE LAST ACCESS AT 10:29:07 ON MONDAY, JANUARY
30, 2023'
- ' IEFA111I FREE IS USING THE FOLLOWING JOB RELATED SETTINGS:'
- ' SWA=BELOW,TIOT SIZE=32K,DSENQSHR=DISALLOW,GDGBIAS=JOB'
- ' IEFA107I FREE STEP1 JOBLIB - DATA SET NAZARE.WDEPLOY.BNPP.LOADBA
NOT FOUND'
- ' IEF272I FREE STEP1 - STEP WAS NOT EXECUTED.'
- ' IEF373I STEP/STEP1 /START 2023030.1029'
- ' IEF032I STEP/STEP1 /STOP 2023030.1029 '
- ' CPU: 0 HR 00 MIN 00.00 SEC SRB: 0 HR 00
MIN 00.00 SEC '
- ' VIRT: 0K SYS: 0K EXT: 0K SYS: 0K'
- ' ATB- REAL: 1044K SLOTS: 0K'
- ' VIRT- ALLOC: 11M SHRD: 0M'
- ' IEF375I JOB/FREE /START 2023030.1029'
- ' IEF033I JOB/FREE /STOP 2023030.1029 '
- ' CPU: 0 HR 00 MIN 00.00 SEC SRB: 0 HR 00
MIN 00.00 SEC '
ddname: JESYSMSG
id: '4'
procstep: ''
record_count: '14'
stepname: JES2
job_id: JOB09018
job_name: FREE
owner: VLODE
ret_code:
msg: JCL ERROR
msg_txt: ''
subsystem: IPO1
system: ''
md5sum: 6d827110c269da5d3219c9e044d310bf
mode: '0600'
msg: SubmitJCLError('')
owner: VLODE
size: 1075
src: /u/vlode/.ansible/tmp/ansible-tmp-1675092551.1458178-3994-235740038396439/source
state: file
uid: 10148

Playbook verbosity output

No response

Contents of ansible.cfg

#*******************************************************************************
# Licensed Materials - Property of IBM
# (c) Copyright IBM Corp. 2022. All Rights Reserved.
#
# Note to U.S. Government Users Restricted Rights:
# Use, duplication or disclosure restricted by GSA ADP Schedule
# Contract with IBM Corp.
#*******************************************************************************
[defaults]
forks = 25
host_key_checking = False
#callbacks_enabled = timer, profile_tasks, profile_roles
stdout_callback = ibm.ibm_zos_plum_deploy.cb_evidences

[ssh_connection]
pipelining = True
#ssh_args = -o ControlMaster=auto -o ControlPersist=3600s -o PreferredAuthentications=publickey

[callback_evidences]
evidences_folder = ../evidences

Contents of the inventory

all:
  hosts:
    cvb1:
      ansible_host: X.X.X.X
      ansible_user: MYUSER
      ansible_ssh_port: 222

Contents of group_vars or host_vars

environment_vars:
  _BPXK_AUTOCVT: "ON"
  ZOAU_HOME: "{{ ZOAU }}"
  #PYTHONPATH: "{{ ZOAU }}/lib"
  LIBPATH: "{{ ZOAU }}/lib:{{ PYZ }}/lib:/lib:/usr/lib:."
  PATH: "{{ ZOAU }}/bin:{{ PYZ }}/bin:/bin:/var/bin"
  _CEE_RUNOPTS: "FILETAG(AUTOCVT,AUTOTAG) POSIX(ON)"
  _TAG_REDIR_ERR: "txt"
  _TAG_REDIR_IN: "txt"
  _TAG_REDIR_OUT: "txt"
  LANG: "C"

Ansible version

ansible [core 2.11.12]
  config file = None
  configured module search path = ['/home/vlode/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
  ansible python module location = /usr/local/lib/python3.8/dist-packages/ansible
  ansible collection location = /home/vlode/.ansible/collections:/usr/share/ansible/collections
  executable location = /usr/local/bin/ansible
  python version = 3.8.10 (default, Jun 22 2022, 20:18:18) [GCC 9.4.0]
  jinja version = 2.10.1
  libyaml = True

IBM z/OS Ansible core Version

v1.4.0

IBM ZOAU version

v1.1.1

z/OS version

2.4

Ansible module

zos_job_submit

Metadata

Metadata

Assignees

Labels

BugSomething isn't working as designed.In ProgressThis issue is under review by a team member.

Type

No type

Projects

Status

✅ Done

Relationships

None yet

Development

No branches or pull requests

Issue actions