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)
........
=====================================================================
Generated by BenchKit 2-2265
Executing tool itstools
Input is Philosophers-COL-000020, examination is ReachabilityCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r061kn-blw3-143254876000425
=====================================================================

--------------------
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 Philosophers-COL-000020-ReachabilityCardinality-0
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-1
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-10
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-11
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-12
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-13
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-14
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-15
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-2
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-3
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-4
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-5
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-6
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-7
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-8
FORMULA_NAME Philosophers-COL-000020-ReachabilityCardinality-9

=== Now, execution of the tool begins

BK_START 1432644430366

FORMULA Philosophers-COL-000020-ReachabilityCardinality-14 TRUE TECHNIQUES TOPOLOGICAL
FORMULA Philosophers-COL-000020-ReachabilityCardinality-13 TRUE TECHNIQUES TOPOLOGICAL
FORMULA Philosophers-COL-000020-ReachabilityCardinality-12 TRUE TECHNIQUES TOPOLOGICAL
FORMULA Philosophers-COL-000020-ReachabilityCardinality-7 FALSE TECHNIQUES TOPOLOGICAL
FORMULA Philosophers-COL-000020-ReachabilityCardinality-6 TRUE TECHNIQUES TOPOLOGICAL
FORMULA Philosophers-COL-000020-ReachabilityCardinality-3 TRUE TECHNIQUES TOPOLOGICAL
work folder: /home/mcc/execution
unsat
work folder: /home/mcc/execution

BK_STOP 1432644436864

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

Java HotSpot(TM) 64-Bit Server VM warning: ignoring option MaxPermSize=512m; support was removed in 8.0
May 26, 2015 12:47:13 PM fr.lip6.move.gal.application.Application transformPNML
INFO: Parsing pnml file : /home/mcc/execution/model.pnml
May 26, 2015 12:47:13 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 26, 2015 12:47:14 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Load time of PNML (colored model parsed with PNMLFW) : 1447 ms
May 26, 2015 12:47:14 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 5 places.
May 26, 2015 12:47:14 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: read order :VarOrder [vars=[think[0], think[1], think[2], think[3], think[4], think[5], think[6], think[7], think[8], think[9], think[10], think[11], think[12], think[13], think[14], think[15], think[16], think[17], think[18], think[19], fork[0], fork[1], fork[2], fork[3], fork[4], fork[5], fork[6], fork[7], fork[8], fork[9], fork[10], fork[11], fork[12], fork[13], fork[14], fork[15], fork[16], fork[17], fork[18], fork[19], catch1[0], catch1[1], catch1[2], catch1[3], catch1[4], catch1[5], catch1[6], catch1[7], catch1[8], catch1[9], catch1[10], catch1[11], catch1[12], catch1[13], catch1[14], catch1[15], catch1[16], catch1[17], catch1[18], catch1[19], catch2[0], catch2[1], catch2[2], catch2[3], catch2[4], catch2[5], catch2[6], catch2[7], catch2[8], catch2[9], catch2[10], catch2[11], catch2[12], catch2[13], catch2[14], catch2[15], catch2[16], catch2[17], catch2[18], catch2[19], eat[0], eat[1], eat[2], eat[3], eat[4], eat[5], eat[6], eat[7], eat[8], eat[9], eat[10], eat[11], eat[12], eat[13], eat[14], eat[15], eat[16], eat[17], eat[18], eat[19]]]
May 26, 2015 12:47:14 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: sort/places :Philo->think,fork,catch1,catch2,eat,

May 26, 2015 12:47:15 PM fr.lip6.move.gal.pnml.togal.HLGALTransformer handlePage
INFO: Transformed 5 transitions.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.pnml.togal.PnmlToGalTransformer transform
INFO: Computed order based on color domains : CompositeGalOrder [children=[VarOrder [vars=[think[0], fork[0], catch1[0], catch2[0], eat[0]]], VarOrder [vars=[think[1], fork[1], catch1[1], catch2[1], eat[1]]], VarOrder [vars=[think[2], fork[2], catch1[2], catch2[2], eat[2]]], VarOrder [vars=[think[3], fork[3], catch1[3], catch2[3], eat[3]]], VarOrder [vars=[think[4], fork[4], catch1[4], catch2[4], eat[4]]], VarOrder [vars=[think[5], fork[5], catch1[5], catch2[5], eat[5]]], VarOrder [vars=[think[6], fork[6], catch1[6], catch2[6], eat[6]]], VarOrder [vars=[think[7], fork[7], catch1[7], catch2[7], eat[7]]], VarOrder [vars=[think[8], fork[8], catch1[8], catch2[8], eat[8]]], VarOrder [vars=[think[9], fork[9], catch1[9], catch2[9], eat[9]]], VarOrder [vars=[think[10], fork[10], catch1[10], catch2[10], eat[10]]], VarOrder [vars=[think[11], fork[11], catch1[11], catch2[11], eat[11]]], VarOrder [vars=[think[12], fork[12], catch1[12], catch2[12], eat[12]]], VarOrder [vars=[think[13], fork[13], catch1[13], catch2[13], eat[13]]], VarOrder [vars=[think[14], fork[14], catch1[14], catch2[14], eat[14]]], VarOrder [vars=[think[15], fork[15], catch1[15], catch2[15], eat[15]]], VarOrder [vars=[think[16], fork[16], catch1[16], catch2[16], eat[16]]], VarOrder [vars=[think[17], fork[17], catch1[17], catch2[17], eat[17]]], VarOrder [vars=[think[18], fork[18], catch1[18], catch2[18], eat[18]]], VarOrder [vars=[think[19], fork[19], catch1[19], catch2[19], eat[19]]]]]
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-3 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-6 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Invariant property Philosophers-COL-000020-ReachabilityCardinality-7 is trivially false : it is not verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-12 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-13 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-14 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-3 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-6 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Invariant property Philosophers-COL-000020-ReachabilityCardinality-7 is trivially false : it is not verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-12 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-13 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.PropertySimplifier rewriteWithInitialState
INFO: Reachable property Philosophers-COL-000020-ReachabilityCardinality-14 is trivially true : it is verified in initial state.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 206 ms
May 26, 2015 12:47:15 PM fr.lip6.move.gal.application.Application applyOrder
INFO: Applying decomposition
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder decomposeWithOrder
INFO: Decomposing Gal with order
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 209 ms
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 220 ms
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder decomposeWithOrder
INFO: Partition obtained :[think[0], fork[0], catch1[0], catch2[0], eat[0], ],[think[1], fork[1], catch1[1], catch2[1], eat[1], ],[think[2], fork[2], catch1[2], catch2[2], eat[2], ],[think[3], fork[3], catch1[3], catch2[3], eat[3], ],[think[4], fork[4], catch1[4], catch2[4], eat[4], ],[think[5], fork[5], catch1[5], catch2[5], eat[5], ],[think[6], fork[6], catch1[6], catch2[6], eat[6], ],[think[7], fork[7], catch1[7], catch2[7], eat[7], ],[think[8], fork[8], catch1[8], catch2[8], eat[8], ],[think[9], fork[9], catch1[9], catch2[9], eat[9], ],[think[10], fork[10], catch1[10], catch2[10], eat[10], ],[think[11], fork[11], catch1[11], catch2[11], eat[11], ],[think[12], fork[12], catch1[12], catch2[12], eat[12], ],[think[13], fork[13], catch1[13], catch2[13], eat[13], ],[think[14], fork[14], catch1[14], catch2[14], eat[14], ],[think[15], fork[15], catch1[15], catch2[15], eat[15], ],[think[16], fork[16], catch1[16], catch2[16], eat[16], ],[think[17], fork[17], catch1[17], catch2[17], eat[17], ],[think[18], fork[18], catch1[18], catch2[18], eat[18], ],[think[19], fork[19], catch1[19], catch2[19], eat[19], ],

May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting array think to variables to allow decomposition.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting array fork to variables to allow decomposition.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 286 ms
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting array catch1 to variables to allow decomposition.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting array catch2 to variables to allow decomposition.
May 26, 2015 12:47:15 PM fr.lip6.move.gal.instantiate.CompositeBuilder rewriteArraysToAllowPartition
INFO: Rewriting array eat to variables to allow decomposition.
May 26, 2015 12:47:16 PM fr.lip6.move.gal.instantiate.Instantiator fuseIsomorphicEffects
INFO: Removed a total of 20 redundant transitions.
May 26, 2015 12:47:16 PM fr.lip6.move.gal.cegar.support.SupportManager computeSupport
INFO: For property Philosophers-COL-000020-ReachabilityCardinality-0:[reachable] : (((!((((((((((((((((((((think_0+think_1)+think_2)+think_3)+think_4)+think_5)+think_6)+think_7)+think_8)+think_9)+think_10)+think_11)+think_12)+think_13)+think_14)+think_15)+think_16)+think_17)+think_18)+think_19)>=1))&&(((((((((((((((((((((catch2_0+catch2_1)+catch2_2)+catch2_3)+catch2_4)+catch2_5)+catch2_6)+catch2_7)+catch2_8)+catch2_9)+catch2_10)+catch2_11)+catch2_12)+catch2_13)+catch2_14)+catch2_15)+catch2_16)+catch2_17)+catch2_18)+catch2_19)>=1)||((((((((((((((((((((eat_0+eat_1)+eat_2)+eat_3)+eat_4)+eat_5)+eat_6)+eat_7)+eat_8)+eat_9)+eat_10)+eat_11)+eat_12)+eat_13)+eat_14)+eat_15)+eat_16)+eat_17)+eat_18)+eat_19)>=1)))&&(!((((((((((((((((((((catch2_0+catch2_1)+catch2_2)+catch2_3)+catch2_4)+catch2_5)+catch2_6)+catch2_7)+catch2_8)+catch2_9)+catch2_10)+catch2_11)+catch2_12)+catch2_13)+catch2_14)+catch2_15)+catch2_16)+catch2_17)+catch2_18)+catch2_19)<=(((((((((((((((((((fork_0+fork_1)+fork_2)+fork_3)+fork_4)+fork_5)+fork_6)+fork_7)+fork_8)+fork_9)+fork_10)+fork_11)+fork_12)+fork_13)+fork_14)+fork_15)+fork_16)+fork_17)+fork_18)+fork_19)))) found Support [think_0, think_1, think_2, think_3, think_4, think_5, think_6, think_7, think_8, think_9, think_10, think_11, think_12, think_13, think_14, think_15, think_16, think_17, think_18, think_19, catch2_0, catch2_1, catch2_2, catch2_3, catch2_4, catch2_5, catch2_6, catch2_7, catch2_8, catch2_9, catch2_10, catch2_11, catch2_12, catch2_13, catch2_14, catch2_15, catch2_16, catch2_17, catch2_18, catch2_19, eat_0, eat_1, eat_2, eat_3, eat_4, eat_5, eat_6, eat_7, eat_8, eat_9, eat_10, eat_11, eat_12, eat_13, eat_14, eat_15, eat_16, eat_17, eat_18, eat_19, fork_0, fork_1, fork_2, fork_3, fork_4, fork_5, fork_6, fork_7, fork_8, fork_9, fork_10, fork_11, fork_12, fork_13, fork_14, fork_15, fork_16, fork_17, fork_18, fork_19]
May 26, 2015 12:47:16 PM fr.lip6.move.gal.cegar.support.SupportManager computeSupport
INFO: Computing abstraction support, depth 0, found support 80
May 26, 2015 12:47:16 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/ReachabilityCardinality.gal : 24 ms
May 26, 2015 12:47:16 PM fr.lip6.move.gal.cegar.abstractor.BoundComputer compute
INFO: Bounding GAL to K = 1
May 26, 2015 12:47:16 PM fr.lip6.move.gal.cegar.abstractor.VariablesAbstractor abstractUsingSupport
INFO: Abstracting GAL, removed 20 / 100 variable(s)
May 26, 2015 12:47:16 PM fr.lip6.move.gal.cegar.checkers.CEGARChecker check
INFO: Checking abstraction for property Philosophers-COL-000020-ReachabilityCardinality-0(refined 0 times)...
May 26, 2015 12:47:16 PM fr.lip6.move.gal.cegar.checkers.CEGARChecker check
INFO: Running its-reach -reachable on the abstraction...
May 26, 2015 12:47:16 PM fr.lip6.move.gal.gal2smt.Gal2SMTFrontEnd checkProperties
INFO: SMT solution for property Philosophers-COL-000020-ReachabilityCardinality-0(UNSAT) depth K=1 took 720 ms
May 26, 2015 12:47:16 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: No display to refresh.
May 26, 2015 12:47:16 PM fr.lip6.move.serialization.SerializationUtil serializePropertiesForITSTools
INFO: Time to serialize properties into /home/mcc/execution/ReachabilityCardinality.prop : 4 ms
May 26, 2015 12:47:16 PM fr.lip6.move.gal.instantiate.GALRewriter flatten
INFO: Flatten gal took : 145 ms
May 26, 2015 12:47:16 PM fr.lip6.move.serialization.SerializationUtil systemToFile
INFO: Time to serialize gal into /home/mcc/execution/work/abstract.gal : 14 ms
java.lang.IllegalThreadStateException: process hasn't exited
at java.lang.UNIXProcess.exitValue(UNIXProcess.java:421)
at fr.lip6.move.gal.itstools.ProcessController.execute(ProcessController.java:125)
at fr.lip6.move.gal.cegar.checkers.ITSLauncher.run(ITSLauncher.java:99)
at fr.lip6.move.gal.cegar.checkers.ITSLauncher.run(ITSLauncher.java:56)
at fr.lip6.move.gal.cegar.checkers.ITSPropertyCheckerAdapter.check(ITSPropertyCheckerAdapter.java:72)
at fr.lip6.move.gal.cegar.checkers.CEGARChecker.check(CEGARChecker.java:46)
at fr.lip6.move.gal.cegar.frontend.CegarFrontEnd.processGal(CegarFrontEnd.java:24)
at fr.lip6.move.gal.application.Application$3.run(Application.java:354)
at java.lang.Thread.run(Thread.java:745)
Exception in thread "Equinox Shutdown Hook" java.lang.IllegalStateException: Shutdown in progress
at java.io.DeleteOnExitHook.add(DeleteOnExitHook.java:60)
at java.io.File.deleteOnExit(File.java:1079)
at org.eclipse.osgi.storagemanager.StorageManager.initializeInstanceFile(StorageManager.java:188)
at org.eclipse.osgi.storagemanager.StorageManager.open(StorageManager.java:707)
at org.eclipse.osgi.storage.Storage.getChildStorageManager(Storage.java:1749)
at org.eclipse.osgi.storage.Storage.save0(Storage.java:1034)
at org.eclipse.osgi.storage.Storage.save(Storage.java:1009)
at org.eclipse.osgi.internal.framework.StorageSaver$StorageSaverTask.run(StorageSaver.java:28)
at java.lang.Thread.run(Thread.java:745)

Sequence of Actions to be Executed by the VM

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

set -x
# this is for BenchKit: configuration of major elements for the test
export BK_INPUT="Philosophers-COL-000020"
export BK_EXAMINATION="ReachabilityCardinality"
export BK_TOOL="itstools"
export BK_RESULT_DIR="/user/u8/hulinhub/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/Philosophers-COL-000020.tgz
mv Philosophers-COL-000020 execution

# this is for BenchKit: explicit launching of the test

cd execution
echo "====================================================================="
echo " Generated by BenchKit 2-2265"
echo " Executing tool itstools"
echo " Input is Philosophers-COL-000020, examination is ReachabilityCardinality"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r061kn-blw3-143254876000425"
echo "====================================================================="
echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "ReachabilityCardinality" = "ReachabilityComputeBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "ReachabilityCardinality" != "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 "ReachabilityCardinality.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property ReachabilityCardinality.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "ReachabilityCardinality.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 '' ReachabilityCardinality.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 ;