Execution Chart

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

Trace from the execution

Waiting for the VM to be ready (probing ssh)
....................
/home/mcc/execution
total 216K
-rw-r--r-- 1 mcc users 4.0K May 15 18:54 CTLCardinality.txt
-rw-r--r-- 1 mcc users 22K May 15 18:54 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.7K May 15 18:54 CTLFireability.txt
-rw-r--r-- 1 mcc users 19K May 15 18:54 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K May 15 18:50 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 6.0K May 15 18:50 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 2.3K May 15 18:54 LTLCardinality.txt
-rw-r--r-- 1 mcc users 11K May 15 18:54 LTLCardinality.xml
-rw-r--r-- 1 mcc users 1.9K May 15 18:54 LTLFireability.txt
-rw-r--r-- 1 mcc users 9.6K May 15 18:54 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.8K May 15 18:54 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 20K May 15 18:54 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 105 May 15 18:54 ReachabilityDeadlock.txt
-rw-r--r-- 1 mcc users 343 May 15 18:54 ReachabilityDeadlock.xml
-rw-r--r-- 1 mcc users 3.2K May 15 18:54 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 20K May 15 18:54 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.6K May 15 18:54 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.6K May 15 18:54 UpperBounds.xml
-rw-r--r-- 1 mcc users 5 May 15 18:50 equiv_pt
-rw-r--r-- 1 mcc users 3 May 15 18:50 instance
-rw-r--r-- 1 mcc users 5 May 15 18:50 iscolored
-rw-r--r-- 1 mcc users 42K May 15 18:50 model.pnml
=====================================================================
Generated by BenchKit 2-3637
Executing tool itstools
Input is SafeBus-COL-06, examination is ReachabilityDeadlock
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r152-smll-152685550300188
=====================================================================

--------------------
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 SafeBus-COL-06-ReachabilityDeadlock-0

=== Now, execution of the tool begins

BK_START 1527027060565

22:11:04.362 [main] ERROR PNML validation - The rng grammar file can't be accessed : www.pnml.org
22:11:04.367 [main] ERROR import - Grammar file errors have been raised, the validation can't be done, process will continue without Grammar validation
Flatten gal took : 340 ms
Constant places removed 6 places and 12 transitions.
Iterating post reduction 0 with 6 rules applied. Total rules applied 6 place count 138 transition count 451
Applied a total of 6 rules in 42 ms. Remains 138 /144 variables (removed 6) and now considering 451/463 (removed 12) transitions.
Normalized transition count is 242
// Phase 1: matrix 242 rows 138 cols
Using solver Z3 to compute partial order matrices.
Built C files in :
/home/mcc/execution
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201805151631/bin/its-ctl-linux64, --gc-threshold, 2000000, --quiet, -i, /home/mcc/execution/ReachabilityDeadlock.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.201805151631/bin/its-ctl-linux64 --gc-threshold 2000000 --quiet -i /home/mcc/execution/ReachabilityDeadlock.pnml.gal -t CGAL -ctl DEADLOCK
No direction supplied, using forward translation only.
Presburger conditions satisfied. Using coverability to approximate state space in K-Induction.
Normalized transition count is 242
// Phase 1: matrix 242 rows 138 cols
invariant :AMC_30 + AMC_31 + AMC_32 + AMC_33 + AMC_34 + AMC_35 + wait_ack_30 + wait_ack_31 + wait_ack_32 + wait_ack_33 + wait_ack_34 + listen_5 + RMC_5 + PMC_5 + wait_msg_5 + loop_em_5 = 1
invariant :cable_free_0 + wait_ack_1 + wait_ack_2 + wait_ack_3 + wait_ack_4 + wait_ack_5 + wait_ack_6 + wait_ack_8 + wait_ack_9 + wait_ack_10 + wait_ack_11 + wait_ack_12 + wait_ack_13 + wait_ack_15 + wait_ack_16 + wait_ack_17 + wait_ack_18 + wait_ack_19 + wait_ack_20 + wait_ack_22 + wait_ack_23 + wait_ack_24 + wait_ack_25 + wait_ack_26 + wait_ack_27 + wait_ack_29 + wait_ack_30 + wait_ack_31 + wait_ack_32 + wait_ack_33 + wait_ack_34 + PMC_0 + PMC_1 + PMC_2 + PMC_3 + PMC_4 + PMC_5 + -1'FMCb_0 = 0
invariant :AMC_12 + AMC_13 + AMC_14 + AMC_15 + AMC_16 + AMC_17 + wait_ack_12 + wait_ack_13 + wait_ack_15 + wait_ack_16 + wait_ack_17 + listen_2 + RMC_2 + PMC_2 + wait_msg_2 + loop_em_2 = 1
invariant :cable_used_5 + -1'FMC_5 + -1'wait_ack_30 + -1'wait_ack_31 + -1'wait_ack_32 + -1'wait_ack_33 + -1'wait_ack_34 + -1'PMC_5 = 0
invariant :FMC_0 + FMC_1 + FMC_2 + FMC_3 + FMC_4 + FMC_5 + FMCb_0 = 1
invariant :Cpt2_0 + Cpt1_1 + Cpt1_2 + Cpt1_3 + Cpt1_4 + Cpt1_5 + -1'AMC_0 + AMC_5 + -1'AMC_6 + AMC_11 + -1'AMC_12 + AMC_17 + -1'AMC_18 + AMC_23 + -1'AMC_24 + AMC_29 + -1'AMC_30 + AMC_35 = 1
invariant :R_tout_0 + S_tout_0 = 1
invariant :cable_used_1 + -1'FMC_1 + -1'wait_ack_6 + -1'wait_ack_8 + -1'wait_ack_9 + -1'wait_ack_10 + -1'wait_ack_11 + -1'PMC_1 = 0
invariant :-1'AMC_6 + -1'AMC_7 + -1'AMC_8 + -1'AMC_9 + -1'AMC_10 + -1'AMC_11 + wait_cable_1 + -1'RMC_1 + -1'PMC_1 = 0
invariant :AMC_0 + AMC_1 + AMC_2 + AMC_3 + AMC_4 + AMC_5 + wait_ack_1 + wait_ack_2 + wait_ack_3 + wait_ack_4 + wait_ack_5 + listen_0 + RMC_0 + PMC_0 + wait_msg_0 + loop_em_0 = 1
invariant :AMC_24 + AMC_25 + AMC_26 + AMC_27 + AMC_28 + AMC_29 + wait_ack_24 + wait_ack_25 + wait_ack_26 + wait_ack_27 + wait_ack_29 + listen_4 + RMC_4 + PMC_4 + wait_msg_4 + loop_em_4 = 1
invariant :cable_used_3 + -1'FMC_3 + -1'wait_ack_18 + -1'wait_ack_19 + -1'wait_ack_20 + -1'wait_ack_22 + -1'wait_ack_23 + -1'PMC_3 = 0
invariant :AMC_18 + AMC_19 + AMC_20 + AMC_21 + AMC_22 + AMC_23 + wait_ack_18 + wait_ack_19 + wait_ack_20 + wait_ack_22 + wait_ack_23 + listen_3 + RMC_3 + PMC_3 + wait_msg_3 + loop_em_3 = 1
invariant :cable_used_2 + -1'FMC_2 + -1'wait_ack_12 + -1'wait_ack_13 + -1'wait_ack_15 + -1'wait_ack_16 + -1'wait_ack_17 + -1'PMC_2 = 0
invariant :wait_ack_30 + wait_ack_31 + wait_ack_32 + wait_ack_33 + wait_ack_34 + wait_cable_5 + listen_5 + wait_msg_5 + loop_em_5 = 1
invariant :Cpt2_4 + -1'Cpt1_4 + AMC_3 + -1'AMC_4 + AMC_9 + -1'AMC_10 + AMC_15 + -1'AMC_16 + AMC_21 + -1'AMC_22 + AMC_27 + -1'AMC_28 + AMC_33 + -1'AMC_34 = 0
invariant :Cpt2_1 + -1'Cpt1_1 + AMC_0 + -1'AMC_1 + AMC_6 + -1'AMC_7 + AMC_12 + -1'AMC_13 + AMC_18 + -1'AMC_19 + AMC_24 + -1'AMC_25 + AMC_30 + -1'AMC_31 = 0
invariant :-1'wait_ack_1 + -1'wait_ack_2 + -1'wait_ack_3 + -1'wait_ack_4 + -1'wait_ack_5 + -1'wait_ack_6 + -1'wait_ack_8 + -1'wait_ack_9 + -1'wait_ack_10 + -1'wait_ack_11 + -1'wait_ack_12 + -1'wait_ack_13 + -1'wait_ack_15 + -1'wait_ack_16 + -1'wait_ack_17 + -1'wait_ack_18 + -1'wait_ack_19 + -1'wait_ack_20 + -1'wait_ack_22 + -1'wait_ack_23 + -1'wait_ack_24 + -1'wait_ack_25 + -1'wait_ack_26 + -1'wait_ack_27 + -1'wait_ack_29 + -1'wait_ack_30 + -1'wait_ack_31 + -1'wait_ack_32 + -1'wait_ack_33 + -1'wait_ack_34 + ACK_0 + MSG_0 + MSG_1 + MSG_2 + MSG_3 + MSG_4 + MSG_5 + T_out_0 = 0
invariant :Cpt2_5 + -1'Cpt1_5 + AMC_4 + -1'AMC_5 + AMC_10 + -1'AMC_11 + AMC_16 + -1'AMC_17 + AMC_22 + -1'AMC_23 + AMC_28 + -1'AMC_29 + AMC_34 + -1'AMC_35 = 0
invariant :cable_used_0 + FMC_1 + FMC_2 + FMC_3 + FMC_4 + FMC_5 + -1'wait_ack_1 + -1'wait_ack_2 + -1'wait_ack_3 + -1'wait_ack_4 + -1'wait_ack_5 + -1'PMC_0 + FMCb_0 = 1
invariant :-1'AMC_12 + -1'AMC_13 + -1'AMC_14 + -1'AMC_15 + -1'AMC_16 + -1'AMC_17 + wait_cable_2 + -1'RMC_2 + -1'PMC_2 = 0
invariant :-1'AMC_18 + -1'AMC_19 + -1'AMC_20 + -1'AMC_21 + -1'AMC_22 + -1'AMC_23 + wait_cable_3 + -1'RMC_3 + -1'PMC_3 = 0
invariant :cable_used_4 + -1'FMC_4 + -1'wait_ack_24 + -1'wait_ack_25 + -1'wait_ack_26 + -1'wait_ack_27 + -1'wait_ack_29 + -1'PMC_4 = 0
invariant :Cpt1_0 + Cpt1_1 + Cpt1_2 + Cpt1_3 + Cpt1_4 + Cpt1_5 = 1
invariant :AMC_6 + AMC_7 + AMC_8 + AMC_9 + AMC_10 + AMC_11 + wait_ack_6 + wait_ack_8 + wait_ack_9 + wait_ack_10 + wait_ack_11 + listen_1 + RMC_1 + PMC_1 + wait_msg_1 + loop_em_1 = 1
invariant :Cpt2_2 + -1'Cpt1_2 + AMC_1 + -1'AMC_2 + AMC_7 + -1'AMC_8 + AMC_13 + -1'AMC_14 + AMC_19 + -1'AMC_20 + AMC_25 + -1'AMC_26 + AMC_31 + -1'AMC_32 = 0
invariant :wait_ack_1 + wait_ack_2 + wait_ack_3 + wait_ack_4 + wait_ack_5 + wait_cable_0 + listen_0 + wait_msg_0 + loop_em_0 = 1
invariant :wait_ack_24 + wait_ack_25 + wait_ack_26 + wait_ack_27 + wait_ack_29 + wait_cable_4 + listen_4 + wait_msg_4 + loop_em_4 = 1
invariant :Cpt2_3 + -1'Cpt1_3 + AMC_2 + -1'AMC_3 + AMC_8 + -1'AMC_9 + AMC_14 + -1'AMC_15 + AMC_20 + -1'AMC_21 + AMC_26 + -1'AMC_27 + AMC_32 + -1'AMC_33 = 0
Running compilation step : CommandLine [args=[gcc, -c, -I/home/mcc/BenchKit//lts_install_dir//include, -I., -std=c99, -fPIC, -O3, model.c], workingDir=/home/mcc/execution]
Compilation finished in 6951 ms.
Running link step : CommandLine [args=[gcc, -shared, -o, gal.so, model.o], workingDir=/home/mcc/execution]
Link finished in 49 ms.
Running LTSmin : CommandLine [args=[/home/mcc/BenchKit//lts_install_dir//bin/pins2lts-mc, ./gal.so, --threads=1, -p, --pins-guards, --when, -d], workingDir=/home/mcc/execution]
Model ,|S| ,Time ,Mem(kb) ,fin. SDD ,fin. DDD ,peak SDD ,peak DDD ,SDD Hom ,SDD cache peak ,DDD Hom ,DDD cachepeak ,SHom cache
reachable,6.81676e+06,262.648,2785120,2,561992,5,6.47024e+06,6,0,1006,5.03691e+06,0


Model ,|S| ,Time ,Mem(kb) ,fin. SDD ,fin. DDD ,peak SDD ,peak DDD ,SDD Hom ,SDD cache peak ,DDD Hom ,DDD cachepeak ,SHom cache
dead,0,554.313,7954264,1,0,6,2.30223e+07,9,1,6088,5.03691e+06,2

System contains 0 deadlocks (shown below if less than --print-limit option) !
FORMULA SafeBus-COL-06-ReachabilityDeadlock-0 FALSE TECHNIQUES DECISION_DIAGRAMS TOPOLOGICAL USE_NUPN
EmptySet
WARNING : LTS min runner thread was asked to interrupt. Dying gracefully.

BK_STOP 1527027624803

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

+ export BINDIR=/home/mcc/BenchKit/
+ BINDIR=/home/mcc/BenchKit/
++ pwd
+ export MODEL=/home/mcc/execution
+ MODEL=/home/mcc/execution
+ /home/mcc/BenchKit//runeclipse.sh /home/mcc/execution ReachabilityDeadlock -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 -consoleLog -data /home/mcc/execution/workspace -pnfolder /home/mcc/execution -examination ReachabilityDeadlock -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
May 22, 2018 10:11:03 PM fr.lip6.move.gal.application.Application start
INFO: Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, ReachabilityDeadlock, -z3path, /home/mcc/BenchKit//z3/bin/z3, -yices2path, /home/mcc/BenchKit//yices/bin/yices, -its, -ltsminpath, /home/mcc/BenchKit//lts_install_dir/, -smt]
May 22, 2018 10:11:03 PM fr.lip6.move.gal.application.MccTranslator transformPNML
INFO: Parsing pnml file : /home/mcc/execution/model.pnml
May 22, 2018 10:11:03 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Detected file is not PT type :http://www.pnml.org/version-2009/grammar/symmetricnet
May 22, 2018 10:11:05 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Load time of PNML (colored model parsed with PNMLFW) : 1342 ms
May 22, 2018 10:11:05 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 20 places.
May 22, 2018 10:11:05 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Computed order using colors.
May 22, 2018 10:11:05 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: sort/places :Dom->AMC,wait_ack,
Dot->cable_free,ACK,T_out,R_tout,S_tout,FMCb,
It->Cpt2,Cpt1,msgl,cable_used,FMC,wait_cable,listen,RMC,PMC,MSG,wait_msg,loop_em,

May 22, 2018 10:11:05 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 14 transitions.
May 22, 2018 10:11:05 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Computed order based on color domains.
May 22, 2018 10:11:05 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.img.gal : 11 ms
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $x and $y of transition C_refuse
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $i and $j of transition C_free
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $x and $y of transition C_provide
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $r and $i of transition I_rec1
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $i and $j of transition I_emit
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $i and $j of transition I_refused
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $r and $i of transition I_rec2
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.DomainAnalyzer computeVariableDomains
INFO: Found a total of 1 fixed domain variables (out of 150 variables) in GAL type Document
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: Found a total of 6 constant array cells/variables (out of 150 variables) in type Document
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: msgl[0-5],
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Instantiator instantiateParameters
INFO: On-the-fly reduction of False transitions avoided exploring 6.0 instantiations of transitions. Total transitions/syncs built is 340
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.DomainAnalyzer computeVariableDomains
INFO: Found a total of 1 fixed domain variables (out of 150 variables) in GAL type Document
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: Found a total of 6 constant array cells/variables (out of 150 variables) in type Document
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: msgl[0-5],
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.Simplifier simplifyConstantVariables
INFO: Removed constant array :msgl[]
May 22, 2018 10:11:05 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 202 ms
May 22, 2018 10:11:05 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.simple.gal : 14 ms
May 22, 2018 10:11:05 PM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was not deterministic with 34 transitions. Expanding to a total of 586 deterministic transitions.
May 22, 2018 10:11:05 PM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Determinization took 10 ms.
May 22, 2018 10:11:06 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/ReachabilityDeadlock.pnml.gal : 14 ms
May 22, 2018 10:11:06 PM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was already deterministic with 451 transitions.
May 22, 2018 10:11:06 PM fr.lip6.move.gal.gal2smt.bmc.KInductionSolver computeAndDeclareInvariants
INFO: Computed 29 place invariants in 57 ms
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.KInductionSolver init
INFO: Proved 138 variables to be positive in 659 ms
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeAblingMatrix
INFO: Computing symmetric may disable matrix : 451 transitions.
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of disable matrix completed :0/451 took 0 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Complete disable matrix. took 75 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeAblingMatrix
INFO: Computing symmetric may enable matrix : 451 transitions.
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Complete enable matrix. took 61 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 22, 2018 10:11:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeCoEnablingMatrix
INFO: Computing symmetric co enabling matrix : 451 transitions.
May 22, 2018 10:11:12 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(1/451) took 5397 ms. Total solver calls (SAT/UNSAT): 467(391/76)
May 22, 2018 10:11:17 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(2/451) took 9648 ms. Total solver calls (SAT/UNSAT): 849(704/145)
May 22, 2018 10:11:20 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(3/451) took 13161 ms. Total solver calls (SAT/UNSAT): 1230(1017/213)
May 22, 2018 10:11:24 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(4/451) took 17476 ms. Total solver calls (SAT/UNSAT): 1610(1330/280)
May 22, 2018 10:11:28 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(5/451) took 21221 ms. Total solver calls (SAT/UNSAT): 1989(1643/346)
May 22, 2018 10:11:32 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(9/451) took 24626 ms. Total solver calls (SAT/UNSAT): 3459(2839/620)
May 22, 2018 10:11:35 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(13/451) took 27924 ms. Total solver calls (SAT/UNSAT): 4913(4020/893)
May 22, 2018 10:11:38 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(17/451) took 31232 ms. Total solver calls (SAT/UNSAT): 6351(5186/1165)
May 22, 2018 10:11:41 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(21/451) took 34514 ms. Total solver calls (SAT/UNSAT): 7773(6337/1436)
May 22, 2018 10:11:45 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(25/451) took 37843 ms. Total solver calls (SAT/UNSAT): 9179(7473/1706)
May 22, 2018 10:11:48 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(29/451) took 40991 ms. Total solver calls (SAT/UNSAT): 10569(8589/1980)
May 22, 2018 10:11:51 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(32/451) took 44143 ms. Total solver calls (SAT/UNSAT): 11689(9082/2607)
May 22, 2018 10:11:55 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(34/451) took 48450 ms. Total solver calls (SAT/UNSAT): 12460(9507/2953)
May 22, 2018 10:11:59 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(36/451) took 51817 ms. Total solver calls (SAT/UNSAT): 13227(9928/3299)
May 22, 2018 10:12:03 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(40/451) took 56370 ms. Total solver calls (SAT/UNSAT): 14629(10477/4152)
May 22, 2018 10:12:06 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(43/451) took 59448 ms. Total solver calls (SAT/UNSAT): 15670(10838/4832)
May 22, 2018 10:12:10 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(45/451) took 62627 ms. Total solver calls (SAT/UNSAT): 16359(11205/5154)
May 22, 2018 10:12:14 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(48/451) took 66946 ms. Total solver calls (SAT/UNSAT): 17385(11748/5637)
May 22, 2018 10:12:18 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(51/451) took 70835 ms. Total solver calls (SAT/UNSAT): 18402(12115/6287)
May 22, 2018 10:12:21 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(53/451) took 74131 ms. Total solver calls (SAT/UNSAT): 19075(12478/6597)
May 22, 2018 10:12:25 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(56/451) took 78426 ms. Total solver calls (SAT/UNSAT): 20077(12842/7235)
May 22, 2018 10:12:29 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(58/451) took 81900 ms. Total solver calls (SAT/UNSAT): 20740(13207/7533)
May 22, 2018 10:12:34 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(62/451) took 86994 ms. Total solver calls (SAT/UNSAT): 22114(13782/8332)
May 22, 2018 10:12:37 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(64/451) took 90210 ms. Total solver calls (SAT/UNSAT): 22825(14207/8618)
May 22, 2018 10:12:42 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(67/451) took 95090 ms. Total solver calls (SAT/UNSAT): 23884(14837/9047)
May 22, 2018 10:12:46 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(71/451) took 98922 ms. Total solver calls (SAT/UNSAT): 25282(15383/9899)
May 22, 2018 10:12:49 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(73/451) took 102106 ms. Total solver calls (SAT/UNSAT): 25945(15742/10203)
May 22, 2018 10:12:53 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(76/451) took 105710 ms. Total solver calls (SAT/UNSAT): 26887(16047/10840)
May 22, 2018 10:12:58 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(79/451) took 110972 ms. Total solver calls (SAT/UNSAT): 27820(16527/11293)
May 22, 2018 10:13:01 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(82/451) took 114291 ms. Total solver calls (SAT/UNSAT): 28744(16832/11912)
May 22, 2018 10:13:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(85/451) took 119923 ms. Total solver calls (SAT/UNSAT): 29659(17312/12347)
May 22, 2018 10:13:10 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(88/451) took 123252 ms. Total solver calls (SAT/UNSAT): 30565(17617/12948)
May 22, 2018 10:13:16 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(91/451) took 129152 ms. Total solver calls (SAT/UNSAT): 31462(18097/13365)
May 22, 2018 10:13:20 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(94/451) took 132737 ms. Total solver calls (SAT/UNSAT): 32350(18402/13948)
May 22, 2018 10:13:25 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(97/451) took 137671 ms. Total solver calls (SAT/UNSAT): 33259(18912/14347)
May 22, 2018 10:13:28 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(100/451) took 140690 ms. Total solver calls (SAT/UNSAT): 34219(19277/14942)
May 22, 2018 10:13:33 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(103/451) took 145920 ms. Total solver calls (SAT/UNSAT): 35170(19811/15359)
May 22, 2018 10:13:37 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(107/451) took 149820 ms. Total solver calls (SAT/UNSAT): 36424(20268/16156)
May 22, 2018 10:13:42 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(109/451) took 154698 ms. Total solver calls (SAT/UNSAT): 37015(20561/16454)
May 22, 2018 10:13:47 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(113/451) took 159587 ms. Total solver calls (SAT/UNSAT): 38125(20928/17197)
May 22, 2018 10:13:50 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(115/451) took 163001 ms. Total solver calls (SAT/UNSAT): 38674(21191/17483)
May 22, 2018 10:13:54 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(120/451) took 167060 ms. Total solver calls (SAT/UNSAT): 40029(21678/18351)
May 22, 2018 10:13:58 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(124/451) took 170589 ms. Total solver calls (SAT/UNSAT): 41095(22067/19028)
May 22, 2018 10:14:01 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(128/451) took 174505 ms. Total solver calls (SAT/UNSAT): 42145(22575/19570)
May 22, 2018 10:14:05 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(132/451) took 178067 ms. Total solver calls (SAT/UNSAT): 43179(22938/20241)
May 22, 2018 10:14:09 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(134/451) took 182494 ms. Total solver calls (SAT/UNSAT): 43750(23265/20485)
May 22, 2018 10:14:12 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(137/451) took 185533 ms. Total solver calls (SAT/UNSAT): 44599(23568/21031)
May 22, 2018 10:14:16 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(139/451) took 189420 ms. Total solver calls (SAT/UNSAT): 45160(23855/21305)
May 22, 2018 10:14:20 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(143/451) took 192856 ms. Total solver calls (SAT/UNSAT): 46270(24223/22047)
May 22, 2018 10:14:24 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(147/451) took 196713 ms. Total solver calls (SAT/UNSAT): 47274(24637/22637)
May 22, 2018 10:14:27 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(152/451) took 199739 ms. Total solver calls (SAT/UNSAT): 48469(25019/23450)
May 22, 2018 10:14:30 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(157/451) took 203085 ms. Total solver calls (SAT/UNSAT): 49639(25400/24239)
May 22, 2018 10:14:33 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(162/451) took 206347 ms. Total solver calls (SAT/UNSAT): 50784(25768/25016)
May 22, 2018 10:14:37 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(167/451) took 209748 ms. Total solver calls (SAT/UNSAT): 51904(26153/25751)
May 22, 2018 10:14:40 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(170/451) took 213319 ms. Total solver calls (SAT/UNSAT): 52624(26504/26120)
May 22, 2018 10:14:45 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(175/451) took 217631 ms. Total solver calls (SAT/UNSAT): 53854(26969/26885)
May 22, 2018 10:14:48 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(180/451) took 220722 ms. Total solver calls (SAT/UNSAT): 55059(27338/27721)
May 22, 2018 10:14:51 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(186/451) took 224018 ms. Total solver calls (SAT/UNSAT): 56292(27658/28634)
May 22, 2018 10:14:54 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(191/451) took 227037 ms. Total solver calls (SAT/UNSAT): 57292(27918/29374)
May 22, 2018 10:14:57 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(196/451) took 230322 ms. Total solver calls (SAT/UNSAT): 58267(28238/30029)
May 22, 2018 10:15:01 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(204/451) took 233718 ms. Total solver calls (SAT/UNSAT): 59775(28618/31157)
May 22, 2018 10:15:04 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(208/451) took 237244 ms. Total solver calls (SAT/UNSAT): 60625(28998/31627)
May 22, 2018 10:15:07 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(219/451) took 240374 ms. Total solver calls (SAT/UNSAT): 62790(29505/33285)
May 22, 2018 10:15:10 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(226/451) took 243510 ms. Total solver calls (SAT/UNSAT): 63952(29702/34250)
May 22, 2018 10:15:14 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(233/451) took 247029 ms. Total solver calls (SAT/UNSAT): 65065(29898/35167)
May 22, 2018 10:15:17 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(239/451) took 250509 ms. Total solver calls (SAT/UNSAT): 65980(30063/35917)
May 22, 2018 10:15:21 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(242/451) took 253573 ms. Total solver calls (SAT/UNSAT): 66484(30192/36292)
May 22, 2018 10:15:25 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(245/451) took 257609 ms. Total solver calls (SAT/UNSAT): 67009(30378/36631)
May 22, 2018 10:15:28 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(255/451) took 260894 ms. Total solver calls (SAT/UNSAT): 68119(30619/37500)
May 22, 2018 10:15:31 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(259/451) took 264328 ms. Total solver calls (SAT/UNSAT): 68705(30873/37832)
May 22, 2018 10:15:35 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(263/451) took 268103 ms. Total solver calls (SAT/UNSAT): 69215(31049/38166)
May 22, 2018 10:15:39 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(268/451) took 271792 ms. Total solver calls (SAT/UNSAT): 69890(31305/38585)
May 22, 2018 10:15:42 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(271/451) took 275545 ms. Total solver calls (SAT/UNSAT): 70307(31473/38834)
May 22, 2018 10:15:47 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(276/451) took 279659 ms. Total solver calls (SAT/UNSAT): 70912(31663/39249)
May 22, 2018 10:15:50 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(281/451) took 283027 ms. Total solver calls (SAT/UNSAT): 71492(31823/39669)
May 22, 2018 10:15:53 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(287/451) took 286392 ms. Total solver calls (SAT/UNSAT): 72179(32009/40170)
May 22, 2018 10:15:56 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(312/451) took 289395 ms. Total solver calls (SAT/UNSAT): 74152(32326/41826)
May 22, 2018 10:15:59 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(347/451) took 292412 ms. Total solver calls (SAT/UNSAT): 75729(32783/42946)
May 22, 2018 10:16:02 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(441/451) took 295417 ms. Total solver calls (SAT/UNSAT): 78642(33234/45408)
May 22, 2018 10:16:02 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Finished co-enabling matrix. took 295539 ms. Total solver calls (SAT/UNSAT): 78678(33237/45441)
May 22, 2018 10:16:02 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeDoNotAccord
INFO: Computing Do-Not-Accords matrix : 451 transitions.
May 22, 2018 10:16:10 PM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Completed DNA matrix. took 7242 ms. Total solver calls (SAT/UNSAT): 5382(0/5382)
May 22, 2018 10:16:10 PM fr.lip6.move.gal.gal2pins.Gal2PinsTransformerNext transform
INFO: Built C files in 304140ms conformant to PINS in folder :/home/mcc/execution

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="SafeBus-COL-06"
export BK_EXAMINATION="ReachabilityDeadlock"
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

tar xzf /home/mcc/BenchKit/INPUTS/SafeBus-COL-06.tgz
mv SafeBus-COL-06 execution
cd execution
pwd
ls -lh

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-3637"
echo " Executing tool itstools"
echo " Input is SafeBus-COL-06, examination is ReachabilityDeadlock"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r152-smll-152685550300188"
echo "====================================================================="
echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "ReachabilityDeadlock" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "ReachabilityDeadlock" != "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 "ReachabilityDeadlock.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property ReachabilityDeadlock.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "ReachabilityDeadlock.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 '' ReachabilityDeadlock.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 ;