Execution Chart

We display below the execution chart for this examination (boot time has been removed).

Trace from the execution

Formatting '/data/fkordon/mcc2023-input.r449-smll-167912641500433.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2023-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
........................................................................................................................................................
=====================================================================
Generated by BenchKit 2-5348
Executing tool marcie
Input is StigmergyCommit-PT-04b, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 1
Run identifier is r449-smll-167912641500433
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 1.1M
-rw-r--r-- 1 mcc users 6.5K Feb 26 10:53 CTLCardinality.txt
-rw-r--r-- 1 mcc users 68K Feb 26 10:53 CTLCardinality.xml
-rw-r--r-- 1 mcc users 4.4K Feb 26 10:53 CTLFireability.txt
-rw-r--r-- 1 mcc users 35K Feb 26 10:53 CTLFireability.xml
-rw-r--r-- 1 mcc users 3.8K Feb 25 17:11 LTLCardinality.txt
-rw-r--r-- 1 mcc users 24K Feb 25 17:11 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.3K Feb 25 17:11 LTLFireability.txt
-rw-r--r-- 1 mcc users 17K Feb 25 17:11 LTLFireability.xml
-rw-r--r-- 1 mcc users 13K Feb 26 10:54 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 138K Feb 26 10:54 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 7.6K Feb 26 10:54 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 61K Feb 26 10:54 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.7K Feb 25 17:11 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.7K Feb 25 17:11 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Mar 5 18:23 equiv_col
-rw-r--r-- 1 mcc users 4 Mar 5 18:23 instance
-rw-r--r-- 1 mcc users 6 Mar 5 18:23 iscolored
-rw-r--r-- 1 mcc users 627K Mar 5 18:23 model.pnml

--------------------
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 StigmergyCommit-PT-04b-CTLCardinality-00
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-01
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-02
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-03
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-04
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-05
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-06
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-07
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-08
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-09
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-10
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-11
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-12
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-13
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-14
FORMULA_NAME StigmergyCommit-PT-04b-CTLCardinality-15

=== Now, execution of the tool begins

BK_START 1679308377806

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
Invoking MCC driver with
BK_TOOL=marcie
BK_EXAMINATION=CTLCardinality
BK_BIN_PATH=/home/mcc/BenchKit/bin/
BK_TIME_CONFINEMENT=3600
BK_INPUT=StigmergyCommit-PT-04b
Not applying reductions.
Model is PT
CTLCardinality PT
timeout --kill-after=10s --signal=SIGINT 1m for testing only

Marcie built on Linux at 2019-11-18.
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: /home/mcc/BenchKit/bin//../marcie/bin/marcie --net-file=model.pnml --mcc-file=CTLCardinality.xml --memory=6 --mcc-mode

parse successfull
net created successfully

Net: StigmergyCommit_PT_04b
(NrP: 1534 NrTr: 2136 NrArc: 8601)

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

net check time: 0m 0.001sec

init dd package: 0m 3.507sec


RS generation: 36m17.211sec


-> reachability set: #nodes 224200 (2.2e+05) #states 6,520,573,710,832 (12)



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

checking: EF [AF [1<=p52]]
normalized: E [true U ~ [EG [~ [1<=p52]]]]

abstracting: (1<=p52)
states: 35,248,510,109 (10)

before gc: list nodes free: 920787

after gc: idd nodes used:421208, unused:63578792; list nodes free:283319983
MC time: 1m29.060sec

checking: EG [AF [1<=p446]]
normalized: EG [~ [EG [~ [1<=p446]]]]

abstracting: (1<=p446)
states: 26,735,748,014 (10)
.MC time: 1m24.012sec

checking: EG [EG [p46<=p30]]
normalized: EG [EG [p46<=p30]]

abstracting: (p46<=p30)
states: 6,485,325,200,723 (12)

before gc: list nodes free: 2071695

after gc: idd nodes used:560414, unused:63439586; list nodes free:291082308
.MC time: 1m18.155sec

checking: ~ [AX [EX [EG [[p1401<=1 | 1<=p940]]]]]
normalized: EX [~ [EX [EG [[p1401<=1 | 1<=p940]]]]]

abstracting: (1<=p940)
states: 35,248,828,649 (10)
abstracting: (p1401<=1)
states: 6,520,573,710,832 (12)

EG iterations: 0
MC time: 1m13.043sec

checking: EF [AX [EG [EX [E [1<=p996 U p533<=p164]]]]]
normalized: E [true U ~ [EX [~ [EG [EX [E [1<=p996 U p533<=p164]]]]]]]

abstracting: (p533<=p164)
states: 6,496,916,283,276 (12)
abstracting: (1<=p996)
states: 2,533,440,240 (9)
MC time: 1m 9.018sec

checking: AF [~ [[[AX [[1<=p1135 & p688<=0]] | ~ [AG [p915<=p103]]] | ~ [EF [1<=p463]]]]]
normalized: ~ [EG [[~ [E [true U 1<=p463]] | [E [true U ~ [p915<=p103]] | ~ [EX [~ [[1<=p1135 & p688<=0]]]]]]]]

abstracting: (p688<=0)
states: 6,518,040,270,592 (12)
abstracting: (1<=p1135)
states: 23,774,543,534 (10)

before gc: list nodes free: 2215103

after gc: idd nodes used:780334, unused:63219666; list nodes free:289992736
MC time: 1m 5.472sec

checking: E [1<=p216 U ~ [AF [[~ [[p232<=1 & p1393<=p1418]] & A [p687<=p1049 U p31<=1]]]]]
normalized: E [1<=p216 U EG [~ [[[~ [EG [~ [p31<=1]]] & ~ [E [~ [p31<=1] U [~ [p687<=p1049] & ~ [p31<=1]]]]] & ~ [[p232<=1 & p1393<=p1418]]]]]]

abstracting: (p1393<=p1418)
states: 6,493,837,962,818 (12)
abstracting: (p232<=1)
states: 6,520,573,710,832 (12)
abstracting: (p31<=1)
states: 6,520,573,710,832 (12)
abstracting: (p687<=p1049)
states: 6,518,040,270,592 (12)
abstracting: (p31<=1)
states: 6,520,573,710,832 (12)
abstracting: (p31<=1)
states: 6,520,573,710,832 (12)
.
EG iterations: 1
MC time: 1m 0.055sec

checking: A [AG [EG [[~ [[p0<=p379 & p1039<=p1066]] & [EX [p194<=0] & [1<=p1398 | 1<=p1163]]]]] U AG [AF [EX [p226<=1]]]]
normalized: [~ [EG [E [true U EG [~ [EX [p226<=1]]]]]] & ~ [E [E [true U EG [~ [EX [p226<=1]]]] U [E [true U ~ [EG [[[[1<=p1398 | 1<=p1163] & EX [p194<=0]] & ~ [[p0<=p379 & p1039<=p1066]]]]]] & E [true U EG [~ [EX [p226<=1]]]]]]]]

abstracting: (p226<=1)
states: 6,520,573,710,832 (12)
MC time: 0m57.063sec

checking: ~ [[AG [[[[p1034<=0 | p785<=1] | ~ [p428<=0]] & [[p212<=0 & 1<=p776] & ~ [p201<=p1528]]]] | ~ [EF [AX [1<=p1250]]]]]
normalized: ~ [[~ [E [true U ~ [EX [~ [1<=p1250]]]]] | ~ [E [true U ~ [[[~ [p201<=p1528] & [p212<=0 & 1<=p776]] & [~ [p428<=0] | [p1034<=0 | p785<=1]]]]]]]]

abstracting: (p785<=1)
states: 6,520,573,710,832 (12)
abstracting: (p1034<=0)
states: 6,485,324,882,183 (12)
abstracting: (p428<=0)
states: 6,485,325,200,723 (12)
abstracting: (1<=p776)
states: 26,735,748,014 (10)
abstracting: (p212<=0)
states: 6,493,837,962,818 (12)
abstracting: (p201<=p1528)
states: 6,498,497,348,979 (12)
MC time: 0m53.092sec

checking: AG [[~ [AF [[AG [p671<=0] & A [1<=p1410 U 1<=p536]]]] & EX [E [E [p662<=p449 U p1386<=1] U [[p1376<=0 & p1314<=1] | EF [1<=p1258]]]]]]
normalized: ~ [E [true U ~ [[EX [E [E [p662<=p449 U p1386<=1] U [E [true U 1<=p1258] | [p1376<=0 & p1314<=1]]]] & EG [~ [[[~ [EG [~ [1<=p536]]] & ~ [E [~ [1<=p536] U [~ [1<=p1410] & ~ [1<=p536]]]]] & ~ [E [true U ~ [p671<=0]]]]]]]]]]

abstracting: (p671<=0)
states: 6,485,324,884,427 (12)
MC time: 0m50.021sec

checking: AX [E [AX [[~ [[p1130<=1 | p633<=0]] & EX [p357<=p1032]]] U [EG [AG [1<=p955]] | [[EG [p24<=p579] & EX [p985<=0]] & EX [[p389<=p986 | p602<=0]]]]]]
normalized: ~ [EX [~ [E [~ [EX [~ [[EX [p357<=p1032] & ~ [[p1130<=1 | p633<=0]]]]]] U [[EX [[p389<=p986 | p602<=0]] & [EX [p985<=0] & EG [p24<=p579]]] | EG [~ [E [true U ~ [1<=p955]]]]]]]]]

abstracting: (1<=p955)
states: 3,150,569,439 (9)
MC time: 0m47.006sec

checking: [AG [[EF [[~ [1<=p347] & A [1<=p921 U 1<=p552]]] | [AG [p845<=1] & p1476<=1]]] & ~ [AG [~ [[~ [AG [p1510<=0]] | [[[1<=p1438 | p1343<=p902] & EX [1<=p426]] | p1484<=0]]]]]]
normalized: [E [true U [[p1484<=0 | [EX [1<=p426] & [1<=p1438 | p1343<=p902]]] | E [true U ~ [p1510<=0]]]] & ~ [E [true U ~ [[[p1476<=1 & ~ [E [true U ~ [p845<=1]]]] | E [true U [[~ [EG [~ [1<=p552]]] & ~ [E [~ [1<=p552] U [~ [1<=p921] & ~ [1<=p552]]]]] & ~ [1<=p347]]]]]]]]

abstracting: (1<=p347)
states: 35,248,510,109 (10)
abstracting: (1<=p552)
states: 26,735,748,014 (10)
abstracting: (1<=p921)
states: 2,810,830,815 (9)
abstracting: (1<=p552)
states: 26,735,748,014 (10)
MC time: 0m44.001sec

checking: [EG [~ [[[p532<=p54 & 1<=p1470] & EF [p911<=p1413]]]] | [EG [~ [[[~ [[p388<=1 | p1503<=0]] & [p1153<=p1465 | EG [p878<=1]]] | EG [~ [p11<=1]]]]] & E [E [EX [AG [p1423<=0]] U AF [~ [1<=p243]]] U EX [1<=p76]]]]
normalized: [[E [E [EX [~ [E [true U ~ [p1423<=0]]]] U ~ [EG [1<=p243]]] U EX [1<=p76]] & EG [~ [[EG [~ [p11<=1]] | [[p1153<=p1465 | EG [p878<=1]] & ~ [[p388<=1 | p1503<=0]]]]]]] | EG [~ [[E [true U p911<=p1413] & [p532<=p54 & 1<=p1470]]]]]

abstracting: (1<=p1470)
states: 6,020,036,078 (9)
abstracting: (p532<=p54)
states: 6,496,916,283,276 (12)
abstracting: (p911<=p1413)
states: 6,514,216,246,402 (12)
MC time: 0m41.082sec

checking: [AF [[~ [AX [[1<=p1359 & AG [p1201<=1]]]] & [[[[EG [p916<=p718] | [p930<=0 | p571<=1]] | E [p723<=p933 U p1264<=0]] & [~ [p1444<=0] & ~ [EG [p842<=0]]]] & EX [A [p1200<=p1233 U p458<=0]]]]] | ~ [[EX [p1468<=p804] & AF [1<=p1275]]]]
normalized: [~ [[~ [EG [~ [1<=p1275]]] & EX [p1468<=p804]]] | ~ [EG [~ [[[EX [[~ [EG [~ [p458<=0]]] & ~ [E [~ [p458<=0] U [~ [p1200<=p1233] & ~ [p458<=0]]]]]] & [[~ [EG [p842<=0]] & ~ [p1444<=0]] & [E [p723<=p933 U p1264<=0] | [[p930<=0 | p571<=1] | EG [p916<=p718]]]]] & EX [~ [[1<=p1359 & ~ [E [true U ~ [p1201<=1]]]]]]]]]]]

abstracting: (p1201<=1)
states: 6,520,573,710,832 (12)
abstracting: (1<=p1359)
states: 23,774,543,534 (10)
MC time: 0m38.018sec

checking: E [[[EG [~ [AF [p902<=p1078]]] | EF [AG [EF [1<=p851]]]] & [[~ [p477<=1] | [AX [EF [p549<=p1169]] & 1<=p1139]] | 1<=p1286]] U AG [[E [~ [p1343<=p499] U [[p818<=p484 | p1109<=p1367] | p1449<=0]] & EF [[EX [p86<=p937] & ~ [1<=p941]]]]]]
normalized: E [[[1<=p1286 | [[1<=p1139 & ~ [EX [~ [E [true U p549<=p1169]]]]] | ~ [p477<=1]]] & [E [true U ~ [E [true U ~ [E [true U 1<=p851]]]]] | EG [EG [~ [p902<=p1078]]]]] U ~ [E [true U ~ [[E [true U [~ [1<=p941] & EX [p86<=p937]]] & E [~ [p1343<=p499] U [p1449<=0 | [p818<=p484 | p1109<=p1367]]]]]]]]

abstracting: (p1109<=p1367)
states: 6,493,974,060,876 (12)
abstracting: (p818<=p484)
states: 6,493,974,060,876 (12)
abstracting: (p1449<=0)
states: 6,493,837,962,818 (12)
abstracting: (p1343<=p499)
states: 6,485,441,998,145 (12)
MC time: 0m36.008sec

checking: [E [AF [AX [1<=p278]] U EG [EF [EG [1<=p1249]]]] & E [[~ [[[~ [E [1<=p434 U p989<=0]] & [[1<=p1286 | p233<=0] & ~ [1<=p936]]] | EG [[p278<=p406 | p5<=p601]]]] | [~ [[~ [EG [1<=p1076]] | 1<=p1502]] | A [~ [p345<=p625] U ~ [[p694<=p729 | 1<=p664]]]]] U 1<=p156]]
normalized: [E [[[[~ [EG [[p694<=p729 | 1<=p664]]] & ~ [E [[p694<=p729 | 1<=p664] U [p345<=p625 & [p694<=p729 | 1<=p664]]]]] | ~ [[1<=p1502 | ~ [EG [1<=p1076]]]]] | ~ [[EG [[p278<=p406 | p5<=p601]] | [[~ [1<=p936] & [1<=p1286 | p233<=0]] & ~ [E [1<=p434 U p989<=0]]]]]] U 1<=p156] & E [~ [EG [EX [~ [1<=p278]]]] U EG [E [true U EG [1<=p1249]]]]]

abstracting: (1<=p1249)
states: 35,248,828,665 (10)
TIME LIMIT: Killed by timeout after 3600 seconds
MemTotal: 16393916 kB
MemFree: 6172616 kB
After kill :
MemTotal: 16393916 kB
MemFree: 16178348 kB

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.031sec

103305 113530 133184 149513 162532 170621 218244 235858 265202 306049 323495 203247 206177 205792 213519 207122 214252 216957 208936 208285 218702 216548 210409 214083 211563 216231 211253 210920 215607 224518 218943 220286 218241 214351 205876 224358 219853 215981 222025 226747 222914 219180 218205 207545 221155 215445 219429 218850 219654 214355 220932 221283 224114 221003 219136 218027 216375 237746 227201 226420 227446 227537 232381 231327 230169 230507 236053 240341 230014 234335 230976 237668 243081 237505 234704 231800 227487 236407 238474 242388 232428 236266 231535 234282 235973 243280 237962 240142 244841 242431 238398 236627 237488 237719 237580 245956 243001 242786 239529 245941 239826 250447 247093 241016 242787 242926 242393 241145 237819 237053 240889 246636 239933 243875 238510 241515 242605 245221 245823 244266 246901 245623 244835 243264 245952 245462 248985 242391
iterations count:12860787 (6020), effective:324212 (151)

initing FirstDep: 0m 0.031sec


net_ddint.h:600: Timeout: after 88 sec


net_ddint.h:600: Timeout: after 83 sec


net_ddint.h:600: Timeout: after 77 sec


net_ddint.h:600: Timeout: after 72 sec


sat_reach.icc:155: Timeout: after 68 sec


net_ddint.h:600: Timeout: after 64 sec


net_ddint.h:600: Timeout: after 59 sec


net_ddint.h:600: Timeout: after 56 sec


sat_reach.icc:155: Timeout: after 52 sec


sat_reach.icc:155: Timeout: after 49 sec


sat_reach.icc:155: Timeout: after 46 sec


sat_reach.icc:155: Timeout: after 43 sec


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

net_ddint.h:600: Timeout: after 40 sec


net_ddint.h:600: Timeout: after 37 sec


sat_reach.icc:155: Timeout: after 35 sec


net_ddint.h:600: Timeout: after 33 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="StigmergyCommit-PT-04b"
export BK_EXAMINATION="CTLCardinality"
export BK_TOOL="marcie"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"
export BK_BIN_PATH="/home/mcc/BenchKit/bin/"

# 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

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-5348"
echo " Executing tool marcie"
echo " Input is StigmergyCommit-PT-04b, 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 r449-smll-167912641500433"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/StigmergyCommit-PT-04b.tgz
mv StigmergyCommit-PT-04b execution
cd execution
if [ "CTLCardinality" = "ReachabilityDeadlock" ] || [ "CTLCardinality" = "UpperBounds" ] || [ "CTLCardinality" = "QuasiLiveness" ] || [ "CTLCardinality" = "StableMarking" ] || [ "CTLCardinality" = "Liveness" ] || [ "CTLCardinality" = "OneSafe" ] || [ "CTLCardinality" = "StateSpace" ]; then
rm -f GenericPropertiesVerdict.xml
fi
pwd
ls -lh

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
elif [ "CTLCardinality" = "ReachabilityDeadlock" ] || [ "CTLCardinality" = "QuasiLiveness" ] || [ "CTLCardinality" = "StableMarking" ] || [ "CTLCardinality" = "Liveness" ] || [ "CTLCardinality" = "OneSafe" ] ; then
echo "FORMULA_NAME CTLCardinality"
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 ;