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

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


Time: 3600 - MCC
----- Start make prepare stdout -----
===========================================================================================
S_DatabaseWithMutex-PT-40: translating PT Petri net model.pnml into LoLA format
===========================================================================================
translating PT Petri net complete


checking for too many tokens
===========================================================================================
S_DatabaseWithMutex-PT-40: translating PT formula ReachabilityDeadlock into LoLA format
===========================================================================================
translating formula complete
touch formulae;
----- Start make result stdout -----
ReachabilityDeadlock @ S_DatabaseWithMutex-PT-40 @ 3539 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, 10001 fired transitions, 0 secs
lola: 3227 generators;83353515 candidates remain ( 0%), 0 secs
lola: stp: The siphon/trap property is inconclusive since the generated formula is too short
lola: STP completed || 1 tries, 18863 fired transitions, 5 secs
lola: 3478 generators;83250224 candidates remain ( 0%), 5 secs
lola: STP completed || 1 tries, 28097 fired transitions, 10 secs
lola: 4544 generators;82915390 candidates remain ( 0%), 10 secs
lola: STP completed || 1 tries, 37723 fired transitions, 15 secs
lola: 5379 generators;77271135 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, 46738 fired transitions, 20 secs || 5375 markings, 5381 edges, 1075 markings/sec, 20 secs
lola: STP completed || 1 tries, 55906 fired transitions, 25 secs || 12752 markings, 12798 edges, 1475 markings/sec, 25 secs
lola: STP completed || 1 tries, 65640 fired transitions, 30 secs || 20403 markings, 20603 edges, 1530 markings/sec, 30 secs
lola: STP completed || 1 tries, 74798 fired transitions, 35 secs || 27975 markings, 28303 edges, 1514 markings/sec, 35 secs
lola: STP completed || 1 tries, 84412 fired transitions, 40 secs || 35609 markings, 36062 edges, 1527 markings/sec, 40 secs
lola: STP completed || 1 tries, 93756 fired transitions, 45 secs || 43181 markings, 43726 edges, 1514 markings/sec, 45 secs
lola: STP completed || 1 tries, 102938 fired transitions, 50 secs || 50707 markings, 51323 edges, 1505 markings/sec, 50 secs
lola: STP completed || 1 tries, 112515 fired transitions, 55 secs || 58284 markings, 59005 edges, 1515 markings/sec, 55 secs
lola: STP completed || 1 tries, 121838 fired transitions, 60 secs || 65944 markings, 66872 edges, 1532 markings/sec, 60 secs
lola: STP completed || 1 tries, 131092 fired transitions, 65 secs || 73462 markings, 74452 edges, 1504 markings/sec, 65 secs
lola: STP completed || 1 tries, 140298 fired transitions, 70 secs || 81006 markings, 82066 edges, 1509 markings/sec, 70 secs
lola: STP completed || 1 tries, 149628 fired transitions, 75 secs || 88619 markings, 89809 edges, 1523 markings/sec, 75 secs
lola: STP completed || 1 tries, 158799 fired transitions, 80 secs || 96134 markings, 97414 edges, 1503 markings/sec, 80 secs
lola: STP completed || 1 tries, 167985 fired transitions, 85 secs || 103650 markings, 105014 edges, 1503 markings/sec, 85 secs
lola: STP completed || 1 tries, 177055 fired transitions, 90 secs || 111022 markings, 112451 edges, 1474 markings/sec, 90 secs
lola: STP completed || 1 tries, 186297 fired transitions, 95 secs || 118539 markings, 120073 edges, 1503 markings/sec, 95 secs
lola: STP completed || 1 tries, 195695 fired transitions, 100 secs || 126129 markings, 127847 edges, 1518 markings/sec, 100 secs
lola: STP completed || 1 tries, 205045 fired transitions, 105 secs || 133728 markings, 135662 edges, 1520 markings/sec, 105 secs
lola: STP completed || 1 tries, 214340 fired transitions, 110 secs || 141252 markings, 143298 edges, 1505 markings/sec, 110 secs
lola: STP completed || 1 tries, 223559 fired transitions, 115 secs || 148875 markings, 151062 edges, 1525 markings/sec, 115 secs
lola: STP completed || 1 tries, 232755 fired transitions, 120 secs || 156550 markings, 159010 edges, 1535 markings/sec, 120 secs
lola: STP completed || 1 tries, 242070 fired transitions, 125 secs || 164150 markings, 166759 edges, 1520 markings/sec, 125 secs
lola: STP completed || 1 tries, 251484 fired transitions, 130 secs || 171728 markings, 174454 edges, 1516 markings/sec, 130 secs
lola: STP completed || 1 tries, 260586 fired transitions, 135 secs || 179337 markings, 182212 edges, 1522 markings/sec, 135 secs
lola: STP completed || 1 tries, 269902 fired transitions, 140 secs || 186954 markings, 189982 edges, 1523 markings/sec, 140 secs
lola: STP completed || 1 tries, 279305 fired transitions, 145 secs || 194455 markings, 197555 edges, 1500 markings/sec, 145 secs
lola: STP completed || 1 tries, 288542 fired transitions, 150 secs || 201974 markings, 205187 edges, 1504 markings/sec, 150 secs
lola: STP completed || 1 tries, 297879 fired transitions, 155 secs || 209548 markings, 212882 edges, 1515 markings/sec, 155 secs
lola: STP completed || 1 tries, 307456 fired transitions, 160 secs || 217177 markings, 220682 edges, 1526 markings/sec, 160 secs
lola: STP completed || 1 tries, 316624 fired transitions, 165 secs || 224821 markings, 228514 edges, 1529 markings/sec, 165 secs
lola: STP completed || 1 tries, 326217 fired transitions, 170 secs || 232465 markings, 236372 edges, 1529 markings/sec, 170 secs
lola: STP completed || 1 tries, 335529 fired transitions, 175 secs || 240056 markings, 244094 edges, 1518 markings/sec, 175 secs
lola: STP completed || 1 tries, 345228 fired transitions, 180 secs || 247672 markings, 251897 edges, 1523 markings/sec, 180 secs
lola: STP completed || 1 tries, 354391 fired transitions, 185 secs || 255287 markings, 259679 edges, 1523 markings/sec, 185 secs
lola: STP completed || 1 tries, 363630 fired transitions, 190 secs || 262871 markings, 267405 edges, 1517 markings/sec, 190 secs
lola: STP completed || 1 tries, 373094 fired transitions, 195 secs || 270537 markings, 275277 edges, 1533 markings/sec, 195 secs
lola: STP completed || 1 tries, 382217 fired transitions, 200 secs || 278301 markings, 283339 edges, 1553 markings/sec, 200 secs
lola: STP completed || 1 tries, 391595 fired transitions, 205 secs || 285945 markings, 291142 edges, 1529 markings/sec, 205 secs
lola: STP completed || 1 tries, 400897 fired transitions, 210 secs || 293566 markings, 298921 edges, 1524 markings/sec, 210 secs
lola: STP completed || 1 tries, 410397 fired transitions, 215 secs || 301236 markings, 306840 edges, 1534 markings/sec, 215 secs
lola: STP completed || 1 tries, 419732 fired transitions, 220 secs || 308868 markings, 314666 edges, 1526 markings/sec, 220 secs
lola: STP completed || 1 tries, 428972 fired transitions, 225 secs || 316405 markings, 322328 edges, 1507 markings/sec, 225 secs
lola: STP completed || 1 tries, 438225 fired transitions, 230 secs || 323958 markings, 330024 edges, 1511 markings/sec, 230 secs
lola: STP completed || 1 tries, 447644 fired transitions, 235 secs || 331494 markings, 337677 edges, 1507 markings/sec, 235 secs
lola: STP completed || 1 tries, 456936 fired transitions, 240 secs || 339091 markings, 345472 edges, 1519 markings/sec, 240 secs
lola: STP completed || 1 tries, 466365 fired transitions, 245 secs || 346624 markings, 353132 edges, 1507 markings/sec, 245 secs
lola: STP completed || 1 tries, 475653 fired transitions, 250 secs || 354225 markings, 360904 edges, 1520 markings/sec, 250 secs
lola: STP completed || 1 tries, 484786 fired transitions, 255 secs || 361812 markings, 368668 edges, 1517 markings/sec, 255 secs
lola: STP completed || 1 tries, 494404 fired transitions, 260 secs || 369347 markings, 376387 edges, 1507 markings/sec, 260 secs
lola: STP completed || 1 tries, 503567 fired transitions, 265 secs || 376787 markings, 383901 edges, 1488 markings/sec, 265 secs
lola: STP completed || 1 tries, 512705 fired transitions, 270 secs || 384299 markings, 391556 edges, 1502 markings/sec, 270 secs
lola: STP completed || 1 tries, 521736 fired transitions, 275 secs || 391888 markings, 399421 edges, 1518 markings/sec, 275 secs
lola: STP completed || 1 tries, 531038 fired transitions, 280 secs || 399421 markings, 407098 edges, 1507 markings/sec, 280 secs
lola: STP completed || 1 tries, 540300 fired transitions, 285 secs || 407051 markings, 414965 edges, 1526 markings/sec, 285 secs
lola: STP completed || 1 tries, 549478 fired transitions, 290 secs || 414615 markings, 422651 edges, 1513 markings/sec, 290 secs
lola: STP completed || 1 tries, 558902 fired transitions, 295 secs || 422262 markings, 430559 edges, 1529 markings/sec, 295 secs
lola: STP completed || 1 tries, 567965 fired transitions, 300 secs || 429871 markings, 438325 edges, 1522 markings/sec, 300 secs
lola: STP completed || 1 tries, 577591 fired transitions, 305 secs || 437611 markings, 446427 edges, 1548 markings/sec, 305 secs
lola: STP completed || 1 tries, 586806 fired transitions, 310 secs || 445225 markings, 454325 edges, 1523 markings/sec, 310 secs
lola: STP completed || 1 tries, 596092 fired transitions, 315 secs || 452908 markings, 462192 edges, 1537 markings/sec, 315 secs
lola: STP completed || 1 tries, 605235 fired transitions, 320 secs || 460552 markings, 470085 edges, 1529 markings/sec, 320 secs
lola: STP completed || 1 tries, 614545 fired transitions, 325 secs || 468110 markings, 477836 edges, 1512 markings/sec, 325 secs
lola: STP completed || 1 tries, 623971 fired transitions, 330 secs || 475657 markings, 485589 edges, 1509 markings/sec, 330 secs
lola: STP completed || 1 tries, 633214 fired transitions, 335 secs || 483213 markings, 493290 edges, 1511 markings/sec, 335 secs
lola: STP completed || 1 tries, 642522 fired transitions, 340 secs || 490921 markings, 501389 edges, 1542 markings/sec, 340 secs
lola: STP completed || 1 tries, 652017 fired transitions, 345 secs || 498454 markings, 509105 edges, 1507 markings/sec, 345 secs
lola: STP completed || 1 tries, 661623 fired transitions, 350 secs || 506059 markings, 516933 edges, 1521 markings/sec, 350 secs
lola: STP completed || 1 tries, 670761 fired transitions, 355 secs || 513690 markings, 524766 edges, 1526 markings/sec, 355 secs
lola: STP completed || 1 tries, 680360 fired transitions, 360 secs || 521349 markings, 532668 edges, 1532 markings/sec, 360 secs
lola: STP completed || 1 tries, 689745 fired transitions, 365 secs || 528940 markings, 540421 edges, 1518 markings/sec, 365 secs
lola: STP completed || 1 tries, 699267 fired transitions, 370 secs || 536600 markings, 548270 edges, 1532 markings/sec, 370 secs
lola: STP completed || 1 tries, 708638 fired transitions, 375 secs || 544220 markings, 556051 edges, 1524 markings/sec, 375 secs
lola: STP completed || 1 tries, 718217 fired transitions, 380 secs || 551926 markings, 564064 edges, 1541 markings/sec, 380 secs
lola: STP completed || 1 tries, 727539 fired transitions, 385 secs || 559555 markings, 571927 edges, 1526 markings/sec, 385 secs
lola: STP completed || 1 tries, 737078 fired transitions, 390 secs || 567136 markings, 579732 edges, 1516 markings/sec, 390 secs
lola: STP completed || 1 tries, 746146 fired transitions, 395 secs || 574680 markings, 587452 edges, 1509 markings/sec, 395 secs
lola: STP completed || 1 tries, 755361 fired transitions, 400 secs || 582214 markings, 595155 edges, 1507 markings/sec, 400 secs
lola: STP completed || 1 tries, 764515 fired transitions, 405 secs || 589736 markings, 602837 edges, 1504 markings/sec, 405 secs
lola: STP completed || 1 tries, 773899 fired transitions, 410 secs || 597240 markings, 610480 edges, 1501 markings/sec, 410 secs
lola: STP completed || 1 tries, 783378 fired transitions, 415 secs || 604833 markings, 618274 edges, 1519 markings/sec, 415 secs
lola: STP completed || 1 tries, 792677 fired transitions, 420 secs || 612333 markings, 625890 edges, 1500 markings/sec, 420 secs
lola: STP completed || 1 tries, 801916 fired transitions, 425 secs || 619816 markings, 633505 edges, 1497 markings/sec, 425 secs
lola: STP completed || 1 tries, 811076 fired transitions, 430 secs || 627438 markings, 641411 edges, 1524 markings/sec, 430 secs
lola: STP completed || 1 tries, 820283 fired transitions, 435 secs || 634960 markings, 649096 edges, 1504 markings/sec, 435 secs
lola: STP completed || 1 tries, 829859 fired transitions, 440 secs || 642552 markings, 656947 edges, 1518 markings/sec, 440 secs
lola: STP completed || 1 tries, 839017 fired transitions, 445 secs || 650155 markings, 664766 edges, 1521 markings/sec, 445 secs
lola: STP completed || 1 tries, 848187 fired transitions, 450 secs || 657739 markings, 672588 edges, 1517 markings/sec, 450 secs
lola: STP completed || 1 tries, 857233 fired transitions, 455 secs || 665434 markings, 680660 edges, 1539 markings/sec, 455 secs
lola: STP completed || 1 tries, 866638 fired transitions, 460 secs || 673056 markings, 688578 edges, 1524 markings/sec, 460 secs
lola: STP completed || 1 tries, 875915 fired transitions, 465 secs || 680556 markings, 696285 edges, 1500 markings/sec, 465 secs
lola: STP completed || 1 tries, 885314 fired transitions, 470 secs || 688115 markings, 704059 edges, 1512 markings/sec, 470 secs
lola: STP completed || 1 tries, 894281 fired transitions, 475 secs || 695629 markings, 711755 edges, 1503 markings/sec, 475 secs
lola: STP completed || 1 tries, 903466 fired transitions, 480 secs || 703090 markings, 719433 edges, 1492 markings/sec, 480 secs
lola: STP completed || 1 tries, 912795 fired transitions, 485 secs || 710644 markings, 727175 edges, 1511 markings/sec, 485 secs
lola: STP completed || 1 tries, 922304 fired transitions, 490 secs || 718112 markings, 734785 edges, 1494 markings/sec, 490 secs
lola: STP completed || 1 tries, 931574 fired transitions, 495 secs || 725829 markings, 742812 edges, 1543 markings/sec, 495 secs
lola: STP completed || 1 tries, 940748 fired transitions, 500 secs || 733447 markings, 750709 edges, 1524 markings/sec, 500 secs
lola: STP completed || 1 tries, 950096 fired transitions, 505 secs || 740960 markings, 758496 edges, 1503 markings/sec, 505 secs
lola: STP completed || 1 tries, 958684 fired transitions, 510 secs || 748316 markings, 766078 edges, 1471 markings/sec, 510 secs
lola: STP completed || 1 tries, 967997 fired transitions, 515 secs || 755608 markings, 773570 edges, 1458 markings/sec, 515 secs
lola: STP completed || 1 tries, 977198 fired transitions, 520 secs || 763161 markings, 781370 edges, 1511 markings/sec, 520 secs
lola: STP completed || 1 tries, 986608 fired transitions, 525 secs || 770717 markings, 789218 edges, 1511 markings/sec, 525 secs
lola: STP completed || 1 tries, 996176 fired transitions, 530 secs || 778346 markings, 797157 edges, 1526 markings/sec, 530 secs
lola: STP completed || 2 tries, 1006076 fired transitions, 535 secs || 786068 markings, 805311 edges, 1544 markings/sec, 535 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 1496449016463

--------------------
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="S_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/S_DatabaseWithMutex-PT-40.tgz
mv S_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 S_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 r098-ebro-149488455600197"
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 ;