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-155286404000318.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-r0010w2000, examination is GlobalProperties
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r148-ebro-155286404000318
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 3.6M
-rw-r--r-- 1 mcc users 4.0K Feb 12 15:36 CTLCardinality.txt
-rw-r--r-- 1 mcc users 23K Feb 12 15:36 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.6K Feb 8 17:34 CTLFireability.txt
-rw-r--r-- 1 mcc users 17K Feb 8 17:34 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:19 LTLCardinality.txt
-rw-r--r-- 1 mcc users 11K Feb 5 01:19 LTLCardinality.xml
-rw-r--r-- 1 mcc users 1.8K Feb 4 22:48 LTLFireability.txt
-rw-r--r-- 1 mcc users 7.7K Feb 4 22:48 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.7K Feb 4 17:28 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 19K Feb 4 17:28 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 3.0K Feb 1 13:49 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 19K Feb 1 13:49 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 3.4M 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-r0010w2000-GlobalProperties-0

=== Now, execution of the tool begins

BK_START 1552999651707

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

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

{
"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 12:47:31 2019
",
"timelimit": 3570
},
"child": null,
"exit":
{
"error": null,
"memory": 5973740,
"runtime": 441.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": 48060,
"conflict_clusters": 2011,
"places": 4030,
"places_significant": 2010,
"singleton_clusters": 0,
"transitions": 4020
},
"result":
{
"edges": 2012,
"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": 377936,
"dead_branches": 2,
"generators": 378144,
"interrupted": true,
"real_computed": 208,
"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: 8050/268435456 symbol table entries, 0 collisions
lola: preprocessing...
lola: Size of bit vector: 128960
lola: finding significant places
lola: 4030 places, 4020 transitions, 2010 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, 510 fired transitions, 0 secs
lola: 2008 generators; 8098321 candidates remain ( 0%), 0 secs
lola: 1 tries, 1096 fired transitions, 5 secs
lola: 2029 generators; 8086243 candidates remain ( 0%), 5 secs
lola: 1 tries, 1672 fired transitions, 10 secs
lola: 2043 generators; 8076144 candidates remain ( 0%), 10 secs
lola: 1 tries, 2180 fired transitions, 15 secs
lola: 6038 generators; 8068105 candidates remain ( 0%), 15 secs
lola: 1 tries, 2712 fired transitions, 20 secs
lola: 14016 generators; 8052039 candidates remain ( 0%), 20 secs
lola: 1 tries, 3300 fired transitions, 25 secs
lola: 17999 generators; 8044012 candidates remain ( 0%), 25 secs
lola: 1 tries, 3882 fired transitions, 30 secs
lola: 25953 generators; 8032014 candidates remain ( 0%), 30 secs
lola: 1 tries, 4388 fired transitions, 35 secs
lola: 29924 generators; 8019955 candidates remain ( 0%), 35 secs
lola: 1 tries, 4940 fired transitions, 40 secs
lola: 33891 generators; 8011944 candidates remain ( 0%), 40 secs
lola: 1 tries, 5504 fired transitions, 45 secs
lola: 41813 generators; 7995934 candidates remain ( 0%), 45 secs
lola: 45768 generators; 7987935 candidates remain ( 0%), 50 secs
lola: 1 tries, 6054 fired transitions, 50 secs
lola: 53666 generators; 7971949 candidates remain ( 0%), 55 secs
lola: 1 tries, 6568 fired transitions, 55 secs
lola: 59579 generators; 7961992 candidates remain ( 0%), 60 secs
lola: 1 tries, 7094 fired transitions, 60 secs
lola: 65482 generators; 7948001 candidates remain ( 0%), 65 secs
lola: 1 tries, 7406 fired transitions, 65 secs
lola: 69210 generators; 7942251 candidates remain ( 0%), 70 secs
lola: 1 tries, 7884 fired transitions, 70 secs
lola: 75303 generators; 7928070 candidates remain ( 0%), 75 secs
lola: 1 tries, 8472 fired transitions, 75 secs
lola: 83141 generators; 7914166 candidates remain ( 1%), 80 secs
lola: 1 tries, 9062 fired transitions, 80 secs
lola: 89009 generators; 7900210 candidates remain ( 1%), 85 secs
lola: 1 tries, 9646 fired transitions, 85 secs
lola: 94868 generators; 7888285 candidates remain ( 1%), 90 secs
lola: 1 tries, 10218 fired transitions, 90 secs
lola: 102666 generators; 7872399 candidates remain ( 1%), 95 secs
lola: 1 tries, 10802 fired transitions, 95 secs
lola: 108504 generators; 7860495 candidates remain ( 1%), 100 secs
lola: 1 tries, 11378 fired transitions, 100 secs
lola: 116274 generators; 7844637 candidates remain ( 1%), 105 secs
lola: 1 tries, 11952 fired transitions, 105 secs
lola: 124028 generators; 7832839 candidates remain ( 1%), 110 secs
lola: 1 tries, 12512 fired transitions, 110 secs
lola: 127899 generators; 7820880 candidates remain ( 1%), 115 secs
lola: 1 tries, 13072 fired transitions, 115 secs
lola: 131766 generators; 7812969 candidates remain ( 1%), 120 secs
lola: 1 tries, 13650 fired transitions, 120 secs
lola: 139488 generators; 7801203 candidates remain ( 1%), 125 secs
lola: 1 tries, 14226 fired transitions, 125 secs
lola: 143343 generators; 7789260 candidates remain ( 1%), 130 secs
lola: 1 tries, 14812 fired transitions, 130 secs
lola: 151041 generators; 7773474 candidates remain ( 1%), 135 secs
lola: 1 tries, 15370 fired transitions, 135 secs
lola: 158723 generators; 7759726 candidates remain ( 2%), 140 secs
lola: 1 tries, 15958 fired transitions, 140 secs
lola: 162558 generators; 7749825 candidates remain ( 2%), 145 secs
lola: 1 tries, 16544 fired transitions, 145 secs
lola: 170216 generators; 7734079 candidates remain ( 2%), 150 secs
lola: 1 tries, 17122 fired transitions, 150 secs
lola: 177858 generators; 7718349 candidates remain ( 2%), 155 secs
lola: 1 tries, 17700 fired transitions, 155 secs
lola: 181673 generators; 7710490 candidates remain ( 2%), 160 secs
lola: 1 tries, 18282 fired transitions, 160 secs
lola: 189291 generators; 7694784 candidates remain ( 2%), 165 secs
lola: 1 tries, 18866 fired transitions, 165 secs
lola: 196893 generators; 7679094 candidates remain ( 2%), 170 secs
lola: 1 tries, 19450 fired transitions, 170 secs
lola: 200688 generators; 7671255 candidates remain ( 2%), 175 secs
lola: 1 tries, 20034 fired transitions, 175 secs
lola: 208266 generators; 7655589 candidates remain ( 2%), 180 secs
lola: 1 tries, 20600 fired transitions, 180 secs
lola: 215828 generators; 7639939 candidates remain ( 2%), 185 secs
lola: 1 tries, 21188 fired transitions, 185 secs
lola: 223374 generators; 7626327 candidates remain ( 2%), 190 secs
lola: 1 tries, 21776 fired transitions, 190 secs
lola: 227141 generators; 7616494 candidates remain ( 2%), 195 secs
lola: 1 tries, 22364 fired transitions, 195 secs
lola: 234663 generators; 7600884 candidates remain ( 2%), 200 secs
lola: 1 tries, 22950 fired transitions, 200 secs
lola: 238418 generators; 7593085 candidates remain ( 3%), 205 secs
lola: 1 tries, 23538 fired transitions, 205 secs
lola: 245916 generators; 7577499 candidates remain ( 3%), 210 secs
lola: 1 tries, 24124 fired transitions, 210 secs
lola: 253398 generators; 7561929 candidates remain ( 3%), 215 secs
lola: 1 tries, 24712 fired transitions, 215 secs
lola: 260864 generators; 7546375 candidates remain ( 3%), 220 secs
lola: 1 tries, 25298 fired transitions, 220 secs
lola: 268314 generators; 7530837 candidates remain ( 3%), 225 secs
lola: 1 tries, 25884 fired transitions, 225 secs
lola: 275748 generators; 7517337 candidates remain ( 3%), 230 secs
lola: 1 tries, 26470 fired transitions, 230 secs
lola: 281313 generators; 7505706 candidates remain ( 3%), 235 secs
lola: 1 tries, 27058 fired transitions, 235 secs
lola: 286869 generators; 7492062 candidates remain ( 3%), 240 secs
lola: 1 tries, 27646 fired transitions, 240 secs
lola: 294263 generators; 7476580 candidates remain ( 3%), 245 secs
lola: 1 tries, 28234 fired transitions, 245 secs
lola: 301641 generators; 7461114 candidates remain ( 3%), 250 secs
lola: 1 tries, 28820 fired transitions, 250 secs
lola: 309003 generators; 7445664 candidates remain ( 3%), 255 secs
lola: 1 tries, 29408 fired transitions, 255 secs
lola: 316349 generators; 7430230 candidates remain ( 4%), 260 secs
lola: 1 tries, 29994 fired transitions, 260 secs
lola: 320016 generators; 7422519 candidates remain ( 4%), 265 secs
lola: 1 tries, 30578 fired transitions, 265 secs
lola: 327338 generators; 7407109 candidates remain ( 4%), 270 secs
lola: 1 tries, 31164 fired transitions, 270 secs
lola: 334644 generators; 7391715 candidates remain ( 4%), 275 secs
lola: 1 tries, 31744 fired transitions, 275 secs
lola: 341934 generators; 7376337 candidates remain ( 4%), 280 secs
lola: 1 tries, 32324 fired transitions, 280 secs
lola: 349208 generators; 7360975 candidates remain ( 4%), 285 secs
lola: 1 tries, 32908 fired transitions, 285 secs
lola: 356466 generators; 7345629 candidates remain ( 4%), 290 secs
lola: 1 tries, 33492 fired transitions, 290 secs
lola: 363708 generators; 7330299 candidates remain ( 4%), 295 secs
lola: 1 tries, 34078 fired transitions, 295 secs
lola: 370934 generators; 7314985 candidates remain ( 4%), 300 secs
lola: 1 tries, 34662 fired transitions, 300 secs
lola: 378144 generators; 7299687 candidates remain ( 4%), 305 secs
lola: time limit for symmetry computation exceeded - aborting
lola: symmetry computation interrupted: continue with partial generating set
lola: computed 378144 generators (208 in search tree, 377936 by composition)
lola: representing 5.45358E+658 symmetries
lola: 2 dead branches visited in search tree
lola: 1 tries, 35246 fired transitions, 305 secs
lola: 1 tries, 35834 fired transitions, 310 secs || 2 markings, 79 edges, 0 markings/sec, 310 secs
lola: 1 tries, 36418 fired transitions, 315 secs || 3 markings, 161 edges, 0 markings/sec, 315 secs
lola: 1 tries, 37002 fired transitions, 320 secs || 3 markings, 250 edges, 0 markings/sec, 320 secs
lola: 1 tries, 37592 fired transitions, 325 secs || 3 markings, 330 edges, 0 markings/sec, 325 secs
lola: 1 tries, 38176 fired transitions, 330 secs || 3 markings, 410 edges, 0 markings/sec, 330 secs
lola: 1 tries, 38764 fired transitions, 335 secs || 3 markings, 489 edges, 0 markings/sec, 335 secs
lola: 1 tries, 39352 fired transitions, 340 secs || 3 markings, 568 edges, 0 markings/sec, 340 secs
lola: 1 tries, 39942 fired transitions, 345 secs || 3 markings, 649 edges, 0 markings/sec, 345 secs
lola: 1 tries, 40528 fired transitions, 350 secs || 3 markings, 728 edges, 0 markings/sec, 350 secs
lola: 1 tries, 41098 fired transitions, 355 secs || 3 markings, 810 edges, 0 markings/sec, 355 secs
lola: 1 tries, 41674 fired transitions, 360 secs || 3 markings, 897 edges, 0 markings/sec, 360 secs
lola: 1 tries, 42264 fired transitions, 365 secs || 3 markings, 981 edges, 0 markings/sec, 365 secs
lola: 1 tries, 42852 fired transitions, 370 secs || 3 markings, 1064 edges, 0 markings/sec, 370 secs
lola: 1 tries, 43442 fired transitions, 375 secs || 3 markings, 1147 edges, 0 markings/sec, 375 secs
lola: 1 tries, 44030 fired transitions, 380 secs || 3 markings, 1229 edges, 0 markings/sec, 380 secs
lola: 1 tries, 44622 fired transitions, 385 secs || 3 markings, 1313 edges, 0 markings/sec, 385 secs
lola: 1 tries, 45212 fired transitions, 390 secs || 3 markings, 1395 edges, 0 markings/sec, 390 secs
lola: 1 tries, 45800 fired transitions, 395 secs || 3 markings, 1479 edges, 0 markings/sec, 395 secs
lola: 1 tries, 46390 fired transitions, 400 secs || 3 markings, 1563 edges, 0 markings/sec, 400 secs
lola: 1 tries, 46978 fired transitions, 405 secs || 3 markings, 1647 edges, 0 markings/sec, 405 secs
lola: 1 tries, 47568 fired transitions, 410 secs || 3 markings, 1729 edges, 0 markings/sec, 410 secs
lola: 1 tries, 48154 fired transitions, 415 secs || 3 markings, 1811 edges, 0 markings/sec, 415 secs
lola: 1 tries, 48738 fired transitions, 420 secs || 3 markings, 1895 edges, 0 markings/sec, 420 secs
lola: 1 tries, 49322 fired transitions, 425 secs || 3 markings, 1980 edges, 0 markings/sec, 425 secs
lola: RESULT
lola: result: no
lola: produced by: state space
lola: The predicate is unreachable.
lola: 3 markings, 2012 edges
lola: memory consumption: 5973740 KB
lola: time consumption: 441 seconds
lola: print data as JSON (--json)
lola: writing JSON to GlobalProperties.json
lola: closed JSON file GlobalProperties.json
rslt: finished

BK_STOP 1553000093084

--------------------
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-r0010w2000"
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-r0010w2000, 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-155286404000318"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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