Execution Chart

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

Trace from the execution

Formatting '/data/fko/mcc2019-input.r129-oct2-155274856700237.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fko/mcc2019-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
......................
=====================================================================
Generated by BenchKit 2-3954
Executing tool itstools
Input is QuasiCertifProtocol-COL-28, examination is GlobalProperties
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r129-oct2-155274856700237
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 272K
-rw-r--r-- 1 mcc users 3.8K Feb 12 10:37 CTLCardinality.txt
-rw-r--r-- 1 mcc users 20K Feb 12 10:37 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.6K Feb 8 12:42 CTLFireability.txt
-rw-r--r-- 1 mcc users 15K Feb 8 12:42 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 10 17:31 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 113 Feb 24 15:05 GlobalProperties.txt
-rw-r--r-- 1 mcc users 351 Feb 24 15:05 GlobalProperties.xml
-rw-r--r-- 1 mcc users 2.5K Feb 5 00:45 LTLCardinality.txt
-rw-r--r-- 1 mcc users 11K Feb 5 00:45 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.0K Feb 4 22:41 LTLFireability.txt
-rw-r--r-- 1 mcc users 8.3K Feb 4 22:41 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.3K Feb 4 13:59 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 16K Feb 4 13:59 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 3.0K Feb 1 10:20 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 17K Feb 1 10:20 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.8K Feb 4 22:26 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.8K Feb 4 22:26 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 Jan 29 09:34 equiv_pt
-rw-r--r-- 1 mcc users 3 Jan 29 09:34 instance
-rw-r--r-- 1 mcc users 5 Jan 29 09:34 iscolored
-rw-r--r-- 1 mcc users 120K Mar 10 17:31 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 QuasiCertifProtocol-COL-28-GlobalProperties-0

=== Now, execution of the tool begins

BK_START 1553956107125

14:28:53.173 [main] ERROR PNML validation - The rng grammar file can't be accessed : www.pnml.org
14:28:53.178 [main] ERROR import - Grammar file errors have been raised, the validation can't be done, process will continue without Grammar validation
Working with output stream class java.io.PrintStream
Flatten gal took : 2692 ms
Constant places removed 63 places and 1 transitions.
Iterating post reduction 0 with 63 rules applied. Total rules applied 63 place count 2935 transition count 445
Performed 2 Post agglomeration using F-continuation condition.
Constant places removed 2 places and 0 transitions.
Iterating post reduction 1 with 2 rules applied. Total rules applied 65 place count 2933 transition count 443
Applied a total of 65 rules in 623 ms. Remains 2933 /2998 variables (removed 65) and now considering 443/446 (removed 3) transitions.
// Phase 1: matrix 443 rows 2933 cols
Using solver Z3 to compute partial order matrices.
Built C files in :
/home/mcc/execution
Presburger conditions satisfied. Using coverability to approximate state space in K-Induction.
// Phase 1: matrix 443 rows 2933 cols
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903171603/bin/its-ctl-linux64, --gc-threshold, 2000000, --quiet, -i, /home/mcc/execution/GlobalProperties.pnml.gal, -t, CGAL, -ctl, DEADLOCK], workingDir=/home/mcc/execution]

its-ctl command run as :

/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201903171603/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /home/mcc/execution/GlobalProperties.pnml.gal -t CGAL -ctl DEADLOCK
No direction supplied, using forward translation only.
built 92 ordering constraints for composite.
sparsehash FATAL ERROR: failed to allocate 34 groups
#
# There is insufficient memory for the Java Runtime Environment to continue.
# Native memory allocation (mmap) failed to map 306184192 bytes for committing reserved memory.
# An error report file with more information is saved as:
# /home/mcc/execution/hs_err_pid470.log
Its-tools:
JVM terminated. Exit code=1
/usr/bin/java
-Dosgi.locking=none
-Declipse.stateSaveDelayInterval=-1
-Dosgi.configuration.area=/tmp/.eclipse
-Xss8m
-Xms40m
-Xmx8192m
-Dfile.encoding=UTF-8
-Dosgi.requiredJavaVersion=1.6
-jar /home/mcc/BenchKit/itstools//plugins/org.eclipse.equinox.launcher_1.5.200.v20180922-1751.jar
-os linux
-ws gtk
-arch x86_64
-showsplash
-launcher /home/mcc/BenchKit/itstools/its-tools
-name Its-tools
--launcher.library /home/mcc/BenchKit/itstools//plugins/org.eclipse.equinox.launcher.gtk.linux.x86_64_1.1.900.v20180922-1751/eclipse_1801.so
-startup /home/mcc/BenchKit/itstools//plugins/org.eclipse.equinox.launcher_1.5.200.v20180922-1751.jar
--launcher.overrideVmargs
-exitdata 0
-data /home/mcc/execution/workspace
-pnfolder /home/mcc/execution
-examination GlobalProperties
-z3path /home/mcc/BenchKit//z3/bin/z3
-yices2path /home/mcc/BenchKit//yices/bin/yices
-its
-ltsminpath /home/mcc/BenchKit//lts_install_dir/
-smt
-vm /usr/bin/java
-vmargs
-Dosgi.locking=none
-Declipse.stateSaveDelayInterval=-1
-Dosgi.configuration.area=/tmp/.eclipse
-Xss8m
-Xms40m
-Xmx8192m
-Dfile.encoding=UTF-8
-Dosgi.requiredJavaVersion=1.6
-jar /home/mcc/BenchKit/itstools//plugins/org.eclipse.equinox.launcher_1.5.200.v20180922-1751.jar

BK_STOP 1553956560967

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

+ export BINDIR=/home/mcc/BenchKit/
+ BINDIR=/home/mcc/BenchKit/
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ [[ GlobalProperties = StateSpace ]]
+ /home/mcc/BenchKit//runeclipse.sh /home/mcc/execution GlobalProperties -its -ltsminpath /home/mcc/BenchKit//lts_install_dir/ -smt
+ ulimit -s 65536
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
+ /home/mcc/BenchKit//itstools/its-tools -data /home/mcc/execution/workspace -pnfolder /home/mcc/execution -examination GlobalProperties -z3path /home/mcc/BenchKit//z3/bin/z3 -yices2path /home/mcc/BenchKit//yices/bin/yices -its -ltsminpath /home/mcc/BenchKit//lts_install_dir/ -smt -vmargs -Dosgi.locking=none -Declipse.stateSaveDelayInterval=-1 -Dosgi.configuration.area=/tmp/.eclipse -Xss8m -Xms40m -Xmx8192m -Dfile.encoding=UTF-8 -Dosgi.requiredJavaVersion=1.6
Mar 30, 2019 2:28:28 PM fr.lip6.move.gal.application.Application start
INFO: Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, GlobalProperties, -z3path, /home/mcc/BenchKit//z3/bin/z3, -yices2path, /home/mcc/BenchKit//yices/bin/yices, -its, -ltsminpath, /home/mcc/BenchKit//lts_install_dir/, -smt]
Mar 30, 2019 2:28:28 PM fr.lip6.move.gal.application.MccTranslator transformPNML
INFO: Parsing pnml file : /home/mcc/execution/model.pnml
Mar 30, 2019 2:28:28 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Detected file is not PT type :http://www.pnml.org/version-2009/grammar/symmetricnet
Mar 30, 2019 2:28:53 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Load time of PNML (colored model parsed with PNMLFW) : 24745 ms
Mar 30, 2019 2:28:53 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 30 places.
Mar 30, 2019 2:28:53 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Computed order using colors.
Mar 30, 2019 2:28:53 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: sort/places :tsidxtsid->n9,n8,n7,
Dot->malicious_reservoir,CstopAbort,SstopAbort,AstopAbort,a5,a4,a3,a2,a1,Astart,AstopOK,
tsid->n6,n5,n4,n3,n2,n1,c1,Cstart,Sstart,s2,s3,s4,s5,s6,SstopOK,CstopOK,

Mar 30, 2019 2:28:53 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 26 transitions.
Mar 30, 2019 2:28:53 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Computed order based on color domains.
Mar 30, 2019 2:28:53 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.img.gal : 34 ms
Mar 30, 2019 2:28:55 PM fr.lip6.move.gal.instantiate.PropertySimplifier evalInInitialState
WARNING: Unexpected boolean logic operator in evalInInitialState fr.lip6.move.gal.impl.EXImpl
Mar 30, 2019 2:28:55 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property QuasiCertifProtocol-COL-28-GlobalProperties-0 is trivially true : it is verified in initial state.
Mar 30, 2019 2:28:55 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 2071 ms
Mar 30, 2019 2:28:56 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.simple.gal : 34 ms
Mar 30, 2019 2:28:56 PM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was not deterministic with 250 transitions. Expanding to a total of 453 deterministic transitions.
Mar 30, 2019 2:28:57 PM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Determinization took 12 ms.
Mar 30, 2019 2:28:58 PM fr.lip6.move.gal.application.MccTranslator applyOrder
INFO: Applying decomposition
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was already deterministic with 443 transitions.
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property QuasiCertifProtocol-COL-28-GlobalProperties-0 is trivially true : it is verified in initial state.
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 300 ms
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property QuasiCertifProtocol-COL-28-GlobalProperties-0 is trivially true : it is verified in initial state.
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 277 ms
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.instantiate.CompositeBuilder decomposeWithOrder
INFO: Decomposing Gal with order
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting arrays to variables to allow decomposition.
Mar 30, 2019 2:28:59 PM fr.lip6.move.gal.gal2smt.bmc.KInductionSolver computeAndDeclareInvariants
INFO: Computed 0 place invariants in 253 ms
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t57,t28,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 1 events :t444.t206,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t55,t26,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t35,t6,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t31,t2,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 202 events :t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,t0,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t38,t9,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t43,t14,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t32,t3,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t46,t17,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t54,t25,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t45,t16,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t39,t10,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t50,t21,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t30,t1,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t41,t12,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t44,t15,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 1 events :t442.t205,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t40,t11,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t36,t7,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t48,t19,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t52,t23,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t51,t22,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t29,t0,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t33,t4,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t34,t5,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t42,t13,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t37,t8,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t56,t27,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t47,t18,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t53,t24,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator dropEvents
INFO: Dropping 2 events :t49,t20,
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.Instantiator fuseIsomorphicEffects
INFO: Removed a total of 262 redundant transitions.
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property QuasiCertifProtocol-COL-28-GlobalProperties-0 is trivially true : it is verified in initial state.
Mar 30, 2019 2:29:00 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 871 ms
Mar 30, 2019 2:29:00 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/GlobalProperties.pnml.gal : 70 ms
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.KInductionSolver init
INFO: Proved 2933 variables to be positive in 89746 ms
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeAblingMatrix
INFO: Computing symmetric may disable matrix : 443 transitions.
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of disable matrix completed :0/443 took 0 ms. Total solver calls (SAT/UNSAT): 0(0/0)
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Complete disable matrix. took 103 ms. Total solver calls (SAT/UNSAT): 0(0/0)
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeAblingMatrix
INFO: Computing symmetric may enable matrix : 443 transitions.
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Complete enable matrix. took 35 ms. Total solver calls (SAT/UNSAT): 0(0/0)
Mar 30, 2019 2:30:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeCoEnablingMatrix
INFO: Computing symmetric co enabling matrix : 443 transitions.
Mar 30, 2019 2:30:35 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(1/443) took 5739 ms. Total solver calls (SAT/UNSAT): 405(405/0)
Mar 30, 2019 2:30:42 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(3/443) took 13320 ms. Total solver calls (SAT/UNSAT): 806(806/0)
Mar 30, 2019 2:30:49 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(4/443) took 19720 ms. Total solver calls (SAT/UNSAT): 1005(1005/0)
Mar 30, 2019 2:30:55 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(5/443) took 26158 ms. Total solver calls (SAT/UNSAT): 1203(1203/0)
Mar 30, 2019 2:31:00 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(7/443) took 31146 ms. Total solver calls (SAT/UNSAT): 1596(1596/0)
Mar 30, 2019 2:31:04 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(8/443) took 34780 ms. Total solver calls (SAT/UNSAT): 1791(1791/0)
Mar 30, 2019 2:31:08 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(10/443) took 38976 ms. Total solver calls (SAT/UNSAT): 2178(2178/0)
Mar 30, 2019 2:31:12 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(12/443) took 42664 ms. Total solver calls (SAT/UNSAT): 2561(2561/0)
Mar 30, 2019 2:31:18 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(13/443) took 48693 ms. Total solver calls (SAT/UNSAT): 2751(2751/0)
Mar 30, 2019 2:31:23 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(14/443) took 53883 ms. Total solver calls (SAT/UNSAT): 2940(2940/0)
Mar 30, 2019 2:31:28 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(15/443) took 59171 ms. Total solver calls (SAT/UNSAT): 3128(3128/0)
Mar 30, 2019 2:31:34 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(16/443) took 64703 ms. Total solver calls (SAT/UNSAT): 3315(3315/0)
Mar 30, 2019 2:31:39 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(17/443) took 70278 ms. Total solver calls (SAT/UNSAT): 3501(3501/0)
Mar 30, 2019 2:31:45 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(19/443) took 76396 ms. Total solver calls (SAT/UNSAT): 3870(3870/0)
Mar 30, 2019 2:31:51 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(20/443) took 81719 ms. Total solver calls (SAT/UNSAT): 4053(4053/0)
Mar 30, 2019 2:31:56 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(21/443) took 86832 ms. Total solver calls (SAT/UNSAT): 4235(4235/0)
Mar 30, 2019 2:31:59 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(23/443) took 90008 ms. Total solver calls (SAT/UNSAT): 4596(4596/0)
Mar 30, 2019 2:32:04 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(25/443) took 94897 ms. Total solver calls (SAT/UNSAT): 4953(4953/0)
Mar 30, 2019 2:32:09 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(26/443) took 99883 ms. Total solver calls (SAT/UNSAT): 5130(5130/0)
Mar 30, 2019 2:32:14 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(27/443) took 104935 ms. Total solver calls (SAT/UNSAT): 5306(5306/0)
Mar 30, 2019 2:32:18 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(28/443) took 109047 ms. Total solver calls (SAT/UNSAT): 5481(5481/0)
Mar 30, 2019 2:32:21 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(31/443) took 112560 ms. Total solver calls (SAT/UNSAT): 6000(6000/0)
Mar 30, 2019 2:32:25 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(35/443) took 116033 ms. Total solver calls (SAT/UNSAT): 6678(6678/0)
Mar 30, 2019 2:32:30 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(36/443) took 120763 ms. Total solver calls (SAT/UNSAT): 6845(6845/0)
Mar 30, 2019 2:32:34 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(39/443) took 124858 ms. Total solver calls (SAT/UNSAT): 7340(7340/0)
Mar 30, 2019 2:32:39 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(40/443) took 130327 ms. Total solver calls (SAT/UNSAT): 7503(7503/0)
Mar 30, 2019 2:32:42 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(42/443) took 133474 ms. Total solver calls (SAT/UNSAT): 7826(7826/0)
Mar 30, 2019 2:32:46 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(46/443) took 136827 ms. Total solver calls (SAT/UNSAT): 8460(8460/0)
Mar 30, 2019 2:32:50 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(47/443) took 141345 ms. Total solver calls (SAT/UNSAT): 8616(8616/0)
Mar 30, 2019 2:32:55 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(50/443) took 146191 ms. Total solver calls (SAT/UNSAT): 9078(9078/0)
Mar 30, 2019 2:33:01 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(53/443) took 151935 ms. Total solver calls (SAT/UNSAT): 9531(9531/0)
Mar 30, 2019 2:33:06 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(56/443) took 157588 ms. Total solver calls (SAT/UNSAT): 9975(9975/0)
Mar 30, 2019 2:33:13 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(58/443) took 163982 ms. Total solver calls (SAT/UNSAT): 10266(10266/0)
Mar 30, 2019 2:33:16 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(61/443) took 167391 ms. Total solver calls (SAT/UNSAT): 10695(10695/0)
Mar 30, 2019 2:33:20 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(62/443) took 170677 ms. Total solver calls (SAT/UNSAT): 10836(10836/0)
Mar 30, 2019 2:33:24 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(64/443) took 175131 ms. Total solver calls (SAT/UNSAT): 11115(11115/0)
Mar 30, 2019 2:33:30 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(68/443) took 180650 ms. Total solver calls (SAT/UNSAT): 11661(11661/0)
Mar 30, 2019 2:33:34 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(69/443) took 185412 ms. Total solver calls (SAT/UNSAT): 11795(11795/0)
Mar 30, 2019 2:33:39 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(70/443) took 190417 ms. Total solver calls (SAT/UNSAT): 11928(11928/0)
Mar 30, 2019 2:33:43 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(71/443) took 194460 ms. Total solver calls (SAT/UNSAT): 12060(12060/0)
Mar 30, 2019 2:33:50 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(73/443) took 200802 ms. Total solver calls (SAT/UNSAT): 12321(12321/0)
Mar 30, 2019 2:33:53 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(75/443) took 204411 ms. Total solver calls (SAT/UNSAT): 12578(12578/0)
Mar 30, 2019 2:33:57 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(76/443) took 208285 ms. Total solver calls (SAT/UNSAT): 12705(12705/0)
Mar 30, 2019 2:34:03 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(78/443) took 214112 ms. Total solver calls (SAT/UNSAT): 12956(12956/0)
Mar 30, 2019 2:34:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(79/443) took 218037 ms. Total solver calls (SAT/UNSAT): 13080(13080/0)
Mar 30, 2019 2:34:11 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(80/443) took 222584 ms. Total solver calls (SAT/UNSAT): 13203(13203/0)
Mar 30, 2019 2:34:15 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(81/443) took 226446 ms. Total solver calls (SAT/UNSAT): 13325(13325/0)
Mar 30, 2019 2:34:19 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(82/443) took 229814 ms. Total solver calls (SAT/UNSAT): 13446(13446/0)
Mar 30, 2019 2:34:24 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(87/443) took 235061 ms. Total solver calls (SAT/UNSAT): 14036(14036/0)
Mar 30, 2019 2:34:27 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(88/443) took 238498 ms. Total solver calls (SAT/UNSAT): 14151(14151/0)
Mar 30, 2019 2:34:31 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(91/443) took 241973 ms. Total solver calls (SAT/UNSAT): 14490(14490/0)
Mar 30, 2019 2:34:34 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(93/443) took 245094 ms. Total solver calls (SAT/UNSAT): 14711(14711/0)
Mar 30, 2019 2:34:39 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(95/443) took 249781 ms. Total solver calls (SAT/UNSAT): 14928(14928/0)
Mar 30, 2019 2:34:45 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(98/443) took 255679 ms. Total solver calls (SAT/UNSAT): 15246(15246/0)
Mar 30, 2019 2:34:50 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(100/443) took 260927 ms. Total solver calls (SAT/UNSAT): 15453(15453/0)
Mar 30, 2019 2:34:53 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(102/443) took 264330 ms. Total solver calls (SAT/UNSAT): 15656(15656/0)
Mar 30, 2019 2:34:59 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(105/443) took 270032 ms. Total solver calls (SAT/UNSAT): 15953(15953/0)
Mar 30, 2019 2:35:04 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(107/443) took 274966 ms. Total solver calls (SAT/UNSAT): 16146(16146/0)
Mar 30, 2019 2:35:09 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(109/443) took 280081 ms. Total solver calls (SAT/UNSAT): 16335(16335/0)
Mar 30, 2019 2:35:14 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(111/443) took 285239 ms. Total solver calls (SAT/UNSAT): 16520(16520/0)
Mar 30, 2019 2:35:19 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(113/443) took 290311 ms. Total solver calls (SAT/UNSAT): 16701(16701/0)
Mar 30, 2019 2:35:23 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(115/443) took 293814 ms. Total solver calls (SAT/UNSAT): 16878(16878/0)
Mar 30, 2019 2:35:26 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(117/443) took 296967 ms. Total solver calls (SAT/UNSAT): 17051(17051/0)
Mar 30, 2019 2:35:30 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(119/443) took 301595 ms. Total solver calls (SAT/UNSAT): 17220(17220/0)
Mar 30, 2019 2:35:35 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(121/443) took 305715 ms. Total solver calls (SAT/UNSAT): 17385(17385/0)
Mar 30, 2019 2:35:38 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(126/443) took 308967 ms. Total solver calls (SAT/UNSAT): 17780(17780/0)
Mar 30, 2019 2:35:42 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(128/443) took 313400 ms. Total solver calls (SAT/UNSAT): 17931(17931/0)
Mar 30, 2019 2:35:47 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(130/443) took 318025 ms. Total solver calls (SAT/UNSAT): 18078(18078/0)
Mar 30, 2019 2:35:52 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(132/443) took 322678 ms. Total solver calls (SAT/UNSAT): 18221(18221/0)
Mar 30, 2019 2:35:56 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(134/443) took 326734 ms. Total solver calls (SAT/UNSAT): 18360(18360/0)
Skipping mayMatrices nes/nds SMT solver raised an exception or timeout.
java.lang.RuntimeException: SMT solver raised an exception or timeout.
at fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver.computeCoEnablingMatrix(NecessaryEnablingsolver.java:480)
at fr.lip6.move.gal.gal2pins.Gal2PinsTransformerNext.printLabels(Gal2PinsTransformerNext.java:530)
at fr.lip6.move.gal.gal2pins.Gal2PinsTransformerNext.printDependencyMatrix(Gal2PinsTransformerNext.java:209)
at fr.lip6.move.gal.gal2pins.Gal2PinsTransformerNext.buildBodyFile(Gal2PinsTransformerNext.java:85)
at fr.lip6.move.gal.gal2pins.Gal2PinsTransformerNext.transform(Gal2PinsTransformerNext.java:830)
at fr.lip6.move.gal.application.LTSminRunner$1.run(LTSminRunner.java:71)
at java.lang.Thread.run(Thread.java:748)
Java HotSpot(TM) 64-Bit Server VM warning: INFO: os::commit_memory(0x0000000738600000, 306184192, 0) failed; error='Cannot allocate memory' (errno=12)

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="QuasiCertifProtocol-COL-28"
export BK_EXAMINATION="GlobalProperties"
export BK_TOOL="itstools"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"

# 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-3954"
echo " Executing tool itstools"
echo " Input is QuasiCertifProtocol-COL-28, examination is GlobalProperties"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r129-oct2-155274856700237"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

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