Execution Chart

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

Trace from the execution

Formatting '/data/fko/mcc2021-input.r159-oct2-162089268200304.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fko/mcc2021-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 itstools
Input is PolyORBLF-PT-S04J06T08, examination is StateSpace
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r159-oct2-162089268200304
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 7.0M
-rw-r--r-- 1 mcc users 86K May 5 16:58 CTLCardinality.txt
-rw-r--r-- 1 mcc users 352K May 10 09:43 CTLCardinality.xml
-rw-r--r-- 1 mcc users 244K May 5 16:58 CTLFireability.txt
-rw-r--r-- 1 mcc users 1.1M May 10 09:43 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K May 6 14:48 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 13K Mar 28 16:27 LTLCardinality.txt
-rw-r--r-- 1 mcc users 46K Mar 28 16:27 LTLCardinality.xml
-rw-r--r-- 1 mcc users 6.9K Mar 28 16:27 LTLFireability.txt
-rw-r--r-- 1 mcc users 35K Mar 28 16:27 LTLFireability.xml
-rw-r--r-- 1 mcc users 7.6K Mar 27 09:23 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 27K Mar 27 09:23 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 22K Mar 25 12:00 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 79K Mar 25 12:00 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 2.5K Mar 22 09:16 UpperBounds.txt
-rw-r--r-- 1 mcc users 5.3K Mar 22 09:16 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 May 5 16:51 equiv_col
-rw-r--r-- 1 mcc users 10 May 5 16:51 instance
-rw-r--r-- 1 mcc users 6 May 5 16:51 iscolored
-rw-r--r-- 1 mcc users 5.0M May 5 16:51 model.pnml

--------------------
content from stdout:

=== Data for post analysis generated by BenchKit (invocation template)

no data necessary for post analysis


=== Now, execution of the tool begins

BK_START 1621204266862

Running Version 0
[2021-05-16 22:31:10] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, StateSpace, -spotpath, /home/mcc/BenchKit/bin//..//ltlfilt, -z3path, /home/mcc/BenchKit/bin//..//z3/bin/z3, -yices2path, /home/mcc/BenchKit/bin//..//yices/bin/yices, -its, -ltsmin, -greatspnpath, /home/mcc/BenchKit/bin//..//greatspn/, -order, META, -manyOrder, -smt, -timeout, 3600]
[2021-05-16 22:31:10] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2021-05-16 22:31:10] [INFO ] Load time of PNML (sax parser for PT used): 372 ms
[2021-05-16 22:31:10] [INFO ] Transformed 792 places.
[2021-05-16 22:31:11] [INFO ] Transformed 4268 transitions.
[2021-05-16 22:31:11] [INFO ] Parsed PT model containing 792 places and 4268 transitions in 504 ms.
Ensure Unique test removed 2168 transitions
Reduce redundant transitions removed 2168 transitions.
Starting structural reductions, iteration 0 : 792/792 places, 2100/2100 transitions.
[2021-05-16 22:31:11] [INFO ] Flow matrix only has 2068 transitions (discarded 32 similar events)
// Phase 1: matrix 2068 rows 792 cols
[2021-05-16 22:31:11] [INFO ] Computed 58 place invariants in 133 ms
[2021-05-16 22:31:13] [INFO ] Dead Transitions using invariants and state equation in 2518 ms returned [42, 52, 54, 60, 73, 96, 97, 129, 141, 149, 162, 178, 203, 211, 215, 238, 276, 306, 333, 356, 408, 414, 427, 441, 452, 465, 468, 471, 474, 481, 499, 531, 533, 540, 559, 561, 567, 607, 626, 631, 660, 666, 667, 669, 690, 693, 700, 704, 708, 712, 720, 722, 725, 738, 771, 791, 793, 821, 834, 835, 837, 841, 845, 864, 866, 869, 876, 877, 878, 895, 913, 921, 929, 940, 943, 945, 955, 962, 965, 968, 980, 983, 984, 1005, 1026, 1038, 1040, 1056, 1073, 1080, 1084, 1088, 1091, 1092, 1094, 1098, 1100, 1102, 1106, 1107, 1113, 1116, 1117, 1126, 1138, 1145, 1149, 1154, 1165, 1166, 1168, 1171, 1177, 1178, 1184, 1185, 1193, 1196, 1197, 1200, 1204, 1206, 1214, 1216, 1221, 1231, 1233, 1244, 1248, 1257, 1265, 1266, 1273, 1275, 1278, 1279, 1280, 1283, 1284, 1293, 1298, 1301, 1302, 1305, 1307, 1309, 1310, 1317, 1318, 1319, 1321, 1323, 1325, 1335, 1337, 1339, 1342, 1364, 1366, 1368, 1372, 1373, 1378, 1379, 1381, 1389, 1392, 1400, 1402, 1406, 1407, 1411, 1412, 1415, 1417, 1422, 1425, 1434, 1441, 1443, 1445, 1449, 1451, 1453, 1456, 1462, 1463, 1465, 1466, 1467, 1473, 1480, 1481, 1493, 1513, 1514, 1521, 1529, 1534, 1538, 1540, 1541, 1543, 1553, 1563, 1568, 1569, 1574, 1580, 1582, 1591, 1592, 1594, 1596, 1598, 1604, 1606, 1609, 1610, 1613, 1621, 1623, 1624, 1628, 1629, 1632, 1635, 1636, 1637, 1638, 1640, 1641, 1643, 1647, 1657, 1659, 1660, 1663, 1665, 1667, 1668, 1669, 1674, 1675, 1682, 1683, 1685, 1686, 1689, 1690, 1691, 1692, 1696, 1704, 1706, 1708, 1710, 1711, 1714, 1716, 1717, 1719, 1722, 1723, 1724, 1727, 1731, 1732, 1733, 1734, 1739, 1741, 1742, 1743, 1744, 1745, 1748, 1757, 1763, 1765, 1767, 1769, 1770, 1773, 1776, 1778, 1786, 1787, 1790, 1794, 1795, 1797, 1800, 1801, 1811, 1812, 1814, 1815, 1817, 1818, 1828, 1830, 1831, 1833, 1836, 1837, 1839, 1840, 1841, 1842, 1845, 1846, 1847, 1849, 1851, 1852, 1861, 1867, 1868, 1869, 1870, 1875, 1876, 1878, 1880, 1882, 1884, 1885, 1888, 1889, 1890, 1891, 1896, 1897, 1898, 1899, 1900, 1902, 1904, 1906, 1907, 1910, 1911, 1912, 1914, 1918, 1920, 1921, 1922, 1925, 1929, 1930, 1931, 1933, 1935, 1936, 1938, 1939, 1944, 1945, 1950, 1952, 1955, 1956, 1960, 1962, 1968, 1977, 1978, 1980, 1982, 1983, 1984, 1987, 1988, 1992, 1994, 1995, 1996, 1998, 1999, 2004, 2005, 2006, 2008, 2009, 2013, 2014, 2015, 2018, 2025, 2026, 2028, 2029, 2033, 2034, 2042, 2043, 2051, 2052, 2053, 2056, 2059, 2060, 2061, 2062, 2063, 2064, 2065, 2066, 2067, 2068, 2069, 2070, 2071, 2072, 2073, 2074, 2077, 2078, 2079, 2080, 2081, 2082, 2085, 2087, 2089, 2091, 2094, 2096, 2097, 2098]
Found 432 dead transitions using SMT.
Drop transitions removed 432 transitions
Dead transitions reduction (with SMT) triggered by suspicious arc values removed 432 transitions :[2098, 2097, 2096, 2094, 2091, 2089, 2087, 2085, 2082, 2081, 2080, 2079, 2078, 2077, 2074, 2073, 2072, 2071, 2070, 2069, 2068, 2067, 2066, 2065, 2064, 2063, 2062, 2061, 2060, 2059, 2056, 2053, 2052, 2051, 2043, 2042, 2034, 2033, 2029, 2028, 2026, 2025, 2018, 2015, 2014, 2013, 2009, 2008, 2006, 2005, 2004, 1999, 1998, 1996, 1995, 1994, 1992, 1988, 1987, 1984, 1983, 1982, 1980, 1978, 1977, 1968, 1962, 1960, 1956, 1955, 1952, 1950, 1945, 1944, 1939, 1938, 1936, 1935, 1933, 1931, 1930, 1929, 1925, 1922, 1921, 1920, 1918, 1914, 1912, 1911, 1910, 1907, 1906, 1904, 1902, 1900, 1899, 1898, 1897, 1896, 1891, 1890, 1889, 1888, 1885, 1884, 1882, 1880, 1878, 1876, 1875, 1870, 1869, 1868, 1867, 1861, 1852, 1851, 1849, 1847, 1846, 1845, 1842, 1841, 1840, 1839, 1837, 1836, 1833, 1831, 1830, 1828, 1818, 1817, 1815, 1814, 1812, 1811, 1801, 1800, 1797, 1795, 1794, 1790, 1787, 1786, 1778, 1776, 1773, 1770, 1769, 1767, 1765, 1763, 1757, 1748, 1745, 1744, 1743, 1742, 1741, 1739, 1734, 1733, 1732, 1731, 1727, 1724, 1723, 1722, 1719, 1717, 1716, 1714, 1711, 1710, 1708, 1706, 1704, 1696, 1692, 1691, 1690, 1689, 1686, 1685, 1683, 1682, 1675, 1674, 1669, 1668, 1667, 1665, 1663, 1660, 1659, 1657, 1647, 1643, 1641, 1640, 1638, 1637, 1636, 1635, 1632, 1629, 1628, 1624, 1623, 1621, 1613, 1610, 1609, 1606, 1604, 1598, 1596, 1594, 1592, 1591, 1582, 1580, 1574, 1569, 1568, 1563, 1553, 1543, 1541, 1540, 1538, 1534, 1529, 1521, 1514, 1513, 1493, 1481, 1480, 1473, 1467, 1466, 1465, 1463, 1462, 1456, 1453, 1451, 1449, 1445, 1443, 1441, 1434, 1425, 1422, 1417, 1415, 1412, 1411, 1407, 1406, 1402, 1400, 1392, 1389, 1381, 1379, 1378, 1373, 1372, 1368, 1366, 1364, 1342, 1339, 1337, 1335, 1325, 1323, 1321, 1319, 1318, 1317, 1310, 1309, 1307, 1305, 1302, 1301, 1298, 1293, 1284, 1283, 1280, 1279, 1278, 1275, 1273, 1266, 1265, 1257, 1248, 1244, 1233, 1231, 1221, 1216, 1214, 1206, 1204, 1200, 1197, 1196, 1193, 1185, 1184, 1178, 1177, 1171, 1168, 1166, 1165, 1154, 1149, 1145, 1138, 1126, 1117, 1116, 1113, 1107, 1106, 1102, 1100, 1098, 1094, 1092, 1091, 1088, 1084, 1080, 1073, 1056, 1040, 1038, 1026, 1005, 984, 983, 980, 968, 965, 962, 955, 945, 943, 940, 929, 921, 913, 895, 878, 877, 876, 869, 866, 864, 845, 841, 837, 835, 834, 821, 793, 791, 771, 738, 725, 722, 720, 712, 708, 704, 700, 693, 690, 669, 667, 666, 660, 631, 626, 607, 567, 561, 559, 540, 533, 531, 499, 481, 474, 471, 468, 465, 452, 441, 427, 414, 408, 356, 333, 306, 276, 238, 215, 211, 203, 178, 162, 149, 141, 129, 97, 96, 73, 60, 54, 52, 42]
[2021-05-16 22:31:14] [INFO ] Redundant transitions in 84 ms returned []
[2021-05-16 22:31:14] [INFO ] Flow matrix only has 1636 transitions (discarded 32 similar events)
// Phase 1: matrix 1636 rows 792 cols
[2021-05-16 22:31:14] [INFO ] Computed 58 place invariants in 88 ms
[2021-05-16 22:31:16] [INFO ] Dead Transitions using invariants and state equation in 1989 ms returned []
Starting structural reductions, iteration 1 : 792/792 places, 1668/2100 transitions.
[2021-05-16 22:31:16] [INFO ] Redundant transitions in 79 ms returned []
[2021-05-16 22:31:16] [INFO ] Flow matrix only has 1636 transitions (discarded 32 similar events)
// Phase 1: matrix 1636 rows 792 cols
[2021-05-16 22:31:16] [INFO ] Computed 58 place invariants in 127 ms
[2021-05-16 22:31:18] [INFO ] Dead Transitions using invariants and state equation in 2495 ms returned []
Finished structural reductions, in 2 iterations. Remains : 792/792 places, 1668/2100 transitions.
Final net has 792 places and 1668 transitions.
[2021-05-16 22:31:19] [INFO ] Flatten gal took : 431 ms
[2021-05-16 22:31:19] [INFO ] Applying decomposition
[2021-05-16 22:31:19] [INFO ] Flatten gal took : 118 ms
Converted graph to binary with : CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202104292328/bin/convert-linux64, -i, /tmp/graph11311724515476274979.txt, -o, /tmp/graph11311724515476274979.bin, -w, /tmp/graph11311724515476274979.weights], workingDir=null]
Built communities with : CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202104292328/bin/louvain-linux64, /tmp/graph11311724515476274979.bin, -l, -1, -v, -w, /tmp/graph11311724515476274979.weights, -q, 0, -e, 0.001], workingDir=null]
[2021-05-16 22:31:20] [INFO ] Decomposing Gal with order
[2021-05-16 22:31:20] [INFO ] Rewriting arrays to variables to allow decomposition.
[2021-05-16 22:31:20] [INFO ] Removed a total of 1259 redundant transitions.
[2021-05-16 22:31:20] [INFO ] Flatten gal took : 309 ms
[2021-05-16 22:31:20] [INFO ] Fuse similar labels procedure discarded/fused a total of 186 labels/synchronizations in 42 ms.
[2021-05-16 22:31:20] [INFO ] Time to serialize gal into /tmp/StateSpace6516069494108965756.gal : 28 ms
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-reach-linux64, --gc-threshold, 2000000, --quiet, -i, /tmp/StateSpace6516069494108965756.gal, -t, CGAL, --stats], workingDir=/home/mcc/execution]

its-reach command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-reach-linux64 --gc-threshold 2000000 --quiet -i /tmp/StateSpace6516069494108965756.gal -t CGAL --stats
Detected timeout of ITS tools.
[2021-05-16 22:51:26] [INFO ] Flatten gal took : 1456 ms
[2021-05-16 22:51:26] [INFO ] Time to serialize gal into /tmp/StateSpace6152178651462593927.gal : 211 ms
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-reach-linux64, --gc-threshold, 2000000, --quiet, -i, /tmp/StateSpace6152178651462593927.gal, -t, CGAL, --stats, --gen-order, FOLLOW], workingDir=/home/mcc/execution]

its-reach command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-reach-linux64 --gc-threshold 2000000 --quiet -i /tmp/StateSpace6152178651462593927.gal -t CGAL --stats --gen-order FOLLOW
Detected timeout of ITS tools.
[2021-05-16 23:11:31] [INFO ] Flatten gal took : 1701 ms
[2021-05-16 23:11:32] [INFO ] Input system was already deterministic with 1668 transitions.
[2021-05-16 23:11:33] [INFO ] Transformed 792 places.
[2021-05-16 23:11:33] [INFO ] Transformed 1668 transitions.
Running greatSPN : CommandLine [args=[/home/mcc/BenchKit/bin//..//greatspn//bin/pinvar, /home/mcc/execution/gspn], workingDir=/home/mcc/execution]
P-invariant computation with GreatSPN timed out. Skipping.
Running greatSPN : CommandLine [args=[/home/mcc/BenchKit/bin//..//greatspn//bin/RGMEDD2, /home/mcc/execution/gspn, -META, -varord-only], workingDir=/home/mcc/execution]
Run of greatSPN captured in /home/mcc/execution/outPut.txt
Using order generated by GreatSPN with heuristic : META
[2021-05-16 23:12:03] [INFO ] Time to serialize gal into /tmp/StateSpace10393265827281766817.gal : 6 ms
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-reach-linux64, --gc-threshold, 2000000, --quiet, -i, /tmp/StateSpace10393265827281766817.gal, -t, CGAL, --stats, --load-order, /home/mcc/execution/model.ord, --gen-order, FOLLOW], workingDir=/home/mcc/execution]

its-reach command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202104292328/bin/its-reach-linux64 --gc-threshold 2000000 --quiet -i /tmp/StateSpace10393265827281766817.gal -t CGAL --stats --load-order /home/mcc/execution/model.ord --gen-order FOLLOW
Successfully loaded order from file /home/mcc/execution/model.ord

BK_TIME_CONFINEMENT_REACHED

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

+ export BINDIR=/home/mcc/BenchKit/bin//../
+ BINDIR=/home/mcc/BenchKit/bin//../
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ StateSpace = StateSpace ]]
+ [[ -f modelMain.xml ]]
+ /home/mcc/BenchKit/bin//..//runeclipse.sh /home/mcc/execution StateSpace -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//..//greatspn/ -order META -manyOrder -smt -timeout 3600
+ ulimit -s 65536
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
++ cut -d . -f 9
++ ls /home/mcc/BenchKit/bin//..//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202104292328.jar
+ VERSION=0
+ echo 'Running Version 0'
+ /home/mcc/BenchKit/bin//..//itstools/its-tools -data /home/mcc/execution/workspace -pnfolder /home/mcc/execution -examination StateSpace -spotpath /home/mcc/BenchKit/bin//..//ltlfilt -z3path /home/mcc/BenchKit/bin//..//z3/bin/z3 -yices2path /home/mcc/BenchKit/bin//..//yices/bin/yices -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//..//greatspn/ -order META -manyOrder -smt -timeout 3600 -vmargs -Dosgi.locking=none -Declipse.stateSaveDelayInterval=-1 -Dosgi.configuration.area=/tmp/.eclipse -Xss128m -Xms40m -Xmx16000m

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="PolyORBLF-PT-S04J06T08"
export BK_EXAMINATION="StateSpace"
export BK_TOOL="itstools"
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-4028"
echo " Executing tool itstools"
echo " Input is PolyORBLF-PT-S04J06T08, examination is StateSpace"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r159-oct2-162089268200304"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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