INFO     Running translator.
INFO     translator input: ['/home/pluehne/Documents/ASP/pddl-instances/ipc-2000/domains/blocks-strips-typed/domain.pddl', '/home/pluehne/Documents/ASP/pddl-instances/ipc-2000/domains/blocks-strips-typed/instances/instance-96.pddl']
INFO     translator arguments: []
INFO     translator time limit: None
INFO     translator memory limit: None
INFO     callstring: /home/pluehne/.usr/bin/python /home/wv/bin/linux/64/fast-downward-10997/builds/release64/bin/translate/translate.py /home/pluehne/Documents/ASP/pddl-instances/ipc-2000/domains/blocks-strips-typed/domain.pddl /home/pluehne/Documents/ASP/pddl-instances/ipc-2000/domains/blocks-strips-typed/instances/instance-96.pddl
Parsing...
Parsing: [0.020s CPU, 0.021s wall-clock]
Normalizing task... [0.000s CPU, 0.002s wall-clock]
Instantiating...
Generating Datalog program... [0.010s CPU, 0.008s wall-clock]
Normalizing Datalog program...
Normalizing Datalog program: [0.010s CPU, 0.011s wall-clock]
Preparing model... [0.020s CPU, 0.017s wall-clock]
Generated 21 rules.
Computing model... [0.820s CPU, 0.818s wall-clock]
7005 relevant atoms
4653 auxiliary atoms
11658 final queue length
20596 total queue pushes
Completing instantiation... [1.480s CPU, 1.474s wall-clock]
Instantiating: [2.340s CPU, 2.338s wall-clock]
Computing fact groups...
Finding invariants...
10 initial candidates
Finding invariants: [0.030s CPU, 0.029s wall-clock]
Checking invariant weight... [0.010s CPU, 0.007s wall-clock]
Instantiating groups... [0.140s CPU, 0.147s wall-clock]
Collecting mutex groups... [0.020s CPU, 0.018s wall-clock]
Choosing groups...
48 uncovered facts
Choosing groups: [0.080s CPU, 0.090s wall-clock]
Building translation key... [0.030s CPU, 0.028s wall-clock]
Computing fact groups: [0.550s CPU, 0.548s wall-clock]
Building STRIPS to SAS dictionary... [0.010s CPU, 0.017s wall-clock]
Building dictionary for full mutex groups... [0.040s CPU, 0.032s wall-clock]
Building mutex information...
Building mutex information: [0.050s CPU, 0.049s wall-clock]
Translating task...
Processing axioms...
Simplifying axioms... [0.000s CPU, 0.000s wall-clock]
Processing axioms: [0.070s CPU, 0.074s wall-clock]
Translating task: [1.730s CPU, 1.738s wall-clock]
6580 effect conditions simplified
0 implied preconditions added
Detecting unreachable propositions...
0 operators removed
0 axioms removed
94 propositions removed
Detecting unreachable propositions: [1.040s CPU, 1.035s wall-clock]
Reordering and filtering variables...
95 of 95 variables necessary.
48 of 95 mutex groups necessary.
4418 of 4418 operators necessary.
0 of 0 axiom rules necessary.
Reordering and filtering variables: [0.550s CPU, 0.550s wall-clock]
Translator variables: 95
Translator derived variables: 0
Translator facts: 2352
Translator goal facts: 46
Translator mutex groups: 48
Translator total mutex groups size: 2304
Translator operators: 4418
Translator axioms: 0
Translator task size: 37791
Translator peak memory: 59416 KB
Writing output... [0.650s CPU, 0.686s wall-clock]
Done! [7.060s CPU, 7.096s wall-clock]
planner.py version 0.0.1

Time:	 1.28s
Memory: 129MB

Iteration 1
Queue:		 [(0,150,0,True)]
Grounded Until:	 0
Expected Memory: 129MB
Grounding...	 [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('step', [31]), ('step', [32]), ('step', [33]), ('step', [34]), ('step', [35]), ('step', [36]), ('step', [37]), ('step', [38]), ('step', [39]), ('step', [40]), ('step', [41]), ('step', [42]), ('step', [43]), ('step', [44]), ('step', [45]), ('step', [46]), ('step', [47]), ('step', [48]), ('step', [49]), ('step', [50]), ('step', [51]), ('step', [52]), ('step', [53]), ('step', [54]), ('step', [55]), ('step', [56]), ('step', [57]), ('step', [58]), ('step', [59]), ('step', [60]), ('step', [61]), ('step', [62]), ('step', [63]), ('step', [64]), ('step', [65]), ('step', [66]), ('step', [67]), ('step', [68]), ('step', [69]), ('step', [70]), ('step', [71]), ('step', [72]), ('step', [73]), ('step', [74]), ('step', [75]), ('step', [76]), ('step', [77]), ('step', [78]), ('step', [79]), ('step', [80]), ('step', [81]), ('step', [82]), ('step', [83]), ('step', [84]), ('step', [85]), ('step', [86]), ('step', [87]), ('step', [88]), ('step', [89]), ('step', [90]), ('step', [91]), ('step', [92]), ('step', [93]), ('step', [94]), ('step', [95]), ('step', [96]), ('step', [97]), ('step', [98]), ('step', [99]), ('step', [100]), ('step', [101]), ('step', [102]), ('step', [103]), ('step', [104]), ('step', [105]), ('step', [106]), ('step', [107]), ('step', [108]), ('step', [109]), ('step', [110]), ('step', [111]), ('step', [112]), ('step', [113]), ('step', [114]), ('step', [115]), ('step', [116]), ('step', [117]), ('step', [118]), ('step', [119]), ('step', [120]), ('step', [121]), ('step', [122]), ('step', [123]), ('step', [124]), ('step', [125]), ('step', [126]), ('step', [127]), ('step', [128]), ('step', [129]), ('step', [130]), ('step', [131]), ('step', [132]), ('step', [133]), ('step', [134]), ('step', [135]), ('step', [136]), ('step', [137]), ('step', [138]), ('step', [139]), ('step', [140]), ('step', [141]), ('step', [142]), ('step', [143]), ('step', [144]), ('step', [145]), ('step', [146]), ('step', [147]), ('step', [148]), ('step', [149]), ('step', [150]), ('check', [150])]
Grounding Time:	 22.40s
Memory:		 1953MB (+1824MB)
Unblocking actions...
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 1
Time         : 183.505s (Solving: 148.23s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 183.416s

Choices      : 703963   (Domain: 703963)
Conflicts    : 26715    (Analyzed: 26715)
Restarts     : 100      (Average: 267.15 Last: 136)
Problems     : 1        (Average Length: 152.00 Splits: 0)
Lemmas       : 26715    (Deleted: 21700)
  Binary     : 6        (Ratio:   0.02%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 26715    (Average Length: 15738.1 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 26715    (Average: 24.52 Max: 7807 Sum: 654922)
  Executed   : 26710    (Average: 24.49 Max: 7807 Sum: 654314 Ratio:  99.91%)
  Bounded    : 5        (Average: 121.60 Max: 152 Sum:    608 Ratio:   0.09%)

Rules        : 0       
Atoms        : 0       
Bodies       : 0       
Tight        : Yes
Variables    : 1046877  (Eliminated:    0 Frozen: 343969)
Constraints  : 7936909  (Binary:  95.2% Ternary:   4.2% Other:   0.5%)

Memory Peak  : 2797MB
Max. Length  : 0 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 149.05s
Memory:		 2733MB (+780MB)
UNKNOWN
Iteration Time:	 182.15s

Iteration 2
Queue:		 [(0,150,1,True)]
Grounded Until:	 150
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 2
Time         : 324.186s (Solving: 288.61s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 324.160s

Choices      : 930133   (Domain: 930133)
Conflicts    : 51023    (Analyzed: 51023)
Restarts     : 200      (Average: 255.12 Last: 137)
Problems     : 2        (Average Length: 152.00 Splits: 0)
Lemmas       : 51023    (Deleted: 45566)
  Binary     : 12       (Ratio:   0.02%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 51023    (Average Length: 16972.7 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 51023    (Average: 16.45 Max: 7807 Sum: 839115)
  Executed   : 51014    (Average: 16.43 Max: 7807 Sum: 838503 Ratio:  99.93%)
  Bounded    : 9        (Average: 68.00 Max: 152 Sum:    612 Ratio:   0.07%)

Rules        : 0       
Atoms        : 0       
Bodies       : 0       
Tight        : Yes
Variables    : 1046877  (Eliminated:    0 Frozen: 343969)
Constraints  : 7921807  (Binary:  95.2% Ternary:   4.2% Other:   0.5%)

Memory Peak  : 2945MB
Max. Length  : 150 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 140.74s
Memory:		 2817MB (+84MB)
UNKNOWN
Iteration Time:	 140.75s

Iteration 3
Queue:		 [(0,150,2,True)]
Grounded Until:	 150
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 3
Time         : 474.376s (Solving: 438.53s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 474.412s

Choices      : 1149190  (Domain: 1149190)
Conflicts    : 77105    (Analyzed: 77105)
Restarts     : 300      (Average: 257.02 Last: 155)
Problems     : 3        (Average Length: 152.00 Splits: 0)
Lemmas       : 77105    (Deleted: 72655)
  Binary     : 15       (Ratio:   0.02%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 77105    (Average Length: 17543.1 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 77105    (Average: 13.18 Max: 7807 Sum: 1016092)
  Executed   : 77094    (Average: 13.17 Max: 7807 Sum: 1015478 Ratio:  99.94%)
  Bounded    : 11       (Average: 55.82 Max: 152 Sum:    614 Ratio:   0.06%)

Rules        : 0       
Atoms        : 0       
Bodies       : 0       
Tight        : Yes
Variables    : 1046877  (Eliminated:    0 Frozen: 343969)
Constraints  : 7921807  (Binary:  95.2% Ternary:   4.2% Other:   0.5%)

Memory Peak  : 2945MB
Max. Length  : 150 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 150.26s
Memory:		 2830MB (+13MB)
UNKNOWN
Iteration Time:	 150.26s

Iteration 4
Queue:		 [(0,150,3,True)]
Grounded Until:	 150
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 4
Time         : 613.555s (Solving: 577.40s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 613.648s

Choices      : 1387512  (Domain: 1387512)
Conflicts    : 101894   (Analyzed: 101894)
Restarts     : 400      (Average: 254.74 Last: 157)
Problems     : 4        (Average Length: 152.00 Splits: 0)
Lemmas       : 101894   (Deleted: 98719)
  Binary     : 21       (Ratio:   0.02%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 101894   (Average Length: 17444.6 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 101894   (Average: 11.91 Max: 7807 Sum: 1213288)
  Executed   : 101881   (Average: 11.90 Max: 7807 Sum: 1212672 Ratio:  99.95%)
  Bounded    : 13       (Average: 47.38 Max: 152 Sum:    616 Ratio:   0.05%)

Rules        : 0       
Atoms        : 0       
Bodies       : 0       
Tight        : Yes
Variables    : 1046877  (Eliminated:    0 Frozen: 343969)
Constraints  : 7921807  (Binary:  95.2% Ternary:   4.2% Other:   0.5%)

Memory Peak  : 2945MB
Max. Length  : 150 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 139.24s
Memory:		 2832MB (+2MB)
UNKNOWN
Iteration Time:	 139.24s

Iteration 5
Queue:		 [(0,150,4,True)]
Grounded Until:	 150
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 5
Time         : 749.577s (Solving: 713.15s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 749.728s

Choices      : 1624525  (Domain: 1624525)
Conflicts    : 126822   (Analyzed: 126822)
Restarts     : 500      (Average: 253.64 Last: 213)
Problems     : 5        (Average Length: 152.00 Splits: 0)
Lemmas       : 126822   (Deleted: 123491)
  Binary     : 21       (Ratio:   0.02%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 126822   (Average Length: 17395.0 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 126822   (Average: 11.10 Max: 7807 Sum: 1407671)
  Executed   : 126809   (Average: 11.09 Max: 7807 Sum: 1407055 Ratio:  99.96%)
  Bounded    : 13       (Average: 47.38 Max: 152 Sum:    616 Ratio:   0.04%)

Rules        : 0       
Atoms        : 0       
Bodies       : 0       
Tight        : Yes
Variables    : 1046877  (Eliminated:    0 Frozen: 343969)
Constraints  : 7921807  (Binary:  95.2% Ternary:   4.2% Other:   0.5%)

Memory Peak  : 2945MB
Max. Length  : 150 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 136.08s
Memory:		 2832MB (+0MB)
UNKNOWN
Iteration Time:	 136.08s

Iteration 6
Queue:		 [(0,150,5,True)]
Grounded Until:	 150
Solving...
*** Info : (planner): INTERRUPTED by signal!
UNKNOWN

INTERRUPTED  : 1

Models       : 0+
Calls        : 6
Time         : 905.629s (Solving: 868.93s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 905.832s

Choices      : 1864505  (Domain: 1864505)
Conflicts    : 153808   (Analyzed: 153808)
Restarts     : 600      (Average: 256.35 Last: 253)
Problems     : 6        (Average Length: 152.00 Splits: 0)
Lemmas       : 153808   (Deleted: 148410)
  Binary     : 30       (Ratio:   0.02%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 153808   (Average Length: 17389.4 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 153808   (Average: 10.45 Max: 7807 Sum: 1607156)
  Executed   : 153791   (Average: 10.45 Max: 7807 Sum: 1606536 Ratio:  99.96%)
  Bounded    : 17       (Average: 36.47 Max: 152 Sum:    620 Ratio:   0.04%)

Rules        : 0       
Atoms        : 0       
Bodies       : 0       
Tight        : Yes
Variables    : 1046877  (Eliminated:    0 Frozen: 343969)
Constraints  : 7921807  (Binary:  95.2% Ternary:   4.2% Other:   0.5%)

Memory Peak  : 2945MB
Max. Length  : 150 steps
Models       : 0


