Execution Chart

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

Trace from the execution

Formatting '/data/fkordon/mcc2020-input.r168-smll-158987806700411.qcow2', fmt=qcow2 size=4294967296 backing_file='/data/fkordon/mcc2020-input.qcow2' encryption=off cluster_size=65536 lazy_refcounts=off
Waiting for the VM to be ready (probing ssh)
......................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................
=====================================================================
Generated by BenchKit 2-4028
Executing tool itslola
Input is CloudOpsManagement-PT-00160by00080, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r168-smll-158987806700411
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 208K
-rw-r--r-- 1 mcc users 4.5K Mar 30 01:44 CTLCardinality.txt
-rw-r--r-- 1 mcc users 22K Mar 30 01:44 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.8K Mar 28 16:06 CTLFireability.txt
-rw-r--r-- 1 mcc users 15K Mar 28 16:06 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 24 05:37 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 6.1K Mar 24 05:37 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 4.0K Apr 8 14:45 LTLCardinality.txt
-rw-r--r-- 1 mcc users 24K Apr 28 14:00 LTLCardinality.xml
-rw-r--r-- 1 mcc users 2.3K Apr 8 14:46 LTLFireability.txt
-rw-r--r-- 1 mcc users 14K Apr 28 14:00 LTLFireability.xml
-rw-r--r-- 1 mcc users 4.6K Mar 27 08:44 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 21K Mar 27 08:44 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 3.6K Mar 26 08:11 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 19K Mar 26 08:11 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 2.0K Mar 28 14:49 UpperBounds.txt
-rw-r--r-- 1 mcc users 4.0K Mar 28 14:49 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Mar 24 05:37 equiv_col
-rw-r--r-- 1 mcc users 13 Mar 24 05:37 instance
-rw-r--r-- 1 mcc users 6 Mar 24 05:37 iscolored
-rw-r--r-- 1 mcc users 17K Mar 24 05:37 model.pnml

--------------------
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 CloudOpsManagement-PT-00160by00080-CTLCardinality-00
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-01
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-02
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-03
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-04
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-05
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-06
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-07
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-08
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-09
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-10
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-11
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-12
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-13
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-14
FORMULA_NAME CloudOpsManagement-PT-00160by00080-CTLCardinality-15

=== Now, execution of the tool begins

BK_START 1591339398056

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
[2020-06-05 06:43:20] [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, -timeout, 3600, -rebuildPNML]
[2020-06-05 06:43:20] [INFO ] Parsing pnml file : /home/mcc/execution/model.pnml
[2020-06-05 06:43:20] [INFO ] Load time of PNML (sax parser for PT used): 47 ms
[2020-06-05 06:43:20] [INFO ] Transformed 27 places.
[2020-06-05 06:43:20] [INFO ] Transformed 29 transitions.
[2020-06-05 06:43:20] [INFO ] Parsed PT model containing 27 places and 29 transitions in 111 ms.
Parsed 16 properties from file /home/mcc/execution/CTLCardinality.xml in 28 ms.
Incomplete random walk after 100043 steps, including 0 resets, run finished after 99 ms. (steps per millisecond=1010 ) properties seen :[0, 1, 1, 0, 1, 1, 1, 1, 1, 0, 1, 1, 1, 0, 0, 1, 1, 1, 1, 1, 1, 1, 1, 1, 0, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1, 1]
// Phase 1: matrix 29 rows 27 cols
[2020-06-05 06:43:21] [INFO ] Computed 11 place invariants in 6 ms
[2020-06-05 06:43:21] [INFO ] [Real]Absence check using 11 positive place invariants in 22 ms returned unsat
[2020-06-05 06:43:21] [INFO ] [Real]Absence check using 11 positive place invariants in 8 ms returned unsat
[2020-06-05 06:43:21] [INFO ] [Real]Absence check using 11 positive place invariants in 7 ms returned unsat
[2020-06-05 06:43:21] [INFO ] [Real]Absence check using 11 positive place invariants in 5 ms returned unsat
[2020-06-05 06:43:21] [INFO ] [Real]Absence check using 11 positive place invariants in 7 ms returned unsat
[2020-06-05 06:43:21] [INFO ] [Real]Absence check using 11 positive place invariants in 5 ms returned unsat
Successfully simplified 6 atomic propositions for a total of 6 simplifications.
[2020-06-05 06:43:21] [INFO ] Initial state reduction rules for CTL removed 1 formulas.
[2020-06-05 06:43:21] [INFO ] Flatten gal took : 46 ms
[2020-06-05 06:43:21] [INFO ] Initial state reduction rules for CTL removed 1 formulas.
[2020-06-05 06:43:21] [INFO ] Flatten gal took : 18 ms
FORMULA CloudOpsManagement-PT-00160by00080-CTLCardinality-03 FALSE TECHNIQUES TOPOLOGICAL INITIAL_STATE
[2020-06-05 06:43:21] [INFO ] Export to MCC properties in file /home/mcc/execution/CTLCardinality.sr.xml took 4 ms.
[2020-06-05 06:43:21] [INFO ] Export to PNML in file /home/mcc/execution/model.sr.pnml took 0 ms.
info: Time: 3600 - MCC
vrfy: Checking CTLCardinality @ CloudOpsManagement-PT-00160by00080 @ 3570 seconds

FORMULA CloudOpsManagement-PT-00160by00080-CTLCardinality-00 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA CloudOpsManagement-PT-00160by00080-CTLCardinality-01 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA CloudOpsManagement-PT-00160by00080-CTLCardinality-06 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA CloudOpsManagement-PT-00160by00080-CTLCardinality-09 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT
vrfy: finished
info: timeLeft: -1
rslt: Output for CTLCardinality @ CloudOpsManagement-PT-00160by00080

{
"build":
{
"architecture": 64,
"assertions": false,
"build_hostname": "mcc2019",
"build_system": "x86_64-unknown-linux-gnu",
"optimizations": true,
"package_version": "2.0",
"svn_version": "3189M"
},
"call":
{
"exec_host": "mcc2020",
"markinglimit": null,
"parameters":
[
"--pnmlnet",
"model.pnml",
"--xmlformula",
"--formula=CTLCardinality.xml",
"--mcc",
"--donotcomputecapacities",
"--encoder=simplecompressed",
"--check=modelchecking",
"--stubborn=tarjan",
"--stateequation=par",
"--quickchecks",
"--timelimit=3570",
"--localtimelimit=0",
"--preference=force_ctl",
"--json=CTLCardinality.json",
"--jsoninclude=formula,formulastat,net"
],
"starttime": "Fri Jun 5 06:43:22 2020
",
"timelimit": 3570
},
"child":
[

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 237
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 0,
"E": 0,
"F": 0,
"G": 0,
"U": 0,
"X": 0,
"aconj": 0,
"adisj": 0,
"aneg": 0,
"comp": 0,
"cont": 0,
"dl": 0,
"fir": 0,
"nodl": 0,
"place_references": 0,
"taut": 0,
"tconj": 0,
"tdisj": 0,
"tneg": 0,
"transition_references": 0,
"unfir": 0,
"visible_places": 0,
"visible_transitions": 0
},
"processed": "TRUE",
"processed_size": 4,
"rewrites": 33
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": true
},
"task":
{
"compoundnumber": 0,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 254
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 0,
"E": 0,
"F": 0,
"G": 0,
"U": 0,
"X": 0,
"aconj": 0,
"adisj": 0,
"aneg": 0,
"comp": 1,
"cont": 0,
"dl": 0,
"fir": 0,
"nodl": 0,
"place_references": 2,
"taut": 0,
"tconj": 0,
"tdisj": 0,
"tneg": 0,
"transition_references": 0,
"unfir": 0,
"visible_places": 2,
"visible_transitions": 0
},
"processed": "(p8 <= p7)",
"processed_size": 10,
"rewrites": 35
},
"result":
{
"edges": 165,
"markings": 165,
"produced_by": "state space /EXEG",
"value": true
},
"task":
{
"compoundnumber": 1,
"search":
{
"store":
{
"encoder": "simple compression",
"type": "prefix"
},
"stubborn":
{
"type": "invisibility based",
"visible": 5
},
"threads": 1,
"type": "dfs"
},
"stateequation":
{
"literals": 1,
"problems": 1
},
"type": "reachability_from_a_successor",
"workflow": "stateequation"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 278
},
"child":
[

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 278
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 0,
"E": 0,
"F": 0,
"G": 0,
"U": 0,
"X": 0,
"aconj": 0,
"adisj": 0,
"aneg": 0,
"comp": 1,
"cont": 0,
"dl": 0,
"fir": 0,
"nodl": 0,
"place_references": 2,
"taut": 0,
"tconj": 0,
"tdisj": 0,
"tneg": 0,
"transition_references": 0,
"unfir": 0,
"visible_places": 2,
"visible_transitions": 0
},
"processed": "(p5 <= p11)",
"processed_size": 11,
"rewrites": 33
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": true
},
"task":
{
"compoundnumber": 3,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 303
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 0,
"E": 1,
"F": 1,
"G": 0,
"U": 0,
"X": 0,
"aconj": 1,
"adisj": 1,
"aneg": 0,
"comp": 3,
"cont": 0,
"dl": 0,
"fir": 0,
"nodl": 0,
"place_references": 4,
"taut": 0,
"tconj": 0,
"tdisj": 0,
"tneg": 0,
"transition_references": 0,
"unfir": 0,
"visible_places": 3,
"visible_transitions": 0
},
"processed": "E (F ((((3 <= p0) OR (p15 <= p10)) AND (2 <= p15))))",
"processed_size": 53,
"rewrites": 34
},
"result":
{
"edges": 1309,
"markings": 822,
"produced_by": "state space",
"value": true
},
"task":
{
"compoundnumber": 4,
"search":
{
"store":
{
"encoder": "simple compression",
"type": "prefix"
},
"stubborn":
{
"type": "reachability preserving/insertion"
},
"threads": 1,
"type": "dfs"
},
"stateequation":
{
"literals": 4,
"problems": 2
},
"type": "reachability",
"workflow": "stateequation||search"
}
}
],
"exit":
{
"localtimelimitreached": false
},
"result":
{
"produced_by": "boolean",
"value": true
},
"task":
{
"compoundnumber": 3,
"type": "boolean"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 303
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 1,
"E": 1,
"F": 0,
"G": 1,
"U": 0,
"X": 1,
"aconj": 0,
"adisj": 0,
"aneg": 0,
"comp": 1,
"cont": 0,
"dl": 0,
"fir": 0,
"nodl": 0,
"place_references": 1,
"taut": 0,
"tconj": 0,
"tdisj": 0,
"tneg": 0,
"transition_references": 0,
"unfir": 0,
"visible_places": 1,
"visible_transitions": 0
},
"processed": "EX(AG((2 <= p10)))",
"processed_size": 18,
"rewrites": 35
},
"net":
{
"conflict_clusters": 11,
"singleton_clusters": 0
},
"result":
{
"edges": 238,
"markings": 239,
"produced_by": "CTL model checker",
"value": false
},
"task":
{
"compoundnumber": 4,
"search":
{
"store":
{
"encoder": "simple compression",
"type": "prefix"
},
"stubborn":
{
"type": "ctl preserving"
},
"type": "ctl model checker"
},
"type": "CTL"
}
}
],
"exit":
{
"error": null,
"memory": 432,
"runtime": 3570.000000,
"signal": null,
"timelimitreached": true
},
"files":
{
"formula": "CTLCardinality.xml",
"net": "model.pnml"
},
"formula":
{
"skeleton": "TRUE : E(X(E(G(**)))) : A(G((A(F(**)) OR (** OR **)))) : A(G(**)) : E(X(A(G(**)))) : (E(F(**)) AND (** OR A(X(**)))) : A(G(**)) : (** AND E(F(**))) : E(X(A(G(**)))) : A(G((E(F(**)) OR **))) : (A(G(E(F(**)))) AND (A(X(**)) AND E(X(*)))) : (A(G(**)) OR (A(G(*)) OR E((** U **)))) : E(F((A(F(**)) AND E(G(**))))) : A((* R *)) : (E(F(A(G(**)))) OR (E(G(*)) AND **))"
},
"net":
{
"arcs": 94,
"conflict_clusters": 11,
"places": 27,
"places_significant": 16,
"singleton_clusters": 0,
"transitions": 29
},
"result":
{
"interim_value": "yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown ",
"preliminary_value": "yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown "
},
"task":
{
"type": "compound"
}
}
lola: LoLA will run for 3570 seconds at most (--timelimit)
lola: NET
lola: input: PNML file (--pnml)
lola: reading net from model.pnml
lola: reading pnml
lola: PNML file contains place/transition net
lola: finished parsing
lola: closed net file model.pnml
lola: 56/268435456 symbol table entries, 0 collisions
lola: preprocessing...
lola: Size of bit vector: 864
lola: finding significant places
lola: 27 places, 29 transitions, 16 significant places
lola: compute conflict clusters
lola: computed conflict clusters
lola: Computing conflicting sets
lola: Computing back conflicting sets
lola: TASK
lola: Reading formula in XML format (--xmlformula)
lola: reading pnml
lola: reading formula from CTLCardinality.xml
lola: A (G ((E (G ((0 <= 0))) OR ((p8 <= 2) AND (p8 <= 1))))) : E (X (E (G ((p8 <= p7))))) : A (G (((A (F ((2 <= p11))) OR (p7 + 1 <= p19)) OR (p22 + 1 <= p18)))) : (A (G ((p7 <= p12))) OR E (((p1 <= 1) U (1 <= 0)))) : (E (G (E (G ((0 <= 0))))) AND E (X (A (G ((2 <= p0)))))) : (E (F ((((3 <= p0) OR (p15 <= p10)) AND (2 <= p15)))) AND ((p5 <= p11) OR A (X ((p16 <= p19))))) : A (G (((p1 + 1 <= p12) OR ((p1 + 1 <= p19) AND ((p24 <= p12) OR (p5 <= p0))) OR ((p12 <= p6) AND (1 <= p18))))) : (((p1 <= p21) AND (p17 <= p25)) AND E (F ((p10 <= p20)))) : E (X (A (G ((2 <= p10))))) : A (G ((E (F ((1 <= p22))) OR (p6 <= 1)))) : ((A (G (E (F ((2 <= p22))))) AND A (X ((p13 <= 2)))) AND NOT(A (X ((p26 <= p0))))) : ((A (G (A (G ((p18 <= p20))))) OR NOT(E (F ((1 <= p4))))) OR E (((p18 <= p20) U (p10 <= p12)))) : E (F ((A (F ((3 <= p4))) AND E (G ((2 <= p24)))))) : NOT(E (((p20 <= p4) U (3 <= p21)))) : (E (F (A (G ((2 <= p10))))) OR (NOT(A (F ((p4 <= p0)))) AND ((p13 <= 2) OR (p0 + 1 <= p19) OR (p26 <= 2))))
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:124
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: rewrite Frontend/Parser/formula_rewrite.k:254
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:180
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:121
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:116
lola: rewrite Frontend/Parser/formula_rewrite.k:323
lola: rewrite Frontend/Parser/formula_rewrite.k:335
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: rewrite Frontend/Parser/formula_rewrite.k:251
lola: rewrite Frontend/Parser/formula_rewrite.k:389
lola: rewrite Frontend/Parser/formula_rewrite.k:326
lola: rewrite Frontend/Parser/formula_rewrite.k:332
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: rewrite Frontend/Parser/formula_rewrite.k:254
lola: rewrite Frontend/Parser/formula_rewrite.k:326
lola: rewrite Frontend/Parser/formula_rewrite.k:338
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: rewrite Frontend/Parser/formula_rewrite.k:323
lola: rewrite Frontend/Parser/formula_rewrite.k:332
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: computing a collection of formulas
lola: RUNNING
lola: subprocess 0 will run for 237 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: TRUE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: TRUE
lola: processed formula length: 4
lola: 33 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: yes
lola: produced by: preprocessing
lola: The net satisfies the property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 1 will run for 254 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: E (X (E (G ((p8 <= p7)))))
lola: ========================================
lola: SUBTASK
lola: checking possible preservation from a successor
lola: rewrite Frontend/Parser/formula_rewrite.k:583
lola: rewrite Frontend/Parser/formula_rewrite.k:715
lola: processed formula: (p8 <= p7)
lola: processed formula length: 10
lola: 35 rewrites
lola: closed formula file CTLCardinality.xml
lola: STORE
lola: using a simple compression encoder (--encoder=simplecompressed)
lola: using a prefix tree store (--store=prefix)
lola: SEARCH (state space /EXEG)
lola: state space: using reachability graph (EXEG version) (--search=depth)
lola: state space: using invisibility based stubborn set method (--stubborn=tarjan)
lola: Planning: workflow for reachability check: stateequation (--findpath=off)
lola: built state equation task
lola: RUNNING
lola: state equation task get result started, id 0
lola: state equation task get result rewrite finished id 0
lola: state equation task get result unparse finished++ id 0
lola: formula 0: (p8 <= p7)
lola: state equation task get result unparse finished id 0
lola: state equation: Generated DNF with 1 literals and 1 conjunctive subformulas
lola: SUBRESULT
lola: result: yes
lola: produced by: state space /EXEG
lola: The predicate is possibly preserved from some successor.
lola: 165 markings, 165 edges
lola: ========================================
lola: subprocess 2 will run for 274 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: E (X (A (G ((2 <= p0)))))
lola: ========================================
lola: SUBTASK
lola: checking CTL
lola: rewrite Frontend/Parser/formula_rewrite.k:813
lola: rewrite Frontend/Parser/formula_rewrite.k:807
lola: processed formula: EX(AG((2 <= p0)))
lola: processed formula length: 17
lola: 35 rewrites
lola: closed formula file CTLCardinality.xml
lola: STORE
lola: using a simple compression encoder (--encoder=simplecompressed)
lola: using a prefix tree store (--store=prefix)
lola: Using CTL preserving stubborn sets
lola: RUNNING
lola: CTL formula contains 1 significant temporal operators and needs 5 bytes of payload
lola: Ignoring fairness constraints (--fair).
lola: 4121265 markings, 6235641 edges, 824253 markings/sec, 0 secs
lola: 8030780 markings, 12148734 edges, 781903 markings/sec, 5 secs
lola: 11850639 markings, 17926250 edges, 763972 markings/sec, 10 secs
lola: 15569118 markings, 23550453 edges, 743696 markings/sec, 15 secs
lola: 19300443 markings, 29194002 edges, 746265 markings/sec, 20 secs
lola: 22971892 markings, 34747032 edges, 734290 markings/sec, 25 secs
lola: 26559312 markings, 40173030 edges, 717484 markings/sec, 30 secs
lola: 30166511 markings, 45628867 edges, 721440 markings/sec, 35 secs
lola: 33751768 markings, 51051564 edges, 717051 markings/sec, 40 secs
lola: 37343242 markings, 56483632 edges, 718295 markings/sec, 45 secs
lola: 40870592 markings, 61818727 edges, 705470 markings/sec, 50 secs
lola: 44431094 markings, 67203921 edges, 712100 markings/sec, 55 secs
lola: 48297600 markings, 73052012 edges, 773301 markings/sec, 60 secs
lola: 51993699 markings, 78642310 edges, 739220 markings/sec, 65 secs
lola: 55591129 markings, 84083414 edges, 719486 markings/sec, 70 secs
lola: 59305949 markings, 89702074 edges, 742964 markings/sec, 75 secs
lola: 62942544 markings, 95202364 edges, 727319 markings/sec, 80 secs
lola: 66544388 markings, 100650121 edges, 720369 markings/sec, 85 secs
lola: 70075989 markings, 105991702 edges, 706320 markings/sec, 90 secs
lola: 73659748 markings, 111412077 edges, 716752 markings/sec, 95 secs
lola: 77280481 markings, 116888403 edges, 724147 markings/sec, 100 secs
lola: 80867688 markings, 122314052 edges, 717441 markings/sec, 105 secs
lola: 84331873 markings, 127553623 edges, 692837 markings/sec, 110 secs
lola: 87880979 markings, 132921600 edges, 709821 markings/sec, 115 secs
lola: 90112953 markings, 136299043 edges, 446395 markings/sec, 120 secs
lola: 91858202 markings, 138938643 edges, 349050 markings/sec, 125 secs
lola: 93724037 markings, 141760597 edges, 373167 markings/sec, 130 secs
lola: 96162003 markings, 145446619 edges, 487593 markings/sec, 135 secs
lola: 99141030 markings, 149954835 edges, 595805 markings/sec, 140 secs
lola: 101015669 markings, 152787704 edges, 374928 markings/sec, 145 secs
lola: 103163374 markings, 156036090 edges, 429541 markings/sec, 150 secs
lola: 106150118 markings, 160553550 edges, 597349 markings/sec, 155 secs
lola: 109140597 markings, 165076650 edges, 598096 markings/sec, 160 secs
lola: 109259387 markings, 165256322 edges, 23758 markings/sec, 165 secs
lola: 110469122 markings, 167086001 edges, 241947 markings/sec, 170 secs
lola: 110771185 markings, 167542871 edges, 60413 markings/sec, 175 secs
lola: 110782963 markings, 167560711 edges, 2356 markings/sec, 180 secs
lola: 110786329 markings, 167565745 edges, 673 markings/sec, 185 secs
lola: 110786417 markings, 167565908 edges, 18 markings/sec, 190 secs
lola: Child process aborted or communication problem between parent and child process
lola: subprocess 3 will run for 278 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: (E (F ((((3 <= p0) OR (p15 <= p10)) AND (2 <= p15)))) AND ((p5 <= p11) OR A (X ((p16 <= p19)))))
lola: ========================================
lola: SUBTASK
lola: checking a Boolean combination of formulas
lola: RUNNING
lola: subprocess 3 will run for 278 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: (p5 <= p11)
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: (p5 <= p11)
lola: processed formula length: 11
lola: 33 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 1 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: yes
lola: produced by: preprocessing
lola: The net satisfies the property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 4 will run for 303 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: E (F ((((3 <= p0) OR (p15 <= p10)) AND (2 <= p15))))
lola: ========================================
lola: SUBTASK
lola: checking reachability
lola: Planning: workflow for reachability check: stateequation||search (--findpath=off)
lola: rewrite Frontend/Parser/formula_rewrite.k:711
lola: processed formula: E (F ((((3 <= p0) OR (p15 <= p10)) AND (2 <= p15))))
lola: processed formula length: 53
lola: 34 rewrites
lola: closed formula file CTLCardinality.xml
lola: STORE
lola: using a simple compression encoder (--encoder=simplecompressed)
lola: using a prefix tree store (--store=prefix)
lola: SEARCH (state space)
lola: state space: using reachability graph (--search=depth)
lola: state space: using reachability preserving stubborn set method with insertion algorithm (--stubborn=tarjan)
lola: built state equation task
lola: RUNNING
lola: state equation task get result started, id 0
lola: rewrite Frontend/Parser/formula_rewrite.k:711
lola: state equation task get result rewrite finished id 0
lola: state equation task get result unparse finished++ id 0
lola: formula 0: (((3 <= p0) OR (p15 <= p10)) AND (2 <= p15))
lola: state equation task get result unparse finished id 0
lola: state equation: Generated DNF with 4 literals and 2 conjunctive subformulas
lola: SUBRESULT
lola: result: yes
lola: produced by: state space
lola: The predicate is reachable.
lola: 822 markings, 1309 edges
lola: state equation: write sara problem file to CTLCardinality-4-0.sara
lola: state equation: calling and running sara
lola: ========================================
lola: SUBRESULT
lola: result: yes
lola: The Boolean predicate is true.
lola: ========================================
lola: subprocess 4 will run for 303 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: E (X (A (G ((2 <= p10)))))
lola: ========================================
lola: SUBTASK
lola: checking CTL
lola: rewrite Frontend/Parser/formula_rewrite.k:813
lola: rewrite Frontend/Parser/formula_rewrite.k:807
lola: processed formula: EX(AG((2 <= p10)))
lola: processed formula length: 18
lola: 35 rewrites
lola: closed formula file CTLCardinality.xml
lola: STORE
lola: using a simple compression encoder (--encoder=simplecompressed)
lola: using a prefix tree store (--store=prefix)
lola: Using CTL preserving stubborn sets
lola: RUNNING
lola: CTL formula contains 1 significant temporal operators and needs 5 bytes of payload
lola: Ignoring fairness constraints (--fair).
lola: SUBRESULT
lola: result: no
lola: produced by: CTL model checker
lola: The net does not satisfy the given formula.
lola: 239 markings, 238 edges
lola: ========================================
lola: subprocess 5 will run for 334 seconds at most (--localtimelimit=0)
lola: ========================================
sara: try reading problem file CTLCardinality-4-0.sara.
sara: place or transition ordering is non-deterministic
lola: ...considering subproblem: (A (G (E (F ((2 <= p22))))) AND (A (X ((p13 <= 2))) AND E (X ((p0 + 1 <= p26)))))
lola: ========================================
lola: SUBTASK
lola: checking a Boolean combination of formulas
lola: RUNNING
lola: subprocess 5 will run for 334 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: A (G (E (F ((2 <= p22)))))
lola: ========================================
lola: SUBTASK
lola: checking liveness
lola: rewrite Frontend/Parser/formula_rewrite.k:603
lola: rewrite Frontend/Parser/formula_rewrite.k:613
lola: rewrite Frontend/Parser/formula_rewrite.k:703
lola: processed formula: (2 <= p22)
lola: processed formula length: 10
lola: 36 rewrites
lola: closed formula file CTLCardinality.xml
lola: STORE
lola: using a simple compression encoder (--encoder=simplecompressed)
lola: using a prefix tree store (--store=prefix)
lola: SEARCH
lola: using tscc preserving stubborn set method (--stubborn)
lola: Planning: workflow for reachability check: stateequation (--findpath=off)
lola: built state equation task
lola: Planning: workflow for reachability check: stateequation (--findpath=off)
lola: built state equation task
lola: RUNNING
lola: state equation task get result started, id 0
lola: rewrite Frontend/Parser/formula_rewrite.k:721
lola: rewrite Frontend/Parser/formula_rewrite.k:787
lola: state equation task get result rewrite finished id 0
lola: state equation task get result unparse finished++ id 0
lola: formula 0: (p22 <= 1)
lola: state equation task get result unparse finished id 0
lola: state equation: Generated DNF with 1 literals and 1 conjunctive subformulas
lola: state equation: write sara problem file to CTLCardinality-5-0.sara

lola: state equation task get result started, id 1
lola: rewrite Frontend/Parser/formula_rewrite.k:711
lola: state equation task get result rewrite finished id 1
lola: state equation task get result unparse finished++ id 1
lola: formula 1: (2 <= p22)
lola: state equation task get result unparse finished id 1
lola: state equation: Generated DNF with 1 literals and 1 conjunctive subformulas
lola: state equation: write sara problem file to CTLCardinality-5-1.sara
lola: state equation: calling and running sara
sara: try reading problem file CTLCardinality-5-1.sara.
sara: place or transition ordering is non-deterministic

lola: state equation 1: solution produced
lola: sara is running 0 secs || sara is running 0 secs || 3195434 markings, 6282016 edges, 639087 markings/sec, 0 secs
lola: sara is running 5 secs || sara is running 5 secs || 5996638 markings, 12272815 edges, 560241 markings/sec, 5 secs
lola: sara is running 10 secs || sara is running 10 secs || 8762987 markings, 18154219 edges, 553270 markings/sec, 10 secs
lola: sara is running 15 secs || sara is running 15 secs || 11355601 markings, 23853618 edges, 518523 markings/sec, 15 secs
lola: sara is running 20 secs || sara is running 20 secs || 13989474 markings, 29599197 edges, 526775 markings/sec, 20 secs
lola: sara is running 25 secs || sara is running 25 secs || 16630269 markings, 35348630 edges, 528159 markings/sec, 25 secs
lola: sara is running 30 secs || sara is running 30 secs || 19252881 markings, 41077949 edges, 524522 markings/sec, 30 secs
lola: sara is running 35 secs || sara is running 35 secs || 21814254 markings, 46695238 edges, 512275 markings/sec, 35 secs
lola: sara is running 40 secs || sara is running 40 secs || 24277111 markings, 52251226 edges, 492571 markings/sec, 40 secs
lola: sara is running 45 secs || sara is running 45 secs || 26718867 markings, 57791516 edges, 488351 markings/sec, 45 secs
lola: sara is running 50 secs || sara is running 50 secs || 29145288 markings, 63297101 edges, 485284 markings/sec, 50 secs
lola: sara is running 55 secs || sara is running 55 secs || 31749089 markings, 68992113 edges, 520760 markings/sec, 55 secs
lola: sara is running 60 secs || sara is running 60 secs || 34208080 markings, 74486715 edges, 491798 markings/sec, 60 secs
lola: sara is running 65 secs || sara is running 65 secs || 36765053 markings, 80154076 edges, 511395 markings/sec, 65 secs
lola: sara is running 70 secs || sara is running 70 secs || 39218658 markings, 85692489 edges, 490721 markings/sec, 70 secs
lola: sara is running 75 secs || sara is running 75 secs || 41588326 markings, 91079897 edges, 473934 markings/sec, 75 secs
lola: sara is running 80 secs || sara is running 80 secs || 43969959 markings, 96508169 edges, 476327 markings/sec, 80 secs
lola: sara is running 85 secs || sara is running 85 secs || 46392602 markings, 101957328 edges, 484529 markings/sec, 85 secs
lola: sara is running 90 secs || sara is running 90 secs || 48783173 markings, 107365272 edges, 478114 markings/sec, 90 secs
lola: sara is running 95 secs || sara is running 95 secs || 51291466 markings, 112952654 edges, 501659 markings/sec, 95 secs
lola: sara is running 100 secs || sara is running 100 secs || 53795852 markings, 118596708 edges, 500877 markings/sec, 100 secs
lola: sara is running 105 secs || sara is running 105 secs || 56129457 markings, 123945646 edges, 466721 markings/sec, 105 secs
lola: sara is running 110 secs || sara is running 110 secs || 58484266 markings, 129322700 edges, 470962 markings/sec, 110 secs
lola: sara is running 115 secs || sara is running 115 secs || 60762035 markings, 134626468 edges, 455554 markings/sec, 115 secs
lola: sara is running 120 secs || sara is running 120 secs || 63157656 markings, 140065650 edges, 479124 markings/sec, 120 secs
lola: sara is running 125 secs || sara is running 125 secs || 65429082 markings, 145328599 edges, 454285 markings/sec, 125 secs
lola: sara is running 130 secs || sara is running 130 secs || 67668187 markings, 150568404 edges, 447821 markings/sec, 130 secs
lola: sara is running 135 secs || sara is running 135 secs || 70032801 markings, 155974915 edges, 472923 markings/sec, 135 secs
lola: sara is running 140 secs || sara is running 140 secs || 72233723 markings, 161135677 edges, 440184 markings/sec, 140 secs
lola: sara is running 145 secs || sara is running 145 secs || 74658040 markings, 166621512 edges, 484863 markings/sec, 145 secs
lola: sara is running 150 secs || sara is running 150 secs || 76979837 markings, 171969025 edges, 464359 markings/sec, 150 secs
lola: sara is running 155 secs || sara is running 155 secs || 79283242 markings, 177263985 edges, 460681 markings/sec, 155 secs
lola: sara is running 160 secs || sara is running 160 secs || 81726681 markings, 182767859 edges, 488688 markings/sec, 160 secs
lola: sara is running 165 secs || sara is running 165 secs || 83918645 markings, 187900847 edges, 438393 markings/sec, 165 secs
lola: sara is running 170 secs || sara is running 170 secs || 86094827 markings, 193008776 edges, 435236 markings/sec, 170 secs
lola: sara is running 175 secs || sara is running 175 secs || 88353070 markings, 198226512 edges, 451649 markings/sec, 175 secs
lola: sara is running 180 secs || sara is running 180 secs || 90589747 markings, 203418000 edges, 447335 markings/sec, 180 secs
lola: sara is running 185 secs || sara is running 185 secs || 92886990 markings, 208678678 edges, 459449 markings/sec, 185 secs
lola: sara is running 190 secs || sara is running 190 secs || 95270974 markings, 214102176 edges, 476797 markings/sec, 190 secs
lola: sara is running 195 secs || sara is running 195 secs || 97531379 markings, 219320697 edges, 452081 markings/sec, 195 secs
lola: sara is running 200 secs || sara is running 200 secs || 99847443 markings, 224639947 edges, 463213 markings/sec, 200 secs
lola: sara is running 205 secs || sara is running 205 secs || 102139184 markings, 229921118 edges, 458348 markings/sec, 205 secs
lola: sara is running 210 secs || sara is running 210 secs || 104377196 markings, 235146129 edges, 447602 markings/sec, 210 secs
lola: sara is running 215 secs || sara is running 215 secs || 106535290 markings, 240212445 edges, 431619 markings/sec, 215 secs
lola: sara is running 220 secs || sara is running 220 secs || 108772492 markings, 245414065 edges, 447440 markings/sec, 220 secs
lola: sara is running 225 secs || sara is running 225 secs || 110873490 markings, 250402237 edges, 420200 markings/sec, 225 secs
lola: sara is running 230 secs || sara is running 230 secs || 113052727 markings, 255497727 edges, 435847 markings/sec, 230 secs
lola: sara is running 235 secs || sara is running 235 secs || 115250679 markings, 260633938 edges, 439590 markings/sec, 235 secs
lola: sara is running 240 secs || sara is running 240 secs || 117388381 markings, 265685890 edges, 427540 markings/sec, 240 secs
lola: sara is running 245 secs || sara is running 245 secs || 119555124 markings, 270759234 edges, 433349 markings/sec, 245 secs
lola: sara is running 250 secs || sara is running 250 secs || 121651897 markings, 275718829 edges, 419355 markings/sec, 250 secs
lola: sara is running 255 secs || sara is running 255 secs || 123820348 markings, 280826196 edges, 433690 markings/sec, 255 secs
lola: sara is running 260 secs || sara is running 260 secs || 125932513 markings, 285835777 edges, 422433 markings/sec, 260 secs
lola: sara is running 265 secs || sara is running 265 secs || 128170896 markings, 291043683 edges, 447677 markings/sec, 265 secs
lola: sara is running 270 secs || sara is running 270 secs || 130341853 markings, 296123167 edges, 434191 markings/sec, 270 secs
lola: sara is running 275 secs || sara is running 275 secs || 132495095 markings, 301153644 edges, 430648 markings/sec, 275 secs
lola: sara is running 280 secs || sara is running 280 secs || 134689094 markings, 306294151 edges, 438800 markings/sec, 280 secs
lola: sara is running 285 secs || sara is running 285 secs || 136960378 markings, 311541919 edges, 454257 markings/sec, 285 secs
lola: sara is running 290 secs || sara is running 290 secs || 139233735 markings, 316804919 edges, 454671 markings/sec, 290 secs
lola: sara is running 295 secs || sara is running 295 secs || 141310511 markings, 321741716 edges, 415355 markings/sec, 295 secs
lola: sara is running 300 secs || sara is running 300 secs || 143432316 markings, 326728187 edges, 424361 markings/sec, 300 secs
lola: sara is running 305 secs || sara is running 305 secs || 145446753 markings, 331418460 edges, 402887 markings/sec, 305 secs
lola: sara is running 310 secs || sara is running 310 secs || 146566239 markings, 334071371 edges, 223897 markings/sec, 310 secs
lola: sara is running 315 secs || sara is running 315 secs || 148111875 markings, 337732613 edges, 309127 markings/sec, 315 secs
lola: sara is running 320 secs || sara is running 320 secs || 149550422 markings, 341115966 edges, 287709 markings/sec, 320 secs
lola: sara is running 325 secs || sara is running 325 secs || 151093440 markings, 344735549 edges, 308604 markings/sec, 325 secs
lola: local time limit reached - aborting
lola:
preliminary result: yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown
lola: caught signal User defined signal 1 - aborting LoLA
lola: local time limit reached - aborting
lola:
preliminary result: yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown
lola:
preliminary result: yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown
lola: memory consumption: 16139688 KB
lola: time consumption: 564 seconds
lola: print data as JSON (--json)
lola: writing JSON to CTLCardinality.json
lola: closed JSON file CTLCardinality.json
lola: caught signal User defined signal 1 - aborting LoLA
lola:
preliminary result: yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown
lola: memory consumption: 16062516 KB
lola: time consumption: 564 seconds
lola: print data as JSON (--json)
lola: writing JSON to CTLCardinality.json
lola: closed JSON file CTLCardinality.json
lola: time limit reached - aborting
lola:
preliminary result: yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown
lola:
preliminary result: yes yes unknown unknown unknown yes unknown unknown no unknown unknown unknown unknown unknown unknown
lola: memory consumption: 432 KB
lola: time consumption: 3570 seconds
lola: print data as JSON (--json)
lola: writing JSON to CTLCardinality.json
lola: closed JSON file CTLCardinality.json
rslt: finished
TIME LIMIT: Killed by timeout after 3600 seconds
MemTotal: 16427332 kB
MemFree: 16295220 kB
After kill :
MemTotal: 16427332 kB
MemFree: 16294212 kB

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

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="CloudOpsManagement-PT-00160by00080"
export BK_EXAMINATION="CTLCardinality"
export BK_TOOL="itslola"
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

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-4028"
echo " Executing tool itslola"
echo " Input is CloudOpsManagement-PT-00160by00080, 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 r168-smll-158987806700411"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/CloudOpsManagement-PT-00160by00080.tgz
mv CloudOpsManagement-PT-00160by00080 execution
cd execution
if [ "CTLCardinality" = "ReachabilityDeadlock" ] || [ "CTLCardinality" = "UpperBounds" ] || [ "CTLCardinality" = "QuasiLiveness" ] || [ "CTLCardinality" = "StableMarking" ] || [ "CTLCardinality" = "Liveness" ] || [ "CTLCardinality" = "OneSafe" ] || [ "CTLCardinality" = "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 [ "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
elif [ "CTLCardinality" = "ReachabilityDeadlock" ] || [ "CTLCardinality" = "QuasiLiveness" ] || [ "CTLCardinality" = "StableMarking" ] || [ "CTLCardinality" = "Liveness" ] || [ "CTLCardinality" = "OneSafe" ] ; then
echo "FORMULA_NAME CTLCardinality"
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 ;