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.r324-tall-165472294000646.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 gold2021
Input is Szymanski-PT-b02, examination is ReachabilityDeadlock
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r324-tall-165472294000646
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 4.1M
-rw-r--r-- 1 mcc users 6.6K Jun 7 17:09 CTLCardinality.txt
-rw-r--r-- 1 mcc users 74K Jun 7 17:09 CTLCardinality.xml
-rw-r--r-- 1 mcc users 5.4K Jun 7 17:08 CTLFireability.txt
-rw-r--r-- 1 mcc users 51K Jun 7 17:08 CTLFireability.xml
-rw-r--r-- 1 mcc users 3.8K Jun 6 15:33 LTLCardinality.txt
-rw-r--r-- 1 mcc users 28K Jun 6 15:33 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.1K Jun 6 15:33 LTLFireability.txt
-rw-r--r-- 1 mcc users 16K Jun 6 15:33 LTLFireability.xml
-rw-r--r-- 1 mcc users 1 Jun 6 12:11 NewModel
-rw-r--r-- 1 mcc users 9.9K Jun 7 17:11 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 106K Jun 7 17:11 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 13K Jun 7 17:10 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 116K Jun 7 17:10 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.6K Jun 6 15:33 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.6K Jun 6 15:33 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Jun 6 12:11 equiv_col
-rw-r--r-- 1 mcc users 4 Jun 6 12:11 instance
-rw-r--r-- 1 mcc users 6 Jun 6 12:11 iscolored
-rw-r--r-- 1 mcc users 3.7M 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 1654759686889

Running Version 0
[2022-06-09 07:28:08] [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-09 07:28:08] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2022-06-09 07:28:08] [INFO ] Load time of PNML (sax parser for PT used): 267 ms
[2022-06-09 07:28:08] [INFO ] Transformed 568 places.
[2022-06-09 07:28:08] [INFO ] Transformed 8320 transitions.
[2022-06-09 07:28:08] [INFO ] Parsed PT model containing 568 places and 8320 transitions in 355 ms.
Parsed 1 properties from file /home/mcc/execution/ReachabilityDeadlock.xml in 4 ms.
Working with output stream class java.io.PrintStream
Deduced a syphon composed of 260 places in 18 ms
Reduce places removed 260 places and 256 transitions.
Built sparse matrix representations for Structural reductions in 12 ms.30826KB memory used
Starting structural reductions, iteration 0 : 308/308 places, 8064/8064 transitions.
Graph (complete) has 12652 edges and 308 vertex of which 306 are kept as prefixes of interest. Removing 2 places using SCC suffix rule.28 ms
Discarding 2 places :
Also discarding 0 output transitions
Discarding 1 places :
Symmetric choice reduction at 0 with 1 rule applications. Total rules 2 place count 305 transition count 7936
Iterating global reduction 0 with 1 rules applied. Total rules applied 3 place count 305 transition count 7936
Applied a total of 3 rules in 550 ms. Remains 305 /308 variables (removed 3) and now considering 7936/8064 (removed 128) transitions.
Finished structural reductions, in 1 iterations. Remains : 305/308 places, 7936/8064 transitions.
Random walk for 1250000 steps, including 0 resets, run took 3516 ms (no deadlock found). (steps per millisecond=355 )
Random directed walk for 1250000 steps, including 0 resets, run took 4406 ms (no deadlock found). (steps per millisecond=283 )
[2022-06-09 07:28:17] [INFO ] Flow matrix only has 2732 transitions (discarded 5204 similar events)
// Phase 1: matrix 2732 rows 305 cols
[2022-06-09 07:28:17] [INFO ] Computed 1 place invariants in 47 ms
[2022-06-09 07:28:18] [INFO ] [Real]Absence check using 0 positive and 1 generalized place invariants in 14 ms returned sat
[2022-06-09 07:28:18] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2022-06-09 07:28:18] [INFO ] [Real]Absence check using state equation in 515 ms returned sat
[2022-06-09 07:28:18] [INFO ] Solution in real domain found non-integer solution.
[2022-06-09 07:28:19] [INFO ] [Nat]Absence check using 0 positive and 1 generalized place invariants in 6 ms returned sat
[2022-06-09 07:28:19] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2022-06-09 07:28:20] [INFO ] [Nat]Absence check using state equation in 678 ms returned sat
[2022-06-09 07:28:20] [INFO ] State equation strengthened by 188 read => feed constraints.
[2022-06-09 07:28:23] [INFO ] SMT solver returned unknown. Retrying;
[2022-06-09 07:28:26] [INFO ] [Nat]Added 188 Read/Feed constraints in 6306 ms returned unknown
Random directed walk for 500000 steps, including 0 resets, run took 1799 ms (no deadlock found). (steps per millisecond=277 )
Random walk for 500000 steps, including 0 resets, run took 1247 ms (no deadlock found). (steps per millisecond=400 )
Random directed walk for 500000 steps, including 0 resets, run took 1762 ms (no deadlock found). (steps per millisecond=283 )
Random walk for 500000 steps, including 0 resets, run took 1657 ms (no deadlock found). (steps per millisecond=301 )
Starting structural reductions, iteration 0 : 305/305 places, 7936/7936 transitions.
Applied a total of 0 rules in 370 ms. Remains 305 /305 variables (removed 0) and now considering 7936/7936 (removed 0) transitions.
[2022-06-09 07:28:33] [INFO ] Flow matrix only has 2732 transitions (discarded 5204 similar events)
// Phase 1: matrix 2732 rows 305 cols
[2022-06-09 07:28:33] [INFO ] Computed 1 place invariants in 26 ms
[2022-06-09 07:28:33] [INFO ] Implicit Places using invariants in 482 ms returned []
[2022-06-09 07:28:33] [INFO ] Flow matrix only has 2732 transitions (discarded 5204 similar events)
// Phase 1: matrix 2732 rows 305 cols
[2022-06-09 07:28:33] [INFO ] Computed 1 place invariants in 20 ms
[2022-06-09 07:28:34] [INFO ] State equation strengthened by 188 read => feed constraints.
[2022-06-09 07:28:34] [INFO ] Implicit Places using invariants and state equation in 888 ms returned []
Implicit Place search using SMT with State Equation took 1374 ms to find 0 implicit places.
[2022-06-09 07:28:35] [INFO ] Redundant transitions in 942 ms returned []
[2022-06-09 07:28:35] [INFO ] Flow matrix only has 2732 transitions (discarded 5204 similar events)
// Phase 1: matrix 2732 rows 305 cols
[2022-06-09 07:28:35] [INFO ] Computed 1 place invariants in 21 ms
[2022-06-09 07:28:37] [INFO ] Dead Transitions using invariants and state equation in 2010 ms returned []
Finished structural reductions, in 1 iterations. Remains : 305/305 places, 7936/7936 transitions.
Random walk for 1250000 steps, including 0 resets, run took 3152 ms (no deadlock found). (steps per millisecond=396 )
Random directed walk for 1250001 steps, including 0 resets, run took 4410 ms (no deadlock found). (steps per millisecond=283 )
[2022-06-09 07:28:45] [INFO ] Flow matrix only has 2732 transitions (discarded 5204 similar events)
// Phase 1: matrix 2732 rows 305 cols
[2022-06-09 07:28:45] [INFO ] Computed 1 place invariants in 21 ms
[2022-06-09 07:28:45] [INFO ] [Real]Absence check using 0 positive and 1 generalized place invariants in 5 ms returned sat
[2022-06-09 07:28:45] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2022-06-09 07:28:46] [INFO ] [Real]Absence check using state equation in 493 ms returned sat
[2022-06-09 07:28:46] [INFO ] Solution in real domain found non-integer solution.
[2022-06-09 07:28:47] [INFO ] [Nat]Absence check using 0 positive and 1 generalized place invariants in 4 ms returned sat
[2022-06-09 07:28:47] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2022-06-09 07:28:47] [INFO ] [Nat]Absence check using state equation in 644 ms returned sat
[2022-06-09 07:28:47] [INFO ] State equation strengthened by 188 read => feed constraints.
[2022-06-09 07:28:50] [INFO ] SMT solver returned unknown. Retrying;
[2022-06-09 07:28:53] [INFO ] [Nat]Added 188 Read/Feed constraints in 6254 ms returned unknown
Random directed walk for 500000 steps, including 0 resets, run took 2795 ms (no deadlock found). (steps per millisecond=178 )
Random walk for 500000 steps, including 0 resets, run took 1225 ms (no deadlock found). (steps per millisecond=408 )
Random directed walk for 500001 steps, including 0 resets, run took 1730 ms (no deadlock found). (steps per millisecond=289 )
Random walk for 500000 steps, including 0 resets, run took 1226 ms (no deadlock found). (steps per millisecond=407 )
[2022-06-09 07:29:01] [INFO ] Flatten gal took : 500 ms
Using solver Z3 to compute partial order matrices.
Built C files in :
/tmp/ltsmin5615376565263170783
[2022-06-09 07:29:01] [INFO ] Too many transitions (8064) to apply POR reductions. Disabling POR matrices.
[2022-06-09 07:29:02] [INFO ] Built C files in 121ms conformant to PINS (ltsmin variant)in folder :/tmp/ltsmin5615376565263170783
Running compilation step : CommandLine [args=[gcc, -c, -I/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.ltsmin.binaries_1.0.0.202104292328/bin/include/, -I., -std=c99, -fPIC, -O2, model.c], workingDir=/tmp/ltsmin5615376565263170783]
[2022-06-09 07:29:02] [INFO ] Applying decomposition
[2022-06-09 07:29:02] [INFO ] Flatten gal took : 304 ms
Converted graph to binary with : CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202104292328/bin/convert-linux64, -i, /tmp/graph15042705148899315125.txt, -o, /tmp/graph15042705148899315125.bin, -w, /tmp/graph15042705148899315125.weights], workingDir=null]
Built communities with : CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202104292328/bin/louvain-linux64, /tmp/graph15042705148899315125.bin, -l, -1, -v, -w, /tmp/graph15042705148899315125.weights, -q, 0, -e, 0.001], workingDir=null]
[2022-06-09 07:29:03] [INFO ] Decomposing Gal with order
[2022-06-09 07:29:03] [INFO ] Rewriting arrays to variables to allow decomposition.
[2022-06-09 07:29:03] [INFO ] Removed a total of 18440 redundant transitions.
[2022-06-09 07:29:04] [INFO ] Flatten gal took : 782 ms
[2022-06-09 07:29:04] [INFO ] Fuse similar labels procedure discarded/fused a total of 966 labels/synchronizations in 358 ms.
[2022-06-09 07:29:04] [INFO ] Time to serialize gal into /tmp/ReachabilityDeadlock8368529156039229492.gal : 40 ms
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-ctl-linux64, --gc-threshold, 2000000, --quiet, -i, /tmp/ReachabilityDeadlock8368529156039229492.gal, -t, CGAL, -ctl, DEADLOCK], workingDir=/home/mcc/execution]

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/ReachabilityDeadlock8368529156039229492.gal -t CGAL -ctl DEADLOCK
No direction supplied, using forward translation only.
Model ,|S| ,Time ,Mem(kb) ,fin. SDD ,fin. DDD ,peak SDD ,peak DDD ,SDD Hom ,SDD cache peak ,DDD Hom ,DDD cachepeak ,SHom cache
reachable,277943,65.654,1235464,3379,1958,1.32405e+06,605705,8070,4.22983e+06,441,4.71688e+06,0


Model ,|S| ,Time ,Mem(kb) ,fin. SDD ,fin. DDD ,peak SDD ,peak DDD ,SDD Hom ,SDD cache peak ,DDD Hom ,DDD cachepeak ,SHom cache
dead,7125,67.9989,1235464,351,195,1.32405e+06,605705,29612,4.22983e+06,1900,4.71688e+06,521877

System contains 7125 deadlocks (shown below if less than --print-limit option) !
FORMULA ReachabilityDeadlock TRUE TECHNIQUES DECISION_DIAGRAMS TOPOLOGICAL USE_NUPN
[ 7125 states ] showing 10 first states
[ u48={[ ]
} i7={[ u41={[ ]
} u39={[ ]
} u60={[ ]
} u59={[ ]
} u58={[ ]
} u30={[ ]
} u28={[ ]
} u18={[ ]
} u17={[ ]
} u8={[ ]
} u6={[ ]
} ]
} i8={[ u27={[ ]
} u61={[ l54=1 l46=1 ]
} u53={[ p512=1 ]
} u52={[ ]
} u25={[ ]
} u16={[ ]
} u14={[ ]
} u5={[ ]
} u3={[ ]
} ]
} i6={[ u55={[ ]
} u47={[ ]
} u40={[ ]
} u29={[ ]
} u19={[ ]
} u7={[ ]
} ]
} i5={[ u51={[ ]
} u50={[ ]
} u49={[ ]
} ]
} i3={[ u46={[ ]
} u34={[ ]
} u33={[ ]
} u45={[ ]
} u23={[ ]
} u44={[ ]
} u22={[ ]
} u13={[ ]
} u11={[ ]
} u1={[ ]
} u0={[ ]
} ]
} i2={[ i2={[ u57={[ ]
} u56={[ l32=1 ]
[ l33=1 ]
[ l34=1 ]
[ l51=1 ]
} u38={[ ]
} u36={[ ]
} ]
[ u57={[ l35=1 ]
[ l36=1 ]
} u56={[ ]
} u38={[ ]
} u36={[ ]
} ]
} i1={[ u43={[ ]
} u32={[ ]
} u20={[ ]
} u9={[ ]
} ]
} i0={[ u42={[ ]
} u31={[ ]
} u21={[ ]
} u10={[ ]
} ]
} ]
} i1={[ u62={[ ]
} u37={[ ]
} u26={[ ]
} u15={[ ]
} u4={[ ]
} ]
} i0={[ u54={[ l25=1 ]
[ l27=1 ]
[ l26=1 ]
[ l28=1 ]
} u35={[ ]
} u24={[ ]
} u12={[ ]
} u2={[ ]
} ]
} ]
WARNING : LTS min runner thread was asked to interrupt. Dying gracefully.

BK_STOP 1654759815557

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

+ 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
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
++ cut -d . -f 9
++ ls /home/mcc/BenchKit/bin//..//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202104292328.jar
+ VERSION=0
+ echo 'Running Version 0'
+ /home/mcc/BenchKit/bin//..//itstools/its-tools -data /home/mcc/execution/workspace -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=/tmp/.eclipse -Xss128m -Xms40m -Xmx16000m

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="Szymanski-PT-b02"
export BK_EXAMINATION="ReachabilityDeadlock"
export BK_TOOL="gold2021"
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 gold2021"
echo " Input is Szymanski-PT-b02, 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 r324-tall-165472294000646"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/Szymanski-PT-b02.tgz
mv Szymanski-PT-b02 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 ;