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 256K
-rw-r--r-- 1 mcc users 4.3K 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 3.2K 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.1K May 15 18:50 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 2.8K May 15 18:54 LTLCardinality.txt
-rw-r--r-- 1 mcc users 12K May 15 18:54 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.2K 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.4K May 15 18:54 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 14K May 15 18:54 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 108 May 15 18:54 ReachabilityDeadlock.txt
-rw-r--r-- 1 mcc users 346 May 15 18:54 ReachabilityDeadlock.xml
-rw-r--r-- 1 mcc users 3.4K May 15 18:54 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 17K 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 81K May 15 18:50 model.pnml
=====================================================================
Generated by BenchKit 2-3637
Executing tool itstools
Input is NeoElection-COL-4, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r116-csrt-152666475100255
=====================================================================

--------------------
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 NeoElection-COL-4-CTLCardinality-00
FORMULA_NAME NeoElection-COL-4-CTLCardinality-01
FORMULA_NAME NeoElection-COL-4-CTLCardinality-02
FORMULA_NAME NeoElection-COL-4-CTLCardinality-03
FORMULA_NAME NeoElection-COL-4-CTLCardinality-04
FORMULA_NAME NeoElection-COL-4-CTLCardinality-05
FORMULA_NAME NeoElection-COL-4-CTLCardinality-06
FORMULA_NAME NeoElection-COL-4-CTLCardinality-07
FORMULA_NAME NeoElection-COL-4-CTLCardinality-08
FORMULA_NAME NeoElection-COL-4-CTLCardinality-09
FORMULA_NAME NeoElection-COL-4-CTLCardinality-10
FORMULA_NAME NeoElection-COL-4-CTLCardinality-11
FORMULA_NAME NeoElection-COL-4-CTLCardinality-12
FORMULA_NAME NeoElection-COL-4-CTLCardinality-13
FORMULA_NAME NeoElection-COL-4-CTLCardinality-14
FORMULA_NAME NeoElection-COL-4-CTLCardinality-15

=== Now, execution of the tool begins

BK_START 1527171953498

14:25:55.772 [main] ERROR PNML validation - The rng grammar file can't be accessed : www.pnml.org
14:25:55.775 [main] ERROR import - Grammar file errors have been raised, the validation can't be done, process will continue without Grammar validation
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/CTLCardinality.pnml.gal, -t, CGAL, -ctl, /home/mcc/execution/CTLCardinality.ctl], 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/CTLCardinality.pnml.gal -t CGAL -ctl /home/mcc/execution/CTLCardinality.ctl
No direction supplied, using forward translation only.
Parsed 16 CTL formulae.
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,2.91912e+11,82.7185,1725752,2,225549,5,5.7046e+06,6,0,3668,3.0406e+06,0


Converting to forward existential form...Done !
original formula: !(EG(EX((((((P_electionInit_0+P_electionInit_1)+P_electionInit_2)+P_electionInit_3)+P_electionInit_4)>=2))))
=> equivalent forward existential formula: [FwdG(Init,EX((((((P_electionInit_0+P_electionInit_1)+P_electionInit_2)+P_electionInit_3)+P_electionInit_4)>=2)))] = FALSE
Reverse transition relation is NOT exact ! Due to transitions T_poll__end_1, T_poll__end_2, T_poll__end_3, T_poll__end_4, T_poll__handleAI1_2, T_poll__handleAI1_3, T_poll__handleAI1_4, T_poll__handleAI2_0_1_0, T_poll__handleAI2_0_2_0, T_poll__handleAI2_1_1_0, T_poll__handleAI2_1_2_0, T_startNeg__end_1, T_startNeg__end_2, T_startNeg__end_3, T_startNeg__end_4, Intersection with reachable at each step enabled. (destroyed/reverse/intersect/total) :1519/50/15/1584
terminate called after throwing an instance of 'std::bad_alloc'
what(): std::bad_alloc

BK_STOP 1527172821865

--------------------
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 CTLCardinality -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 CTLCardinality -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 24, 2018 2:25:55 PM fr.lip6.move.gal.application.Application start
INFO: Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, CTLCardinality, -z3path, /home/mcc/BenchKit//z3/bin/z3, -yices2path, /home/mcc/BenchKit//yices/bin/yices, -its, -ltsminpath, /home/mcc/BenchKit//lts_install_dir/, -smt]
May 24, 2018 2:25:55 PM fr.lip6.move.gal.application.MccTranslator transformPNML
INFO: Parsing pnml file : /home/mcc/execution/model.pnml
May 24, 2018 2:25:55 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 24, 2018 2:25:56 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Load time of PNML (colored model parsed with PNMLFW) : 969 ms
May 24, 2018 2:25:56 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 18 places.
May 24, 2018 2:25:56 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Computed order using colors.
May 24, 2018 2:25:56 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: sort/places :M * POS->P-sendAnnPs__broadcasting,P-startNeg__broadcasting,
M * STAGE->P-stage,
M * M * MT * M->P-network,P-poll__networl,
M * POS * M->P-masterList,
M * BOOL * M->P-masterState,
M->P-crashed,dead,P-electedPrimary,P-electedSecondary,P-electionFailed,P-electionInit,P-poll__handlingMessage,P-poll__pollEnd,P-poll__waitingMessage,P-polling,
M * M * NEGSTEP->P-negotiation,

May 24, 2018 2:25:56 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 22 transitions.
May 24, 2018 2:25:56 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Computed order based on color domains.
May 24, 2018 2:25:56 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/model.pnml.img.gal : 10 ms
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.DomainAnalyzer computeVariableDomains
INFO: Found a total of 4 fixed domain variables (out of 1830 variables) in GAL type NeoElection_COL_4
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: Found a total of 115 constant array cells/variables (out of 1830 variables) in type NeoElection_COL_4
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: P_crashed[0-4], P_electionFailed[0-4], P_masterList[0-99], dead[0-4],
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Instantiator instantiateParameters
INFO: On-the-fly reduction of False transitions avoided exploring 1001.0 instantiations of transitions. Total transitions/syncs built is 1794
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Instantiator normalizeCalls
INFO: Calls to non existing labels (possibly due to false guards) leads to 10 abort statements.
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.DomainAnalyzer computeVariableDomains
INFO: Found a total of 4 fixed domain variables (out of 1830 variables) in GAL type NeoElection_COL_4
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: Found a total of 1282 constant array cells/variables (out of 1830 variables) in type NeoElection_COL_4
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: P_negotiation[0,3,6,9,12,15,18,30,36,45,54,60,72], P_poll__networl[0-5,10-35,40-65,70-95,100-125,130-155,160-185,190-215,220-245,250-275,280-305,310-335,340-365,370-395,400-425,430-455,460-485,490-515,520-545,550-575,580-605,610-635,640-665,670-695,700-725,730-749], P_sendAnnPs__broadcasting[1-2], P_crashed[0-4], P_electionFailed[0-4], P_startNeg__broadcasting[1-2], P_masterList[0-99], P_network[1-4,11-14,16-19,21-24,26-29,31-34,41-44,46-49,51-54,56-59,61-64,71-74,76-79,81-84,86-89,91-94,101-104,106-109,111-114,116-119,121-124,131-134,136-139,141-144,146-149,151-154,161-164,166-169,171-174,176-179,181-184,191-194,196-199,201-204,206-209,211-214,221-224,226-229,231-234,236-239,241-244,251-254,256-259,261-264,266-269,271-274,281-284,286-289,291-294,296-299,301-304,311-314,316-319,321-324,326-329,331-334,341-344,346-349,351-354,356-359,361-364,371-374,376-379,381-384,386-389,391-394,401-404,406-409,411-414,416-419,421-424,431-434,436-439,441-444,446-449,451-454,461-464,466-469,471-474,476-479,481-484,491-494,496-499,501-504,506-509,511-514,521-524,526-529,531-534,536-539,541-544,551-554,556-559,561-564,566-569,571-574,581-584,586-589,591-594,596-599,601-604,611-614,616-619,621-624,626-629,631-634,641-644,646-649,651-654,656-659,661-664,671-674,676-679,681-684,686-689,691-694,701-704,706-709,711-714,716-719,721-724,731-734,736-739,741-744,746-749], dead[0-4],
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier simplifyConstantVariables
INFO: Removed constant array :P_crashed[]
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier simplifyConstantVariables
INFO: Removed constant array :P_electionFailed[]
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier simplifyConstantVariables
INFO: Removed constant array :P_masterList[]
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier simplifyConstantVariables
INFO: Removed constant array :dead[]
May 24, 2018 2:25:56 PM fr.lip6.move.gal.instantiate.Simplifier simplifyConstantVariables
INFO: Simplified 3110 expressions due to constant valuations.
May 24, 2018 2:25:57 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: Found a total of 1167 constant array cells/variables (out of 1715 variables) in type NeoElection_COL_4
May 24, 2018 2:25:57 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: P_negotiation[0,3,6,9,12,15,18,30,36,45,54,60,72], P_poll__networl[0-5,10-35,40-65,70-95,100-125,130-155,160-185,190-215,220-245,250-275,280-305,310-335,340-365,370-395,400-425,430-455,460-485,490-515,520-545,550-575,580-605,610-635,640-665,670-695,700-725,730-749], P_sendAnnPs__broadcasting[1-2], P_startNeg__broadcasting[1-2], P_network[1-4,11-14,16-19,21-24,26-29,31-34,41-44,46-49,51-54,56-59,61-64,71-74,76-79,81-84,86-89,91-94,101-104,106-109,111-114,116-119,121-124,131-134,136-139,141-144,146-149,151-154,161-164,166-169,171-174,176-179,181-184,191-194,196-199,201-204,206-209,211-214,221-224,226-229,231-234,236-239,241-244,251-254,256-259,261-264,266-269,271-274,281-284,286-289,291-294,296-299,301-304,311-314,316-319,321-324,326-329,331-334,341-344,346-349,351-354,356-359,361-364,371-374,376-379,381-384,386-389,391-394,401-404,406-409,411-414,416-419,421-424,431-434,436-439,441-444,446-449,451-454,461-464,466-469,471-474,476-479,481-484,491-494,496-499,501-504,506-509,511-514,521-524,526-529,531-534,536-539,541-544,551-554,556-559,561-564,566-569,571-574,581-584,586-589,591-594,596-599,601-604,611-614,616-619,621-624,626-629,631-634,641-644,646-649,651-654,656-659,661-664,671-674,676-679,681-684,686-689,691-694,701-704,706-709,711-714,716-719,721-724,731-734,736-739,741-744,746-749],
May 24, 2018 2:25:57 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: Found a total of 1167 constant array cells/variables (out of 1715 variables) in type NeoElection_COL_4
May 24, 2018 2:25:57 PM fr.lip6.move.gal.instantiate.Simplifier printConstantVars
INFO: P_negotiation[0,3,6,9,12,15,18,30,36,45,54,60,72], P_poll__networl[0-5,10-35,40-65,70-95,100-125,130-155,160-185,190-215,220-245,250-275,280-305,310-335,340-365,370-395,400-425,430-455,460-485,490-515,520-545,550-575,580-605,610-635,640-665,670-695,700-725,730-749], P_sendAnnPs__broadcasting[1-2], P_startNeg__broadcasting[1-2], P_network[1-4,11-14,16-19,21-24,26-29,31-34,41-44,46-49,51-54,56-59,61-64,71-74,76-79,81-84,86-89,91-94,101-104,106-109,111-114,116-119,121-124,131-134,136-139,141-144,146-149,151-154,161-164,166-169,171-174,176-179,181-184,191-194,196-199,201-204,206-209,211-214,221-224,226-229,231-234,236-239,241-244,251-254,256-259,261-264,266-269,271-274,281-284,286-289,291-294,296-299,301-304,311-314,316-319,321-324,326-329,331-334,341-344,346-349,351-354,356-359,361-364,371-374,376-379,381-384,386-389,391-394,401-404,406-409,411-414,416-419,421-424,431-434,436-439,441-444,446-449,451-454,461-464,466-469,471-474,476-479,481-484,491-494,496-499,501-504,506-509,511-514,521-524,526-529,531-534,536-539,541-544,551-554,556-559,561-564,566-569,571-574,581-584,586-589,591-594,596-599,601-604,611-614,616-619,621-624,626-629,631-634,641-644,646-649,651-654,656-659,661-664,671-674,676-679,681-684,686-689,691-694,701-704,706-709,711-714,716-719,721-724,731-734,736-739,741-744,746-749],
May 24, 2018 2:25:57 PM fr.lip6.move.gal.instantiate.Simplifier removeUncalledTransitions
INFO: Removed 5 uncalled transitions from type NeoElection_COL_4
May 24, 2018 2:25:57 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 980 ms
May 24, 2018 2:25:59 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/CTLCardinality.pnml.gal : 95 ms
May 24, 2018 2:25:59 PM fr.lip6.move.serialization.SerializationUtil serializePropertiesForITSCTLTools
INFO: Time to serialize properties into /home/mcc/execution/CTLCardinality.ctl : 21 ms
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="NeoElection-COL-4"
export BK_EXAMINATION="CTLCardinality"
export BK_TOOL="itstools"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"

# 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/NeoElection-COL-4.tgz
mv NeoElection-COL-4 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 NeoElection-COL-4, examination is CTLCardinality"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r116-csrt-152666475100255"
echo "====================================================================="
echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "CTLCardinality" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "CTLCardinality" != "StateSpace" ] ; then
echo "The expected result is a vector of booleans"
echo BOOL_VECTOR
else
echo "no data necessary for post analysis"
fi
echo
if [ -f "CTLCardinality.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property CTLCardinality.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "CTLCardinality.xml" ] ; then # for cunf (txt files deleted;-)
echo echo "here is the order used to build the result vector(from xml file)"
for x in $(grep '' CTLCardinality.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
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 ;