About the Execution of Marcie for S_DrinkVendingMachine-PT-10
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-2979
Executing tool marcie
Input is S_DrinkVendingMachine-PT-10, examination is UpperBounds
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 1
Run identifier is r149kn-smll-146416258900425
=====================================================================
--------------------
content from stdout:
=== Data for post analysis generated by BenchKit (invocation template)
The expected result is a vector of positive values
NUM_VECTOR
here is the order used to build the result vector(from text file)
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-0
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-1
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-10
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-11
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-12
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-13
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-14
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-15
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-2
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-3
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-4
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-5
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-6
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-7
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-8
FORMULA_NAME DrinkVendingMachine-COL-10-UpperBounds-9
=== Now, execution of the tool begins
BK_START 1464349970334
Marcie rev. 8535M (built: crohr on 2016-04-27)
A model checker for Generalized Stochastic Petri nets
authors: Alex Tovchigrechko (IDD package and CTL model checking)
Martin Schwarick (Symbolic numerical analysis and CSL model checking)
Christian Rohr (Simulative and approximative numerical model checking)
marcie@informatik.tu-cottbus.de
called as: marcie --net-file=model.pnml --mcc-file=UpperBounds.xml --mcc-mode --memory=6 --suppress
parse successfull
net created successfully
Net: DrinkVendingMachine_PT_10
(NrP: 120 NrTr: 111160 NrArc: 1026520)
net check time: 0m 0.159sec
parse formulas
formulas created successfully
place and transition orderings generation:0m21.492sec
init dd package: 0m 3.612sec
RS generation: 0m 7.266sec
-> reachability set: #nodes 180 (1.8e+02) #states 1,152,921,504,606,846,976 (18)
starting MCC model checker
--------------------------
checking: place_bound(productSlots_1,productSlots_3,productSlots_2,productSlots_5,productSlots_4,productSlots_7,productSlots_6,productSlots_9,productSlots_8,productSlots_10)
normalized: place_bound(productSlots_1,productSlots_3,productSlots_2,productSlots_5,productSlots_4,productSlots_7,productSlots_6,productSlots_9,productSlots_8,productSlots_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-0 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
normalized: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-1 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
normalized: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-2 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theProducts_2,theProducts_3,theProducts_1,theProducts_6,theProducts_7,theProducts_4,theProducts_5,theProducts_10,theProducts_8,theProducts_9)
normalized: place_bound(theProducts_2,theProducts_3,theProducts_1,theProducts_6,theProducts_7,theProducts_4,theProducts_5,theProducts_10,theProducts_8,theProducts_9)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-3 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
normalized: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-4 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
normalized: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-5 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
normalized: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-6 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
normalized: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-7 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theProducts_2,theProducts_3,theProducts_1,theProducts_6,theProducts_7,theProducts_4,theProducts_5,theProducts_10,theProducts_8,theProducts_9)
normalized: place_bound(theProducts_2,theProducts_3,theProducts_1,theProducts_6,theProducts_7,theProducts_4,theProducts_5,theProducts_10,theProducts_8,theProducts_9)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-9 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
normalized: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-11 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
normalized: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-12 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
normalized: place_bound(theOptions_1,theOptions_2,theOptions_3,theOptions_5,theOptions_4,theOptions_7,theOptions_6,theOptions_9,theOptions_8,theOptions_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-13 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
normalized: place_bound(optionSlots_1,optionSlots_2,optionSlots_3,optionSlots_4,optionSlots_5,optionSlots_6,optionSlots_7,optionSlots_8,optionSlots_9,optionSlots_10)
-> the formula is 10
FORMULA DrinkVendingMachine-COL-10-UpperBounds-14 10 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(ready_3,ready_2,ready_5,ready_4,ready_1,ready_11,ready_10,ready_13,ready_12,ready_7,ready_6,ready_9,ready_8,ready_20,ready_21,ready_18,ready_19,ready_16,ready_17,ready_14,ready_15,ready_28,ready_29,ready_26,ready_27,ready_24,ready_25,ready_22,ready_23,ready_37,ready_36,ready_35,ready_34,ready_33,ready_32,ready_31,ready_30,ready_40,ready_39,ready_38)
normalized: place_bound(ready_3,ready_2,ready_5,ready_4,ready_1,ready_11,ready_10,ready_13,ready_12,ready_7,ready_6,ready_9,ready_8,ready_20,ready_21,ready_18,ready_19,ready_16,ready_17,ready_14,ready_15,ready_28,ready_29,ready_26,ready_27,ready_24,ready_25,ready_22,ready_23,ready_37,ready_36,ready_35,ready_34,ready_33,ready_32,ready_31,ready_30,ready_40,ready_39,ready_38)
-> the formula is 40
FORMULA DrinkVendingMachine-COL-10-UpperBounds-8 40 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(ready_3,ready_2,ready_5,ready_4,ready_1,ready_11,ready_10,ready_13,ready_12,ready_7,ready_6,ready_9,ready_8,ready_20,ready_21,ready_18,ready_19,ready_16,ready_17,ready_14,ready_15,ready_28,ready_29,ready_26,ready_27,ready_24,ready_25,ready_22,ready_23,ready_37,ready_36,ready_35,ready_34,ready_33,ready_32,ready_31,ready_30,ready_40,ready_39,ready_38)
normalized: place_bound(ready_3,ready_2,ready_5,ready_4,ready_1,ready_11,ready_10,ready_13,ready_12,ready_7,ready_6,ready_9,ready_8,ready_20,ready_21,ready_18,ready_19,ready_16,ready_17,ready_14,ready_15,ready_28,ready_29,ready_26,ready_27,ready_24,ready_25,ready_22,ready_23,ready_37,ready_36,ready_35,ready_34,ready_33,ready_32,ready_31,ready_30,ready_40,ready_39,ready_38)
-> the formula is 40
FORMULA DrinkVendingMachine-COL-10-UpperBounds-10 40 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
checking: place_bound(wait_1,wait_4,wait_5,wait_2,wait_3,wait_9,wait_8,wait_7,wait_6,wait_13,wait_12,wait_11,wait_10,wait_17,wait_16,wait_15,wait_14,wait_21,wait_20,wait_19,wait_18,wait_26,wait_27,wait_28,wait_29,wait_22,wait_23,wait_24,wait_25,wait_34,wait_35,wait_36,wait_37,wait_30,wait_31,wait_32,wait_33,wait_39,wait_38,wait_40)
normalized: place_bound(wait_1,wait_4,wait_5,wait_2,wait_3,wait_9,wait_8,wait_7,wait_6,wait_13,wait_12,wait_11,wait_10,wait_17,wait_16,wait_15,wait_14,wait_21,wait_20,wait_19,wait_18,wait_26,wait_27,wait_28,wait_29,wait_22,wait_23,wait_24,wait_25,wait_34,wait_35,wait_36,wait_37,wait_30,wait_31,wait_32,wait_33,wait_39,wait_38,wait_40)
-> the formula is 40
FORMULA DrinkVendingMachine-COL-10-UpperBounds-15 40 TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT
MC time: 0m 0.000sec
Total processing time: 1m 7.999sec
BK_STOP 1464350038421
--------------------
content from stderr:
check for maximal unmarked siphon
ok
check if there are places and transitions
ok
check if there are transitions without pre-places
ok
check if at least one transition is enabled in m0
ok
check if there are transitions that can never fire
ok
initing FirstDep: 0m 3.142sec
243 188 245 282 214 206 187 234 228 217 280 217 269 375 256 267 261 238 190 160 170 170 160 160 162 161 172 162 170 163 163 163 164 164 164 164 165 165 165 165 165 165 178 178 173 173 166 166 166 166 166 166 167 167 167 167 167 167 167 167 167 190 168 168 168 168 168 168 168 168 168 168 168 168 168 200 169 169 169 169 169 169 169 169 169 169 169 169 169 169 169 170 170 170 170 170 170 170 170 170 170 170 170 170 170 170 170 170 170 170 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 171 202 197 197 197 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 172 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 173 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 174 208 208 208 208 194 194 194 194 194 194 194 194 194 194 194 194 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 175 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 176 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 177 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 178 241 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 186 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 179 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180 180
iterations count:832369 (7), effective:461 (0)
initing FirstDep: 0m 3.338sec
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_DrinkVendingMachine-PT-10"
export BK_EXAMINATION="UpperBounds"
export BK_TOOL="marcie"
export BK_RESULT_DIR="/root/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_DrinkVendingMachine-PT-10.tgz
mv S_DrinkVendingMachine-PT-10 execution
# this is for BenchKit: explicit launching of the test
cd execution
echo "====================================================================="
echo " Generated by BenchKit 2-2979"
echo " Executing tool marcie"
echo " Input is S_DrinkVendingMachine-PT-10, examination is UpperBounds"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 1"
echo " Run identifier is r149kn-smll-146416258900425"
echo "====================================================================="
echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "UpperBounds" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "UpperBounds" != "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 "UpperBounds.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property UpperBounds.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "UpperBounds.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 '
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 ;