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 204K
-rw-r--r-- 1 mcc users 3.3K May 15 18:54 CTLCardinality.txt
-rw-r--r-- 1 mcc users 17K May 15 18:54 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.6K May 15 18:54 CTLFireability.txt
-rw-r--r-- 1 mcc users 16K 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.3K May 15 18:50 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 2.4K 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 2.0K May 15 18:54 LTLFireability.txt
-rw-r--r-- 1 mcc users 8.6K May 15 18:54 LTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K 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 113 May 15 18:54 ReachabilityDeadlock.txt
-rw-r--r-- 1 mcc users 351 May 15 18:54 ReachabilityDeadlock.xml
-rw-r--r-- 1 mcc users 2.8K May 15 18:54 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 16K May 15 18:54 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.7K May 15 18:54 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.8K 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 2 May 15 18:50 instance
-rw-r--r-- 1 mcc users 5 May 15 18:50 iscolored
-rw-r--r-- 1 mcc users 44K May 15 18:50 model.pnml
=====================================================================
Generated by BenchKit 2-3637
Executing tool itstoolsl
Input is LamportFastMutEx-COL-8, examination is ReachabilityDeadlock
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r117-csrt-152666476500104
=====================================================================

--------------------
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 LamportFastMutEx-COL-8-ReachabilityDeadlock-0

=== Now, execution of the tool begins

BK_START 1527241679780

09:48:01.988 [main] ERROR PNML validation - The rng grammar file can't be accessed : www.pnml.org
09:48:01.991 [main] ERROR import - Grammar file errors have been raised, the validation can't be done, process will continue without Grammar validation
Flatten gal took : 244 ms
Constant places removed 18 places and 9 transitions.
Iterating post reduction 0 with 18 rules applied. Total rules applied 18 place count 297 transition count 657
Symmetric choice reduction at 1 with 1 rule applications. Total rules 19 place count 297 transition count 657
Constant places removed 1 places and 4 transitions.
Performed 1 Post agglomeration using F-continuation condition.
Iterating post reduction 1 with 2 rules applied. Total rules applied 21 place count 296 transition count 652
Constant places removed 2 places and 4 transitions.
Iterating post reduction 2 with 2 rules applied. Total rules applied 23 place count 294 transition count 648
Constant places removed 14 places and 18 transitions.
Iterating post reduction 3 with 14 rules applied. Total rules applied 37 place count 280 transition count 630
Constant places removed 10 places and 18 transitions.
Iterating post reduction 4 with 10 rules applied. Total rules applied 47 place count 270 transition count 612
Constant places removed 2 places and 10 transitions.
Iterating post reduction 5 with 2 rules applied. Total rules applied 49 place count 268 transition count 602
Constant places removed 1 places and 9 transitions.
Iterating post reduction 6 with 1 rules applied. Total rules applied 50 place count 267 transition count 593
Constant places removed 1 places and 9 transitions.
Iterating post reduction 7 with 1 rules applied. Total rules applied 51 place count 266 transition count 584
Performed 8 Post agglomeration using F-continuation condition.
Constant places removed 8 places and 0 transitions.
Iterating post reduction 8 with 8 rules applied. Total rules applied 59 place count 258 transition count 576
Applied a total of 59 rules in 95 ms. Remains 258 /315 variables (removed 57) and now considering 576/666 (removed 90) transitions.
Normalized transition count is 408
// Phase 1: matrix 408 rows 258 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.
Normalized transition count is 408
// Phase 1: matrix 408 rows 258 cols
invariant :wait_67 + -1'P_await_13_7 + done_67 = 0
invariant :wait_66 + -1'P_await_13_7 + done_66 = 0
invariant :wait_44 + -1'P_await_13_4 + done_44 = 0
invariant :wait_11 + -1'P_await_13_1 + done_11 = 0
invariant :P_start_1_1 + P_setx_3_1 + P_setbi_5_1 + P_ify0_4_1 + P_sety_9_1 + P_ifxi_10_1 + P_setbi_11_1 + P_await_13_1 + P_ifyi_15_1 + P_awaity_1 + P_CS_21_1 + P_setbi_24_1 = 1
invariant :wait_17 + -1'P_await_13_1 + done_17 = 0
invariant :P_start_1_6 + P_setx_3_6 + P_setbi_5_6 + P_ify0_4_6 + P_sety_9_6 + P_ifxi_10_6 + P_setbi_11_6 + P_await_13_6 + P_ifyi_15_6 + P_awaity_6 + P_CS_21_6 + P_setbi_24_6 = 1
invariant :wait_37 + -1'P_await_13_4 + done_37 = 0
invariant :P_start_1_4 + P_setx_3_4 + P_setbi_5_4 + P_ify0_4_4 + P_sety_9_4 + P_ifxi_10_4 + P_setbi_11_4 + P_await_13_4 + P_ifyi_15_4 + P_awaity_4 + P_CS_21_4 + P_setbi_24_4 = 1
invariant :wait_25 + -1'P_await_13_2 + done_25 = 0
invariant :wait_49 + -1'P_await_13_5 + done_49 = 0
invariant :wait_57 + -1'P_await_13_6 + done_57 = 0
invariant :wait_12 + -1'P_await_13_1 + done_12 = 0
invariant :wait_53 + -1'P_await_13_5 + done_53 = 0
invariant :wait_55 + -1'P_await_13_6 + done_55 = 0
invariant :b_4 + b_5 = 1
invariant :b_12 + b_13 = 1
invariant :wait_16 + -1'P_await_13_1 + done_16 = 0
invariant :wait_69 + -1'P_await_13_7 + done_69 = 0
invariant :wait_42 + -1'P_await_13_4 + done_42 = 0
invariant :wait_19 + -1'P_await_13_2 + done_19 = 0
invariant :wait_68 + -1'P_await_13_7 + done_68 = 0
invariant :wait_26 + -1'P_await_13_2 + done_26 = 0
invariant :wait_15 + -1'P_await_13_1 + done_15 = 0
invariant :wait_76 + -1'P_await_13_8 + done_76 = 0
invariant :wait_39 + -1'P_await_13_4 + done_39 = 0
invariant :wait_77 + -1'P_await_13_8 + done_77 = 0
invariant :wait_34 + -1'P_await_13_3 + done_34 = 0
invariant :b_10 + b_11 = 1
invariant :b_2 + b_3 = 1
invariant :wait_31 + -1'P_await_13_3 + done_31 = 0
invariant :wait_75 + -1'P_await_13_8 + done_75 = 0
invariant :wait_59 + -1'P_await_13_6 + done_59 = 0
invariant :wait_23 + -1'P_await_13_2 + done_23 = 0
invariant :wait_40 + -1'P_await_13_4 + done_40 = 0
invariant :wait_61 + -1'P_await_13_6 + done_61 = 0
invariant :wait_28 + -1'P_await_13_3 + done_28 = 0
invariant :wait_43 + -1'P_await_13_4 + done_43 = 0
invariant :wait_14 + -1'P_await_13_1 + done_14 = 0
invariant :wait_60 + -1'P_await_13_6 + done_60 = 0
invariant :wait_70 + -1'P_await_13_7 + done_70 = 0
invariant :wait_73 + -1'P_await_13_8 + done_73 = 0
invariant :x_0 + x_1 + x_2 + x_3 + x_4 + x_5 + x_6 + x_7 + x_8 = 1
invariant :P_start_1_8 + P_setx_3_8 + P_setbi_5_8 + P_ify0_4_8 + P_sety_9_8 + P_ifxi_10_8 + P_setbi_11_8 + P_await_13_8 + P_ifyi_15_8 + P_awaity_8 + P_CS_21_8 + P_setbi_24_8 = 1
invariant :wait_20 + -1'P_await_13_2 + done_20 = 0
invariant :wait_78 + -1'P_await_13_8 + done_78 = 0
invariant :wait_51 + -1'P_await_13_5 + done_51 = 0
invariant :P_start_1_3 + P_setx_3_3 + P_setbi_5_3 + P_ify0_4_3 + P_sety_9_3 + P_ifxi_10_3 + P_setbi_11_3 + P_await_13_3 + P_ifyi_15_3 + P_awaity_3 + P_CS_21_3 + P_setbi_24_3 = 1
invariant :wait_35 + -1'P_await_13_3 + done_35 = 0
invariant :P_start_1_2 + P_setx_3_2 + P_setbi_5_2 + P_ify0_4_2 + P_sety_9_2 + P_ifxi_10_2 + P_setbi_11_2 + P_await_13_2 + P_ifyi_15_2 + P_awaity_2 + P_CS_21_2 + P_setbi_24_2 = 1
invariant :wait_13 + -1'P_await_13_1 + done_13 = 0
invariant :wait_38 + -1'P_await_13_4 + done_38 = 0
invariant :y_0 + y_1 + y_2 + y_3 + y_4 + y_5 + y_6 + y_7 + y_8 = 1
invariant :wait_48 + -1'P_await_13_5 + done_48 = 0
invariant :wait_52 + -1'P_await_13_5 + done_52 = 0
invariant :wait_79 + -1'P_await_13_8 + done_79 = 0
invariant :b_8 + b_9 = 1
invariant :wait_56 + -1'P_await_13_6 + done_56 = 0
invariant :wait_10 + -1'P_await_13_1 + done_10 = 0
invariant :wait_50 + -1'P_await_13_5 + done_50 = 0
invariant :wait_32 + -1'P_await_13_3 + done_32 = 0
invariant :b_16 + b_17 = 1
invariant :wait_65 + -1'P_await_13_7 + done_65 = 0
invariant :P_start_1_5 + P_setx_3_5 + P_setbi_5_5 + P_ify0_4_5 + P_sety_9_5 + P_ifxi_10_5 + P_setbi_11_5 + P_await_13_5 + P_ifyi_15_5 + P_awaity_5 + P_CS_21_5 + P_setbi_24_5 = 1
invariant :wait_30 + -1'P_await_13_3 + done_30 = 0
invariant :wait_33 + -1'P_await_13_3 + done_33 = 0
invariant :wait_29 + -1'P_await_13_3 + done_29 = 0
invariant :b_6 + b_7 = 1
invariant :wait_71 + -1'P_await_13_7 + done_71 = 0
invariant :wait_22 + -1'P_await_13_2 + done_22 = 0
invariant :b_14 + b_15 = 1
invariant :wait_41 + -1'P_await_13_4 + done_41 = 0
invariant :wait_24 + -1'P_await_13_2 + done_24 = 0
invariant :wait_47 + -1'P_await_13_5 + done_47 = 0
invariant :wait_62 + -1'P_await_13_6 + done_62 = 0
invariant :wait_21 + -1'P_await_13_2 + done_21 = 0
invariant :wait_80 + -1'P_await_13_8 + done_80 = 0
invariant :wait_46 + -1'P_await_13_5 + done_46 = 0
invariant :wait_64 + -1'P_await_13_7 + done_64 = 0
invariant :P_start_1_7 + P_setx_3_7 + P_setbi_5_7 + P_ify0_4_7 + P_sety_9_7 + P_ifxi_10_7 + P_setbi_11_7 + P_await_13_7 + P_ifyi_15_7 + P_awaity_7 + P_CS_21_7 + P_setbi_24_7 = 1
invariant :wait_58 + -1'P_await_13_6 + done_58 = 0
invariant :wait_74 + -1'P_await_13_8 + done_74 = 0
Invoking ITS tools like this :CommandLine [args=[/home/mcc/BenchKit/itstools/plugins/fr.lip6.move.gal.itstools.binaries_1.0.0.201805241334/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.201805241334/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.
built 176 ordering constraints for composite.
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 8188 ms.
Running link step : CommandLine [args=[gcc, -shared, -o, gal.so, model.o], workingDir=/home/mcc/execution]
Link finished in 101 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]
terminate called after throwing an instance of 'St9bad_alloc'
what(): std::bad_alloc

BK_TIME_CONFINEMENT_REACHED

--------------------
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/ -louvain -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/ -louvain -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 25, 2018 9:48:01 AM 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/, -louvain, -smt]
May 25, 2018 9:48:01 AM fr.lip6.move.gal.application.MccTranslator transformPNML
INFO: Parsing pnml file : /home/mcc/execution/model.pnml
May 25, 2018 9:48:01 AM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Detected file is not PT type :http://www.pnml.org/version-2009/grammar/symmetricnet
May 25, 2018 9:48:02 AM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Load time of PNML (colored model parsed with PNMLFW) : 865 ms
May 25, 2018 9:48:02 AM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 18 places.
May 25, 2018 9:48:02 AM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Computed order using colors.
May 25, 2018 9:48:02 AM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: sort/places :pid * pid->wait,done,
pid * bool->b,
pid->P-start_1,x,y,P-setx_3,P-setbi_5,P-ify0_4,P-sety_9,P-ifxi_10,P-setbi_11,P-fordo_12,P-await_13,P-ifyi_15,P-awaity,P-CS_21,P-setbi_24,

May 25, 2018 9:48:02 AM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 17 transitions.
May 25, 2018 9:48:02 AM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Computed order based on color domains.
May 25, 2018 9:48:02 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.img.gal : 8 ms
May 25, 2018 9:48:02 AM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $i and $y of transition T_yeqi_15
May 25, 2018 9:48:02 AM fr.lip6.move.gal.instantiate.Instantiator fuseEqualParameters
INFO: Fused parameters : $i and $x of transition T_xeqi_10
May 25, 2018 9:48:02 AM fr.lip6.move.gal.instantiate.Instantiator instantiateParameters
INFO: On-the-fly reduction of False transitions avoided exploring 19.0 instantiations of transitions. Total transitions/syncs built is 633
May 25, 2018 9:48:02 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 135 ms
May 25, 2018 9:48:02 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.simple.gal : 12 ms
May 25, 2018 9:48:02 AM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was not deterministic with 121 transitions. Expanding to a total of 786 deterministic transitions.
May 25, 2018 9:48:02 AM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Determinization took 6 ms.
May 25, 2018 9:48:03 AM fr.lip6.move.gal.application.MccTranslator applyOrder
INFO: Applying decomposition
May 25, 2018 9:48:03 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 116 ms
May 25, 2018 9:48:03 AM fr.lip6.move.gal.semantics.DeterministicNextBuilder getDeterministicNext
INFO: Input system was already deterministic with 576 transitions.
May 25, 2018 9:48:03 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 125 ms
May 25, 2018 9:48:03 AM fr.lip6.move.gal.instantiate.CompositeBuilder decomposeWithOrder
INFO: Decomposing Gal with order
May 25, 2018 9:48:03 AM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 73 ms
May 25, 2018 9:48:03 AM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting arrays to variables to allow decomposition.
May 25, 2018 9:48:03 AM fr.lip6.move.gal.gal2smt.bmc.KInductionSolver computeAndDeclareInvariants
INFO: Computed 82 place invariants in 58 ms
May 25, 2018 9:48:03 AM fr.lip6.move.gal.instantiate.Instantiator fuseIsomorphicEffects
INFO: Removed a total of 1011 redundant transitions.
May 25, 2018 9:48:03 AM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/ReachabilityDeadlock.pnml.gal : 7 ms
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.KInductionSolver init
INFO: Proved 258 variables to be positive in 810 ms
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeAblingMatrix
INFO: Computing symmetric may disable matrix : 576 transitions.
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of disable matrix completed :0/576 took 1 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Complete disable matrix. took 77 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeAblingMatrix
INFO: Computing symmetric may enable matrix : 576 transitions.
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Complete enable matrix. took 33 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 25, 2018 9:48:04 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeCoEnablingMatrix
INFO: Computing symmetric co enabling matrix : 576 transitions.
May 25, 2018 9:48:07 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(31/576) took 3060 ms. Total solver calls (SAT/UNSAT): 4240(273/3967)
May 25, 2018 9:48:10 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(68/576) took 6139 ms. Total solver calls (SAT/UNSAT): 10197(668/9529)
May 25, 2018 9:48:13 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(103/576) took 9301 ms. Total solver calls (SAT/UNSAT): 17708(1252/16456)
May 25, 2018 9:48:16 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(117/576) took 12334 ms. Total solver calls (SAT/UNSAT): 21859(1610/20249)
May 25, 2018 9:48:19 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(131/576) took 15387 ms. Total solver calls (SAT/UNSAT): 25814(1944/23870)
May 25, 2018 9:48:22 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(150/576) took 18414 ms. Total solver calls (SAT/UNSAT): 30868(2359/28509)
May 25, 2018 9:48:27 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(183/576) took 23056 ms. Total solver calls (SAT/UNSAT): 35988(2884/33104)
May 25, 2018 9:48:31 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(197/576) took 26564 ms. Total solver calls (SAT/UNSAT): 39215(3173/36042)
May 25, 2018 9:48:35 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(198/576) took 31120 ms. Total solver calls (SAT/UNSAT): 39438(3193/36245)
May 25, 2018 9:48:39 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(201/576) took 35142 ms. Total solver calls (SAT/UNSAT): 40101(3253/36848)
May 25, 2018 9:48:42 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(204/576) took 38395 ms. Total solver calls (SAT/UNSAT): 40755(3310/37445)
May 25, 2018 9:48:47 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(206/576) took 42464 ms. Total solver calls (SAT/UNSAT): 41186(3348/37838)
May 25, 2018 9:48:50 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(209/576) took 45947 ms. Total solver calls (SAT/UNSAT): 41825(3405/38420)
May 25, 2018 9:48:54 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(213/576) took 50179 ms. Total solver calls (SAT/UNSAT): 42663(3478/39185)
May 25, 2018 9:48:58 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(215/576) took 53908 ms. Total solver calls (SAT/UNSAT): 43076(3514/39562)
May 25, 2018 9:49:01 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(218/576) took 56940 ms. Total solver calls (SAT/UNSAT): 43688(3568/40120)
May 25, 2018 9:49:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(219/576) took 61730 ms. Total solver calls (SAT/UNSAT): 43890(3586/40304)
May 25, 2018 9:49:09 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(225/576) took 64880 ms. Total solver calls (SAT/UNSAT): 45081(3688/41393)
May 25, 2018 9:49:12 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(227/576) took 68092 ms. Total solver calls (SAT/UNSAT): 45470(3722/41748)
May 25, 2018 9:49:15 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(228/576) took 71136 ms. Total solver calls (SAT/UNSAT): 45663(3739/41924)
May 25, 2018 9:49:20 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(229/576) took 76210 ms. Total solver calls (SAT/UNSAT): 45855(3755/42100)
May 25, 2018 9:49:25 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(232/576) took 80593 ms. Total solver calls (SAT/UNSAT): 46425(3803/42622)
May 25, 2018 9:49:28 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(233/576) took 83912 ms. Total solver calls (SAT/UNSAT): 46613(3819/42794)
May 25, 2018 9:49:37 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(235/576) took 93012 ms. Total solver calls (SAT/UNSAT): 46986(3851/43135)
May 25, 2018 9:49:44 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(237/576) took 99790 ms. Total solver calls (SAT/UNSAT): 47355(3883/43472)
May 25, 2018 9:49:49 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(238/576) took 105217 ms. Total solver calls (SAT/UNSAT): 47538(3898/43640)
May 25, 2018 9:49:53 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(241/576) took 108593 ms. Total solver calls (SAT/UNSAT): 48081(3943/44138)
May 25, 2018 9:49:56 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(243/576) took 111898 ms. Total solver calls (SAT/UNSAT): 48438(3973/44465)
May 25, 2018 9:50:00 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(248/576) took 116087 ms. Total solver calls (SAT/UNSAT): 49313(4046/45267)
May 25, 2018 9:50:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(254/576) took 121525 ms. Total solver calls (SAT/UNSAT): 50330(4130/46200)
May 25, 2018 9:50:10 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(256/576) took 125525 ms. Total solver calls (SAT/UNSAT): 50598(4151/46447)
May 25, 2018 9:50:13 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(257/576) took 128786 ms. Total solver calls (SAT/UNSAT): 50699(4158/46541)
May 25, 2018 9:50:19 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(260/576) took 134760 ms. Total solver calls (SAT/UNSAT): 50996(4179/46817)
May 25, 2018 9:50:24 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(262/576) took 140090 ms. Total solver calls (SAT/UNSAT): 51189(4193/46996)
May 25, 2018 9:50:29 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(264/576) took 145322 ms. Total solver calls (SAT/UNSAT): 51378(4206/47172)
May 25, 2018 9:50:34 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(267/576) took 150154 ms. Total solver calls (SAT/UNSAT): 51654(4225/47429)
May 25, 2018 9:50:37 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(273/576) took 153217 ms. Total solver calls (SAT/UNSAT): 52179(4260/47919)
May 25, 2018 9:50:40 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(287/576) took 156326 ms. Total solver calls (SAT/UNSAT): 53264(4326/48938)
May 25, 2018 9:50:45 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(302/576) took 160871 ms. Total solver calls (SAT/UNSAT): 54209(4373/49836)
May 25, 2018 9:50:49 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(306/576) took 164637 ms. Total solver calls (SAT/UNSAT): 54423(4380/50043)
May 25, 2018 9:50:52 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(316/576) took 167681 ms. Total solver calls (SAT/UNSAT): 54888(4393/50495)
May 25, 2018 9:50:55 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(325/576) took 170747 ms. Total solver calls (SAT/UNSAT): 55241(4475/50766)
May 25, 2018 9:50:58 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(331/576) took 173759 ms. Total solver calls (SAT/UNSAT): 55466(4546/50920)
May 25, 2018 9:51:02 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(336/576) took 178278 ms. Total solver calls (SAT/UNSAT): 55648(4598/51050)
May 25, 2018 9:51:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(339/576) took 181731 ms. Total solver calls (SAT/UNSAT): 55758(4632/51126)
May 25, 2018 9:51:09 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(347/576) took 185090 ms. Total solver calls (SAT/UNSAT): 56042(4710/51332)
May 25, 2018 9:51:13 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(352/576) took 188922 ms. Total solver calls (SAT/UNSAT): 56214(4752/51462)
May 25, 2018 9:51:16 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(356/576) took 192104 ms. Total solver calls (SAT/UNSAT): 56352(4788/51564)
May 25, 2018 9:51:19 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(361/576) took 195395 ms. Total solver calls (SAT/UNSAT): 56521(4827/51694)
May 25, 2018 9:51:23 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(368/576) took 199162 ms. Total solver calls (SAT/UNSAT): 56748(4874/51874)
May 25, 2018 9:51:27 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(375/576) took 202855 ms. Total solver calls (SAT/UNSAT): 56968(4914/52054)
May 25, 2018 9:51:30 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(378/576) took 205912 ms. Total solver calls (SAT/UNSAT): 57070(4938/52132)
May 25, 2018 9:51:33 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(389/576) took 208962 ms. Total solver calls (SAT/UNSAT): 57354(4956/52398)
May 25, 2018 9:51:36 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(393/576) took 212033 ms. Total solver calls (SAT/UNSAT): 57695(4984/52711)
May 25, 2018 9:51:42 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(405/576) took 217719 ms. Total solver calls (SAT/UNSAT): 59381(5147/54234)
May 25, 2018 9:51:47 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(407/576) took 223344 ms. Total solver calls (SAT/UNSAT): 59648(5173/54475)
May 25, 2018 9:51:53 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(412/576) took 228917 ms. Total solver calls (SAT/UNSAT): 60298(5235/55063)
May 25, 2018 9:51:59 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(416/576) took 234541 ms. Total solver calls (SAT/UNSAT): 60800(5282/55518)
May 25, 2018 9:52:02 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(419/576) took 237597 ms. Total solver calls (SAT/UNSAT): 61166(5318/55848)
May 25, 2018 9:52:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(425/576) took 242038 ms. Total solver calls (SAT/UNSAT): 61871(5383/56488)
May 25, 2018 9:52:12 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(430/576) took 247806 ms. Total solver calls (SAT/UNSAT): 62431(5436/56995)
May 25, 2018 9:52:16 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(432/576) took 251639 ms. Total solver calls (SAT/UNSAT): 62648(5455/57193)
May 25, 2018 9:52:19 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(434/576) took 254752 ms. Total solver calls (SAT/UNSAT): 62861(5475/57386)
May 25, 2018 9:52:22 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(439/576) took 258214 ms. Total solver calls (SAT/UNSAT): 63376(5523/57853)
May 25, 2018 9:52:26 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(448/576) took 261958 ms. Total solver calls (SAT/UNSAT): 64240(5600/58640)
May 25, 2018 9:52:30 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(450/576) took 266027 ms. Total solver calls (SAT/UNSAT): 64421(5616/58805)
May 25, 2018 9:52:33 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(456/576) took 269279 ms. Total solver calls (SAT/UNSAT): 64940(5663/59277)
May 25, 2018 9:52:38 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(458/576) took 273762 ms. Total solver calls (SAT/UNSAT): 65105(5677/59428)
May 25, 2018 9:52:42 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(461/576) took 278073 ms. Total solver calls (SAT/UNSAT): 65345(5698/59647)
May 25, 2018 9:52:45 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(468/576) took 281118 ms. Total solver calls (SAT/UNSAT): 65590(5712/59878)
May 25, 2018 9:52:48 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(472/576) took 284319 ms. Total solver calls (SAT/UNSAT): 65707(5719/59988)
May 25, 2018 9:52:51 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(483/576) took 287428 ms. Total solver calls (SAT/UNSAT): 66466(5793/60673)
May 25, 2018 9:52:56 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(492/576) took 291545 ms. Total solver calls (SAT/UNSAT): 66997(5844/61153)
May 25, 2018 9:52:59 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(501/576) took 294721 ms. Total solver calls (SAT/UNSAT): 67447(5886/61561)
May 25, 2018 9:53:02 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(508/576) took 297838 ms. Total solver calls (SAT/UNSAT): 67741(5913/61828)
May 25, 2018 9:53:05 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of co-enabling matrix(547/576) took 300886 ms. Total solver calls (SAT/UNSAT): 68486(5964/62522)
May 25, 2018 9:53:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Finished co-enabling matrix. took 302208 ms. Total solver calls (SAT/UNSAT): 68524(5964/62560)
May 25, 2018 9:53:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver computeDoNotAccord
INFO: Computing Do-Not-Accords matrix : 576 transitions.
May 25, 2018 9:53:06 AM fr.lip6.move.gal.gal2smt.bmc.NecessaryEnablingsolver printStats
INFO: Computation of Completed DNA matrix. took 53 ms. Total solver calls (SAT/UNSAT): 0(0/0)
May 25, 2018 9:53:07 AM fr.lip6.move.gal.gal2pins.Gal2PinsTransformerNext transform
INFO: Built C files in 303758ms conformant to PINS in folder :/home/mcc/execution
ITS-tools command line returned an error code 134

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="LamportFastMutEx-COL-8"
export BK_EXAMINATION="ReachabilityDeadlock"
export BK_TOOL="itstoolsl"
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/LamportFastMutEx-COL-8.tgz
mv LamportFastMutEx-COL-8 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 itstoolsl"
echo " Input is LamportFastMutEx-COL-8, 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 r117-csrt-152666476500104"
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 ;