About the Execution of ITS-Tools for Philosophers-PT-000500
Execution Chart
We display below the execution chart for this examination (boot time has been removed).
Trace from the execution
Formatting '/data/fkordon/mcc2022-input.r168-tall-165277016500333.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2022-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 Philosophers-PT-000500, examination is UpperBounds
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r168-tall-165277016500333
=====================================================================
--------------------
preparation of the directory to be used:
/home/mcc/execution
total 7.5M
-rw-r--r-- 1 mcc users 331K Apr 29 12:01 CTLCardinality.txt
-rw-r--r-- 1 mcc users 1.8M Apr 29 12:01 CTLCardinality.xml
-rw-r--r-- 1 mcc users 278K Apr 29 11:45 CTLFireability.txt
-rw-r--r-- 1 mcc users 1.7M Apr 29 11:45 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K May 10 09:34 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 238K May 9 08:26 LTLCardinality.txt
-rw-r--r-- 1 mcc users 895K May 9 08:26 LTLCardinality.xml
-rw-r--r-- 1 mcc users 195K May 9 08:26 LTLFireability.txt
-rw-r--r-- 1 mcc users 884K May 9 08:26 LTLFireability.xml
-rw-r--r-- 1 mcc users 46K May 9 08:26 UpperBounds.txt
-rw-r--r-- 1 mcc users 130K May 9 08:26 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 May 10 09:34 equiv_col
-rw-r--r-- 1 mcc users 7 May 10 09:34 instance
-rw-r--r-- 1 mcc users 6 May 10 09:34 iscolored
-rw-r--r-- 1 mcc users 1.1M May 10 09:34 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 Philosophers-PT-000500-UpperBounds-00
FORMULA_NAME Philosophers-PT-000500-UpperBounds-01
FORMULA_NAME Philosophers-PT-000500-UpperBounds-02
FORMULA_NAME Philosophers-PT-000500-UpperBounds-03
FORMULA_NAME Philosophers-PT-000500-UpperBounds-04
FORMULA_NAME Philosophers-PT-000500-UpperBounds-05
FORMULA_NAME Philosophers-PT-000500-UpperBounds-06
FORMULA_NAME Philosophers-PT-000500-UpperBounds-07
FORMULA_NAME Philosophers-PT-000500-UpperBounds-08
FORMULA_NAME Philosophers-PT-000500-UpperBounds-09
FORMULA_NAME Philosophers-PT-000500-UpperBounds-10
FORMULA_NAME Philosophers-PT-000500-UpperBounds-11
FORMULA_NAME Philosophers-PT-000500-UpperBounds-12
FORMULA_NAME Philosophers-PT-000500-UpperBounds-13
FORMULA_NAME Philosophers-PT-000500-UpperBounds-14
FORMULA_NAME Philosophers-PT-000500-UpperBounds-15
=== Now, execution of the tool begins
BK_START 1652826506728
Running Version 202205111006
[2022-05-17 22:28:27] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, UpperBounds, -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]
[2022-05-17 22:28:27] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2022-05-17 22:28:28] [INFO ] Load time of PNML (sax parser for PT used): 241 ms
[2022-05-17 22:28:28] [INFO ] Transformed 2500 places.
[2022-05-17 22:28:28] [INFO ] Transformed 2500 transitions.
[2022-05-17 22:28:28] [INFO ] Parsed PT model containing 2500 places and 2500 transitions in 359 ms.
Parsed 16 properties from file /home/mcc/execution/UpperBounds.xml in 35 ms.
// Phase 1: matrix 2500 rows 2500 cols
[2022-05-17 22:28:28] [INFO ] Computed 1000 place invariants in 38 ms
FORMULA Philosophers-PT-000500-UpperBounds-15 1 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-14 1 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-10 1 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-09 1 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-08 1 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-07 500 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-06 500 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-05 500 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-04 500 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-02 500 TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA Philosophers-PT-000500-UpperBounds-00 500 TECHNIQUES TOPOLOGICAL INITIAL_STATE
Incomplete random walk after 10000 steps, including 2 resets, run finished after 789 ms. (steps per millisecond=12 ) properties (out of 5) seen :298
FORMULA Philosophers-PT-000500-UpperBounds-13 1 TECHNIQUES TOPOLOGICAL RANDOM_WALK
FORMULA Philosophers-PT-000500-UpperBounds-12 1 TECHNIQUES TOPOLOGICAL RANDOM_WALK
FORMULA Philosophers-PT-000500-UpperBounds-11 1 TECHNIQUES TOPOLOGICAL RANDOM_WALK
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 255 ms. (steps per millisecond=39 ) properties (out of 2) seen :3
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 245 ms. (steps per millisecond=40 ) properties (out of 2) seen :4
// Phase 1: matrix 2500 rows 2500 cols
[2022-05-17 22:28:29] [INFO ] Computed 1000 place invariants in 18 ms
[2022-05-17 22:28:30] [INFO ] [Real]Absence check using 1000 positive place invariants in 270 ms returned sat
[2022-05-17 22:28:30] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2022-05-17 22:28:31] [INFO ] [Real]Absence check using state equation in 1201 ms returned sat
[2022-05-17 22:28:31] [INFO ] Solution in real domain found non-integer solution.
[2022-05-17 22:28:32] [INFO ] [Nat]Absence check using 1000 positive place invariants in 243 ms returned sat
[2022-05-17 22:28:32] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2022-05-17 22:28:33] [INFO ] [Nat]Absence check using state equation in 1056 ms returned sat
[2022-05-17 22:28:33] [INFO ] Computed and/alt/rep : 1500/2000/1500 causal constraints (skipped 0 transitions) in 130 ms.
[2022-05-17 22:28:35] [INFO ] Added : 84 causal constraints over 17 iterations in 2312 ms. Result :sat
Minimization took 571 ms.
[2022-05-17 22:28:36] [INFO ] [Real]Absence check using 1000 positive place invariants in 247 ms returned sat
[2022-05-17 22:28:36] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2022-05-17 22:28:37] [INFO ] [Real]Absence check using state equation in 973 ms returned sat
[2022-05-17 22:28:37] [INFO ] Solution in real domain found non-integer solution.
[2022-05-17 22:28:38] [INFO ] [Nat]Absence check using 1000 positive place invariants in 234 ms returned sat
[2022-05-17 22:28:38] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2022-05-17 22:28:39] [INFO ] [Nat]Absence check using state equation in 959 ms returned sat
[2022-05-17 22:28:39] [INFO ] Computed and/alt/rep : 1500/2000/1500 causal constraints (skipped 0 transitions) in 106 ms.
[2022-05-17 22:28:41] [INFO ] Added : 67 causal constraints over 14 iterations in 1810 ms. Result :sat
Minimization took 336 ms.
Current structural bounds on expressions (after SMT) : [500, 500] Max seen :[83, 212]
FORMULA Philosophers-PT-000500-UpperBounds-03 500 TECHNIQUES TOPOLOGICAL PARIKH_WALK
Support contains 500 out of 2500 places. Attempting structural reductions.
Starting structural reductions in SAFETY mode, iteration 0 : 2500/2500 places, 2500/2500 transitions.
Free-agglomeration rule (complex) applied 1000 times.
Iterating global reduction 0 with 1000 rules applied. Total rules applied 1000 place count 2500 transition count 1500
Reduce places removed 1000 places and 0 transitions.
Ensure Unique test removed 500 transitions
Reduce isomorphic transitions removed 500 transitions.
Iterating post reduction 0 with 1500 rules applied. Total rules applied 2500 place count 1500 transition count 1000
Applied a total of 2500 rules in 573 ms. Remains 1500 /2500 variables (removed 1000) and now considering 1000/2500 (removed 1500) transitions.
Finished structural reductions, in 1 iterations. Remains : 1500/2500 places, 1000/2500 transitions.
// Phase 1: matrix 1000 rows 1500 cols
[2022-05-17 22:28:52] [INFO ] Computed 1000 place invariants in 4 ms
Finished random walk after 423619 steps, including 1 resets, run visited all 1 properties in 30001 ms. (steps per millisecond=14 )
Finished Best-First random walk after 165248 steps, including 0 resets, run visited all 1 properties in 5006 ms. (steps per millisecond=33 )
// Phase 1: matrix 1000 rows 1500 cols
[2022-05-17 22:29:27] [INFO ] Computed 1000 place invariants in 7 ms
[2022-05-17 22:29:28] [INFO ] [Real]Absence check using 1000 positive place invariants in 269 ms returned sat
[2022-05-17 22:29:28] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2022-05-17 22:29:28] [INFO ] [Real]Absence check using state equation in 454 ms returned sat
[2022-05-17 22:29:28] [INFO ] Solution in real domain found non-integer solution.
[2022-05-17 22:29:29] [INFO ] [Nat]Absence check using 1000 positive place invariants in 275 ms returned sat
[2022-05-17 22:29:29] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2022-05-17 22:29:29] [INFO ] [Nat]Absence check using state equation in 432 ms returned sat
[2022-05-17 22:29:29] [INFO ] Computed and/alt/rep : 500/500/500 causal constraints (skipped 0 transitions) in 39 ms.
[2022-05-17 22:29:29] [INFO ] Added : 0 causal constraints over 0 iterations in 147 ms. Result :sat
Minimization took 218 ms.
Current structural bounds on expressions (after SMT) : [500] Max seen :[245]
Support contains 500 out of 1500 places. Attempting structural reductions.
Starting structural reductions in SAFETY mode, iteration 0 : 1500/1500 places, 1000/1000 transitions.
Applied a total of 0 rules in 51 ms. Remains 1500 /1500 variables (removed 0) and now considering 1000/1000 (removed 0) transitions.
Finished structural reductions, in 1 iterations. Remains : 1500/1500 places, 1000/1000 transitions.
Starting structural reductions in SAFETY mode, iteration 0 : 1500/1500 places, 1000/1000 transitions.
Applied a total of 0 rules in 62 ms. Remains 1500 /1500 variables (removed 0) and now considering 1000/1000 (removed 0) transitions.
// Phase 1: matrix 1000 rows 1500 cols
[2022-05-17 22:29:31] [INFO ] Computed 1000 place invariants in 5 ms
[2022-05-17 22:29:32] [INFO ] Implicit Places using invariants in 905 ms returned [0, 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 19, 20, 21, 22, 23, 24, 25, 26, 27, 28, 29, 30, 31, 32, 33, 34, 35, 36, 37, 38, 39, 40, 41, 42, 43, 44, 45, 46, 47, 48, 49, 50, 51, 52, 53, 54, 55, 56, 57, 58, 59, 60, 61, 62, 63, 64, 65, 66, 67, 68, 69, 70, 71, 72, 73, 74, 75, 76, 77, 78, 79, 80, 81, 82, 83, 84, 85, 86, 87, 88, 89, 90, 91, 92, 93, 94, 95, 96, 97, 98, 99, 100, 101, 102, 103, 104, 105, 106, 107, 108, 109, 110, 111, 112, 113, 114, 115, 116, 117, 118, 119, 120, 121, 122, 123, 124, 125, 126, 127, 128, 129, 130, 131, 132, 133, 134, 135, 136, 137, 138, 139, 140, 141, 142, 143, 144, 145, 146, 147, 148, 149, 150, 151, 152, 153, 154, 155, 156, 157, 158, 159, 160, 161, 162, 163, 164, 165, 166, 167, 168, 169, 170, 171, 172, 173, 174, 175, 176, 177, 178, 179, 180, 181, 182, 183, 184, 185, 186, 187, 188, 189, 190, 191, 192, 193, 194, 195, 196, 197, 198, 199, 200, 201, 202, 203, 204, 205, 206, 207, 208, 209, 210, 211, 212, 213, 214, 215, 216, 217, 218, 219, 220, 221, 222, 223, 224, 225, 226, 227, 228, 229, 230, 231, 232, 233, 234, 235, 236, 237, 238, 239, 240, 241, 242, 243, 244, 245, 246, 247, 248, 249, 250, 251, 252, 253, 254, 255, 256, 257, 258, 259, 260, 261, 262, 263, 264, 265, 266, 267, 268, 269, 270, 271, 272, 273, 274, 275, 276, 277, 278, 279, 280, 281, 282, 283, 284, 285, 286, 287, 288, 289, 290, 291, 292, 293, 294, 295, 296, 297, 298, 299, 300, 301, 302, 303, 304, 305, 306, 307, 308, 309, 310, 311, 312, 313, 314, 315, 316, 317, 318, 319, 320, 321, 322, 323, 324, 325, 326, 327, 328, 329, 330, 331, 332, 333, 334, 335, 336, 337, 338, 339, 340, 341, 342, 343, 344, 345, 346, 347, 348, 349, 350, 351, 352, 353, 354, 355, 356, 357, 358, 359, 360, 361, 362, 363, 364, 365, 366, 367, 368, 369, 370, 371, 372, 373, 374, 375, 376, 377, 378, 379, 380, 381, 382, 383, 384, 385, 386, 387, 388, 389, 390, 391, 392, 393, 394, 395, 396, 397, 398, 399, 400, 401, 402, 403, 404, 405, 406, 407, 408, 409, 410, 411, 412, 413, 414, 415, 416, 417, 418, 419, 420, 421, 422, 423, 424, 425, 426, 427, 428, 429, 430, 431, 432, 433, 434, 435, 436, 437, 438, 439, 440, 441, 442, 443, 444, 445, 446, 447, 448, 449, 450, 451, 452, 453, 454, 455, 456, 457, 458, 459, 460, 461, 462, 463, 464, 465, 466, 467, 468, 469, 470, 471, 472, 473, 474, 475, 476, 485, 486, 487, 489, 490, 491, 492, 493, 494, 495, 496, 497, 498, 499, 500, 501, 502, 503, 504, 505, 506, 507, 508]
Discarding 500 places :
Implicit Place search using SMT only with invariants took 916 ms to find 500 implicit places.
Starting structural reductions in SAFETY mode, iteration 1 : 1000/1500 places, 1000/1000 transitions.
Applied a total of 0 rules in 25 ms. Remains 1000 /1000 variables (removed 0) and now considering 1000/1000 (removed 0) transitions.
Finished structural reductions, in 2 iterations. Remains : 1000/1500 places, 1000/1000 transitions.
// Phase 1: matrix 1000 rows 1000 cols
[2022-05-17 22:29:32] [INFO ] Computed 500 place invariants in 3 ms
Finished random walk after 575416 steps, including 1 resets, run visited all 1 properties in 30001 ms. (steps per millisecond=19 )
Finished Best-First random walk after 213460 steps, including 0 resets, run visited all 1 properties in 5001 ms. (steps per millisecond=42 )
// Phase 1: matrix 1000 rows 1000 cols
[2022-05-17 22:30:07] [INFO ] Computed 500 place invariants in 7 ms
[2022-05-17 22:30:07] [INFO ] [Real]Absence check using 500 positive place invariants in 143 ms returned unsat
Current structural bounds on expressions (after SMT) : [250] Max seen :[250]
FORMULA Philosophers-PT-000500-UpperBounds-01 250 TECHNIQUES TOPOLOGICAL SAT_SMT RANDOM_WALK
All properties solved without resorting to model-checking.
Total runtime 99996 ms.
BK_STOP 1652826607886
--------------------
content from stderr:
+ export LANG=C
+ LANG=C
+ export BINDIR=/home/mcc/BenchKit/bin//../
+ BINDIR=/home/mcc/BenchKit/bin//../
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ UpperBounds = StateSpace ]]
+ /home/mcc/BenchKit/bin//..//runeclipse.sh /home/mcc/execution UpperBounds -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//..//greatspn/ -order META -manyOrder -smt -timeout 3600
+ ulimit -s 65536
+ export PYTHONPATH=/usr/lib/python3.9/site-packages/
+ PYTHONPATH=/usr/lib/python3.9/site-packages/
+ export LD_LIBRARY_PATH=/usr/local/lib:
+ LD_LIBRARY_PATH=/usr/local/lib:
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
++ sed s/.jar//
++ perl -pe 's/.*\.//g'
++ ls /home/mcc/BenchKit/bin//..//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202205111006.jar
+ VERSION=202205111006
+ echo 'Running Version 202205111006'
+ /home/mcc/BenchKit/bin//..//itstools/its-tools -data @none -pnfolder /home/mcc/execution -examination UpperBounds -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=@none -Xss128m -Xms40m -Xmx8192m
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="Philosophers-PT-000500"
export BK_EXAMINATION="UpperBounds"
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 Philosophers-PT-000500, 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 r168-tall-165277016500333"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"
tar xzf /home/mcc/BenchKit/INPUTS/Philosophers-PT-000500.tgz
mv Philosophers-PT-000500 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 '
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 ;