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.r187-oct2-158987942400261.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-PT-15, examination is ReachabilityDeadlock
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r187-oct2-158987942400261
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 7.9M
-rw-r--r-- 1 mcc users 16K Apr 12 12:09 CTLCardinality.txt
-rw-r--r-- 1 mcc users 63K Apr 12 12:09 CTLCardinality.xml
-rw-r--r-- 1 mcc users 376K Apr 11 13:35 CTLFireability.txt
-rw-r--r-- 1 mcc users 1.2M Apr 11 13:35 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 24 05:38 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 22K Apr 14 12:50 LTLCardinality.txt
-rw-r--r-- 1 mcc users 87K Apr 28 14:02 LTLCardinality.xml
-rw-r--r-- 1 mcc users 130K Apr 14 12:50 LTLFireability.txt
-rw-r--r-- 1 mcc users 405K Apr 28 14:02 LTLFireability.xml
-rw-r--r-- 1 mcc users 19K Apr 10 17:25 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 69K Apr 10 17:25 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 155K Apr 10 00:01 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 503K Apr 10 00:01 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 2.3K Apr 10 22:28 UpperBounds.txt
-rw-r--r-- 1 mcc users 5.4K Apr 10 22:28 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 Mar 24 05:38 equiv_col
-rw-r--r-- 1 mcc users 3 Mar 24 05:38 instance
-rw-r--r-- 1 mcc users 6 Mar 24 05:38 iscolored
-rw-r--r-- 1 mcc users 4.9M 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 booleans
BOOL_VECTOR

FORMULA_NAME ReachabilityDeadlock

=== Now, execution of the tool begins

BK_START 1591284516487

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
[2020-06-04 15:28:38] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, ReachabilityDeadlock, -z3path, /home/mcc/BenchKit//z3/bin/z3, -yices2path, /home/mcc/BenchKit//yices/bin/yices, -timeout, 1800, -rebuildPNML]
[2020-06-04 15:28:38] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2020-06-04 15:28:39] [INFO ] Load time of PNML (sax parser for PT used): 513 ms
[2020-06-04 15:28:39] [INFO ] Transformed 621 places.
[2020-06-04 15:28:39] [INFO ] Transformed 4771 transitions.
[2020-06-04 15:28:39] [INFO ] Parsed PT model containing 621 places and 4771 transitions in 580 ms.
Reduce places removed 15 places and 0 transitions.
Parsed 1 properties from file /home/mcc/execution/ReachabilityDeadlock.xml in 34 ms.
Working with output stream class java.io.PrintStream
Built sparse matrix representations for Structural reductions in 8 ms.26904KB memory used
Starting structural reductions, iteration 0 : 606/606 places, 4771/4771 transitions.
Applied a total of 0 rules in 1230 ms. Remains 606 /606 variables (removed 0) and now considering 4771/4771 (removed 0) transitions.
Finished structural reductions, in 1 iterations. Remains : 606/606 places, 4771/4771 transitions.
Starting structural reductions, iteration 0 : 606/606 places, 4771/4771 transitions.
Applied a total of 0 rules in 986 ms. Remains 606 /606 variables (removed 0) and now considering 4771/4771 (removed 0) transitions.
[2020-06-04 15:28:41] [INFO ] Flow matrix only has 1412 transitions (discarded 3359 similar events)
// Phase 1: matrix 1412 rows 606 cols
[2020-06-04 15:28:41] [INFO ] Computed 65 place invariants in 65 ms
[2020-06-04 15:28:45] [INFO ] Implicit Places using invariants in 3304 ms returned [546, 547, 548, 549, 550, 551, 552, 553, 554, 555, 556, 557, 558, 559, 560]
Discarding 15 places :
Implicit Place search using SMT only with invariants took 3353 ms to find 15 implicit places.
[2020-06-04 15:28:45] [INFO ] Redundant transitions in 278 ms returned []
[2020-06-04 15:28:45] [INFO ] Flow matrix only has 1412 transitions (discarded 3359 similar events)
// Phase 1: matrix 1412 rows 591 cols
[2020-06-04 15:28:45] [INFO ] Computed 50 place invariants in 54 ms
[2020-06-04 15:28:57] [INFO ] Dead Transitions using invariants and state equation in 12155 ms returned [211, 227, 243, 259, 275, 291, 307, 323, 339, 355, 371, 387, 403, 419, 435, 436, 452, 468, 484, 500, 516, 532, 548, 564, 580, 596, 612, 628, 644, 660, 661, 677, 693, 709, 725, 741, 757, 773, 789, 805, 821, 837, 853, 869, 885, 886, 902, 918, 934, 950, 966, 982, 998, 1014, 1030, 1046, 1062, 1078, 1094, 1110, 1111, 1127, 1143, 1159, 1175, 1191, 1207, 1223, 1239, 1255, 1271, 1287, 1303, 1319, 1335, 1336, 1352, 1368, 1384, 1400, 1416, 1432, 1448, 1464, 1480, 1496, 1512, 1528, 1544, 1560, 1561, 1577, 1593, 1609, 1625, 1641, 1657, 1673, 1689, 1705, 1721, 1737, 1753, 1769, 1785, 1786, 1802, 1818, 1834, 1850, 1866, 1882, 1898, 1914, 1930, 1946, 1962, 1978, 1994, 2010, 2011, 2027, 2043, 2059, 2075, 2091, 2107, 2123, 2139, 2155, 2171, 2187, 2203, 2219, 2235, 2236, 2252, 2268, 2284, 2300, 2316, 2332, 2348, 2364, 2380, 2396, 2412, 2428, 2444, 2460, 2461, 2477, 2493, 2509, 2525, 2541, 2557, 2573, 2589, 2605, 2621, 2637, 2653, 2669, 2685, 2686, 2702, 2718, 2734, 2750, 2766, 2782, 2798, 2814, 2830, 2846, 2862, 2878, 2894, 2910, 2911, 2927, 2943, 2959, 2975, 2991, 3007, 3023, 3039, 3055, 3071, 3087, 3103, 3119, 3135, 3136, 3152, 3168, 3184, 3200, 3216, 3232, 3248, 3264, 3280, 3296, 3312, 3328, 3344, 3360, 3361, 3377, 3393, 3409, 3425, 3441, 3457, 3473, 3489, 3505, 3521, 3537, 3553, 3569, 3585]
Found 225 dead transitions using SMT.
Drop transitions removed 225 transitions
Dead transitions reduction (with SMT) removed 225 transitions :[3585, 3569, 3553, 3537, 3521, 3505, 3489, 3473, 3457, 3441, 3425, 3409, 3393, 3377, 3361, 3360, 3344, 3328, 3312, 3296, 3280, 3264, 3248, 3232, 3216, 3200, 3184, 3168, 3152, 3136, 3135, 3119, 3103, 3087, 3071, 3055, 3039, 3023, 3007, 2991, 2975, 2959, 2943, 2927, 2911, 2910, 2894, 2878, 2862, 2846, 2830, 2814, 2798, 2782, 2766, 2750, 2734, 2718, 2702, 2686, 2685, 2669, 2653, 2637, 2621, 2605, 2589, 2573, 2557, 2541, 2525, 2509, 2493, 2477, 2461, 2460, 2444, 2428, 2412, 2396, 2380, 2364, 2348, 2332, 2316, 2300, 2284, 2268, 2252, 2236, 2235, 2219, 2203, 2187, 2171, 2155, 2139, 2123, 2107, 2091, 2075, 2059, 2043, 2027, 2011, 2010, 1994, 1978, 1962, 1946, 1930, 1914, 1898, 1882, 1866, 1850, 1834, 1818, 1802, 1786, 1785, 1769, 1753, 1737, 1721, 1705, 1689, 1673, 1657, 1641, 1625, 1609, 1593, 1577, 1561, 1560, 1544, 1528, 1512, 1496, 1480, 1464, 1448, 1432, 1416, 1400, 1384, 1368, 1352, 1336, 1335, 1319, 1303, 1287, 1271, 1255, 1239, 1223, 1207, 1191, 1175, 1159, 1143, 1127, 1111, 1110, 1094, 1078, 1062, 1046, 1030, 1014, 998, 982, 966, 950, 934, 918, 902, 886, 885, 869, 853, 837, 821, 805, 789, 773, 757, 741, 725, 709, 693, 677, 661, 660, 644, 628, 612, 596, 580, 564, 548, 532, 516, 500, 484, 468, 452, 436, 435, 419, 403, 387, 371, 355, 339, 323, 307, 291, 275, 259, 243, 227, 211]
Starting structural reductions, iteration 1 : 591/606 places, 4546/4771 transitions.
Performed 15 Pre agglomeration using Quasi-Persistent + Divergent Free condition..
Pre-agglomeration after 0 with 15 Pre rules applied. Total rules applied 0 place count 591 transition count 4531
Deduced a syphon composed of 15 places in 3 ms
Reduce places removed 15 places and 0 transitions.
Iterating global reduction 0 with 30 rules applied. Total rules applied 30 place count 576 transition count 4531
Applied a total of 30 rules in 1004 ms. Remains 576 /591 variables (removed 15) and now considering 4531/4546 (removed 15) transitions.
[2020-06-04 15:28:58] [INFO ] Flow matrix only has 1397 transitions (discarded 3134 similar events)
// Phase 1: matrix 1397 rows 576 cols
[2020-06-04 15:28:58] [INFO ] Computed 50 place invariants in 26 ms
[2020-06-04 15:29:03] [INFO ] Implicit Places using invariants in 5274 ms returned []
[2020-06-04 15:29:03] [INFO ] Flow matrix only has 1397 transitions (discarded 3134 similar events)
// Phase 1: matrix 1397 rows 576 cols
[2020-06-04 15:29:03] [INFO ] Computed 50 place invariants in 28 ms
[2020-06-04 15:29:06] [INFO ] State equation strengthened by 241 read => feed constraints.
[2020-06-04 15:29:31] [INFO ] Implicit Places using invariants and state equation in 28163 ms returned []
Implicit Place search using SMT with State Equation took 33456 ms to find 0 implicit places.
Finished structural reductions, in 2 iterations. Remains : 576/606 places, 4531/4771 transitions.
Random walk for 1250000 steps, including 0 resets, run took 11103 ms (no deadlock found). (steps per millisecond=112 )
Random directed walk for 1250001 steps, including 0 resets, run took 9362 ms (no deadlock found). (steps per millisecond=133 )
[2020-06-04 15:29:52] [INFO ] Flow matrix only has 1397 transitions (discarded 3134 similar events)
// Phase 1: matrix 1397 rows 576 cols
[2020-06-04 15:29:52] [INFO ] Computed 50 place invariants in 9 ms
[2020-06-04 15:29:53] [INFO ] [Real]Absence check using 19 positive place invariants in 19 ms returned sat
[2020-06-04 15:29:53] [INFO ] [Real]Absence check using 19 positive and 31 generalized place invariants in 47 ms returned sat
[2020-06-04 15:29:53] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2020-06-04 15:29:55] [INFO ] [Real]Absence check using state equation in 2186 ms returned sat
[2020-06-04 15:29:56] [INFO ] Solution in real domain found non-integer solution.
[2020-06-04 15:29:58] [INFO ] [Nat]Absence check using 19 positive place invariants in 70 ms returned sat
[2020-06-04 15:29:58] [INFO ] [Nat]Absence check using 19 positive and 31 generalized place invariants in 255 ms returned sat
[2020-06-04 15:29:58] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2020-06-04 15:30:00] [INFO ] [Nat]Absence check using state equation in 1867 ms returned sat
[2020-06-04 15:30:00] [INFO ] State equation strengthened by 241 read => feed constraints.
[2020-06-04 15:30:01] [INFO ] [Nat]Added 241 Read/Feed constraints in 297 ms returned sat
[2020-06-04 15:30:03] [INFO ] Deduced a trap composed of 74 places in 2890 ms
[2020-06-04 15:30:04] [INFO ] Deduced a trap composed of 61 places in 317 ms
[2020-06-04 15:30:04] [INFO ] Deduced a trap composed of 61 places in 353 ms
[2020-06-04 15:30:05] [INFO ] Deduced a trap composed of 61 places in 676 ms
[2020-06-04 15:30:05] [INFO ] Deduced a trap composed of 74 places in 326 ms
[2020-06-04 15:30:06] [INFO ] Deduced a trap composed of 61 places in 265 ms
[2020-06-04 15:30:08] [INFO ] Deduced a trap composed of 74 places in 2221 ms
[2020-06-04 15:30:09] [INFO ] Deduced a trap composed of 61 places in 1026 ms
[2020-06-04 15:30:12] [INFO ] Deduced a trap composed of 74 places in 2303 ms
[2020-06-04 15:30:13] [INFO ] Deduced a trap composed of 61 places in 1433 ms
[2020-06-04 15:30:14] [INFO ] Deduced a trap composed of 74 places in 395 ms
[2020-06-04 15:30:15] [INFO ] Deduced a trap composed of 61 places in 845 ms
[2020-06-04 15:30:15] [INFO ] Deduced a trap composed of 61 places in 431 ms
[2020-06-04 15:30:15] [INFO ] Trap strengthening (SAT) tested/added 14/13 trap constraints in 14812 ms
[2020-06-04 15:30:16] [INFO ] Computed and/alt/rep : 4516/176370/1382 causal constraints in 197 ms.
[2020-06-04 15:30:22] [INFO ] Added : 65 causal constraints over 13 iterations in 6676 ms. Result :sat
[2020-06-04 15:30:23] [INFO ] Deduced a trap composed of 74 places in 477 ms
[2020-06-04 15:30:24] [INFO ] Deduced a trap composed of 61 places in 971 ms
[2020-06-04 15:30:27] [INFO ] Deduced a trap composed of 74 places in 1204 ms
[2020-06-04 15:30:28] [INFO ] Deduced a trap composed of 61 places in 298 ms
[2020-06-04 15:30:29] [INFO ] Deduced a trap composed of 74 places in 445 ms
[2020-06-04 15:30:30] [INFO ] Deduced a trap composed of 61 places in 288 ms
[2020-06-04 15:30:30] [INFO ] Deduced a trap composed of 61 places in 236 ms
[2020-06-04 15:30:31] [INFO ] Deduced a trap composed of 61 places in 603 ms
[2020-06-04 15:30:33] [INFO ] Deduced a trap composed of 74 places in 216 ms
[2020-06-04 15:30:34] [INFO ] Deduced a trap composed of 61 places in 253 ms
[2020-06-04 15:30:34] [INFO ] Deduced a trap composed of 74 places in 189 ms
[2020-06-04 15:30:35] [INFO ] Deduced a trap composed of 61 places in 505 ms
[2020-06-04 15:30:35] [INFO ] Trap strengthening (SAT) tested/added 13/12 trap constraints in 13263 ms
Attempting to minimize the solution found.
Minimization took 166 ms.
Parikh directed walk for 192700 steps, including 1000 resets, run took 2358 ms. (steps per millisecond=81 )
Random directed walk for 500000 steps, including 0 resets, run took 3489 ms (no deadlock found). (steps per millisecond=143 )
Random walk for 500000 steps, including 0 resets, run took 4324 ms (no deadlock found). (steps per millisecond=115 )
Random directed walk for 500000 steps, including 0 resets, run took 3425 ms (no deadlock found). (steps per millisecond=145 )
Random walk for 500000 steps, including 0 resets, run took 4265 ms (no deadlock found). (steps per millisecond=117 )
[2020-06-04 15:30:54] [INFO ] Flatten gal took : 396 ms
[2020-06-04 15:30:54] [INFO ] Export to MCC properties in file /home/mcc/execution/ReachabilityDeadlock.sr.xml took 1 ms.
[2020-06-04 15:30:54] [INFO ] Export to PNML in file /home/mcc/execution/model.sr.pnml took 30 ms.
info: Time: 1800 - MCC
vrfy: Checking ReachabilityDeadlock @ SafeBus-PT-15 @ 1770 seconds
TIME LIMIT: Killed by timeout after 1800 seconds
MemTotal: 16427364 kB
MemFree: 15055636 kB
After kill :
MemTotal: 16427364 kB
MemFree: 16142720 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-PT-15"
export BK_EXAMINATION="ReachabilityDeadlock"
export BK_TOOL="itslola"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="1800"
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-PT-15, examination is ReachabilityDeadlock"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r187-oct2-158987942400261"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/SafeBus-PT-15.tgz
mv SafeBus-PT-15 execution
cd execution
if [ "ReachabilityDeadlock" = "ReachabilityDeadlock" ] || [ "ReachabilityDeadlock" = "UpperBounds" ] || [ "ReachabilityDeadlock" = "QuasiLiveness" ] || [ "ReachabilityDeadlock" = "StableMarking" ] || [ "ReachabilityDeadlock" = "Liveness" ] || [ "ReachabilityDeadlock" = "OneSafe" ] || [ "ReachabilityDeadlock" = "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 [ "ReachabilityDeadlock" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "ReachabilityDeadlock" != "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 "ReachabilityDeadlock.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property ReachabilityDeadlock.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "ReachabilityDeadlock.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 '' ReachabilityDeadlock.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ "ReachabilityDeadlock" = "ReachabilityDeadlock" ] || [ "ReachabilityDeadlock" = "QuasiLiveness" ] || [ "ReachabilityDeadlock" = "StableMarking" ] || [ "ReachabilityDeadlock" = "Liveness" ] || [ "ReachabilityDeadlock" = "OneSafe" ] ; then
echo "FORMULA_NAME ReachabilityDeadlock"
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 ;