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

--------------------
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-20-ReachabilityDeadlock-0

=== Now, execution of the tool begins

BK_START 1496447838091


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


checking for too many tokens
===========================================================================================
S_DatabaseWithMutex-PT-20: translating PT formula ReachabilityDeadlock into LoLA format
===========================================================================================
translating formula complete
touch formulae;
----- Start make result stdout -----
ReachabilityDeadlock @ S_DatabaseWithMutex-PT-20 @ 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: 6460/65536 symbol table entries, 161 collisions
lola: preprocessing...
lola: finding significant places
lola: 3260 places, 3200 transitions, 2800 significant places
lola: computing forward-conflicting sets
lola: computing back-conflicting sets
lola: 4080 transition conflict sets
lola: TASK
lola: reading formula from DatabaseWithMutex-COL-20-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 11200 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 77462 variables and 374864 clauses shipped to Minisat
lola: computed 1201 generators (57 in search tree, 1144 by composition)
lola: representing 5.91901E+36 symmetries
lola: 0 dead branches visited in search tree
lola: stp: The siphon/trap property is inconclusive since the generated formula is too short
lola: STP completed || 1 tries, 59210 fired transitions, 0 secs || 28054 markings, 28622 edges, 5611 markings/sec, 0 secs
lola: STP completed || 1 tries, 116954 fired transitions, 5 secs || 64179 markings, 65662 edges, 7225 markings/sec, 5 secs
lola: STP completed || 1 tries, 174754 fired transitions, 10 secs || 100226 markings, 102581 edges, 7209 markings/sec, 10 secs
lola: STP completed || 1 tries, 232467 fired transitions, 15 secs || 136079 markings, 139316 edges, 7171 markings/sec, 15 secs
lola: STP completed || 1 tries, 290077 fired transitions, 20 secs || 172013 markings, 176296 edges, 7187 markings/sec, 20 secs
lola: STP completed || 1 tries, 348276 fired transitions, 25 secs || 208354 markings, 213718 edges, 7268 markings/sec, 25 secs
lola: STP completed || 1 tries, 405981 fired transitions, 30 secs || 244392 markings, 250541 edges, 7208 markings/sec, 30 secs
lola: STP completed || 1 tries, 463795 fired transitions, 35 secs || 280201 markings, 286736 edges, 7162 markings/sec, 35 secs
lola: STP completed || 1 tries, 521328 fired transitions, 40 secs || 316239 markings, 323447 edges, 7208 markings/sec, 40 secs
lola: STP completed || 1 tries, 579093 fired transitions, 45 secs || 352402 markings, 360452 edges, 7233 markings/sec, 45 secs
lola: STP completed || 1 tries, 637157 fired transitions, 50 secs || 388576 markings, 397435 edges, 7235 markings/sec, 50 secs
lola: STP completed || 1 tries, 695609 fired transitions, 55 secs || 424561 markings, 434002 edges, 7197 markings/sec, 55 secs
lola: STP completed || 1 tries, 753614 fired transitions, 60 secs || 460564 markings, 470810 edges, 7201 markings/sec, 60 secs
lola: STP completed || 1 tries, 811256 fired transitions, 65 secs || 496184 markings, 506988 edges, 7124 markings/sec, 65 secs
lola: STP completed || 1 tries, 869243 fired transitions, 70 secs || 532171 markings, 543981 edges, 7197 markings/sec, 70 secs
lola: STP completed || 1 tries, 927413 fired transitions, 75 secs || 568261 markings, 581158 edges, 7218 markings/sec, 75 secs
lola: STP completed || 1 tries, 985272 fired transitions, 80 secs || 604235 markings, 617934 edges, 7195 markings/sec, 80 secs
lola: STP completed || 2 tries, 1043196 fired transitions, 85 secs || 640342 markings, 654917 edges, 7221 markings/sec, 85 secs
lola: STP completed || 2 tries, 1101035 fired transitions, 90 secs || 676306 markings, 691931 edges, 7193 markings/sec, 90 secs
lola: STP completed || 2 tries, 1159144 fired transitions, 95 secs || 712466 markings, 729211 edges, 7232 markings/sec, 95 secs
lola: STP completed || 2 tries, 1216383 fired transitions, 100 secs || 748075 markings, 765956 edges, 7122 markings/sec, 100 secs
lola: STP completed || 2 tries, 1274438 fired transitions, 105 secs || 783846 markings, 802880 edges, 7154 markings/sec, 105 secs
lola: STP completed || 2 tries, 1332289 fired transitions, 110 secs || 818987 markings, 838369 edges, 7028 markings/sec, 110 secs
lola: STP completed || 2 tries, 1390521 fired transitions, 115 secs || 854607 markings, 875318 edges, 7124 markings/sec, 115 secs
lola: STP completed || 2 tries, 1449146 fired transitions, 120 secs || 890360 markings, 912469 edges, 7151 markings/sec, 120 secs
lola: STP completed || 2 tries, 1507061 fired transitions, 125 secs || 925966 markings, 948987 edges, 7121 markings/sec, 125 secs
lola: STP completed || 2 tries, 1564907 fired transitions, 130 secs || 961360 markings, 985405 edges, 7079 markings/sec, 130 secs
lola: STP completed || 2 tries, 1622312 fired transitions, 135 secs || 996410 markings, 1021412 edges, 7010 markings/sec, 135 secs
lola: STP completed || 2 tries, 1679682 fired transitions, 140 secs || 1031401 markings, 1057417 edges, 6998 markings/sec, 140 secs
lola: STP completed || 2 tries, 1736707 fired transitions, 145 secs || 1066398 markings, 1093254 edges, 6999 markings/sec, 145 secs
lola: STP completed || 2 tries, 1793869 fired transitions, 150 secs || 1101434 markings, 1129411 edges, 7007 markings/sec, 150 secs
lola: STP completed || 2 tries, 1852009 fired transitions, 155 secs || 1136904 markings, 1165967 edges, 7094 markings/sec, 155 secs
lola: STP completed || 2 tries, 1910238 fired transitions, 160 secs || 1172899 markings, 1203354 edges, 7199 markings/sec, 160 secs
lola: STP completed || 2 tries, 1968217 fired transitions, 165 secs || 1208829 markings, 1240630 edges, 7186 markings/sec, 165 secs
lola: STP completed || 3 tries, 2026286 fired transitions, 170 secs || 1244769 markings, 1277641 edges, 7188 markings/sec, 170 secs
lola: STP completed || 3 tries, 2084517 fired transitions, 175 secs || 1280673 markings, 1314693 edges, 7181 markings/sec, 175 secs
lola: STP completed || 3 tries, 2142638 fired transitions, 180 secs || 1316845 markings, 1352574 edges, 7234 markings/sec, 180 secs
lola: STP completed || 3 tries, 2200521 fired transitions, 185 secs || 1352556 markings, 1389374 edges, 7142 markings/sec, 185 secs
lola: STP completed || 3 tries, 2257927 fired transitions, 190 secs || 1388223 markings, 1426340 edges, 7133 markings/sec, 190 secs
lola: STP completed || 3 tries, 2315435 fired transitions, 195 secs || 1423952 markings, 1463506 edges, 7146 markings/sec, 195 secs
lola: STP completed || 3 tries, 2373164 fired transitions, 200 secs || 1459702 markings, 1500747 edges, 7150 markings/sec, 200 secs
lola: STP completed || 3 tries, 2430713 fired transitions, 205 secs || 1495512 markings, 1537979 edges, 7162 markings/sec, 205 secs
lola: STP completed || 3 tries, 2488566 fired transitions, 210 secs || 1531571 markings, 1575503 edges, 7212 markings/sec, 210 secs
lola: STP completed || 3 tries, 2546377 fired transitions, 215 secs || 1567392 markings, 1612136 edges, 7164 markings/sec, 215 secs
lola: STP completed || 3 tries, 2604654 fired transitions, 220 secs || 1603387 markings, 1649456 edges, 7199 markings/sec, 220 secs
lola: STP completed || 3 tries, 2662007 fired transitions, 225 secs || 1638825 markings, 1685365 edges, 7088 markings/sec, 225 secs
lola: STP completed || 3 tries, 2720105 fired transitions, 230 secs || 1674697 markings, 1722662 edges, 7174 markings/sec, 230 secs
lola: STP completed || 3 tries, 2778227 fired transitions, 235 secs || 1710361 markings, 1759289 edges, 7133 markings/sec, 235 secs
lola: STP completed || 3 tries, 2836260 fired transitions, 240 secs || 1746166 markings, 1796206 edges, 7161 markings/sec, 240 secs
lola: STP completed || 3 tries, 2894780 fired transitions, 245 secs || 1782181 markings, 1833301 edges, 7203 markings/sec, 245 secs
lola: STP completed || 3 tries, 2953168 fired transitions, 250 secs || 1818213 markings, 1870699 edges, 7206 markings/sec, 250 secs
lola: STP completed || 4 tries, 3011267 fired transitions, 255 secs || 1854133 markings, 1908050 edges, 7184 markings/sec, 255 secs
lola: STP completed || 4 tries, 3068921 fired transitions, 260 secs || 1889478 markings, 1944061 edges, 7069 markings/sec, 260 secs
lola: STP completed || 4 tries, 3126375 fired transitions, 265 secs || 1924851 markings, 1980427 edges, 7075 markings/sec, 265 secs
lola: STP completed || 4 tries, 3183492 fired transitions, 270 secs || 1959851 markings, 2016236 edges, 7000 markings/sec, 270 secs
lola: STP completed || 4 tries, 3240858 fired transitions, 275 secs || 1995083 markings, 2052663 edges, 7046 markings/sec, 275 secs
lola: STP completed || 4 tries, 3298250 fired transitions, 280 secs || 2030500 markings, 2089387 edges, 7083 markings/sec, 280 secs
lola: STP completed || 4 tries, 3355430 fired transitions, 285 secs || 2065746 markings, 2125750 edges, 7049 markings/sec, 285 secs
lola: STP completed || 4 tries, 3412796 fired transitions, 290 secs || 2101055 markings, 2162136 edges, 7062 markings/sec, 290 secs
lola: STP completed || 4 tries, 3470047 fired transitions, 295 secs || 2136196 markings, 2198042 edges, 7028 markings/sec, 295 secs
lola: STP completed || 4 tries, 3526750 fired transitions, 300 secs || 2171628 markings, 2234523 edges, 7086 markings/sec, 300 secs
lola: STP completed || 4 tries, 3584296 fired transitions, 305 secs || 2206953 markings, 2270850 edges, 7065 markings/sec, 305 secs
lola: STP completed || 4 tries, 3641536 fired transitions, 310 secs || 2242542 markings, 2307993 edges, 7118 markings/sec, 310 secs
lola: STP completed || 4 tries, 3699218 fired transitions, 315 secs || 2277616 markings, 2344123 edges, 7015 markings/sec, 315 secs
lola: STP completed || 4 tries, 3756631 fired transitions, 320 secs || 2313061 markings, 2381090 edges, 7089 markings/sec, 320 secs
lola: STP completed || 4 tries, 3814005 fired transitions, 325 secs || 2348501 markings, 2417894 edges, 7088 markings/sec, 325 secs
lola: STP completed || 4 tries, 3871267 fired transitions, 330 secs || 2383421 markings, 2453572 edges, 6984 markings/sec, 330 secs
lola: STP completed || 4 tries, 3928422 fired transitions, 335 secs || 2418410 markings, 2489294 edges, 6998 markings/sec, 335 secs
lola: STP completed || 4 tries, 3985514 fired transitions, 340 secs || 2453609 markings, 2525569 edges, 7040 markings/sec, 340 secs
lola: STP completed || 5 tries, 4042873 fired transitions, 345 secs || 2488975 markings, 2562375 edges, 7073 markings/sec, 345 secs
lola: STP completed || 5 tries, 4100154 fired transitions, 350 secs || 2524134 markings, 2598931 edges, 7032 markings/sec, 350 secs
lola: STP completed || 5 tries, 4157300 fired transitions, 355 secs || 2559352 markings, 2635192 edges, 7044 markings/sec, 355 secs
lola: STP completed || 5 tries, 4214718 fired transitions, 360 secs || 2594224 markings, 2670748 edges, 6974 markings/sec, 360 secs
lola: STP completed || 5 tries, 4271881 fired transitions, 365 secs || 2629308 markings, 2706697 edges, 7017 markings/sec, 365 secs
lola: STP completed || 5 tries, 4329615 fired transitions, 370 secs || 2664714 markings, 2743697 edges, 7081 markings/sec, 370 secs
lola: STP completed || 5 tries, 4386878 fired transitions, 375 secs || 2699901 markings, 2780291 edges, 7037 markings/sec, 375 secs
lola: STP completed || 5 tries, 4444083 fired transitions, 380 secs || 2735028 markings, 2816614 edges, 7025 markings/sec, 380 secs
lola: STP completed || 5 tries, 4502104 fired transitions, 385 secs || 2770535 markings, 2853559 edges, 7101 markings/sec, 385 secs
lola: STP completed || 5 tries, 4560385 fired transitions, 390 secs || 2806213 markings, 2890533 edges, 7136 markings/sec, 390 secs
lola: STP completed || 5 tries, 4618717 fired transitions, 395 secs || 2841767 markings, 2927206 edges, 7111 markings/sec, 395 secs
lola: STP completed || 5 tries, 4677029 fired transitions, 400 secs || 2877480 markings, 2964192 edges, 7143 markings/sec, 400 secs
lola: STP completed || 5 tries, 4734704 fired transitions, 405 secs || 2913046 markings, 3000587 edges, 7113 markings/sec, 405 secs
lola: STP completed || 5 tries, 4792319 fired transitions, 410 secs || 2948478 markings, 3036822 edges, 7086 markings/sec, 410 secs
lola: STP completed || 5 tries, 4850470 fired transitions, 415 secs || 2983912 markings, 3073476 edges, 7087 markings/sec, 415 secs
lola: STP completed || 5 tries, 4908865 fired transitions, 420 secs || 3019731 markings, 3111000 edges, 7164 markings/sec, 420 secs
lola: STP completed || 5 tries, 4967310 fired transitions, 425 secs || 3055632 markings, 3148416 edges, 7180 markings/sec, 425 secs
lola: STP completed || 6 tries, 5025301 fired transitions, 430 secs || 3091119 markings, 3185282 edges, 7097 markings/sec, 430 secs
lola: STP completed || 6 tries, 5082579 fired transitions, 435 secs || 3126434 markings, 3221375 edges, 7063 markings/sec, 435 secs
lola: STP completed || 6 tries, 5140593 fired transitions, 440 secs || 3162036 markings, 3258078 edges, 7120 markings/sec, 440 secs
lola: STP completed || 6 tries, 5198725 fired transitions, 445 secs || 3197488 markings, 3294095 edges, 7090 markings/sec, 445 secs
lola: STP completed || 6 tries, 5256735 fired transitions, 450 secs || 3232662 markings, 3329824 edges, 7035 markings/sec, 450 secs
lola: STP completed || 6 tries, 5314933 fired transitions, 455 secs || 3268662 markings, 3367422 edges, 7200 markings/sec, 455 secs
lola: STP completed || 6 tries, 5372886 fired transitions, 460 secs || 3304532 markings, 3404376 edges, 7174 markings/sec, 460 secs
lola: STP completed || 6 tries, 5430994 fired transitions, 465 secs || 3340144 markings, 3441010 edges, 7122 markings/sec, 465 secs
lola: STP completed || 6 tries, 5488864 fired transitions, 470 secs || 3375916 markings, 3477911 edges, 7154 markings/sec, 470 secs
lola: STP completed || 6 tries, 5546766 fired transitions, 475 secs || 3411752 markings, 3514522 edges, 7167 markings/sec, 475 secs
lola: STP completed || 6 tries, 5604762 fired transitions, 480 secs || 3447487 markings, 3551598 edges, 7147 markings/sec, 480 secs
lola: STP completed || 6 tries, 5663259 fired transitions, 485 secs || 3483254 markings, 3588710 edges, 7153 markings/sec, 485 secs
lola: STP completed || 6 tries, 5721437 fired transitions, 490 secs || 3518908 markings, 3625411 edges, 7131 markings/sec, 490 secs
lola: STP completed || 6 tries, 5779871 fired transitions, 495 secs || 3555091 markings, 3663161 edges, 7237 markings/sec, 495 secs
lola: STP completed || 6 tries, 5837636 fired transitions, 500 secs || 3590287 markings, 3698976 edges, 7039 markings/sec, 500 secs
lola: STP completed || 6 tries, 5895507 fired transitions, 505 secs || 3625726 markings, 3735766 edges, 7088 markings/sec, 505 secs
lola: STP completed || 6 tries, 5953267 fired transitions, 510 secs || 3660939 markings, 3771813 edges, 7043 markings/sec, 510 secs
lola: STP completed || 7 tries, 6011343 fired transitions, 515 secs || 3696195 markings, 3808114 edges, 7051 markings/sec, 515 secs
lola: STP completed || 7 tries, 6068949 fired transitions, 520 secs || 3731072 markings, 3843514 edges, 6975 markings/sec, 520 secs
lola: STP completed || 7 tries, 6127033 fired transitions, 525 secs || 3766690 markings, 3880767 edges, 7124 markings/sec, 525 secs
lola: STP completed || 7 tries, 6184469 fired transitions, 530 secs || 3801542 markings, 3916447 edges, 6970 markings/sec, 530 secs
lola: STP completed || 7 tries, 6241622 fired transitions, 535 secs || 3836506 markings, 3952163 edges, 6993 markings/sec, 535 secs
lola: STP completed || 7 tries, 6299817 fired transitions, 540 secs || 3871985 markings, 3989105 edges, 7096 markings/sec, 540 secs
lola: STP completed || 7 tries, 6357643 fired transitions, 545 secs || 3907297 markings, 4025559 edges, 7062 markings/sec, 545 secs
lola: STP completed || 7 tries, 6415711 fired transitions, 550 secs || 3942778 markings, 4062656 edges, 7096 markings/sec, 550 secs
lola: STP completed || 7 tries, 6473716 fired transitions, 555 secs || 3978351 markings, 4099449 edges, 7115 markings/sec, 555 secs
lola: STP completed || 7 tries, 6532165 fired transitions, 560 secs || 4014410 markings, 4137273 edges, 7212 markings/sec, 560 secs
lola: STP completed || 7 tries, 6590580 fired transitions, 565 secs || 4050390 markings, 4174981 edges, 7196 markings/sec, 565 secs
lola: STP completed || 7 tries, 6648867 fired transitions, 570 secs || 4086128 markings, 4212248 edges, 7148 markings/sec, 570 secs
lola: STP completed || 7 tries, 6706055 fired transitions, 575 secs || 4121541 markings, 4249043 edges, 7083 markings/sec, 575 secs
lola: STP completed || 7 tries, 6763747 fired transitions, 580 secs || 4156785 markings, 4284989 edges, 7049 markings/sec, 580 secs
Killed
FORMULA DatabaseWithMutex-COL-20-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 1496448429067

--------------------
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-20"
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-20.tgz
mv S_DatabaseWithMutex-PT-20 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-20, 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-149488455600188"
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 ;