Execution Chart

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

Trace from the execution

Formatting '/data/fko/mcc2020-input.r186-oct2-158987938900373.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fko/mcc2020-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
.............................................................................................
=====================================================================
Generated by BenchKit 2-4028
Executing tool itslola
Input is SafeBus-COL-20, examination is UpperBounds
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r186-oct2-158987938900373
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 208K
-rw-r--r-- 1 mcc users 3.7K Apr 12 12:11 CTLCardinality.txt
-rw-r--r-- 1 mcc users 21K Apr 12 12:10 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.4K Apr 11 13:51 CTLFireability.txt
-rw-r--r-- 1 mcc users 15K Apr 11 13:51 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 24 05:38 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 3.1K Apr 14 12:50 LTLCardinality.txt
-rw-r--r-- 1 mcc users 20K Apr 28 14:02 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.1K Apr 14 12:50 LTLFireability.txt
-rw-r--r-- 1 mcc users 17K Apr 28 14:02 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.2K Apr 10 17:26 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 15K Apr 10 17:26 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 2.9K Apr 10 00:22 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 18K Apr 10 00:21 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.6K Apr 10 22:28 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.6K Apr 10 22:28 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 Mar 24 05:38 equiv_pt
-rw-r--r-- 1 mcc users 3 Mar 24 05:38 instance
-rw-r--r-- 1 mcc users 5 Mar 24 05:38 iscolored
-rw-r--r-- 1 mcc users 42K Mar 24 05:38 model.pnml

--------------------
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 SafeBus-COL-20-UpperBounds-00
FORMULA_NAME SafeBus-COL-20-UpperBounds-01
FORMULA_NAME SafeBus-COL-20-UpperBounds-02
FORMULA_NAME SafeBus-COL-20-UpperBounds-03
FORMULA_NAME SafeBus-COL-20-UpperBounds-04
FORMULA_NAME SafeBus-COL-20-UpperBounds-05
FORMULA_NAME SafeBus-COL-20-UpperBounds-06
FORMULA_NAME SafeBus-COL-20-UpperBounds-07
FORMULA_NAME SafeBus-COL-20-UpperBounds-08
FORMULA_NAME SafeBus-COL-20-UpperBounds-09
FORMULA_NAME SafeBus-COL-20-UpperBounds-10
FORMULA_NAME SafeBus-COL-20-UpperBounds-11
FORMULA_NAME SafeBus-COL-20-UpperBounds-12
FORMULA_NAME SafeBus-COL-20-UpperBounds-13
FORMULA_NAME SafeBus-COL-20-UpperBounds-14
FORMULA_NAME SafeBus-COL-20-UpperBounds-15

=== Now, execution of the tool begins

BK_START 1591236816263

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
[2020-06-04 02:13:38] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, UpperBounds, -z3path, /home/mcc/BenchKit//z3/bin/z3, -yices2path, /home/mcc/BenchKit//yices/bin/yices, -timeout, 3600, -rebuildPNML]
[2020-06-04 02:13:38] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2020-06-04 02:13:38] [INFO ] Detected file is not PT type :http://www.pnml.org/version-2009/grammar/symmetricnet
SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
SLF4J: Defaulting to no-operation (NOP) logger implementation
SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
[2020-06-04 02:13:39] [INFO ] Load time of PNML (colored model parsed with PNMLFW) : 584 ms
[2020-06-04 02:13:39] [INFO ] sort/places :
Dom->AMC,wait_ack,
Dot->cable_free,ACK,T_out,R_tout,S_tout,FMCb,
It->Cpt2,Cpt1,msgl,cable_used,FMC,wait_cable,listen,RMC,PMC,MSG,wait_msg,loop_em,

[2020-06-04 02:13:39] [INFO ] Detected 1 constant HL places corresponding to 20 PT places.
[2020-06-04 02:13:39] [INFO ] Imported 20 HL places and 14 HL transitions for a total of 1046 PT places and 179241.0 transition bindings in 83 ms.
[2020-06-04 02:13:39] [INFO ] Computed order based on color domains.
[2020-06-04 02:13:39] [INFO ] Unfolded HLPN to a Petri net with 1046 places and 10501 transitions in 183 ms.
[2020-06-04 02:13:39] [INFO ] Unfolded HLPN properties in 1 ms.
Deduced a syphon composed of 20 places in 9 ms
Reduce places removed 40 places and 40 transitions.
Parsed 16 properties from file /home/mcc/execution/UpperBounds.xml in 320 ms.
Finished random walk after 0 steps, including 0 resets, run visited all 0 properties in 3 ms. (steps per millisecond=0 )
[2020-06-04 02:13:41] [INFO ] Flatten gal took : 1108 ms
FORMULA SafeBus-COL-20-UpperBounds-07 1 TECHNIQUES TOPOLOGICAL INITIAL_STATE
Finished random walk after 0 steps, including 0 resets, run visited all 0 properties in 1 ms. (steps per millisecond=0 )
Finished probabilistic random walk after 0 steps, run visited all 0 properties in 0 ms. (steps per millisecond=0 )
[2020-06-04 02:13:41] [INFO ] Flow matrix only has 2482 transitions (discarded 7979 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:13:41] [INFO ] Computed 85 place invariants in 153 ms
Support contains 523 out of 1006 places. Attempting structural reductions.
Starting structural reductions, iteration 0 : 1006/1006 places, 10461/10461 transitions.
Applied a total of 0 rules in 5276 ms. Remains 1006 /1006 variables (removed 0) and now considering 10461/10461 (removed 0) transitions.
Finished structural reductions, in 1 iterations. Remains : 1006/1006 places, 10461/10461 transitions.
Finished random walk after 0 steps, including 0 resets, run visited all 0 properties in 1 ms. (steps per millisecond=0 )
Finished probabilistic random walk after 0 steps, run visited all 0 properties in 0 ms. (steps per millisecond=0 )
[2020-06-04 02:13:46] [INFO ] Flow matrix only has 2482 transitions (discarded 7979 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:13:46] [INFO ] Computed 85 place invariants in 64 ms
Support contains 523 out of 1006 places. Attempting structural reductions.
Starting structural reductions, iteration 0 : 1006/1006 places, 10461/10461 transitions.
Applied a total of 0 rules in 5500 ms. Remains 1006 /1006 variables (removed 0) and now considering 10461/10461 (removed 0) transitions.
Finished structural reductions, in 1 iterations. Remains : 1006/1006 places, 10461/10461 transitions.
Starting structural reductions, iteration 0 : 1006/1006 places, 10461/10461 transitions.
Applied a total of 0 rules in 5245 ms. Remains 1006 /1006 variables (removed 0) and now considering 10461/10461 (removed 0) transitions.
[2020-06-04 02:13:57] [INFO ] Flow matrix only has 2482 transitions (discarded 7979 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:13:57] [INFO ] Computed 85 place invariants in 50 ms
[2020-06-04 02:14:01] [INFO ] Implicit Places using invariants in 3725 ms returned []
Implicit Place search using SMT only with invariants took 3732 ms to find 0 implicit places.
[2020-06-04 02:14:01] [INFO ] Flow matrix only has 2482 transitions (discarded 7979 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:14:01] [INFO ] Computed 85 place invariants in 35 ms
[2020-06-04 02:14:31] [INFO ] Performed 3569/10461 'is it Dead' test of which 160 returned DEAD in 30 seconds.
[2020-06-04 02:15:01] [INFO ] Dead Transitions with SMT raised an exceptionSMT solver raised an error when submitting script. Raised (error "Failed to assert expression: java.io.IOException: Broken pipe (and (>= s13 1) (>= s45 1) (>= s414 1) (>= s985 1))") after 60082 ms
Found 320 dead transitions using SMT.
Drop transitions removed 320 transitions
Dead transitions reduction (with SMT) removed 320 transitions :[6700, 6699, 6698, 6697, 6696, 6695, 6694, 6693, 6692, 6691, 6690, 6689, 6688, 6687, 6686, 6685, 6684, 6683, 6682, 6681, 6280, 6279, 6278, 6277, 6276, 6275, 6274, 6273, 6272, 6271, 6270, 6269, 6268, 6267, 6266, 6265, 6264, 6263, 6262, 6261, 5860, 5859, 5858, 5857, 5856, 5855, 5854, 5853, 5852, 5851, 5850, 5849, 5848, 5847, 5846, 5845, 5844, 5843, 5842, 5841, 5440, 5439, 5438, 5437, 5436, 5435, 5434, 5433, 5432, 5431, 5430, 5429, 5428, 5427, 5426, 5425, 5424, 5423, 5422, 5421, 5020, 5019, 5018, 5017, 5016, 5015, 5014, 5013, 5012, 5011, 5010, 5009, 5008, 5007, 5006, 5005, 5004, 5003, 5002, 5001, 4600, 4599, 4598, 4597, 4596, 4595, 4594, 4593, 4592, 4591, 4590, 4589, 4588, 4587, 4586, 4585, 4584, 4583, 4582, 4581, 4180, 4179, 4178, 4177, 4176, 4175, 4174, 4173, 4172, 4171, 4170, 4169, 4168, 4167, 4166, 4165, 4164, 4163, 4162, 4161, 3760, 3759, 3758, 3757, 3756, 3755, 3754, 3753, 3752, 3751, 3750, 3749, 3748, 3747, 3746, 3745, 3744, 3743, 3742, 3741, 3340, 3339, 3338, 3337, 3336, 3335, 3334, 3333, 3332, 3331, 3330, 3329, 3328, 3327, 3326, 3325, 3324, 3323, 3322, 3321, 2920, 2919, 2918, 2917, 2916, 2915, 2914, 2913, 2912, 2911, 2910, 2909, 2908, 2907, 2906, 2905, 2904, 2903, 2902, 2901, 2500, 2499, 2498, 2497, 2496, 2495, 2494, 2493, 2492, 2491, 2490, 2489, 2488, 2487, 2486, 2485, 2484, 2483, 2482, 2481, 2080, 2079, 2078, 2077, 2076, 2075, 2074, 2073, 2072, 2071, 2070, 2069, 2068, 2067, 2066, 2065, 2064, 2063, 2062, 2061, 1660, 1659, 1658, 1657, 1656, 1655, 1654, 1653, 1652, 1651, 1650, 1649, 1648, 1647, 1646, 1645, 1644, 1643, 1642, 1641, 1240, 1239, 1238, 1237, 1236, 1235, 1234, 1233, 1232, 1231, 1230, 1229, 1228, 1227, 1226, 1225, 1224, 1223, 1222, 1221, 820, 819, 818, 817, 816, 815, 814, 813, 812, 811, 810, 809, 808, 807, 806, 805, 804, 803, 802, 801, 400, 399, 398, 397, 396, 395, 394, 393, 392, 391, 390, 389, 388, 387, 386, 385, 384, 383, 382, 381]
Starting structural reductions, iteration 1 : 1006/1006 places, 10141/10461 transitions.
Applied a total of 0 rules in 4462 ms. Remains 1006 /1006 variables (removed 0) and now considering 10141/10141 (removed 0) transitions.
[2020-06-04 02:15:05] [INFO ] Flow matrix only has 2482 transitions (discarded 7659 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:15:05] [INFO ] Computed 85 place invariants in 41 ms
[2020-06-04 02:15:36] [INFO ] Performed 2429/10141 'is it Dead' test of which 0 returned DEAD in 30 seconds.
[2020-06-04 02:16:05] [INFO ] Dead Transitions with SMT raised an exceptionSMT solver raised an error when submitting script. Raised (error "Failed to assert expression: java.io.IOException: Broken pipe (and (>= s7 1) (>= s43 1) (>= s388 1) (>= s985 1))") after 60107 ms
Finished structural reductions, in 2 iterations. Remains : 1006/1006 places, 10141/10461 transitions.
Finished random walk after 0 steps, including 0 resets, run visited all 0 properties in 1 ms. (steps per millisecond=0 )
Finished probabilistic random walk after 0 steps, run visited all 0 properties in 0 ms. (steps per millisecond=0 )
[2020-06-04 02:16:06] [INFO ] Flow matrix only has 2482 transitions (discarded 7659 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:16:06] [INFO ] Computed 85 place invariants in 50 ms
Support contains 523 out of 1006 places. Attempting structural reductions.
Starting structural reductions, iteration 0 : 1006/1006 places, 10141/10141 transitions.
Applied a total of 0 rules in 4111 ms. Remains 1006 /1006 variables (removed 0) and now considering 10141/10141 (removed 0) transitions.
Finished structural reductions, in 1 iterations. Remains : 1006/1006 places, 10141/10141 transitions.
Starting structural reductions, iteration 0 : 1006/1006 places, 10141/10141 transitions.
Applied a total of 0 rules in 4147 ms. Remains 1006 /1006 variables (removed 0) and now considering 10141/10141 (removed 0) transitions.
[2020-06-04 02:16:14] [INFO ] Flow matrix only has 2482 transitions (discarded 7659 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:16:14] [INFO ] Computed 85 place invariants in 57 ms
[2020-06-04 02:16:25] [INFO ] Implicit Places using invariants in 10679 ms returned []
Implicit Place search using SMT only with invariants took 10709 ms to find 0 implicit places.
[2020-06-04 02:16:25] [INFO ] Flow matrix only has 2482 transitions (discarded 7659 similar events)
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:16:25] [INFO ] Computed 85 place invariants in 78 ms
[2020-06-04 02:16:55] [INFO ] Performed 2654/10141 'is it Dead' test of which 0 returned DEAD in 30 seconds.
[2020-06-04 02:17:25] [INFO ] Dead Transitions with SMT raised an exceptionSMT solver raised an error when submitting script. Raised (error "Failed to assert expression: java.io.IOException: Broken pipe (and (>= s14 1) (>= s54 1) (>= s295 1) (>= s985 1))") after 60138 ms
Finished structural reductions, in 1 iterations. Remains : 1006/1006 places, 10141/10141 transitions.
Finished random walk after 0 steps, including 0 resets, run visited all 0 properties in 1 ms. (steps per millisecond=0 )
Ensure Unique test removed 7659 transitions
Reduce isomorphic transitions removed 7659 transitions.
Iterating post reduction 0 with 7659 rules applied. Total rules applied 7659 place count 1006 transition count 2482
Applied a total of 7659 rules in 198 ms. Remains 1006 /1006 variables (removed 0) and now considering 2482/10141 (removed 7659) transitions.
// Phase 1: matrix 2482 rows 1006 cols
[2020-06-04 02:17:25] [INFO ] Computed 85 place invariants in 109 ms
[2020-06-04 02:17:25] [INFO ] Export to MCC properties in file /home/mcc/execution/UpperBounds.sr.xml took 9 ms.
[2020-06-04 02:17:25] [INFO ] Export to PNML in file /home/mcc/execution/model.sr.pnml took 201 ms.
info: Time: 3600 - MCC
vrfy: Checking UpperBounds @ SafeBus-COL-20 @ 3570 seconds

FORMULA SafeBus-COL-20-UpperBounds-00 1 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA SafeBus-COL-20-UpperBounds-01 1 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA SafeBus-COL-20-UpperBounds-09 1 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA SafeBus-COL-20-UpperBounds-14 1 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA SafeBus-COL-20-UpperBounds-13 20 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA SafeBus-COL-20-UpperBounds-15 20 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA SafeBus-COL-20-UpperBounds-12 1 TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT
TIME LIMIT: Killed by timeout after 3600 seconds
MemTotal: 16427332 kB
MemFree: 15567888 kB
After kill :
MemTotal: 16427332 kB
MemFree: 16125908 kB

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

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="SafeBus-COL-20"
export BK_EXAMINATION="UpperBounds"
export BK_TOOL="itslola"
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

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-4028"
echo " Executing tool itslola"
echo " Input is SafeBus-COL-20, examination is UpperBounds"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r186-oct2-158987938900373"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/SafeBus-COL-20.tgz
mv SafeBus-COL-20 execution
cd execution
if [ "UpperBounds" = "ReachabilityDeadlock" ] || [ "UpperBounds" = "UpperBounds" ] || [ "UpperBounds" = "QuasiLiveness" ] || [ "UpperBounds" = "StableMarking" ] || [ "UpperBounds" = "Liveness" ] || [ "UpperBounds" = "OneSafe" ] || [ "UpperBounds" = "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 [ "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 '' UpperBounds.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ "UpperBounds" = "ReachabilityDeadlock" ] || [ "UpperBounds" = "QuasiLiveness" ] || [ "UpperBounds" = "StableMarking" ] || [ "UpperBounds" = "Liveness" ] || [ "UpperBounds" = "OneSafe" ] ; then
echo "FORMULA_NAME UpperBounds"
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 ;