11/21/18 17:15:24 Setting maximum file descriptors to 80000.
11/21/18 17:15:24 ******************************************************
11/21/18 17:15:24 ** condor_scheduniv_exec.9964452.0 (CONDOR_DAGMAN) STARTING UP
11/21/18 17:15:24 ** /usr/bin/condor_dagman
11/21/18 17:15:24 ** SubsystemInfo: name=DAGMAN type=DAGMAN(10) class=DAEMON(1)
11/21/18 17:15:24 ** Configuration: subsystem:DAGMAN local:<NONE> class:DAEMON
11/21/18 17:15:24 ** $CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $
11/21/18 17:15:24 ** $CondorPlatform: x86_64_RedHat7 $
11/21/18 17:15:24 ** PID = 81396
11/21/18 17:15:24 ** Log last touched time unavailable (No such file or directory)
11/21/18 17:15:24 ******************************************************
11/21/18 17:15:24 Using config source: /etc/condor/condor_config
11/21/18 17:15:24 Using local config sources:
11/21/18 17:15:24 /etc/condor/config.d/00_logging
11/21/18 17:15:24 /etc/condor/config.d/00_security
11/21/18 17:15:24 /etc/condor/config.d/20_ganglia
11/21/18 17:15:24 /etc/condor/config.d/20_master_daemons
11/21/18 17:15:24 /etc/condor/config.d/20_schedd
11/21/18 17:15:24 /etc/condor/config.d/20_schedd_limit_num_jobs
11/21/18 17:15:24 /etc/condor/config.d/20_schedd_stats
11/21/18 17:15:24 /etc/condor/config.d/20_schedd_stats_rss
11/21/18 17:15:24 /etc/condor/config.d/20_shared_port
11/21/18 17:15:24 /etc/condor/config.d/20_submit
11/21/18 17:15:24 /etc/condor/config.d/21_submit_singularity
11/21/18 17:15:24 /etc/condor/config.d/60_npx_submit
11/21/18 17:15:24 /etc/condor/config.d/60_npx_submit_sane_reqs
11/21/18 17:15:24 /etc/condor/config.d/99_block_submissions_group_long
11/21/18 17:15:24 /etc/condor/condor_config.local
11/21/18 17:15:24 config Macros = 134, Sorted = 134, StringBytes = 5412, TablesBytes = 4984
11/21/18 17:15:24 CLASSAD_CACHING is ENABLED
11/21/18 17:15:24 Daemon Log is logging: D_ALWAYS D_ERROR
11/21/18 17:15:24 DaemonCore: No command port requested.
11/21/18 17:15:24 DAGMAN_USE_STRICT setting: 1
11/21/18 17:15:24 DAGMAN_VERBOSITY setting: 3
11/21/18 17:15:24 DAGMAN_DEBUG_CACHE_SIZE setting: 5242880
11/21/18 17:15:24 DAGMAN_DEBUG_CACHE_ENABLE setting: False
11/21/18 17:15:24 DAGMAN_SUBMIT_DELAY setting: 1
11/21/18 17:15:24 DAGMAN_MAX_SUBMIT_ATTEMPTS setting: 6
11/21/18 17:15:24 DAGMAN_STARTUP_CYCLE_DETECT setting: False
11/21/18 17:15:24 DAGMAN_MAX_SUBMITS_PER_INTERVAL setting: 5
11/21/18 17:15:24 DAGMAN_AGGRESSIVE_SUBMIT setting: False
11/21/18 17:15:24 DAGMAN_USER_LOG_SCAN_INTERVAL setting: 5
11/21/18 17:15:24 DAGMAN_QUEUE_UPDATE_INTERVAL setting: 300
11/21/18 17:15:24 DAGMAN_DEFAULT_PRIORITY setting: 0
11/21/18 17:15:24 DAGMAN_SUPPRESS_NOTIFICATION setting: True
11/21/18 17:15:24 allow_events (DAGMAN_ALLOW_EVENTS) setting: 114
11/21/18 17:15:24 DAGMAN_RETRY_SUBMIT_FIRST setting: True
11/21/18 17:15:24 DAGMAN_RETRY_NODE_FIRST setting: False
11/21/18 17:15:24 DAGMAN_MAX_JOBS_IDLE setting: 1000
11/21/18 17:15:24 DAGMAN_MAX_JOBS_SUBMITTED setting: 0
11/21/18 17:15:24 DAGMAN_MAX_PRE_SCRIPTS setting: 20
11/21/18 17:15:24 DAGMAN_MAX_POST_SCRIPTS setting: 20
11/21/18 17:15:24 DAGMAN_MUNGE_NODE_NAMES setting: True
11/21/18 17:15:24 DAGMAN_PROHIBIT_MULTI_JOBS setting: False
11/21/18 17:15:24 DAGMAN_SUBMIT_DEPTH_FIRST setting: False
11/21/18 17:15:24 DAGMAN_ALWAYS_RUN_POST setting: False
11/21/18 17:15:24 DAGMAN_ABORT_DUPLICATES setting: True
11/21/18 17:15:24 DAGMAN_ABORT_ON_SCARY_SUBMIT setting: True
11/21/18 17:15:24 DAGMAN_PENDING_REPORT_INTERVAL setting: 600
11/21/18 17:15:24 DAGMAN_AUTO_RESCUE setting: True
11/21/18 17:15:24 DAGMAN_MAX_RESCUE_NUM setting: 100
11/21/18 17:15:24 DAGMAN_WRITE_PARTIAL_RESCUE setting: True
11/21/18 17:15:24 DAGMAN_DEFAULT_NODE_LOG setting: @(DAG_DIR)/@(DAG_FILE).nodes.log
11/21/18 17:15:24 DAGMAN_GENERATE_SUBDAG_SUBMITS setting: True
11/21/18 17:15:24 DAGMAN_MAX_JOB_HOLDS setting: 100
11/21/18 17:15:24 DAGMAN_HOLD_CLAIM_TIME setting: 20
11/21/18 17:15:24 ALL_DEBUG setting:
11/21/18 17:15:24 DAGMAN_DEBUG setting:
11/21/18 17:15:24 DAGMAN_SUPPRESS_JOB_LOGS setting: False
11/21/18 17:15:24 DAGMAN_REMOVE_NODE_JOBS setting: True
11/21/18 17:15:24 argv[0] == "condor_scheduniv_exec.9964452.0"
11/21/18 17:15:24 argv[1] == "-Lockfile"
11/21/18 17:15:24 argv[2] == "condor/submit/exampledag.submit.lock"
11/21/18 17:15:24 argv[3] == "-AutoRescue"
11/21/18 17:15:24 argv[4] == "1"
11/21/18 17:15:24 argv[5] == "-DoRescueFrom"
11/21/18 17:15:24 argv[6] == "0"
11/21/18 17:15:24 argv[7] == "-Dag"
11/21/18 17:15:24 argv[8] == "condor/submit/exampledag.submit"
11/21/18 17:15:24 argv[9] == "-Suppress_notification"
11/21/18 17:15:24 argv[10] == "-CsdVersion"
11/21/18 17:15:24 argv[11] == "$CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $"
11/21/18 17:15:24 argv[12] == "-Dagman"
11/21/18 17:15:24 argv[13] == "/usr/bin/condor_dagman"
11/21/18 17:15:24 Workflow batch-name: <exampledag.submit+9964452>
11/21/18 17:15:24 Workflow accounting_group: <>
11/21/18 17:15:24 Workflow accounting_group_user: <>
11/21/18 17:15:24 Warning: failed to get attribute DAGNodeName
11/21/18 17:15:24 DAGMAN_LOG_ON_NFS_IS_ERROR setting: False
11/21/18 17:15:24 Default node log file is: </home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log>
11/21/18 17:15:24 DAG Lockfile will be written to condor/submit/exampledag.submit.lock
11/21/18 17:15:24 DAG Input file is condor/submit/exampledag.submit
11/21/18 17:15:24 Parsing 1 dagfiles
11/21/18 17:15:24 Parsing condor/submit/exampledag.submit ...
11/21/18 17:15:24 Dag contains 1 total jobs
11/21/18 17:15:24 Sleeping for 3 seconds to ensure ProcessId uniqueness
11/21/18 17:15:27 Bootstrapping...
11/21/18 17:15:27 Number of pre-completed nodes: 0
11/21/18 17:15:27 MultiLogFiles: truncating log file /home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log
11/21/18 17:15:27 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:15:27 Of 1 nodes total:
11/21/18 17:15:27 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:15:27 === === === === === === ===
11/21/18 17:15:27 0 0 0 0 1 0 0
11/21/18 17:15:27 0 job proc(s) currently held
11/21/18 17:15:27 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:15:27 Registering condor_event_timer...
11/21/18 17:15:28 Sleeping for 1 s (DAGMAN_SUBMIT_DELAY) to throttle submissions...
11/21/18 17:15:29 Submitting HTCondor Node examplejob_$(Cluster)_$(Process) job(s)...
11/21/18 17:15:29 Adding a DAGMan workflow log /home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log
11/21/18 17:15:29 Masking the events recorded in the DAGMAN workflow log
11/21/18 17:15:29 Mask for workflow log is 0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36
11/21/18 17:15:29 submitting: /usr/bin/condor_submit -a dag_node_name' '=' 'examplejob_$(Cluster)_$(Process) -a +DAGManJobId' '=' '9964452 -a DAGManJobId' '=' '9964452 -batch-name exampledag.submit+9964452 -a submit_event_notes' '=' 'DAG' 'Node:' 'examplejob_$(Cluster)_$(Process) -a dagman_log' '=' '/home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log -a +DAGManNodesMask' '=' '"0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36" -a ARGS' '=' 'hello' 'world! -a job_name' '=' 'examplejob_$(Cluster)_$(Process) -a DAG_STATUS' '=' '0 -a FAILED_COUNT' '=' '0 -a notification' '=' 'never -a +DAGParentNodeNames' '=' '"" condor/submit/examplejob.submit
11/21/18 17:15:29 From submit: Submitting job(s)...
11/21/18 17:15:29 From submit: 3 job(s) submitted to cluster 9964472.
11/21/18 17:15:29 assigned HTCondor ID (9964472.0.0)
11/21/18 17:15:29 Just submitted 1 job this cycle...
11/21/18 17:15:29 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:15:29 ERROR: job examplejob_9964472_0 not found!
11/21/18 17:15:29 Event: ULOG_SUBMIT for unknown Node (9964472.0.0) {11/21/18 17:15:29}: ignoring...
11/21/18 17:15:29 ERROR: job examplejob_9964472_1 not found!
11/21/18 17:15:29 Event: ULOG_SUBMIT for unknown Node (9964472.1.0) {11/21/18 17:15:29}: ignoring...
11/21/18 17:15:29 ERROR: job examplejob_9964472_2 not found!
11/21/18 17:15:29 Event: ULOG_SUBMIT for unknown Node (9964472.2.0) {11/21/18 17:15:29}: ignoring...
11/21/18 17:15:29 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:15:29 Of 1 nodes total:
11/21/18 17:15:29 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:15:29 === === === === === === ===
11/21/18 17:15:29 0 0 1 0 0 0 0
11/21/18 17:15:29 0 job proc(s) currently held
11/21/18 17:15:29 DAGMan Runtime Statistics: [ EventCycleTimeCount = 0.0; SleepCycleTimeSum = 0.0; EventCycleTimeSum = 0.0; LogProcessCycleTimeCount = 1.0; LogProcessCycleTimeSum = 0.0001499652862548828; LogProcessCycleTimeMin = 0.0001499652862548828; LogProcessCycleTimeMax = 0.0001499652862548828; LogProcessCycleTimeAvg = 0.0001499652862548828; LogProcessCycleTimeStd = 0.0001499652862548828; SubmitCycleTimeMax = 1.090536117553711; SubmitCycleTimeCount = 1.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 1.090536117553711; SubmitCycleTimeStd = 1.090536117553711; SubmitCycleTimeAvg = 1.090536117553711; SubmitCycleTimeMin = 1.090536117553711; ]
11/21/18 17:21:24 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:21:24 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9964472.0.0) {11/21/18 17:21:23}
11/21/18 17:21:24 BAD EVENT: job (9964472.0.0) executing, submit count < 1 (0)
11/21/18 17:21:24 BAD EVENT is warning only
11/21/18 17:21:24 Number of idle job procs: 0
11/21/18 17:21:24 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9964472.1.0) {11/21/18 17:21:24}
11/21/18 17:21:24 BAD EVENT: job (9964472.1.0) executing, submit count < 1 (0)
11/21/18 17:21:24 BAD EVENT is warning only
11/21/18 17:21:24 Number of idle job procs: 0
11/21/18 17:21:24 Event: ULOG_JOB_TERMINATED for HTCondor Node examplejob_$(Cluster)_$(Process) (9964472.0.0) {11/21/18 17:21:24}
11/21/18 17:21:24 BAD EVENT: job (9964472.0.0) ended, submit count < 1 (0)
11/21/18 17:21:24 BAD EVENT is warning only
11/21/18 17:21:24 Number of idle job procs: 0
11/21/18 17:21:24 ERROR "Assertion ERROR on (node->_queuedNodeJobProcs >= 0)" at line 4261 in file /slots/10/dir_2238096/userdir/.tmp4gngbv/BUILD/condor-8.7.7/src/condor_dagman/dag.cpp
11/21/18 17:21:29 Setting maximum file descriptors to 80000.
11/21/18 17:21:29 ******************************************************
11/21/18 17:21:29 ** condor_scheduniv_exec.9964452.0 (CONDOR_DAGMAN) STARTING UP
11/21/18 17:21:29 ** /usr/bin/condor_dagman
11/21/18 17:21:29 ** SubsystemInfo: name=DAGMAN type=DAGMAN(10) class=DAEMON(1)
11/21/18 17:21:29 ** Configuration: subsystem:DAGMAN local:<NONE> class:DAEMON
11/21/18 17:21:29 ** $CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $
11/21/18 17:21:29 ** $CondorPlatform: x86_64_RedHat7 $
11/21/18 17:21:29 ** PID = 92774
11/21/18 17:21:29 ** Log last touched 11/21 17:21:24
11/21/18 17:21:29 ******************************************************
11/21/18 17:21:29 Using config source: /etc/condor/condor_config
11/21/18 17:21:29 Using local config sources:
11/21/18 17:21:29 /etc/condor/config.d/00_logging
11/21/18 17:21:29 /etc/condor/config.d/00_security
11/21/18 17:21:29 /etc/condor/config.d/20_ganglia
11/21/18 17:21:29 /etc/condor/config.d/20_master_daemons
11/21/18 17:21:29 /etc/condor/config.d/20_schedd
11/21/18 17:21:29 /etc/condor/config.d/20_schedd_limit_num_jobs
11/21/18 17:21:29 /etc/condor/config.d/20_schedd_stats
11/21/18 17:21:29 /etc/condor/config.d/20_schedd_stats_rss
11/21/18 17:21:29 /etc/condor/config.d/20_shared_port
11/21/18 17:21:29 /etc/condor/config.d/20_submit
11/21/18 17:21:29 /etc/condor/config.d/21_submit_singularity
11/21/18 17:21:29 /etc/condor/config.d/60_npx_submit
11/21/18 17:21:29 /etc/condor/config.d/60_npx_submit_sane_reqs
11/21/18 17:21:29 /etc/condor/config.d/99_block_submissions_group_long
11/21/18 17:21:29 /etc/condor/condor_config.local
11/21/18 17:21:29 config Macros = 134, Sorted = 134, StringBytes = 5412, TablesBytes = 4984
11/21/18 17:21:29 CLASSAD_CACHING is ENABLED
11/21/18 17:21:29 Daemon Log is logging: D_ALWAYS D_ERROR
11/21/18 17:21:29 DaemonCore: No command port requested.
11/21/18 17:21:29 DAGMAN_USE_STRICT setting: 1
11/21/18 17:21:29 DAGMAN_VERBOSITY setting: 3
11/21/18 17:21:29 DAGMAN_DEBUG_CACHE_SIZE setting: 5242880
11/21/18 17:21:29 DAGMAN_DEBUG_CACHE_ENABLE setting: False
11/21/18 17:21:29 DAGMAN_SUBMIT_DELAY setting: 1
11/21/18 17:21:29 DAGMAN_MAX_SUBMIT_ATTEMPTS setting: 6
11/21/18 17:21:29 DAGMAN_STARTUP_CYCLE_DETECT setting: False
11/21/18 17:21:29 DAGMAN_MAX_SUBMITS_PER_INTERVAL setting: 5
11/21/18 17:21:29 DAGMAN_AGGRESSIVE_SUBMIT setting: False
11/21/18 17:21:29 DAGMAN_USER_LOG_SCAN_INTERVAL setting: 5
11/21/18 17:21:29 DAGMAN_QUEUE_UPDATE_INTERVAL setting: 300
11/21/18 17:21:29 DAGMAN_DEFAULT_PRIORITY setting: 0
11/21/18 17:21:29 DAGMAN_SUPPRESS_NOTIFICATION setting: True
11/21/18 17:21:29 allow_events (DAGMAN_ALLOW_EVENTS) setting: 114
11/21/18 17:21:29 DAGMAN_RETRY_SUBMIT_FIRST setting: True
11/21/18 17:21:29 DAGMAN_RETRY_NODE_FIRST setting: False
11/21/18 17:21:29 DAGMAN_MAX_JOBS_IDLE setting: 1000
11/21/18 17:21:29 DAGMAN_MAX_JOBS_SUBMITTED setting: 0
11/21/18 17:21:29 DAGMAN_MAX_PRE_SCRIPTS setting: 20
11/21/18 17:21:29 DAGMAN_MAX_POST_SCRIPTS setting: 20
11/21/18 17:21:29 DAGMAN_MUNGE_NODE_NAMES setting: True
11/21/18 17:21:29 DAGMAN_PROHIBIT_MULTI_JOBS setting: False
11/21/18 17:21:29 DAGMAN_SUBMIT_DEPTH_FIRST setting: False
11/21/18 17:21:29 DAGMAN_ALWAYS_RUN_POST setting: False
11/21/18 17:21:29 DAGMAN_ABORT_DUPLICATES setting: True
11/21/18 17:21:29 DAGMAN_ABORT_ON_SCARY_SUBMIT setting: True
11/21/18 17:21:29 DAGMAN_PENDING_REPORT_INTERVAL setting: 600
11/21/18 17:21:29 DAGMAN_AUTO_RESCUE setting: True
11/21/18 17:21:29 DAGMAN_MAX_RESCUE_NUM setting: 100
11/21/18 17:21:29 DAGMAN_WRITE_PARTIAL_RESCUE setting: True
11/21/18 17:21:29 DAGMAN_DEFAULT_NODE_LOG setting: @(DAG_DIR)/@(DAG_FILE).nodes.log
11/21/18 17:21:29 DAGMAN_GENERATE_SUBDAG_SUBMITS setting: True
11/21/18 17:21:29 DAGMAN_MAX_JOB_HOLDS setting: 100
11/21/18 17:21:29 DAGMAN_HOLD_CLAIM_TIME setting: 20
11/21/18 17:21:29 ALL_DEBUG setting:
11/21/18 17:21:29 DAGMAN_DEBUG setting:
11/21/18 17:21:29 DAGMAN_SUPPRESS_JOB_LOGS setting: False
11/21/18 17:21:29 DAGMAN_REMOVE_NODE_JOBS setting: True
11/21/18 17:21:29 argv[0] == "condor_scheduniv_exec.9964452.0"
11/21/18 17:21:29 argv[1] == "-Lockfile"
11/21/18 17:21:29 argv[2] == "condor/submit/exampledag.submit.lock"
11/21/18 17:21:29 argv[3] == "-AutoRescue"
11/21/18 17:21:29 argv[4] == "1"
11/21/18 17:21:29 argv[5] == "-DoRescueFrom"
11/21/18 17:21:29 argv[6] == "0"
11/21/18 17:21:29 argv[7] == "-Dag"
11/21/18 17:21:29 argv[8] == "condor/submit/exampledag.submit"
11/21/18 17:21:29 argv[9] == "-Suppress_notification"
11/21/18 17:21:29 argv[10] == "-CsdVersion"
11/21/18 17:21:29 argv[11] == "$CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $"
11/21/18 17:21:29 argv[12] == "-Dagman"
11/21/18 17:21:29 argv[13] == "/usr/bin/condor_dagman"
11/21/18 17:21:29 Workflow batch-name: <exampledag.submit+9964452>
11/21/18 17:21:29 Workflow accounting_group: <>
11/21/18 17:21:29 Workflow accounting_group_user: <>
11/21/18 17:21:29 Warning: failed to get attribute DAGNodeName
11/21/18 17:21:29 DAGMAN_LOG_ON_NFS_IS_ERROR setting: False
11/21/18 17:21:29 Default node log file is: </home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log>
11/21/18 17:21:29 DAG Lockfile will be written to condor/submit/exampledag.submit.lock
11/21/18 17:21:29 DAG Input file is condor/submit/exampledag.submit
11/21/18 17:21:29 Parsing 1 dagfiles
11/21/18 17:21:29 Parsing condor/submit/exampledag.submit ...
11/21/18 17:21:29 Dag contains 1 total jobs
11/21/18 17:21:29 Lock file condor/submit/exampledag.submit.lock detected,
11/21/18 17:21:29 Duplicate DAGMan PID 81396 is no longer alive; this DAGMan should continue.
11/21/18 17:21:29 Using default node job log file
11/21/18 17:21:29 Sleeping for 3 seconds to ensure ProcessId uniqueness
11/21/18 17:21:32 Bootstrapping...
11/21/18 17:21:32 Number of pre-completed nodes: 0
11/21/18 17:21:32 Running in RECOVERY mode... >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11/21/18 17:21:32 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:21:32 ERROR: job examplejob_9964472_0 not found!
11/21/18 17:21:32 Event: ULOG_SUBMIT for unknown Node (9964472.0.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: job examplejob_9964472_1 not found!
11/21/18 17:21:32 Event: ULOG_SUBMIT for unknown Node (9964472.1.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: job examplejob_9964472_2 not found!
11/21/18 17:21:32 Event: ULOG_SUBMIT for unknown Node (9964472.2.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: node for condor ID 9964472.0.0 not found! (might be because of node retries)
11/21/18 17:21:32 Event: ULOG_EXECUTE for unknown Node (9964472.0.0) {11/21/18 17:21:23}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: node for condor ID 9964472.1.0 not found! (might be because of node retries)
11/21/18 17:21:32 Event: ULOG_EXECUTE for unknown Node (9964472.1.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: node for condor ID 9964472.0.0 not found! (might be because of node retries)
11/21/18 17:21:32 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.0.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: node for condor ID 9964472.2.0 not found! (might be because of node retries)
11/21/18 17:21:32 Event: ULOG_EXECUTE for unknown Node (9964472.2.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: node for condor ID 9964472.1.0 not found! (might be because of node retries)
11/21/18 17:21:32 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.1.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:21:32 ERROR: node for condor ID 9964472.2.0 not found! (might be because of node retries)
11/21/18 17:21:32 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.2.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:21:32 ------------------------------
11/21/18 17:21:32 HTCondor Recovery Complete
11/21/18 17:21:32 ------------------------------
11/21/18 17:21:32 ...done with RECOVERY mode <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
11/21/18 17:21:32 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:21:32 Of 1 nodes total:
11/21/18 17:21:32 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:21:32 === === === === === === ===
11/21/18 17:21:32 0 0 0 0 0 1 0
11/21/18 17:21:32 0 job proc(s) currently held
11/21/18 17:21:32 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:21:32 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:21:32 Of 1 nodes total:
11/21/18 17:21:32 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:21:32 === === === === === === ===
11/21/18 17:21:32 0 0 0 0 1 0 0
11/21/18 17:21:32 0 job proc(s) currently held
11/21/18 17:21:32 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:21:32 Registering condor_event_timer...
11/21/18 17:21:33 Sleeping for 1 s (DAGMAN_SUBMIT_DELAY) to throttle submissions...
11/21/18 17:21:34 Submitting HTCondor Node examplejob_$(Cluster)_$(Process) job(s)...
11/21/18 17:21:34 Adding a DAGMan workflow log /home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log
11/21/18 17:21:34 Masking the events recorded in the DAGMAN workflow log
11/21/18 17:21:34 Mask for workflow log is 0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36
11/21/18 17:21:34 submitting: /usr/bin/condor_submit -a dag_node_name' '=' 'examplejob_$(Cluster)_$(Process) -a +DAGManJobId' '=' '9964452 -a DAGManJobId' '=' '9964452 -batch-name exampledag.submit+9964452 -a submit_event_notes' '=' 'DAG' 'Node:' 'examplejob_$(Cluster)_$(Process) -a dagman_log' '=' '/home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log -a +DAGManNodesMask' '=' '"0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36" -a ARGS' '=' 'hello' 'world! -a job_name' '=' 'examplejob_$(Cluster)_$(Process) -a DAG_STATUS' '=' '0 -a FAILED_COUNT' '=' '0 -a notification' '=' 'never -a +DAGParentNodeNames' '=' '"" condor/submit/examplejob.submit
11/21/18 17:21:34 From submit: Submitting job(s)...
11/21/18 17:21:34 From submit: 3 job(s) submitted to cluster 9964867.
11/21/18 17:21:34 assigned HTCondor ID (9964867.0.0)
11/21/18 17:21:34 Just submitted 1 job this cycle...
11/21/18 17:21:34 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:21:34 ERROR: job examplejob_9964867_0 not found!
11/21/18 17:21:34 Event: ULOG_SUBMIT for unknown Node (9964867.0.0) {11/21/18 17:21:34}: ignoring...
11/21/18 17:21:34 ERROR: job examplejob_9964867_1 not found!
11/21/18 17:21:34 Event: ULOG_SUBMIT for unknown Node (9964867.1.0) {11/21/18 17:21:34}: ignoring...
11/21/18 17:21:34 ERROR: job examplejob_9964867_2 not found!
11/21/18 17:21:34 Event: ULOG_SUBMIT for unknown Node (9964867.2.0) {11/21/18 17:21:34}: ignoring...
11/21/18 17:21:34 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:21:34 Of 1 nodes total:
11/21/18 17:21:34 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:21:34 === === === === === === ===
11/21/18 17:21:34 0 0 1 0 0 0 0
11/21/18 17:21:34 0 job proc(s) currently held
11/21/18 17:21:34 DAGMan Runtime Statistics: [ EventCycleTimeCount = 0.0; SleepCycleTimeSum = 0.0; EventCycleTimeSum = 0.0; LogProcessCycleTimeCount = 1.0; LogProcessCycleTimeSum = 9.989738464355469E-05; LogProcessCycleTimeMin = 9.989738464355469E-05; LogProcessCycleTimeMax = 9.989738464355469E-05; LogProcessCycleTimeAvg = 9.989738464355469E-05; LogProcessCycleTimeStd = 9.989738464355469E-05; SubmitCycleTimeMax = 1.084985971450806; SubmitCycleTimeCount = 1.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 1.084985971450806; SubmitCycleTimeStd = 1.084985971450806; SubmitCycleTimeAvg = 1.084985971450806; SubmitCycleTimeMin = 1.084985971450806; ]
11/21/18 17:23:24 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:23:24 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9964867.0.0) {11/21/18 17:23:24}
11/21/18 17:23:24 BAD EVENT: job (9964867.0.0) executing, submit count < 1 (0)
11/21/18 17:23:24 BAD EVENT is warning only
11/21/18 17:23:24 Number of idle job procs: 0
11/21/18 17:23:24 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9964867.1.0) {11/21/18 17:23:24}
11/21/18 17:23:24 BAD EVENT: job (9964867.1.0) executing, submit count < 1 (0)
11/21/18 17:23:24 BAD EVENT is warning only
11/21/18 17:23:24 Number of idle job procs: 0
11/21/18 17:23:24 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9964867.2.0) {11/21/18 17:23:24}
11/21/18 17:23:24 BAD EVENT: job (9964867.2.0) executing, submit count < 1 (0)
11/21/18 17:23:24 BAD EVENT is warning only
11/21/18 17:23:24 Number of idle job procs: 0
11/21/18 17:23:24 Event: ULOG_JOB_TERMINATED for HTCondor Node examplejob_$(Cluster)_$(Process) (9964867.0.0) {11/21/18 17:23:24}
11/21/18 17:23:24 BAD EVENT: job (9964867.0.0) ended, submit count < 1 (0)
11/21/18 17:23:24 BAD EVENT is warning only
11/21/18 17:23:24 Number of idle job procs: 0
11/21/18 17:23:24 ERROR "Assertion ERROR on (node->_queuedNodeJobProcs >= 0)" at line 4261 in file /slots/10/dir_2238096/userdir/.tmp4gngbv/BUILD/condor-8.7.7/src/condor_dagman/dag.cpp
11/21/18 17:24:23 Setting maximum file descriptors to 80000.
11/21/18 17:24:23 ******************************************************
11/21/18 17:24:23 ** condor_scheduniv_exec.9964452.0 (CONDOR_DAGMAN) STARTING UP
11/21/18 17:24:23 ** /usr/bin/condor_dagman
11/21/18 17:24:23 ** SubsystemInfo: name=DAGMAN type=DAGMAN(10) class=DAEMON(1)
11/21/18 17:24:23 ** Configuration: subsystem:DAGMAN local:<NONE> class:DAEMON
11/21/18 17:24:23 ** $CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $
11/21/18 17:24:23 ** $CondorPlatform: x86_64_RedHat7 $
11/21/18 17:24:23 ** PID = 97750
11/21/18 17:24:23 ** Log last touched 11/21 17:23:24
11/21/18 17:24:23 ******************************************************
11/21/18 17:24:23 Using config source: /etc/condor/condor_config
11/21/18 17:24:23 Using local config sources:
11/21/18 17:24:23 /etc/condor/config.d/00_logging
11/21/18 17:24:23 /etc/condor/config.d/00_security
11/21/18 17:24:23 /etc/condor/config.d/20_ganglia
11/21/18 17:24:23 /etc/condor/config.d/20_master_daemons
11/21/18 17:24:23 /etc/condor/config.d/20_schedd
11/21/18 17:24:23 /etc/condor/config.d/20_schedd_limit_num_jobs
11/21/18 17:24:23 /etc/condor/config.d/20_schedd_stats
11/21/18 17:24:23 /etc/condor/config.d/20_schedd_stats_rss
11/21/18 17:24:23 /etc/condor/config.d/20_shared_port
11/21/18 17:24:23 /etc/condor/config.d/20_submit
11/21/18 17:24:23 /etc/condor/config.d/21_submit_singularity
11/21/18 17:24:23 /etc/condor/config.d/60_npx_submit
11/21/18 17:24:23 /etc/condor/config.d/60_npx_submit_sane_reqs
11/21/18 17:24:23 /etc/condor/config.d/99_block_submissions_group_long
11/21/18 17:24:23 /etc/condor/condor_config.local
11/21/18 17:24:23 config Macros = 134, Sorted = 134, StringBytes = 5412, TablesBytes = 4984
11/21/18 17:24:23 CLASSAD_CACHING is ENABLED
11/21/18 17:24:23 Daemon Log is logging: D_ALWAYS D_ERROR
11/21/18 17:24:23 DaemonCore: No command port requested.
11/21/18 17:24:23 DAGMAN_USE_STRICT setting: 1
11/21/18 17:24:23 DAGMAN_VERBOSITY setting: 3
11/21/18 17:24:23 DAGMAN_DEBUG_CACHE_SIZE setting: 5242880
11/21/18 17:24:23 DAGMAN_DEBUG_CACHE_ENABLE setting: False
11/21/18 17:24:23 DAGMAN_SUBMIT_DELAY setting: 1
11/21/18 17:24:23 DAGMAN_MAX_SUBMIT_ATTEMPTS setting: 6
11/21/18 17:24:23 DAGMAN_STARTUP_CYCLE_DETECT setting: False
11/21/18 17:24:23 DAGMAN_MAX_SUBMITS_PER_INTERVAL setting: 5
11/21/18 17:24:23 DAGMAN_AGGRESSIVE_SUBMIT setting: False
11/21/18 17:24:23 DAGMAN_USER_LOG_SCAN_INTERVAL setting: 5
11/21/18 17:24:23 DAGMAN_QUEUE_UPDATE_INTERVAL setting: 300
11/21/18 17:24:23 DAGMAN_DEFAULT_PRIORITY setting: 0
11/21/18 17:24:23 DAGMAN_SUPPRESS_NOTIFICATION setting: True
11/21/18 17:24:23 allow_events (DAGMAN_ALLOW_EVENTS) setting: 114
11/21/18 17:24:23 DAGMAN_RETRY_SUBMIT_FIRST setting: True
11/21/18 17:24:23 DAGMAN_RETRY_NODE_FIRST setting: False
11/21/18 17:24:23 DAGMAN_MAX_JOBS_IDLE setting: 1000
11/21/18 17:24:23 DAGMAN_MAX_JOBS_SUBMITTED setting: 0
11/21/18 17:24:23 DAGMAN_MAX_PRE_SCRIPTS setting: 20
11/21/18 17:24:23 DAGMAN_MAX_POST_SCRIPTS setting: 20
11/21/18 17:24:23 DAGMAN_MUNGE_NODE_NAMES setting: True
11/21/18 17:24:23 DAGMAN_PROHIBIT_MULTI_JOBS setting: False
11/21/18 17:24:23 DAGMAN_SUBMIT_DEPTH_FIRST setting: False
11/21/18 17:24:23 DAGMAN_ALWAYS_RUN_POST setting: False
11/21/18 17:24:23 DAGMAN_ABORT_DUPLICATES setting: True
11/21/18 17:24:23 DAGMAN_ABORT_ON_SCARY_SUBMIT setting: True
11/21/18 17:24:23 DAGMAN_PENDING_REPORT_INTERVAL setting: 600
11/21/18 17:24:23 DAGMAN_AUTO_RESCUE setting: True
11/21/18 17:24:23 DAGMAN_MAX_RESCUE_NUM setting: 100
11/21/18 17:24:23 DAGMAN_WRITE_PARTIAL_RESCUE setting: True
11/21/18 17:24:23 DAGMAN_DEFAULT_NODE_LOG setting: @(DAG_DIR)/@(DAG_FILE).nodes.log
11/21/18 17:24:23 DAGMAN_GENERATE_SUBDAG_SUBMITS setting: True
11/21/18 17:24:23 DAGMAN_MAX_JOB_HOLDS setting: 100
11/21/18 17:24:23 DAGMAN_HOLD_CLAIM_TIME setting: 20
11/21/18 17:24:23 ALL_DEBUG setting:
11/21/18 17:24:23 DAGMAN_DEBUG setting:
11/21/18 17:24:23 DAGMAN_SUPPRESS_JOB_LOGS setting: False
11/21/18 17:24:23 DAGMAN_REMOVE_NODE_JOBS setting: True
11/21/18 17:24:23 argv[0] == "condor_scheduniv_exec.9964452.0"
11/21/18 17:24:23 argv[1] == "-Lockfile"
11/21/18 17:24:23 argv[2] == "condor/submit/exampledag.submit.lock"
11/21/18 17:24:23 argv[3] == "-AutoRescue"
11/21/18 17:24:23 argv[4] == "1"
11/21/18 17:24:23 argv[5] == "-DoRescueFrom"
11/21/18 17:24:23 argv[6] == "0"
11/21/18 17:24:23 argv[7] == "-Dag"
11/21/18 17:24:23 argv[8] == "condor/submit/exampledag.submit"
11/21/18 17:24:23 argv[9] == "-Suppress_notification"
11/21/18 17:24:23 argv[10] == "-CsdVersion"
11/21/18 17:24:23 argv[11] == "$CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $"
11/21/18 17:24:23 argv[12] == "-Dagman"
11/21/18 17:24:23 argv[13] == "/usr/bin/condor_dagman"
11/21/18 17:24:23 Workflow batch-name: <exampledag.submit+9964452>
11/21/18 17:24:23 Workflow accounting_group: <>
11/21/18 17:24:23 Workflow accounting_group_user: <>
11/21/18 17:24:23 Warning: failed to get attribute DAGNodeName
11/21/18 17:24:23 DAGMAN_LOG_ON_NFS_IS_ERROR setting: False
11/21/18 17:24:23 Default node log file is: </home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log>
11/21/18 17:24:23 DAG Lockfile will be written to condor/submit/exampledag.submit.lock
11/21/18 17:24:23 DAG Input file is condor/submit/exampledag.submit
11/21/18 17:24:23 Parsing 1 dagfiles
11/21/18 17:24:23 Parsing condor/submit/exampledag.submit ...
11/21/18 17:24:23 Dag contains 1 total jobs
11/21/18 17:24:23 Lock file condor/submit/exampledag.submit.lock detected,
11/21/18 17:24:23 Duplicate DAGMan PID 92774 is no longer alive; this DAGMan should continue.
11/21/18 17:24:23 Using default node job log file
11/21/18 17:24:23 Sleeping for 3 seconds to ensure ProcessId uniqueness
11/21/18 17:24:26 Bootstrapping...
11/21/18 17:24:26 Number of pre-completed nodes: 0
11/21/18 17:24:26 Running in RECOVERY mode... >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11/21/18 17:24:26 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:24:26 ERROR: job examplejob_9964472_0 not found!
11/21/18 17:24:26 Event: ULOG_SUBMIT for unknown Node (9964472.0.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: job examplejob_9964472_1 not found!
11/21/18 17:24:26 Event: ULOG_SUBMIT for unknown Node (9964472.1.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: job examplejob_9964472_2 not found!
11/21/18 17:24:26 Event: ULOG_SUBMIT for unknown Node (9964472.2.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964472.0.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_EXECUTE for unknown Node (9964472.0.0) {11/21/18 17:21:23}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964472.1.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_EXECUTE for unknown Node (9964472.1.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964472.0.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.0.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964472.2.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_EXECUTE for unknown Node (9964472.2.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964472.1.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.1.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964472.2.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.2.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: job examplejob_9964867_0 not found!
11/21/18 17:24:26 Event: ULOG_SUBMIT for unknown Node (9964867.0.0) {11/21/18 17:21:34}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: job examplejob_9964867_1 not found!
11/21/18 17:24:26 Event: ULOG_SUBMIT for unknown Node (9964867.1.0) {11/21/18 17:21:34}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: job examplejob_9964867_2 not found!
11/21/18 17:24:26 Event: ULOG_SUBMIT for unknown Node (9964867.2.0) {11/21/18 17:21:34}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964867.0.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_EXECUTE for unknown Node (9964867.0.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964867.1.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_EXECUTE for unknown Node (9964867.1.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964867.2.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_EXECUTE for unknown Node (9964867.2.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964867.0.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964867.0.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964867.1.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964867.1.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ERROR: node for condor ID 9964867.2.0 not found! (might be because of node retries)
11/21/18 17:24:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964867.2.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:24:26 ------------------------------
11/21/18 17:24:26 HTCondor Recovery Complete
11/21/18 17:24:26 ------------------------------
11/21/18 17:24:26 ...done with RECOVERY mode <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
11/21/18 17:24:26 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:24:26 Of 1 nodes total:
11/21/18 17:24:26 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:24:26 === === === === === === ===
11/21/18 17:24:26 0 0 0 0 0 1 0
11/21/18 17:24:26 0 job proc(s) currently held
11/21/18 17:24:26 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:24:26 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:24:26 Of 1 nodes total:
11/21/18 17:24:26 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:24:26 === === === === === === ===
11/21/18 17:24:26 0 0 0 0 1 0 0
11/21/18 17:24:26 0 job proc(s) currently held
11/21/18 17:24:26 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:24:26 Registering condor_event_timer...
11/21/18 17:24:27 Sleeping for 1 s (DAGMAN_SUBMIT_DELAY) to throttle submissions...
11/21/18 17:24:28 Submitting HTCondor Node examplejob_$(Cluster)_$(Process) job(s)...
11/21/18 17:24:28 Adding a DAGMan workflow log /home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log
11/21/18 17:24:28 Masking the events recorded in the DAGMAN workflow log
11/21/18 17:24:28 Mask for workflow log is 0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36
11/21/18 17:24:28 submitting: /usr/bin/condor_submit -a dag_node_name' '=' 'examplejob_$(Cluster)_$(Process) -a +DAGManJobId' '=' '9964452 -a DAGManJobId' '=' '9964452 -batch-name exampledag.submit+9964452 -a submit_event_notes' '=' 'DAG' 'Node:' 'examplejob_$(Cluster)_$(Process) -a dagman_log' '=' '/home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log -a +DAGManNodesMask' '=' '"0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36" -a ARGS' '=' 'hello' 'world! -a job_name' '=' 'examplejob_$(Cluster)_$(Process) -a DAG_STATUS' '=' '0 -a FAILED_COUNT' '=' '0 -a notification' '=' 'never -a +DAGParentNodeNames' '=' '"" condor/submit/examplejob.submit
11/21/18 17:24:29 From submit: Submitting job(s)...
11/21/18 17:24:29 From submit: 3 job(s) submitted to cluster 9965000.
11/21/18 17:24:29 assigned HTCondor ID (9965000.0.0)
11/21/18 17:24:29 Just submitted 1 job this cycle...
11/21/18 17:24:29 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:24:29 ERROR: job examplejob_9965000_0 not found!
11/21/18 17:24:29 Event: ULOG_SUBMIT for unknown Node (9965000.0.0) {11/21/18 17:24:29}: ignoring...
11/21/18 17:24:29 ERROR: job examplejob_9965000_1 not found!
11/21/18 17:24:29 Event: ULOG_SUBMIT for unknown Node (9965000.1.0) {11/21/18 17:24:29}: ignoring...
11/21/18 17:24:29 ERROR: job examplejob_9965000_2 not found!
11/21/18 17:24:29 Event: ULOG_SUBMIT for unknown Node (9965000.2.0) {11/21/18 17:24:29}: ignoring...
11/21/18 17:24:29 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:24:29 Of 1 nodes total:
11/21/18 17:24:29 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:24:29 === === === === === === ===
11/21/18 17:24:29 0 0 1 0 0 0 0
11/21/18 17:24:29 0 job proc(s) currently held
11/21/18 17:24:29 DAGMan Runtime Statistics: [ EventCycleTimeCount = 0.0; SleepCycleTimeSum = 0.0; EventCycleTimeSum = 0.0; LogProcessCycleTimeCount = 1.0; LogProcessCycleTimeSum = 0.000102996826171875; LogProcessCycleTimeMin = 0.000102996826171875; LogProcessCycleTimeMax = 0.000102996826171875; LogProcessCycleTimeAvg = 0.000102996826171875; LogProcessCycleTimeStd = 0.000102996826171875; SubmitCycleTimeMax = 1.086297035217285; SubmitCycleTimeCount = 1.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 1.086297035217285; SubmitCycleTimeStd = 1.086297035217285; SubmitCycleTimeAvg = 1.086297035217285; SubmitCycleTimeMin = 1.086297035217285; ]
11/21/18 17:26:29 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:26:29 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9965000.2.0) {11/21/18 17:26:24}
11/21/18 17:26:29 BAD EVENT: job (9965000.2.0) executing, submit count < 1 (0)
11/21/18 17:26:29 BAD EVENT is warning only
11/21/18 17:26:29 Number of idle job procs: 0
11/21/18 17:26:29 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9965000.0.0) {11/21/18 17:26:24}
11/21/18 17:26:29 BAD EVENT: job (9965000.0.0) executing, submit count < 1 (0)
11/21/18 17:26:29 BAD EVENT is warning only
11/21/18 17:26:29 Number of idle job procs: 0
11/21/18 17:26:29 Event: ULOG_EXECUTE for HTCondor Node examplejob_$(Cluster)_$(Process) (9965000.1.0) {11/21/18 17:26:24}
11/21/18 17:26:29 BAD EVENT: job (9965000.1.0) executing, submit count < 1 (0)
11/21/18 17:26:29 BAD EVENT is warning only
11/21/18 17:26:29 Number of idle job procs: 0
11/21/18 17:26:29 Event: ULOG_JOB_TERMINATED for HTCondor Node examplejob_$(Cluster)_$(Process) (9965000.2.0) {11/21/18 17:26:24}
11/21/18 17:26:29 BAD EVENT: job (9965000.2.0) ended, submit count < 1 (0)
11/21/18 17:26:29 BAD EVENT is warning only
11/21/18 17:26:29 Number of idle job procs: 0
11/21/18 17:26:29 ERROR "Assertion ERROR on (node->_queuedNodeJobProcs >= 0)" at line 4261 in file /slots/10/dir_2238096/userdir/.tmp4gngbv/BUILD/condor-8.7.7/src/condor_dagman/dag.cpp
11/21/18 17:27:23 Setting maximum file descriptors to 80000.
11/21/18 17:27:23 ******************************************************
11/21/18 17:27:23 ** condor_scheduniv_exec.9964452.0 (CONDOR_DAGMAN) STARTING UP
11/21/18 17:27:23 ** /usr/bin/condor_dagman
11/21/18 17:27:23 ** SubsystemInfo: name=DAGMAN type=DAGMAN(10) class=DAEMON(1)
11/21/18 17:27:23 ** Configuration: subsystem:DAGMAN local:<NONE> class:DAEMON
11/21/18 17:27:23 ** $CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $
11/21/18 17:27:23 ** $CondorPlatform: x86_64_RedHat7 $
11/21/18 17:27:23 ** PID = 103976
11/21/18 17:27:23 ** Log last touched 11/21 17:26:29
11/21/18 17:27:23 ******************************************************
11/21/18 17:27:23 Using config source: /etc/condor/condor_config
11/21/18 17:27:23 Using local config sources:
11/21/18 17:27:23 /etc/condor/config.d/00_logging
11/21/18 17:27:23 /etc/condor/config.d/00_security
11/21/18 17:27:23 /etc/condor/config.d/20_ganglia
11/21/18 17:27:23 /etc/condor/config.d/20_master_daemons
11/21/18 17:27:23 /etc/condor/config.d/20_schedd
11/21/18 17:27:23 /etc/condor/config.d/20_schedd_limit_num_jobs
11/21/18 17:27:23 /etc/condor/config.d/20_schedd_stats
11/21/18 17:27:23 /etc/condor/config.d/20_schedd_stats_rss
11/21/18 17:27:23 /etc/condor/config.d/20_shared_port
11/21/18 17:27:23 /etc/condor/config.d/20_submit
11/21/18 17:27:23 /etc/condor/config.d/21_submit_singularity
11/21/18 17:27:23 /etc/condor/config.d/60_npx_submit
11/21/18 17:27:23 /etc/condor/config.d/60_npx_submit_sane_reqs
11/21/18 17:27:23 /etc/condor/config.d/99_block_submissions_group_long
11/21/18 17:27:23 /etc/condor/condor_config.local
11/21/18 17:27:23 config Macros = 134, Sorted = 134, StringBytes = 5414, TablesBytes = 4984
11/21/18 17:27:23 CLASSAD_CACHING is ENABLED
11/21/18 17:27:23 Daemon Log is logging: D_ALWAYS D_ERROR
11/21/18 17:27:23 DaemonCore: No command port requested.
11/21/18 17:27:23 DAGMAN_USE_STRICT setting: 1
11/21/18 17:27:23 DAGMAN_VERBOSITY setting: 3
11/21/18 17:27:23 DAGMAN_DEBUG_CACHE_SIZE setting: 5242880
11/21/18 17:27:23 DAGMAN_DEBUG_CACHE_ENABLE setting: False
11/21/18 17:27:23 DAGMAN_SUBMIT_DELAY setting: 1
11/21/18 17:27:23 DAGMAN_MAX_SUBMIT_ATTEMPTS setting: 6
11/21/18 17:27:23 DAGMAN_STARTUP_CYCLE_DETECT setting: False
11/21/18 17:27:23 DAGMAN_MAX_SUBMITS_PER_INTERVAL setting: 5
11/21/18 17:27:23 DAGMAN_AGGRESSIVE_SUBMIT setting: False
11/21/18 17:27:23 DAGMAN_USER_LOG_SCAN_INTERVAL setting: 5
11/21/18 17:27:23 DAGMAN_QUEUE_UPDATE_INTERVAL setting: 300
11/21/18 17:27:23 DAGMAN_DEFAULT_PRIORITY setting: 0
11/21/18 17:27:23 DAGMAN_SUPPRESS_NOTIFICATION setting: True
11/21/18 17:27:23 allow_events (DAGMAN_ALLOW_EVENTS) setting: 114
11/21/18 17:27:23 DAGMAN_RETRY_SUBMIT_FIRST setting: True
11/21/18 17:27:23 DAGMAN_RETRY_NODE_FIRST setting: False
11/21/18 17:27:23 DAGMAN_MAX_JOBS_IDLE setting: 1000
11/21/18 17:27:23 DAGMAN_MAX_JOBS_SUBMITTED setting: 0
11/21/18 17:27:23 DAGMAN_MAX_PRE_SCRIPTS setting: 20
11/21/18 17:27:23 DAGMAN_MAX_POST_SCRIPTS setting: 20
11/21/18 17:27:23 DAGMAN_MUNGE_NODE_NAMES setting: True
11/21/18 17:27:23 DAGMAN_PROHIBIT_MULTI_JOBS setting: False
11/21/18 17:27:23 DAGMAN_SUBMIT_DEPTH_FIRST setting: False
11/21/18 17:27:23 DAGMAN_ALWAYS_RUN_POST setting: False
11/21/18 17:27:23 DAGMAN_ABORT_DUPLICATES setting: True
11/21/18 17:27:23 DAGMAN_ABORT_ON_SCARY_SUBMIT setting: True
11/21/18 17:27:23 DAGMAN_PENDING_REPORT_INTERVAL setting: 600
11/21/18 17:27:23 DAGMAN_AUTO_RESCUE setting: True
11/21/18 17:27:23 DAGMAN_MAX_RESCUE_NUM setting: 100
11/21/18 17:27:23 DAGMAN_WRITE_PARTIAL_RESCUE setting: True
11/21/18 17:27:23 DAGMAN_DEFAULT_NODE_LOG setting: @(DAG_DIR)/@(DAG_FILE).nodes.log
11/21/18 17:27:23 DAGMAN_GENERATE_SUBDAG_SUBMITS setting: True
11/21/18 17:27:23 DAGMAN_MAX_JOB_HOLDS setting: 100
11/21/18 17:27:23 DAGMAN_HOLD_CLAIM_TIME setting: 20
11/21/18 17:27:23 ALL_DEBUG setting:
11/21/18 17:27:23 DAGMAN_DEBUG setting:
11/21/18 17:27:23 DAGMAN_SUPPRESS_JOB_LOGS setting: False
11/21/18 17:27:23 DAGMAN_REMOVE_NODE_JOBS setting: True
11/21/18 17:27:23 argv[0] == "condor_scheduniv_exec.9964452.0"
11/21/18 17:27:23 argv[1] == "-Lockfile"
11/21/18 17:27:23 argv[2] == "condor/submit/exampledag.submit.lock"
11/21/18 17:27:23 argv[3] == "-AutoRescue"
11/21/18 17:27:23 argv[4] == "1"
11/21/18 17:27:23 argv[5] == "-DoRescueFrom"
11/21/18 17:27:23 argv[6] == "0"
11/21/18 17:27:23 argv[7] == "-Dag"
11/21/18 17:27:23 argv[8] == "condor/submit/exampledag.submit"
11/21/18 17:27:23 argv[9] == "-Suppress_notification"
11/21/18 17:27:23 argv[10] == "-CsdVersion"
11/21/18 17:27:23 argv[11] == "$CondorVersion: 8.7.7 Mar 13 2018 BuildID: 435313 $"
11/21/18 17:27:23 argv[12] == "-Dagman"
11/21/18 17:27:23 argv[13] == "/usr/bin/condor_dagman"
11/21/18 17:27:23 Workflow batch-name: <exampledag.submit+9964452>
11/21/18 17:27:23 Workflow accounting_group: <>
11/21/18 17:27:23 Workflow accounting_group_user: <>
11/21/18 17:27:23 Warning: failed to get attribute DAGNodeName
11/21/18 17:27:23 DAGMAN_LOG_ON_NFS_IS_ERROR setting: False
11/21/18 17:27:23 Default node log file is: </home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log>
11/21/18 17:27:23 DAG Lockfile will be written to condor/submit/exampledag.submit.lock
11/21/18 17:27:23 DAG Input file is condor/submit/exampledag.submit
11/21/18 17:27:23 Parsing 1 dagfiles
11/21/18 17:27:23 Parsing condor/submit/exampledag.submit ...
11/21/18 17:27:23 Dag contains 1 total jobs
11/21/18 17:27:23 Lock file condor/submit/exampledag.submit.lock detected,
11/21/18 17:27:23 Duplicate DAGMan PID 97750 is no longer alive; this DAGMan should continue.
11/21/18 17:27:23 Using default node job log file
11/21/18 17:27:23 Sleeping for 3 seconds to ensure ProcessId uniqueness
11/21/18 17:27:26 Bootstrapping...
11/21/18 17:27:26 Number of pre-completed nodes: 0
11/21/18 17:27:26 Running in RECOVERY mode... >>>>>>>>>>>>>>>>>>>>>>>>>>>>>>
11/21/18 17:27:26 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:27:26 ERROR: job examplejob_9964472_0 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9964472.0.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9964472_1 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9964472.1.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9964472_2 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9964472.2.0) {11/21/18 17:15:29}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964472.0.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9964472.0.0) {11/21/18 17:21:23}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964472.1.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9964472.1.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964472.0.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.0.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964472.2.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9964472.2.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964472.1.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.1.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964472.2.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964472.2.0) {11/21/18 17:21:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9964867_0 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9964867.0.0) {11/21/18 17:21:34}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9964867_1 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9964867.1.0) {11/21/18 17:21:34}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9964867_2 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9964867.2.0) {11/21/18 17:21:34}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964867.0.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9964867.0.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964867.1.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9964867.1.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964867.2.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9964867.2.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964867.0.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964867.0.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964867.1.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964867.1.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9964867.2.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9964867.2.0) {11/21/18 17:23:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9965000_0 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9965000.0.0) {11/21/18 17:24:29}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9965000_1 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9965000.1.0) {11/21/18 17:24:29}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: job examplejob_9965000_2 not found!
11/21/18 17:27:26 Event: ULOG_SUBMIT for unknown Node (9965000.2.0) {11/21/18 17:24:29}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9965000.2.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9965000.2.0) {11/21/18 17:26:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9965000.0.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9965000.0.0) {11/21/18 17:26:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9965000.1.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_EXECUTE for unknown Node (9965000.1.0) {11/21/18 17:26:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9965000.2.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9965000.2.0) {11/21/18 17:26:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9965000.0.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9965000.0.0) {11/21/18 17:26:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ERROR: node for condor ID 9965000.1.0 not found! (might be because of node retries)
11/21/18 17:27:26 Event: ULOG_JOB_TERMINATED for unknown Node (9965000.1.0) {11/21/18 17:26:24}: ignoring... [recovery mode]
11/21/18 17:27:26 ------------------------------
11/21/18 17:27:26 HTCondor Recovery Complete
11/21/18 17:27:26 ------------------------------
11/21/18 17:27:26 ...done with RECOVERY mode <<<<<<<<<<<<<<<<<<<<<<<<<<<<<<
11/21/18 17:27:26 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:27:26 Of 1 nodes total:
11/21/18 17:27:26 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:27:26 === === === === === === ===
11/21/18 17:27:26 0 0 0 0 0 1 0
11/21/18 17:27:26 0 job proc(s) currently held
11/21/18 17:27:26 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:27:26 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:27:26 Of 1 nodes total:
11/21/18 17:27:26 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:27:26 === === === === === === ===
11/21/18 17:27:26 0 0 0 0 1 0 0
11/21/18 17:27:26 0 job proc(s) currently held
11/21/18 17:27:26 DAGMan Runtime Statistics: [ EventCycleTimeSum = 0.0; EventCycleTimeCount = 0.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 0.0; SubmitCycleTimeCount = 0.0; LogProcessCycleTimeSum = 0.0; SleepCycleTimeSum = 0.0; LogProcessCycleTimeCount = 0.0; ]
11/21/18 17:27:26 Registering condor_event_timer...
11/21/18 17:27:27 Sleeping for 1 s (DAGMAN_SUBMIT_DELAY) to throttle submissions...
11/21/18 17:27:28 Submitting HTCondor Node examplejob_$(Cluster)_$(Process) job(s)...
11/21/18 17:27:28 Adding a DAGMan workflow log /home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log
11/21/18 17:27:28 Masking the events recorded in the DAGMAN workflow log
11/21/18 17:27:28 Mask for workflow log is 0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36
11/21/18 17:27:28 submitting: /usr/bin/condor_submit -a dag_node_name' '=' 'examplejob_$(Cluster)_$(Process) -a +DAGManJobId' '=' '9964452 -a DAGManJobId' '=' '9964452 -batch-name exampledag.submit+9964452 -a submit_event_notes' '=' 'DAG' 'Node:' 'examplejob_$(Cluster)_$(Process) -a dagman_log' '=' '/home/jbourbeau/software/pycondor/examples_untracked/condor/submit/exampledag.submit.nodes.log -a +DAGManNodesMask' '=' '"0,1,2,4,5,7,9,10,11,12,13,16,17,24,27,35,36" -a ARGS' '=' 'hello' 'world! -a job_name' '=' 'examplejob_$(Cluster)_$(Process) -a DAG_STATUS' '=' '0 -a FAILED_COUNT' '=' '0 -a notification' '=' 'never -a +DAGParentNodeNames' '=' '"" condor/submit/examplejob.submit
11/21/18 17:27:28 From submit: Submitting job(s)...
11/21/18 17:27:28 From submit: 3 job(s) submitted to cluster 9965294.
11/21/18 17:27:28 assigned HTCondor ID (9965294.0.0)
11/21/18 17:27:28 Just submitted 1 job this cycle...
11/21/18 17:27:28 Currently monitoring 1 HTCondor log file(s)
11/21/18 17:27:28 ERROR: job examplejob_9965294_0 not found!
11/21/18 17:27:28 Event: ULOG_SUBMIT for unknown Node (9965294.0.0) {11/21/18 17:27:28}: ignoring...
11/21/18 17:27:28 ERROR: job examplejob_9965294_1 not found!
11/21/18 17:27:28 Event: ULOG_SUBMIT for unknown Node (9965294.1.0) {11/21/18 17:27:28}: ignoring...
11/21/18 17:27:28 ERROR: job examplejob_9965294_2 not found!
11/21/18 17:27:28 Event: ULOG_SUBMIT for unknown Node (9965294.2.0) {11/21/18 17:27:28}: ignoring...
11/21/18 17:27:28 DAG status: 0 (DAG_STATUS_OK)
11/21/18 17:27:28 Of 1 nodes total:
11/21/18 17:27:28 Done Pre Queued Post Ready Un-Ready Failed
11/21/18 17:27:28 === === === === === === ===
11/21/18 17:27:28 0 0 1 0 0 0 0
11/21/18 17:27:28 0 job proc(s) currently held
11/21/18 17:27:28 DAGMan Runtime Statistics: [ EventCycleTimeCount = 0.0; SleepCycleTimeSum = 0.0; EventCycleTimeSum = 0.0; LogProcessCycleTimeCount = 1.0; LogProcessCycleTimeSum = 0.0001089572906494141; LogProcessCycleTimeMin = 0.0001089572906494141; LogProcessCycleTimeMax = 0.0001089572906494141; LogProcessCycleTimeAvg = 0.0001089572906494141; LogProcessCycleTimeStd = 0.0001089572906494141; SubmitCycleTimeMax = 1.104439973831177; SubmitCycleTimeCount = 1.0; SleepCycleTimeCount = 0.0; SubmitCycleTimeSum = 1.104439973831177; SubmitCycleTimeStd = 1.104439973831177; SubmitCycleTimeAvg = 1.104439973831177; SubmitCycleTimeMin = 1.104439973831177; ]