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 SmallOperatingSystem-PT-MT0256DC0128, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 1
Run identifier is r125kn-qhx2-146373371100165
=====================================================================

--------------------
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 SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-0
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-1
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-10
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-11
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-12
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-13
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-14
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-15
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-2
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-3
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-4
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-5
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-6
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-7
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-8
FORMULA_NAME SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-9

=== Now, execution of the tool begins

BK_START 1464443240502


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=CTLCardinality.xml --mcc-mode --memory=6 --suppress

parse successfull
net created successfully

Net: SmallOperatingSystem_PT_MT0256DC0128
(NrP: 9 NrTr: 8 NrArc: 27)

net check time: 0m 0.000sec

parse formulas
formulas created successfully
place and transition orderings generation:0m 0.000sec

init dd package: 0m16.651sec


RS generation: 0m14.220sec


-> reachability set: #nodes 18057 (1.8e+04) #states 7,890,709,281 (9)



starting MCC model checker
--------------------------

checking: EF [AG [2<=TransferToDisk]]
normalized: E [true U ~ [E [true U ~ [2<=TransferToDisk]]]]

abstracting: (2<=TransferToDisk) states: 7,544,917,600 (9)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-4 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 3.001sec

checking: ~ [EF [AG [3<=TaskOnDisk]]]
normalized: ~ [E [true U ~ [E [true U ~ [3<=TaskOnDisk]]]]]

abstracting: (3<=TaskOnDisk) states: 7,890,709,281 (9)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-5 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.046sec

checking: AG [EF [3<=DiskControllerUnit]]
normalized: ~ [E [true U ~ [E [true U 3<=DiskControllerUnit]]]]

abstracting: (3<=DiskControllerUnit) states: 7,746,840,234 (9)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-12 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.145sec

checking: AG [1<=FreeMemSegment]
normalized: ~ [E [true U ~ [1<=FreeMemSegment]]]

abstracting: (1<=FreeMemSegment) states: 7,762,533,376 (9)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-15 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 9.219sec

checking: [AG [TaskReady<=CPUUnit] | EX [3<=TransferToDisk]]
normalized: [EX [3<=TransferToDisk] | ~ [E [true U ~ [TaskReady<=CPUUnit]]]]

abstracting: (TaskReady<=CPUUnit) states: 7,890,709,281 (9)
abstracting: (3<=TransferToDisk) states: 7,376,281,920 (9)
.-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-0 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.961sec

checking: [E [~ [3<=LoadingMem] U ~ [3<=DiskControllerUnit]] | TaskOnDisk<=LoadingMem]
normalized: [TaskOnDisk<=LoadingMem | E [~ [3<=LoadingMem] U ~ [3<=DiskControllerUnit]]]

abstracting: (3<=DiskControllerUnit) states: 7,746,840,234 (9)
abstracting: (3<=LoadingMem) states: 7,376,281,920 (9)
abstracting: (TaskOnDisk<=LoadingMem) states: 366,145 (5)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-11 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m35.329sec

checking: E [AX [FreeMemSegment<=TaskOnDisk] U ~ [TaskSuspended<=CPUUnit]]
normalized: E [~ [EX [~ [FreeMemSegment<=TaskOnDisk]]] U ~ [TaskSuspended<=CPUUnit]]

abstracting: (TaskSuspended<=CPUUnit) states: 7,890,709,281 (9)
abstracting: (FreeMemSegment<=TaskOnDisk) states: 7,890,709,281 (9)
.-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-13 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 8.283sec

checking: E [EF [3<=TaskReady] U [~ [1<=FreeMemSegment] & [3<=FreeMemSegment | FreeMemSegment<=DiskControllerUnit]]]
normalized: E [E [true U 3<=TaskReady] U [[3<=FreeMemSegment | FreeMemSegment<=DiskControllerUnit] & ~ [1<=FreeMemSegment]]]

abstracting: (1<=FreeMemSegment) states: 7,762,533,376 (9)
abstracting: (FreeMemSegment<=DiskControllerUnit) states: 4,890,891,681 (9)
abstracting: (3<=FreeMemSegment) states: 7,510,491,456 (9)
abstracting: (3<=TaskReady) states: 7,510,491,456 (9)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-6 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 6.891sec

checking: [AX [AF [1<=TaskReady]] | [AG [[TaskOnDisk<=TaskSuspended & 1<=ExecutingTask]] & 3<=TaskOnDisk]]
normalized: [[3<=TaskOnDisk & ~ [E [true U ~ [[TaskOnDisk<=TaskSuspended & 1<=ExecutingTask]]]]] | ~ [EX [EG [~ [1<=TaskReady]]]]]

abstracting: (1<=TaskReady) states: 7,762,533,376 (9)
..................................................................................................................................
EG iterations: 130
.abstracting: (1<=ExecutingTask) states: 7,762,533,376 (9)
abstracting: (TaskOnDisk<=TaskSuspended) states: 8,385 (3)
abstracting: (3<=TaskOnDisk) states: 7,890,709,281 (9)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-7 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 9.722sec

checking: [AG [AF [TransferToDisk<=TaskOnDisk]] | ~ [A [TaskReady<=ExecutingTask U 3<=CPUUnit]]]
normalized: [~ [[~ [EG [~ [3<=CPUUnit]]] & ~ [E [~ [3<=CPUUnit] U [~ [3<=CPUUnit] & ~ [TaskReady<=ExecutingTask]]]]]] | ~ [E [true U EG [~ [TransferToDisk<=TaskOnDisk]]]]]

abstracting: (TransferToDisk<=TaskOnDisk) states: 7,890,709,281 (9)
.
EG iterations: 1
abstracting: (TaskReady<=ExecutingTask) states: 3,977,579,073 (9)
abstracting: (3<=CPUUnit) states: 7,890,709,260 (9)
abstracting: (3<=CPUUnit) states: 7,890,709,260 (9)
abstracting: (3<=CPUUnit) states: 7,890,709,260 (9)
.
EG iterations: 1
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-8 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 2.710sec

checking: [AG [AG [TransferToDisk<=DiskControllerUnit]] & EG [[3<=ExecutingTask | [2<=TaskOnDisk | 3<=TaskSuspended]]]]
normalized: [EG [[3<=ExecutingTask | [2<=TaskOnDisk | 3<=TaskSuspended]]] & ~ [E [true U E [true U ~ [TransferToDisk<=DiskControllerUnit]]]]]

abstracting: (TransferToDisk<=DiskControllerUnit) states: 5,429,771,425 (9)
abstracting: (3<=TaskSuspended) states: 7,510,491,456 (9)
abstracting: (2<=TaskOnDisk) states: 7,890,709,281 (9)
abstracting: (3<=ExecutingTask) states: 7,510,491,456 (9)

EG iterations: 0
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-10 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m22.650sec

checking: EG [[AX [ExecutingTask<=TaskReady] | [[TaskSuspended<=DiskControllerUnit & 2<=LoadingMem] & ~ [TaskReady<=DiskControllerUnit]]]]
normalized: EG [[[~ [TaskReady<=DiskControllerUnit] & [TaskSuspended<=DiskControllerUnit & 2<=LoadingMem]] | ~ [EX [~ [ExecutingTask<=TaskReady]]]]]

abstracting: (ExecutingTask<=TaskReady) states: 3,977,579,073 (9)
.abstracting: (2<=LoadingMem) states: 7,544,917,600 (9)
abstracting: (TaskSuspended<=DiskControllerUnit) states: 4,890,891,681 (9)
abstracting: (TaskReady<=DiskControllerUnit) states: 4,890,891,681 (9)
..................................................................................................................................
EG iterations: 130
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-2 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 1m19.153sec

checking: EX [[AG [DiskControllerUnit<=TaskOnDisk] | [[LoadingMem<=ExecutingTask & 1<=LoadingMem] & TaskSuspended<=CPUUnit]]]
normalized: EX [[[TaskSuspended<=CPUUnit & [LoadingMem<=ExecutingTask & 1<=LoadingMem]] | ~ [E [true U ~ [DiskControllerUnit<=TaskOnDisk]]]]]

abstracting: (DiskControllerUnit<=TaskOnDisk) states: 7,890,709,281 (9)
abstracting: (1<=LoadingMem) states: 7,716,382,336 (9)
abstracting: (LoadingMem<=ExecutingTask) states: 4,593,289,025 (9)
abstracting: (TaskSuspended<=CPUUnit) states: 7,890,709,281 (9)
.-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-14 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 4.265sec

checking: ~ [[[[[TaskSuspended<=DiskControllerUnit & LoadingMem<=LoadingMem] & LoadingMem<=DiskControllerUnit] & AX [3<=TaskSuspended]] | EF [~ [1<=DiskControllerUnit]]]]
normalized: ~ [[E [true U ~ [1<=DiskControllerUnit]] | [~ [EX [~ [3<=TaskSuspended]]] & [LoadingMem<=DiskControllerUnit & [TaskSuspended<=DiskControllerUnit & LoadingMem<=LoadingMem]]]]]

abstracting: (LoadingMem<=LoadingMem) states: 7,890,709,281 (9)
abstracting: (TaskSuspended<=DiskControllerUnit) states: 4,890,891,681 (9)
abstracting: (LoadingMem<=DiskControllerUnit) states: 5,429,771,425 (9)
abstracting: (3<=TaskSuspended) states: 7,510,491,456 (9)
.abstracting: (1<=DiskControllerUnit) states: 7,843,476,576 (9)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-3 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m13.520sec

checking: AG [[DiskControllerUnit<=TaskSuspended | [[3<=TaskSuspended | FreeMemSegment<=CPUUnit] | [2<=TaskSuspended & TransferToDisk<=TaskReady]]]]
normalized: ~ [E [true U ~ [[DiskControllerUnit<=TaskSuspended | [[3<=TaskSuspended | FreeMemSegment<=CPUUnit] | [2<=TaskSuspended & TransferToDisk<=TaskReady]]]]]]

abstracting: (TransferToDisk<=TaskReady) states: 4,593,289,025 (9)
abstracting: (2<=TaskSuspended) states: 7,635,796,896 (9)
abstracting: (FreeMemSegment<=CPUUnit) states: 7,890,709,281 (9)
abstracting: (3<=TaskSuspended) states: 7,510,491,456 (9)
abstracting: (DiskControllerUnit<=TaskSuspended) states: 3,070,125,825 (9)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-9 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m15.476sec

checking: [[[[[1<=CPUUnit | TaskSuspended<=ExecutingTask] | 3<=ExecutingTask] & [TaskOnDisk<=TaskOnDisk | 2<=TaskSuspended]] & [[[ExecutingTask<=CPUUnit | CPUUnit<=TaskSuspended] & ~ [DiskControllerUnit<=CPUUnit]] & [[2<=LoadingMem | 3<=TaskOnDisk] & ~ [FreeMemSegment<=TransferToDisk]]]] | [AG [[1<=FreeMemSegment | 1<=DiskControllerUnit]] & [AX [TaskSuspended<=DiskControllerUnit] | TransferToDisk<=TaskOnDisk]]]
normalized: [[[TransferToDisk<=TaskOnDisk | ~ [EX [~ [TaskSuspended<=DiskControllerUnit]]]] & ~ [E [true U ~ [[1<=FreeMemSegment | 1<=DiskControllerUnit]]]]] | [[[~ [FreeMemSegment<=TransferToDisk] & [2<=LoadingMem | 3<=TaskOnDisk]] & [~ [DiskControllerUnit<=CPUUnit] & [ExecutingTask<=CPUUnit | CPUUnit<=TaskSuspended]]] & [[TaskOnDisk<=TaskOnDisk | 2<=TaskSuspended] & [3<=ExecutingTask | [1<=CPUUnit | TaskSuspended<=ExecutingTask]]]]]

abstracting: (TaskSuspended<=ExecutingTask) states: 3,977,579,073 (9)
abstracting: (1<=CPUUnit) states: 7,890,709,280 (9)
abstracting: (3<=ExecutingTask) states: 7,510,491,456 (9)
abstracting: (2<=TaskSuspended) states: 7,635,796,896 (9)
abstracting: (TaskOnDisk<=TaskOnDisk) states: 7,890,709,281 (9)
abstracting: (CPUUnit<=TaskSuspended) states: 257
abstracting: (ExecutingTask<=CPUUnit) states: 7,581,389,985 (9)
abstracting: (DiskControllerUnit<=CPUUnit) states: 7,810,382,177 (9)
abstracting: (3<=TaskOnDisk) states: 7,890,709,281 (9)
abstracting: (2<=LoadingMem) states: 7,544,917,600 (9)
abstracting: (FreeMemSegment<=TransferToDisk) states: 3,379,445,121 (9)
abstracting: (1<=DiskControllerUnit) states: 7,843,476,576 (9)
abstracting: (1<=FreeMemSegment) states: 7,762,533,376 (9)
abstracting: (TaskSuspended<=DiskControllerUnit) states: 4,890,891,681 (9)
.abstracting: (TransferToDisk<=TaskOnDisk) states: 7,890,709,281 (9)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0256DC0128-CTLCardinality-1 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 3m58.683sec


Total processing time: 8m 6.546sec


BK_STOP 1464443727371

--------------------
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 0.004sec

593 1009 17987 57261
iterations count:4488 (561), effective:1152 (144)

initing FirstDep: 0m 0.000sec


iterations count:135 (16), effective:127 (15)

iterations count:8 (1), effective:0 (0)

iterations count:17 (2), effective:6 (0)
18057
iterations count:1160 (145), effective:512 (64)
33699 17418
iterations count:2516 (314), effective:627 (78)

iterations count:53 (6), effective:15 (1)
18057
iterations count:1160 (145), effective:512 (64)

iterations count:8 (1), effective:0 (0)
27591
iterations count:1495 (186), effective:388 (48)

iterations count:8 (1), effective:0 (0)
17693 18031
iterations count:2056 (257), effective:512 (64)
34057 17740
iterations count:2824 (353), effective:896 (112)

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="SmallOperatingSystem-PT-MT0256DC0128"
export BK_EXAMINATION="CTLCardinality"
export BK_TOOL="marcie"
export BK_RESULT_DIR="/home/fko/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/SmallOperatingSystem-PT-MT0256DC0128.tgz
mv SmallOperatingSystem-PT-MT0256DC0128 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 SmallOperatingSystem-PT-MT0256DC0128, examination is CTLCardinality"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 1"
echo " Run identifier is r125kn-qhx2-146373371100165"
echo "====================================================================="
echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "CTLCardinality" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "CTLCardinality" != "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 "CTLCardinality.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property CTLCardinality.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "CTLCardinality.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 '' CTLCardinality.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 ;