Execution Chart

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

Trace from the execution

Formatting '/data/fkordon/mcc2022-input.r085-tall-165260115500107.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2022-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
...................
=====================================================================
Generated by BenchKit 2-4028
Executing tool itstools
Input is DoubleExponent-PT-002, examination is QuasiLiveness
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r085-tall-165260115500107
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 252K
-rw-r--r-- 1 mcc users 6.0K Apr 29 13:48 CTLCardinality.txt
-rw-r--r-- 1 mcc users 64K Apr 29 13:48 CTLCardinality.xml
-rw-r--r-- 1 mcc users 5.4K Apr 29 13:47 CTLFireability.txt
-rw-r--r-- 1 mcc users 50K Apr 29 13:47 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K May 10 09:33 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 3.7K May 9 07:41 LTLCardinality.txt
-rw-r--r-- 1 mcc users 26K May 9 07:41 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.3K May 9 07:41 LTLFireability.txt
-rw-r--r-- 1 mcc users 20K May 9 07:41 LTLFireability.xml
-rw-r--r-- 1 mcc users 1.7K May 9 07:41 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.7K May 9 07:41 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 May 10 09:33 equiv_col
-rw-r--r-- 1 mcc users 4 May 10 09:33 instance
-rw-r--r-- 1 mcc users 6 May 10 09:33 iscolored
-rw-r--r-- 1 mcc users 33K May 10 09:33 model.pnml

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

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

The expected result is a vector of booleans
BOOL_VECTOR

FORMULA_NAME QuasiLiveness

=== Now, execution of the tool begins

BK_START 1652702001705

Running Version 202205111006
[2022-05-16 11:53:22] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, QuasiLiveness, -spotpath, /home/mcc/BenchKit/bin//..//ltlfilt, -z3path, /home/mcc/BenchKit/bin//..//z3/bin/z3, -yices2path, /home/mcc/BenchKit/bin//..//yices/bin/yices, -its, -ltsmin, -greatspnpath, /home/mcc/BenchKit/bin//..//greatspn/, -order, META, -manyOrder, -smt, -timeout, 1800]
[2022-05-16 11:53:22] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2022-05-16 11:53:23] [INFO ] Load time of PNML (sax parser for PT used): 67 ms
[2022-05-16 11:53:23] [INFO ] Transformed 110 places.
[2022-05-16 11:53:23] [INFO ] Transformed 98 transitions.
[2022-05-16 11:53:23] [INFO ] Parsed PT model containing 110 places and 98 transitions in 170 ms.
Starting structural reductions in LIVENESS mode, iteration 0 : 110/110 places, 98/98 transitions.
Reduce places removed 4 places and 0 transitions.
Drop transitions removed 24 transitions
Trivial Post-agglo rules discarded 24 transitions
Performed 24 trivial Post agglomeration. Transition count delta: 24
Iterating post reduction 0 with 28 rules applied. Total rules applied 28 place count 106 transition count 74
Reduce places removed 24 places and 0 transitions.
Iterating post reduction 1 with 24 rules applied. Total rules applied 52 place count 82 transition count 74
Performed 4 Pre agglomeration using Quasi-Persistent + Divergent Free condition..
Pre-agglomeration after 2 with 4 Pre rules applied. Total rules applied 52 place count 82 transition count 70
Deduced a syphon composed of 4 places in 1 ms
Reduce places removed 4 places and 0 transitions.
Iterating global reduction 2 with 8 rules applied. Total rules applied 60 place count 78 transition count 70
Performed 22 Post agglomeration using F-continuation condition.Transition count delta: 22
Deduced a syphon composed of 22 places in 0 ms
Reduce places removed 22 places and 0 transitions.
Iterating global reduction 2 with 44 rules applied. Total rules applied 104 place count 56 transition count 48
Performed 2 Post agglomeration using F-continuation condition.Transition count delta: 0
Deduced a syphon composed of 2 places in 1 ms
Reduce places removed 2 places and 0 transitions.
Iterating global reduction 2 with 4 rules applied. Total rules applied 108 place count 54 transition count 48
Applied a total of 108 rules in 35 ms. Remains 54 /110 variables (removed 56) and now considering 48/98 (removed 50) transitions.
// Phase 1: matrix 48 rows 54 cols
[2022-05-16 11:53:23] [INFO ] Computed 11 place invariants in 15 ms
[2022-05-16 11:53:23] [INFO ] Implicit Places using invariants in 230 ms returned [36, 37]
Discarding 2 places :
Implicit Place search using SMT only with invariants took 279 ms to find 2 implicit places.
Starting structural reductions in LIVENESS mode, iteration 1 : 52/110 places, 48/98 transitions.
Drop transitions removed 1 transitions
Trivial Post-agglo rules discarded 1 transitions
Performed 1 trivial Post agglomeration. Transition count delta: 1
Iterating post reduction 0 with 1 rules applied. Total rules applied 1 place count 52 transition count 47
Reduce places removed 1 places and 0 transitions.
Iterating post reduction 1 with 1 rules applied. Total rules applied 2 place count 51 transition count 47
Performed 3 Post agglomeration using F-continuation condition.Transition count delta: 3
Deduced a syphon composed of 3 places in 0 ms
Reduce places removed 3 places and 0 transitions.
Iterating global reduction 2 with 6 rules applied. Total rules applied 8 place count 48 transition count 44
Applied a total of 8 rules in 5 ms. Remains 48 /52 variables (removed 4) and now considering 44/48 (removed 4) transitions.
// Phase 1: matrix 44 rows 48 cols
[2022-05-16 11:53:23] [INFO ] Computed 9 place invariants in 8 ms
[2022-05-16 11:53:23] [INFO ] Implicit Places using invariants in 72 ms returned []
// Phase 1: matrix 44 rows 48 cols
[2022-05-16 11:53:23] [INFO ] Computed 9 place invariants in 4 ms
[2022-05-16 11:53:23] [INFO ] State equation strengthened by 4 read => feed constraints.
[2022-05-16 11:53:23] [INFO ] Implicit Places using invariants and state equation in 91 ms returned []
Implicit Place search using SMT with State Equation took 166 ms to find 0 implicit places.
Starting structural reductions in LIVENESS mode, iteration 2 : 48/110 places, 44/98 transitions.
Finished structural reductions, in 2 iterations. Remains : 48/110 places, 44/98 transitions.
Discarding 10 transitions out of 44. Remains 34
Initial state reduction rules removed 1 formulas.
Incomplete random walk after 10000 steps, including 1221 resets, run finished after 503 ms. (steps per millisecond=19 ) properties (out of 33) seen :20
Incomplete Best-First random walk after 10000 steps, including 732 resets, run finished after 145 ms. (steps per millisecond=68 ) properties (out of 13) seen :0
Incomplete Best-First random walk after 10000 steps, including 759 resets, run finished after 202 ms. (steps per millisecond=49 ) properties (out of 13) seen :0
Incomplete Best-First random walk after 10000 steps, including 754 resets, run finished after 119 ms. (steps per millisecond=84 ) properties (out of 13) seen :0
Incomplete Best-First random walk after 10000 steps, including 756 resets, run finished after 214 ms. (steps per millisecond=46 ) properties (out of 13) seen :0
Incomplete Best-First random walk after 10001 steps, including 762 resets, run finished after 294 ms. (steps per millisecond=34 ) properties (out of 13) seen :0
Incomplete Best-First random walk after 10000 steps, including 767 resets, run finished after 229 ms. (steps per millisecond=43 ) properties (out of 13) seen :0
Incomplete Best-First random walk after 10001 steps, including 744 resets, run finished after 188 ms. (steps per millisecond=53 ) properties (out of 13) seen :4
Incomplete Best-First random walk after 10001 steps, including 767 resets, run finished after 119 ms. (steps per millisecond=84 ) properties (out of 9) seen :0
Incomplete Best-First random walk after 10000 steps, including 769 resets, run finished after 120 ms. (steps per millisecond=83 ) properties (out of 9) seen :0
Incomplete Best-First random walk after 10000 steps, including 730 resets, run finished after 166 ms. (steps per millisecond=60 ) properties (out of 9) seen :1
Incomplete Best-First random walk after 10000 steps, including 774 resets, run finished after 137 ms. (steps per millisecond=72 ) properties (out of 8) seen :0
Incomplete Best-First random walk after 10001 steps, including 767 resets, run finished after 159 ms. (steps per millisecond=62 ) properties (out of 8) seen :0
Running SMT prover for 8 properties.
// Phase 1: matrix 44 rows 48 cols
[2022-05-16 11:53:26] [INFO ] Computed 9 place invariants in 3 ms
[2022-05-16 11:53:26] [INFO ] [Real]Absence check using 2 positive place invariants in 1 ms returned sat
[2022-05-16 11:53:26] [INFO ] [Real]Absence check using 2 positive and 7 generalized place invariants in 2 ms returned sat
[2022-05-16 11:53:26] [INFO ] After 43ms SMT Verify possible using state equation in real domain returned unsat :0 sat :1 real:7
[2022-05-16 11:53:26] [INFO ] State equation strengthened by 4 read => feed constraints.
[2022-05-16 11:53:26] [INFO ] After 4ms SMT Verify possible using 4 Read/Feed constraints in real domain returned unsat :0 sat :0 real:8
[2022-05-16 11:53:26] [INFO ] After 118ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0 real:8
[2022-05-16 11:53:26] [INFO ] [Nat]Absence check using 2 positive place invariants in 1 ms returned sat
[2022-05-16 11:53:26] [INFO ] [Nat]Absence check using 2 positive and 7 generalized place invariants in 2 ms returned sat
[2022-05-16 11:53:26] [INFO ] After 41ms SMT Verify possible using state equation in natural domain returned unsat :0 sat :8
[2022-05-16 11:53:26] [INFO ] After 25ms SMT Verify possible using 4 Read/Feed constraints in natural domain returned unsat :0 sat :8
[2022-05-16 11:53:26] [INFO ] Deduced a trap composed of 6 places in 41 ms of which 8 ms to minimize.
[2022-05-16 11:53:26] [INFO ] Trap strengthening (SAT) tested/added 2/1 trap constraints in 48 ms
[2022-05-16 11:53:26] [INFO ] Deduced a trap composed of 11 places in 23 ms of which 1 ms to minimize.
[2022-05-16 11:53:26] [INFO ] Trap strengthening (SAT) tested/added 2/1 trap constraints in 30 ms
[2022-05-16 11:53:26] [INFO ] After 152ms SMT Verify possible using trap constraints in natural domain returned unsat :0 sat :8
Attempting to minimize the solution found.
Minimization took 34 ms.
[2022-05-16 11:53:26] [INFO ] After 279ms SMT Verify possible using all constraints in natural domain returned unsat :0 sat :8
Parikh walk visited 0 properties in 1803 ms.
Support contains 14 out of 48 places. Attempting structural reductions.
Starting structural reductions in SAFETY mode, iteration 0 : 48/48 places, 44/44 transitions.
Partial Free-agglomeration rule applied 2 times.
Drop transitions removed 2 transitions
Iterating global reduction 0 with 2 rules applied. Total rules applied 2 place count 48 transition count 44
Reduce places removed 1 places and 1 transitions.
Iterating global reduction 0 with 1 rules applied. Total rules applied 3 place count 47 transition count 43
Applied a total of 3 rules in 11 ms. Remains 47 /48 variables (removed 1) and now considering 43/44 (removed 1) transitions.
Finished structural reductions, in 1 iterations. Remains : 47/48 places, 43/44 transitions.
Incomplete random walk after 1000000 steps, including 125118 resets, run finished after 16268 ms. (steps per millisecond=61 ) properties (out of 8) seen :2
Interrupted Best-First random walk after 438921 steps, including 36648 resets, run timeout after 5001 ms. (steps per millisecond=87 ) properties seen 0
Interrupted Best-First random walk after 428431 steps, including 35764 resets, run timeout after 5001 ms. (steps per millisecond=85 ) properties seen 0
Interrupted Best-First random walk after 447417 steps, including 37257 resets, run timeout after 5001 ms. (steps per millisecond=89 ) properties seen 0
Interrupted Best-First random walk after 472590 steps, including 39393 resets, run timeout after 5001 ms. (steps per millisecond=94 ) properties seen 0
Interrupted Best-First random walk after 476832 steps, including 39738 resets, run timeout after 5001 ms. (steps per millisecond=95 ) properties seen 0
Interrupted Best-First random walk after 456567 steps, including 38131 resets, run timeout after 5001 ms. (steps per millisecond=91 ) properties seen 0
Running SMT prover for 6 properties.
// Phase 1: matrix 43 rows 47 cols
[2022-05-16 11:54:14] [INFO ] Computed 9 place invariants in 4 ms
[2022-05-16 11:54:14] [INFO ] [Real]Absence check using 2 positive place invariants in 1 ms returned sat
[2022-05-16 11:54:14] [INFO ] [Real]Absence check using 2 positive and 7 generalized place invariants in 2 ms returned sat
[2022-05-16 11:54:14] [INFO ] After 31ms SMT Verify possible using state equation in real domain returned unsat :0 sat :1 real:5
[2022-05-16 11:54:14] [INFO ] State equation strengthened by 4 read => feed constraints.
[2022-05-16 11:54:14] [INFO ] After 3ms SMT Verify possible using 4 Read/Feed constraints in real domain returned unsat :0 sat :0 real:6
[2022-05-16 11:54:14] [INFO ] After 76ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0 real:6
[2022-05-16 11:54:14] [INFO ] [Nat]Absence check using 2 positive place invariants in 1 ms returned sat
[2022-05-16 11:54:14] [INFO ] [Nat]Absence check using 2 positive and 7 generalized place invariants in 1 ms returned sat
[2022-05-16 11:54:14] [INFO ] After 29ms SMT Verify possible using state equation in natural domain returned unsat :0 sat :6
[2022-05-16 11:54:14] [INFO ] After 17ms SMT Verify possible using 4 Read/Feed constraints in natural domain returned unsat :0 sat :6
[2022-05-16 11:54:14] [INFO ] Deduced a trap composed of 10 places in 26 ms of which 1 ms to minimize.
[2022-05-16 11:54:14] [INFO ] Trap strengthening (SAT) tested/added 2/1 trap constraints in 31 ms
[2022-05-16 11:54:14] [INFO ] Deduced a trap composed of 9 places in 21 ms of which 1 ms to minimize.
[2022-05-16 11:54:14] [INFO ] Deduced a trap composed of 8 places in 19 ms of which 0 ms to minimize.
[2022-05-16 11:54:14] [INFO ] Trap strengthening (SAT) tested/added 3/2 trap constraints in 50 ms
[2022-05-16 11:54:14] [INFO ] After 122ms SMT Verify possible using trap constraints in natural domain returned unsat :0 sat :6
Attempting to minimize the solution found.
Minimization took 19 ms.
[2022-05-16 11:54:14] [INFO ] After 207ms SMT Verify possible using all constraints in natural domain returned unsat :0 sat :6
Parikh walk visited 0 properties in 1360 ms.
Support contains 11 out of 47 places. Attempting structural reductions.
Starting structural reductions in SAFETY mode, iteration 0 : 47/47 places, 43/43 transitions.
Free-agglomeration rule (complex) applied 1 times.
Iterating global reduction 0 with 1 rules applied. Total rules applied 1 place count 47 transition count 42
Reduce places removed 1 places and 0 transitions.
Iterating post reduction 0 with 1 rules applied. Total rules applied 2 place count 46 transition count 42
Partial Free-agglomeration rule applied 1 times.
Drop transitions removed 1 transitions
Iterating global reduction 1 with 1 rules applied. Total rules applied 3 place count 46 transition count 42
Applied a total of 3 rules in 6 ms. Remains 46 /47 variables (removed 1) and now considering 42/43 (removed 1) transitions.
Finished structural reductions, in 1 iterations. Remains : 46/47 places, 42/43 transitions.
Incomplete random walk after 1000000 steps, including 127230 resets, run finished after 15342 ms. (steps per millisecond=65 ) properties (out of 6) seen :0
Interrupted Best-First random walk after 447133 steps, including 37533 resets, run timeout after 5001 ms. (steps per millisecond=89 ) properties seen 0
Interrupted Best-First random walk after 460880 steps, including 38851 resets, run timeout after 5001 ms. (steps per millisecond=92 ) properties seen 0
Interrupted Best-First random walk after 477330 steps, including 40311 resets, run timeout after 5001 ms. (steps per millisecond=95 ) properties seen 0
Interrupted Best-First random walk after 459657 steps, including 38877 resets, run timeout after 5001 ms. (steps per millisecond=91 ) properties seen 0
Interrupted Best-First random walk after 462030 steps, including 38931 resets, run timeout after 5001 ms. (steps per millisecond=92 ) properties seen 0
Interrupted Best-First random walk after 396615 steps, including 33323 resets, run timeout after 5001 ms. (steps per millisecond=79 ) properties seen 0
Finished probabilistic random walk after 2560 steps, run visited all 6 properties in 28 ms. (steps per millisecond=91 )
Probabilistic random walk after 2560 steps, saw 1306 distinct states, run finished after 28 ms. (steps per millisecond=91 ) properties seen :6
Able to resolve query QuasiLiveness after proving 34 properties.
FORMULA QuasiLiveness TRUE TECHNIQUES RANDOM_WALK PROBABILISTIC_WALK BESTFIRST_WALK TOPOLOGICAL INITIAL_STATE
Total runtime 98835 ms.

BK_STOP 1652702101749

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

+ export LANG=C
+ LANG=C
+ export BINDIR=/home/mcc/BenchKit/bin//../
+ BINDIR=/home/mcc/BenchKit/bin//../
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ QuasiLiveness = StateSpace ]]
+ /home/mcc/BenchKit/bin//..//runeclipse.sh /home/mcc/execution QuasiLiveness -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//..//greatspn/ -order META -manyOrder -smt -timeout 1800
+ ulimit -s 65536
+ export PYTHONPATH=/usr/lib/python3.9/site-packages/
+ PYTHONPATH=/usr/lib/python3.9/site-packages/
+ export LD_LIBRARY_PATH=/usr/local/lib:
+ LD_LIBRARY_PATH=/usr/local/lib:
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
++ sed s/.jar//
++ perl -pe 's/.*\.//g'
++ ls /home/mcc/BenchKit/bin//..//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202205111006.jar
+ VERSION=202205111006
+ echo 'Running Version 202205111006'
+ /home/mcc/BenchKit/bin//..//itstools/its-tools -data @none -pnfolder /home/mcc/execution -examination QuasiLiveness -spotpath /home/mcc/BenchKit/bin//..//ltlfilt -z3path /home/mcc/BenchKit/bin//..//z3/bin/z3 -yices2path /home/mcc/BenchKit/bin//..//yices/bin/yices -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//..//greatspn/ -order META -manyOrder -smt -timeout 1800 -vmargs -Dosgi.locking=none -Declipse.stateSaveDelayInterval=-1 -Dosgi.configuration.area=@none -Xss128m -Xms40m -Xmx8192m

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="DoubleExponent-PT-002"
export BK_EXAMINATION="QuasiLiveness"
export BK_TOOL="itstools"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="1800"
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-4028"
echo " Executing tool itstools"
echo " Input is DoubleExponent-PT-002, examination is QuasiLiveness"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r085-tall-165260115500107"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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