fond
Model Checking Contest 2023
13th edition, Paris, France, April 26, 2023 (at TOOLympics II)
Execution of r329-tall-167889202400721
Last Updated
May 14, 2023

About the Execution of LTSMin+red for ResAllocation-PT-R003C100

Execution Summary
Max Memory
Used (MB)
Time wait (ms) CPU Usage (ms) I/O Wait (ms) Computed Result Execution
Status
15864.899 3578751.00 14287899.00 327.80 ??F?F?TTTF????FF normal

Execution Chart

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

Trace from the execution

Formatting '/data/fkordon/mcc2023-input.r329-tall-167889202400721.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2023-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................
=====================================================================
Generated by BenchKit 2-5348
Executing tool ltsminxred
Input is ResAllocation-PT-R003C100, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r329-tall-167889202400721
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 1.2M
-rw-r--r-- 1 mcc users 7.4K Feb 25 15:23 CTLCardinality.txt
-rw-r--r-- 1 mcc users 76K Feb 25 15:23 CTLCardinality.xml
-rw-r--r-- 1 mcc users 6.0K Feb 25 15:22 CTLFireability.txt
-rw-r--r-- 1 mcc users 53K Feb 25 15:22 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K Jan 29 11:41 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 6.7K Jan 29 11:41 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 3.5K Feb 25 16:44 LTLCardinality.txt
-rw-r--r-- 1 mcc users 22K Feb 25 16:44 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.3K Feb 25 16:44 LTLFireability.txt
-rw-r--r-- 1 mcc users 17K Feb 25 16:44 LTLFireability.xml
-rw-r--r-- 1 mcc users 10K Feb 25 15:24 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 97K Feb 25 15:24 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 10K Feb 25 15:24 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 84K Feb 25 15:24 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.8K Feb 25 16:44 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.8K Feb 25 16:44 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Mar 5 18:23 equiv_col
-rw-r--r-- 1 mcc users 9 Mar 5 18:23 instance
-rw-r--r-- 1 mcc users 6 Mar 5 18:23 iscolored
-rw-r--r-- 1 mcc users 721K Mar 5 18:23 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 ResAllocation-PT-R003C100-CTLCardinality-00
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-01
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-02
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-03
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-04
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-05
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-06
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-07
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-08
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-09
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-10
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-11
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-12
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-13
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-14
FORMULA_NAME ResAllocation-PT-R003C100-CTLCardinality-15

=== Now, execution of the tool begins

BK_START 1679165975453

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
Invoking MCC driver with
BK_TOOL=ltsminxred
BK_EXAMINATION=CTLCardinality
BK_BIN_PATH=/home/mcc/BenchKit/bin/
BK_TIME_CONFINEMENT=3600
BK_INPUT=ResAllocation-PT-R003C100
Applying reductions before tool ltsmin
Invoking reducer
Running Version 202303021504
[2023-03-18 18:59:37] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, CTLCardinality, -timeout, 360, -rebuildPNML]
[2023-03-18 18:59:37] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2023-03-18 18:59:37] [INFO ] Load time of PNML (sax parser for PT used): 105 ms
[2023-03-18 18:59:37] [INFO ] Transformed 600 places.
[2023-03-18 18:59:37] [INFO ] Transformed 400 transitions.
[2023-03-18 18:59:37] [INFO ] Parsed PT model containing 600 places and 400 transitions and 1794 arcs in 173 ms.
Parsed 16 properties from file /home/mcc/execution/CTLCardinality.xml in 11 ms.
Support contains 137 out of 600 places. Attempting structural reductions.
Starting structural reductions in LTL mode, iteration 0 : 600/600 places, 400/400 transitions.
Applied a total of 0 rules in 53 ms. Remains 600 /600 variables (removed 0) and now considering 400/400 (removed 0) transitions.
// Phase 1: matrix 400 rows 600 cols
[2023-03-18 18:59:37] [INFO ] Computed 300 place invariants in 16 ms
[2023-03-18 18:59:37] [INFO ] Implicit Places using invariants in 485 ms returned [597, 599]
Discarding 2 places :
Implicit Place search using SMT only with invariants took 512 ms to find 2 implicit places.
Starting structural reductions in LTL mode, iteration 1 : 598/600 places, 400/400 transitions.
Applied a total of 0 rules in 23 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 2 iterations and 589 ms. Remains : 598/600 places, 400/400 transitions.
Support contains 137 out of 598 places after structural reductions.
[2023-03-18 18:59:38] [INFO ] Flatten gal took : 84 ms
[2023-03-18 18:59:38] [INFO ] Flatten gal took : 37 ms
[2023-03-18 18:59:38] [INFO ] Input system was already deterministic with 400 transitions.
Support contains 136 out of 598 places (down from 137) after GAL structural reductions.
Incomplete random walk after 10000 steps, including 48 resets, run finished after 571 ms. (steps per millisecond=17 ) properties (out of 90) seen :73
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 6 ms. (steps per millisecond=166 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 6 ms. (steps per millisecond=166 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 6 ms. (steps per millisecond=166 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 13 ms. (steps per millisecond=77 ) properties (out of 17) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 6 ms. (steps per millisecond=166 ) properties (out of 17) seen :0
Running SMT prover for 17 properties.
// Phase 1: matrix 400 rows 598 cols
[2023-03-18 18:59:39] [INFO ] Computed 298 place invariants in 9 ms
[2023-03-18 18:59:39] [INFO ] [Real]Absence check using 298 positive place invariants in 47 ms returned sat
[2023-03-18 18:59:39] [INFO ] After 319ms SMT Verify possible using all constraints in real domain returned unsat :14 sat :0 real:3
[2023-03-18 18:59:39] [INFO ] [Nat]Absence check using 298 positive place invariants in 41 ms returned sat
[2023-03-18 18:59:39] [INFO ] After 181ms SMT Verify possible using state equation in natural domain returned unsat :16 sat :1
[2023-03-18 18:59:39] [INFO ] Deduced a trap composed of 4 places in 45 ms of which 5 ms to minimize.
[2023-03-18 18:59:39] [INFO ] Deduced a trap composed of 4 places in 68 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 4 places in 41 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 4 places in 45 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 4 places in 32 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 4 places in 29 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 6 places in 28 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 6 places in 28 ms of which 1 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Deduced a trap composed of 4 places in 24 ms of which 0 ms to minimize.
[2023-03-18 18:59:40] [INFO ] Trap strengthening (SAT) tested/added 10/9 trap constraints in 568 ms
[2023-03-18 18:59:40] [INFO ] After 767ms SMT Verify possible using trap constraints in natural domain returned unsat :16 sat :1
Attempting to minimize the solution found.
Minimization took 22 ms.
[2023-03-18 18:59:40] [INFO ] After 961ms SMT Verify possible using all constraints in natural domain returned unsat :16 sat :1
Fused 17 Parikh solutions to 1 different solutions.
Finished Parikh walk after 83 steps, including 0 resets, run visited all 1 properties in 2 ms. (steps per millisecond=41 )
Parikh walk visited 1 properties in 3 ms.
Successfully simplified 16 atomic propositions for a total of 16 simplifications.
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 31 ms
[2023-03-18 18:59:40] [INFO ] Initial state reduction rules for CTL removed 1 formulas.
FORMULA ResAllocation-PT-R003C100-CTLCardinality-14 FALSE TECHNIQUES TOPOLOGICAL INITIAL_STATE
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 30 ms
[2023-03-18 18:59:40] [INFO ] Input system was already deterministic with 400 transitions.
Support contains 105 out of 598 places (down from 119) after GAL structural reductions.
Computed a total of 0 stabilizing places and 0 stable transitions
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 10 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 10 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 24 ms
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 25 ms
[2023-03-18 18:59:40] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 14 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 14 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 21 ms
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 22 ms
[2023-03-18 18:59:40] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 8 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 8 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 19 ms
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 19 ms
[2023-03-18 18:59:40] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 8 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 8 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:40] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 8 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 9 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:40] [INFO ] Flatten gal took : 17 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 9 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 9 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in SI_CTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Performed 99 Post agglomeration using F-continuation condition.Transition count delta: 99
Deduced a syphon composed of 99 places in 1 ms
Reduce places removed 100 places and 0 transitions.
Iterating global reduction 0 with 199 rules applied. Total rules applied 199 place count 498 transition count 301
Applied a total of 199 rules in 56 ms. Remains 498 /598 variables (removed 100) and now considering 301/400 (removed 99) transitions.
Finished structural reductions in SI_CTL mode , in 1 iterations and 56 ms. Remains : 498/598 places, 301/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 11 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 12 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 301 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 9 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 9 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in SI_CTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Performed 100 Post agglomeration using F-continuation condition.Transition count delta: 100
Deduced a syphon composed of 100 places in 0 ms
Reduce places removed 101 places and 0 transitions.
Iterating global reduction 0 with 201 rules applied. Total rules applied 201 place count 497 transition count 300
Applied a total of 201 rules in 38 ms. Remains 497 /598 variables (removed 101) and now considering 300/400 (removed 100) transitions.
Finished structural reductions in SI_CTL mode , in 1 iterations and 38 ms. Remains : 497/598 places, 300/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 10 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 10 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 300 transitions.
Finished random walk after 85 steps, including 0 resets, run visited all 1 properties in 3 ms. (steps per millisecond=28 )
FORMULA ResAllocation-PT-R003C100-CTLCardinality-08 TRUE TECHNIQUES TOPOLOGICAL RANDOM_WALK
Starting structural reductions in SI_CTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Performed 99 Post agglomeration using F-continuation condition.Transition count delta: 99
Deduced a syphon composed of 99 places in 0 ms
Reduce places removed 100 places and 0 transitions.
Iterating global reduction 0 with 199 rules applied. Total rules applied 199 place count 498 transition count 301
Applied a total of 199 rules in 28 ms. Remains 498 /598 variables (removed 100) and now considering 301/400 (removed 99) transitions.
Finished structural reductions in SI_CTL mode , in 1 iterations and 28 ms. Remains : 498/598 places, 301/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 11 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 11 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 301 transitions.
Starting structural reductions in SI_CTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Performed 99 Post agglomeration using F-continuation condition.Transition count delta: 99
Deduced a syphon composed of 99 places in 0 ms
Reduce places removed 100 places and 0 transitions.
Iterating global reduction 0 with 199 rules applied. Total rules applied 199 place count 498 transition count 301
Applied a total of 199 rules in 26 ms. Remains 498 /598 variables (removed 100) and now considering 301/400 (removed 99) transitions.
Finished structural reductions in SI_CTL mode , in 1 iterations and 26 ms. Remains : 498/598 places, 301/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 10 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 15 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 301 transitions.
Starting structural reductions in SI_CTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Performed 98 Post agglomeration using F-continuation condition.Transition count delta: 98
Deduced a syphon composed of 98 places in 0 ms
Reduce places removed 99 places and 0 transitions.
Iterating global reduction 0 with 197 rules applied. Total rules applied 197 place count 499 transition count 302
Applied a total of 197 rules in 24 ms. Remains 499 /598 variables (removed 99) and now considering 302/400 (removed 98) transitions.
Finished structural reductions in SI_CTL mode , in 1 iterations and 24 ms. Remains : 499/598 places, 302/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 10 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 11 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 302 transitions.
Starting structural reductions in SI_CTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Performed 95 Post agglomeration using F-continuation condition.Transition count delta: 95
Deduced a syphon composed of 95 places in 0 ms
Reduce places removed 96 places and 0 transitions.
Iterating global reduction 0 with 191 rules applied. Total rules applied 191 place count 502 transition count 305
Applied a total of 191 rules in 24 ms. Remains 502 /598 variables (removed 96) and now considering 305/400 (removed 95) transitions.
Finished structural reductions in SI_CTL mode , in 1 iterations and 24 ms. Remains : 502/598 places, 305/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 11 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 18 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 305 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 7 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 7 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 15 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 19 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 400 transitions.
Starting structural reductions in LTL mode, iteration 0 : 598/598 places, 400/400 transitions.
Applied a total of 0 rules in 7 ms. Remains 598 /598 variables (removed 0) and now considering 400/400 (removed 0) transitions.
Finished structural reductions in LTL mode , in 1 iterations and 7 ms. Remains : 598/598 places, 400/400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 14 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 15 ms
[2023-03-18 18:59:41] [INFO ] Input system was already deterministic with 400 transitions.
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 17 ms
[2023-03-18 18:59:41] [INFO ] Flatten gal took : 16 ms
[2023-03-18 18:59:41] [INFO ] Export to MCC of 14 properties in file /home/mcc/execution/CTLCardinality.sr.xml took 2 ms.
[2023-03-18 18:59:41] [INFO ] Export to PNML in file /home/mcc/execution/model.sr.pnml of net with 598 places, 400 transitions and 1790 arcs took 3 ms.
Total runtime 4686 ms.
There are residual formulas that ITS could not solve within timeout
pnml2lts-sym model.pnml --lace-workers=4 --vset=lddmc --saturation=sat -rbs,w2W,ru,hf --sylvan-sizes=20,28,20,28 --ctl=/tmp/486/ctl_0_ --ctl=/tmp/486/ctl_1_ --ctl=/tmp/486/ctl_2_ --ctl=/tmp/486/ctl_3_ --ctl=/tmp/486/ctl_4_ --ctl=/tmp/486/ctl_5_ --ctl=/tmp/486/ctl_6_ --ctl=/tmp/486/ctl_7_ --ctl=/tmp/486/ctl_8_ --ctl=/tmp/486/ctl_9_ --ctl=/tmp/486/ctl_10_ --ctl=/tmp/486/ctl_11_ --ctl=/tmp/486/ctl_12_ --ctl=/tmp/486/ctl_13_ --mu-par --mu-opt
TIME LIMIT: Killed by timeout after 3570 seconds
MemTotal: 16393232 kB
MemFree: 291736 kB
After kill :
MemTotal: 16393232 kB
MemFree: 16097964 kB
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-00
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-01
FORMULA ResAllocation-PT-R003C100-CTLCardinality-02 FALSE TECHNIQUES DECISION_DIAGRAMS PARALLEL_PROCESSING USE_NUPN
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-03
FORMULA ResAllocation-PT-R003C100-CTLCardinality-04 FALSE TECHNIQUES DECISION_DIAGRAMS PARALLEL_PROCESSING USE_NUPN
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-05
FORMULA ResAllocation-PT-R003C100-CTLCardinality-06 TRUE TECHNIQUES DECISION_DIAGRAMS PARALLEL_PROCESSING USE_NUPN
FORMULA ResAllocation-PT-R003C100-CTLCardinality-07 TRUE TECHNIQUES DECISION_DIAGRAMS PARALLEL_PROCESSING USE_NUPN
FORMULA ResAllocation-PT-R003C100-CTLCardinality-09 FALSE TECHNIQUES DECISION_DIAGRAMS PARALLEL_PROCESSING USE_NUPN
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-10
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-11
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-12
Could not compute solution for formula : ResAllocation-PT-R003C100-CTLCardinality-13
FORMULA ResAllocation-PT-R003C100-CTLCardinality-15 FALSE TECHNIQUES DECISION_DIAGRAMS PARALLEL_PROCESSING USE_NUPN

BK_STOP 1679169554204

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

+ ulimit -s 65536
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
+ export PYTHONPATH=/home/mcc/BenchKit/itstools/pylibs
+ PYTHONPATH=/home/mcc/BenchKit/itstools/pylibs
+ export LD_LIBRARY_PATH=/home/mcc/BenchKit/itstools/pylibs:
+ LD_LIBRARY_PATH=/home/mcc/BenchKit/itstools/pylibs:
++ sed s/.jar//
++ perl -pe 's/.*\.//g'
++ ls /home/mcc/BenchKit/bin//../reducer/bin//../../itstools//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202303021504.jar
+ VERSION=202303021504
+ echo 'Running Version 202303021504'
+ /home/mcc/BenchKit/bin//../reducer/bin//../../itstools//itstools/its-tools -pnfolder /home/mcc/execution -examination CTLCardinality -timeout 360 -rebuildPNML
mcc2023
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-00
ctl formula formula --ctl=/tmp/486/ctl_0_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-01
ctl formula formula --ctl=/tmp/486/ctl_1_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-02
ctl formula formula --ctl=/tmp/486/ctl_2_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-03
ctl formula formula --ctl=/tmp/486/ctl_3_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-04
ctl formula formula --ctl=/tmp/486/ctl_4_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-05
ctl formula formula --ctl=/tmp/486/ctl_5_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-06
ctl formula formula --ctl=/tmp/486/ctl_6_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-07
ctl formula formula --ctl=/tmp/486/ctl_7_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-09
ctl formula formula --ctl=/tmp/486/ctl_8_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-10
ctl formula formula --ctl=/tmp/486/ctl_9_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-11
ctl formula formula --ctl=/tmp/486/ctl_10_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-12
ctl formula formula --ctl=/tmp/486/ctl_11_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-13
ctl formula formula --ctl=/tmp/486/ctl_12_
ctl formula name ResAllocation-PT-R003C100-CTLCardinality-15
ctl formula formula --ctl=/tmp/486/ctl_13_
pnml2lts-sym: Exploration order is bfs-prev
pnml2lts-sym: Saturation strategy is sat
pnml2lts-sym: Guided search strategy is unguided
pnml2lts-sym: Attractor strategy is default
pnml2lts-sym: opening model.pnml
pnml2lts-sym: Edge label is id
Warning: program compiled against libxml 210 using older 209
pnml2lts-sym: Petri net has 598 places, 400 transitions and 1790 arcs
pnml2lts-sym: Petri net Petri analyzed
pnml2lts-sym: There are no safe places
pnml2lts-sym: Loading Petri net took 0.000 real 0.000 user 0.000 sys
pnml2lts-sym: Initializing regrouping layer
pnml2lts-sym: Regroup specification: bs,w2W,ru,hf
pnml2lts-sym: Regroup Boost's Sloan
pnml2lts-sym: Regroup over-approximate must-write to may-write
pnml2lts-sym: Regroup Row sUbsume
pnml2lts-sym: Reqroup Horizontal Flip
pnml2lts-sym: Regrouping: 400->200 groups
pnml2lts-sym: Regrouping took 0.090 real 0.080 user 0.000 sys
pnml2lts-sym: state vector length is 598; there are 200 groups
pnml2lts-sym: Creating a multi-core ListDD domain.
pnml2lts-sym: Sylvan allocates 15.000 GB virtual memory for nodes table and operation cache.
pnml2lts-sym: Initial nodes table and operation cache requires 60.00 MB.
pnml2lts-sym: Using GBgetTransitionsShortR2W as next-state function
pnml2lts-sym: got initial state
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: parsing CTL formula
pnml2lts-sym: converting CTL to mu-calculus...
pnml2lts-sym: Exploration took 2680 group checks and 0 next state calls
pnml2lts-sym: reachability took 0.140 real 0.520 user 0.040 sys
pnml2lts-sym: counting visited states...
pnml2lts-sym: counting took 0.000 real 0.010 user 0.000 sys
pnml2lts-sym: state space has 1.41951197160194e+57 states, 5338 nodes
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: Formula /tmp/486/ctl_13_ does not hold for the initial state
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: Formula /tmp/486/ctl_2_ does not hold for the initial state
pnml2lts-sym: Formula /tmp/486/ctl_4_ does not hold for the initial state
pnml2lts-sym: Formula /tmp/486/ctl_6_ holds for the initial state
pnml2lts-sym: Formula /tmp/486/ctl_7_ holds for the initial state
pnml2lts-sym: Formula /tmp/486/ctl_8_ does not hold for the initial state
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
pnml2lts-sym: vset_sylvan: starting garbage collection
pnml2lts-sym: vset_sylvan: garbage collection done
Killing (15) : 492
Killing (9) : 492

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="ResAllocation-PT-R003C100"
export BK_EXAMINATION="CTLCardinality"
export BK_TOOL="ltsminxred"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"
export BK_BIN_PATH="/home/mcc/BenchKit/bin/"

# 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-5348"
echo " Executing tool ltsminxred"
echo " Input is ResAllocation-PT-R003C100, examination is CTLCardinality"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r329-tall-167889202400721"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/ResAllocation-PT-R003C100.tgz
mv ResAllocation-PT-R003C100 execution
cd execution
if [ "CTLCardinality" = "ReachabilityDeadlock" ] || [ "CTLCardinality" = "UpperBounds" ] || [ "CTLCardinality" = "QuasiLiveness" ] || [ "CTLCardinality" = "StableMarking" ] || [ "CTLCardinality" = "Liveness" ] || [ "CTLCardinality" = "OneSafe" ] || [ "CTLCardinality" = "StateSpace" ]; 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 [ "CTLCardinality" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "CTLCardinality" != "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 "CTLCardinality.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property CTLCardinality.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "CTLCardinality.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 '' CTLCardinality.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ "CTLCardinality" = "ReachabilityDeadlock" ] || [ "CTLCardinality" = "QuasiLiveness" ] || [ "CTLCardinality" = "StableMarking" ] || [ "CTLCardinality" = "Liveness" ] || [ "CTLCardinality" = "OneSafe" ] ; then
echo "FORMULA_NAME CTLCardinality"
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 ;