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.r319-tall-165472290200431.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 RERS2020-PT-pb109, examination is ReachabilityDeadlock
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r319-tall-165472290200431
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 85M
-rw-r--r-- 1 mcc users 8.5K Jun 7 17:43 CTLCardinality.txt
-rw-r--r-- 1 mcc users 92K Jun 7 17:43 CTLCardinality.xml
-rw-r--r-- 1 mcc users 6.0K Jun 7 17:41 CTLFireability.txt
-rw-r--r-- 1 mcc users 48K Jun 7 17:41 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Jun 6 15:29 LTLCardinality.txt
-rw-r--r-- 1 mcc users 28K Jun 6 15:29 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.6K Jun 6 15:29 LTLFireability.txt
-rw-r--r-- 1 mcc users 16K Jun 6 15:29 LTLFireability.xml
-rw-r--r-- 1 mcc users 1 Jun 6 12:11 NewModel
-rw-r--r-- 1 mcc users 6.5K Jun 7 17:47 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 55K Jun 7 17:47 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 7.9K Jun 7 17:45 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 56K Jun 7 17:45 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.6K Jun 6 15:29 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.7K Jun 6 15:29 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Jun 6 12:11 equiv_col
-rw-r--r-- 1 mcc users 6 Jun 6 12:11 instance
-rw-r--r-- 1 mcc users 6 Jun 6 12:11 iscolored
-rw-r--r-- 1 mcc users 84M Jun 6 12:11 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 ReachabilityDeadlock

=== Now, execution of the tool begins

BK_START 1654728216134

Running Version 202205111006
[2022-06-08 22:43:37] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, ReachabilityDeadlock, -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-06-08 22:43:37] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2022-06-08 22:43:40] [INFO ] Load time of PNML (sax parser for PT used): 2750 ms
[2022-06-08 22:43:40] [INFO ] Transformed 1907 places.
[2022-06-08 22:43:40] [INFO ] Transformed 151872 transitions.
[2022-06-08 22:43:40] [INFO ] Found NUPN structural information;
[2022-06-08 22:43:40] [INFO ] Parsed PT model containing 1907 places and 151872 transitions in 3441 ms.
Parsed 1 properties from file /home/mcc/execution/ReachabilityDeadlock.xml in 7 ms.
Working with output stream class java.io.PrintStream
Ensure Unique test removed 6571 transitions
Reduce redundant transitions removed 6571 transitions.
Built sparse matrix representations for Structural reductions in 48 ms.260703KB memory used
Starting structural reductions in DEADLOCKS mode, iteration 0 : 1907/1907 places, 145301/145301 transitions.
Computed a total of 485 stabilizing places and 59095 stable transitions
Graph (complete) has 140297 edges and 1907 vertex of which 1906 are kept as prefixes of interest. Removing 1 places using SCC suffix rule.783 ms
Discarding 1 places :
Also discarding 0 output transitions
Ensure Unique test removed 15 places
Computed a total of 470 stabilizing places and 59095 stable transitions
Drop transitions removed 2 transitions
Trivial Post-agglo rules discarded 2 transitions
Performed 2 trivial Post agglomeration. Transition count delta: 2
Iterating post reduction 0 with 17 rules applied. Total rules applied 18 place count 1891 transition count 145299
Reduce places removed 2 places and 0 transitions.
Ensure Unique test removed 2 transitions
Reduce isomorphic transitions removed 2 transitions.
Computed a total of 469 stabilizing places and 59093 stable transitions
Iterating post reduction 1 with 4 rules applied. Total rules applied 22 place count 1889 transition count 145297
Computed a total of 469 stabilizing places and 59093 stable transitions
Performed 1 Pre agglomeration using Quasi-Persistent + Divergent Free condition..
Pre-agglomeration after 2 with 1 Pre rules applied. Total rules applied 22 place count 1889 transition count 145296
Deduced a syphon composed of 1 places in 113 ms
Reduce places removed 1 places and 0 transitions.
Iterating global reduction 2 with 2 rules applied. Total rules applied 24 place count 1888 transition count 145296
Computed a total of 468 stabilizing places and 59092 stable transitions
Performed 23 Post agglomeration using F-continuation condition.Transition count delta: 23
Deduced a syphon composed of 23 places in 127 ms
Reduce places removed 23 places and 0 transitions.
Iterating global reduction 2 with 46 rules applied. Total rules applied 70 place count 1865 transition count 145273
Ensure Unique test removed 95 transitions
Reduce isomorphic transitions removed 95 transitions.
Computed a total of 461 stabilizing places and 59036 stable transitions
Iterating post reduction 2 with 95 rules applied. Total rules applied 165 place count 1865 transition count 145178
Computed a total of 461 stabilizing places and 59036 stable transitions
Performed 3 Post agglomeration using F-continuation condition.Transition count delta: 0
Deduced a syphon composed of 3 places in 107 ms
Reduce places removed 3 places and 0 transitions.
Iterating global reduction 3 with 6 rules applied. Total rules applied 171 place count 1862 transition count 145178
Ensure Unique test removed 3 transitions
Reduce isomorphic transitions removed 3 transitions.
Computed a total of 459 stabilizing places and 59034 stable transitions
Iterating post reduction 3 with 3 rules applied. Total rules applied 174 place count 1862 transition count 145175
Computed a total of 459 stabilizing places and 59034 stable transitions
Computed a total of 459 stabilizing places and 59034 stable transitions
Reduce places removed 1 places and 1 transitions.
Iterating global reduction 4 with 1 rules applied. Total rules applied 175 place count 1861 transition count 145174
Computed a total of 458 stabilizing places and 59033 stable transitions
Computed a total of 458 stabilizing places and 59033 stable transitions
Applied a total of 175 rules in 10961 ms. Remains 1861 /1907 variables (removed 46) and now considering 145174/145301 (removed 127) transitions.
Finished structural reductions, in 1 iterations. Remains : 1861/1907 places, 145174/145301 transitions.
Interrupted Random walk after 284262 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=9 )
[2022-06-08 22:44:23] [INFO ] Flow matrix only has 100545 transitions (discarded 44629 similar events)
// Phase 1: matrix 100545 rows 1861 cols
[2022-06-08 22:44:23] [INFO ] Computed 15 place invariants in 601 ms
[2022-06-08 22:44:42] [INFO ] [Real]Absence check using 15 positive place invariants in 132 ms returned sat
[2022-06-08 22:44:42] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2022-06-08 22:45:09] [INFO ] [Real]Absence check using state equation in 26862 ms returned sat
[2022-06-08 22:45:10] [INFO ] Solution in real domain found non-integer solution.
[2022-06-08 22:45:28] [INFO ] [Nat]Absence check using 15 positive place invariants in 258 ms returned sat
[2022-06-08 22:45:28] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2022-06-08 22:45:55] [INFO ] [Nat]Absence check using state equation in 26420 ms returned sat
[2022-06-08 22:45:57] [INFO ] State equation strengthened by 5223 read => feed constraints.
[2022-06-08 22:46:12] [INFO ] [Nat]Added 5223 Read/Feed constraints in 15077 ms returned sat
[2022-06-08 22:46:23] [INFO ] Deduced a trap composed of 85 places in 9925 ms of which 12 ms to minimize.
[2022-06-08 22:46:34] [INFO ] Deduced a trap composed of 107 places in 8733 ms of which 3 ms to minimize.
[2022-06-08 22:46:43] [INFO ] Deduced a trap composed of 114 places in 8264 ms of which 1 ms to minimize.
[2022-06-08 22:46:52] [INFO ] Deduced a trap composed of 23 places in 7496 ms of which 1 ms to minimize.
[2022-06-08 22:47:02] [INFO ] Deduced a trap composed of 51 places in 8588 ms of which 1 ms to minimize.
[2022-06-08 22:47:14] [INFO ] Deduced a trap composed of 214 places in 10488 ms of which 2 ms to minimize.
[2022-06-08 22:47:25] [INFO ] Deduced a trap composed of 58 places in 10107 ms of which 2 ms to minimize.
[2022-06-08 22:47:37] [INFO ] Deduced a trap composed of 19 places in 10456 ms of which 3 ms to minimize.
[2022-06-08 22:47:49] [INFO ] Deduced a trap composed of 141 places in 10153 ms of which 1 ms to minimize.
[2022-06-08 22:48:00] [INFO ] Deduced a trap composed of 147 places in 9648 ms of which 2 ms to minimize.
[2022-06-08 22:48:11] [INFO ] Deduced a trap composed of 208 places in 10344 ms of which 2 ms to minimize.
[2022-06-08 22:48:23] [INFO ] Deduced a trap composed of 112 places in 9793 ms of which 2 ms to minimize.
[2022-06-08 22:48:34] [INFO ] Deduced a trap composed of 53 places in 10108 ms of which 2 ms to minimize.
[2022-06-08 22:48:47] [INFO ] Deduced a trap composed of 20 places in 11622 ms of which 2 ms to minimize.
[2022-06-08 22:49:00] [INFO ] Deduced a trap composed of 33 places in 11114 ms of which 2 ms to minimize.
[2022-06-08 22:49:13] [INFO ] Deduced a trap composed of 170 places in 11858 ms of which 2 ms to minimize.
[2022-06-08 22:49:27] [INFO ] Deduced a trap composed of 151 places in 11580 ms of which 1 ms to minimize.
[2022-06-08 22:49:41] [INFO ] Deduced a trap composed of 111 places in 12481 ms of which 1 ms to minimize.
[2022-06-08 22:49:52] [INFO ] Deduced a trap composed of 48 places in 10017 ms of which 2 ms to minimize.
[2022-06-08 22:50:05] [INFO ] Deduced a trap composed of 151 places in 11112 ms of which 2 ms to minimize.
[2022-06-08 22:50:17] [INFO ] Deduced a trap composed of 128 places in 10944 ms of which 2 ms to minimize.
[2022-06-08 22:50:17] [WARNING] SMT solver failed with error :java.lang.RuntimeException: SMT solver raised an error when submitting script. Raised (error "Failed to assert expression: java.io.IOException: Stream close... while checking Deadlocks.
Interrupted Random directed walk after 243492 steps, including 0 resets, run timeout after 30002 ms. (steps per millisecond=8 )
Finished random walk after 301 steps, including 0 resets, run found a deadlock after 33 ms. (steps per millisecond=9 )
FORMULA ReachabilityDeadlock TRUE TECHNIQUES TOPOLOGICAL STRUCTURAL_REDUCTION RANDOM_WALK
Total runtime 430537 ms.

BK_STOP 1654728647888

--------------------
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
+ [[ ReachabilityDeadlock = StateSpace ]]
+ /home/mcc/BenchKit/bin//..//runeclipse.sh /home/mcc/execution ReachabilityDeadlock -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
++ perl -pe 's/.*\.//g'
++ sed s/.jar//
++ 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 ReachabilityDeadlock -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="RERS2020-PT-pb109"
export BK_EXAMINATION="ReachabilityDeadlock"
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 RERS2020-PT-pb109, examination is ReachabilityDeadlock"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r319-tall-165472290200431"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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