Execution Chart

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

Trace from the execution

Waiting for the VM to be ready (probing ssh)
.........
=====================================================================
Generated by BenchKit 2-3254
Executing tool lola
Input is DrinkVendingMachine-PT-10, examination is ReachabilityDeadlock
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r028-blw3-149440264100233
=====================================================================

--------------------
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 DrinkVendingMachine-COL-10-ReachabilityDeadlock-0

=== Now, execution of the tool begins

BK_START 1494683223800


Time: 3600 - MCC
----- Start make prepare stdout -----
checking for too many tokens
----- Start make result stdout -----
ReachabilityDeadlock @ DrinkVendingMachine-PT-10 @ 3539 seconds
----- Start make result stdout -----
lola: NET
lola: reading net from model.pnml.lola
lola: finished parsing
lola: closed net file model.pnml.lola
lola: 111280/65536 symbol table entries, 66439 collisions
lola: preprocessing...
lola: finding significant places
lola: 120 places, 111160 transitions, 60 significant places
lola: computing forward-conflicting sets
lola: computing back-conflicting sets
lola: 48320 transition conflict sets
lola: TASK
lola: reading formula from DrinkVendingMachine-COL-10-ReachabilityDeadlock.task
lola: E (F (DEADLOCK))
lola: E (F (DEADLOCK))
lola: checking reachability of deadlocks
lola: Planning: workflow for deadlock check: siphon||findpath||search) (--findpath=par,--siphontrap=par)
lola: STORE
lola: using a bit-perfect encoder (--encoder=bit)
lola: using 8 bytes per marking, with 4 unused bits
lola: using a prefix tree store (--store=prefix)
lola: SEARCH (state space)
lola: state space: using reachability graph (--search=depth)
lola: state space: using deadlock preserving stubborn set method with insertion algorithm(--stubborn=tarjan)
lola: SEARCH (findpath)
lola: findpath: using deadlock preserving stubborn set method with insertion algorithm (--stubborn=tarjan)
lola: RUNNING
lola: stp: The siphon/trap property is not applicable since the net is inhomogeneous
lola: findpath: starting randomized, memory-less exploration (--search=findpath)
lola: findpath: searching for paths with maximal depth 1000000 (--depthlimit)
lola: findpath: no retry limit given (--retrylimit)
lola: findpath: transitions are chosen randomly
lola: computing symmetries (--symmetry)
lola: time limit for symmetry computation of 300 seconds given (--symmtimelimit)
lola: STP completed || 1 tries, 0 fired transitions, 0 secs
lola: 0 generators; 6812 candidates remain ( 0%), 0 secs
lola: STP completed || 1 tries, 0 fired transitions, 5 secs
lola: 0 generators; 6812 candidates remain ( 0%), 5 secs
lola: STP completed || 1 tries, 0 fired transitions, 10 secs
lola: 0 generators; 6812 candidates remain ( 0%), 10 secs
lola: STP completed || 1 tries, 6 fired transitions, 15 secs
lola: 0 generators; 6812 candidates remain ( 0%), 15 secs
lola: STP completed || 1 tries, 6 fired transitions, 20 secs
lola: 0 generators; 6812 candidates remain ( 0%), 20 secs
lola: STP completed || 1 tries, 6 fired transitions, 25 secs
lola: 0 generators; 6812 candidates remain ( 0%), 25 secs
lola: 0 generators; 6812 candidates remain ( 0%), 30 secs
lola: STP completed || 1 tries, 6 fired transitions, 30 secs
lola: 0 generators; 6812 candidates remain ( 0%), 35 secs
lola: STP completed || 1 tries, 12 fired transitions, 35 secs
lola: 0 generators; 6812 candidates remain ( 0%), 40 secs
lola: STP completed || 1 tries, 12 fired transitions, 40 secs
lola: 0 generators; 6812 candidates remain ( 0%), 45 secs
lola: STP completed || 1 tries, 12 fired transitions, 45 secs
lola: 0 generators; 6812 candidates remain ( 0%), 50 secs
lola: STP completed || 1 tries, 12 fired transitions, 50 secs
lola: 0 generators; 6812 candidates remain ( 0%), 55 secs
lola: STP completed || 1 tries, 18 fired transitions, 55 secs
lola: 0 generators; 6812 candidates remain ( 0%), 60 secs
lola: STP completed || 1 tries, 18 fired transitions, 60 secs
lola: 0 generators; 6812 candidates remain ( 0%), 65 secs
lola: STP completed || 1 tries, 18 fired transitions, 65 secs
lola: 0 generators; 6812 candidates remain ( 0%), 70 secs
lola: STP completed || 1 tries, 18 fired transitions, 70 secs
lola: 0 generators; 6812 candidates remain ( 0%), 75 secs
lola: STP completed || 1 tries, 23 fired transitions, 75 secs
lola: 0 generators; 6812 candidates remain ( 0%), 80 secs
lola: STP completed || 1 tries, 23 fired transitions, 80 secs
lola: 0 generators; 6812 candidates remain ( 0%), 85 secs
lola: STP completed || 1 tries, 23 fired transitions, 85 secs
lola: 0 generators; 6812 candidates remain ( 0%), 90 secs
lola: STP completed || 1 tries, 23 fired transitions, 90 secs
lola: 0 generators; 6812 candidates remain ( 0%), 95 secs
lola: STP completed || 1 tries, 29 fired transitions, 95 secs
lola: 0 generators; 6812 candidates remain ( 0%), 100 secs
lola: STP completed || 1 tries, 29 fired transitions, 100 secs
lola: 0 generators; 6812 candidates remain ( 0%), 105 secs
lola: STP completed || 1 tries, 29 fired transitions, 105 secs
lola: 0 generators; 6812 candidates remain ( 0%), 110 secs
lola: STP completed || 1 tries, 29 fired transitions, 110 secs
lola: 0 generators; 6812 candidates remain ( 0%), 115 secs
lola: STP completed || 1 tries, 35 fired transitions, 115 secs
lola: 0 generators; 6812 candidates remain ( 0%), 120 secs
lola: STP completed || 1 tries, 35 fired transitions, 120 secs
lola: 0 generators; 6812 candidates remain ( 0%), 125 secs
lola: STP completed || 1 tries, 35 fired transitions, 125 secs
lola: 0 generators; 6812 candidates remain ( 0%), 130 secs
lola: STP completed || 1 tries, 35 fired transitions, 130 secs
lola: 0 generators; 6812 candidates remain ( 0%), 135 secs
lola: STP completed || 1 tries, 41 fired transitions, 135 secs
lola: 0 generators; 6812 candidates remain ( 0%), 140 secs
lola: STP completed || 1 tries, 41 fired transitions, 140 secs
lola: 0 generators; 6812 candidates remain ( 0%), 145 secs
lola: STP completed || 1 tries, 41 fired transitions, 145 secs
lola: 0 generators; 6812 candidates remain ( 0%), 150 secs
lola: STP completed || 1 tries, 47 fired transitions, 150 secs
lola: 0 generators; 6812 candidates remain ( 0%), 155 secs
lola: STP completed || 1 tries, 47 fired transitions, 155 secs
lola: 0 generators; 6812 candidates remain ( 0%), 160 secs
lola: STP completed || 1 tries, 47 fired transitions, 160 secs
lola: 0 generators; 6812 candidates remain ( 0%), 165 secs
lola: STP completed || 1 tries, 47 fired transitions, 165 secs
lola: 0 generators; 6812 candidates remain ( 0%), 170 secs
lola: STP completed || 1 tries, 51 fired transitions, 170 secs
lola: 0 generators; 6812 candidates remain ( 0%), 175 secs
lola: STP completed || 1 tries, 51 fired transitions, 175 secs
lola: 0 generators; 6812 candidates remain ( 0%), 180 secs
lola: STP completed || 1 tries, 51 fired transitions, 180 secs
lola: 0 generators; 6812 candidates remain ( 0%), 185 secs
lola: STP completed || 1 tries, 51 fired transitions, 185 secs
lola: 0 generators; 6812 candidates remain ( 0%), 190 secs
lola: STP completed || 1 tries, 57 fired transitions, 190 secs
lola: 0 generators; 6812 candidates remain ( 0%), 195 secs
lola: STP completed || 1 tries, 57 fired transitions, 195 secs
lola: 0 generators; 6812 candidates remain ( 0%), 200 secs
lola: STP completed || 1 tries, 57 fired transitions, 200 secs
lola: 0 generators; 6812 candidates remain ( 0%), 205 secs
lola: STP completed || 1 tries, 57 fired transitions, 205 secs
lola: 0 generators; 6812 candidates remain ( 0%), 210 secs
lola: STP completed || 1 tries, 63 fired transitions, 210 secs
lola: 0 generators; 6812 candidates remain ( 0%), 215 secs
lola: STP completed || 1 tries, 63 fired transitions, 215 secs
lola: 0 generators; 6812 candidates remain ( 0%), 220 secs
lola: STP completed || 1 tries, 63 fired transitions, 220 secs
lola: 0 generators; 6812 candidates remain ( 0%), 225 secs
lola: STP completed || 1 tries, 63 fired transitions, 225 secs
lola: 0 generators; 6812 candidates remain ( 0%), 230 secs
lola: STP completed || 1 tries, 69 fired transitions, 230 secs
lola: 0 generators; 6812 candidates remain ( 0%), 235 secs
lola: STP completed || 1 tries, 69 fired transitions, 235 secs
lola: 0 generators; 6812 candidates remain ( 0%), 240 secs
lola: STP completed || 1 tries, 69 fired transitions, 240 secs
lola: 0 generators; 6812 candidates remain ( 0%), 245 secs
lola: STP completed || 1 tries, 69 fired transitions, 245 secs
lola: 0 generators; 6812 candidates remain ( 0%), 250 secs
lola: STP completed || 1 tries, 75 fired transitions, 250 secs
lola: 0 generators; 6812 candidates remain ( 0%), 255 secs
lola: STP completed || 1 tries, 75 fired transitions, 255 secs
lola: 0 generators; 6812 candidates remain ( 0%), 260 secs
lola: STP completed || 1 tries, 75 fired transitions, 260 secs
lola: 0 generators; 6812 candidates remain ( 0%), 265 secs
lola: STP completed || 1 tries, 75 fired transitions, 265 secs
lola: 0 generators; 6812 candidates remain ( 0%), 270 secs
lola: STP completed || 1 tries, 81 fired transitions, 270 secs
lola: 0 generators; 6812 candidates remain ( 0%), 275 secs
lola: STP completed || 1 tries, 81 fired transitions, 275 secs
lola: 0 generators; 6812 candidates remain ( 0%), 280 secs
lola: STP completed || 1 tries, 81 fired transitions, 280 secs
lola: 0 generators; 6812 candidates remain ( 0%), 285 secs
lola: STP completed || 1 tries, 87 fired transitions, 285 secs
lola: 0 generators; 6812 candidates remain ( 0%), 290 secs
lola: STP completed || 1 tries, 87 fired transitions, 290 secs
lola: 0 generators; 6812 candidates remain ( 0%), 295 secs
lola: STP completed || 1 tries, 87 fired transitions, 295 secs
lola: 0 generators; 6812 candidates remain ( 0%), 300 secs
lola: STP completed || 1 tries, 87 fired transitions, 300 secs
lola: 0 generators; 6812 candidates remain ( 0%), 305 secs
lola: time limit for symmetry computation exceeded - aborting
lola: STP completed || 1 tries, 93 fired transitions, 305 secs
lola: symmetry computation interrupted: continue with partial generating set
lola: computed 0 generators (0 in search tree, 0 by composition)
lola: representing 1 symmetries
lola: 2 dead branches visited in search tree
lola: STP completed || 1 tries, 93 fired transitions, 310 secs || 1 markings, 0 edges, 0 markings/sec, 310 secs
lola: STP completed || 1 tries, 93 fired transitions, 315 secs || 1 markings, 0 edges, 0 markings/sec, 315 secs
lola: STP completed || 1 tries, 93 fired transitions, 320 secs || 1 markings, 0 edges, 0 markings/sec, 320 secs
lola: STP completed || 1 tries, 99 fired transitions, 325 secs || 132 markings, 218 edges, 26 markings/sec, 325 secs
lola: STP completed || 1 tries, 99 fired transitions, 330 secs || 1361 markings, 2495 edges, 246 markings/sec, 330 secs
lola: STP completed || 1 tries, 99 fired transitions, 335 secs || 2555 markings, 4753 edges, 239 markings/sec, 335 secs
lola: STP completed || 1 tries, 99 fired transitions, 340 secs || 3826 markings, 7272 edges, 254 markings/sec, 340 secs
lola: STP completed || 1 tries, 105 fired transitions, 345 secs || 5043 markings, 9702 edges, 243 markings/sec, 345 secs
lola: STP completed || 1 tries, 105 fired transitions, 350 secs || 6334 markings, 12504 edges, 258 markings/sec, 350 secs
lola: STP completed || 1 tries, 105 fired transitions, 355 secs || 7591 markings, 15368 edges, 251 markings/sec, 355 secs
lola: STP completed || 1 tries, 105 fired transitions, 360 secs || 8773 markings, 18122 edges, 236 markings/sec, 360 secs
lola: STP completed || 1 tries, 111 fired transitions, 365 secs || 9947 markings, 21007 edges, 235 markings/sec, 365 secs
lola: STP completed || 1 tries, 111 fired transitions, 370 secs || 11268 markings, 24617 edges, 264 markings/sec, 370 secs
lola: STP completed || 1 tries, 111 fired transitions, 375 secs || 12603 markings, 28528 edges, 267 markings/sec, 375 secs
lola: STP completed || 1 tries, 111 fired transitions, 380 secs || 14023 markings, 34223 edges, 284 markings/sec, 380 secs
lola: STP completed || 1 tries, 117 fired transitions, 385 secs || 15577 markings, 41922 edges, 311 markings/sec, 385 secs
lola: STP completed || 1 tries, 117 fired transitions, 390 secs || 16939 markings, 50046 edges, 272 markings/sec, 390 secs
lola: STP completed || 1 tries, 117 fired transitions, 395 secs || 18266 markings, 67950 edges, 265 markings/sec, 395 secs
lola: RESULT
lola: result: no
lola: produced by: state space
lola: The net does not have deadlocks.
lola: 19362 markings, 101461 edges
FORMULA DrinkVendingMachine-COL-10-ReachabilityDeadlock-0 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT SAT_SMT STATE_COMPRESSION STUBBORN_SETS SYMMETRIES TOPOLOGICAL USE_NUPN
----- Kill lola and sara stdout -----
----- Finished stdout -----

BK_STOP 1494683649452

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

----- Start make prepare stderr -----
----- Start make result stderr -----
----- Start make result stderr -----
----- Kill lola and sara stderr -----
----- Finished stderr -----

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="DrinkVendingMachine-PT-10"
export BK_EXAMINATION="ReachabilityDeadlock"
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

tar xzf /home/mcc/BenchKit/INPUTS/DrinkVendingMachine-PT-10.tgz
mv DrinkVendingMachine-PT-10 execution

# this is for BenchKit: explicit launching of the test

cd execution
echo "====================================================================="
echo " Generated by BenchKit 2-3254"
echo " Executing tool lola"
echo " Input is DrinkVendingMachine-PT-10, 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 r028-blw3-149440264100233"
echo "====================================================================="
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
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 ;