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.r078-tall-165260110600657.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 FamilyReunion-COL-L00400M0040C020P020G001, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r078-tall-165260110600657
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 388K
-rw-r--r-- 1 mcc users 6.5K Apr 30 20:44 CTLCardinality.txt
-rw-r--r-- 1 mcc users 65K Apr 30 20:44 CTLCardinality.xml
-rw-r--r-- 1 mcc users 6.9K Apr 30 18:29 CTLFireability.txt
-rw-r--r-- 1 mcc users 49K Apr 30 18:29 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K May 10 09:33 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 7.0K May 10 09:33 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 3.9K May 9 07:48 LTLCardinality.txt
-rw-r--r-- 1 mcc users 25K May 9 07:48 LTLCardinality.xml
-rw-r--r-- 1 mcc users 3.1K May 9 07:49 LTLFireability.txt
-rw-r--r-- 1 mcc users 17K May 9 07:49 LTLFireability.xml
-rw-r--r-- 1 mcc users 2.0K May 9 07:49 UpperBounds.txt
-rw-r--r-- 1 mcc users 4.0K May 9 07:49 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 May 10 09:33 equiv_pt
-rw-r--r-- 1 mcc users 24 May 10 09:33 instance
-rw-r--r-- 1 mcc users 5 May 10 09:33 iscolored
-rw-r--r-- 1 mcc users 159K May 10 09:33 model.pnml

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

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

The expected result is a vector of booleans
BOOL_VECTOR

here is the order used to build the result vector(from text file)
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-00
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-01
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-02
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-03
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-04
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-05
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-06
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-07
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-08
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-09
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-10
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-11
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-12
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-13
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-14
FORMULA_NAME FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-15

=== Now, execution of the tool begins

BK_START 1652708822738

Running Version 202205111006
[2022-05-16 13:47:03] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, CTLCardinality, -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-16 13:47:03] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2022-05-16 13:47:04] [INFO ] Detected file is not PT type :http://www.pnml.org/version-2009/grammar/symmetricnet
log4j:WARN No appenders could be found for logger (org.apache.axiom.locator.DefaultOMMetaFactoryLocator).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
[2022-05-16 13:47:04] [WARNING] Using fallBack plugin, rng conformance not checked
[2022-05-16 13:47:04] [INFO ] Load time of PNML (colored model parsed with PNMLFW) : 722 ms
[2022-05-16 13:47:04] [INFO ] Detected 5 constant HL places corresponding to 106 PT places.
[2022-05-16 13:47:04] [INFO ] Imported 104 HL places and 66 HL transitions for a total of 538109 PT places and 508890.0 transition bindings in 49 ms.
Parsed 16 properties from file /home/mcc/execution/CTLCardinality.xml in 18 ms.
[2022-05-16 13:47:04] [INFO ] Built PT skeleton of HLPN with 104 places and 66 transitions 198 arcs in 10 ms.
[2022-05-16 13:47:04] [INFO ] Skeletonized 16 HLPN properties in 2 ms.
Computed a total of 104 stabilizing places and 66 stable transitions
Complete graph has no SCC; deadlocks are unavoidable. place count 104 transition count 66
Detected that all paths lead to deadlock. Applying this knowledge to assert that all AP eventually converge (and all enablings converge to false).
AF dead knowledge conclusive for 1 formulas.
FORMULA FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-00 FALSE TECHNIQUES TOPOLOGICAL INITIAL_STATE
Remains 15 properties that can be checked using skeleton over-approximation.
Reduce places removed 5 places and 0 transitions.
Computed a total of 99 stabilizing places and 66 stable transitions
Complete graph has no SCC; deadlocks are unavoidable. place count 99 transition count 66
Detected that all paths lead to deadlock. Applying this knowledge to assert that all AP eventually converge (and all enablings converge to false).
Incomplete random walk after 10052 steps, including 2 resets, run finished after 45 ms. (steps per millisecond=223 ) properties (out of 70) seen :11
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 43 ms. (steps per millisecond=23 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 22 ms. (steps per millisecond=45 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 21 ms. (steps per millisecond=47 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 35 ms. (steps per millisecond=28 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 6 ms. (steps per millisecond=166 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 22 ms. (steps per millisecond=45 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 13 ms. (steps per millisecond=77 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 12 ms. (steps per millisecond=83 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 15 ms. (steps per millisecond=66 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 14 ms. (steps per millisecond=71 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 11 ms. (steps per millisecond=91 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 3 ms. (steps per millisecond=333 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 11 ms. (steps per millisecond=91 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 11 ms. (steps per millisecond=91 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 11 ms. (steps per millisecond=91 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 12 ms. (steps per millisecond=83 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 22 ms. (steps per millisecond=45 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 18 ms. (steps per millisecond=55 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 16 ms. (steps per millisecond=62 ) properties (out of 59) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 5 ms. (steps per millisecond=200 ) properties (out of 59) seen :1
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 5 ms. (steps per millisecond=200 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 10 ms. (steps per millisecond=100 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 11 ms. (steps per millisecond=90 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 10 ms. (steps per millisecond=100 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 6 ms. (steps per millisecond=166 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 4 ms. (steps per millisecond=250 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 13 ms. (steps per millisecond=77 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1000 steps, including 2 resets, run finished after 8 ms. (steps per millisecond=125 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 17 ms. (steps per millisecond=58 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 10 ms. (steps per millisecond=100 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 9 ms. (steps per millisecond=111 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 11 ms. (steps per millisecond=91 ) properties (out of 58) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 5 ms. (steps per millisecond=200 ) properties (out of 58) seen :1
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 10 ms. (steps per millisecond=100 ) properties (out of 57) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 7 ms. (steps per millisecond=143 ) properties (out of 57) seen :0
Running SMT prover for 57 properties.
// Phase 1: matrix 66 rows 99 cols
[2022-05-16 13:47:05] [INFO ] Computed 33 place invariants in 12 ms
[2022-05-16 13:47:06] [INFO ] [Real]Absence check using 4 positive place invariants in 3 ms returned sat
[2022-05-16 13:47:06] [INFO ] [Real]Absence check using 4 positive and 29 generalized place invariants in 13 ms returned sat
[2022-05-16 13:47:06] [INFO ] After 436ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0 real:57
[2022-05-16 13:47:06] [INFO ] [Nat]Absence check using 4 positive place invariants in 1 ms returned sat
[2022-05-16 13:47:06] [INFO ] [Nat]Absence check using 4 positive and 29 generalized place invariants in 7 ms returned sat
[2022-05-16 13:47:06] [INFO ] After 232ms SMT Verify possible using state equation in natural domain returned unsat :10 sat :47
[2022-05-16 13:47:07] [INFO ] After 625ms SMT Verify possible using trap constraints in natural domain returned unsat :10 sat :47
Attempting to minimize the solution found.
Minimization took 217 ms.
[2022-05-16 13:47:07] [INFO ] After 1125ms SMT Verify possible using all constraints in natural domain returned unsat :10 sat :47
Fused 57 Parikh solutions to 46 different solutions.
Finished Parikh walk after 1046 steps, including 0 resets, run visited all 1 properties in 4 ms. (steps per millisecond=261 )
Parikh walk visited 47 properties in 3042 ms.
Successfully simplified 10 atomic propositions for a total of 15 simplifications.
FORMULA FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-06 FALSE TECHNIQUES TOPOLOGICAL INITIAL_STATE
FORMULA FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-12 FALSE TECHNIQUES TOPOLOGICAL INITIAL_STATE
[2022-05-16 13:47:10] [INFO ] Flatten gal took : 30 ms
[2022-05-16 13:47:10] [INFO ] Initial state reduction rules for CTL removed 1 formulas.
FORMULA FamilyReunion-COL-L00400M0040C020P020G001-CTLCardinality-01 FALSE TECHNIQUES TOPOLOGICAL INITIAL_STATE
[2022-05-16 13:47:10] [INFO ] Flatten gal took : 8 ms
Transition Gate2ANDJoin forces synchronizations/join behavior on parameter l of sort LegalResident
Transition ReceiveLangChoice forces synchronizations/join behavior on parameter m of sort MICSystem
Symmetric sort wr.t. initial and guards and successors and join/free detected :CINFORMI
Symmetric sort wr.t. initial detected :CINFORMI
Symmetric sort wr.t. initial and guards detected :CINFORMI
Applying symmetric unfolding of full symmetric sort :CINFORMI domain size was 21
Transition Gate1ANDJoin forces synchronizations/join behavior on parameter p of sort PublicAdminOffice
Symmetric sort wr.t. initial and guards and successors and join/free detected :GovernmentCommission
Symmetric sort wr.t. initial detected :GovernmentCommission
Symmetric sort wr.t. initial and guards detected :GovernmentCommission
Applying symmetric unfolding of full symmetric sort :GovernmentCommission domain size was 11
Symmetric sort wr.t. initial and guards and successors and join/free detected :Response
Symmetric sort wr.t. initial detected :Response
Transition SendClearanceToRel : guard parameter $r(Response:2) in guard (EQ $r 0)introduces in Response(2) partition with 2 elements
[2022-05-16 13:47:11] [INFO ] Unfolded HLPN to a Petri net with 477829 places and 412249 transitions 1184997 arcs in 1472 ms.
[2022-05-16 13:47:11] [INFO ] Unfolded 12 HLPN properties in 28 ms.
Deduced a syphon composed of 401 places in 1161 ms
Reduce places removed 467 places and 0 transitions.
Support contains 310440 out of 477362 places. Attempting structural reductions.
Starting structural reductions in LTL mode, iteration 0 : 477362/477362 places, 412249/412249 transitions.
Reduce places removed 423 places and 0 transitions.
Discarding 33283 places :
Implicit places reduction removed 33283 places
Iterating post reduction 0 with 33706 rules applied. Total rules applied 33706 place count 443656 transition count 412249
Applied a total of 33706 rules in 73250 ms. Remains 443656 /477362 variables (removed 33706) and now considering 412249/412249 (removed 0) transitions.
// Phase 1: matrix 412249 rows 443656 cols
[2022-05-16 13:50:32] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 13:51:11] [INFO ] Implicit Places using invariants in 158660 ms returned []
Implicit Place search using SMT only with invariants took 158662 ms to find 0 implicit places.
// Phase 1: matrix 412249 rows 443656 cols
[2022-05-16 13:53:11] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 13:54:03] [INFO ] Dead Transitions using invariants and state equation in 172109 ms found 0 transitions.
Starting structural reductions in LTL mode, iteration 1 : 443656/477362 places, 412249/412249 transitions.
Finished structural reductions, in 1 iterations. Remains : 443656/477362 places, 412249/412249 transitions.
Support contains 310440 out of 443656 places after structural reductions.
[2022-05-16 13:54:21] [INFO ] Flatten gal took : 13954 ms
[2022-05-16 13:54:36] [INFO ] Flatten gal took : 12494 ms
[2022-05-16 13:55:10] [INFO ] Input system was already deterministic with 412249 transitions.
Incomplete random walk after 10000 steps, including 2 resets, run finished after 20632 ms. (steps per millisecond=0 ) properties (out of 53) seen :13
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 66 ms. (steps per millisecond=15 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 57 ms. (steps per millisecond=17 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 48 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 49 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 67 ms. (steps per millisecond=14 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 48 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 47 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 53 ms. (steps per millisecond=18 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 231 ms. (steps per millisecond=4 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 39 ms. (steps per millisecond=25 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 424 ms. (steps per millisecond=2 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 38 ms. (steps per millisecond=26 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 63 ms. (steps per millisecond=15 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 56 ms. (steps per millisecond=17 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 57 ms. (steps per millisecond=17 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 47 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 47 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 46 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 49 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 47 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 50 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 48 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 424 ms. (steps per millisecond=2 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 49 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 39 ms. (steps per millisecond=25 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 53 ms. (steps per millisecond=18 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 407 ms. (steps per millisecond=2 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 59 ms. (steps per millisecond=16 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 50 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 48 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 42 ms. (steps per millisecond=23 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 231 ms. (steps per millisecond=4 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 606 ms. (steps per millisecond=1 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 58 ms. (steps per millisecond=17 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 48 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 57 ms. (steps per millisecond=17 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 47 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 48 ms. (steps per millisecond=20 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 58 ms. (steps per millisecond=17 ) properties (out of 40) seen :0
Incomplete Best-First random walk after 1001 steps, including 2 resets, run finished after 47 ms. (steps per millisecond=21 ) properties (out of 40) seen :0
Running SMT prover for 40 properties.
// Phase 1: matrix 412249 rows 443656 cols
[2022-05-16 13:57:41] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 13:58:06] [WARNING] SMT solver failed with error :SMT solver raised an error when submitting script.... while checking expressions.
[2022-05-16 13:58:06] [INFO ] After 25030ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0
Fused 40 Parikh solutions to 1 different solutions.
Parikh walk visited 0 properties in 0 ms.
Support contains 86682 out of 443656 places. Attempting structural reductions.
Starting structural reductions in SAFETY mode, iteration 0 : 443656/443656 places, 412249/412249 transitions.
Discarding 49724 places :
Implicit places reduction removed 49724 places
Drop transitions removed 98646 transitions
Trivial Post-agglo rules discarded 98646 transitions
Performed 98646 trivial Post agglomeration. Transition count delta: 98646
Iterating post reduction 0 with 148370 rules applied. Total rules applied 148370 place count 393932 transition count 313603
Reduce places removed 98646 places and 0 transitions.
Performed 2815 Post agglomeration using F-continuation condition.
Performed 5358 Post agglomeration using F-continuation condition.
Performed 8472 Post agglomeration using F-continuation condition.
Performed 10558 Post agglomeration using F-continuation condition.
Performed 12989 Post agglomeration using F-continuation condition.
Performed 15916 Post agglomeration using F-continuation condition.
Performed 18219 Post agglomeration using F-continuation condition.
Performed 21319 Post agglomeration using F-continuation condition.
Performed 24607 Post agglomeration using F-continuation condition.
Performed 27968 Post agglomeration using F-continuation condition.
Performed 31223 Post agglomeration using F-continuation condition.
Performed 33684 Post agglomeration using F-continuation condition.Transition count delta: 33684
Iterating post reduction 1 with 132330 rules applied. Total rules applied 280700 place count 295286 transition count 279919
[2022-05-16 14:06:54] [INFO ] Computed 79407 place invariants in 1101279 ms
Reduce places removed 33684 places and 0 transitions.
Iterating post reduction 2 with 33684 rules applied. Total rules applied 314384 place count 261602 transition count 279919
[2022-05-16 14:10:48] [INFO ] Computed 79407 place invariants in 1177179 ms
Performed 25664 Pre agglomeration using Quasi-Persistent + Divergent Free condition..
Pre-agglomeration after 3 with 25664 Pre rules applied. Total rules applied 314384 place count 261602 transition count 254255
Deduced a syphon composed of 25664 places in 328 ms
Ensure Unique test removed 16441 places
Reduce places removed 42105 places and 0 transitions.
Iterating global reduction 3 with 67769 rules applied. Total rules applied 382153 place count 219497 transition count 254255
Discarding 8421 places :
Implicit places reduction removed 8421 places
Iterating post reduction 3 with 8421 rules applied. Total rules applied 390574 place count 211076 transition count 254255
Performed 2884 Post agglomeration using F-continuation condition.
Performed 8195 Post agglomeration using F-continuation condition.
Performed 8822 Post agglomeration using F-continuation condition.Transition count delta: 8822
Deduced a syphon composed of 8822 places in 332 ms
Reduce places removed 8822 places and 0 transitions.
Iterating global reduction 4 with 17644 rules applied. Total rules applied 408218 place count 202254 transition count 245433
Free-agglomeration rule (complex) applied 401 times.
Iterating global reduction 4 with 401 rules applied. Total rules applied 408619 place count 202254 transition count 245032
Reduce places removed 401 places and 0 transitions.
Iterating post reduction 4 with 401 rules applied. Total rules applied 409020 place count 201853 transition count 245032
Applied a total of 409020 rules in 926473 ms. Remains 201853 /443656 variables (removed 241803) and now considering 245032/412249 (removed 167217) transitions.
Finished structural reductions, in 1 iterations. Remains : 201853/443656 places, 245032/412249 transitions.
[2022-05-16 14:13:52] [INFO ] Flatten gal took : 15371 ms
[2022-05-16 14:14:11] [INFO ] Flatten gal took : 16518 ms
[2022-05-16 14:14:46] [INFO ] Input system was already deterministic with 412249 transitions.
Computed a total of 443656 stabilizing places and 412249 stable transitions
Complete graph has no SCC; deadlocks are unavoidable. place count 443656 transition count 412249
Detected that all paths lead to deadlock. Applying this knowledge to assert that all AP eventually converge (and all enablings converge to false).
Starting structural reductions in LTL mode, iteration 0 : 443656/443656 places, 412249/412249 transitions.
Reduce places removed 845 places and 0 transitions.
Discarding 66566 places :
Implicit places reduction removed 66566 places
Iterating post reduction 0 with 67411 rules applied. Total rules applied 67411 place count 376245 transition count 412249
Applied a total of 67411 rules in 31487 ms. Remains 376245 /443656 variables (removed 67411) and now considering 412249/412249 (removed 0) transitions.
// Phase 1: matrix 412249 rows 376245 cols
[2022-05-16 14:17:22] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 14:17:53] [INFO ] Implicit Places using invariants in 151185 ms returned []
Implicit Place search using SMT only with invariants took 151188 ms to find 0 implicit places.
// Phase 1: matrix 412249 rows 376245 cols
[2022-05-16 14:19:24] [INFO ] Computed 79407 place invariants in 1422960 ms
[2022-05-16 14:19:53] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 14:20:08] [INFO ] Computed 12437 place invariants in 286200 ms
[2022-05-16 14:20:21] [INFO ] Dead Transitions using invariants and state equation in 148471 ms found 0 transitions.
Starting structural reductions in LTL mode, iteration 1 : 376245/443656 places, 412249/412249 transitions.
Finished structural reductions, in 1 iterations. Remains : 376245/443656 places, 412249/412249 transitions.
[2022-05-16 14:20:34] [INFO ] Flatten gal took : 11592 ms
[2022-05-16 14:20:44] [INFO ] Flatten gal took : 10730 ms
[2022-05-16 14:21:02] [INFO ] Input system was already deterministic with 412249 transitions.
[2022-05-16 14:21:15] [INFO ] Flatten gal took : 11410 ms
[2022-05-16 14:21:26] [INFO ] Flatten gal took : 10731 ms
[2022-05-16 14:21:28] [INFO ] Time to serialize gal into /tmp/CTLCardinality3002718826528045187.gal : 1522 ms
[2022-05-16 14:21:28] [INFO ] Time to serialize properties into /tmp/CTLCardinality11766352831533100139.ctl : 6 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality3002718826528045187.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality11766352831533100139.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...275
Detected timeout of ITS tools.
[2022-05-16 14:22:11] [INFO ] Flatten gal took : 11651 ms
[2022-05-16 14:22:11] [INFO ] Applying decomposition
[2022-05-16 14:22:21] [INFO ] Flatten gal took : 10783 ms
Louvain graph construction failed due to OOM.
[2022-05-16 14:23:11] [WARNING] Could not apply decomposition. Using flat GAL structure.
java.lang.NullPointerException
at fr.lip6.move.gal.application.mcc.MccTranslator.applyOrder(MccTranslator.java:230)
at fr.lip6.move.gal.application.mcc.MccTranslator.flattenSpec(MccTranslator.java:264)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.verifyWithSDD(GlobalPropertySolver.java:654)
at fr.lip6.move.gal.application.Application.startNoEx(Application.java:606)
at fr.lip6.move.gal.application.Application.start(Application.java:174)
at fr.lip6.move.gal.itscl.application.Application.start(Application.java:43)
at org.eclipse.equinox.internal.app.EclipseAppHandle.run(EclipseAppHandle.java:203)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:136)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:104)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:401)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:255)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.eclipse.equinox.launcher.Main.invokeFramework(Main.java:659)
at org.eclipse.equinox.launcher.Main.basicRun(Main.java:596)
at org.eclipse.equinox.launcher.Main.run(Main.java:1467)
at org.eclipse.equinox.launcher.Main.main(Main.java:1440)
[2022-05-16 14:23:22] [INFO ] Flatten gal took : 11603 ms
[2022-05-16 14:23:24] [INFO ] Time to serialize gal into /tmp/CTLCardinality10474019542559150874.gal : 1276 ms
[2022-05-16 14:23:24] [INFO ] Time to serialize properties into /tmp/CTLCardinality1278570548790802602.ctl : 5 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality10474019542559150874.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality1278570548790802602.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...275
[2022-05-16 14:23:30] [INFO ] Computed 12437 place invariants in 337045 ms
Detected timeout of ITS tools.
Starting structural reductions in SI_CTL mode, iteration 0 : 443656/443656 places, 412249/412249 transitions.
Graph (complete) has 664077 edges and 443656 vertex of which 83408 are kept as prefixes of interest. Removing 360248 places using SCC suffix rule.862 ms
Discarding 360248 places :
Also discarding 329643 output transitions
Drop transitions removed 329643 transitions
Reduce places removed 401 places and 401 transitions.
Discarding 16441 places :
Implicit places reduction removed 16441 places
Drop transitions removed 16441 transitions
Trivial Post-agglo rules discarded 16441 transitions
Performed 16441 trivial Post agglomeration. Transition count delta: 16441
Iterating post reduction 0 with 32882 rules applied. Total rules applied 32883 place count 66566 transition count 65764
Reduce places removed 16441 places and 0 transitions.
Iterating post reduction 1 with 16441 rules applied. Total rules applied 49324 place count 50125 transition count 65764
Applied a total of 49324 rules in 11331 ms. Remains 50125 /443656 variables (removed 393531) and now considering 65764/412249 (removed 346485) transitions.
// Phase 1: matrix 65764 rows 50125 cols
[2022-05-16 14:24:07] [INFO ] Computed 401 place invariants in 976 ms
[2022-05-16 14:24:11] [INFO ] SMT solver returned unknown. Retrying;
[2022-05-16 14:24:11] [INFO ] Implicit Places using invariants in 5142 ms returned []
Implicit Place search using SMT only with invariants took 5143 ms to find 0 implicit places.
// Phase 1: matrix 65764 rows 50125 cols
[2022-05-16 14:24:12] [INFO ] Computed 401 place invariants in 1030 ms
[2022-05-16 14:24:16] [INFO ] SMT solver returned unknown. Retrying;
[2022-05-16 14:24:16] [INFO ] Dead Transitions using invariants and state equation in 4964 ms found 0 transitions.
Starting structural reductions in SI_CTL mode, iteration 1 : 50125/443656 places, 65764/412249 transitions.
Finished structural reductions, in 1 iterations. Remains : 50125/443656 places, 65764/412249 transitions.
[2022-05-16 14:24:18] [INFO ] Flatten gal took : 1575 ms
[2022-05-16 14:24:19] [INFO ] Flatten gal took : 1535 ms
[2022-05-16 14:24:23] [INFO ] Input system was already deterministic with 65764 transitions.
[2022-05-16 14:24:25] [INFO ] Flatten gal took : 1874 ms
[2022-05-16 14:24:27] [INFO ] Flatten gal took : 1858 ms
[2022-05-16 14:24:27] [INFO ] Time to serialize gal into /tmp/CTLCardinality9302643100676524193.gal : 196 ms
[2022-05-16 14:24:28] [INFO ] Time to serialize properties into /tmp/CTLCardinality7852444301658168674.ctl : 9 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality9302643100676524193.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality7852444301658168674.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...274
No direction supplied, using forward translation only.
Parsed 1 CTL formulae.
Detected timeout of ITS tools.
[2022-05-16 14:24:59] [INFO ] Flatten gal took : 1721 ms
[2022-05-16 14:24:59] [INFO ] Applying decomposition
[2022-05-16 14:25:01] [INFO ] Flatten gal took : 1792 ms
Louvain graph construction failed due to OOM.
[2022-05-16 14:25:47] [WARNING] Could not apply decomposition. Using flat GAL structure.
java.lang.NullPointerException
at fr.lip6.move.gal.application.mcc.MccTranslator.applyOrder(MccTranslator.java:230)
at fr.lip6.move.gal.application.mcc.MccTranslator.flattenSpec(MccTranslator.java:264)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.verifyWithSDD(GlobalPropertySolver.java:654)
at fr.lip6.move.gal.application.Application.startNoEx(Application.java:606)
at fr.lip6.move.gal.application.Application.start(Application.java:174)
at fr.lip6.move.gal.itscl.application.Application.start(Application.java:43)
at org.eclipse.equinox.internal.app.EclipseAppHandle.run(EclipseAppHandle.java:203)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:136)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:104)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:401)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:255)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.eclipse.equinox.launcher.Main.invokeFramework(Main.java:659)
at org.eclipse.equinox.launcher.Main.basicRun(Main.java:596)
at org.eclipse.equinox.launcher.Main.run(Main.java:1467)
at org.eclipse.equinox.launcher.Main.main(Main.java:1440)
[2022-05-16 14:25:48] [INFO ] Flatten gal took : 1485 ms
[2022-05-16 14:25:49] [INFO ] Time to serialize gal into /tmp/CTLCardinality2412812618929359650.gal : 162 ms
[2022-05-16 14:25:49] [INFO ] Time to serialize properties into /tmp/CTLCardinality17081629877850435668.ctl : 9 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality2412812618929359650.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality17081629877850435668.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...275
No direction supplied, using forward translation only.
Parsed 1 CTL formulae.
Detected timeout of ITS tools.
Starting structural reductions in LTL mode, iteration 0 : 443656/443656 places, 412249/412249 transitions.
Reduce places removed 866 places and 0 transitions.
Discarding 66967 places :
Implicit places reduction removed 66967 places
Iterating post reduction 0 with 67833 rules applied. Total rules applied 67833 place count 375823 transition count 412249
Applied a total of 67833 rules in 33141 ms. Remains 375823 /443656 variables (removed 67833) and now considering 412249/412249 (removed 0) transitions.
// Phase 1: matrix 412249 rows 375823 cols
[2022-05-16 14:28:52] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 14:29:20] [INFO ] Implicit Places using invariants in 148355 ms returned []
Implicit Place search using SMT only with invariants took 148356 ms to find 0 implicit places.
// Phase 1: matrix 412249 rows 375823 cols
[2022-05-16 14:30:55] [INFO ] Computed 12035 place invariants in 242897 ms
[2022-05-16 14:31:21] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 14:31:48] [INFO ] Dead Transitions using invariants and state equation in 147208 ms found 0 transitions.
Starting structural reductions in LTL mode, iteration 1 : 375823/443656 places, 412249/412249 transitions.
Finished structural reductions, in 1 iterations. Remains : 375823/443656 places, 412249/412249 transitions.
[2022-05-16 14:32:00] [INFO ] Flatten gal took : 11299 ms
[2022-05-16 14:32:12] [INFO ] Flatten gal took : 12549 ms
[2022-05-16 14:32:37] [INFO ] Input system was already deterministic with 412249 transitions.
[2022-05-16 14:32:50] [INFO ] Flatten gal took : 11161 ms
[2022-05-16 14:33:01] [INFO ] Flatten gal took : 10359 ms
[2022-05-16 14:33:02] [INFO ] Time to serialize gal into /tmp/CTLCardinality6421982127919726982.gal : 952 ms
[2022-05-16 14:33:02] [INFO ] Time to serialize properties into /tmp/CTLCardinality6937395583459451791.ctl : 36 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality6421982127919726982.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality6937395583459451791.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...274
Detected timeout of ITS tools.
[2022-05-16 14:33:37] [INFO ] Computed 12035 place invariants in 256458 ms
[2022-05-16 14:33:44] [INFO ] Flatten gal took : 10646 ms
[2022-05-16 14:33:44] [INFO ] Applying decomposition
[2022-05-16 14:33:54] [INFO ] Flatten gal took : 10356 ms
Louvain graph construction failed due to OOM.
[2022-05-16 14:34:35] [WARNING] Could not apply decomposition. Using flat GAL structure.
java.lang.NullPointerException
at fr.lip6.move.gal.application.mcc.MccTranslator.applyOrder(MccTranslator.java:230)
at fr.lip6.move.gal.application.mcc.MccTranslator.flattenSpec(MccTranslator.java:264)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.verifyWithSDD(GlobalPropertySolver.java:654)
at fr.lip6.move.gal.application.Application.startNoEx(Application.java:606)
at fr.lip6.move.gal.application.Application.start(Application.java:174)
at fr.lip6.move.gal.itscl.application.Application.start(Application.java:43)
at org.eclipse.equinox.internal.app.EclipseAppHandle.run(EclipseAppHandle.java:203)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:136)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:104)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:401)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:255)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.eclipse.equinox.launcher.Main.invokeFramework(Main.java:659)
at org.eclipse.equinox.launcher.Main.basicRun(Main.java:596)
at org.eclipse.equinox.launcher.Main.run(Main.java:1467)
at org.eclipse.equinox.launcher.Main.main(Main.java:1440)
[2022-05-16 14:34:47] [INFO ] Flatten gal took : 11494 ms
[2022-05-16 14:34:48] [INFO ] Time to serialize gal into /tmp/CTLCardinality1745377251845732984.gal : 976 ms
[2022-05-16 14:34:48] [INFO ] Time to serialize properties into /tmp/CTLCardinality13978534263129811955.ctl : 19 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality1745377251845732984.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality13978534263129811955.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...275
Detected timeout of ITS tools.
Starting structural reductions in LTL mode, iteration 0 : 443656/443656 places, 412249/412249 transitions.
Reduce places removed 864 places and 0 transitions.
Discarding 66967 places :
Implicit places reduction removed 66967 places
Iterating post reduction 0 with 67831 rules applied. Total rules applied 67831 place count 375825 transition count 412249
Applied a total of 67831 rules in 57895 ms. Remains 375825 /443656 variables (removed 67831) and now considering 412249/412249 (removed 0) transitions.
// Phase 1: matrix 412249 rows 375825 cols
[2022-05-16 14:38:17] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 14:38:44] [INFO ] Implicit Places using invariants in 147681 ms returned []
Implicit Place search using SMT only with invariants took 147682 ms to find 0 implicit places.
// Phase 1: matrix 412249 rows 375825 cols
[2022-05-16 14:40:44] [WARNING] Invariant computation timed out after 120 seconds.
[2022-05-16 14:40:50] [INFO ] Computed 12037 place invariants in 273412 ms
[2022-05-16 14:41:13] [INFO ] Dead Transitions using invariants and state equation in 148549 ms found 0 transitions.
Starting structural reductions in LTL mode, iteration 1 : 375825/443656 places, 412249/412249 transitions.
Finished structural reductions, in 1 iterations. Remains : 375825/443656 places, 412249/412249 transitions.
[2022-05-16 14:41:24] [INFO ] Flatten gal took : 10187 ms
[2022-05-16 14:41:34] [INFO ] Flatten gal took : 10075 ms
[2022-05-16 14:41:52] [INFO ] Input system was already deterministic with 412249 transitions.
[2022-05-16 14:42:06] [INFO ] Flatten gal took : 12076 ms
[2022-05-16 14:42:18] [INFO ] Flatten gal took : 12202 ms
[2022-05-16 14:42:21] [INFO ] Time to serialize gal into /tmp/CTLCardinality7826298912277231806.gal : 1164 ms
[2022-05-16 14:42:21] [INFO ] Time to serialize properties into /tmp/CTLCardinality15312411317197496143.ctl : 58 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality7826298912277231806.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality15312411317197496143.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...275
Detected timeout of ITS tools.
[2022-05-16 14:43:05] [INFO ] Flatten gal took : 12977 ms
[2022-05-16 14:43:05] [INFO ] Applying decomposition
[2022-05-16 14:43:17] [INFO ] Flatten gal took : 12354 ms
[2022-05-16 14:43:35] [INFO ] Computed 12037 place invariants in 290656 ms
Louvain graph construction failed due to OOM.
[2022-05-16 14:44:02] [WARNING] Could not apply decomposition. Using flat GAL structure.
java.lang.NullPointerException
at fr.lip6.move.gal.application.mcc.MccTranslator.applyOrder(MccTranslator.java:230)
at fr.lip6.move.gal.application.mcc.MccTranslator.flattenSpec(MccTranslator.java:264)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.verifyWithSDD(GlobalPropertySolver.java:654)
at fr.lip6.move.gal.application.Application.startNoEx(Application.java:606)
at fr.lip6.move.gal.application.Application.start(Application.java:174)
at fr.lip6.move.gal.itscl.application.Application.start(Application.java:43)
at org.eclipse.equinox.internal.app.EclipseAppHandle.run(EclipseAppHandle.java:203)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.runApplication(EclipseAppLauncher.java:136)
at org.eclipse.core.runtime.internal.adaptor.EclipseAppLauncher.start(EclipseAppLauncher.java:104)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:401)
at org.eclipse.core.runtime.adaptor.EclipseStarter.run(EclipseStarter.java:255)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:566)
at org.eclipse.equinox.launcher.Main.invokeFramework(Main.java:659)
at org.eclipse.equinox.launcher.Main.basicRun(Main.java:596)
at org.eclipse.equinox.launcher.Main.run(Main.java:1467)
at org.eclipse.equinox.launcher.Main.main(Main.java:1440)
[2022-05-16 14:44:13] [INFO ] Flatten gal took : 11115 ms
[2022-05-16 14:44:15] [INFO ] Time to serialize gal into /tmp/CTLCardinality5508645548471286341.gal : 974 ms
[2022-05-16 14:44:15] [INFO ] Time to serialize properties into /tmp/CTLCardinality5519916204783356190.ctl : 35 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLCardinality5508645548471286341.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLCardinality5519916204783356190.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202205111006/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /tmp/C...274
Detected timeout of ITS tools.
Starting structural reductions in SI_CTL mode, iteration 0 : 443656/443656 places, 412249/412249 transitions.
Graph (complete) has 664077 edges and 443656 vertex of which 437153 are kept as prefixes of interest. Removing 6503 places using SCC suffix rule.797 ms
Discarding 6503 places :
Also discarding 5234 output transitions
Drop transitions removed 5234 transitions
Reduce places removed 401 places and 401 transitions.
Discarding 49724 places :
Implicit places reduction removed 49724 places
Drop transitions removed 176440 transitions
Trivial Post-agglo rules discarded 176440 transitions
Performed 176440 trivial Post agglomeration. Transition count delta: 176440
Iterating post reduction 0 with 226164 rules applied. Total rules applied 226165 place count 387028 transition count 230174
Reduce places removed 176440 places and 0 transitions.
Performed 6319 Post agglomeration using F-continuation condition.

BK_TIME_CONFINEMENT_REACHED

--------------------
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
+ [[ CTLCardinality = StateSpace ]]
+ /home/mcc/BenchKit/bin//..//runeclipse.sh /home/mcc/execution CTLCardinality -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 CTLCardinality -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="FamilyReunion-COL-L00400M0040C020P020G001"
export BK_EXAMINATION="CTLCardinality"
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 FamilyReunion-COL-L00400M0040C020P020G001, examination is CTLCardinality"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r078-tall-165260110600657"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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