Execution Chart

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

Trace from the execution

Formatting '/data/fkordon/mcc2021-input.r299-tall-162124165100367.qcow2', fmt=qcow2 size=4294967296 backing_file='/data/fkordon/mcc2021-input.qcow2' encryption=off cluster_size=65536 lazy_refcounts=off
Waiting for the VM to be ready (probing ssh)
..............................................................................................................................................................................................................................................................................................................................................
=====================================================================
Generated by BenchKit 2-4028
Executing tool itstools
Input is MultiCrashLeafsetExtension-PT-S16C04, examination is QuasiLiveness
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r299-tall-162124165100367
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 8.5M
-rw-r--r-- 1 mcc users 53K May 15 08:57 CTLCardinality.txt
-rw-r--r-- 1 mcc users 185K May 15 08:57 CTLCardinality.xml
-rw-r--r-- 1 mcc users 31K May 15 08:57 CTLFireability.txt
-rw-r--r-- 1 mcc users 99K May 15 08:57 CTLFireability.xml
-rw-r--r-- 1 mcc users 7.0K May 12 07:58 LTLCardinality.txt
-rw-r--r-- 1 mcc users 31K May 12 07:58 LTLCardinality.xml
-rw-r--r-- 1 mcc users 4.3K May 12 07:58 LTLFireability.txt
-rw-r--r-- 1 mcc users 19K May 12 07:58 LTLFireability.xml
-rw-r--r-- 1 mcc users 1 May 12 08:13 NewModel
-rw-r--r-- 1 mcc users 4.4K May 11 18:49 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 16K May 11 18:49 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 4.5K May 11 15:52 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 16K May 11 15:52 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.9K May 12 04:40 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.9K May 12 04:40 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 May 12 08:13 equiv_col
-rw-r--r-- 1 mcc users 7 May 12 08:13 instance
-rw-r--r-- 1 mcc users 6 May 12 08:13 iscolored
-rw-r--r-- 1 mcc users 8.0M May 12 08:13 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 QuasiLiveness

=== Now, execution of the tool begins

BK_START 1621322044120

Running Version 0
[2021-05-18 07:14:05] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, QuasiLiveness, -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, 1800]
[2021-05-18 07:14:05] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2021-05-18 07:14:06] [INFO ] Load time of PNML (sax parser for PT used): 314 ms
[2021-05-18 07:14:06] [INFO ] Transformed 4260 places.
[2021-05-18 07:14:06] [INFO ] Transformed 6027 transitions.
[2021-05-18 07:14:06] [INFO ] Parsed PT model containing 4260 places and 6027 transitions in 407 ms.
Starting structural reductions, iteration 0 : 4260/4260 places, 6027/6027 transitions.
Reduce places removed 28 places and 0 transitions.
Drop transitions removed 1088 transitions
Trivial Post-agglo rules discarded 1088 transitions
Performed 1088 trivial Post agglomeration. Transition count delta: 1088
Iterating post reduction 0 with 1116 rules applied. Total rules applied 1116 place count 4232 transition count 4939
Reduce places removed 1088 places and 0 transitions.
Iterating post reduction 1 with 1088 rules applied. Total rules applied 2204 place count 3144 transition count 4939
Performed 136 Pre agglomeration using Quasi-Persistent + Divergent Free condition..
Pre-agglomeration after 2 with 136 Pre rules applied. Total rules applied 2204 place count 3144 transition count 4803
Deduced a syphon composed of 136 places in 17 ms
Reduce places removed 136 places and 0 transitions.
Iterating global reduction 2 with 272 rules applied. Total rules applied 2476 place count 3008 transition count 4803
Discarding 952 places :
Symmetric choice reduction at 2 with 952 rule applications. Total rules 3428 place count 2056 transition count 3851
Iterating global reduction 2 with 952 rules applied. Total rules applied 4380 place count 2056 transition count 3851
Performed 8 Pre agglomeration using Quasi-Persistent + Divergent Free condition..
Pre-agglomeration after 2 with 8 Pre rules applied. Total rules applied 4380 place count 2056 transition count 3843
Deduced a syphon composed of 8 places in 6 ms
Reduce places removed 8 places and 0 transitions.
Iterating global reduction 2 with 16 rules applied. Total rules applied 4396 place count 2048 transition count 3843
Discarding 238 places :
Symmetric choice reduction at 2 with 238 rule applications. Total rules 4634 place count 1810 transition count 2891
Iterating global reduction 2 with 238 rules applied. Total rules applied 4872 place count 1810 transition count 2891
Performed 17 Post agglomeration using F-continuation condition.Transition count delta: 17
Deduced a syphon composed of 17 places in 2 ms
Reduce places removed 17 places and 0 transitions.
Iterating global reduction 2 with 34 rules applied. Total rules applied 4906 place count 1793 transition count 2874
Applied a total of 4906 rules in 1079 ms. Remains 1793 /4260 variables (removed 2467) and now considering 2874/6027 (removed 3153) transitions.
[2021-05-18 07:14:07] [INFO ] Flow matrix only has 2602 transitions (discarded 272 similar events)
// Phase 1: matrix 2602 rows 1793 cols
[2021-05-18 07:14:07] [INFO ] Computed 469 place invariants in 146 ms
[2021-05-18 07:14:09] [INFO ] Implicit Places using invariants in 2543 ms returned [4, 7, 11, 12, 13, 14, 15, 20, 27, 36, 47, 60, 75, 91, 107, 108, 109, 110, 111, 124, 135, 144, 151, 156, 159, 170, 176, 179, 180, 181, 182, 183, 186, 191, 198, 207, 218, 231, 245, 259, 260, 261, 262, 263, 274, 283, 290, 295, 298, 300, 311, 317, 320, 321, 322, 323, 324, 326, 329, 334, 341, 350, 361, 373, 385, 386, 387, 388, 389, 398, 405, 410, 413, 415, 417, 428, 434, 437, 438, 439, 440, 441, 443, 445, 448, 453, 460, 469, 479, 489, 490, 491, 492, 493, 500, 505, 508, 510, 512, 514, 525, 531, 534, 535, 536, 537, 538, 540, 542, 544, 547, 552, 559, 567, 575, 576, 577, 578, 579, 584, 587, 589, 591, 593, 595, 606, 612, 615, 616, 617, 618, 619, 621, 623, 625, 627, 630, 635, 641, 647, 648, 649, 650, 651, 654, 656, 658, 660, 662, 664, 675, 681, 684, 685, 686, 687, 688, 690, 692, 694, 696, 698, 701, 705, 709, 710, 711, 712, 713, 715, 717, 719, 721, 723, 725, 736, 742, 745, 746, 747, 748, 749, 751, 753, 755, 757, 759, 761, 763, 766, 767, 768, 769, 770, 772, 774, 776, 778, 780, 782, 793, 795, 798, 801, 802, 803, 804, 805, 807, 809, 811, 813, 815, 817, 819, 822, 823, 824, 825, 826, 828, 830, 832, 834, 836, 838, 849, 855, 858, 859, 860, 861, 862, 864, 866, 868, 870, 872, 874, 876, 879, 880, 881, 882, 883, 885, 887, 889, 891, 893, 895, 906, 912, 915, 916, 917, 918, 919, 921, 923, 925, 927, 929, 931, 934, 939, 940, 941, 942, 943, 946, 948, 950, 952, 954, 956, 967, 973, 976, 977, 978, 979, 980, 982, 984, 986, 988, 990, 993, 998, 1005, 1006, 1007, 1008, 1009, 1014, 1017, 1019, 1021, 1023, 1025, 1036, 1042, 1045, 1046, 1047, 1048, 1049, 1051, 1053, 1055, 1057, 1060, 1065, 1072, 1081, 1082, 1083, 1084, 1085, 1092, 1097, 1100, 1102, 1104, 1106, 1117, 1123, 1126, 1127, 1128, 1129, 1130, 1132, 1134, 1136, 1139, 1144, 1151, 1160, 1171, 1172, 1173, 1174, 1175, 1184, 1191, 1196, 1199, 1201, 1203, 1214, 1220, 1223, 1224, 1225, 1226, 1227, 1229, 1231, 1234, 1239, 1246, 1255, 1266, 1279, 1280, 1281, 1282, 1283, 1294, 1303, 1310, 1315, 1318, 1320, 1331, 1337, 1340, 1341, 1342, 1343, 1344, 1346, 1349, 1354, 1361, 1370, 1381, 1394, 1409, 1410, 1411, 1412, 1413, 1426, 1437, 1446, 1453, 1458, 1461, 1472, 1474, 1477, 1480, 1481, 1482, 1483, 1484, 1487, 1492, 1499, 1508, 1519, 1532, 1547, 1564, 1565, 1566, 1567, 1568, 1583, 1596, 1607, 1616, 1623, 1628, 1640]
Discarding 428 places :
Implicit Place search using SMT only with invariants took 2590 ms to find 428 implicit places.
[2021-05-18 07:14:09] [INFO ] Flow matrix only has 2602 transitions (discarded 272 similar events)
// Phase 1: matrix 2602 rows 1365 cols
[2021-05-18 07:14:09] [INFO ] Computed 41 place invariants in 98 ms
[2021-05-18 07:14:11] [INFO ] Dead Transitions using invariants and state equation in 1392 ms returned []
Starting structural reductions, iteration 1 : 1365/4260 places, 2874/6027 transitions.
Discarding 128 places :
Symmetric choice reduction at 0 with 128 rule applications. Total rules 128 place count 1237 transition count 2746
Iterating global reduction 0 with 128 rules applied. Total rules applied 256 place count 1237 transition count 2746
Discarding 128 places :
Symmetric choice reduction at 0 with 128 rule applications. Total rules 384 place count 1109 transition count 2618
Iterating global reduction 0 with 128 rules applied. Total rules applied 512 place count 1109 transition count 2618
Discarding 32 places :
Symmetric choice reduction at 0 with 32 rule applications. Total rules 544 place count 1077 transition count 2490
Iterating global reduction 0 with 32 rules applied. Total rules applied 576 place count 1077 transition count 2490
Applied a total of 576 rules in 340 ms. Remains 1077 /1365 variables (removed 288) and now considering 2490/2874 (removed 384) transitions.
[2021-05-18 07:14:11] [INFO ] Flow matrix only has 2218 transitions (discarded 272 similar events)
// Phase 1: matrix 2218 rows 1077 cols
[2021-05-18 07:14:11] [INFO ] Computed 41 place invariants in 23 ms
[2021-05-18 07:14:12] [INFO ] Implicit Places using invariants in 933 ms returned []
[2021-05-18 07:14:12] [INFO ] Flow matrix only has 2218 transitions (discarded 272 similar events)
// Phase 1: matrix 2218 rows 1077 cols
[2021-05-18 07:14:12] [INFO ] Computed 41 place invariants in 21 ms
[2021-05-18 07:14:13] [INFO ] State equation strengthened by 841 read => feed constraints.
[2021-05-18 07:14:13] [INFO ] Implicit Places using invariants and state equation in 1000 ms returned []
Implicit Place search using SMT with State Equation took 1936 ms to find 0 implicit places.
Finished structural reductions, in 2 iterations. Remains : 1077/4260 places, 2490/6027 transitions.
[2021-05-18 07:14:13] [INFO ] Initial state test concluded for 17 properties.
Incomplete random walk after 10000 steps, including 338 resets, run finished after 1188 ms. (steps per millisecond=8 ) properties (out of 2473) seen :678
Running SMT prover for 1795 properties.
[2021-05-18 07:14:14] [INFO ] Flow matrix only has 2218 transitions (discarded 272 similar events)
// Phase 1: matrix 2218 rows 1077 cols
[2021-05-18 07:14:14] [INFO ] Computed 41 place invariants in 19 ms
[2021-05-18 07:14:38] [INFO ] [Real]Absence check using 31 positive place invariants in 28 ms returned sat
[2021-05-18 07:14:38] [INFO ] [Real]Absence check using 31 positive and 10 generalized place invariants in 1 ms returned sat
[2021-05-18 07:14:39] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2021-05-18 07:14:39] [WARNING] SMT solver failed with error :SMT solver raised an error when submitting script.... while checking expressions.
Fused 1795 Parikh solutions to 1 different solutions.
Support contains 992 out of 1077 places. Attempting structural reductions.
Starting structural reductions, iteration 0 : 1077/1077 places, 2490/2490 transitions.
Free-agglomeration rule (complex) applied 7 times.
Iterating global reduction 0 with 7 rules applied. Total rules applied 7 place count 1077 transition count 2483
Reduce places removed 7 places and 0 transitions.
Iterating post reduction 0 with 7 rules applied. Total rules applied 14 place count 1070 transition count 2483
Applied a total of 14 rules in 190 ms. Remains 1070 /1077 variables (removed 7) and now considering 2483/2490 (removed 7) transitions.
Finished structural reductions, in 1 iterations. Remains : 1070/1077 places, 2483/2490 transitions.
[2021-05-18 07:14:40] [INFO ] Flatten gal took : 237 ms
[2021-05-18 07:14:40] [INFO ] Flatten gal took : 157 ms
[2021-05-18 07:14:40] [INFO ] Time to serialize gal into /tmp/ReachabilityCardinality17424713591682887313.gal : 38 ms
[2021-05-18 07:14:40] [INFO ] Time to serialize properties into /tmp/ReachabilityCardinality12665140206016133461.prop : 9 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/ReachabilityCardinality17424713591682887313.gal, -t, CGAL, -reachable-file, /tmp/ReachabilityCardinality12665140206016133461.prop, --nowitness, --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/ReachabilityCardinality17424713591682887313.gal -t CGAL -reachable-file /tmp/ReachabilityCardinality12665140206016133461.prop --nowitness --gen-order FOLLOW
Loading property file /tmp/ReachabilityCardinality12665140206016133461.prop.
SDD proceeding with computation,1795 properties remain. new max is 4
SDD size :1 after 21
SDD proceeding with computation,1795 properties remain. new max is 8
SDD size :21 after 23
SDD proceeding with computation,1795 properties remain. new max is 16
SDD size :23 after 31
SDD proceeding with computation,1795 properties remain. new max is 32
SDD size :31 after 33
Detected timeout of ITS tools.
[2021-05-18 07:14:55] [INFO ] Flatten gal took : 125 ms
[2021-05-18 07:14:55] [INFO ] Applying decomposition
[2021-05-18 07:14:56] [INFO ] Flatten gal took : 105 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/graph3344700263784543861.txt, -o, /tmp/graph3344700263784543861.bin, -w, /tmp/graph3344700263784543861.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/graph3344700263784543861.bin, -l, -1, -v, -w, /tmp/graph3344700263784543861.weights, -q, 0, -e, 0.001], workingDir=null]
[2021-05-18 07:14:56] [INFO ] Decomposing Gal with order
[2021-05-18 07:14:56] [INFO ] Rewriting arrays to variables to allow decomposition.
[2021-05-18 07:14:57] [INFO ] Removed a total of 2245 redundant transitions.
[2021-05-18 07:14:57] [INFO ] Flatten gal took : 804 ms
[2021-05-18 07:14:57] [INFO ] Fuse similar labels procedure discarded/fused a total of 1390 labels/synchronizations in 163 ms.
[2021-05-18 07:14:57] [INFO ] Time to serialize gal into /tmp/ReachabilityCardinality2983240032362236416.gal : 22 ms
[2021-05-18 07:14:57] [INFO ] Time to serialize properties into /tmp/ReachabilityCardinality3163788648841180713.prop : 7 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/ReachabilityCardinality2983240032362236416.gal, -t, CGAL, -reachable-file, /tmp/ReachabilityCardinality3163788648841180713.prop, --nowitness], 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/ReachabilityCardinality2983240032362236416.gal -t CGAL -reachable-file /tmp/ReachabilityCardinality3163788648841180713.prop --nowitness
Loading property file /tmp/ReachabilityCardinality3163788648841180713.prop.
SDD proceeding with computation,1795 properties remain. new max is 4
SDD size :1 after 8
SDD proceeding with computation,1795 properties remain. new max is 8
SDD size :8 after 12
SDD proceeding with computation,1795 properties remain. new max is 16
SDD size :12 after 25
SDD proceeding with computation,1795 properties remain. new max is 32
SDD size :25 after 34
SDD proceeding with computation,1795 properties remain. new max is 64
SDD size :34 after 39
SDD proceeding with computation,1795 properties remain. new max is 128
SDD size :39 after 43
SDD proceeding with computation,1795 properties remain. new max is 256
SDD size :43 after 49
SDD proceeding with computation,1795 properties remain. new max is 512
SDD size :49 after 57
SDD proceeding with computation,1795 properties remain. new max is 1024
SDD size :57 after 73
SDD proceeding with computation,1795 properties remain. new max is 2048
SDD size :73 after 80
SDD proceeding with computation,1795 properties remain. new max is 4096
SDD size :80 after 145
SDD proceeding with computation,1795 properties remain. new max is 8192
SDD size :145 after 240
SDD proceeding with computation,1795 properties remain. new max is 16384
SDD size :240 after 284
SDD proceeding with computation,1795 properties remain. new max is 32768
SDD size :284 after 411
Detected timeout of ITS tools.
Built C files in :
/tmp/ltsmin7816676154766295243
[2021-05-18 07:15:12] [INFO ] Built C files in 121ms conformant to PINS (ltsmin variant)in folder :/tmp/ltsmin7816676154766295243
Running compilation step : CommandLine [args=[gcc, -c, -I/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.ltsmin.binaries_1.0.0.202104292328/bin/include/, -I., -std=c99, -fPIC, -O2, model.c], workingDir=/tmp/ltsmin7816676154766295243]
WARNING : LTS min runner thread failed on error :java.lang.RuntimeException: Compilation or link of executable timed out.java.util.concurrent.TimeoutException: Subprocess running CommandLine [args=[gcc, -c, -I/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.ltsmin.binaries_1.0.0.202104292328/bin/include/, -I., -std=c99, -fPIC, -O2, model.c], workingDir=/tmp/ltsmin7816676154766295243] killed by timeout after 1 SECONDS
java.lang.RuntimeException: Compilation or link of executable timed out.java.util.concurrent.TimeoutException: Subprocess running CommandLine [args=[gcc, -c, -I/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.ltsmin.binaries_1.0.0.202104292328/bin/include/, -I., -std=c99, -fPIC, -O2, model.c], workingDir=/tmp/ltsmin7816676154766295243] killed by timeout after 1 SECONDS
at fr.lip6.move.gal.application.LTSminRunner$1.run(LTSminRunner.java:79)
at java.base/java.lang.Thread.run(Thread.java:834)
Interrupted random walk after 346701 steps, including 11630 resets, run timeout after 30001 ms. (steps per millisecond=11 ) properties seen :{6=1, 7=1, 50=1, 51=1, 63=1, 64=1, 76=1, 77=1, 131=1, 163=1, 164=1, 174=1, 184=1, 224=1, 225=1, 248=1, 249=1, 250=1, 257=1, 265=1, 266=1, 273=1, 295=1, 296=1, 312=1, 313=1, 318=1, 319=1, 325=1, 326=1, 358=1, 362=1, 366=1, 367=1, 371=1, 381=1, 382=1, 390=1, 391=1, 392=1, 393=1, 394=1, 396=1, 397=1, 398=1, 399=1, 406=1, 407=1, 414=1, 415=1, 416=1, 467=1, 468=1, 475=1, 476=1, 477=1, 484=1, 485=1, 492=1, 493=1, 494=1, 496=1, 498=1, 499=1, 508=1, 509=1, 520=1, 521=1, 524=1, 528=1, 529=1, 530=1, 543=1, 544=1, 555=1, 560=1, 561=1, 567=1, 568=1, 574=1, 575=1, 576=1, 596=1, 619=1, 620=1, 621=1, 635=1, 636=1, 643=1, 659=1, 666=1, 667=1, 699=1, 700=1, 701=1, 710=1, 711=1, 721=1, 722=1, 763=1, 764=1, 807=1, 808=1, 809=1, 820=1, 821=1, 833=1, 1237=1, 1238=1, 1239=1, 1241=1, 1242=1, 1244=1, 1245=1, 1246=1, 1247=1, 1248=1, 1249=1, 1250=1, 1252=1, 1253=1, 1254=1, 1255=1, 1256=1, 1257=1, 1258=1, 1259=1, 1266=1, 1267=1, 1268=1, 1269=1, 1280=1, 1282=1, 1286=1, 1288=1, 1290=1, 1291=1, 1292=1, 1296=1, 1300=1, 1301=1, 1302=1, 1303=1, 1304=1, 1312=1, 1320=1, 1321=1, 1322=1, 1323=1, 1324=1, 1325=1, 1326=1, 1327=1, 1328=1, 1329=1, 1346=1, 1347=1, 1348=1, 1349=1, 1350=1, 1351=1, 1352=1, 1353=1, 1354=1, 1362=1, 1370=1, 1371=1, 1372=1, 1373=1, 1374=1, 1375=1, 1383=1, 1385=1, 1387=1, 1389=1, 1397=1, 1399=1, 1407=1, 1408=1, 1409=1, 1416=1, 1417=1, 1418=1, 1419=1, 1421=1, 1422=1, 1423=1, 1424=1, 1425=1, 1426=1, 1427=1, 1429=1, 1430=1, 1432=1, 1433=1, 1434=1, 1436=1, 1437=1, 1438=1}
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 19 ms. (steps per millisecond=52 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 17 ms. (steps per millisecond=58 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 19 ms. (steps per millisecond=52 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 6 resets, run finished after 17 ms. (steps per millisecond=58 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 17 ms. (steps per millisecond=58 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 17 ms. (steps per millisecond=58 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 6 resets, run finished after 17 ms. (steps per millisecond=58 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1001 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Incomplete Best-First random walk after 1000 steps, including 5 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 1596) seen :0
Running SMT prover for 1596 properties.
[2021-05-18 07:15:44] [INFO ] Flow matrix only has 2205 transitions (discarded 278 similar events)
// Phase 1: matrix 2205 rows 1070 cols
[2021-05-18 07:15:44] [INFO ] Computed 41 place invariants in 21 ms
[2021-05-18 07:16:05] [INFO ] [Real]Absence check using 31 positive place invariants in 35 ms returned sat
[2021-05-18 07:16:05] [INFO ] [Real]Absence check using 31 positive and 10 generalized place invariants in 2 ms returned sat
[2021-05-18 07:16:26] [INFO ] [Real]Adding state equation constraints to refine reachable states.
[2021-05-18 07:17:48] [INFO ] [Real]Absence check using state equation in 81860 ms returned unsat :0 sat :1397 real:199
[2021-05-18 07:17:48] [INFO ] State equation strengthened by 841 read => feed constraints.
[2021-05-18 07:17:48] [INFO ] [Real]Added 841 Read/Feed constraints in 83 ms returned sat
Attempting to minimize the solution found.
Minimization took 2 ms.
[2021-05-18 07:19:29] [INFO ] SMT Verify possible in real domain returnedunsat :0 sat :20 real:1576
[2021-05-18 07:19:49] [INFO ] [Nat]Absence check using 31 positive place invariants in 39 ms returned sat
[2021-05-18 07:19:49] [INFO ] [Nat]Absence check using 31 positive and 10 generalized place invariants in 2 ms returned sat
[2021-05-18 07:20:09] [INFO ] [Nat]Adding state equation constraints to refine reachable states.
[2021-05-18 07:21:26] [INFO ] [Nat]Absence check using state equation in 76604 ms returned unsat :0 sat :1596
[2021-05-18 07:21:26] [INFO ] [Nat]Added 841 Read/Feed constraints in 69 ms returned sat
Attempting to minimize the solution found.
Minimization took 14442 ms.
[2021-05-18 07:23:14] [INFO ] SMT Verify possible in nat domain returned unsat :1130 sat :466
FORMULA QuasiLiveness FALSE TECHNIQUES RANDOM_WALK STRUCTURAL_REDUCTION TOPOLOGICAL INITIAL_STATE SAT_SMT

BK_STOP 1621322595904

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

+ export BINDIR=/home/mcc/BenchKit/bin//../
+ BINDIR=/home/mcc/BenchKit/bin//../
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ QuasiLiveness = StateSpace ]]
+ /home/mcc/BenchKit/bin//..//runeclipse.sh /home/mcc/execution QuasiLiveness -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//..//greatspn/ -order META -manyOrder -smt -timeout 1800
+ 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 QuasiLiveness -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 1800 -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="MultiCrashLeafsetExtension-PT-S16C04"
export BK_EXAMINATION="QuasiLiveness"
export BK_TOOL="itstools"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="1800"
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 MultiCrashLeafsetExtension-PT-S16C04, examination is QuasiLiveness"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r299-tall-162124165100367"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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