Execution Chart

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

Trace from the execution

Formatting '/mnt/tpsp/fkordon/mcc2019-input.r148-ebro-155286404100354.qcow2', fmt=qcow2 size=4294967296 backing_file='/mnt/tpsp/fkordon/mcc2019-input.qcow2' encryption=off cluster_size=65536 lazy_refcounts=off
Waiting for the VM to be ready (probing ssh)
......................
=====================================================================
Generated by BenchKit 2-3954
Executing tool lola
Input is RwMutex-PT-r1000w0010, examination is GlobalProperties
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r148-ebro-155286404100354
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 2.1M
-rw-r--r-- 1 mcc users 3.4K Feb 12 15:39 CTLCardinality.txt
-rw-r--r-- 1 mcc users 19K Feb 12 15:39 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.5K Feb 8 17:37 CTLFireability.txt
-rw-r--r-- 1 mcc users 16K Feb 8 17:37 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 10 17:31 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 108 Feb 24 15:05 GlobalProperties.txt
-rw-r--r-- 1 mcc users 346 Feb 24 15:05 GlobalProperties.xml
-rw-r--r-- 1 mcc users 2.4K Feb 5 01:20 LTLCardinality.txt
-rw-r--r-- 1 mcc users 10K Feb 5 01:20 LTLCardinality.xml
-rw-r--r-- 1 mcc users 1.9K Feb 4 22:48 LTLFireability.txt
-rw-r--r-- 1 mcc users 8.9K Feb 4 22:48 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.8K Feb 4 17:30 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 19K Feb 4 17:30 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 3.0K Feb 1 13:52 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 19K Feb 1 13:52 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.7K Feb 4 22:30 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.7K Feb 4 22:30 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Jan 29 09:35 equiv_col
-rw-r--r-- 1 mcc users 11 Jan 29 09:35 instance
-rw-r--r-- 1 mcc users 6 Jan 29 09:35 iscolored
-rw-r--r-- 1 mcc users 2.0M Mar 10 17:31 model.pnml

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

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

The expected result is a vector of booleans
BOOL_VECTOR

here is the order used to build the result vector(from text file)
FORMULA_NAME RwMutex-PT-r1000w0010-GlobalProperties-0

=== Now, execution of the tool begins

BK_START 1553001718357

info: Time: 3600 - MCC
vrfy: Checking GlobalProperties @ RwMutex-PT-r1000w0010 @ 3570 seconds

FORMULA RwMutex-PT-r1000w0010-GlobalProperties-0 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT SAT_SMT STATE_COMPRESSION STUBBORN_SETS SYMMETRIES TOPOLOGICAL USE_NUPN UNFOLDING_TO_PT
vrfy: finished
info: timeLeft: 3345
rslt: Output for GlobalProperties @ RwMutex-PT-r1000w0010

{
"build":
{
"architecture": 64,
"assertions": false,
"build_hostname": "mcc2019",
"build_system": "x86_64-unknown-linux-gnu",
"optimizations": true,
"package_version": "2.0",
"svn_version": "3189M"
},
"call":
{
"exec_host": "mcc2019",
"markinglimit": null,
"parameters":
[
"--pnmlnet",
"model.pnml",
"--xmlformula",
"--formula=GlobalProperties.xml",
"--mcc",
"--donotcomputecapacities",
"--encoder=simplecompressed",
"--check=modelchecking",
"--stubborn=combined",
"--siphontrap=par",
"--siphondepth=10",
"--findpath=par",
"--retrylimit=0",
"--depthlimit=1000000",
"--symmetry",
"--symmtimelimit=300",
"--threads=2",
"--symmetrydepth=1073741824",
"--timelimit=3570",
"--json=GlobalProperties.json",
"--jsoninclude=formula,formulastat,net"
],
"starttime": "Tue Mar 19 13:21:58 2019
",
"timelimit": 3570
},
"child": null,
"exit":
{
"error": null,
"memory": 5420468,
"runtime": 225.000000,
"signal": null,
"timelimitreached": false
},
"files":
{
"formula": "GlobalProperties.xml",
"net": "model.pnml"
},
"formula":
{
"count":
{
"A": 0,
"E": 1,
"F": 1,
"G": 0,
"U": 0,
"X": 0,
"aconj": 0,
"adisj": 0,
"aneg": 0,
"comp": 0,
"cont": 0,
"dl": 1,
"fir": 0,
"nodl": 0,
"place_references": 0,
"taut": 0,
"tconj": 0,
"tdisj": 0,
"tneg": 0,
"transition_references": 0,
"unfir": 0,
"visible_places": 0,
"visible_transitions": 0
},
"processed": "E (F (DEADLOCK))",
"processed_size": 16,
"rewrites": 1,
"skeleton": "E(F(**))"
},
"net":
{
"arcs": 26040,
"conflict_clusters": 1011,
"places": 3020,
"places_significant": 1010,
"singleton_clusters": 0,
"transitions": 2020
},
"result":
{
"edges": 1012,
"markings": 3,
"produced_by": "state space",
"value": false
},
"task":
{
"findpath":
{
"depthlimit": 1000000,
"retrylimit": null,
"stubborn":
{
"type": "reachability preserving/insertion"
},
"threads": 2
},
"search":
{
"store":
{
"encoder": "simple compression",
"type": "prefix"
},
"stubborn":
{
"type": "reachability preserving/insertion"
},
"symmetry":
{
"card": inf,
"composed": 498530,
"dead_branches": 0,
"generators": 499545,
"interrupted": false,
"real_computed": 1015,
"timelimit": 300
},
"threads": 2,
"type": "dfs"
},
"type": "reachability",
"workflow": "findpath||search"
}
}
lola: LoLA will run for 3570 seconds at most (--timelimit)
lola: NET
lola: input: PNML file (--pnml)
lola: reading net from model.pnml
lola: reading pnml
lola: PNML file contains place/transition net
lola: finished parsing
lola: closed net file model.pnml
lola: 5040/268435456 symbol table entries, 0 collisions
lola: preprocessing...
lola: Size of bit vector: 96640
lola: finding significant places
lola: 3020 places, 2020 transitions, 1010 significant places
lola: compute conflict clusters
lola: computed conflict clusters
lola: Computing conflicting sets
lola: Computing back conflicting sets
lola: TASK
lola: Reading formula in XML format (--xmlformula)
lola: reading pnml
lola: reading formula from GlobalProperties.xml
lola: E (F (DEADLOCK))
lola: checking reachability
lola: Planning: workflow for reachability check: findpath||search (--findpath=par)
lola: rewrite Frontend/Parser/formula_rewrite.k:711
lola: processed formula: E (F (DEADLOCK))
lola: processed formula length: 16
lola: 1 rewrites
lola: closed formula file GlobalProperties.xml
lola: STORE
lola: using a simple compression encoder (--encoder=simplecompressed)
lola: using a prefix tree store (--store=prefix)
lola: SEARCH (state space)
lola: state space: using reachability graph (--search=depth)
lola: state space: using reachability preserving stubborn set method with insertion algorithm (--stubborn=tarjan)
lola: rewrite Frontend/Parser/formula_rewrite.k:711
lola: SEARCH (findpath)
lola: findpath: using reachability preserving stubborn set method with insertion algorithm (--stubborn=tarjan)
lola: RUNNING
lola: findpath: starting randomized, memory-less exploration (--findpath)
lola: findpath: searching for paths with maximal depth 1000000 (--depthlimit)
lola: findpath: no retry limit given (--retrylimit)
lola: findpath: transitions are chosen hash-driven
lola: computing symmetries (--symmetry)
lola: time limit for symmetry computation of 300 seconds given (--symmtimelimit)
lola: 1 tries, 50758 fired transitions, 0 secs
lola: 5988 generators; 4531521 candidates remain ( 0%), 0 secs
lola: 1 tries, 99972 fired transitions, 5 secs
lola: 15873 generators; 4501446 candidates remain ( 0%), 5 secs
lola: 1 tries, 148470 fired transitions, 10 secs
lola: 25658 generators; 4471471 candidates remain ( 0%), 10 secs
lola: 1 tries, 196197 fired transitions, 15 secs
lola: 35343 generators; 4441596 candidates remain ( 0%), 15 secs
lola: 1 tries, 243110 fired transitions, 20 secs
lola: 46680 generators; 4410069 candidates remain ( 1%), 20 secs
lola: 1 tries, 289482 fired transitions, 25 secs
lola: 56298 generators; 4376223 candidates remain ( 1%), 25 secs
lola: 1 tries, 335266 fired transitions, 30 secs
lola: 67524 generators; 4340769 candidates remain ( 1%), 30 secs
lola: 1 tries, 380455 fired transitions, 35 secs
lola: 77688 generators; 4308396 candidates remain ( 1%), 35 secs
lola: 1 tries, 425118 fired transitions, 40 secs
lola: 87731 generators; 4276144 candidates remain ( 2%), 40 secs
lola: 1 tries, 469304 fired transitions, 45 secs
lola: 98549 generators; 4241098 candidates remain ( 2%), 45 secs
lola: 1 tries, 512978 fired transitions, 50 secs
lola: 110106 generators; 4205313 candidates remain ( 2%), 50 secs
lola: 1 tries, 556202 fired transitions, 55 secs
lola: 120624 generators; 4168548 candidates remain ( 2%), 55 secs
lola: 1 tries, 599038 fired transitions, 60 secs
lola: 131856 generators; 4131069 candidates remain ( 3%), 60 secs
lola: 1 tries, 641478 fired transitions, 65 secs
lola: 142800 generators; 4095897 candidates remain ( 3%), 65 secs
lola: 1 tries, 683654 fired transitions, 70 secs
lola: 152981 generators; 4059469 candidates remain ( 3%), 70 secs
lola: 1 tries, 725428 fired transitions, 75 secs
lola: 163719 generators; 4022484 candidates remain ( 3%), 75 secs
lola: 1 tries, 766846 fired transitions, 80 secs
lola: 174288 generators; 3985668 candidates remain ( 4%), 80 secs
lola: 1 tries, 808078 fired transitions, 85 secs
lola: 184688 generators; 3949021 candidates remain ( 4%), 85 secs
lola: 1 tries, 849050 fired transitions, 90 secs
lola: 195699 generators; 3907431 candidates remain ( 4%), 90 secs
lola: 1 tries, 889690 fired transitions, 95 secs
lola: 199620 generators; 3870690 candidates remain ( 4%), 95 secs
lola: 1 tries, 930006 fired transitions, 100 secs
lola: 210365 generators; 3831805 candidates remain ( 5%), 100 secs
lola: 1 tries, 970127 fired transitions, 105 secs
lola: 221660 generators; 3790360 candidates remain ( 5%), 105 secs
lola: 2 tries, 1009948 fired transitions, 110 secs
lola: 233460 generators; 3746400 candidates remain ( 5%), 110 secs
lola: 2 tries, 1049548 fired transitions, 115 secs
lola: 245004 generators; 3702696 candidates remain ( 6%), 115 secs
lola: 2 tries, 1089042 fired transitions, 120 secs
lola: 257685 generators; 3653835 candidates remain ( 6%), 120 secs
lola: 2 tries, 1128296 fired transitions, 125 secs
lola: 270042 generators; 3607308 candidates remain ( 6%), 125 secs
lola: 2 tries, 1167336 fired transitions, 130 secs
lola: 282734 generators; 3554416 candidates remain ( 7%), 130 secs
lola: 2 tries, 1206068 fired transitions, 135 secs
lola: 295704 generators; 3503256 candidates remain ( 7%), 135 secs
lola: 2 tries, 1244754 fired transitions, 140 secs
lola: 308274 generators; 3448476 candidates remain ( 8%), 140 secs
lola: 2 tries, 1283211 fired transitions, 145 secs
lola: 321042 generators; 3393498 candidates remain ( 8%), 145 secs
lola: 2 tries, 1321572 fired transitions, 150 secs
lola: 335667 generators; 3328623 candidates remain ( 9%), 150 secs
lola: 2 tries, 1359714 fired transitions, 155 secs
lola: 350760 generators; 3259260 candidates remain ( 9%), 155 secs
lola: 2 tries, 1397748 fired transitions, 160 secs
lola: 365642 generators; 3188098 candidates remain (10%), 160 secs
lola: 2 tries, 1435632 fired transitions, 165 secs
lola: 381690 generators; 3107730 candidates remain (10%), 165 secs
lola: 2 tries, 1473370 fired transitions, 170 secs
lola: 397619 generators; 3023461 candidates remain (11%), 170 secs
lola: 2 tries, 1510990 fired transitions, 175 secs
lola: 414467 generators; 2932243 candidates remain (12%), 175 secs
lola: 2 tries, 1548554 fired transitions, 180 secs
lola: 431831 generators; 2822419 candidates remain (13%), 180 secs
lola: 2 tries, 1585986 fired transitions, 185 secs
lola: 452574 generators; 2677056 candidates remain (14%), 185 secs
lola: 2 tries, 1623206 fired transitions, 190 secs
lola: 475674 generators; 2477076 candidates remain (16%), 190 secs
lola: computed 499545 generators (1015 in search tree, 498530 by composition)
lola: representing 1.46018E+2574 symmetries
lola: 0 dead branches visited in search tree
lola: 2 tries, 1660388 fired transitions, 195 secs || 2 markings, 43 edges, 0 markings/sec, 195 secs
lola: 2 tries, 1697922 fired transitions, 200 secs || 2 markings, 329 edges, 0 markings/sec, 200 secs
lola: 2 tries, 1735424 fired transitions, 205 secs || 2 markings, 616 edges, 0 markings/sec, 205 secs
lola: 2 tries, 1772738 fired transitions, 210 secs || 2 markings, 903 edges, 0 markings/sec, 210 secs
lola: RESULT
lola: result: no
lola: produced by: state space
lola: The predicate is unreachable.
lola: 3 markings, 1012 edges
lola: memory consumption: 5420468 KB
lola: time consumption: 225 seconds
lola: print data as JSON (--json)
lola: writing JSON to GlobalProperties.json
lola: closed JSON file GlobalProperties.json
rslt: finished

BK_STOP 1553001943631

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

grep: GenericPropertiesVerdict.xml: No such file or directory
grep: GenericPropertiesVerdict.xml: No such file or directory

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="RwMutex-PT-r1000w0010"
export BK_EXAMINATION="GlobalProperties"
export BK_TOOL="lola"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"

# this is specific to your benchmark or test

export BIN_DIR="$HOME/BenchKit/bin"

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

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-3954"
echo " Executing tool lola"
echo " Input is RwMutex-PT-r1000w0010, examination is GlobalProperties"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r148-ebro-155286404100354"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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

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