Execution Chart

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

Trace from the execution

Formatting '/data/fkordon/mcc2023-input.r225-tall-167856407100102.qcow2', fmt=qcow2 size=4294967296 backing_file=/data/fkordon/mcc2023-input.qcow2 cluster_size=65536 lazy_refcounts=off refcount_bits=16
Waiting for the VM to be ready (probing ssh)
...........................................................................................................................................................................................................................................
=====================================================================
Generated by BenchKit 2-5348
Executing tool marcie
Input is IBMB2S565S3960-PT-none, examination is ReachabilityCardinality
Time confinement is 3600 seconds
Memory confinement is 16384 MBytes
Number of cores is 1
Run identifier is r225-tall-167856407100102
=====================================================================

--------------------
preparation of the directory to be used:
/home/mcc/execution
total 496K
-rw-r--r-- 1 mcc users 9.3K Feb 26 05:47 CTLCardinality.txt
-rw-r--r-- 1 mcc users 64K Feb 26 05:47 CTLCardinality.xml
-rw-r--r-- 1 mcc users 8.0K Feb 26 05:46 CTLFireability.txt
-rw-r--r-- 1 mcc users 45K Feb 26 05:46 CTLFireability.xml
-rw-r--r-- 1 mcc users 4.2K Jan 29 11:40 GenericPropertiesDefinition.xml
-rw-r--r-- 1 mcc users 6.4K Jan 29 11:40 GenericPropertiesVerdict.xml
-rw-r--r-- 1 mcc users 6.1K Feb 25 16:17 LTLCardinality.txt
-rw-r--r-- 1 mcc users 28K Feb 25 16:17 LTLCardinality.xml
-rw-r--r-- 1 mcc users 3.9K Feb 25 16:17 LTLFireability.txt
-rw-r--r-- 1 mcc users 18K Feb 25 16:17 LTLFireability.xml
-rw-r--r-- 1 mcc users 12K Feb 26 05:47 ReachabilityCardinality.txt
-rw-r--r-- 1 mcc users 65K Feb 26 05:47 ReachabilityCardinality.xml
-rw-r--r-- 1 mcc users 11K Feb 26 05:47 ReachabilityFireability.txt
-rw-r--r-- 1 mcc users 44K Feb 26 05:47 ReachabilityFireability.xml
-rw-r--r-- 1 mcc users 2.2K Feb 25 16:17 UpperBounds.txt
-rw-r--r-- 1 mcc users 4.3K Feb 25 16:17 UpperBounds.xml
-rw-r--r-- 1 mcc users 6 Mar 5 18:22 equiv_col
-rw-r--r-- 1 mcc users 5 Mar 5 18:22 instance
-rw-r--r-- 1 mcc users 6 Mar 5 18:22 iscolored
-rw-r--r-- 1 mcc users 127K Mar 5 18:22 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 IBMB2S565S3960-PT-none-ReachabilityCardinality-00
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-01
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-02
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-03
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-04
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-05
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-06
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-07
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-08
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-09
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-10
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-11
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-12
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-13
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-14
FORMULA_NAME IBMB2S565S3960-PT-none-ReachabilityCardinality-15

=== Now, execution of the tool begins

BK_START 1678606137763

bash -c /home/mcc/BenchKit/BenchKit_head.sh 2> STDERR ; echo ; echo -n "BK_STOP " ; date -u +%s%3N
Invoking MCC driver with
BK_TOOL=marcie
BK_EXAMINATION=ReachabilityCardinality
BK_BIN_PATH=/home/mcc/BenchKit/bin/
BK_TIME_CONFINEMENT=3600
BK_INPUT=IBMB2S565S3960-PT-none
Not applying reductions.
Model is PT
ReachabilityCardinality PT
timeout --kill-after=10s --signal=SIGINT 1m for testing only

Marcie built on Linux at 2019-11-18.
A model checker for Generalized Stochastic Petri nets

authors: Alex Tovchigrechko (IDD package and CTL model checking)

Martin Schwarick (Symbolic numerical analysis and CSL model checking)

Christian Rohr (Simulative and approximative numerical model checking)

marcie@informatik.tu-cottbus.de

called as: /home/mcc/BenchKit/bin//../marcie/bin/marcie --net-file=model.pnml --mcc-file=ReachabilityCardinality.xml --memory=6 --mcc-mode

parse successfull
net created successfully

Net: IBMB2S565S3960_PT_none
(NrP: 273 NrTr: 179 NrArc: 572)

parse formulas
formulas created successfully
place and transition orderings generation:0m 0.005sec

net check time: 0m 0.000sec

init dd package: 0m 2.771sec


RS generation: 5m46.291sec


-> reachability set: #nodes 4000871 (4.0e+06) #states 15,510,635,947,410,534 (16)



starting MCC model checker
--------------------------

checking: AG [join_s00001164_input_s00001066<=1]
normalized: ~ [E [true U ~ [join_s00001164_input_s00001066<=1]]]

abstracting: (join_s00001164_input_s00001066<=1)
states: 15,510,635,947,410,534 (16)
-> the formula is TRUE

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-00 TRUE TECHNIQUES SEQUENTIAL_PROCESSING DECISION_DIAGRAMS UNFOLDING_TO_PT

MC time: 0m 2.988sec

checking: EF [~ [callToTask_s00001360_output_s00001077<=0]]
normalized: E [true U ~ [callToTask_s00001360_output_s00001077<=0]]

abstracting: (callToTask_s00001360_output_s00001077<=0)
states: 15,161,165,430,196,733 (16)

before gc: list nodes free: 1026603

after gc: idd nodes used:8992729, unused:55007271; list nodes free:271996972
MC time: 3m38.900sec

checking: EF [1<=merge_s00002240_input_s00001127]
normalized: E [true U 1<=merge_s00002240_input_s00001127]

abstracting: (1<=merge_s00002240_input_s00001127)
states: 544,037,429,927,175 (14)
MC time: 3m22.727sec

checking: EF [1<=fork_s00001298_input_s00001066]
normalized: E [true U 1<=fork_s00001298_input_s00001066]

abstracting: (1<=fork_s00001298_input_s00001066)
states: 1,280,823,646,692,230 (15)

before gc: list nodes free: 817289

after gc: idd nodes used:9754395, unused:54245605; list nodes free:274709891
MC time: 3m 9.097sec

checking: AG [callToTask_s00003976_input_s00001052<=0]
normalized: ~ [E [true U ~ [callToTask_s00003976_input_s00001052<=0]]]

abstracting: (callToTask_s00003976_input_s00001052<=0)
states: 14,960,479,797,793,305 (16)
MC time: 2m56.620sec

checking: AG [join_s00002243_input_s00001263<=callToTask_s00001360_output_s00001077]
normalized: ~ [E [true U ~ [join_s00002243_input_s00001263<=callToTask_s00001360_output_s00001077]]]

abstracting: (join_s00002243_input_s00001263<=callToTask_s00001360_output_s00001077)
states: 11,894,476,954,528,906 (16)

before gc: list nodes free: 343163

after gc: idd nodes used:18366851, unused:45633149; list nodes free:236067507
MC time: 2m44.419sec

checking: EF [~ [merge_s00002241_activated<=fork_s00001403_input_s00001066]]
normalized: E [true U ~ [merge_s00002241_activated<=fork_s00001403_input_s00001066]]

abstracting: (merge_s00002241_activated<=fork_s00001403_input_s00001066)
states: 5,995,348,350,518,199 (15)
MC time: 2m33.991sec

checking: AG [process_s00000565__s00003960_output_s00001249<=callToProcess_s00003904_input_s00001066]
normalized: ~ [E [true U ~ [process_s00000565__s00003960_output_s00001249<=callToProcess_s00003904_input_s00001066]]]

abstracting: (process_s00000565__s00003960_output_s00001249<=callToProcess_s00003904_input_s00001066)
states: 6,083,112,138,605,387 (15)
MC time: 2m25.010sec

checking: EF [~ [merge_s00002241_activated<=merge_s00001061_input_s00001165]]
normalized: E [true U ~ [merge_s00002241_activated<=merge_s00001061_input_s00001165]]

abstracting: (merge_s00002241_activated<=merge_s00001061_input_s00001165)
states: 6,379,195,005,233,157 (15)
MC time: 2m14.197sec

checking: EF [[[[~ [callToTask_s00003970_input_s00001068<=1] | 1<=endNode_s00001401_input_default] | 1<=callToTask_s00003973_output_s00001054] & merge_s00001162_input_s00001128<=1]]
normalized: E [true U [merge_s00001162_input_s00001128<=1 & [1<=callToTask_s00003973_output_s00001054 | [1<=endNode_s00001401_input_default | ~ [callToTask_s00003970_input_s00001068<=1]]]]]

abstracting: (callToTask_s00003970_input_s00001068<=1)

before gc: list nodes free: 783477

after gc: idd nodes used:18764460, unused:45235540; list nodes free:243113003
states: 15,494,505,311,465,598 (16)
abstracting: (1<=endNode_s00001401_input_default)
states: 323,644,936,551,575 (14)
abstracting: (1<=callToTask_s00003973_output_s00001054)
states: 1,280,823,646,692,230 (15)
abstracting: (merge_s00001162_input_s00001128<=1)
states: 15,510,635,947,410,534 (16)
MC time: 2m 6.278sec

checking: EF [[~ [fork_s00002500_activated_s00001073<=merge_s00001162_input_s00001166] & join_s00001164_input_s00001052<=callToTask_s00003908_output_s00001077]]
normalized: E [true U [join_s00001164_input_s00001052<=callToTask_s00003908_output_s00001077 & ~ [fork_s00002500_activated_s00001073<=merge_s00001162_input_s00001166]]]

abstracting: (fork_s00002500_activated_s00001073<=merge_s00001162_input_s00001166)
states: 12,503,338,781,139,047 (16)
abstracting: (join_s00001164_input_s00001052<=callToTask_s00003908_output_s00001077)
states: 14,695,790,080,558,673 (16)

before gc: list nodes free: 281003

after gc: idd nodes used:31244321, unused:32755679; list nodes free:175846327
MC time: 1m56.480sec

checking: AG [~ [[~ [[fork_s00001403_activated_s00001078<=0 & [callToTask_s00003962_output_s00001054<=0 & [[endNode_s00001401_input_default<=0 & callToTask_s00003908_output_s00001083<=1] | [1<=merge_s00001161_input_s00001128 & callToTask_s00003980_input_s00001068<=1]]]]] & [~ [[callToTask_s00003979_output_s00001054<=0 | [[[~ [merge_s00002239_input_s00001066<=0] & decision_s00001126_activated<=1] & callToProcess_s00003859_inputCriterion_s00001053_used<=join_s00001163_input_s00001128] | callToProcess_s00003872_inputCriterion_s00001053_used<=process_s00000565__s00003960_output_s00001249]]] & merge_s00001061_input_s00001165<=callToTask_s00003974_output_s00001077]]]]
normalized: ~ [E [true U [[merge_s00001061_input_s00001165<=callToTask_s00003974_output_s00001077 & ~ [[callToTask_s00003979_output_s00001054<=0 | [callToProcess_s00003872_inputCriterion_s00001053_used<=process_s00000565__s00003960_output_s00001249 | [callToProcess_s00003859_inputCriterion_s00001053_used<=join_s00001163_input_s00001128 & [decision_s00001126_activated<=1 & ~ [merge_s00002239_input_s00001066<=0]]]]]]] & ~ [[fork_s00001403_activated_s00001078<=0 & [callToTask_s00003962_output_s00001054<=0 & [[1<=merge_s00001161_input_s00001128 & callToTask_s00003980_input_s00001068<=1] | [endNode_s00001401_input_default<=0 & callToTask_s00003908_output_s00001083<=1]]]]]]]]

abstracting: (callToTask_s00003908_output_s00001083<=1)
states: 15,425,106,017,266,363 (16)
abstracting: (endNode_s00001401_input_default<=0)
states: 15,186,991,010,858,959 (16)
abstracting: (callToTask_s00003980_input_s00001068<=1)
states: 15,504,887,341,865,909 (16)
abstracting: (1<=merge_s00001161_input_s00001128)
states: 550,156,149,617,229 (14)
abstracting: (callToTask_s00003962_output_s00001054<=0)
states: 14,960,479,797,793,305 (16)
abstracting: (fork_s00001403_activated_s00001078<=0)
states: 14,121,265,372,550,970 (16)
abstracting: (merge_s00002239_input_s00001066<=0)
states: 15,191,546,186,853,591 (16)
abstracting: (decision_s00001126_activated<=1)
states: 15,494,505,311,465,598 (16)
abstracting: (callToProcess_s00003859_inputCriterion_s00001053_used<=join_s00001163_input_s00001128)
states: 15,201,999,400,066,991 (16)
abstracting: (callToProcess_s00003872_inputCriterion_s00001053_used<=process_s00000565__s00003960_output_s00001249)
states: 15,305,124,640,075,364 (16)

before gc: list nodes free: 183220

after gc: idd nodes used:51033822, unused:12966178; list nodes free:77410463
abstracting: (callToTask_s00003979_output_s00001054<=0)
states: 15,276,804,595,350,516 (16)
abstracting: (merge_s00001061_input_s00001165<=callToTask_s00003974_output_s00001077)
states: 14,232,356,641,378,428 (16)
MC time: 2m34.556sec

checking: EF [[[[[[[[[~ [callToTask_s00003970_input_s00001066<=merge_s00001161_input_s00001068] | ~ [callToTask_s00003965_output_s00001077<=0]] & [[callToProcess_s00003880_input_s00001052<=fork_s00002500_input_s00001052 & 1<=callToProcess_s00003872_output_s00001077] & [callToTask_s00003965_output_s00001054<=callToTask_s00003974_output_s00001054 & callToTask_s00003976_output_s00001077<=1]]] & callToTask_s00003913_output_s00001077<=callToTask_s00003979_output_s00001054] & 1<=callToProcess_s00003884_output_s00001054] | ~ [[[fork_s00001403_activated_s00001075<=1 | callToTask_s00003977_input_s00001066<=callToTask_s00003979_output_s00001054] | ~ [1<=callToTask_s00003963_input_s00001066]]]] & callToTask_s00003976_input_s00001066<=0] & 1<=decision_s00002760_activated] | 1<=decision_s00001126_activated]]
normalized: E [true U [1<=decision_s00001126_activated | [1<=decision_s00002760_activated & [callToTask_s00003976_input_s00001066<=0 & [[1<=callToProcess_s00003884_output_s00001054 & [callToTask_s00003913_output_s00001077<=callToTask_s00003979_output_s00001054 & [[~ [callToTask_s00003970_input_s00001066<=merge_s00001161_input_s00001068] | ~ [callToTask_s00003965_output_s00001077<=0]] & [[callToTask_s00003965_output_s00001054<=callToTask_s00003974_output_s00001054 & callToTask_s00003976_output_s00001077<=1] & [callToProcess_s00003880_input_s00001052<=fork_s00002500_input_s00001052 & 1<=callToProcess_s00003872_output_s00001077]]]]] | ~ [[~ [1<=callToTask_s00003963_input_s00001066] | [fork_s00001403_activated_s00001075<=1 | callToTask_s00003977_input_s00001066<=callToTask_s00003979_output_s00001054]]]]]]]]

abstracting: (callToTask_s00003977_input_s00001066<=callToTask_s00003979_output_s00001054)
states: 15,277,230,596,496,538 (16)
abstracting: (fork_s00001403_activated_s00001075<=1)
states: 15,496,105,978,124,649 (16)
abstracting: (1<=callToTask_s00003963_input_s00001066)
states: 3,677,145,448,665,316 (15)
abstracting: (1<=callToProcess_s00003872_output_s00001077)

before gc: list nodes free: 1310281

after gc: idd nodes used:52304867, unused:11695133; list nodes free:73484012
states: 550,156,149,617,229 (14)
abstracting: (callToProcess_s00003880_input_s00001052<=fork_s00002500_input_s00001052)
states: 11,187,109,173,425,839 (16)
abstracting: (callToTask_s00003976_output_s00001077<=1)
states: 15,494,505,311,465,598 (16)
abstracting: (callToTask_s00003965_output_s00001054<=callToTask_s00003974_output_s00001054)
states: 15,081,310,841,961,679 (16)
abstracting: (callToTask_s00003965_output_s00001077<=0)
states: 15,079,443,870,068,044 (16)
abstracting: (callToTask_s00003970_input_s00001066<=merge_s00001161_input_s00001068)
states: 14,976,239,834,719,983 (16)

before gc: list nodes free: 8656102

after gc: idd nodes used:63117126, unused:882874; list nodes free:13961867

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-03 CANNOT_COMPUTE

MC time: 1m40.707sec

checking: EF [[~ [fork_s00001298_input_s00001052<=join_s00001163_input_s00001128] | [[1<=callToTask_s00003976_input_s00001066 & [[callToTask_s00003963_output_s00001054<=0 & [[[[callToTask_s00003978_inputCriterion_s00001053_used<=0 | 1<=callToTask_s00003978_inputCriterion_s00001053_used] & callToTask_s00003913_inputCriterion_s00001053_used<=1] & callToTask_s00003908_inputCriterion_s00001053_used<=decision_s00001157_activated] & ~ [[[merge_s00001162_input_s00001068<=callToTask_s00003975_output_s00001077 | decision_s00001158_activated<=callToProcess_s00003957_inputCriterion_s00001053_used] & [1<=callToTask_s00003977_inputCriterion_s00001053_used & callToTask_s00003966_output_s00001054<=callToTask_s00003969_output_s00001080]]]]] & merge_s00001162_input_s00001068<=1]] & ~ [[merge_s00001160_input_s00001052<=join_s00002243_activated_s00001062 & callToProcess_s00001108_inputCriterion_s00001053_used<=0]]]]]
normalized: E [true U [[~ [[merge_s00001160_input_s00001052<=join_s00002243_activated_s00001062 & callToProcess_s00001108_inputCriterion_s00001053_used<=0]] & [1<=callToTask_s00003976_input_s00001066 & [merge_s00001162_input_s00001068<=1 & [callToTask_s00003963_output_s00001054<=0 & [[callToTask_s00003908_inputCriterion_s00001053_used<=decision_s00001157_activated & [callToTask_s00003913_inputCriterion_s00001053_used<=1 & [callToTask_s00003978_inputCriterion_s00001053_used<=0 | 1<=callToTask_s00003978_inputCriterion_s00001053_used]]] & ~ [[[merge_s00001162_input_s00001068<=callToTask_s00003975_output_s00001077 | decision_s00001158_activated<=callToProcess_s00003957_inputCriterion_s00001053_used] & [1<=callToTask_s00003977_inputCriterion_s00001053_used & callToTask_s00003966_output_s00001054<=callToTask_s00003969_output_s00001080]]]]]]]] | ~ [fork_s00001298_input_s00001052<=join_s00001163_input_s00001128]]]

abstracting: (fork_s00001298_input_s00001052<=join_s00001163_input_s00001128)

before gc: list nodes free: 8979089

after gc: idd nodes used:59437918, unused:4562082; list nodes free:35542091
states: 14,326,316,882,802,214 (16)
abstracting: (callToTask_s00003966_output_s00001054<=callToTask_s00003969_output_s00001080)
states: 12,241,157,977,735,734 (16)
abstracting: (1<=callToTask_s00003977_inputCriterion_s00001053_used)

before gc: list nodes free: 9110331

after gc: idd nodes used:63617096, unused:382904; list nodes free:11379177

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-05 CANNOT_COMPUTE

MC time: 1m 8.269sec

checking: AG [~ [[[callToTask_s00003978_output_s00001077<=0 | [1<=callToProcess_s00003859_output_s00001054 & [[[~ [1<=merge_s00002238_input_s00001066] & [[1<=decision_s00002760_activated | callToTask_s00003980_output_s00001080<=0] & ~ [1<=merge_s00001061_input_s00001127]]] & [~ [1<=fork_s00002501_activated_s00001075] & ~ [callToProcess_s00003880_output_s00001077<=callToTask_s00003976_output_s00001077]]] & ~ [1<=callToProcess_s00003957_input_s00001068]]]] & [[[[[callToTask_s00003975_input_s00001066<=callToTask_s00003980_input_s00001052 & [~ [merge_s00002240_activated<=1] & [1<=decision_s00002762_activated & callToTask_s00003964_input_s00001066<=callToProcess_s00003876_inputCriterion_s00001053_used]]] & process_s00000565__s00003960_input_s00001052<=0] | 1<=callToTask_s00003980_output_s00001080] & [~ [1<=callToTask_s00003969_inputCriterion_s00001053_used] & ~ [[[decision_s00002055_activated<=1 & callToProcess_s00003957_input_s00001068<=join_s00001164_activated_s00001062] | ~ [merge_s00001061_input_s00001165<=callToTask_s00003970_input_s00001066]]]]] & ~ [[1<=callToTask_s00003913_input_s00001052 | [~ [[[callToTask_s00003913_input_s00001066<=fork_s00002755_activated_s00001073 & merge_s00002240_input_s00001066<=0] & fork_s00001403_activated_s00001073<=callToProcess_s00003880_input_s00001052]] | [[[callToTask_s00003975_input_s00001052<=callToProcess_s00003872_output_s00001054 & callToTask_s00003968_inputCriterion_s00001053_used<=callToTask_s00003964_output_s00001054] | callToTask_s00003981_inputCriterion_s00001053_used<=1] | [[callToTask_s00003974_inputCriterion_s00001053_used<=1 & callToProcess_s00003872_output_s00001077<=0] & [1<=join_s00002243_activated_s00001065 | callToTask_s00003980_output_s00001083<=1]]]]]]]]]]
normalized: ~ [E [true U [[callToTask_s00003978_output_s00001077<=0 | [1<=callToProcess_s00003859_output_s00001054 & [~ [1<=callToProcess_s00003957_input_s00001068] & [[~ [callToProcess_s00003880_output_s00001077<=callToTask_s00003976_output_s00001077] & ~ [1<=fork_s00002501_activated_s00001075]] & [[~ [1<=merge_s00001061_input_s00001127] & [1<=decision_s00002760_activated | callToTask_s00003980_output_s00001080<=0]] & ~ [1<=merge_s00002238_input_s00001066]]]]]] & [[[~ [[[decision_s00002055_activated<=1 & callToProcess_s00003957_input_s00001068<=join_s00001164_activated_s00001062] | ~ [merge_s00001061_input_s00001165<=callToTask_s00003970_input_s00001066]]] & ~ [1<=callToTask_s00003969_inputCriterion_s00001053_used]] & [1<=callToTask_s00003980_output_s00001080 | [process_s00000565__s00003960_input_s00001052<=0 & [callToTask_s00003975_input_s00001066<=callToTask_s00003980_input_s00001052 & [[1<=decision_s00002762_activated & callToTask_s00003964_input_s00001066<=callToProcess_s00003876_inputCriterion_s00001053_used] & ~ [merge_s00002240_activated<=1]]]]]] & ~ [[1<=callToTask_s00003913_input_s00001052 | [~ [[fork_s00001403_activated_s00001073<=callToProcess_s00003880_input_s00001052 & [callToTask_s00003913_input_s00001066<=fork_s00002755_activated_s00001073 & merge_s00002240_input_s00001066<=0]]] | [[callToTask_s00003981_inputCriterion_s00001053_used<=1 | [callToTask_s00003975_input_s00001052<=callToProcess_s00003872_output_s00001054 & callToTask_s00003968_inputCriterion_s00001053_used<=callToTask_s00003964_output_s00001054]] | [[1<=join_s00002243_activated_s00001065 | callToTask_s00003980_output_s00001083<=1] & [callToTask_s00003974_inputCriterion_s00001053_used<=1 & callToProcess_s00003872_output_s00001077<=0]]]]]]]]]]

abstracting: (callToProcess_s00003872_output_s00001077<=0)

before gc: list nodes free: 9110250

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18360724

before gc: list nodes free: 10800012

after gc: idd nodes used:62532288, unused:1467712; list nodes free:18360727

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-04 CANNOT_COMPUTE

MC time: 0m50.243sec

checking: EF [[[[[~ [[[[[1<=merge_s00002238_input_s00001052 & fork_s00001298_input_s00001052<=callToTask_s00003980_output_s00001054] & callToTask_s00003913_input_s00001052<=callToProcess_s00001108_inputCriterion_s00001053_used] | merge_s00002241_activated<=callToTask_s00003975_output_s00001080] & ~ [[[join_s00002243_activated_s00001064<=callToProcess_s00003957_output_s00001077 & callToTask_s00003964_output_s00001077<=0] | [1<=merge_s00002240_input_s00001052 | 1<=callToTask_s00003979_output_s00001077]]]]] | [[[[1<=callToTask_s00003908_output_s00001077 & endNode_s00002053_input_default<=0] | ~ [[callToTask_s00003969_input_s00001052<=0 | callToProcess_s00003880_inputCriterion_s00001053_used<=1]]] & [1<=callToTask_s00003980_output_s00001080 & [[callToTask_s00003962_input_s00001068<=0 & callToTask_s00003908_input_s00001052<=callToTask_s00003969_input_s00001066] & callToTask_s00003908_output_s00001077<=callToTask_s00003971_input_s00001052]]] & callToProcess_s00003957_input_s00001052<=callToTask_s00003913_input_s00001066]] | callToTask_s00003970_input_s00001052<=1] | callToTask_s00003977_output_s00001077<=merge_s00001161_input_s00001127] & [~ [decision_s00001126_activated<=1] | ~ [[[[[[[callToProcess_s00003884_input_s00001052<=callToProcess_s00003859_output_s00001080 | 1<=callToTask_s00003913_inputCriterion_s00001053_used] & [callToTask_s00003976_input_s00001066<=callToTask_s00003981_output_s00001077 | callToProcess_s00001108_output_s00001077<=callToTask_s00003978_output_s00001077]] | 1<=merge_s00001161_input_s00001068] & [callToTask_s00003971_inputCriterion_s00001053_used<=callToTask_s00003963_output_s00001054 | ~ [callToTask_s00003970_inputCriterion_s00001053_used<=0]]] | ~ [[[~ [callToProcess_s00003872_input_s00001052<=join_s00002243_input_s00001166] & ~ [callToTask_s00003976_output_s00001054<=join_s00001163_input_s00001066]] & ~ [[1<=merge_s00001161_input_s00001068 | decision_s00002055_activated<=1]]]]] | ~ [join_s00001163_input_s00001128<=fork_s00001071_activated_s00001073]]]]]]
normalized: E [true U [[callToTask_s00003977_output_s00001077<=merge_s00001161_input_s00001127 | [callToTask_s00003970_input_s00001052<=1 | [~ [[~ [[[1<=merge_s00002240_input_s00001052 | 1<=callToTask_s00003979_output_s00001077] | [join_s00002243_activated_s00001064<=callToProcess_s00003957_output_s00001077 & callToTask_s00003964_output_s00001077<=0]]] & [merge_s00002241_activated<=callToTask_s00003975_output_s00001080 | [callToTask_s00003913_input_s00001052<=callToProcess_s00001108_inputCriterion_s00001053_used & [1<=merge_s00002238_input_s00001052 & fork_s00001298_input_s00001052<=callToTask_s00003980_output_s00001054]]]]] | [callToProcess_s00003957_input_s00001052<=callToTask_s00003913_input_s00001066 & [[~ [[callToTask_s00003969_input_s00001052<=0 | callToProcess_s00003880_inputCriterion_s00001053_used<=1]] | [1<=callToTask_s00003908_output_s00001077 & endNode_s00002053_input_default<=0]] & [1<=callToTask_s00003980_output_s00001080 & [callToTask_s00003908_output_s00001077<=callToTask_s00003971_input_s00001052 & [callToTask_s00003962_input_s00001068<=0 & callToTask_s00003908_input_s00001052<=callToTask_s00003969_input_s00001066]]]]]]]] & [~ [[~ [join_s00001163_input_s00001128<=fork_s00001071_activated_s00001073] | [~ [[[~ [callToTask_s00003976_output_s00001054<=join_s00001163_input_s00001066] & ~ [callToProcess_s00003872_input_s00001052<=join_s00002243_input_s00001166]] & ~ [[1<=merge_s00001161_input_s00001068 | decision_s00002055_activated<=1]]]] | [[callToTask_s00003971_inputCriterion_s00001053_used<=callToTask_s00003963_output_s00001054 | ~ [callToTask_s00003970_inputCriterion_s00001053_used<=0]] & [1<=merge_s00001161_input_s00001068 | [[callToProcess_s00003884_input_s00001052<=callToProcess_s00003859_output_s00001080 | 1<=callToTask_s00003913_inputCriterion_s00001053_used] & [callToTask_s00003976_input_s00001066<=callToTask_s00003981_output_s00001077 | callToProcess_s00001108_output_s00001077<=callToTask_s00003978_output_s00001077]]]]]]] | ~ [decision_s00001126_activated<=1]]]]

abstracting: (decision_s00001126_activated<=1)
states: 15,494,505,311,465,598 (16)

before gc: list nodes free: 10800012

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18360731
abstracting: (callToProcess_s00001108_output_s00001077<=callToTask_s00003978_output_s00001077)

before gc: list nodes free: 9544531

after gc: idd nodes used:63509821, unused:490179; list nodes free:12471488

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-07 CANNOT_COMPUTE

MC time: 0m59.563sec

checking: EF [~ [callToTask_s00001360_output_s00001077<=0]]
normalized: E [true U ~ [callToTask_s00001360_output_s00001077<=0]]

abstracting: (callToTask_s00001360_output_s00001077<=0)
states: 15,161,165,430,196,733 (16)

before gc: list nodes free: 9544526

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18360731

before gc: list nodes free: 9715563

after gc: idd nodes used:63777993, unused:222007; list nodes free:10908320

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-06 CANNOT_COMPUTE

MC time: 1m 1.859sec

checking: EF [1<=merge_s00002240_input_s00001127]
normalized: E [true U 1<=merge_s00002240_input_s00001127]

abstracting: (1<=merge_s00002240_input_s00001127)
states: 544,037,429,927,175 (14)

before gc: list nodes free: 9716809

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365838

before gc: list nodes free: 10430153

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365838

before gc: list nodes free: 10338315

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365838

before gc: list nodes free: 10602304

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10969375

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10995936

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-11 CANNOT_COMPUTE

MC time: 3m28.577sec

checking: EF [1<=fork_s00001298_input_s00001066]
normalized: E [true U 1<=fork_s00001298_input_s00001066]

abstracting: (1<=fork_s00001298_input_s00001066)
states: 1,280,823,646,692,230 (15)

before gc: list nodes free: 11134550

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10372850

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365838

before gc: list nodes free: 10282674

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365838

before gc: list nodes free: 10547629

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10907781

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10930307

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 11058902

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10834143

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365842

before gc: list nodes free: 10798152

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365778

before gc: list nodes free: 11114489

after gc: idd nodes used:63923105, unused:76895; list nodes free:11511271

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-12 CANNOT_COMPUTE

MC time: 8m 6.580sec

checking: AG [callToTask_s00003976_input_s00001052<=0]
normalized: ~ [E [true U ~ [callToTask_s00003976_input_s00001052<=0]]]

abstracting: (callToTask_s00003976_input_s00001052<=0)
states: 14,960,479,797,793,305 (16)

before gc: list nodes free: 11114489

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18365660

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-13 CANNOT_COMPUTE

MC time: 0m29.062sec

checking: AG [join_s00002243_input_s00001263<=callToTask_s00001360_output_s00001077]
normalized: ~ [E [true U ~ [join_s00002243_input_s00001263<=callToTask_s00001360_output_s00001077]]]

abstracting: (join_s00002243_input_s00001263<=callToTask_s00001360_output_s00001077)
states: 11,894,476,954,528,906 (16)

before gc: list nodes free: 9695592

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18366549

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-01 CANNOT_COMPUTE

MC time: 0m29.069sec

checking: EF [~ [merge_s00002241_activated<=fork_s00001403_input_s00001066]]
normalized: E [true U ~ [merge_s00002241_activated<=fork_s00001403_input_s00001066]]

abstracting: (merge_s00002241_activated<=fork_s00001403_input_s00001066)
states: 5,995,348,350,518,199 (15)

before gc: list nodes free: 10249488

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18366728

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-08 CANNOT_COMPUTE

MC time: 0m30.097sec

checking: AG [process_s00000565__s00003960_output_s00001249<=callToProcess_s00003904_input_s00001066]
normalized: ~ [E [true U ~ [process_s00000565__s00003960_output_s00001249<=callToProcess_s00003904_input_s00001066]]]

abstracting: (process_s00000565__s00003960_output_s00001249<=callToProcess_s00003904_input_s00001066)
states: 6,083,112,138,605,387 (15)

before gc: list nodes free: 8601912

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18366758

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-10 CANNOT_COMPUTE

MC time: 0m30.537sec

checking: EF [~ [merge_s00002241_activated<=merge_s00001061_input_s00001165]]
normalized: E [true U ~ [merge_s00002241_activated<=merge_s00001061_input_s00001165]]

abstracting: (merge_s00002241_activated<=merge_s00001061_input_s00001165)
states: 6,379,195,005,233,157 (15)

before gc: list nodes free: 7935574

after gc: idd nodes used:62532287, unused:1467713; list nodes free:18366941

before gc: list nodes free: 10294398

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180994

before gc: list nodes free: 10294370

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180994

before gc: list nodes free: 10294339

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180994

before gc: list nodes free: 10292646

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180994

before gc: list nodes free: 10117359

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180994

before gc: list nodes free: 10290041

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180994

before gc: list nodes free: 10449531

after gc: idd nodes used:62551731, unused:1448269; list nodes free:18180998

FORMULA IBMB2S565S3960-PT-none-ReachabilityCardinality-15 CANNOT_COMPUTE

TIME LIMIT: Killed by timeout after 3600 seconds
MemTotal: 16393932 kB
MemFree: 4965296 kB
After kill :
MemTotal: 16393932 kB
MemFree: 16182124 kB

BK_TIME_CONFINEMENT_REACHED

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

check for maximal unmarked siphon
ok
check for constant places
ok
check if there are places and transitions
ok
check if there are transitions without pre-places
ok
check if at least one transition is enabled in m0
ok
check if there are transitions that can never fire
ok


initing FirstDep: 0m 0.002sec


iterations count:8948 (49), effective:1199 (6)

initing FirstDep: 0m 0.001sec


sat_reach.icc:155: Timeout: after 216 sec


sat_reach.icc:155: Timeout: after 201 sec


sat_reach.icc:155: Timeout: after 188 sec


sat_reach.icc:155: Timeout: after 175 sec


sat_reach.icc:155: Timeout: after 163 sec


sat_reach.icc:155: Timeout: after 152 sec


sat_reach.icc:155: Timeout: after 142 sec


sat_reach.icc:155: Timeout: after 132 sec


sat_reach.icc:155: Timeout: after 123 sec


sat_reach.icc:155: Timeout: after 115 sec


sat_reach.icc:155: Timeout: after 107 sec

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

idd.cc:820: DD Exception: No free nodes

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="IBMB2S565S3960-PT-none"
export BK_EXAMINATION="ReachabilityCardinality"
export BK_TOOL="marcie"
export BK_RESULT_DIR="/tmp/BK_RESULTS/OUTPUTS"
export BK_TIME_CONFINEMENT="3600"
export BK_MEMORY_CONFINEMENT="16384"
export BK_BIN_PATH="/home/mcc/BenchKit/bin/"

# this is specific to your benchmark or test

export BIN_DIR="$HOME/BenchKit/bin"

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

# this is for BenchKit: explicit launching of the test
echo "====================================================================="
echo " Generated by BenchKit 2-5348"
echo " Executing tool marcie"
echo " Input is IBMB2S565S3960-PT-none, examination is ReachabilityCardinality"
echo " Time confinement is $BK_TIME_CONFINEMENT seconds"
echo " Memory confinement is 16384 MBytes"
echo " Number of cores is 1"
echo " Run identifier is r225-tall-167856407100102"
echo "====================================================================="
echo
echo "--------------------"
echo "preparation of the directory to be used:"

tar xzf /home/mcc/BenchKit/INPUTS/IBMB2S565S3960-PT-none.tgz
mv IBMB2S565S3960-PT-none execution
cd execution
if [ "ReachabilityCardinality" = "ReachabilityDeadlock" ] || [ "ReachabilityCardinality" = "UpperBounds" ] || [ "ReachabilityCardinality" = "QuasiLiveness" ] || [ "ReachabilityCardinality" = "StableMarking" ] || [ "ReachabilityCardinality" = "Liveness" ] || [ "ReachabilityCardinality" = "OneSafe" ] || [ "ReachabilityCardinality" = "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 [ "ReachabilityCardinality" = "UpperBounds" ] ; then
echo "The expected result is a vector of positive values"
echo NUM_VECTOR
elif [ "ReachabilityCardinality" != "StateSpace" ] ; then
echo "The expected result is a vector of booleans"
echo BOOL_VECTOR
else
echo "no data necessary for post analysis"
fi
echo
if [ -f "ReachabilityCardinality.txt" ] ; then
echo "here is the order used to build the result vector(from text file)"
for x in $(grep Property ReachabilityCardinality.txt | cut -d ' ' -f 2 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ -f "ReachabilityCardinality.xml" ] ; then # for cunf (txt files deleted;-)
echo echo "here is the order used to build the result vector(from xml file)"
for x in $(grep '' ReachabilityCardinality.xml | cut -d '>' -f 2 | cut -d '<' -f 1 | sort -u) ; do
echo "FORMULA_NAME $x"
done
elif [ "ReachabilityCardinality" = "ReachabilityDeadlock" ] || [ "ReachabilityCardinality" = "QuasiLiveness" ] || [ "ReachabilityCardinality" = "StableMarking" ] || [ "ReachabilityCardinality" = "Liveness" ] || [ "ReachabilityCardinality" = "OneSafe" ] ; then
echo "FORMULA_NAME ReachabilityCardinality"
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 ;