Execution Chart

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

Trace from the execution

Formatting '/data/fkordon/mcc2023-input.r307-tall-167873961000164.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2023-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
....................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................
=====================================================================
Generated by BenchKit 2-5348
Executing tool itstools
Input is Philosophers-PT-005000, examination is Liveness
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r307-tall-167873961000164
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 226M
-rw-r--r-- 1 mcc users 8.4M Feb 25 22:07 CTLCardinality.txt
-rw-r--r-- 1 mcc users 43M Feb 25 22:07 CTLCardinality.xml
-rw-r--r-- 1 mcc users 5.2M Feb 25 17:39 CTLFireability.txt
-rw-r--r-- 1 mcc users 32M Feb 25 17:39 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K Jan 29 11:40 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 3.2M Feb 25 16:33 LTLCardinality.txt
-rw-r--r-- 1 mcc users 12M Feb 25 16:33 LTLCardinality.xml
-rw-r--r-- 1 mcc users 1.7M Feb 25 16:33 LTLFireability.txt
-rw-r--r-- 1 mcc users 7.1M Feb 25 16:33 LTLFireability.xml
-rw-r--r-- 1 mcc users 8.1M Feb 26 08:29 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 41M Feb 26 08:29 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 7.9M Feb 26 03:31 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 46M Feb 26 03:31 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 477K Feb 25 16:33 UpperBounds.txt
-rw-r--r-- 1 mcc users 1.3M Feb 25 16:33 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 Mar 5 18:23 equiv_col
-rw-r--r-- 1 mcc users 7 Mar 5 18:23 instance
-rw-r--r-- 1 mcc users 6 Mar 5 18:23 iscolored
-rw-r--r-- 1 mcc users 11M Mar 5 18:23 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 Liveness

=== Now, execution of the tool begins

BK_START 1678863580328

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
Invoking MCC driver with
BK_TOOL=itstools
BK_EXAMINATION=Liveness
BK_BIN_PATH=/home/mcc/BenchKit/bin/
BK_TIME_CONFINEMENT=1800
BK_INPUT=Philosophers-PT-005000
Not applying reductions.
Model is PT
Liveness PT
Running Version 202303021504
[2023-03-15 06:59:43] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, Liveness, -its, -ltsmin, -greatspnpath, /home/mcc/BenchKit/bin//../itstools/bin//..//greatspn/, -order, META, -manyOrder, -smt, -timeout, 1800]
[2023-03-15 06:59:43] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2023-03-15 06:59:43] [INFO ] Load time of PNML (sax parser for PT used): 547 ms
[2023-03-15 06:59:44] [INFO ] Transformed 25000 places.
[2023-03-15 06:59:44] [INFO ] Transformed 25000 transitions.
[2023-03-15 06:59:44] [INFO ] Parsed PT model containing 25000 places and 25000 transitions and 80000 arcs in 723 ms.
Built sparse matrix representations for Structural reductions in 35 ms.283160KB memory used
Starting structural reductions in DEADLOCK mode, iteration 0 : 25000/25000 places, 25000/25000 transitions.
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Applied a total of 0 rules in 369 ms. Remains 25000 /25000 variables (removed 0) and now considering 25000/25000 (removed 0) transitions.
Finished structural reductions in DEADLOCK mode , in 1 iterations and 373 ms. Remains : 25000/25000 places, 25000/25000 transitions.
Starting structural reductions in DEADLOCK mode, iteration 0 : 25000/25000 places, 25000/25000 transitions.
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Applied a total of 0 rules in 245 ms. Remains 25000 /25000 variables (removed 0) and now considering 25000/25000 (removed 0) transitions.
// Phase 1: matrix 25000 rows 25000 cols
[2023-03-15 06:59:45] [INFO ] Computed 10000 place invariants in 133 ms
[2023-03-15 06:59:47] [INFO ] Implicit Places using invariants in 2424 ms returned []
Implicit Place search using SMT only with invariants took 2447 ms to find 0 implicit places.
[2023-03-15 06:59:47] [INFO ] Invariant cache hit.
[2023-03-15 06:59:52] [INFO ] Dead Transitions using invariants and state equation in 4436 ms found 0 transitions.
Finished structural reductions in DEADLOCK mode , in 1 iterations and 7132 ms. Remains : 25000/25000 places, 25000/25000 transitions.
Interrupted Random walk after 110917 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=3 )
[2023-03-15 07:00:22] [INFO ] Invariant cache hit.
[2023-03-15 07:05:22] [INFO ] [Real]Absence check using 10000 positive place invariants in 250588 ms returned unknown
Interrupted Random directed walk after 19999 steps, including 0 resets, run timeout after 32361 ms. (steps per millisecond=0 )
Interrupted Random walk after 113377 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=3 )
Interrupted Random directed walk after 19997 steps, including 0 resets, run timeout after 31924 ms. (steps per millisecond=0 )
Interrupted Random walk after 116200 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=3 )
Starting structural reductions in DEADLOCK mode, iteration 0 : 25000/25000 places, 25000/25000 transitions.
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Applied a total of 0 rules in 183 ms. Remains 25000 /25000 variables (removed 0) and now considering 25000/25000 (removed 0) transitions.
[2023-03-15 07:07:27] [INFO ] Invariant cache hit.
[2023-03-15 07:07:28] [INFO ] Implicit Places using invariants in 1849 ms returned []
Implicit Place search using SMT only with invariants took 1851 ms to find 0 implicit places.
[2023-03-15 07:07:28] [INFO ] Invariant cache hit.
[2023-03-15 07:07:30] [INFO ] Dead Transitions using invariants and state equation in 1900 ms found 0 transitions.
Finished structural reductions in DEADLOCK mode , in 1 iterations and 3939 ms. Remains : 25000/25000 places, 25000/25000 transitions.
Interrupted Random walk after 112140 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=3 )
[2023-03-15 07:08:00] [INFO ] Invariant cache hit.
[2023-03-15 07:13:00] [INFO ] [Real]Absence check using 10000 positive place invariants in 243255 ms returned unknown
Interrupted Random directed walk after 19994 steps, including 0 resets, run timeout after 31910 ms. (steps per millisecond=0 )
Interrupted Random walk after 115037 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=3 )
Interrupted Random directed walk after 19997 steps, including 0 resets, run timeout after 32086 ms. (steps per millisecond=0 )
Interrupted Random walk after 116218 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=3 )
[2023-03-15 07:15:06] [INFO ] Flatten gal took : 1280 ms
Starting structural reductions in LIVENESS mode, iteration 0 : 25000/25000 places, 25000/25000 transitions.
Performed 5000 Post agglomeration using F-continuation condition.Transition count delta: 5000
Deduced a syphon composed of 5000 places in 3 ms
Reduce places removed 5000 places and 0 transitions.
Iterating global reduction 0 with 10000 rules applied. Total rules applied 10000 place count 20000 transition count 20000
Applied a total of 10000 rules in 2801 ms. Remains 20000 /25000 variables (removed 5000) and now considering 20000/25000 (removed 5000) transitions.
// Phase 1: matrix 20000 rows 20000 cols
[2023-03-15 07:15:09] [INFO ] Computed 10000 place invariants in 45 ms
[2023-03-15 07:15:11] [INFO ] Implicit Places using invariants in 1627 ms returned []
Implicit Place search using SMT only with invariants took 1628 ms to find 0 implicit places.
[2023-03-15 07:15:11] [INFO ] Invariant cache hit.
[2023-03-15 07:15:12] [INFO ] Dead Transitions using invariants and state equation in 1510 ms found 0 transitions.
Starting structural reductions in LIVENESS mode, iteration 1 : 20000/25000 places, 20000/25000 transitions.
Finished structural reductions in LIVENESS mode , in 1 iterations and 5944 ms. Remains : 20000/25000 places, 20000/25000 transitions.
Initial state reduction rules removed 10000 formulas.
[2023-03-15 07:15:13] [INFO ] Flatten gal took : 671 ms
[2023-03-15 07:15:14] [INFO ] Flatten gal took : 679 ms
[2023-03-15 07:15:14] [INFO ] Time to serialize gal into /tmp/ReachabilityCardinality7121889508017612876.gal : 149 ms
[2023-03-15 07:15:14] [INFO ] Time to serialize properties into /tmp/ReachabilityCardinality15343041098153274548.prop : 47 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-reach-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/ReachabilityCardinality7121889508017612876.gal' '-t' 'CGAL' '-reachable-file' '/tmp/ReachabilityCardinality15343041098153274548.prop' '--nowitness' '--gen-order' 'FOLLOW'

its-reach command run as :

/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-reach-linux64 --gc-threshold 2000000 --quiet ...329
Loading property file /tmp/ReachabilityCardinality15343041098153274548.prop.
Incomplete random walk after 10000 steps, including 2 resets, run finished after 15125 ms. (steps per millisecond=0 ) properties (out of 10000) seen :5510
Running SMT prover for 4490 properties.
[2023-03-15 07:15:28] [INFO ] Invariant cache hit.
java.lang.RuntimeException: SMT solver raised an error when submitting script. Raised (error "Failed to assert expression: java.io.IOException: Stream close...
at fr.lip6.move.gal.structural.smt.SMTUtils.execAndCheckResult(SMTUtils.java:251)
at fr.lip6.move.gal.structural.smt.DeadlockTester.assertInvariants(DeadlockTester.java:2340)
at fr.lip6.move.gal.structural.smt.DeadlockTester.verifyPossible(DeadlockTester.java:618)
at fr.lip6.move.gal.structural.smt.DeadlockTester.testUnreachableWithSMTIncremental(DeadlockTester.java:339)
at fr.lip6.move.gal.structural.smt.DeadlockTester.testUnreachableWithSMT(DeadlockTester.java:223)
at fr.lip6.move.gal.application.solver.ReachabilitySolver.applyReductions(ReachabilitySolver.java:95)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.applyReachabilitySolver(GlobalPropertySolver.java:724)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.solveProperty(GlobalPropertySolver.java:558)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.preSolveLiveness(GlobalPropertySolver.java:399)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.preStableMarking(GlobalPropertySolver.java:321)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.solveProperty(GlobalPropertySolver.java:294)
at fr.lip6.move.gal.application.Application.startNoEx(Application.java:386)
at fr.lip6.move.gal.application.Application.start(Application.java:178)
at fr.lip6.move.gal.itscl.application.Application.start(Application.java:45)
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:402)
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:77)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:568)
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)
[2023-03-15 07:15:53] [WARNING] SMT solver failed with error :SMT solver raised an error when submitting script.... while checking expressions.
[2023-03-15 07:15:53] [INFO ] After 25041ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0
Fused 4490 Parikh solutions to 1 different solutions.
Parikh walk visited 0 properties in 1 ms.
Support contains 7875 out of 20000 places. Attempting structural reductions.
Starting structural reductions in REACHABILITY mode, iteration 0 : 20000/20000 places, 20000/20000 transitions.
Free-agglomeration rule (complex) applied 1476 times.
Iterating global reduction 0 with 1476 rules applied. Total rules applied 1476 place count 20000 transition count 18524
Reduce places removed 2138 places and 0 transitions.
Drop transitions removed 1476 transitions
Reduce isomorphic transitions removed 1476 transitions.
Iterating post reduction 0 with 3614 rules applied. Total rules applied 5090 place count 17862 transition count 17048
Applied a total of 5090 rules in 10906 ms. Remains 17862 /20000 variables (removed 2138) and now considering 17048/20000 (removed 2952) transitions.
Finished structural reductions in REACHABILITY mode , in 1 iterations and 10907 ms. Remains : 17862/20000 places, 17048/20000 transitions.
Interrupted random walk after 25249 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=0 ) properties seen 3438
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 144 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 144 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 184 ms. (steps per millisecond=5 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 145 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 144 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 142 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 144 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 156 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 145 ms. (steps per millisecond=6 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 142 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 142 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 142 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 168 ms. (steps per millisecond=5 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 142 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Incomplete Best-First random walk after 1001 steps, including 0 resets, run finished after 143 ms. (steps per millisecond=7 ) properties (out of 1052) seen :0
Running SMT prover for 1052 properties.
// Phase 1: matrix 17048 rows 17862 cols
[2023-03-15 07:16:41] [INFO ] Computed 9338 place invariants in 52 ms
[2023-03-15 07:20:28] [INFO ] [Real]Absence check using 9338 positive place invariants in 3346 ms returned unknown
java.lang.RuntimeException: SMT solver raised an error when submitting script. Raised (error "Error writing to Z3 solver: java.io.IOException: Stream closed...
at fr.lip6.move.gal.structural.smt.SMTUtils.execAndCheckResult(SMTUtils.java:251)
at fr.lip6.move.gal.structural.smt.DeadlockTester.verifyPossible(DeadlockTester.java:629)
at fr.lip6.move.gal.structural.smt.DeadlockTester.testUnreachableWithSMTIncremental(DeadlockTester.java:339)
at fr.lip6.move.gal.structural.smt.DeadlockTester.testUnreachableWithSMT(DeadlockTester.java:223)
at fr.lip6.move.gal.application.solver.ReachabilitySolver.applyReductions(ReachabilitySolver.java:95)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.applyReachabilitySolver(GlobalPropertySolver.java:724)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.solveProperty(GlobalPropertySolver.java:558)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.preSolveLiveness(GlobalPropertySolver.java:399)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.preStableMarking(GlobalPropertySolver.java:321)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.solveProperty(GlobalPropertySolver.java:294)
at fr.lip6.move.gal.application.Application.startNoEx(Application.java:386)
at fr.lip6.move.gal.application.Application.start(Application.java:178)
at fr.lip6.move.gal.itscl.application.Application.start(Application.java:45)
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:402)
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:77)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:568)
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)
[2023-03-15 07:20:28] [WARNING] SMT solver failed with error :SMT solver raised an error when submitting script.... while checking expressions.
[2023-03-15 07:20:28] [INFO ] After 225073ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0
Fused 1052 Parikh solutions to 1 different solutions.
Parikh walk visited 0 properties in 0 ms.
Support contains 2001 out of 17862 places. Attempting structural reductions.
Starting structural reductions in REACHABILITY mode, iteration 0 : 17862/17862 places, 17048/17048 transitions.
Graph (complete) has 50632 edges and 17862 vertex of which 14861 are kept as prefixes of interest. Removing 3001 places using SCC suffix rule.13 ms
Discarding 3001 places :
Also discarding 2714 output transitions
Drop transitions removed 2714 transitions
Discarding 2 places :
Also discarding 1 output transitions
Drop transitions removed 1 transitions
Remove reverse transitions (loop back) rule discarded transition FF2a_75.End_75 and 2 places that fell out of Prefix Of Interest.
Drop transitions removed 63 transitions
Reduce isomorphic transitions removed 63 transitions.
Drop transitions removed 114 transitions
Trivial Post-agglo rules discarded 114 transitions
Performed 114 trivial Post agglomeration. Transition count delta: 114
Iterating post reduction 0 with 178 rules applied. Total rules applied 179 place count 14859 transition count 14156
Reduce places removed 228 places and 0 transitions.
Drop transitions removed 114 transitions
Reduce isomorphic transitions removed 114 transitions.
Iterating post reduction 1 with 342 rules applied. Total rules applied 521 place count 14631 transition count 14042
Free-agglomeration rule (complex) applied 4382 times.
Iterating global reduction 2 with 4382 rules applied. Total rules applied 4903 place count 14631 transition count 9660
Reduce places removed 8723 places and 0 transitions.
Drop transitions removed 4382 transitions
Reduce isomorphic transitions removed 4382 transitions.
Iterating post reduction 2 with 13105 rules applied. Total rules applied 18008 place count 5908 transition count 5278
Applied a total of 18008 rules in 8234 ms. Remains 5908 /17862 variables (removed 11954) and now considering 5278/17048 (removed 11770) transitions.
Finished structural reductions in REACHABILITY mode , in 1 iterations and 8234 ms. Remains : 5908/17862 places, 5278/17048 transitions.
Finished random walk after 93990 steps, including 0 resets, run visited all 1052 properties in 25831 ms. (steps per millisecond=3 )
ITS runner failed with exception null
java.lang.InterruptedException
at java.base/java.lang.Object.wait(Native Method)
at java.base/java.lang.Thread.join(Thread.java:1304)
at java.base/java.lang.Thread.join(Thread.java:1372)
at fr.lip6.move.gal.application.runner.AbstractRunner.join(AbstractRunner.java:27)
at fr.lip6.move.gal.application.runner.its.ITSRunner.join(ITSRunner.java:177)
at fr.lip6.move.gal.application.solver.global.GlobalPropertySolver.verifyWithSDD(GlobalPropertySolver.java:675)
at fr.lip6.move.gal.application.solver.ReachabilitySolver.lambda$2(ReachabilitySolver.java:63)
at java.base/java.lang.Thread.run(Thread.java:833)
Able to resolve query QuasiLiveness after proving 20000 properties.
Starting structural reductions in LIVENESS mode, iteration 0 : 25000/25000 places, 25000/25000 transitions.
Performed 5000 Post agglomeration using F-continuation condition.Transition count delta: 5000
Deduced a syphon composed of 5000 places in 4 ms
Reduce places removed 5000 places and 0 transitions.
Iterating global reduction 0 with 10000 rules applied. Total rules applied 10000 place count 20000 transition count 20000
Applied a total of 10000 rules in 2627 ms. Remains 20000 /25000 variables (removed 5000) and now considering 20000/25000 (removed 5000) transitions.
// Phase 1: matrix 20000 rows 20000 cols
[2023-03-15 07:21:05] [INFO ] Computed 10000 place invariants in 40 ms
[2023-03-15 07:21:06] [INFO ] Implicit Places using invariants in 1572 ms returned []
Implicit Place search using SMT only with invariants took 1574 ms to find 0 implicit places.
[2023-03-15 07:21:06] [INFO ] Invariant cache hit.
[2023-03-15 07:21:08] [INFO ] Dead Transitions using invariants and state equation in 1765 ms found 0 transitions.
Starting structural reductions in LIVENESS mode, iteration 1 : 20000/25000 places, 20000/25000 transitions.
Finished structural reductions in LIVENESS mode , in 1 iterations and 5975 ms. Remains : 20000/25000 places, 20000/25000 transitions.
[2023-03-15 07:21:09] [INFO ] Flatten gal took : 664 ms
[2023-03-15 07:21:10] [INFO ] Flatten gal took : 664 ms
[2023-03-15 07:21:10] [INFO ] Time to serialize gal into /tmp/CTLFireability16429087152865706234.gal : 85 ms
[2023-03-15 07:21:10] [INFO ] Time to serialize properties into /tmp/CTLFireability13171015886633846131.ctl : 171 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLFireability16429087152865706234.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLFireability13171015886633846131.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -...285
Detected timeout of ITS tools.
[2023-03-15 07:21:14] [INFO ] Flatten gal took : 625 ms
[2023-03-15 07:21:14] [INFO ] Applying decomposition
[2023-03-15 07:21:15] [INFO ] Flatten gal took : 647 ms
Converted graph to binary with : '/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202303021504/bin/convert-linux64' '-i' '/tmp/graph18152165555991353652.txt' '-o' '/tmp/graph18152165555991353652.bin' '-w' '/tmp/graph18152165555991353652.weights'
Built communities with : '/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202303021504/bin/louvain-linux64' '/tmp/graph18152165555991353652.bin' '-l' '-1' '-v' '-w' '/tmp/graph18152165555991353652.weights' '-q' '0' '-e' '0.001'
[2023-03-15 07:21:17] [INFO ] Decomposing Gal with order
[2023-03-15 07:21:20] [INFO ] Rewriting arrays to variables to allow decomposition.
[2023-03-15 07:22:27] [INFO ] Removed a total of 120 redundant transitions.
[2023-03-15 07:22:28] [INFO ] Flatten gal took : 1426 ms
[2023-03-15 07:22:43] [INFO ] Fuse similar labels procedure discarded/fused a total of 0 labels/synchronizations in 68 ms.
[2023-03-15 07:22:43] [INFO ] Time to serialize gal into /tmp/CTLFireability11643065649380950356.gal : 176 ms
[2023-03-15 07:22:43] [INFO ] Time to serialize properties into /tmp/CTLFireability16830181430211272954.ctl : 121 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLFireability11643065649380950356.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLFireability16830181430211272954.ctl'

its-ctl command run as :

/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -...264
Detected timeout of ITS tools.
[2023-03-15 07:22:47] [INFO ] Flatten gal took : 636 ms
[2023-03-15 07:22:48] [INFO ] Flatten gal took : 663 ms
[2023-03-15 07:22:48] [INFO ] Time to serialize gal into /tmp/CTLFireability1491586007427575794.gal : 81 ms
[2023-03-15 07:22:48] [INFO ] Time to serialize properties into /tmp/CTLFireability12892674020537266276.ctl : 86 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLFireability1491586007427575794.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLFireability12892674020537266276.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -...284
No direction supplied, using forward translation only.
Parsed 20000 CTL formulae.
Detected timeout of ITS tools.
[2023-03-15 07:23:19] [INFO ] Flatten gal took : 750 ms
[2023-03-15 07:23:19] [INFO ] Applying decomposition
[2023-03-15 07:23:19] [INFO ] Flatten gal took : 679 ms
Converted graph to binary with : '/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202303021504/bin/convert-linux64' '-i' '/tmp/graph6840984023964656967.txt' '-o' '/tmp/graph6840984023964656967.bin' '-w' '/tmp/graph6840984023964656967.weights'
Built communities with : '/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.louvain.binaries_1.0.0.202303021504/bin/louvain-linux64' '/tmp/graph6840984023964656967.bin' '-l' '-1' '-v' '-w' '/tmp/graph6840984023964656967.weights' '-q' '0' '-e' '0.001'
[2023-03-15 07:23:21] [INFO ] Decomposing Gal with order
[2023-03-15 07:23:24] [INFO ] Rewriting arrays to variables to allow decomposition.
[2023-03-15 07:24:13] [INFO ] Removed a total of 110 redundant transitions.
[2023-03-15 07:24:13] [INFO ] Flatten gal took : 996 ms
[2023-03-15 07:24:38] [INFO ] Fuse similar labels procedure discarded/fused a total of 0 labels/synchronizations in 38 ms.
[2023-03-15 07:24:38] [INFO ] Time to serialize gal into /tmp/CTLFireability11257711681151970607.gal : 131 ms
[2023-03-15 07:24:38] [INFO ] Time to serialize properties into /tmp/CTLFireability9475339555094093307.ctl : 106 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLFireability11257711681151970607.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLFireability9475339555094093307.ctl'

its-ctl command run as :

/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -...263
No direction supplied, using forward translation only.
Parsed 20000 CTL formulae.
Detected timeout of ITS tools.
[2023-03-15 07:25:09] [INFO ] Flatten gal took : 635 ms
[2023-03-15 07:25:10] [INFO ] Flatten gal took : 652 ms
[2023-03-15 07:25:10] [INFO ] Time to serialize gal into /tmp/CTLFireability11548396535540048221.gal : 84 ms
[2023-03-15 07:25:10] [INFO ] Time to serialize properties into /tmp/CTLFireability9236049180390426904.ctl : 79 ms
Invoking ITS tools like this :cd /home/mcc/execution;'/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64' '--gc-threshold' '2000000' '--quiet' '-i' '/tmp/CTLFireability11548396535540048221.gal' '-t' 'CGAL' '-ctl' '/tmp/CTLFireability9236049180390426904.ctl' '--gen-order' 'FOLLOW'

its-ctl command run as :

/home/mcc/BenchKit/itstools/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.202303021504/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -...284
Detected timeout of ITS tools.
No direction supplied, using forward translation only.
Parsed 20000 CTL formulae.
TIME LIMIT: Killed by timeout after 1800 seconds
MemTotal: 16393216 kB
MemFree: 9772316 kB
After kill :
MemTotal: 16393216 kB
MemFree: 15892176 kB

BK_TIME_CONFINEMENT_REACHED

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

+ export BINDIR=/home/mcc/BenchKit/bin//../itstools/bin//../
+ BINDIR=/home/mcc/BenchKit/bin//../itstools/bin//../
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ Liveness = StateSpace ]]
+ /home/mcc/BenchKit/bin//../itstools/bin//..//runeclipse.sh /home/mcc/execution Liveness -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//../itstools/bin//..//greatspn/ -order META -manyOrder -smt -timeout 1800
+ ulimit -s 65536
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
+ export PYTHONPATH=/home/mcc/BenchKit/itstools/pylibs
+ PYTHONPATH=/home/mcc/BenchKit/itstools/pylibs
+ export LD_LIBRARY_PATH=/home/mcc/BenchKit/itstools/pylibs:
+ LD_LIBRARY_PATH=/home/mcc/BenchKit/itstools/pylibs:
++ sed s/.jar//
++ perl -pe 's/.*\.//g'
++ ls /home/mcc/BenchKit/bin//../itstools/bin//..//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202303021504.jar
+ VERSION=202303021504
+ echo 'Running Version 202303021504'
+ /home/mcc/BenchKit/bin//../itstools/bin//..//itstools/its-tools -pnfolder /home/mcc/execution -examination Liveness -its -ltsmin -greatspnpath /home/mcc/BenchKit/bin//../itstools/bin//..//greatspn/ -order META -manyOrder -smt -timeout 1800

Sequence of Actions to be Executed by the VM

This is useful if one wants to reexecute the tool in the VM from the submitted image disk.

set -x
# this is for BenchKit: configuration of major elements for the test
export BK_INPUT="Philosophers-PT-005000"
export BK_EXAMINATION="Liveness"
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-5348"
echo " Executing tool itstools"
echo " Input is Philosophers-PT-005000, examination is Liveness"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r307-tall-167873961000164"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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