[2020-07-03 00:27:33,97] [info] Running with database db.url = jdbc:hsqldb:mem:fb4e23cb-5eb9-494b-ae42-1d9af7d01aab;shutdown=false;hsqldb.tx=mvcc
[2020-07-03 00:27:39,37] [info] Running migration RenameWorkflowOptionsInMetadata with a read batch size of 100000 and a write batch size of 100000
[2020-07-03 00:27:39,38] [info] [RenameWorkflowOptionsInMetadata] 100%
[2020-07-03 00:27:39,44] [info] Running with database db.url = jdbc:hsqldb:mem:d1976698-78aa-47bf-b7ce-e2d98a2e4308;shutdown=false;hsqldb.tx=mvcc
[2020-07-03 00:27:39,74] [info] Slf4jLogger started
[2020-07-03 00:27:39,86] [info] Workflow heartbeat configuration:
{
"cromwellId" : "cromid-e524f6a",
"heartbeatInterval" : "2 minutes",
"ttl" : "10 minutes",
"failureShutdownDuration" : "5 minutes",
"writeBatchSize" : 10000,
"writeThreshold" : 10000
}
[2020-07-03 00:27:39,89] [info] Metadata summary refreshing every 1 second.
[2020-07-03 00:27:39,91] [info] KvWriteActor configured to flush with batch size 200 and process rate 5 seconds.
[2020-07-03 00:27:39,91] [info] WriteMetadataActor configured to flush with batch size 200 and process rate 5 seconds.
[2020-07-03 00:27:39,91] [info] CallCacheWriteActor configured to flush with batch size 100 and process rate 3 seconds.
[2020-07-03 00:27:39,91] [�[38;5;220mwarn�[0m] 'docker.hash-lookup.gcr-api-queries-per-100-seconds' is being deprecated, use 'docker.hash-lookup.gcr.throttle' instead (see reference.conf)
[2020-07-03 00:27:39,97] [info] JobExecutionTokenDispenser - Distribution rate: 50 per 1 seconds.
[2020-07-03 00:27:40,09] [info] SingleWorkflowRunnerActor: Version 48
[2020-07-03 00:27:40,09] [info] SingleWorkflowRunnerActor: Submitting workflow
[2020-07-03 00:27:40,13] [info] Unspecified type (Unspecified version) workflow 5c8eec74-dace-4680-a79b-b5ca0d003def submitted
[2020-07-03 00:27:40,15] [info] SingleWorkflowRunnerActor: Workflow submitted �[38;5;2m5c8eec74-dace-4680-a79b-b5ca0d003def�[0m
[2020-07-03 00:27:40,15] [info] 1 new workflows fetched by cromid-e524f6a: 5c8eec74-dace-4680-a79b-b5ca0d003def
[2020-07-03 00:27:40,16] [info] WorkflowManagerActor Starting workflow �[38;5;2m5c8eec74-dace-4680-a79b-b5ca0d003def�[0m
[2020-07-03 00:27:40,17] [info] WorkflowManagerActor Successfully started WorkflowActor-5c8eec74-dace-4680-a79b-b5ca0d003def
[2020-07-03 00:27:40,17] [info] Retrieved 1 workflows from the WorkflowStoreActor
[2020-07-03 00:27:40,20] [info] WorkflowStoreHeartbeatWriteActor configured to flush with batch size 10000 and process rate 2 minutes.
[2020-07-03 00:27:40,25] [info] MaterializeWorkflowDescriptorActor [�[38;5;2m5c8eec74�[0m]: Parsing workflow as WDL 1.0
[2020-07-03 00:27:42,50] [info] MaterializeWorkflowDescriptorActor [�[38;5;2m5c8eec74�[0m]: Call-to-Backend assignments: MultiBamExpressionQuantification.mergedStringtieTPMs -> Local, RNAseq.CPAT -> Local, GatkPreprocess.gatherBamFiles -> Local, GatkPreprocess.applyBqsr -> Local, MultiBamExpressionQuantification.mergedStringtieFPKMs -> Local, SampleWorkflow.umiDedup -> Local, QC.FastqcRead2 -> Local, SingleSampleCalling.callY -> Local, SingleSampleCalling.mergeSingleSampleVcf -> Local, CalculateRegions.intersectX -> Local, SampleWorkflow.star -> Local, SampleWorkflow.hisat2 -> Local, CalculateRegions.inverseBed -> Local, MultiBamExpressionQuantification.stringtieAssembly -> Local, MultiBamExpressionQuantification.stringtie -> Local, RNAseq.multiqcTask -> Local, SingleSampleCalling.Stats -> Local, RNAseq.gffreadTask -> Local, QC.FastqcRead1 -> Local, GatkPreprocess.splitNCigarReads -> Local, GatkPreprocess.gatherBqsr -> Local, BamMetrics.targetIntervalsLists -> Local, RNAseq.makeStarIndex -> Local, SingleSampleCalling.callX -> Local, MultiBamExpressionQuantification.htSeqCount -> Local, CalculateRegions.intersectAutosomalRegions -> Local, SingleSampleCalling.mergeSingleSampleGvcf -> Local, BamMetrics.rnaSeqMetrics -> Local, BamMetrics.Flagstat -> Local, SampleWorkflow.markDuplicates -> Local, BamMetrics.targetMetrics -> Local, CalculateRegions.scatterAutosomalRegions -> Local, QC.Cutadapt -> Local, MultiBamExpressionQuantification.mergedHTSeqFragmentsPerGenes -> Local, BamMetrics.ampliconIntervalsLists -> Local, RNAseq.GffCompare -> Local, RNAseq.scatterList -> Local, RNAseq.ConvertDockerTagsFile -> Local, QC.FastqcRead2After -> Local, CalculateRegions.intersectY -> Local, BamMetrics.picardMetrics -> Local, CalculateRegions.mergeBeds -> Local, RNAseq.ConvertSampleConfig -> Local, SingleSampleCalling.callAutosomal -> Local, QC.FastqcRead1After -> Local, MultiBamExpressionQuantification.mergeStringtieGtf -> Local, SampleWorkflow.postUmiDedupMarkDuplicates -> Local, GatkPreprocess.baseRecalibrator -> Local, SampleWorkflow.indexStarBam -> Local
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, cpu, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,63] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [cpu, memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [time_minutes, memory] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:42,64] [�[38;5;220mwarn�[0m] Local [�[38;5;2m5c8eec74�[0m]: Key/s [memory, time_minutes] is/are not supported by backend. Unsupported attributes will not be part of job executions.
[2020-07-03 00:27:44,82] [info] WorkflowExecutionActor-5c8eec74-dace-4680-a79b-b5ca0d003def [�[38;5;2m5c8eec74�[0m]: Starting RNAseq.ConvertSampleConfig, RNAseq.ConvertDockerTagsFile
[2020-07-03 00:27:44,98] [info] Not triggering log of token queue status. Effective log interval = None
[2020-07-03 00:27:44,99] [info] Assigned new job execution tokens to the following groups: 5c8eec74: 2
[2020-07-03 00:27:46,66] [�[38;5;220mwarn�[0m] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertSampleConfig:NA:1]: Unrecognized runtime attribute keys: time_minutes, memory
[2020-07-03 00:27:46,66] [�[38;5;220mwarn�[0m] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertDockerTagsFile:NA:1]: Unrecognized runtime attribute keys: time_minutes, memory
[2020-07-03 00:27:46,71] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertDockerTagsFile:NA:1]: �[38;5;5mset -e
mkdir -p "$(dirname ./dockerImages.json)"
python <<CODE
import json
import yaml
with open("/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile/inputs/-904235375/dockerImages.yml", "r") as input_yaml:
content = yaml.load(input_yaml)
with open("./dockerImages.json", "w") as output_json:
json.dump(content, output_json)
CODE�[0m
[2020-07-03 00:27:46,71] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertSampleConfig:NA:1]: �[38;5;5mset -e
mkdir -p "$(dirname ./samples.json)"
biowdl-input-converter \
-o ./samples.json \
--skip-file-check \
\
\
/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig/inputs/-1211160950/Rna3PairedEnd.yml�[0m
[2020-07-03 00:27:46,75] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertSampleConfig:NA:1]: executing: # make sure there is no preexisting Docker CID file
rm -f /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig/execution/docker_cid
# run as in the original configuration without --rm flag (will remove later)
docker run \
--cidfile /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig/execution/docker_cid \
-i \
--user $EUID \
--entrypoint /bin/bash \
-v /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig:/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig:delegated \
quay.io/biocontainers/biowdl-input-converter@sha256:e41ea016fc53a258ad39fac9b4eccc41cceab392cb43169dfef9fc29fb317f49 /cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig/execution/script
# get the return code (working even if the container was detached)
rc=$(docker wait �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig/execution/docker_cid�[0m)
# remove the container after waiting
docker rm �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertSampleConfig/execution/docker_cid�[0m
# return exit code
exit $rc
[2020-07-03 00:27:46,75] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertDockerTagsFile:NA:1]: executing: # make sure there is no preexisting Docker CID file
rm -f /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile/execution/docker_cid
# run as in the original configuration without --rm flag (will remove later)
docker run \
--cidfile /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile/execution/docker_cid \
-i \
--user $EUID \
--entrypoint /bin/bash \
-v /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile:/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile:delegated \
quay.io/biocontainers/biowdl-input-converter@sha256:e41ea016fc53a258ad39fac9b4eccc41cceab392cb43169dfef9fc29fb317f49 /cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile/execution/script
# get the return code (working even if the container was detached)
rc=$(docker wait �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile/execution/docker_cid�[0m)
# remove the container after waiting
docker rm �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-ConvertDockerTagsFile/execution/docker_cid�[0m
# return exit code
exit $rc
[2020-07-03 00:27:49,98] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertSampleConfig:NA:1]: job id: 16572
[2020-07-03 00:27:49,98] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertDockerTagsFile:NA:1]: job id: 16577
[2020-07-03 00:27:49,98] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertSampleConfig:NA:1]: Status change from - to WaitingForReturnCode
[2020-07-03 00:27:49,98] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertDockerTagsFile:NA:1]: Status change from - to WaitingForReturnCode
[2020-07-03 00:27:55,49] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertSampleConfig:NA:1]: Status change from WaitingForReturnCode to Done
[2020-07-03 00:27:56,10] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.ConvertDockerTagsFile:NA:1]: Status change from WaitingForReturnCode to Done
[2020-07-03 00:27:59,17] [info] WorkflowExecutionActor-5c8eec74-dace-4680-a79b-b5ca0d003def [�[38;5;2m5c8eec74�[0m]: Starting RNAseq.scatterList
[2020-07-03 00:27:59,99] [info] Assigned new job execution tokens to the following groups: 5c8eec74: 1
[2020-07-03 00:28:01,23] [�[38;5;220mwarn�[0m] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.scatterList:NA:1]: Unrecognized runtime attribute keys: cpu, time_minutes, memory
[2020-07-03 00:28:01,24] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.scatterList:NA:1]: �[38;5;5mscatter-regions \
--print-paths \
--scatter-size 1000000000 \
\
--prefix scatters/scatter- \
/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList/inputs/2074281362/reference.fasta.fai�[0m
[2020-07-03 00:28:01,25] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.scatterList:NA:1]: executing: # make sure there is no preexisting Docker CID file
rm -f /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList/execution/docker_cid
# run as in the original configuration without --rm flag (will remove later)
docker run \
--cidfile /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList/execution/docker_cid \
-i \
--user $EUID \
--entrypoint /bin/bash \
-v /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList:/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList:delegated \
quay.io/biocontainers/chunked-scatter@sha256:88ccc279639105d7ea4defe43c06f88b738f69b1156706ba3c335d84a37e9b64 /cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList/execution/script
# get the return code (working even if the container was detached)
rc=$(docker wait �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList/execution/docker_cid�[0m)
# remove the container after waiting
docker rm �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-scatterList/execution/docker_cid�[0m
# return exit code
exit $rc
[2020-07-03 00:28:04,93] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.scatterList:NA:1]: job id: 17269
[2020-07-03 00:28:04,94] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m5c8eec74�[0mRNAseq.scatterList:NA:1]: Status change from - to Done
[2020-07-03 00:28:05,30] [info] 815e1e42-f503-45ad-9454-7be49d04361a-SubWorkflowActor-SubWorkflow-calculateRegions:-1:1 [�[38;5;2m815e1e42�[0m]: Starting CalculateRegions.scatterAutosomalRegions
[2020-07-03 00:28:05,99] [info] Assigned new job execution tokens to the following groups: 5c8eec74: 1
[2020-07-03 00:28:06,00] [�[38;5;220mwarn�[0m] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m815e1e42�[0mCalculateRegions.scatterAutosomalRegions:NA:1]: Unrecognized runtime attribute keys: cpu, time_minutes, memory
[2020-07-03 00:28:06,00] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m815e1e42�[0mCalculateRegions.scatterAutosomalRegions:NA:1]: �[38;5;5mscatter-regions \
--print-paths \
--scatter-size 1000000000 \
\
--prefix scatters/scatter- \
/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/inputs/2074281362/reference.fasta.fai�[0m
[2020-07-03 00:28:06,05] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m815e1e42�[0mCalculateRegions.scatterAutosomalRegions:NA:1]: executing: # make sure there is no preexisting Docker CID file
rm -f /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/execution/docker_cid
# run as in the original configuration without --rm flag (will remove later)
docker run \
--cidfile /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/execution/docker_cid \
-i \
--user $EUID \
--entrypoint /bin/bash \
-v /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions:/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions:delegated \
quay.io/biocontainers/chunked-scatter@sha256:88ccc279639105d7ea4defe43c06f88b738f69b1156706ba3c335d84a37e9b64 /cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/execution/script
# get the return code (working even if the container was detached)
rc=$(docker wait �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/execution/docker_cid�[0m)
# remove the container after waiting
docker rm �[38;5;5mcat /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/execution/docker_cid�[0m
# return exit code
exit $rc
[2020-07-03 00:28:07,38] [info] 44e3077d-bc41-4537-8498-c1e23d4a29a8-SubWorkflowActor-SubWorkflow-qc:0:1 [�[38;5;2m44e3077d�[0m]: Starting QC.FastqcRead1
[2020-07-03 00:28:07,99] [info] Assigned new job execution tokens to the following groups: 5c8eec74: 1
[2020-07-03 00:28:09,93] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m815e1e42�[0mCalculateRegions.scatterAutosomalRegions:NA:1]: job id: 17613
[2020-07-03 00:28:09,93] [info] BackgroundConfigAsyncJobExecutionActor [�[38;5;2m815e1e42�[0mCalculateRegions.scatterAutosomalRegions:NA:1]: Status change from - to Done
[2020-07-03 00:28:11,43] [info] 815e1e42-f503-45ad-9454-7be49d04361a-SubWorkflowActor-SubWorkflow-calculateRegions:-1:1 [�[38;5;2m815e1e42�[0m]: Workflow CalculateRegions complete. Final Outputs:
{
"CalculateRegions.autosomalRegionScatters": ["/tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-calculateRegions/CalculateRegions/815e1e42-f503-45ad-9454-7be49d04361a/call-scatterAutosomalRegions/execution/scatters/scatter-0.bed"],
"CalculateRegions.Xregions": null,
"CalculateRegions.Yregions": null,
"CalculateRegions.autosomalRegions": null
}
[2020-07-03 00:28:12,47] [info] WorkflowManagerActor Workflow 5c8eec74-dace-4680-a79b-b5ca0d003def failed (during ExecutingWorkflowState): cromwell.engine.workflow.lifecycle.execution.job.preparation.JobPreparationActor$$anonfun$1$$anon$1: Call input and runtime attributes evaluation failed for FastqcRead1:
Failed to evaluate input '__timeMinutes' (reason 1 of 1): [Attempted 1 time(s)] - NoSuchFileException: /tmp/pytest_workflow_wd6hgac4/Rna3PairedEndVariantCalling/cromwell-executions/RNAseq/5c8eec74-dace-4680-a79b-b5ca0d003def/call-sampleJobs/shard-0/SampleWorkflow/e70c60fe-c8c1-4c64-b058-c092b5838d70/call-qc/shard-0/QC/44e3077d-bc41-4537-8498-c1e23d4a29a8/call-FastqcRead1/execution/tests/data/rna3/R1.fq.gz
at cromwell.engine.workflow.lifecycle.execution.job.preparation.JobPreparationActor$$anonfun$1.applyOrElse(JobPreparationActor.scala:76)
at cromwell.engine.workflow.lifecycle.execution.job.preparation.JobPreparationActor$$anonfun$1.applyOrElse(JobPreparationActor.scala:69)
at scala.runtime.AbstractPartialFunction.apply(AbstractPartialFunction.scala:38)
at akka.actor.FSM.processEvent(FSM.scala:707)
at akka.actor.FSM.processEvent$(FSM.scala:704)
at cromwell.engine.workflow.lifecycle.execution.job.preparation.JobPreparationActor.processEvent(JobPreparationActor.scala:45)
at akka.actor.FSM.akka$actor$FSM$$processMsg(FSM.scala:701)
at akka.actor.FSM$$anonfun$receive$1.applyOrElse(FSM.scala:695)
at akka.actor.Actor.aroundReceive(Actor.scala:539)
at akka.actor.Actor.aroundReceive$(Actor.scala:537)
at cromwell.engine.workflow.lifecycle.execution.job.preparation.JobPreparationActor.aroundReceive(JobPreparationActor.scala:45)
at akka.actor.ActorCell.receiveMessage(ActorCell.scala:612)
at akka.actor.ActorCell.invoke(ActorCell.scala:581)
at akka.dispatch.Mailbox.processMailbox(Mailbox.scala:268)
at akka.dispatch.Mailbox.run(Mailbox.scala:229)
at akka.dispatch.Mailbox.exec(Mailbox.scala:241)
at akka.dispatch.forkjoin.ForkJoinTask.doExec(ForkJoinTask.java:260)
at akka.dispatch.forkjoin.ForkJoinPool$WorkQueue.runTask(ForkJoinPool.java:1339)
at akka.dispatch.forkjoin.ForkJoinPool.runWorker(ForkJoinPool.java:1979)
at akka.dispatch.forkjoin.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:107)
[2020-07-03 00:28:12,47] [info] WorkflowManagerActor WorkflowActor-5c8eec74-dace-4680-a79b-b5ca0d003def is in a terminal state: WorkflowFailedState
[2020-07-03 00:28:23,85] [info] SingleWorkflowRunnerActor workflow finished with status 'Failed'.
[2020-07-03 00:28:24,93] [info] Workflow polling stopped
[2020-07-03 00:28:24,96] [info] 0 workflows released by cromid-e524f6a
[2020-07-03 00:28:24,96] [info] Shutting down WorkflowStoreActor - Timeout = 5 seconds
[2020-07-03 00:28:24,97] [info] Shutting down WorkflowLogCopyRouter - Timeout = 5 seconds
[2020-07-03 00:28:24,97] [info] Shutting down JobExecutionTokenDispenser - Timeout = 5 seconds
[2020-07-03 00:28:24,98] [info] Aborting all running workflows.
[2020-07-03 00:28:24,98] [info] JobExecutionTokenDispenser stopped
[2020-07-03 00:28:24,98] [info] WorkflowStoreActor stopped
[2020-07-03 00:28:24,98] [info] WorkflowLogCopyRouter stopped
[2020-07-03 00:28:24,98] [info] Shutting down WorkflowManagerActor - Timeout = 3600 seconds
[2020-07-03 00:28:24,98] [info] WorkflowManagerActor All workflows finished
[2020-07-03 00:28:24,98] [info] WorkflowManagerActor stopped
[2020-07-03 00:28:25,13] [info] Connection pools shut down
[2020-07-03 00:28:25,14] [info] Shutting down SubWorkflowStoreActor - Timeout = 1800 seconds
[2020-07-03 00:28:25,14] [info] Shutting down JobStoreActor - Timeout = 1800 seconds
[2020-07-03 00:28:25,14] [info] Shutting down CallCacheWriteActor - Timeout = 1800 seconds
[2020-07-03 00:28:25,14] [info] SubWorkflowStoreActor stopped
[2020-07-03 00:28:25,14] [info] Shutting down ServiceRegistryActor - Timeout = 1800 seconds
[2020-07-03 00:28:25,14] [info] Shutting down DockerHashActor - Timeout = 1800 seconds
[2020-07-03 00:28:25,14] [info] Shutting down IoProxy - Timeout = 1800 seconds
[2020-07-03 00:28:25,14] [info] CallCacheWriteActor Shutting down: 0 queued messages to process
[2020-07-03 00:28:25,14] [info] JobStoreActor stopped
[2020-07-03 00:28:25,14] [info] CallCacheWriteActor stopped
[2020-07-03 00:28:25,14] [info] IoProxy stopped
[2020-07-03 00:28:25,14] [info] WriteMetadataActor Shutting down: 0 queued messages to process
[2020-07-03 00:28:25,14] [info] KvWriteActor Shutting down: 0 queued messages to process
[2020-07-03 00:28:25,14] [info] DockerHashActor stopped
[2020-07-03 00:28:25,14] [info] ServiceRegistryActor stopped
[2020-07-03 00:28:25,15] [info] Shutting down connection pool: curAllocated=0 idleQueues.size=0 waitQueue.size=0 maxWaitQueueLimit=256 closed=false
[2020-07-03 00:28:25,15] [info] Shutting down connection pool: curAllocated=0 idleQueues.size=0 waitQueue.size=0 maxWaitQueueLimit=256 closed=false
[2020-07-03 00:28:25,15] [info] Shutting down connection pool: curAllocated=0 idleQueues.size=0 waitQueue.size=0 maxWaitQueueLimit=256 closed=false
[2020-07-03 00:28:25,15] [info] Shutting down connection pool: curAllocated=0 idleQueues.size=0 waitQueue.size=0 maxWaitQueueLimit=256 closed=false
[2020-07-03 00:28:25,16] [info] Database closed
[2020-07-03 00:28:25,16] [info] Stream materializer shut down
[2020-07-03 00:28:25,16] [info] WDL HTTP import resolver closed