fond
Model Checking Contest 2019
9th edition, Prague, Czech Republic, April 7, 2019 (TOOLympics)
Execution of r049-oct2-155234405100436
Last Updated
Apr 15, 2019

About the Execution of LoLA for DLCround-PT-10a

Execution Summary
Max Memory
Used (MB)
Time wait (ms) CPU Usage (ms) I/O Wait (ms) Computed Result Execution
Status
15918.940 731741.00 698536.00 2047.80 FTFFFFFTFFFFF?TT normal

Execution Chart

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

Trace from the execution

Formatting '/data/fko/mcc2019-input.r049-oct2-155234405100436.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fko/mcc2019-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
.............................................................................
=====================================================================
Generated by BenchKit 2-3954
Executing tool lola
Input is DLCround-PT-10a, examination is CTLCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 4
Run identifier is r049-oct2-155234405100436
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 824K
-rw-r--r-- 1 mcc users 3.8K Feb 10 06:58 CTLCardinality.txt
-rw-r--r-- 1 mcc users 22K Feb 10 06:58 CTLCardinality.xml
-rw-r--r-- 1 mcc users 2.4K Feb 6 05:28 CTLFireability.txt
-rw-r--r-- 1 mcc users 16K Feb 6 05:28 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.0K Mar 10 17:31 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 5.9K Mar 10 17:31 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 102 Feb 24 15:05 GlobalProperties.txt
-rw-r--r-- 1 mcc users 340 Feb 24 15:05 GlobalProperties.xml
-rw-r--r-- 1 mcc users 2.3K Feb 4 23:22 LTLCardinality.txt
-rw-r--r-- 1 mcc users 9.8K Feb 4 23:22 LTLCardinality.xml
-rw-r--r-- 1 mcc users 1.8K Feb 4 22:33 LTLFireability.txt
-rw-r--r-- 1 mcc users 8.3K Feb 4 22:33 LTLFireability.xml
-rw-r--r-- 1 mcc users 3.1K Feb 2 22:44 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 15K Feb 2 22:44 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 2.9K Jan 30 13:03 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 18K Jan 30 13:03 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 1.6K Feb 4 22:19 UpperBounds.txt
-rw-r--r-- 1 mcc users 3.6K Feb 4 22:19 UpperBounds.xml

-rw-r--r-- 1 mcc users 6 Jan 29 09:34 equiv_col
-rw-r--r-- 1 mcc users 4 Jan 29 09:34 instance
-rw-r--r-- 1 mcc users 6 Jan 29 09:34 iscolored
-rw-r--r-- 1 mcc users 660K Mar 10 17:31 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 DLCround-PT-10a-CTLCardinality-00
FORMULA_NAME DLCround-PT-10a-CTLCardinality-01
FORMULA_NAME DLCround-PT-10a-CTLCardinality-02
FORMULA_NAME DLCround-PT-10a-CTLCardinality-03
FORMULA_NAME DLCround-PT-10a-CTLCardinality-04
FORMULA_NAME DLCround-PT-10a-CTLCardinality-05
FORMULA_NAME DLCround-PT-10a-CTLCardinality-06
FORMULA_NAME DLCround-PT-10a-CTLCardinality-07
FORMULA_NAME DLCround-PT-10a-CTLCardinality-08
FORMULA_NAME DLCround-PT-10a-CTLCardinality-09
FORMULA_NAME DLCround-PT-10a-CTLCardinality-10
FORMULA_NAME DLCround-PT-10a-CTLCardinality-11
FORMULA_NAME DLCround-PT-10a-CTLCardinality-12
FORMULA_NAME DLCround-PT-10a-CTLCardinality-13
FORMULA_NAME DLCround-PT-10a-CTLCardinality-14
FORMULA_NAME DLCround-PT-10a-CTLCardinality-15

=== Now, execution of the tool begins

BK_START 1552457340591

info: Time: 3600 - MCC
vrfy: Checking CTLCardinality @ DLCround-PT-10a @ 3570 seconds

FORMULA DLCround-PT-10a-CTLCardinality-00 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-01 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-02 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-03 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-04 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-05 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-06 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-07 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-08 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-09 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-10 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-12 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-15 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-14 TRUE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-11 FALSE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT

FORMULA DLCround-PT-10a-CTLCardinality-13 CANNOT_COMPUTE TECHNIQUES COLLATERAL_PROCESSING EXPLICIT TOPOLOGICAL STATE_COMPRESSION STUBBORN_SETS USE_NUPN UNFOLDING_TO_PT
vrfy: finished
info: timeLeft: 2838
rslt: Output for CTLCardinality @ DLCround-PT-10a

{
"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": "mcc2019",
"markinglimit": null,
"parameters":
[
"--pnmlnet",
"model.pnml",
"--xmlformula",
"--formula=CTLCardinality.xml",
"--mcc",
"--donotcomputecapacities",
"--encoder=simplecompressed",
"--safe",
"--check=modelchecking",
"--stubborn=tarjan",
"--stateequation=par",
"--quickchecks",
"--timelimit=3570",
"--localtimelimit=0",
"--preference=force_ctl",
"--json=CTLCardinality.json",
"--jsoninclude=formula,formulastat,net"
],
"starttime": "Wed Mar 13 06:09:00 2019
",
"timelimit": 3570
},
"child":
[

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 222
},
"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": "DEADLOCK",
"processed_size": 8,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 0,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"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": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": true
},
"task":
{
"compoundnumber": 1,
"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": 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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 2,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 274
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 3,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 297
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 4,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 324
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 0,
"E": 0,
"F": 0,
"G": 0,
"U": 0,
"X": 0,
"aconj": 1,
"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": "((1 <= p291))",
"processed_size": 13,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 5,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 356
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 6,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 396
},
"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": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": true
},
"task":
{
"compoundnumber": 7,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 445
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 8,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 509
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 9,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 594
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 10,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 712
},
"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": "FALSE",
"processed_size": 5,
"rewrites": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": false
},
"task":
{
"compoundnumber": 11,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 891
},
"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": 94
},
"result":
{
"edges": 0,
"markings": 0,
"produced_by": "preprocessing",
"value": true
},
"task":
{
"compoundnumber": 12,
"type": "initial_satisfaction",
"workflow": "preprocessing"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 1188
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 0,
"E": 1,
"F": 1,
"G": 0,
"U": 0,
"X": 0,
"aconj": 0,
"adisj": 0,
"aneg": 1,
"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": "E (F ((p118 + 1 <= p76)))",
"processed_size": 25,
"rewrites": 95
},
"result":
{
"edges": 4,
"markings": 5,
"produced_by": "state space",
"value": true
},
"task":
{
"compoundnumber": 13,
"search":
{
"store":
{
"encoder": "simple compression",
"type": "prefix"
},
"stubborn":
{
"type": "reachability preserving/insertion"
},
"threads": 1,
"type": "dfs"
},
"stateequation":
{
"literals": 1,
"problems": 1
},
"type": "reachability",
"workflow": "stateequation||search"
}
},

{
"call":
{
"dynamic_timelimit": true,
"localtimelimit": 1782
},
"exit":
{
"localtimelimitreached": false
},
"formula":
{
"count":
{
"A": 1,
"E": 0,
"F": 0,
"G": 1,
"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": "A (G ((p124 <= p97)))",
"processed_size": 21,
"rewrites": 96
},
"result":
{
"edges": 10,
"markings": 9,
"produced_by": "state space",
"value": false
},
"task":
{
"compoundnumber": 14,
"search":
{
"store":
{
"encoder": "simple compression",
"type": "prefix"
},
"stubborn":
{
"type": "reachability preserving/insertion"
},
"threads": 1,
"type": "dfs"
},
"stateequation":
{
"literals": 1,
"problems": 1
},
"type": "invariance",
"workflow": "stateequation||search"
}
}
],
"exit":
{
"error": null,
"memory": 33328,
"runtime": 708.000000,
"signal": null,
"timelimitreached": false
},
"files":
{
"formula": "CTLCardinality.xml",
"net": "model.pnml"
},
"formula":
{
"skeleton": "DEADLOCK : TRUE : FALSE : FALSE : FALSE : ** : FALSE : TRUE : FALSE : FALSE : FALSE : A(G(**)) : FALSE : E(G(E(F(*)))) : E(F(*)) : TRUE"
},
"net":
{
"arcs": 10130,
"conflict_clusters": 25,
"places": 337,
"places_significant": 124,
"singleton_clusters": 0,
"transitions": 2605
},
"result":
{
"preliminary_value": "no yes no no no no no yes no no no no no unknown yes yes ",
"value": "no yes no no no no no yes no no no no no unknown yes yes "
},
"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: 2942/268435456 symbol table entries, 0 collisions
lola: preprocessing...
lola: Size of bit vector: 337
lola: finding significant places
lola: 337 places, 2605 transitions, 124 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: LP says that atomic proposition is always false: (2 <= p12)
lola: place invariant simplifies atomic proposition
lola: before: (p301 <= p213)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (2 <= p296)
lola: LP says that atomic proposition is always false: (2 <= p193)
lola: place invariant simplifies atomic proposition
lola: before: (p157 <= p272)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (2 <= p32)
lola: LP says that atomic proposition is always false: (3 <= p12)
lola: LP says that atomic proposition is always false: (3 <= p24)
lola: place invariant simplifies atomic proposition
lola: before: (p167 <= p222)
lola: after: (0 <= 0)
lola: place invariant simplifies atomic proposition
lola: before: (p272 <= p246)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (3 <= p3)
lola: LP says that atomic proposition is always false: (3 <= p257)
lola: LP says that atomic proposition is always false: (3 <= p187)
lola: LP says that atomic proposition is always false: (3 <= p128)
lola: place invariant simplifies atomic proposition
lola: before: (p263 <= p156)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (2 <= p128)
lola: LP says that atomic proposition is always true: (p39 <= p221)
lola: LP says that atomic proposition is always false: (3 <= p258)
lola: place invariant simplifies atomic proposition
lola: before: (p279 <= p221)
lola: after: (0 <= 0)
lola: place invariant simplifies atomic proposition
lola: before: (p277 <= p156)
lola: after: (0 <= 0)
lola: place invariant simplifies atomic proposition
lola: before: (p323 <= p147)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (2 <= p210)
lola: LP says that atomic proposition is always true: (p46 <= p203)
lola: LP says that atomic proposition is always true: (p17 <= p215)
lola: place invariant simplifies atomic proposition
lola: before: (p301 <= p325)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (2 <= p36)
lola: place invariant simplifies atomic proposition
lola: before: (p139 <= p178)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (3 <= p310)
lola: LP says that atomic proposition is always false: (2 <= p53)
lola: LP says that atomic proposition is always false: (3 <= p123)
lola: LP says that atomic proposition is always false: (2 <= p100)
lola: LP says that atomic proposition is always true: (p137 <= p260)
lola: LP says that atomic proposition is always true: (p63 <= p199)
lola: place invariant simplifies atomic proposition
lola: before: (p156 <= p165)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always true: (p18 <= p262)
lola: LP says that atomic proposition is always false: (2 <= p150)
lola: place invariant simplifies atomic proposition
lola: before: (p239 <= p269)
lola: after: (0 <= 0)
lola: LP says that atomic proposition is always false: (3 <= p36)
lola: place invariant simplifies atomic proposition
lola: before: (p148 <= p158)
lola: after: (0 <= 0)
lola: A (X (E (F (())))) : NOT(E ((() U ()))) : E (F ((() AND E (F ((3 <= p12)))))) : E (F (())) : E (F ((E (X ((3 <= p3))) AND ()))) : A ((E (F ((3 <= p187))) U ((1 <= p291)))) : A (G (E (F ((1 <= 0))))) : ((2 <= p128) OR A (G (E (F ((p39 <= p221)))))) : E ((() U ())) : (((E (X ((p124 <= p77))) OR ()) AND ()) OR ((p64 <= p37) AND E (((0 <= 0) U (3 <= p310))))) : E (F (A (((2 <= p53) U (3 <= p123))))) : ((() AND E (F (()))) AND A (G (E (G ((p124 <= p97)))))) : NOT(A (X (A (G ((p18 <= p262)))))) : NOT(A (F (A (G ((p0 <= p46)))))) : NOT(((A (G ((p76 <= p118))) AND ()) AND ())) : A (G ((A (G ((p245 <= p57))) OR A (F ((0 <= 0))))))
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:139
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:169
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:282
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:118
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:133
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:118
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:184
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:163
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:154
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: rewrite Frontend/Parser/formula_rewrite.k:122
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:169
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:123
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:116
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:169
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:117
lola: rewrite Frontend/Parser/formula_rewrite.k:122
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:98
lola: rewrite Frontend/Parser/formula_rewrite.k:180
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: rewrite Frontend/Parser/formula_rewrite.k:157
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:154
lola: rewrite Frontend/Parser/formula_rewrite.k:148
lola: rewrite Frontend/Parser/formula_rewrite.k:116
lola: rewrite Frontend/Parser/formula_rewrite.k:458
lola: rewrite Frontend/Parser/formula_rewrite.k:116
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: rewrite Frontend/Parser/formula_rewrite.k:136
lola: rewrite Frontend/Parser/formula_rewrite.k:279
lola: rewrite Frontend/Parser/formula_rewrite.k:323
lola: rewrite Frontend/Parser/formula_rewrite.k:332
lola: rewrite Frontend/Parser/formula_rewrite.k:323
lola: rewrite Frontend/Parser/formula_rewrite.k:329
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:115
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:115
lola: rewrite Frontend/Parser/formula_rewrite.k:323
lola: rewrite Frontend/Parser/formula_rewrite.k:329
lola: rewrite Frontend/Parser/formula_rewrite.k:297
lola: rewrite Frontend/Parser/formula_rewrite.k:100
lola: rewrite Frontend/Parser/formula_rewrite.k:154
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: rewrite Frontend/Parser/formula_rewrite.k:123
lola: rewrite Frontend/Parser/formula_rewrite.k:160
lola: rewrite Frontend/Parser/formula_rewrite.k:151
lola: computing a collection of formulas
lola: RUNNING
lola: subprocess 0 will run for 222 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: DEADLOCK
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: DEADLOCK
lola: processed formula length: 8
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 1 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: 94 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 2 will run for 254 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 3 will run for 274 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 4 will run for 297 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 5 will run for 324 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: ((1 <= p291))
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: ((1 <= p291))
lola: processed formula length: 13
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 1 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 6 will run for 356 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 7 will run for 396 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: 94 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 8 will run for 445 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 9 will run for 509 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 10 will run for 594 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 11 will run for 712 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: FALSE
lola: ========================================
lola: SUBTASK
lola: checking initial satisfaction
lola: processed formula: FALSE
lola: processed formula length: 5
lola: 94 rewrites
lola: closed formula file CTLCardinality.xml
lola: processed formula with 0 atomic propositions
lola: RUNNING
lola: SUBRESULT
lola: result: no
lola: produced by: preprocessing
lola: The net violates the given property already in its initial state.
lola: 0 markings, 0 edges
lola: ========================================
lola: subprocess 12 will run for 891 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: 94 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 13 will run for 1188 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: E (F ((p118 + 1 <= p76)))
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 ((p118 + 1 <= p76)))
lola: processed formula length: 25
lola: 95 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: (p118 + 1 <= p76)
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
lola: The predicate is reachable.
lola: 5 markings, 4 edges
lola: subprocess 14 will run for 1782 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: A (G ((p124 <= p97)))
lola: ========================================
lola: SUBTASK
lola: checking invariance
lola: Planning: workflow for reachability check: stateequation||search (--findpath=off)
lola: rewrite Frontend/Parser/formula_rewrite.k:721
lola: rewrite Frontend/Parser/formula_rewrite.k:787
lola: processed formula: A (G ((p124 <= p97)))
lola: processed formula length: 21
lola: 96 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: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: (p97 + 1 <= p124)
lola: state equation task get result unparse finished id 0
lola: state equation: Generated DNF with 1 literals and 1 conjunctive subformulas
lola: ========================================
lola: SUBRESULT
lola: result: no
lola: produced by: state space
lola: The predicate is not invariant.
lola: 9 markings, 10 edges
lola: ========================================
lola: subprocess 15 will run for 3564 seconds at most (--localtimelimit=0)
lola: ========================================
lola: ...considering subproblem: E (G (E (F ((p46 + 1 <= p0)))))
lola: ========================================
lola: SUBTASK
lola: checking possible preservation of reachability
lola: rewrite Frontend/Parser/formula_rewrite.k:593
lola: rewrite Frontend/Parser/formula_rewrite.k:628
lola: rewrite Frontend/Parser/formula_rewrite.k:731
lola: processed formula: (p46 + 1 <= p0)
lola: processed formula length: 15
lola: 97 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 /EGEF)
lola: state space: using reachability graph (EGEF version) (--search=depth)
lola: state space: using ctl preserving stubborn set method (--stubborn=tarjan)
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:788
lola: state equation task get result rewrite finished id 0
lola: state equation task get result unparse finished++ id 0
lola: formula 0: (p0 <= p46)
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 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: (p46 + 1 <= p0)
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-15-0.sara
lola: state equation: calling and running sara
sara: try reading problem file CTLCardinality-15-0.sara.
lola: state equation: write sara problem file to CTLCardinality-15-1.sara
lola: state equation: calling and running sara
sara: try reading problem file CTLCardinality-15-1.sara.
sara: place or transition ordering is non-deterministic

sara: place or transition ordering is non-deterministic

lola: state equation 0: solution produced
lola: state equation 1: solution produced
lola: sara is running 0 secs || sara is running 0 secs || 196981 markings, 4809023 edges, 39396 markings/sec, 0 secs
lola: sara is running 5 secs || sara is running 5 secs || 376064 markings, 9493991 edges, 35817 markings/sec, 5 secs
lola: sara is running 10 secs || sara is running 10 secs || 548954 markings, 14071869 edges, 34578 markings/sec, 10 secs
lola: sara is running 15 secs || sara is running 15 secs || 730073 markings, 18756268 edges, 36224 markings/sec, 15 secs
lola: sara is running 20 secs || sara is running 20 secs || 909399 markings, 23418068 edges, 35865 markings/sec, 20 secs
lola: sara is running 25 secs || sara is running 25 secs || 1068493 markings, 28096866 edges, 31819 markings/sec, 25 secs
lola: sara is running 30 secs || sara is running 30 secs || 1226373 markings, 32756495 edges, 31576 markings/sec, 30 secs
lola: sara is running 35 secs || sara is running 35 secs || 1386883 markings, 37420741 edges, 32102 markings/sec, 35 secs
lola: sara is running 40 secs || sara is running 40 secs || 1549701 markings, 42098456 edges, 32564 markings/sec, 40 secs
lola: sara is running 45 secs || sara is running 45 secs || 1707341 markings, 46822609 edges, 31528 markings/sec, 45 secs
lola: sara is running 50 secs || sara is running 50 secs || 1864991 markings, 51828698 edges, 31530 markings/sec, 50 secs
lola: sara is running 55 secs || sara is running 55 secs || 2020216 markings, 56782329 edges, 31045 markings/sec, 55 secs
lola: sara is running 60 secs || sara is running 60 secs || 2175138 markings, 61652955 edges, 30984 markings/sec, 60 secs
lola: sara is running 65 secs || sara is running 65 secs || 2332715 markings, 66549900 edges, 31515 markings/sec, 65 secs
lola: sara is running 70 secs || sara is running 70 secs || 2482891 markings, 71341059 edges, 30035 markings/sec, 70 secs
lola: sara is running 75 secs || sara is running 75 secs || 2625866 markings, 75905302 edges, 28595 markings/sec, 75 secs
lola: sara is running 80 secs || sara is running 80 secs || 2767950 markings, 80431852 edges, 28417 markings/sec, 80 secs
lola: sara is running 85 secs || sara is running 85 secs || 2916046 markings, 85083066 edges, 29619 markings/sec, 85 secs
lola: sara is running 90 secs || sara is running 90 secs || 3063849 markings, 89693873 edges, 29561 markings/sec, 90 secs
lola: sara is running 95 secs || sara is running 95 secs || 3206723 markings, 94264744 edges, 28575 markings/sec, 95 secs
lola: sara is running 100 secs || sara is running 100 secs || 3352324 markings, 99048106 edges, 29120 markings/sec, 100 secs
lola: sara is running 105 secs || sara is running 105 secs || 3491205 markings, 103811546 edges, 27776 markings/sec, 105 secs
lola: sara is running 110 secs || sara is running 110 secs || 3634844 markings, 108665962 edges, 28728 markings/sec, 110 secs
lola: sara is running 115 secs || sara is running 115 secs || 3770875 markings, 113315908 edges, 27206 markings/sec, 115 secs
lola: sara is running 120 secs || sara is running 120 secs || 3914436 markings, 118119455 edges, 28712 markings/sec, 120 secs
lola: sara is running 125 secs || sara is running 125 secs || 4052898 markings, 122860255 edges, 27692 markings/sec, 125 secs
lola: sara is running 130 secs || sara is running 130 secs || 4205203 markings, 127369770 edges, 30461 markings/sec, 130 secs
lola: sara is running 135 secs || sara is running 135 secs || 4357440 markings, 131845130 edges, 30447 markings/sec, 135 secs
lola: sara is running 140 secs || sara is running 140 secs || 4514093 markings, 136374757 edges, 31331 markings/sec, 140 secs
lola: sara is running 145 secs || sara is running 145 secs || 4673693 markings, 140946551 edges, 31920 markings/sec, 145 secs
lola: sara is running 150 secs || sara is running 150 secs || 4829445 markings, 145491038 edges, 31150 markings/sec, 150 secs
lola: sara is running 155 secs || sara is running 155 secs || 4976950 markings, 150105851 edges, 29501 markings/sec, 155 secs
lola: sara is running 160 secs || sara is running 160 secs || 5127142 markings, 154904104 edges, 30038 markings/sec, 160 secs
lola: sara is running 165 secs || sara is running 165 secs || 5280221 markings, 159672595 edges, 30616 markings/sec, 165 secs
lola: sara is running 170 secs || sara is running 170 secs || 5432548 markings, 164426617 edges, 30465 markings/sec, 170 secs
lola: sara is running 175 secs || sara is running 175 secs || 5580573 markings, 169133478 edges, 29605 markings/sec, 175 secs
lola: sara is running 180 secs || sara is running 180 secs || 5727358 markings, 173730203 edges, 29357 markings/sec, 180 secs
lola: sara is running 185 secs || sara is running 185 secs || 5866838 markings, 178170332 edges, 27896 markings/sec, 185 secs
lola: sara is running 190 secs || sara is running 190 secs || 6013237 markings, 182748339 edges, 29280 markings/sec, 190 secs
lola: sara is running 195 secs || sara is running 195 secs || 6153420 markings, 187189000 edges, 28037 markings/sec, 195 secs
lola: sara is running 200 secs || sara is running 200 secs || 6301896 markings, 191779762 edges, 29695 markings/sec, 200 secs
lola: sara is running 205 secs || sara is running 205 secs || 6442902 markings, 196272772 edges, 28201 markings/sec, 205 secs
lola: sara is running 210 secs || sara is running 210 secs || 6594605 markings, 200890652 edges, 30341 markings/sec, 210 secs
lola: sara is running 215 secs || sara is running 215 secs || 6744568 markings, 205402919 edges, 29993 markings/sec, 215 secs
lola: sara is running 220 secs || sara is running 220 secs || 6898382 markings, 209899305 edges, 30763 markings/sec, 220 secs
lola: sara is running 225 secs || sara is running 225 secs || 7074594 markings, 215015733 edges, 35242 markings/sec, 225 secs
lola: sara is running 230 secs || sara is running 230 secs || 7257019 markings, 220123469 edges, 36485 markings/sec, 230 secs
lola: sara is running 235 secs || sara is running 235 secs || 7427513 markings, 225142159 edges, 34099 markings/sec, 235 secs
lola: sara is running 240 secs || sara is running 240 secs || 7597508 markings, 230082400 edges, 33999 markings/sec, 240 secs
lola: sara is running 245 secs || sara is running 245 secs || 7766245 markings, 235032771 edges, 33747 markings/sec, 245 secs
lola: sara is running 250 secs || sara is running 250 secs || 7932935 markings, 239933721 edges, 33338 markings/sec, 250 secs
lola: sara is running 255 secs || sara is running 255 secs || 8109687 markings, 244928863 edges, 35350 markings/sec, 255 secs
lola: sara is running 260 secs || sara is running 260 secs || 8276843 markings, 249860321 edges, 33431 markings/sec, 260 secs
lola: sara is running 265 secs || sara is running 265 secs || 8442031 markings, 254754567 edges, 33038 markings/sec, 265 secs
lola: sara is running 270 secs || sara is running 270 secs || 8603990 markings, 259629410 edges, 32392 markings/sec, 270 secs
lola: sara is running 275 secs || sara is running 275 secs || 8777416 markings, 264528661 edges, 34685 markings/sec, 275 secs
lola: sara is running 280 secs || sara is running 280 secs || 8941228 markings, 269386167 edges, 32762 markings/sec, 280 secs
lola: sara is running 285 secs || sara is running 285 secs || 9122217 markings, 274296181 edges, 36198 markings/sec, 285 secs
lola: sara is running 290 secs || sara is running 290 secs || 9313103 markings, 279137333 edges, 38177 markings/sec, 290 secs
lola: sara is running 295 secs || sara is running 295 secs || 9487512 markings, 283956159 edges, 34882 markings/sec, 295 secs
lola: sara is running 300 secs || sara is running 300 secs || 9662054 markings, 288879875 edges, 34908 markings/sec, 300 secs
lola: sara is running 305 secs || sara is running 305 secs || 9830071 markings, 293731763 edges, 33603 markings/sec, 305 secs
lola: sara is running 310 secs || sara is running 310 secs || 9987116 markings, 298553212 edges, 31409 markings/sec, 310 secs
lola: sara is running 315 secs || sara is running 315 secs || 10148949 markings, 303373969 edges, 32367 markings/sec, 315 secs
lola: sara is running 320 secs || sara is running 320 secs || 10314028 markings, 308213799 edges, 33016 markings/sec, 320 secs
lola: sara is running 325 secs || sara is running 325 secs || 10470841 markings, 312935959 edges, 31363 markings/sec, 325 secs
lola: sara is running 330 secs || sara is running 330 secs || 10523715 markings, 314459132 edges, 10575 markings/sec, 330 secs
lola: sara is running 335 secs || sara is running 335 secs || 10524806 markings, 314486441 edges, 218 markings/sec, 335 secs
lola: sara is running 340 secs || sara is running 340 secs || 10525623 markings, 314506596 edges, 163 markings/sec, 340 secs
lola: sara is running 345 secs || sara is running 345 secs || 10525638 markings, 314506900 edges, 3 markings/sec, 345 secs
lola: sara is running 350 secs || sara is running 350 secs || 10525706 markings, 314508328 edges, 14 markings/sec, 350 secs
lola: Child process aborted or communication problem between parent and child process
lola: ========================================
lola: ...considering subproblem: E (G (E (F ((p46 + 1 <= p0)))))
lola: ========================================
lola: SUBTASK
lola: checking possible preservation of reachability
lola: rewrite Frontend/Parser/formula_rewrite.k:593
lola: rewrite Frontend/Parser/formula_rewrite.k:628
lola: rewrite Frontend/Parser/formula_rewrite.k:731
lola: processed formula: (p46 + 1 <= p0)
lola: processed formula length: 15
lola: 97 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 /EGEF)
lola: state space: using reachability graph (EGEF version) (--search=depth)
lola: state space: using ctl preserving stubborn set method (--stubborn=tarjan)
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 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 started, id 0
lola: rewrite Frontend/Parser/formula_rewrite.k:721
lola: rewrite Frontend/Parser/formula_rewrite.k:788
lola: state equation task get result rewrite finished id 0
lola: state equation task get result unparse finished++ id 0
lola: formula 0: (p0 <= p46)
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 task get result unparse finished++ id 1
lola: formula 1: (p46 + 1 <= p0)
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-16-0.sara
lola: state equation: calling and running sara
sara: try reading problem file CTLCardinality-16-0.sara.
lola: state equation: write sara problem file to CTLCardinality-16-1.sara
lola: state equation: calling and running sara
sara: try reading problem file CTLCardinality-16-1.sara.
sara: place or transition ordering is non-deterministic

lola: state equation 0: solution produced
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 || 176038 markings, 4328530 edges, 35208 markings/sec, 0 secs
lola: sara is running 5 secs || sara is running 5 secs || 363297 markings, 9146320 edges, 37452 markings/sec, 5 secs
lola: sara is running 10 secs || sara is running 10 secs || 539176 markings, 13806205 edges, 35176 markings/sec, 10 secs
lola: sara is running 15 secs || sara is running 15 secs || 723140 markings, 18563727 edges, 36793 markings/sec, 15 secs
lola: sara is running 20 secs || sara is running 20 secs || 905556 markings, 23304031 edges, 36483 markings/sec, 20 secs
lola: sara is running 25 secs || sara is running 25 secs || 1066256 markings, 28028912 edges, 32140 markings/sec, 25 secs
lola: sara is running 30 secs || sara is running 30 secs || 1223367 markings, 32672696 edges, 31422 markings/sec, 30 secs
lola: sara is running 35 secs || sara is running 35 secs || 1383196 markings, 37313117 edges, 31966 markings/sec, 35 secs
lola: sara is running 40 secs || sara is running 40 secs || 1546033 markings, 41987370 edges, 32567 markings/sec, 40 secs
lola: sara is running 45 secs || sara is running 45 secs || 1703189 markings, 46688540 edges, 31431 markings/sec, 45 secs
lola: sara is running 50 secs || sara is running 50 secs || 1860822 markings, 51700275 edges, 31527 markings/sec, 50 secs
lola: sara is running 55 secs || sara is running 55 secs || 2015344 markings, 56638562 edges, 30904 markings/sec, 55 secs
lola: sara is running 60 secs || sara is running 60 secs || 2170471 markings, 61501067 edges, 31025 markings/sec, 60 secs
lola: sara is running 65 secs || sara is running 65 secs || 2327250 markings, 66378362 edges, 31356 markings/sec, 65 secs
lola: sara is running 70 secs || sara is running 70 secs || 2476530 markings, 71146053 edges, 29856 markings/sec, 70 secs
lola: sara is running 75 secs || sara is running 75 secs || 2617841 markings, 75645124 edges, 28262 markings/sec, 75 secs
lola: sara is running 80 secs || sara is running 80 secs || 2757693 markings, 80095396 edges, 27970 markings/sec, 80 secs
lola: sara is running 85 secs || sara is running 85 secs || 2904596 markings, 84703304 edges, 29381 markings/sec, 85 secs
lola: sara is running 90 secs || sara is running 90 secs || 3049580 markings, 89243993 edges, 28997 markings/sec, 90 secs
lola: sara is running 95 secs || sara is running 95 secs || 3190597 markings, 93769292 edges, 28203 markings/sec, 95 secs
lola: sara is running 100 secs || sara is running 100 secs || 3335838 markings, 98487582 edges, 29048 markings/sec, 100 secs
lola: sara is running 105 secs || sara is running 105 secs || 3474594 markings, 103246376 edges, 27751 markings/sec, 105 secs
lola: sara is running 110 secs || sara is running 110 secs || 3616593 markings, 108092906 edges, 28400 markings/sec, 110 secs
lola: sara is running 115 secs || sara is running 115 secs || 3757645 markings, 112867978 edges, 28210 markings/sec, 115 secs
lola: sara is running 120 secs || sara is running 120 secs || 3901563 markings, 117681276 edges, 28784 markings/sec, 120 secs
lola: sara is running 125 secs || sara is running 125 secs || 4039868 markings, 122413035 edges, 27661 markings/sec, 125 secs
lola: sara is running 130 secs || sara is running 130 secs || 4188355 markings, 126856673 edges, 29697 markings/sec, 130 secs
lola: sara is running 135 secs || sara is running 135 secs || 4336827 markings, 131238397 edges, 29694 markings/sec, 135 secs
lola: sara is running 140 secs || sara is running 140 secs || 4493650 markings, 135757507 edges, 31365 markings/sec, 140 secs
lola: sara is running 145 secs || sara is running 145 secs || 4649135 markings, 140236014 edges, 31097 markings/sec, 145 secs
lola: sara is running 150 secs || sara is running 150 secs || 4800753 markings, 144681045 edges, 30324 markings/sec, 150 secs
lola: sara is running 155 secs || sara is running 155 secs || 4953718 markings, 149375963 edges, 30593 markings/sec, 155 secs
lola: sara is running 160 secs || sara is running 160 secs || 5101690 markings, 154073834 edges, 29594 markings/sec, 160 secs
lola: sara is running 165 secs || sara is running 165 secs || 5254374 markings, 158846925 edges, 30537 markings/sec, 165 secs
lola: sara is running 170 secs || sara is running 170 secs || 5403818 markings, 163537313 edges, 29889 markings/sec, 170 secs
lola: sara is running 175 secs || sara is running 175 secs || 5552454 markings, 168228267 edges, 29727 markings/sec, 175 secs
lola: sara is running 180 secs || sara is running 180 secs || 5698097 markings, 172838837 edges, 29129 markings/sec, 180 secs
lola: sara is running 185 secs || sara is running 185 secs || 5839571 markings, 177302284 edges, 28295 markings/sec, 185 secs
lola: sara is running 190 secs || sara is running 190 secs || 5982310 markings, 181837532 edges, 28548 markings/sec, 190 secs
lola: sara is running 195 secs || sara is running 195 secs || 6127512 markings, 186365077 edges, 29040 markings/sec, 195 secs
lola: sara is running 200 secs || sara is running 200 secs || 6275494 markings, 190950228 edges, 29596 markings/sec, 200 secs
lola: sara is running 205 secs || sara is running 205 secs || 6417604 markings, 195453905 edges, 28422 markings/sec, 205 secs
lola: sara is running 210 secs || sara is running 210 secs || 6569139 markings, 200115139 edges, 30307 markings/sec, 210 secs
lola: sara is running 215 secs || sara is running 215 secs || 6720350 markings, 204656067 edges, 30242 markings/sec, 215 secs
lola: sara is running 220 secs || sara is running 220 secs || 6872977 markings, 209138166 edges, 30525 markings/sec, 220 secs
lola: sara is running 225 secs || sara is running 225 secs || 7044070 markings, 214126419 edges, 34219 markings/sec, 225 secs
lola: sara is running 230 secs || sara is running 230 secs || 7225594 markings, 219264191 edges, 36305 markings/sec, 230 secs
lola: sara is running 235 secs || sara is running 235 secs || 7400233 markings, 224339346 edges, 34928 markings/sec, 235 secs
lola: sara is running 240 secs || sara is running 240 secs || 7570614 markings, 229316976 edges, 34076 markings/sec, 240 secs
lola: sara is running 245 secs || sara is running 245 secs || 7741225 markings, 234292332 edges, 34122 markings/sec, 245 secs
lola: sara is running 250 secs || sara is running 250 secs || 7909386 markings, 239230005 edges, 33632 markings/sec, 250 secs
lola: sara is running 255 secs || sara is running 255 secs || 8086121 markings, 244248974 edges, 35347 markings/sec, 255 secs
lola: sara is running 260 secs || sara is running 260 secs || 8254803 markings, 249192834 edges, 33736 markings/sec, 260 secs
lola: sara is running 265 secs || sara is running 265 secs || 8421127 markings, 254125292 edges, 33265 markings/sec, 265 secs
lola: sara is running 270 secs || sara is running 270 secs || 8585451 markings, 259059682 edges, 32865 markings/sec, 270 secs
lola: sara is running 275 secs || sara is running 275 secs || 8755658 markings, 263954639 edges, 34041 markings/sec, 275 secs
lola: sara is running 280 secs || sara is running 280 secs || 8921381 markings, 268813577 edges, 33145 markings/sec, 280 secs
lola: sara is running 285 secs || sara is running 285 secs || 9101273 markings, 273769935 edges, 35978 markings/sec, 285 secs
lola: sara is running 290 secs || sara is running 290 secs || 9289284 markings, 278614830 edges, 37602 markings/sec, 290 secs
lola: sara is running 295 secs || sara is running 295 secs || 9469239 markings, 283431185 edges, 35991 markings/sec, 295 secs
lola: sara is running 300 secs || sara is running 300 secs || 9647332 markings, 288462941 edges, 35619 markings/sec, 300 secs
lola: sara is running 305 secs || sara is running 305 secs || 9820920 markings, 293452110 edges, 34718 markings/sec, 305 secs
lola: sara is running 310 secs || sara is running 310 secs || 9981767 markings, 298384035 edges, 32169 markings/sec, 310 secs
lola: sara is running 315 secs || sara is running 315 secs || 10146823 markings, 303307789 edges, 33011 markings/sec, 315 secs
lola: sara is running 320 secs || sara is running 320 secs || 10315353 markings, 308256216 edges, 33706 markings/sec, 320 secs
lola: sara is running 325 secs || sara is running 325 secs || 10475584 markings, 313072299 edges, 32046 markings/sec, 325 secs
lola: sara is running 330 secs || sara is running 330 secs || 10523781 markings, 314460878 edges, 9639 markings/sec, 330 secs
lola: Child process aborted or communication problem between parent and child process
lola: RESULT
lola:
SUMMARY: no yes no no no no no yes no no no no no unknown yes yes
lola:
preliminary result: no yes no no no no no yes no no no no no unknown yes yes
lola: memory consumption: 33328 KB
lola: time consumption: 708 seconds
lola: print data as JSON (--json)
lola: writing JSON to CTLCardinality.json
lola: closed JSON file CTLCardinality.json
rslt: finished

BK_STOP 1552458072332

--------------------
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="DLCround-PT-10a"
export BK_EXAMINATION="CTLCardinality"
export BK_TOOL="lola"
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-3954"
echo " Executing tool lola"
echo " Input is DLCround-PT-10a, 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 r049-oct2-155234405100436"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/DLCround-PT-10a.tgz
mv DLCround-PT-10a execution
cd execution
if [ "CTLCardinality" = "GlobalProperties" ] ; then
rm -f GenericPropertiesVerdict.xml
fi
if [ "CTLCardinality" = "UpperBounds" ] ; 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
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 ;