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-PT-40, examination is ReachabilityDeadlock
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r018-ebro-149436309000197
=====================================================================

--------------------
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 1494624229300


Time: 3600 - MCC
----- Start make prepare stdout -----
checking for too many tokens
----- Start make result stdout -----
ReachabilityDeadlock @ DatabaseWithMutex-PT-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, 4149 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: computing symmetries (--symmetry)
lola: time limit for symmetry computation of 300 seconds given (--symmtimelimit)
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: STP: formula with 308522 variables and 2200924 clauses shipped to Minisat
lola: STP running || 1 tries, 10156 fired transitions, 0 secs
lola: 3227 generators;83353515 candidates remain ( 0%), 0 secs
lola: STP running || 1 tries, 19517 fired transitions, 5 secs
lola: 3503 generators;83250199 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, 29106 fired transitions, 10 secs
lola: 4533 generators;82928271 candidates remain ( 0%), 10 secs
lola: STP completed || 1 tries, 38899 fired transitions, 15 secs
lola: 5326 generators;77644544 candidates remain ( 0%), 15 secs
lola: computed 5589 generators (123 in search tree, 5466 by composition)
lola: representing 6.65718E+95 symmetries
lola: 0 dead branches visited in search tree
lola: STP completed || 1 tries, 48425 fired transitions, 20 secs || 4572 markings, 4578 edges, 914 markings/sec, 20 secs
lola: STP completed || 1 tries, 57158 fired transitions, 25 secs || 11718 markings, 11754 edges, 1429 markings/sec, 25 secs
lola: STP completed || 1 tries, 66112 fired transitions, 30 secs || 19054 markings, 19196 edges, 1467 markings/sec, 30 secs
lola: STP completed || 1 tries, 75187 fired transitions, 35 secs || 26365 markings, 26593 edges, 1462 markings/sec, 35 secs
lola: STP completed || 1 tries, 84117 fired transitions, 40 secs || 33770 markings, 34149 edges, 1481 markings/sec, 40 secs
lola: STP completed || 1 tries, 93184 fired transitions, 45 secs || 41058 markings, 41517 edges, 1458 markings/sec, 45 secs
lola: STP completed || 1 tries, 102412 fired transitions, 50 secs || 48362 markings, 48943 edges, 1461 markings/sec, 50 secs
lola: STP completed || 1 tries, 111401 fired transitions, 55 secs || 55820 markings, 56514 edges, 1492 markings/sec, 55 secs
lola: STP completed || 1 tries, 120896 fired transitions, 60 secs || 63279 markings, 64086 edges, 1492 markings/sec, 60 secs
lola: STP completed || 1 tries, 130120 fired transitions, 65 secs || 70769 markings, 71698 edges, 1498 markings/sec, 65 secs
lola: STP completed || 1 tries, 139052 fired transitions, 70 secs || 78152 markings, 79202 edges, 1477 markings/sec, 70 secs
lola: STP completed || 1 tries, 148354 fired transitions, 75 secs || 85453 markings, 86587 edges, 1460 markings/sec, 75 secs
lola: STP completed || 1 tries, 157309 fired transitions, 80 secs || 92864 markings, 94105 edges, 1482 markings/sec, 80 secs
lola: STP completed || 1 tries, 166512 fired transitions, 85 secs || 100249 markings, 101559 edges, 1477 markings/sec, 85 secs
lola: STP completed || 1 tries, 175875 fired transitions, 90 secs || 107762 markings, 109176 edges, 1503 markings/sec, 90 secs
lola: STP completed || 1 tries, 185142 fired transitions, 95 secs || 115329 markings, 116921 edges, 1513 markings/sec, 95 secs
lola: STP completed || 1 tries, 194379 fired transitions, 100 secs || 122796 markings, 124510 edges, 1493 markings/sec, 100 secs
lola: STP completed || 1 tries, 203617 fired transitions, 105 secs || 130286 markings, 132167 edges, 1498 markings/sec, 105 secs
lola: STP completed || 1 tries, 213227 fired transitions, 110 secs || 137889 markings, 140062 edges, 1521 markings/sec, 110 secs
lola: STP completed || 1 tries, 222200 fired transitions, 115 secs || 145527 markings, 147895 edges, 1528 markings/sec, 115 secs
lola: STP completed || 1 tries, 231643 fired transitions, 120 secs || 153091 markings, 155610 edges, 1513 markings/sec, 120 secs
lola: STP completed || 1 tries, 240866 fired transitions, 125 secs || 160635 markings, 163283 edges, 1509 markings/sec, 125 secs
lola: STP completed || 1 tries, 250487 fired transitions, 130 secs || 168160 markings, 170892 edges, 1505 markings/sec, 130 secs
lola: STP completed || 1 tries, 260195 fired transitions, 135 secs || 175828 markings, 178770 edges, 1534 markings/sec, 135 secs
lola: STP completed || 1 tries, 269285 fired transitions, 140 secs || 183350 markings, 186392 edges, 1504 markings/sec, 140 secs
lola: STP completed || 1 tries, 278411 fired transitions, 145 secs || 190944 markings, 194145 edges, 1519 markings/sec, 145 secs
lola: STP completed || 1 tries, 287474 fired transitions, 150 secs || 198589 markings, 202049 edges, 1529 markings/sec, 150 secs
lola: STP completed || 1 tries, 296557 fired transitions, 155 secs || 206237 markings, 209920 edges, 1530 markings/sec, 155 secs
lola: STP completed || 1 tries, 305971 fired transitions, 160 secs || 213861 markings, 217766 edges, 1525 markings/sec, 160 secs
lola: STP completed || 1 tries, 315249 fired transitions, 165 secs || 221495 markings, 225599 edges, 1527 markings/sec, 165 secs
lola: STP completed || 1 tries, 324832 fired transitions, 170 secs || 229087 markings, 233348 edges, 1518 markings/sec, 170 secs
lola: STP completed || 1 tries, 334132 fired transitions, 175 secs || 236667 markings, 241172 edges, 1516 markings/sec, 175 secs
lola: STP completed || 1 tries, 343116 fired transitions, 180 secs || 244177 markings, 248816 edges, 1502 markings/sec, 180 secs
lola: STP completed || 1 tries, 352294 fired transitions, 185 secs || 251673 markings, 256490 edges, 1499 markings/sec, 185 secs
lola: STP completed || 1 tries, 361987 fired transitions, 190 secs || 259293 markings, 264278 edges, 1524 markings/sec, 190 secs
lola: STP completed || 1 tries, 371348 fired transitions, 195 secs || 266963 markings, 272198 edges, 1534 markings/sec, 195 secs
lola: STP completed || 1 tries, 380894 fired transitions, 200 secs || 274618 markings, 280084 edges, 1531 markings/sec, 200 secs
lola: STP completed || 1 tries, 390176 fired transitions, 205 secs || 282204 markings, 287827 edges, 1517 markings/sec, 205 secs
lola: STP completed || 1 tries, 399313 fired transitions, 210 secs || 289789 markings, 295542 edges, 1517 markings/sec, 210 secs
lola: STP completed || 1 tries, 408908 fired transitions, 215 secs || 297461 markings, 303442 edges, 1534 markings/sec, 215 secs
lola: STP completed || 1 tries, 418128 fired transitions, 220 secs || 305176 markings, 311500 edges, 1543 markings/sec, 220 secs
lola: STP completed || 1 tries, 427550 fired transitions, 225 secs || 312918 markings, 319628 edges, 1548 markings/sec, 225 secs
lola: STP completed || 1 tries, 437055 fired transitions, 230 secs || 320440 markings, 327269 edges, 1504 markings/sec, 230 secs
lola: STP completed || 1 tries, 446255 fired transitions, 235 secs || 327996 markings, 335060 edges, 1511 markings/sec, 235 secs
lola: STP completed || 1 tries, 455755 fired transitions, 240 secs || 335649 markings, 343013 edges, 1531 markings/sec, 240 secs
lola: STP completed || 1 tries, 464662 fired transitions, 245 secs || 343106 markings, 350568 edges, 1491 markings/sec, 245 secs
lola: STP completed || 1 tries, 473806 fired transitions, 250 secs || 350861 markings, 358752 edges, 1551 markings/sec, 250 secs
lola: STP completed || 1 tries, 482968 fired transitions, 255 secs || 358520 markings, 366750 edges, 1532 markings/sec, 255 secs
lola: STP completed || 1 tries, 492265 fired transitions, 260 secs || 366083 markings, 374527 edges, 1513 markings/sec, 260 secs
lola: STP completed || 1 tries, 501712 fired transitions, 265 secs || 373696 markings, 382302 edges, 1523 markings/sec, 265 secs
lola: STP completed || 1 tries, 510886 fired transitions, 270 secs || 381116 markings, 389869 edges, 1484 markings/sec, 270 secs
lola: STP completed || 1 tries, 520088 fired transitions, 275 secs || 388679 markings, 397642 edges, 1513 markings/sec, 275 secs
lola: STP completed || 1 tries, 529797 fired transitions, 280 secs || 396286 markings, 405501 edges, 1521 markings/sec, 280 secs
lola: STP completed || 1 tries, 538991 fired transitions, 285 secs || 403876 markings, 413279 edges, 1518 markings/sec, 285 secs
lola: STP completed || 1 tries, 548382 fired transitions, 290 secs || 411503 markings, 421161 edges, 1525 markings/sec, 290 secs
lola: STP completed || 1 tries, 557577 fired transitions, 295 secs || 419069 markings, 428902 edges, 1513 markings/sec, 295 secs
lola: STP completed || 1 tries, 566948 fired transitions, 300 secs || 426652 markings, 436694 edges, 1517 markings/sec, 300 secs
lola: STP completed || 1 tries, 576133 fired transitions, 305 secs || 434224 markings, 444477 edges, 1514 markings/sec, 305 secs
lola: STP completed || 1 tries, 585383 fired transitions, 310 secs || 441825 markings, 452311 edges, 1520 markings/sec, 310 secs
lola: STP completed || 1 tries, 594518 fired transitions, 315 secs || 449339 markings, 459945 edges, 1503 markings/sec, 315 secs
lola: STP completed || 1 tries, 603771 fired transitions, 320 secs || 456888 markings, 467625 edges, 1510 markings/sec, 320 secs
lola: STP completed || 1 tries, 612980 fired transitions, 325 secs || 464490 markings, 475405 edges, 1520 markings/sec, 325 secs
lola: STP completed || 1 tries, 622286 fired transitions, 330 secs || 472065 markings, 483115 edges, 1515 markings/sec, 330 secs
lola: STP completed || 1 tries, 631574 fired transitions, 335 secs || 479672 markings, 490875 edges, 1521 markings/sec, 335 secs
lola: STP completed || 1 tries, 640629 fired transitions, 340 secs || 487251 markings, 498645 edges, 1516 markings/sec, 340 secs
lola: STP completed || 1 tries, 650033 fired transitions, 345 secs || 494878 markings, 506476 edges, 1525 markings/sec, 345 secs
lola: STP completed || 1 tries, 659274 fired transitions, 350 secs || 502455 markings, 514217 edges, 1515 markings/sec, 350 secs
lola: STP completed || 1 tries, 668392 fired transitions, 355 secs || 510005 markings, 521882 edges, 1510 markings/sec, 355 secs
lola: STP completed || 1 tries, 677668 fired transitions, 360 secs || 517720 markings, 529923 edges, 1543 markings/sec, 360 secs
lola: STP completed || 1 tries, 686943 fired transitions, 365 secs || 525441 markings, 537956 edges, 1544 markings/sec, 365 secs
lola: STP completed || 1 tries, 696468 fired transitions, 370 secs || 533073 markings, 545774 edges, 1526 markings/sec, 370 secs
lola: STP completed || 1 tries, 705706 fired transitions, 375 secs || 540817 markings, 553813 edges, 1549 markings/sec, 375 secs
lola: STP completed || 1 tries, 715106 fired transitions, 380 secs || 548441 markings, 561651 edges, 1525 markings/sec, 380 secs
lola: STP completed || 1 tries, 724290 fired transitions, 385 secs || 556206 markings, 569787 edges, 1553 markings/sec, 385 secs
lola: STP completed || 1 tries, 733908 fired transitions, 390 secs || 563922 markings, 577815 edges, 1543 markings/sec, 390 secs
lola: STP completed || 1 tries, 742979 fired transitions, 395 secs || 571644 markings, 585834 edges, 1544 markings/sec, 395 secs
lola: STP completed || 1 tries, 752560 fired transitions, 400 secs || 579279 markings, 593711 edges, 1527 markings/sec, 400 secs
lola: STP completed || 1 tries, 761782 fired transitions, 405 secs || 586966 markings, 601616 edges, 1537 markings/sec, 405 secs
lola: STP completed || 1 tries, 771137 fired transitions, 410 secs || 594542 markings, 609327 edges, 1515 markings/sec, 410 secs
lola: STP completed || 1 tries, 780403 fired transitions, 415 secs || 602079 markings, 616948 edges, 1507 markings/sec, 415 secs
lola: STP completed || 1 tries, 789842 fired transitions, 420 secs || 609743 markings, 624853 edges, 1533 markings/sec, 420 secs
lola: STP completed || 1 tries, 799112 fired transitions, 425 secs || 617450 markings, 632875 edges, 1541 markings/sec, 425 secs
lola: STP completed || 1 tries, 808574 fired transitions, 430 secs || 625139 markings, 640842 edges, 1538 markings/sec, 430 secs
lola: STP completed || 1 tries, 817579 fired transitions, 435 secs || 632736 markings, 648602 edges, 1519 markings/sec, 435 secs
lola: STP completed || 1 tries, 827258 fired transitions, 440 secs || 640337 markings, 656434 edges, 1520 markings/sec, 440 secs
lola: STP completed || 1 tries, 836534 fired transitions, 445 secs || 648008 markings, 664348 edges, 1534 markings/sec, 445 secs
lola: STP completed || 1 tries, 845786 fired transitions, 450 secs || 655684 markings, 672341 edges, 1535 markings/sec, 450 secs
lola: STP completed || 1 tries, 854842 fired transitions, 455 secs || 663239 markings, 680134 edges, 1511 markings/sec, 455 secs
lola: STP completed || 1 tries, 864104 fired transitions, 460 secs || 670812 markings, 687999 edges, 1515 markings/sec, 460 secs
lola: STP completed || 1 tries, 873508 fired transitions, 465 secs || 678358 markings, 695727 edges, 1509 markings/sec, 465 secs
lola: STP completed || 1 tries, 882838 fired transitions, 470 secs || 685978 markings, 703572 edges, 1524 markings/sec, 470 secs
lola: STP completed || 1 tries, 892043 fired transitions, 475 secs || 693460 markings, 711264 edges, 1496 markings/sec, 475 secs
lola: STP completed || 1 tries, 901247 fired transitions, 480 secs || 701073 markings, 719066 edges, 1523 markings/sec, 480 secs
lola: STP completed || 1 tries, 910842 fired transitions, 485 secs || 708716 markings, 726978 edges, 1529 markings/sec, 485 secs
lola: STP completed || 1 tries, 920241 fired transitions, 490 secs || 716386 markings, 734972 edges, 1534 markings/sec, 490 secs
lola: STP completed || 1 tries, 929399 fired transitions, 495 secs || 724061 markings, 742922 edges, 1535 markings/sec, 495 secs
lola: STP completed || 1 tries, 938966 fired transitions, 500 secs || 731502 markings, 750456 edges, 1488 markings/sec, 500 secs
lola: STP completed || 1 tries, 947972 fired transitions, 505 secs || 739024 markings, 758222 edges, 1504 markings/sec, 505 secs
lola: STP completed || 1 tries, 957543 fired transitions, 510 secs || 746484 markings, 765876 edges, 1492 markings/sec, 510 secs
lola: STP completed || 1 tries, 966622 fired transitions, 515 secs || 753976 markings, 773554 edges, 1498 markings/sec, 515 secs
lola: STP completed || 1 tries, 976164 fired transitions, 520 secs || 761657 markings, 781598 edges, 1536 markings/sec, 520 secs
lola: STP completed || 1 tries, 985445 fired transitions, 525 secs || 769176 markings, 789264 edges, 1504 markings/sec, 525 secs
lola: STP completed || 1 tries, 994684 fired transitions, 530 secs || 776672 markings, 796978 edges, 1499 markings/sec, 530 secs
lola: STP completed || 2 tries, 1004410 fired transitions, 535 secs || 784157 markings, 804672 edges, 1497 markings/sec, 535 secs
lola: STP completed || 2 tries, 1014192 fired transitions, 540 secs || 791620 markings, 812412 edges, 1493 markings/sec, 540 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 1494624783106

--------------------
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-PT-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-PT-40.tgz
mv DatabaseWithMutex-PT-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-PT-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-149436309000197"
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 ;