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-3254
Executing tool marcie
Input is IBMB2S565S3960-PT-none, examination is CTLFireability
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 1
Run identifier is r031-blw7-149440473800094
=====================================================================

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

=== Now, execution of the tool begins

BK_START 1494452235633

timeout --kill-after=10s --signal=SIGINT 1m for testing only

Marcie rev. 8852M (built: crohr on 2017-05-03)
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=CTLFireability.xml --memory=6

parse successfull
net created successfully

Net: IBMB2S565S3960_PT_none
(NrP: 273 NrTr: 179 NrArc: 572)

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

net check time: 0m 0.000sec

init dd package: 0m 1.120sec


RS generation: 0m 0.012sec


-> last computed set: #nodes 43 (4.3e+01) #states 28


parse successfull
net created successfully

Net: IBMB2S565S3960_PT_none
(NrP: 273 NrTr: 179 NrArc: 572)

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

net check time: 0m 0.000sec

init dd package: 0m 3.554sec


RS generation: 3m40.465sec


-> reachability set: #nodes 3663978 (3.7e+06) #states 15,510,635,947,410,534 (16)



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

checking: AX [EF [EX [IS_FIREABLE [decision_s00002055_activate_s00001072]]]]
normalized: ~ [EX [~ [E [true U EX [IS_FIREABLE [decision_s00002055_activate_s00001072]]]]]]

.MC time: 3m31.286sec

checking: AG [AF [IS_FIREABLE [join_s00001164_activate_s00001062]]]
normalized: ~ [E [true U EG [~ [IS_FIREABLE [join_s00001164_activate_s00001062]]]]]


before gc: list nodes free: 637874

after gc: idd nodes used:7244081, unused:56755919; list nodes free:312884107

before gc: list nodes free: 1391279

after gc: idd nodes used:8464407, unused:55535593; list nodes free:335287339
MC time: 3m21.402sec

checking: AG [~ [AX [[IS_FIREABLE [decision_s00002055_fire_s00001075] | IS_FIREABLE [merge_s00001160_activate_s00001064]]]]]
normalized: ~ [E [true U ~ [EX [~ [[IS_FIREABLE [decision_s00002055_fire_s00001075] | IS_FIREABLE [merge_s00001160_activate_s00001064]]]]]]]


before gc: list nodes free: 1714591

after gc: idd nodes used:6165601, unused:57834399; list nodes free:348238666
MC time: 3m 7.726sec

checking: AG [~ [[IS_FIREABLE [decision_s00002948_fire_s00001075] | AF [IS_FIREABLE [decision_s00002762_activate_s00001072]]]]]
normalized: ~ [E [true U [IS_FIREABLE [decision_s00002948_fire_s00001075] | ~ [EG [~ [IS_FIREABLE [decision_s00002762_activate_s00001072]]]]]]]


before gc: list nodes free: 9192849

after gc: idd nodes used:6128123, unused:57871877; list nodes free:347879036
MC time: 2m57.231sec

checking: AF [[EG [~ [IS_FIREABLE [decision_s00001158_activate_s00001072]]] & IS_FIREABLE [fork_s00001071_fire_s00001073]]]
normalized: ~ [EG [~ [[IS_FIREABLE [fork_s00001071_fire_s00001073] & EG [~ [IS_FIREABLE [decision_s00001158_activate_s00001072]]]]]]]


before gc: list nodes free: 572953

after gc: idd nodes used:6788357, unused:57211643; list nodes free:345846743
MC time: 2m45.368sec

checking: AX [AF [[IS_FIREABLE [merge_s00002239_fire_s00001069] | [IS_FIREABLE [decision_s00002056_fire_s00001078] | IS_FIREABLE [callToProcess_s00003904_outputCriterion_s00001055]]]]]
normalized: ~ [EX [EG [~ [[IS_FIREABLE [merge_s00002239_fire_s00001069] | [IS_FIREABLE [decision_s00002056_fire_s00001078] | IS_FIREABLE [callToProcess_s00003904_outputCriterion_s00001055]]]]]]]


before gc: list nodes free: 800537

after gc: idd nodes used:7324485, unused:56675515; list nodes free:346047844
MC time: 2m35.653sec

checking: AG [[AX [~ [IS_FIREABLE [callToTask_s00003967_outputCriterion_s00001055]]] | [~ [~ [IS_FIREABLE [callToProcess_s00001108_inputCriterion_s00001053]]] | EX [IS_FIREABLE [fork_s00001403_fire_s00001078]]]]]
normalized: ~ [E [true U ~ [[[IS_FIREABLE [callToProcess_s00001108_inputCriterion_s00001053] | EX [IS_FIREABLE [fork_s00001403_fire_s00001078]]] | ~ [EX [IS_FIREABLE [callToTask_s00003967_outputCriterion_s00001055]]]]]]]


before gc: list nodes free: 13217535

after gc: idd nodes used:5653485, unused:58346515; list nodes free:359420349
MC time: 2m22.059sec

checking: AF [E [[IS_FIREABLE [merge_s00002240_activate_s00001064] & IS_FIREABLE [decision_s00002948_activate_s00001072]] U IS_FIREABLE [callToTask_s00003975_inputCriterion_s00001053]]]
normalized: ~ [EG [~ [E [[IS_FIREABLE [merge_s00002240_activate_s00001064] & IS_FIREABLE [decision_s00002948_activate_s00001072]] U IS_FIREABLE [callToTask_s00003975_inputCriterion_s00001053]]]]]

MC time: 2m14.102sec

checking: AX [EF [[IS_FIREABLE [join_s00002243_fire_s00001069] | [IS_FIREABLE [fork_s00001298_fire_s00001075] | IS_FIREABLE [process_s00000565__s00003960_outputCriterion_s00001055]]]]]
normalized: ~ [EX [~ [E [true U [IS_FIREABLE [join_s00002243_fire_s00001069] | [IS_FIREABLE [fork_s00001298_fire_s00001075] | IS_FIREABLE [process_s00000565__s00003960_outputCriterion_s00001055]]]]]]]

MC time: 2m12.575sec

checking: ~ [[[AG [IS_FIREABLE [process_s00000565__s00003960_inputCriterion_s00001053]] | EF [IS_FIREABLE [join_s00002243_fire_s00001069]]] | ~ [[EF [IS_FIREABLE [callToTask_s00003913_inputCriterion_s00001053]] & AF [IS_FIREABLE [join_s00001164_activate_s00001062]]]]]]
normalized: ~ [[~ [[~ [EG [~ [IS_FIREABLE [join_s00001164_activate_s00001062]]]] & E [true U IS_FIREABLE [callToTask_s00003913_inputCriterion_s00001053]]]] | [E [true U IS_FIREABLE [join_s00002243_fire_s00001069]] | ~ [E [true U ~ [IS_FIREABLE [process_s00000565__s00003960_inputCriterion_s00001053]]]]]]]

MC time: 1m59.497sec

checking: A [EF [[IS_FIREABLE [callToTask_s00003979_inputCriterion_s00001053] & IS_FIREABLE [decision_s00002761_fire_s00001073]]] U A [IS_FIREABLE [callToProcess_s00001108_outputCriterion_s00001055] U IS_FIREABLE [fork_s00001071_fire_s00001073]]]
normalized: [~ [EG [~ [[~ [EG [~ [IS_FIREABLE [fork_s00001071_fire_s00001073]]]] & ~ [E [~ [IS_FIREABLE [fork_s00001071_fire_s00001073]] U [~ [IS_FIREABLE [callToProcess_s00001108_outputCriterion_s00001055]] & ~ [IS_FIREABLE [fork_s00001071_fire_s00001073]]]]]]]]] & ~ [E [~ [[~ [EG [~ [IS_FIREABLE [fork_s00001071_fire_s00001073]]]] & ~ [E [~ [IS_FIREABLE [fork_s00001071_fire_s00001073]] U [~ [IS_FIREABLE [callToProcess_s00001108_outputCriterion_s00001055]] & ~ [IS_FIREABLE [fork_s00001071_fire_s00001073]]]]]]] U [~ [E [true U [IS_FIREABLE [callToTask_s00003979_inputCriterion_s00001053] & IS_FIREABLE [decision_s00002761_fire_s00001073]]]] & ~ [[~ [EG [~ [IS_FIREABLE [fork_s00001071_fire_s00001073]]]] & ~ [E [~ [IS_FIREABLE [fork_s00001071_fire_s00001073]] U [~ [IS_FIREABLE [callToProcess_s00001108_outputCriterion_s00001055]] & ~ [IS_FIREABLE [fork_s00001071_fire_s00001073]]]]]]]]]]]

MC time: 1m51.310sec

checking: A [[[~ [IS_FIREABLE [merge_s00002239_activate_s00001064]] & [IS_FIREABLE [callToTask_s00003980_inputCriterion_s00001053] & IS_FIREABLE [merge_s00002239_activate_s00001062]]] & [~ [IS_FIREABLE [merge_s00001162_activate_s00001262]] & [IS_FIREABLE [callToTask_s00003963_inputCriterion_s00001053] | IS_FIREABLE [merge_s00002239_fire_s00001069]]]] U ~ [EF [IS_FIREABLE [callToTask_s00003961_outputCriterion_s00001055]]]]
normalized: [~ [EG [E [true U IS_FIREABLE [callToTask_s00003961_outputCriterion_s00001055]]]] & ~ [E [E [true U IS_FIREABLE [callToTask_s00003961_outputCriterion_s00001055]] U [~ [[[~ [IS_FIREABLE [merge_s00001162_activate_s00001262]] & [IS_FIREABLE [callToTask_s00003963_inputCriterion_s00001053] | IS_FIREABLE [merge_s00002239_fire_s00001069]]] & [[IS_FIREABLE [callToTask_s00003980_inputCriterion_s00001053] & IS_FIREABLE [merge_s00002239_activate_s00001062]] & ~ [IS_FIREABLE [merge_s00002239_activate_s00001064]]]]] & E [true U IS_FIREABLE [callToTask_s00003961_outputCriterion_s00001055]]]]]]


before gc: list nodes free: 58054097

after gc: idd nodes used:4490722, unused:59509278; list nodes free:365328388
MC time: 1m47.041sec

checking: AF [[[EX [IS_FIREABLE [callToTask_s00003976_inputCriterion_s00001053]] & EX [IS_FIREABLE [callToTask_s00003967_outputCriterion_s00001055]]] | [IS_FIREABLE [callToTask_s00003979_inputCriterion_s00001053] | [[IS_FIREABLE [fork_s00001071_activate_s00001072] | IS_FIREABLE [callToTask_s00003963_inputCriterion_s00001053]] | [IS_FIREABLE [join_s00001164_fire_s00001069] | IS_FIREABLE [callToTask_s00003976_inputCriterion_s00001053]]]]]]
normalized: ~ [EG [~ [[[IS_FIREABLE [callToTask_s00003979_inputCriterion_s00001053] | [[IS_FIREABLE [fork_s00001071_activate_s00001072] | IS_FIREABLE [callToTask_s00003963_inputCriterion_s00001053]] | [IS_FIREABLE [join_s00001164_fire_s00001069] | IS_FIREABLE [callToTask_s00003976_inputCriterion_s00001053]]]] | [EX [IS_FIREABLE [callToTask_s00003967_outputCriterion_s00001055]] & EX [IS_FIREABLE [callToTask_s00003976_inputCriterion_s00001053]]]]]]]

MC time: 1m38.977sec

checking: [EX [[[[IS_FIREABLE [callToTask_s00003971_outputCriterion_s00001055] | IS_FIREABLE [merge_s00002239_activate_s00001062]] & ~ [IS_FIREABLE [callToProcess_s00001108_outputCriterion_s00001055]]] & IS_FIREABLE [decision_s00002760_fire_s00001073]]] | [IS_FIREABLE [callToTask_s00003967_inputCriterion_s00001053] | AG [~ [[IS_FIREABLE [callToTask_s00003970_outputCriterion_s00001055] | IS_FIREABLE [callToTask_s00003975_inputCriterion_s00001053]]]]]]
normalized: [[IS_FIREABLE [callToTask_s00003967_inputCriterion_s00001053] | ~ [E [true U [IS_FIREABLE [callToTask_s00003970_outputCriterion_s00001055] | IS_FIREABLE [callToTask_s00003975_inputCriterion_s00001053]]]]] | EX [[IS_FIREABLE [decision_s00002760_fire_s00001073] & [~ [IS_FIREABLE [callToProcess_s00001108_outputCriterion_s00001055]] & [IS_FIREABLE [callToTask_s00003971_outputCriterion_s00001055] | IS_FIREABLE [merge_s00002239_activate_s00001062]]]]]]


before gc: list nodes free: 31600615

after gc: idd nodes used:5932292, unused:58067708; list nodes free:357528149
MC time: 1m45.789sec

checking: [AG [A [IS_FIREABLE [decision_s00002761_fire_s00001073] U IS_FIREABLE [callToProcess_s00003884_outputCriterion_s00001055]]] | AF [[[[IS_FIREABLE [merge_s00001162_activate_s00001065] | IS_FIREABLE [merge_s00001161_fire_s00001069]] | IS_FIREABLE [decision_s00001123_activate_s00001072]] & [IS_FIREABLE [decision_s00001126_fire_s00001075] & [IS_FIREABLE [callToProcess_s00003876_inputCriterion_s00001053] & IS_FIREABLE [callToTask_s00003975_inputCriterion_s00001053]]]]]]
normalized: [~ [EG [~ [[[IS_FIREABLE [decision_s00001126_fire_s00001075] & [IS_FIREABLE [callToProcess_s00003876_inputCriterion_s00001053] & IS_FIREABLE [callToTask_s00003975_inputCriterion_s00001053]]] & [IS_FIREABLE [decision_s00001123_activate_s00001072] | [IS_FIREABLE [merge_s00001162_activate_s00001065] | IS_FIREABLE [merge_s00001161_fire_s00001069]]]]]]] | ~ [E [true U ~ [[~ [EG [~ [IS_FIREABLE [callToProcess_s00003884_outputCriterion_s00001055]]]] & ~ [E [~ [IS_FIREABLE [callToProcess_s00003884_outputCriterion_s00001055]] U [~ [IS_FIREABLE [decision_s00002761_fire_s00001073]] & ~ [IS_FIREABLE [callToProcess_s00003884_outputCriterion_s00001055]]]]]]]]]]

MC time: 1m23.240sec

checking: [~ [A [[IS_FIREABLE [join_s00002243_activate_s00001064] | IS_FIREABLE [callToProcess_s00003859_outputCriterion_s00001055]] U [IS_FIREABLE [callToTask_s00003970_outputCriterion_s00001055] | IS_FIREABLE [merge_s00002239_fire_s00001069]]]] & [[[[IS_FIREABLE [decision_s00003020_activate_s00001072] & [IS_FIREABLE [merge_s00001160_activate_s00001067] | IS_FIREABLE [callToTask_s00003964_inputCriterion_s00001053]]] & [IS_FIREABLE [callToTask_s00003974_outputCriterion_s00001055] | ~ [IS_FIREABLE [callToProcess_s00003904_inputCriterion_s00001053]]]] | [[~ [IS_FIREABLE [callToTask_s00003977_inputCriterion_s00001053]] & [IS_FIREABLE [decision_s00001159_activate_s00001072] & IS_FIREABLE [decision_s00002760_fire_s00001073]]] & ~ [~ [IS_FIREABLE [merge_s00002239_activate_s00001064]]]]] | ~ [AG [~ [IS_FIREABLE [join_s00002243_activate_s00001065]]]]]]
normalized: [[E [true U IS_FIREABLE [join_s00002243_activate_s00001065]] | [[IS_FIREABLE [merge_s00002239_activate_s00001064] & [[IS_FIREABLE [decision_s00001159_activate_s00001072] & IS_FIREABLE [decision_s00002760_fire_s00001073]] & ~ [IS_FIREABLE [callToTask_s00003977_inputCriterion_s00001053]]]] | [[IS_FIREABLE [callToTask_s00003974_outputCriterion_s00001055] | ~ [IS_FIREABLE [callToProcess_s00003904_inputCriterion_s00001053]]] & [IS_FIREABLE [decision_s00003020_activate_s00001072] & [IS_FIREABLE [merge_s00001160_activate_s00001067] | IS_FIREABLE [callToTask_s00003964_inputCriterion_s00001053]]]]]] & ~ [[~ [EG [~ [[IS_FIREABLE [callToTask_s00003970_outputCriterion_s00001055] | IS_FIREABLE [merge_s00002239_fire_s00001069]]]]] & ~ [E [~ [[IS_FIREABLE [callToTask_s00003970_outputCriterion_s00001055] | IS_FIREABLE [merge_s00002239_fire_s00001069]]] U [~ [[IS_FIREABLE [join_s00002243_activate_s00001064] | IS_FIREABLE [callToProcess_s00003859_outputCriterion_s00001055]]] & ~ [[IS_FIREABLE [callToTask_s00003970_outputCriterion_s00001055] | IS_FIREABLE [merge_s00002239_fire_s00001069]]]]]]]]]


BK_TIME_CONFINEMENT_REACHED

--------------------
content from stderr:

check for maximal unmarked siphon
ok
check for constant places
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.000sec

zbdd.cc:1122: Boundedness exception: net is not 1-bounded!

check for maximal unmarked siphon
ok
check for constant places
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.000sec


iterations count:8863 (49), effective:1374 (7)

initing FirstDep: 0m 0.001sec


sat_reach.icc:155: Timeout: after 210 sec


net_ddint.h:596: Timeout: after 197 sec


net_ddint.h:596: Timeout: after 184 sec


net_ddint.h:596: Timeout: after 173 sec


net_ddint.h:596: Timeout: after 162 sec


net_ddint.h:596: Timeout: after 151 sec


net_ddint.h:596: Timeout: after 141 sec


sat_reach.icc:155: Timeout: after 133 sec


sat_reach.icc:155: Timeout: after 124 sec


sat_reach.icc:155: Timeout: after 116 sec


sat_reach.icc:155: Timeout: after 108 sec


sat_reach.icc:155: Timeout: after 101 sec


net_ddint.h:596: Timeout: after 95 sec


net_ddint.h:596: Timeout: after 89 sec


sat_reach.icc:155: Timeout: after 82 sec


sat_reach.icc:155: Timeout: after 77 sec

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="IBMB2S565S3960-PT-none"
export BK_EXAMINATION="CTLFireability"
export BK_TOOL="marcie"
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/IBMB2S565S3960-PT-none.tgz
mv IBMB2S565S3960-PT-none execution

# this is for BenchKit: explicit launching of the test

cd execution
echo "====================================================================="
echo " Generated by BenchKit 2-3254"
echo " Executing tool marcie"
echo " Input is IBMB2S565S3960-PT-none, examination is CTLFireability"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 1"
echo " Run identifier is r031-blw7-149440473800094"
echo "====================================================================="
echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "CTLFireability" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "CTLFireability" != "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 "CTLFireability.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property CTLFireability.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "CTLFireability.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 '' CTLFireability.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 ;