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

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

=== Now, execution of the tool begins

BK_START 1464442762403


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_MT0128DC0064
(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.975sec


RS generation: 0m 1.671sec


-> reachability set: #nodes 4937 (4.9e+03) #states 261,156,753 (8)



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

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

abstracting: (CPUUnit<=DiskControllerUnit) states: 3,022,481 (6)
-> the formula is FALSE

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

MC time: 0m 1.131sec

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

abstracting: (TaskOnDisk<=TaskReady) states: 2,145 (3)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-12 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.698sec

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

abstracting: (FreeMemSegment<=TaskOnDisk) states: 261,156,753 (8)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-13 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.512sec

checking: AG [[EG [3<=DiskControllerUnit] | 2<=TaskReady]]
normalized: ~ [E [true U ~ [[2<=TaskReady | EG [3<=DiskControllerUnit]]]]]

abstracting: (3<=DiskControllerUnit) states: 251,534,682 (8)
.
EG iterations: 1
abstracting: (2<=TaskReady) states: 244,658,128 (8)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-14 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 1.017sec

checking: AF [[EF [DiskControllerUnit<=TaskReady] | EX [1<=TransferToDisk]]]
normalized: ~ [EG [~ [[EX [1<=TransferToDisk] | E [true U DiskControllerUnit<=TaskReady]]]]]

abstracting: (DiskControllerUnit<=TaskReady) states: 102,756,225 (8)
abstracting: (1<=TransferToDisk) states: 249,840,448 (8)
..
EG iterations: 1
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-2 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.726sec

checking: AG [A [DiskControllerUnit<=TaskOnDisk U 3<=CPUUnit]]
normalized: ~ [E [true U ~ [[~ [EG [~ [3<=CPUUnit]]] & ~ [E [~ [3<=CPUUnit] U [~ [DiskControllerUnit<=TaskOnDisk] & ~ [3<=CPUUnit]]]]]]]]

abstracting: (3<=CPUUnit) states: 261,156,732 (8)
abstracting: (DiskControllerUnit<=TaskOnDisk) states: 261,156,753 (8)
abstracting: (3<=CPUUnit) states: 261,156,732 (8)
abstracting: (3<=CPUUnit) states: 261,156,732 (8)
.
EG iterations: 1
-> the formula is FALSE

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

MC time: 0m 0.060sec

checking: AF [[AG [1<=DiskControllerUnit] & FreeMemSegment<=TaskOnDisk]]
normalized: ~ [EG [~ [[FreeMemSegment<=TaskOnDisk & ~ [E [true U ~ [1<=DiskControllerUnit]]]]]]]

abstracting: (1<=DiskControllerUnit) states: 258,042,928 (8)
abstracting: (FreeMemSegment<=TaskOnDisk) states: 261,156,753 (8)

EG iterations: 0
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-11 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.422sec

checking: AG [AF [[CPUUnit<=CPUUnit | TaskSuspended<=TaskReady]]]
normalized: ~ [E [true U EG [~ [[CPUUnit<=CPUUnit | TaskSuspended<=TaskReady]]]]]

abstracting: (TaskSuspended<=TaskReady) states: 132,687,137 (8)
abstracting: (CPUUnit<=CPUUnit) states: 261,156,753 (8)
.
EG iterations: 1
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-10 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.207sec

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

abstracting: (3<=TaskOnDisk) states: 261,156,753 (8)
abstracting: (1<=TaskReady) states: 252,814,848 (8)
abstracting: (TaskSuspended<=DiskControllerUnit) states: 163,001,553 (8)
abstracting: (2<=TaskOnDisk) states: 261,156,753 (8)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-15 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 1.192sec

checking: [EF [FreeMemSegment<=TransferToDisk] | [E [2<=LoadingMem U 1<=TaskSuspended] | EF [[FreeMemSegment<=TaskOnDisk & FreeMemSegment<=LoadingMem]]]]
normalized: [[E [true U [FreeMemSegment<=TaskOnDisk & FreeMemSegment<=LoadingMem]] | E [2<=LoadingMem U 1<=TaskSuspended]] | E [true U FreeMemSegment<=TransferToDisk]]

abstracting: (FreeMemSegment<=TransferToDisk) states: 113,180,353 (8)
abstracting: (1<=TaskSuspended) states: 252,814,848 (8)
abstracting: (2<=LoadingMem) states: 238,890,288 (8)
abstracting: (FreeMemSegment<=LoadingMem) states: 113,180,353 (8)
abstracting: (FreeMemSegment<=TaskOnDisk) states: 261,156,753 (8)
-> the formula is TRUE

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

MC time: 0m 2.519sec

checking: [[FreeMemSegment<=FreeMemSegment & AG [TransferToDisk<=TransferToDisk]] | EG [[[1<=TaskSuspended & 1<=TaskSuspended] | [1<=TaskSuspended & TaskOnDisk<=ExecutingTask]]]]
normalized: [EG [[[1<=TaskSuspended & TaskOnDisk<=ExecutingTask] | [1<=TaskSuspended & 1<=TaskSuspended]]] | [FreeMemSegment<=FreeMemSegment & ~ [E [true U ~ [TransferToDisk<=TransferToDisk]]]]]

abstracting: (TransferToDisk<=TransferToDisk) states: 261,156,753 (8)
abstracting: (FreeMemSegment<=FreeMemSegment) states: 261,156,753 (8)
abstracting: (1<=TaskSuspended) states: 252,814,848 (8)
abstracting: (1<=TaskSuspended) states: 252,814,848 (8)
abstracting: (TaskOnDisk<=ExecutingTask) states: 2,145 (3)
abstracting: (1<=TaskSuspended) states: 252,814,848 (8)
.
EG iterations: 1
-> the formula is TRUE

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

MC time: 0m 0.146sec

checking: [[E [FreeMemSegment<=LoadingMem U FreeMemSegment<=LoadingMem] & ~ [2<=ExecutingTask]] | E [~ [1<=TaskSuspended] U [TaskOnDisk<=TaskSuspended & DiskControllerUnit<=FreeMemSegment]]]
normalized: [[~ [2<=ExecutingTask] & E [FreeMemSegment<=LoadingMem U FreeMemSegment<=LoadingMem]] | E [~ [1<=TaskSuspended] U [TaskOnDisk<=TaskSuspended & DiskControllerUnit<=FreeMemSegment]]]

abstracting: (DiskControllerUnit<=FreeMemSegment) states: 102,756,225 (8)
abstracting: (TaskOnDisk<=TaskSuspended) states: 2,145 (3)
abstracting: (1<=TaskSuspended) states: 252,814,848 (8)
abstracting: (FreeMemSegment<=LoadingMem) states: 113,180,353 (8)
abstracting: (FreeMemSegment<=LoadingMem) states: 113,180,353 (8)
abstracting: (2<=ExecutingTask) states: 244,658,128 (8)
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-0 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.259sec

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

abstracting: (LoadingMem<=FreeMemSegment) states: 153,343,905 (8)
abstracting: (3<=TransferToDisk) states: 228,297,888 (8)
abstracting: (FreeMemSegment<=TaskOnDisk) states: 261,156,753 (8)
abstracting: (1<=TaskOnDisk) states: 261,156,753 (8)
abstracting: (TaskSuspended<=ExecutingTask) states: 132,687,137 (8)
abstracting: (DiskControllerUnit<=TaskReady) states: 102,756,225 (8)
abstracting: (2<=TaskSuspended) states: 244,658,128 (8)
.-> the formula is TRUE

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

MC time: 0m 0.302sec

checking: ~ [[[AG [2<=DiskControllerUnit] & [LoadingMem<=CPUUnit & [2<=CPUUnit & ExecutingTask<=DiskControllerUnit]]] | [EF [3<=CPUUnit] & [~ [TaskOnDisk<=LoadingMem] & [TaskOnDisk<=LoadingMem & 2<=TaskOnDisk]]]]]
normalized: ~ [[[E [true U 3<=CPUUnit] & [~ [TaskOnDisk<=LoadingMem] & [TaskOnDisk<=LoadingMem & 2<=TaskOnDisk]]] | [[LoadingMem<=CPUUnit & [2<=CPUUnit & ExecutingTask<=DiskControllerUnit]] & ~ [E [true U ~ [2<=DiskControllerUnit]]]]]]

abstracting: (2<=DiskControllerUnit) states: 254,835,504 (8)
abstracting: (ExecutingTask<=DiskControllerUnit) states: 163,001,553 (8)
abstracting: (2<=CPUUnit) states: 261,156,747 (8)
abstracting: (LoadingMem<=CPUUnit) states: 261,156,753 (8)
abstracting: (2<=TaskOnDisk) states: 261,156,753 (8)
abstracting: (TaskOnDisk<=LoadingMem) states: 47,905 (4)
abstracting: (TaskOnDisk<=LoadingMem) states: 47,905 (4)
abstracting: (3<=CPUUnit) states: 261,156,732 (8)
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-1 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.512sec

checking: [[~ [[[TaskReady<=ExecutingTask & CPUUnit<=ExecutingTask] | [LoadingMem<=TransferToDisk | FreeMemSegment<=LoadingMem]]] | ~ [AX [TransferToDisk<=TaskReady]]] | AF [AG [ExecutingTask<=TaskOnDisk]]]
normalized: [~ [EG [E [true U ~ [ExecutingTask<=TaskOnDisk]]]] | [EX [~ [TransferToDisk<=TaskReady]] | ~ [[[LoadingMem<=TransferToDisk | FreeMemSegment<=LoadingMem] | [TaskReady<=ExecutingTask & CPUUnit<=ExecutingTask]]]]]

abstracting: (CPUUnit<=ExecutingTask) states: 11,238,513 (7)
abstracting: (TaskReady<=ExecutingTask) states: 132,687,137 (8)
abstracting: (FreeMemSegment<=LoadingMem) states: 113,180,353 (8)
abstracting: (LoadingMem<=TransferToDisk) states: 133,459,601 (8)
abstracting: (TransferToDisk<=TaskReady) states: 153,343,905 (8)
.abstracting: (ExecutingTask<=TaskOnDisk) states: 261,156,753 (8)
.
EG iterations: 1
-> the formula is TRUE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-5 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 1.497sec

checking: [[[3<=CPUUnit | ~ [~ [CPUUnit<=LoadingMem]]] & [AG [3<=CPUUnit] | ~ [1<=FreeMemSegment]]] & A [[2<=TaskOnDisk & TaskOnDisk<=LoadingMem] U [TaskOnDisk<=TransferToDisk | ExecutingTask<=CPUUnit]]]
normalized: [[~ [EG [~ [[TaskOnDisk<=TransferToDisk | ExecutingTask<=CPUUnit]]]] & ~ [E [~ [[TaskOnDisk<=TransferToDisk | ExecutingTask<=CPUUnit]] U [~ [[2<=TaskOnDisk & TaskOnDisk<=LoadingMem]] & ~ [[TaskOnDisk<=TransferToDisk | ExecutingTask<=CPUUnit]]]]]] & [[~ [1<=FreeMemSegment] | ~ [E [true U ~ [3<=CPUUnit]]]] & [3<=CPUUnit | CPUUnit<=LoadingMem]]]

abstracting: (CPUUnit<=LoadingMem) states: 65
abstracting: (3<=CPUUnit) states: 261,156,732 (8)
abstracting: (3<=CPUUnit) states: 261,156,732 (8)
abstracting: (1<=FreeMemSegment) states: 252,814,848 (8)
abstracting: (ExecutingTask<=CPUUnit) states: 250,732,625 (8)
abstracting: (TaskOnDisk<=TransferToDisk) states: 47,905 (4)
abstracting: (TaskOnDisk<=LoadingMem) states: 47,905 (4)
abstracting: (2<=TaskOnDisk) states: 261,156,753 (8)
abstracting: (ExecutingTask<=CPUUnit) states: 250,732,625 (8)
abstracting: (TaskOnDisk<=TransferToDisk) states: 47,905 (4)
abstracting: (ExecutingTask<=CPUUnit) states: 250,732,625 (8)
abstracting: (TaskOnDisk<=TransferToDisk) states: 47,905 (4)
.
EG iterations: 1
-> the formula is FALSE

FORMULA SmallOperatingSystem-PT-MT0128DC0064-CTLCardinality-6 FALSE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 0.164sec


Total processing time: 0m34.556sec


BK_STOP 1464442797035

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

509 14819
iterations count:2248 (281), effective:576 (72)

initing FirstDep: 0m 0.000sec

4984
iterations count:1288 (161), effective:448 (56)
4606
iterations count:1928 (241), effective:640 (80)
4812
iterations count:1260 (157), effective:313 (39)

iterations count:776 (97), effective:192 (24)
4279 5024
iterations count:2150 (268), effective:630 (78)
4923
iterations count:1032 (129), effective:256 (32)

iterations count:980 (122), effective:260 (32)

iterations count:520 (65), effective:192 (24)

iterations count:24 (3), effective:5 (0)

iterations count:232 (29), effective:96 (12)

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

iterations count:8 (1), effective:0 (0)
4931
iterations count:1016 (127), effective:252 (31)

iterations count:20 (2), effective:3 (0)
4279 5024
iterations count:2150 (268), effective:630 (78)

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

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-MT0128DC0064"
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-MT0128DC0064.tgz
mv SmallOperatingSystem-PT-MT0128DC0064 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-MT0128DC0064, 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-146373371100147"
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 ;