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-3253
Executing tool lola
Input is DatabaseWithMutex-COL-40, examination is ReachabilityDeadlock
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r018-ebro-149436309000170
=====================================================================

--------------------
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 DatabaseWithMutex-COL-40-ReachabilityDeadlock-0

=== Now, execution of the tool begins

BK_START 1494623117174


Time: 3600 - MCC
----- Start make prepare stdout -----
checking for too many tokens
----- Start make result stdout -----
ReachabilityDeadlock @ DatabaseWithMutex-COL-40 @ 3540 seconds
Makefile:224: recipe for target 'verify' failed
----- Start make result stdout -----
lola: NET
lola: reading net from model.pnml.lola
lola: finished parsing
lola: closed net file model.pnml.lola
lola: 25720/65536 symbol table entries, 8752 collisions
lola: preprocessing...
lola: finding significant places
lola: 12920 places, 12800 transitions, 11200 significant places
lola: computing forward-conflicting sets
lola: computing back-conflicting sets
lola: 16160 transition conflict sets
lola: TASK
lola: reading formula from DatabaseWithMutex-COL-40-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 44800 bytes per marking, with 0 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: 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: formula with 308522 variables and 2200924 clauses shipped to Minisat
lola: STP running || 1 tries, 10053 fired transitions, 0 secs
lola: 1812 generators;83327688 candidates remain ( 0%), 0 secs
lola: STP running || 1 tries, 19615 fired transitions, 5 secs
lola: 3227 generators;83198613 candidates remain ( 0%), 5 secs
lola: stp: The siphon/trap property is inconclusive since the generated formula is too short
lola: STP completed || 1 tries, 28223 fired transitions, 10 secs
lola: 3516 generators;82259623 candidates remain ( 0%), 10 secs
lola: computed 4183 generators (108 in search tree, 4075 by composition)
lola: representing 6.65718E+95 symmetries
lola: 0 dead branches visited in search tree
lola: STP completed || 1 tries, 37255 fired transitions, 15 secs || 447 markings, 449 edges, 89 markings/sec, 15 secs
lola: STP completed || 1 tries, 46664 fired transitions, 20 secs || 7852 markings, 7875 edges, 1481 markings/sec, 20 secs
lola: STP completed || 1 tries, 55753 fired transitions, 25 secs || 15147 markings, 15183 edges, 1459 markings/sec, 25 secs
lola: STP completed || 1 tries, 64943 fired transitions, 30 secs || 22435 markings, 22485 edges, 1458 markings/sec, 30 secs
lola: STP completed || 1 tries, 73891 fired transitions, 35 secs || 29768 markings, 29836 edges, 1467 markings/sec, 35 secs
lola: STP completed || 1 tries, 82844 fired transitions, 40 secs || 37108 markings, 37194 edges, 1468 markings/sec, 40 secs
lola: STP completed || 1 tries, 91941 fired transitions, 45 secs || 44544 markings, 44650 edges, 1487 markings/sec, 45 secs
lola: STP completed || 1 tries, 101208 fired transitions, 50 secs || 51996 markings, 52120 edges, 1490 markings/sec, 50 secs
lola: STP completed || 1 tries, 110685 fired transitions, 55 secs || 59432 markings, 59571 edges, 1487 markings/sec, 55 secs
lola: STP completed || 1 tries, 119874 fired transitions, 60 secs || 66889 markings, 67039 edges, 1491 markings/sec, 60 secs
lola: STP completed || 1 tries, 129281 fired transitions, 65 secs || 74327 markings, 74500 edges, 1488 markings/sec, 65 secs
lola: STP completed || 1 tries, 138406 fired transitions, 70 secs || 81743 markings, 81935 edges, 1483 markings/sec, 70 secs
lola: STP completed || 1 tries, 147966 fired transitions, 75 secs || 89163 markings, 89374 edges, 1484 markings/sec, 75 secs
lola: STP completed || 1 tries, 157308 fired transitions, 80 secs || 96585 markings, 96823 edges, 1484 markings/sec, 80 secs
lola: STP completed || 1 tries, 166300 fired transitions, 85 secs || 104038 markings, 104292 edges, 1491 markings/sec, 85 secs
lola: STP completed || 1 tries, 175212 fired transitions, 90 secs || 111432 markings, 111716 edges, 1479 markings/sec, 90 secs
lola: STP completed || 1 tries, 184698 fired transitions, 95 secs || 118814 markings, 119120 edges, 1476 markings/sec, 95 secs
lola: STP completed || 1 tries, 193958 fired transitions, 100 secs || 126157 markings, 126486 edges, 1469 markings/sec, 100 secs
lola: STP completed || 1 tries, 203020 fired transitions, 105 secs || 133461 markings, 133815 edges, 1461 markings/sec, 105 secs
lola: STP completed || 1 tries, 212231 fired transitions, 110 secs || 140826 markings, 141201 edges, 1473 markings/sec, 110 secs
lola: STP completed || 1 tries, 221192 fired transitions, 115 secs || 148133 markings, 148537 edges, 1461 markings/sec, 115 secs
lola: STP completed || 1 tries, 230371 fired transitions, 120 secs || 155334 markings, 155773 edges, 1440 markings/sec, 120 secs
lola: STP completed || 1 tries, 239401 fired transitions, 125 secs || 162595 markings, 163064 edges, 1452 markings/sec, 125 secs
lola: STP completed || 1 tries, 248819 fired transitions, 130 secs || 169895 markings, 170389 edges, 1460 markings/sec, 130 secs
lola: STP completed || 1 tries, 258161 fired transitions, 135 secs || 177243 markings, 177769 edges, 1470 markings/sec, 135 secs
lola: STP completed || 1 tries, 267142 fired transitions, 140 secs || 184468 markings, 185011 edges, 1445 markings/sec, 140 secs
lola: STP completed || 1 tries, 276417 fired transitions, 145 secs || 191781 markings, 192340 edges, 1463 markings/sec, 145 secs
lola: STP completed || 1 tries, 285828 fired transitions, 150 secs || 199051 markings, 199619 edges, 1454 markings/sec, 150 secs
lola: STP completed || 1 tries, 295229 fired transitions, 155 secs || 206337 markings, 206924 edges, 1457 markings/sec, 155 secs
lola: STP completed || 1 tries, 305116 fired transitions, 160 secs || 213694 markings, 214305 edges, 1471 markings/sec, 160 secs
lola: STP completed || 1 tries, 314454 fired transitions, 165 secs || 220982 markings, 221620 edges, 1458 markings/sec, 165 secs
lola: STP completed || 1 tries, 323618 fired transitions, 170 secs || 228289 markings, 228947 edges, 1461 markings/sec, 170 secs
lola: STP completed || 1 tries, 332656 fired transitions, 175 secs || 235480 markings, 236174 edges, 1438 markings/sec, 175 secs
lola: STP completed || 1 tries, 341853 fired transitions, 180 secs || 242732 markings, 243453 edges, 1450 markings/sec, 180 secs
lola: STP completed || 1 tries, 351481 fired transitions, 185 secs || 250080 markings, 250817 edges, 1470 markings/sec, 185 secs
lola: STP completed || 1 tries, 360539 fired transitions, 190 secs || 257437 markings, 258201 edges, 1471 markings/sec, 190 secs
lola: STP completed || 1 tries, 369479 fired transitions, 195 secs || 264734 markings, 265523 edges, 1459 markings/sec, 195 secs
lola: STP completed || 1 tries, 378672 fired transitions, 200 secs || 272070 markings, 272879 edges, 1467 markings/sec, 200 secs
lola: STP completed || 1 tries, 388222 fired transitions, 205 secs || 279398 markings, 280231 edges, 1466 markings/sec, 205 secs
lola: STP completed || 1 tries, 397424 fired transitions, 210 secs || 286667 markings, 287537 edges, 1454 markings/sec, 210 secs
lola: STP completed || 1 tries, 406370 fired transitions, 215 secs || 293930 markings, 294821 edges, 1453 markings/sec, 215 secs
lola: STP completed || 1 tries, 415769 fired transitions, 220 secs || 301189 markings, 302096 edges, 1452 markings/sec, 220 secs
lola: STP completed || 1 tries, 425294 fired transitions, 225 secs || 308404 markings, 309345 edges, 1443 markings/sec, 225 secs
lola: STP completed || 1 tries, 434470 fired transitions, 230 secs || 315749 markings, 316708 edges, 1469 markings/sec, 230 secs
lola: STP completed || 1 tries, 443366 fired transitions, 235 secs || 323091 markings, 324082 edges, 1468 markings/sec, 235 secs
lola: STP completed || 1 tries, 452586 fired transitions, 240 secs || 330460 markings, 331486 edges, 1474 markings/sec, 240 secs
lola: STP completed || 1 tries, 461962 fired transitions, 245 secs || 337810 markings, 338868 edges, 1470 markings/sec, 245 secs
lola: STP completed || 1 tries, 470949 fired transitions, 250 secs || 345243 markings, 346305 edges, 1487 markings/sec, 250 secs
lola: STP completed || 1 tries, 479936 fired transitions, 255 secs || 352517 markings, 353594 edges, 1455 markings/sec, 255 secs
lola: STP completed || 1 tries, 489516 fired transitions, 260 secs || 359770 markings, 360870 edges, 1451 markings/sec, 260 secs
lola: STP completed || 1 tries, 498712 fired transitions, 265 secs || 367042 markings, 368163 edges, 1454 markings/sec, 265 secs
lola: STP completed || 1 tries, 508134 fired transitions, 270 secs || 374360 markings, 375508 edges, 1464 markings/sec, 270 secs
lola: STP completed || 1 tries, 517090 fired transitions, 275 secs || 381692 markings, 382865 edges, 1466 markings/sec, 275 secs
lola: STP completed || 1 tries, 526088 fired transitions, 280 secs || 388997 markings, 390200 edges, 1461 markings/sec, 280 secs
lola: STP completed || 1 tries, 535483 fired transitions, 285 secs || 396280 markings, 397511 edges, 1457 markings/sec, 285 secs
lola: STP completed || 1 tries, 544705 fired transitions, 290 secs || 403500 markings, 404751 edges, 1444 markings/sec, 290 secs
lola: STP completed || 1 tries, 554032 fired transitions, 295 secs || 410801 markings, 412085 edges, 1460 markings/sec, 295 secs
lola: STP completed || 1 tries, 563303 fired transitions, 300 secs || 418101 markings, 419418 edges, 1460 markings/sec, 300 secs
lola: STP completed || 1 tries, 572582 fired transitions, 305 secs || 425387 markings, 426730 edges, 1457 markings/sec, 305 secs
lola: STP completed || 1 tries, 582012 fired transitions, 310 secs || 432709 markings, 434084 edges, 1464 markings/sec, 310 secs
lola: STP completed || 1 tries, 591096 fired transitions, 315 secs || 440041 markings, 441444 edges, 1466 markings/sec, 315 secs
lola: STP completed || 1 tries, 600519 fired transitions, 320 secs || 447358 markings, 448785 edges, 1463 markings/sec, 320 secs
lola: STP completed || 1 tries, 609623 fired transitions, 325 secs || 454721 markings, 456170 edges, 1473 markings/sec, 325 secs
lola: STP completed || 1 tries, 618843 fired transitions, 330 secs || 461999 markings, 463478 edges, 1456 markings/sec, 330 secs
lola: STP completed || 1 tries, 628253 fired transitions, 335 secs || 469269 markings, 470767 edges, 1454 markings/sec, 335 secs
lola: STP completed || 1 tries, 637286 fired transitions, 340 secs || 476568 markings, 478094 edges, 1460 markings/sec, 340 secs
lola: STP completed || 1 tries, 646289 fired transitions, 345 secs || 483898 markings, 485456 edges, 1466 markings/sec, 345 secs
lola: STP completed || 1 tries, 655792 fired transitions, 350 secs || 491219 markings, 492801 edges, 1464 markings/sec, 350 secs
lola: STP completed || 1 tries, 665304 fired transitions, 355 secs || 498518 markings, 500124 edges, 1460 markings/sec, 355 secs
lola: STP completed || 1 tries, 675148 fired transitions, 360 secs || 505857 markings, 507485 edges, 1468 markings/sec, 360 secs
lola: STP completed || 1 tries, 684443 fired transitions, 365 secs || 513244 markings, 514903 edges, 1477 markings/sec, 365 secs
lola: STP completed || 1 tries, 693906 fired transitions, 370 secs || 520592 markings, 522271 edges, 1470 markings/sec, 370 secs
lola: STP completed || 1 tries, 703007 fired transitions, 375 secs || 527939 markings, 529637 edges, 1469 markings/sec, 375 secs
lola: STP completed || 1 tries, 712578 fired transitions, 380 secs || 535330 markings, 537059 edges, 1478 markings/sec, 380 secs
lola: STP completed || 1 tries, 722380 fired transitions, 385 secs || 542665 markings, 544415 edges, 1467 markings/sec, 385 secs
lola: STP completed || 1 tries, 731941 fired transitions, 390 secs || 550087 markings, 551847 edges, 1484 markings/sec, 390 secs
lola: STP completed || 1 tries, 741044 fired transitions, 395 secs || 557371 markings, 559146 edges, 1457 markings/sec, 395 secs
lola: STP completed || 1 tries, 750726 fired transitions, 400 secs || 564630 markings, 566418 edges, 1452 markings/sec, 400 secs
lola: STP completed || 1 tries, 759524 fired transitions, 405 secs || 571888 markings, 573705 edges, 1452 markings/sec, 405 secs
lola: STP completed || 1 tries, 769028 fired transitions, 410 secs || 579150 markings, 580986 edges, 1452 markings/sec, 410 secs
lola: STP completed || 1 tries, 777889 fired transitions, 415 secs || 586492 markings, 588340 edges, 1468 markings/sec, 415 secs
lola: STP completed || 1 tries, 786962 fired transitions, 420 secs || 593931 markings, 596161 edges, 1488 markings/sec, 420 secs
lola: STP completed || 1 tries, 795995 fired transitions, 425 secs || 601138 markings, 603380 edges, 1441 markings/sec, 425 secs
Killed
FORMULA DatabaseWithMutex-COL-40-ReachabilityDeadlock-0 CANNOT_COMPUTE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT SAT_SMT STATE_COMPRESSION STUBBORN_SETS SYMMETRIES TOPOLOGICAL USE_NUPN
----- Kill lola and sara stdout -----
----- Finished stdout -----

BK_STOP 1494623557043

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

----- Start make prepare stderr -----
----- Start make result stderr -----
make: [verify] Error 137 (ignored)
----- 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="DatabaseWithMutex-COL-40"
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/DatabaseWithMutex-COL-40.tgz
mv DatabaseWithMutex-COL-40 execution

# this is for BenchKit: explicit launching of the test

cd execution
echo "====================================================================="
echo " Generated by BenchKit 2-3253"
echo " Executing tool lola"
echo " Input is DatabaseWithMutex-COL-40, 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 r018-ebro-149436309000170"
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 ;