fond
Model Checking Contest 2019
9th edition, Prague, Czech Republic, April 7, 2019 (TOOLympics)
Execution of r189-csrt-155225080900779
Last Updated
Apr 15, 2019

About the Execution of ITS-Tools.M for BusinessProcesses-PT-12

Execution Summary
Max Memory
Used (MB)
Time wait (ms) CPU Usage (ms) I/O Wait (ms) Computed Result Execution
Status
15900.930 1856209.00 1876615.00 310.30 [undef] Cannot compute

Execution Chart

We display below the execution chart for this examination (boot time has been removed).

Trace from the execution

Formatting '/local/x2003239/mcc2019-input.r189-csrt-155225080900779.qcow2', fmt=qcow2 size=4294967296 backing_file=/local/x2003239/mcc2019-input.qcow2 encryption=off cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
.....................
=====================================================================
Generated by BenchKit 2-3954
Executing tool itstoolsm
Input is BusinessProcesses-PT-12, examination is CTLFireability
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r189-csrt-155225080900779
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 312K
-rw-r--r-- 1 mcc users 3.5K Feb 9 08:20 CTLCardinality.txt
-rw-r--r-- 1 mcc users 19K Feb 9 08:20 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.3K Feb 5 06:46 CTLFireability.txt
-rw-r--r-- 1 mcc users 14K Feb 5 06:46 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 10 17:31 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 6.3K Mar 10 17:31 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 110 Feb 24 15:05 GlobalProperties.txt
-rw-r--r-- 1 mcc users 348 Feb 24 15:05 GlobalProperties.xml
-rw-r--r-- 1 mcc users 2.5K Feb 4 23:03 LTLCardinality.txt
-rw-r--r-- 1 mcc users 11K Feb 4 23:03 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.0K Feb 4 22:32 LTLFireability.txt
-rw-r--r-- 1 mcc users 11K Feb 4 22:32 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.9K Feb 2 01:37 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 21K Feb 2 01:37 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 2.7K Jan 29 14:56 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 15K Jan 29 14:56 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.7K Feb 4 22:18 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.8K Feb 4 22:18 UpperBounds.xml

-rw-r--r-- 1 mcc users 6 Jan 29 09:34 equiv_col
-rw-r--r-- 1 mcc users 3 Jan 29 09:34 instance
-rw-r--r-- 1 mcc users 6 Jan 29 09:34 iscolored
-rw-r--r-- 1 mcc users 146K Mar 10 17:31 model.pnml

--------------------
content from stdout:

=== Data for post analysis generated by BenchKit (invocation template)

The expected result is a vector of booleans
BOOL_VECTOR

here is the order used to build the result vector(from text file)
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-00
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-01
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-02
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-03
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-04
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-05
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-06
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-07
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-08
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-09
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-10
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-11
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-12
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-13
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-14
FORMULA_NAME BusinessProcesses-PT-12-CTLFireability-15

=== Now, execution of the tool begins

BK_START 1553646112438

Running greatSPN : CommandLine [args=[/home/mcc/BenchKit//greatspn//bin/pinvar, /home/mcc/execution/gspn], workingDir=/home/mcc/execution]
P-invariant computation with GreatSPN timed out. Skipping.
Running greatSPN : CommandLine [args=[/home/mcc/BenchKit//greatspn//bin/RGMEDD2, /home/mcc/execution/gspn, -META, -varord-only], workingDir=/home/mcc/execution]
Run of greatSPN captured in /home/mcc/execution/outPut.txt
Using order generated by GreatSPN with heuristic : META
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903251645/bin/its-ctl-linux64, --gc-threshold, 2000000, --quiet, -i, /home/mcc/execution/CTLFireability.pnml.gal, -t, CGAL, -ctl, /home/mcc/execution/CTLFireability.ctl, --load-order, /home/mcc/execution/model.ord], workingDir=/home/mcc/execution]

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903251645/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /home/mcc/execution/CTLFireability.pnml.gal -t CGAL -ctl /home/mcc/execution/CTLFireability.ctl --load-order /home/mcc/execution/model.ord
Successfully loaded order from file /home/mcc/execution/model.ord
No direction supplied, using forward translation only.
Parsed 16 CTL formulae.
sparsehash FATAL ERROR: failed to allocate 41 groups
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903251645/bin/its-ctl-linux64, --gc-threshold, 2000000, --quiet, -i, /home/mcc/execution/CTLFireability.pnml.gal, -t, CGAL, -ctl, /home/mcc/execution/CTLFireability.ctl], workingDir=/home/mcc/execution]

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903251645/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /home/mcc/execution/CTLFireability.pnml.gal -t CGAL -ctl /home/mcc/execution/CTLFireability.ctl
No direction supplied, using forward translation only.
Parsed 16 CTL formulae.
built 2 ordering constraints for composite.
built 132 ordering constraints for composite.
built 75 ordering constraints for composite.
built 74 ordering constraints for composite.
built 73 ordering constraints for composite.
built 73 ordering constraints for composite.
built 71 ordering constraints for composite.
built 68 ordering constraints for composite.
built 66 ordering constraints for composite.
built 64 ordering constraints for composite.
built 62 ordering constraints for composite.
built 60 ordering constraints for composite.
built 58 ordering constraints for composite.
built 54 ordering constraints for composite.
built 52 ordering constraints for composite.
built 50 ordering constraints for composite.
built 48 ordering constraints for composite.
built 44 ordering constraints for composite.
built 42 ordering constraints for composite.
built 39 ordering constraints for composite.
built 37 ordering constraints for composite.
built 35 ordering constraints for composite.
built 16 ordering constraints for composite.
built 14 ordering constraints for composite.
built 12 ordering constraints for composite.
built 7 ordering constraints for composite.
built 25 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 59 ordering constraints for composite.
built 57 ordering constraints for composite.
built 55 ordering constraints for composite.
built 53 ordering constraints for composite.
built 51 ordering constraints for composite.
built 49 ordering constraints for composite.
built 47 ordering constraints for composite.
built 45 ordering constraints for composite.
built 43 ordering constraints for composite.
built 41 ordering constraints for composite.
built 39 ordering constraints for composite.
built 37 ordering constraints for composite.
built 35 ordering constraints for composite.
built 33 ordering constraints for composite.
built 31 ordering constraints for composite.
built 29 ordering constraints for composite.
built 27 ordering constraints for composite.
built 25 ordering constraints for composite.
built 23 ordering constraints for composite.
built 21 ordering constraints for composite.
built 19 ordering constraints for composite.
built 17 ordering constraints for composite.
built 15 ordering constraints for composite.
built 13 ordering constraints for composite.
built 11 ordering constraints for composite.
built 9 ordering constraints for composite.
built 7 ordering constraints for composite.
built 6 ordering constraints for composite.
built 80 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
terminate called after throwing an instance of 'std::bad_alloc'
what(): std::bad_alloc
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903251645/bin/its-ctl-linux64, --gc-threshold, 2000000, --quiet, -i, /home/mcc/execution/CTLFireability.pnml.gal, -t, CGAL, -ctl, /home/mcc/execution/CTLFireability.ctl], workingDir=/home/mcc/execution]

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903251645/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /home/mcc/execution/CTLFireability.pnml.gal -t CGAL -ctl /home/mcc/execution/CTLFireability.ctl
No direction supplied, using forward translation only.
Parsed 16 CTL formulae.
built 2 ordering constraints for composite.
built 132 ordering constraints for composite.
built 75 ordering constraints for composite.
built 74 ordering constraints for composite.
built 73 ordering constraints for composite.
built 73 ordering constraints for composite.
built 71 ordering constraints for composite.
built 68 ordering constraints for composite.
built 66 ordering constraints for composite.
built 64 ordering constraints for composite.
built 62 ordering constraints for composite.
built 60 ordering constraints for composite.
built 58 ordering constraints for composite.
built 54 ordering constraints for composite.
built 52 ordering constraints for composite.
built 50 ordering constraints for composite.
built 48 ordering constraints for composite.
built 44 ordering constraints for composite.
built 42 ordering constraints for composite.
built 39 ordering constraints for composite.
built 37 ordering constraints for composite.
built 35 ordering constraints for composite.
built 16 ordering constraints for composite.
built 14 ordering constraints for composite.
built 12 ordering constraints for composite.
built 7 ordering constraints for composite.
built 25 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
built 59 ordering constraints for composite.
built 57 ordering constraints for composite.
built 55 ordering constraints for composite.
built 53 ordering constraints for composite.
built 51 ordering constraints for composite.
built 49 ordering constraints for composite.
built 47 ordering constraints for composite.
built 45 ordering constraints for composite.
built 43 ordering constraints for composite.
built 41 ordering constraints for composite.
built 39 ordering constraints for composite.
built 37 ordering constraints for composite.
built 35 ordering constraints for composite.
built 33 ordering constraints for composite.
built 31 ordering constraints for composite.
built 29 ordering constraints for composite.
built 27 ordering constraints for composite.
built 25 ordering constraints for composite.
built 23 ordering constraints for composite.
built 21 ordering constraints for composite.
built 19 ordering constraints for composite.
built 17 ordering constraints for composite.
built 15 ordering constraints for composite.
built 13 ordering constraints for composite.
built 11 ordering constraints for composite.
built 9 ordering constraints for composite.
built 7 ordering constraints for composite.
built 6 ordering constraints for composite.
built 80 ordering constraints for composite.
built 6 ordering constraints for composite.
built 6 ordering constraints for composite.
terminate called after throwing an instance of 'std::bad_alloc'
what(): std::bad_alloc

BK_STOP 1553647968647

--------------------
content from stderr:

+ export BINDIR=/home/mcc/BenchKit/
+ BINDIR=/home/mcc/BenchKit/
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ CTLFireability = StateSpace ]]
+ /home/mcc/BenchKit//runeclipse.sh /home/mcc/execution CTLFireability -its -ltsminpath /home/mcc/BenchKit//lts_install_dir/ -greatspnpath /home/mcc/BenchKit//greatspn/ -order META -manyOrder -smt
+ ulimit -s 65536
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
+ /home/mcc/BenchKit//itstools/its-tools -data /home/mcc/execution/workspace -pnfolder /home/mcc/execution -examination CTLFireability -z3path /home/mcc/BenchKit//z3/bin/z3 -yices2path /home/mcc/BenchKit//yices/bin/yices -its -ltsminpath /home/mcc/BenchKit//lts_install_dir/ -greatspnpath /home/mcc/BenchKit//greatspn/ -order META -manyOrder -smt -vmargs -Dosgi.locking=none -Declipse.stateSaveDelayInterval=-1 -Dosgi.configuration.area=/tmp/.eclipse -Xss8m -Xms40m -Xmx8192m -Dfile.encoding=UTF-8 -Dosgi.requiredJavaVersion=1.6
Mar 27, 2019 12:21:54 AM fr.lip6.move.gal.application.Application start
INFO: Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, CTLFireability, -z3path, /home/mcc/BenchKit//z3/bin/z3, -yices2path, /home/mcc/BenchKit//yices/bin/yices, -its, -ltsminpath, /home/mcc/BenchKit//lts_install_dir/, -greatspnpath, /home/mcc/BenchKit//greatspn/, -order, META, -manyOrder, -smt]
Mar 27, 2019 12:21:54 AM fr.lip6.move.gal.application.MccTranslator transformPNML
INFO: Parsing pnml file : /home/mcc/execution/model.pnml
Mar 27, 2019 12:21:54 AM fr.lip6.move.gal.nupn.PTNetReader loadFromXML
INFO: Load time of PNML (sax parser for PT used): 149 ms
Mar 27, 2019 12:21:54 AM fr.lip6.move.gal.pnml.togal.PTGALTransformer handlePage
INFO: Transformed 586 places.
Mar 27, 2019 12:21:54 AM fr.lip6.move.gal.pnml.togal.PTGALTransformer handlePage
INFO: Transformed 525 transitions.
Mar 27, 2019 12:21:54 AM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Found NUPN structural information;
Mar 27, 2019 12:21:54 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.img.gal : 34 ms
Mar 27, 2019 12:21:55 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 119 ms
Mar 27, 2019 12:21:55 AM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was already deterministic with 525 transitions.
Mar 27, 2019 12:21:55 AM fr.lip6.move.gal.application.StructuralToGreatSPN handlePage
INFO: Transformed 586 places.
Mar 27, 2019 12:21:55 AM fr.lip6.move.gal.application.StructuralToGreatSPN handlePage
INFO: Transformed 525 transitions.
Mar 27, 2019 12:22:25 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/CTLFireability.pnml.gal : 5 ms
Mar 27, 2019 12:22:25 AM fr.lip6.move.serialization.SerializationUtil serializePropertiesForITSCTLTools
INFO: Time to serialize properties into /home/mcc/execution/CTLFireability.ctl : 1 ms
ITS-tools command line returned an error code 1
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.application.MccTranslator applyOrder
INFO: Applying decomposition
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 90 ms
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 80 ms
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.CompositeBuilder decomposeWithOrder
INFO: Decomposing Gal with order
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting arrays to variables to allow decomposition.
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t497,t497,t497,
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t462,t462,t462,
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t457,t457,t457,
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t474,t474,t474,
Mar 27, 2019 12:35:17 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 9 events :t463,t468,t458,t468,t463,t468,t458,t463,t458,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 9 events :t465,t465,t465,t460,t460,t460,t455,t455,t455,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t470,t470,t470,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t492,t492,t492,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t505,t505,t505,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t465,t465,t465,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t455,t455,t455,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t479,t479,t479,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t456,t456,t456,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t466,t466,t466,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t501,t501,t501,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 15 events :t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t460,t460,t460,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 15 events :t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 15 events :t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t483,t483,t483,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t488,t488,t488,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t467,t467,t467,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t461,t461,t461,
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.Instantiator fuseIsomorphicEffects
INFO: Removed a total of 117 redundant transitions.
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 219 ms
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub1 :[i1.u134.t479, i1.u196.t455, i1.u136.t470]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub1 :[i1.u41.t457, i1.u194.t458]
Mar 27, 2019 12:35:18 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/CTLFireability.pnml.gal : 40 ms
Mar 27, 2019 12:35:18 AM fr.lip6.move.serialization.SerializationUtil serializePropertiesForITSCTLTools
INFO: Time to serialize properties into /home/mcc/execution/CTLFireability.ctl : 2 ms
ITS-tools command line returned an error code 134
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.application.MccTranslator applyOrder
INFO: Applying decomposition
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 111 ms
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 56 ms
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.CompositeBuilder decomposeWithOrder
INFO: Decomposing Gal with order
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting arrays to variables to allow decomposition.
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t466,t466,t466,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t460,t460,t460,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t479,t479,t479,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t497,t497,t497,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t457,t457,t457,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 9 events :t465,t465,t465,t460,t460,t460,t455,t455,t455,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 15 events :t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,t465,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 15 events :t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,t460,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t462,t462,t462,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t465,t465,t465,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 9 events :t463,t468,t458,t468,t463,t468,t458,t463,t458,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t505,t505,t505,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t488,t488,t488,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t492,t492,t492,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t474,t474,t474,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t501,t501,t501,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t455,t455,t455,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t483,t483,t483,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t456,t456,t456,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 15 events :t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,t455,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t461,t461,t461,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t470,t470,t470,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 3 events :t467,t467,t467,
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.Instantiator fuseIsomorphicEffects
INFO: Removed a total of 117 redundant transitions.
Mar 27, 2019 12:44:06 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 95 ms
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub1 :[i1.u134.t479, i1.u196.t455, i1.u136.t470]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0 :[i0.u41.t457, i0.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.gal.instantiate.CompositeBuilder fuseSimilarLabels
INFO: Found fuseable labels in BusinessProcesses_PT_12_flat_flat_mod_flat_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub0_sub1 :[i1.u41.t457, i1.u194.t458]
Mar 27, 2019 12:44:07 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/CTLFireability.pnml.gal : 16 ms
Mar 27, 2019 12:44:07 AM fr.lip6.move.serialization.SerializationUtil serializePropertiesForITSCTLTools
INFO: Time to serialize properties into /home/mcc/execution/CTLFireability.ctl : 1 ms
ITS-tools command line returned an error code 134

Sequence of Actions to be Executed by the VM

This is useful if one wants to reexecute the tool in the VM from the submitted image disk.

set -x
# this is for BenchKit: configuration of major elements for the test
export BK_INPUT="BusinessProcesses-PT-12"
export BK_EXAMINATION="CTLFireability"
export BK_TOOL="itstoolsm"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"

# this is specific to your benchmark or test

export BIN_DIR="$HOME/BenchKit/bin"

# remove the execution directoty if it exists (to avoid increse of .vmdk images)
if [ -d execution ] ; then
rm -rf execution
fi

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-3954"
echo " Executing tool itstoolsm"
echo " Input is BusinessProcesses-PT-12, examination is CTLFireability"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r189-csrt-155225080900779"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/BusinessProcesses-PT-12.tgz
mv BusinessProcesses-PT-12 execution
cd execution
if [ "CTLFireability" = "GlobalProperties" ] ; then
rm -f GenericPropertiesVerdict.xml
fi
if [ "CTLFireability" = "UpperBounds" ] ; then
rm -f GenericPropertiesVerdict.xml
fi
pwd
ls -lh

echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "CTLFireability" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "CTLFireability" != "StateSpace" ] ; then
echo "The expected result is a vector of booleans"
echo BOOL_VECTOR
else
echo "no data necessary for post analysis"
fi
echo
if [ -f "CTLFireability.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property CTLFireability.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "CTLFireability.xml" ] ; then # for cunf (txt files deleted;-)
echo echo "here is the order used to build the result vector(from xml file)"
for x in $(grep '' CTLFireability.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
fi
echo
echo "=== Now, execution of the tool begins"
echo
echo -n "BK_START "
date -u +%s%3N
echo
timeout -s 9 $BK_TIME_CONFINEMENT bash -c "/home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n \"BK_STOP \" ; date -u +%s%3N"
if [ $? -eq 137 ] ; then
echo
echo "BK_TIME_CONFINEMENT_REACHED"
fi
echo
echo "--------------------"
echo "content from stderr:"
echo
cat STDERR ;