Execution Chart

Sorry, for this execution, no execution chart could be reported.

Trace from the execution

Formatting '/data/fkordon/mcc2023-input.r501-tall-167912725500774.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2023-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
...........................................
=====================================================================
Generated by BenchKit 2-5348
Executing tool lolaxred
Input is VehicularWifi-COL-none, examination is Liveness
Time confinement is 1800 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r501-tall-167912725500774
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 672K
-rw-r--r-- 1 mcc users 7.6K Feb 25 14:02 CTLCardinality.txt
-rw-r--r-- 1 mcc users 69K Feb 25 14:02 CTLCardinality.xml
-rw-r--r-- 1 mcc users 6.8K Feb 25 14:02 CTLFireability.txt
-rw-r--r-- 1 mcc users 54K Feb 25 14:02 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K Jan 29 11:41 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 3.8K Feb 25 17:26 LTLCardinality.txt
-rw-r--r-- 1 mcc users 23K Feb 25 17:26 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.7K Feb 25 17:26 LTLFireability.txt
-rw-r--r-- 1 mcc users 16K Feb 25 17:26 LTLFireability.xml
-rw-r--r-- 1 mcc users 17K Feb 25 14:02 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 154K Feb 25 14:02 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 11K Feb 25 14:02 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 76K Feb 25 14:02 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.8K Feb 25 17:26 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.8K Feb 25 17:26 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Mar 5 18:23 equiv_pt
-rw-r--r-- 1 mcc users 5 Mar 5 18:23 instance
-rw-r--r-- 1 mcc users 5 Mar 5 18:23 iscolored
-rw-r--r-- 1 mcc users 188K Mar 5 18:23 model.pnml

--------------------
content from stdout:

=== Data for post analysis generated by BenchKit (invocation template)

The expected result is a vector of booleans
BOOL_VECTOR

FORMULA_NAME Liveness

=== Now, execution of the tool begins

BK_START 1679524669744

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
Invoking MCC driver with
BK_TOOL=lolaxred
BK_EXAMINATION=Liveness
BK_BIN_PATH=/home/mcc/BenchKit/bin/
BK_TIME_CONFINEMENT=1800
BK_INPUT=VehicularWifi-COL-none
Applying reductions before tool lola
Invoking reducer
Running Version 202303021504
[2023-03-22 22:37:51] [INFO ] Running its-tools with arguments : [-pnfolder, /home/mcc/execution, -examination, Liveness, -timeout, 180, -rebuildPNML]
[2023-03-22 22:37:51] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2023-03-22 22:37:51] [INFO ] Detected file is not PT type :http://www.pnml.org/version-2009/grammar/symmetricnet
log4j:WARN No appenders could be found for logger (org.apache.axiom.locator.DefaultOMMetaFactoryLocator).
log4j:WARN Please initialize the log4j system properly.
log4j:WARN See http://logging.apache.org/log4j/1.2/faq.html#noconfig for more info.
[2023-03-22 22:37:52] [WARNING] Using fallBack plugin, rng conformance not checked
[2023-03-22 22:37:52] [INFO ] Load time of PNML (colored model parsed with PNMLFW) : 593 ms
[2023-03-22 22:37:52] [INFO ] Detected 1 constant HL places corresponding to 81920 PT places.
[2023-03-22 22:37:52] [INFO ] Imported 21 HL places and 41 HL transitions for a total of 115566 PT places and 379924.0 transition bindings in 110 ms.
[2023-03-22 22:37:52] [INFO ] Built PT skeleton of HLPN with 21 places and 41 transitions 136 arcs in 5 ms.
[2023-03-22 22:37:52] [INFO ] Skeletonized 0 HLPN properties in 0 ms.
[2023-03-22 22:37:53] [INFO ] Unfolded HLPN to a Petri net with 115566 places and 83836 transitions 301810 arcs in 1137 ms.
[2023-03-22 22:37:53] [INFO ] Unfolded 0 HLPN properties in 0 ms.
[2023-03-22 22:37:54] [INFO ] Unfolded HLPN to a Petri net with 115566 places and 83836 transitions 301810 arcs in 520 ms.
[2023-03-22 22:37:54] [INFO ] Unfolded 41 HLPN properties in 19 ms.
[2023-03-22 22:37:54] [INFO ] Reduced 32912 identical enabling conditions.
[2023-03-22 22:37:54] [INFO ] Built PT skeleton of HLPN with 21 places and 41 transitions 136 arcs in 2 ms.
[2023-03-22 22:37:54] [INFO ] Skeletonized 40 HLPN properties in 2 ms. Removed 1 properties that had guard overlaps.
Reduce places removed 1 places and 0 transitions.
Computed a total of 0 stabilizing places and 0 stable transitions
Prefix of Interest using HLPN skeleton for deadlock discarded 0 places and 0 transitions.
Arc [14:1*[$sa, $sb, $p, (MOD (ADD (MOD (MINUS $b 1) 1024) 1024) 1024)]] contains successor/predecessor on variables of sort BackoffStage
Arc [13:1*[$sa, $sb, $p, (MOD (ADD $tx 1) 20)]] contains successor/predecessor on variables of sort TxCount
Transition CorrectPacketButAck forces synchronizations/join behavior on parameter p2 of sort Pr
Arc [4:1*[$sa, $sb, $p, (MOD (ADD $pt 1) 4)]] contains successor/predecessor on variables of sort PT
Domain [St(2), St(2), Pr(4), PT(4)] of place Sense breaks symmetries in sort St
[2023-03-22 22:37:55] [INFO ] Unfolded HLPN to a Petri net with 115566 places and 83836 transitions 301810 arcs in 472 ms.
[2023-03-22 22:37:55] [INFO ] Unfolded 41 HLPN properties in 4 ms.
[2023-03-22 22:37:55] [INFO ] Reduced 32912 identical enabling conditions.
Deduced a syphon composed of 98581 places in 231 ms
Reduce places removed 106837 places and 54136 transitions.
Built sparse matrix representations for Structural reductions in 18 ms.486863KB memory used
Starting structural reductions in DEADLOCK mode, iteration 0 : 8729/8729 places, 29700/29700 transitions.
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Applied a total of 0 rules in 404 ms. Remains 8729 /8729 variables (removed 0) and now considering 29700/29700 (removed 0) transitions.
[2023-03-22 22:37:57] [INFO ] Flow matrix only has 17308 transitions (discarded 12392 similar events)
// Phase 1: matrix 17308 rows 8729 cols
[2023-03-22 22:37:58] [INFO ] Computed 8 place invariants in 1021 ms
[2023-03-22 22:37:59] [INFO ] Dead Transitions using invariants and state equation in 2447 ms found 0 transitions.
Finished structural reductions in DEADLOCK mode , in 1 iterations and 2899 ms. Remains : 8729/8729 places, 29700/29700 transitions.
Starting structural reductions in DEADLOCK mode, iteration 0 : 8729/8729 places, 29700/29700 transitions.
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Computed a total of 0 stabilizing places and 0 stable transitions
Applied a total of 0 rules in 209 ms. Remains 8729 /8729 variables (removed 0) and now considering 29700/29700 (removed 0) transitions.
[2023-03-22 22:38:00] [INFO ] Flow matrix only has 17308 transitions (discarded 12392 similar events)
[2023-03-22 22:38:00] [INFO ] Invariant cache hit.
[2023-03-22 22:38:01] [INFO ] Implicit Places using invariants in 1051 ms returned []
Implicit Place search using SMT only with invariants took 1054 ms to find 0 implicit places.
[2023-03-22 22:38:01] [INFO ] Flow matrix only has 17308 transitions (discarded 12392 similar events)
[2023-03-22 22:38:01] [INFO ] Invariant cache hit.
[2023-03-22 22:38:01] [INFO ] Dead Transitions using invariants and state equation in 769 ms found 0 transitions.
Finished structural reductions in DEADLOCK mode , in 1 iterations and 2043 ms. Remains : 8729/8729 places, 29700/29700 transitions.
Interrupted Random walk after 1030559 steps, including 0 resets, run timeout after 30001 ms. (steps per millisecond=34 )
[2023-03-22 22:38:31] [INFO ] Flow matrix only has 17308 transitions (discarded 12392 similar events)
[2023-03-22 22:38:31] [INFO ] Invariant cache hit.
[2023-03-22 22:38:38] [INFO ] [Real]Absence check using 6 positive place invariants in 72 ms returned sat
[2023-03-22 22:38:38] [INFO ] [Real]Absence check using 6 positive and 2 generalized place invariants in 88 ms returned unsat
[2023-03-22 22:38:38] [INFO ] Unfolded HLPN to a Petri net with 115566 places and 83836 transitions 301810 arcs in 469 ms.
[2023-03-22 22:38:38] [INFO ] Unfolded 41 HLPN properties in 2 ms.
Initial state reduction rules removed 2 formulas.
[2023-03-22 22:38:38] [INFO ] Reduced 32912 identical enabling conditions.
Deduced a syphon composed of 98581 places in 154 ms
Reduce places removed 106837 places and 54136 transitions.
Incomplete random walk after 10000 steps, including 2 resets, run finished after 766 ms. (steps per millisecond=13 ) properties (out of 39) seen :34
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 61 ms. (steps per millisecond=163 ) properties (out of 5) seen :0
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 103 ms. (steps per millisecond=97 ) properties (out of 5) seen :0
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 54 ms. (steps per millisecond=185 ) properties (out of 5) seen :0
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 59 ms. (steps per millisecond=169 ) properties (out of 5) seen :0
Incomplete Best-First random walk after 10001 steps, including 2 resets, run finished after 53 ms. (steps per millisecond=188 ) properties (out of 5) seen :0
Running SMT prover for 5 properties.
[2023-03-22 22:38:41] [INFO ] Flow matrix only has 17308 transitions (discarded 12392 similar events)
[2023-03-22 22:38:41] [INFO ] Invariant cache hit.
[2023-03-22 22:38:43] [INFO ] [Real]Absence check using 6 positive place invariants in 44 ms returned sat
[2023-03-22 22:38:43] [INFO ] [Real]Absence check using 6 positive and 2 generalized place invariants in 99 ms returned sat
[2023-03-22 22:38:58] [INFO ] After 17346ms SMT Verify possible using all constraints in real domain returned unsat :0 sat :0 real:5
[2023-03-22 22:39:00] [INFO ] [Nat]Absence check using 6 positive place invariants in 40 ms returned sat
[2023-03-22 22:39:00] [INFO ] [Nat]Absence check using 6 positive and 2 generalized place invariants in 102 ms returned sat
[2023-03-22 22:39:16] [INFO ] After 15127ms SMT Verify possible using state equation in natural domain returned unsat :0 sat :5
[2023-03-22 22:39:16] [INFO ] State equation strengthened by 8411 read => feed constraints.
[2023-03-22 22:39:20] [INFO ] After 4051ms SMT Verify possible using 8411 Read/Feed constraints in natural domain returned unsat :0 sat :5
[2023-03-22 22:39:24] [INFO ] After 8223ms SMT Verify possible using trap constraints in natural domain returned unsat :0 sat :5
Attempting to minimize the solution found.
Minimization took 5 ms.
[2023-03-22 22:39:24] [INFO ] After 25921ms SMT Verify possible using all constraints in natural domain returned unsat :0 sat :5
Finished Parikh walk after 4287 steps, including 158 resets, run visited all 5 properties in 298 ms. (steps per millisecond=14 )
Parikh walk visited 5 properties in 313 ms.
Able to resolve query QuasiLiveness after proving 41 properties.
Deduced a syphon composed of 98581 places in 113 ms
Reduce places removed 106837 places and 54136 transitions.
Unable to solve all queries for examination Liveness. Remains :41 assertions to prove.
No /home/mcc/execution/Liveness.xml examination file found. Proceeding without properties.
Parsed 41 properties from file /home/mcc/execution/Liveness.xml in 0 ms.
Working with output stream class java.io.PrintStream
Total runtime 94779 ms.
starting LoLA
BK_INPUT VehicularWifi-COL-none
BK_EXAMINATION: Liveness
bin directory: /home/mcc/BenchKit/bin//../reducer/bin//../../lola/bin/
current directory: /home/mcc/execution
GlobalProperty: Liveness

BK_STOP 1679525081096

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

+ ulimit -s 65536
+ [[ -z '' ]]
+ export LTSMIN_MEM_SIZE=8589934592
+ LTSMIN_MEM_SIZE=8589934592
+ export PYTHONPATH=/home/mcc/BenchKit/itstools/pylibs
+ PYTHONPATH=/home/mcc/BenchKit/itstools/pylibs
+ export LD_LIBRARY_PATH=/home/mcc/BenchKit/itstools/pylibs:
+ LD_LIBRARY_PATH=/home/mcc/BenchKit/itstools/pylibs:
++ sed s/.jar//
++ perl -pe 's/.*\.//g'
++ ls /home/mcc/BenchKit/bin//../reducer/bin//../../itstools//itstools/plugins/fr.lip6.move.gal.application.pnmcc_1.0.0.202303021504.jar
+ VERSION=202303021504
+ echo 'Running Version 202303021504'
+ /home/mcc/BenchKit/bin//../reducer/bin//../../itstools//itstools/its-tools -pnfolder /home/mcc/execution -examination Liveness -timeout 180 -rebuildPNML
lola: MEM LIMIT 32
lola: MEM LIMIT 5
lola: NET
lola: input: PNML file (--pnmlnet)
lola: reading net from /home/mcc/execution/model.pnml
lola: reading pnml
lola: PNML file contains High-Level net
lola: L 1 U 2 C 2
lola: INS St_1
lola: INS St_2
lola: L 1 U 4 C 4
lola: INS Pr_1
lola: INS Pr_2
lola: INS Pr_3
lola: INS Pr_4
lola: finished parsing
lola: closed net file /home/mcc/execution/model.pnml
lola: TR BINDINGS
lola: TR BINDINGS DONE
lola: Places: 115566, Transitions: 83836
lola: @ trans ExitingErrorSpike
lola: @ trans GoToBackoff
lola: @ trans BeginReceiving
lola: @ trans DropPacket
lola: @ trans DecrementBackoff
lola: @ trans ResumeBackoff2
lola: @ trans ResumeBackoff4
lola: @ trans SenderSenseCollision
lola: @ trans StopReceiving
lola: @ trans EnteringErrorSpike
lola: @ trans SendCTS
lola: @ trans CorrectPacketRts
lola: @ trans WaitAIFS3
lola: @ trans ResumeBackoff1
lola: @ trans GettingGarbled
lola: @ trans SenderTimeout
lola: @ trans BeginWaitingForResponse
lola: @ trans SendAck
lola: @ trans PauseBackoff
lola: @ trans SenderWrongPacket
lola: @ trans WaitAIFS2
lola: @ trans ShortEnterVulnerable
lola: @ trans WaitAIFS1
lola: @ trans WaitAIFS4
lola: @ trans ResumeBackoff3
lola: @ trans SendRTS
lola: @ trans ReceiverWrongPacket
lola: @ trans CorrectPacketAck
lola: @ trans EndingGarbled
lola: @ trans ExitBackoff
lola: @ trans PacketArrival
lola: @ trans ReceiverSenseCollision
lola: @ trans ReceiverGarbled
lola: @ trans CorrectPacketButAck
lola: @ trans SenderGarbled
lola: @ trans ChooseBackoff
lola: @ trans SendData
lola: @ trans ReturnToBackoff
lola: @ trans BeginSending
lola: @ trans InternalCollision
lola: @ trans ReceiverTimeout
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
lola: reporting
lola: not produced: 83836
/home/mcc/BenchKit/bin//../reducer/bin//../../lola/bin//../BenchKit_head.sh: line 63: 489 Killed lola --conf=$BIN_DIR/configfiles/globalconf --check=Liveness $DIR/model.pnml

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="VehicularWifi-COL-none"
export BK_EXAMINATION="Liveness"
export BK_TOOL="lolaxred"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="1800"
export BK_MEMORY_CONFINEMENT="16384"
export BK_BIN_PATH="/home/mcc/BenchKit/bin/"

# this is specific to your benchmark or test

export BIN_DIR="$HOME/BenchKit/bin"

# remove the execution directoty if it exists (to avoid increse of .vmdk images)
if [ -d execution ] ; then
rm -rf execution
fi

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-5348"
echo " Executing tool lolaxred"
echo " Input is VehicularWifi-COL-none, examination is Liveness"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 4"
echo " Run identifier is r501-tall-167912725500774"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/VehicularWifi-COL-none.tgz
mv VehicularWifi-COL-none execution
cd execution
if [ "Liveness" = "ReachabilityDeadlock" ] || [ "Liveness" = "UpperBounds" ] || [ "Liveness" = "QuasiLiveness" ] || [ "Liveness" = "StableMarking" ] || [ "Liveness" = "Liveness" ] || [ "Liveness" = "OneSafe" ] || [ "Liveness" = "StateSpace" ]; then
rm -f GenericPropertiesVerdict.xml
fi
pwd
ls -lh

echo
echo "--------------------"
echo "content from stdout:"
echo
echo "=== Data for post analysis generated by BenchKit (invocation template)"
echo
if [ "Liveness" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "Liveness" != "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 "Liveness.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property Liveness.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "Liveness.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 '' Liveness.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ "Liveness" = "ReachabilityDeadlock" ] || [ "Liveness" = "QuasiLiveness" ] || [ "Liveness" = "StableMarking" ] || [ "Liveness" = "Liveness" ] || [ "Liveness" = "OneSafe" ] ; then
echo "FORMULA_NAME Liveness"
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 ;