INFO     Running translator.
INFO     translator input: ['/home/pluehne/Documents/ASP/pddl-instances/ipc-2011/domains/elevator-sequential-satisficing/domain.pddl', '/home/pluehne/Documents/ASP/pddl-instances/ipc-2011/domains/elevator-sequential-satisficing/instances/instance-12.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-2011/domains/elevator-sequential-satisficing/domain.pddl /home/pluehne/Documents/ASP/pddl-instances/ipc-2011/domains/elevator-sequential-satisficing/instances/instance-12.pddl
Parsing...
Parsing: [0.070s CPU, 0.063s wall-clock]
Normalizing task... [0.000s CPU, 0.003s wall-clock]
Instantiating...
Generating Datalog program... [0.010s CPU, 0.018s wall-clock]
Normalizing Datalog program...
Normalizing Datalog program: [0.020s CPU, 0.024s wall-clock]
Preparing model... [0.060s CPU, 0.052s wall-clock]
Generated 46 rules.
Computing model... [3.290s CPU, 3.299s wall-clock]
30860 relevant atoms
11763 auxiliary atoms
42623 final queue length
98023 total queue pushes
Completing instantiation... [10.910s CPU, 10.898s wall-clock]
Instantiating: [14.330s CPU, 14.330s wall-clock]
Computing fact groups...
Finding invariants...
12 initial candidates
Finding invariants: [0.160s CPU, 0.156s wall-clock]
Checking invariant weight... [0.000s CPU, 0.005s wall-clock]
Instantiating groups... [0.110s CPU, 0.103s wall-clock]
Collecting mutex groups... [0.010s CPU, 0.010s wall-clock]
Choosing groups...
0 uncovered facts
Choosing groups: [0.030s CPU, 0.028s wall-clock]
Building translation key... [0.020s CPU, 0.018s wall-clock]
Computing fact groups: [0.400s CPU, 0.394s wall-clock]
Building STRIPS to SAS dictionary... [0.000s CPU, 0.008s wall-clock]
Building dictionary for full mutex groups... [0.020s CPU, 0.010s wall-clock]
Building mutex information...
Building mutex information: [0.000s CPU, 0.009s wall-clock]
Translating task...
Processing axioms...
Simplifying axioms... [0.000s CPU, 0.000s wall-clock]
Processing axioms: [0.480s CPU, 0.471s wall-clock]
Translating task: [8.830s CPU, 8.821s wall-clock]
0 effect conditions simplified
0 implied preconditions added
Detecting unreachable propositions...
0 operators removed
0 axioms removed
59 propositions removed
Detecting unreachable propositions: [5.070s CPU, 5.069s wall-clock]
Reordering and filtering variables...
59 of 59 variables necessary.
0 of 59 mutex groups necessary.
28664 of 28664 operators necessary.
0 of 0 axiom rules necessary.
Reordering and filtering variables: [1.340s CPU, 1.342s wall-clock]
Translator variables: 59
Translator derived variables: 0
Translator facts: 1531
Translator goal facts: 43
Translator mutex groups: 0
Translator total mutex groups size: 0
Translator operators: 28664
Translator axioms: 0
Translator task size: 172249
Translator peak memory: 148824 KB
Writing output... [2.930s CPU, 3.137s wall-clock]
Done! [33.370s CPU, 33.569s wall-clock]
planner.py version 0.0.1

Time:	 7.22s
Memory: 527MB

Iteration 1
Queue:		 [(0,0,0,True), (1,5,0,True), (2,10,0,True), (3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 0
Solving...
[start: stats after solve call]

Models       : 0
Calls        : 1
Time         : 8.289s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 7.256s

Choices      : 0       
Conflicts    : 0        (Analyzed: 0)
Restarts     : 0       
Problems     : 1        (Average Length: 2.00 Splits: 0)
Lemmas       : 0        (Deleted: 0)
  Binary     : 0        (Ratio:   0.00%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 0        (Average:  0.00 Max:   0 Sum:      0)
  Executed   : 0        (Average:  0.00 Max:   0 Sum:      0 Ratio:   0.00%)
  Bounded    : 0        (Average:  0.00 Max:   0 Sum:      0 Ratio: 100.00%)

Rules        : 519224  
Atoms        : 519224  
Bodies       : 1        (Original: 0)
Tight        : Yes
Variables    : 0        (Eliminated:    0 Frozen:    0)
Constraints  : 0        (Binary:   0.0% Ternary:   0.0% Other:   0.0%)

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

[endof: stats after solve call]
Solving Time:	 0.04s
Memory:		 600MB (+73MB)
UNSAT
Iteration Time:	 0.04s

Iteration 2
Queue:		 [(1,5,0,True), (2,10,0,True), (3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 0
Expected Memory: 600MB
Grounding...	 [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])]
Grounding Time:	 2.44s
Memory:		 600MB (+0MB)
Unblocking actions...
Solving...
[start: stats after solve call]

Models       : 0
Calls        : 2
Time         : 26.019s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s)
CPU Time     : 24.992s

Choices      : 0       
Conflicts    : 0        (Analyzed: 0)
Restarts     : 0       
Problems     : 2        (Average Length: 4.50 Splits: 0)
Lemmas       : 0        (Deleted: 0)
  Binary     : 0        (Ratio:   0.00%)
  Ternary    : 0        (Ratio:   0.00%)
  Conflict   : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 0        (Average:  0.00 Max:   0 Sum:      0)
  Executed   : 0        (Average:  0.00 Max:   0 Sum:      0 Ratio:   0.00%)
  Bounded    : 0        (Average:  0.00 Max:   0 Sum:      0 Ratio: 100.00%)

Rules        : 519224  
Atoms        : 519224  
Bodies       : 1        (Original: 0)
Tight        : Yes
Variables    : 128797   (Eliminated:    0 Frozen: 128797)
Constraints  : 294420   (Binary:  98.0% Ternary:   0.9% Other:   1.1%)

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

[endof: stats after solve call]
Solving Time:	 13.62s
Memory:		 636MB (+36MB)
UNSAT
Iteration Time:	 17.74s

Iteration 3
Queue:		 [(2,10,0,True), (3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 5
Expected Memory: 672.0MB
Grounding...	 [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])]
Grounding Time:	 2.70s
Memory:		 636MB (+0MB)
Unblocking actions...
Solving...
[start: stats after solve call]

Models       : 0
Calls        : 3
Time         : 67.119s (Solving: 13.76s 1st Model: 0.00s Unsat: 13.76s)
CPU Time     : 66.112s

Choices      : 348022   (Domain: 348022)
Conflicts    : 30382    (Analyzed: 30381)
Restarts     : 87       (Average: 349.21 Last: 750)
Problems     : 3        (Average Length: 7.00 Splits: 0)
Lemmas       : 30381    (Deleted: 12790)
  Binary     : 601      (Ratio:   1.98%)
  Ternary    : 310      (Ratio:   1.02%)
  Conflict   : 30381    (Average Length:  361.9 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 30381    (Average: 10.72 Max: 863 Sum: 325685)
  Executed   : 30354    (Average: 10.72 Max: 863 Sum: 325658 Ratio:  99.99%)
  Bounded    : 27       (Average:  1.00 Max:   1 Sum:     27 Ratio:   0.01%)

Rules        : 519224  
Atoms        : 519224  
Bodies       : 1        (Original: 0)
Tight        : Yes
Variables    : 284281   (Eliminated:    0 Frozen: 284281)
Constraints  : 1556069  (Binary:  98.6% Ternary:   0.6% Other:   0.7%)

Memory Peak  : 731MB
Max. Length  : 5 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 36.98s
Memory:		 731MB (+95MB)
UNSAT
Iteration Time:	 41.12s

Iteration 4
Queue:		 [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 10
Expected Memory: 826.0MB
Grounding...	 [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])]
Grounding Time:	 2.46s
Memory:		 757MB (+26MB)
Unblocking actions...
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 4
Time         : 121.539s (Solving: 40.09s 1st Model: 0.00s Unsat: 13.76s)
CPU Time     : 120.556s

Choices      : 1527014  (Domain: 1527014)
Conflicts    : 60045    (Analyzed: 60044)
Restarts     : 187      (Average: 321.09 Last: 750)
Problems     : 4        (Average Length: 9.50 Splits: 0)
Lemmas       : 60044    (Deleted: 37958)
  Binary     : 955      (Ratio:   1.59%)
  Ternary    : 572      (Ratio:   0.95%)
  Conflict   : 60044    (Average Length:  507.0 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 60044    (Average: 23.31 Max: 2406 Sum: 1399399)
  Executed   : 60005    (Average: 23.31 Max: 2406 Sum: 1399360 Ratio: 100.00%)
  Bounded    : 39       (Average:  1.00 Max:   1 Sum:     39 Ratio:   0.00%)

Rules        : 519224  
Atoms        : 519224  
Bodies       : 1        (Original: 0)
Tight        : Yes
Variables    : 443217   (Eliminated:    0 Frozen: 443217)
Constraints  : 2852919  (Binary:  98.7% Ternary:   0.6% Other:   0.7%)

Memory Peak  : 1024MB
Max. Length  : 10 steps
Models       : 0

[endof: stats after solve call]
Solving Time:	 50.60s
Memory:		 960MB (+203MB)
UNKNOWN
Iteration Time:	 54.46s

Iteration 5
Queue:		 [(4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 15
Expected Memory: 1189.0MB
Grounding...	 [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])]
Grounding Time:	 2.53s
Memory:		 983MB (+23MB)
Unblocking actions...
Solving...
[start: stats after solve call]

Models       : 1+
Calls        : 5
Time         : 190.478s (Solving: 80.74s 1st Model: 40.59s Unsat: 13.76s)
CPU Time     : 189.524s

Choices      : 4387938  (Domain: 4387737)
Conflicts    : 83812    (Analyzed: 83811)
Restarts     : 249      (Average: 336.59 Last: 750)
Model-Level  : 5526.0  
Problems     : 5        (Average Length: 12.00 Splits: 0)
Lemmas       : 83811    (Deleted: 65639)
  Binary     : 1194     (Ratio:   1.42%)
  Ternary    : 704      (Ratio:   0.84%)
  Conflict   : 83811    (Average Length:  550.5 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 83811    (Average: 48.49 Max: 6806 Sum: 4063913)
  Executed   : 83733    (Average: 48.49 Max: 6806 Sum: 4063793 Ratio: 100.00%)
  Bounded    : 78       (Average:  1.54 Max:  22 Sum:    120 Ratio:   0.00%)

Rules        : 519224  
Atoms        : 519224  
Bodies       : 1        (Original: 0)
Tight        : Yes
Variables    : 602153   (Eliminated:    0 Frozen: 602153)
Constraints  : 4149769  (Binary:  98.7% Ternary:   0.6% Other:   0.7%)

Memory Peak  : 1093MB
Max. Length  : 15 steps
Models       : 1

[endof: stats after solve call]
Solving Time:	 64.97s
Memory:		 1086MB (+103MB)
SAT
Testing...
NOT SERIALIZABLE
Testing Time:	 10.91s
Memory:		 1356MB (+270MB)
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 6
Time         : 380.531s (Solving: 261.49s 1st Model: 40.59s Unsat: 13.76s)
CPU Time     : 379.660s

Choices      : 6991839  (Domain: 6991638)
Conflicts    : 137374   (Analyzed: 137373)
Restarts     : 349      (Average: 393.62 Last: 750)
Model-Level  : 5526.0  
Problems     : 6        (Average Length: 13.67 Splits: 0)
Lemmas       : 137373   (Deleted: 112638)
  Binary     : 1586     (Ratio:   1.15%)
  Ternary    : 970      (Ratio:   0.71%)
  Conflict   : 137373   (Average Length: 1440.9 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 137373   (Average: 48.27 Max: 6806 Sum: 6630571)
  Executed   : 137245   (Average: 48.26 Max: 6806 Sum: 6629351 Ratio:  99.98%)
  Bounded    : 128      (Average:  9.53 Max:  22 Sum:   1220 Ratio:   0.02%)

Rules        : 1937791  (Original: 1937710)
Atoms        : 578640  
Bodies       : 911402   (Original: 911320)
  Count      : 1104     (Original: 1116)
Equivalences : 460060   (Atom=Atom: 31 Body=Body: 0 Other: 460029)
Tight        : Yes
Variables    : 604526   (Eliminated:   93 Frozen: 604433)
Constraints  : 5512450  (Binary:  99.0% Ternary:   0.5% Other:   0.5%)

Memory Peak  : 1549MB
Max. Length  : 15 steps
Models       : 1

[endof: stats after solve call]
Solving Time:	 185.32s
Memory:		 1485MB (+129MB)
UNKNOWN
Iteration Time:	 265.20s

Iteration 6
Queue:		 [(5,25,0,True), (6,30,0,True)]
Grounded Until:	 20
Expected Memory: 1714.0MB
Grounding...	 [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])]
Grounding Time:	 5.23s
Memory:		 1516MB (+31MB)
Unblocking actions...
Solving...
[start: stats after solve call]

Models       : 0+
Calls        : 7
Time         : 740.209s (Solving: 576.29s 1st Model: 40.59s Unsat: 13.76s)
CPU Time     : 739.488s

Choices      : 20758277 (Domain: 20758076)
Conflicts    : 198594   (Analyzed: 198593)
Restarts     : 449      (Average: 442.30 Last: 750)
Model-Level  : 5526.0  
Problems     : 7        (Average Length: 15.57 Splits: 0)
Lemmas       : 198593   (Deleted: 168240)
  Binary     : 2271     (Ratio:   1.14%)
  Ternary    : 1476     (Ratio:   0.74%)
  Conflict   : 198593   (Average Length: 1320.5 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 198593   (Average: 101.62 Max: 7862 Sum: 20181146)
  Executed   : 198233   (Average: 101.58 Max: 7862 Sum: 20173662 Ratio:  99.96%)
  Bounded    : 360      (Average: 20.79 Max:  27 Sum:   7484 Ratio:   0.04%)

Rules        : 1937791  (Original: 1937710)
Atoms        : 578640  
Bodies       : 911402   (Original: 911320)
  Count      : 1104     (Original: 1116)
Equivalences : 460060   (Atom=Atom: 31 Body=Body: 0 Other: 460029)
Tight        : Yes
Variables    : 764052   (Eliminated:   93 Frozen: 763959)
Constraints  : 7170560  (Binary:  99.0% Ternary:   0.5% Other:   0.5%)

Memory Peak  : 1727MB
Max. Length  : 20 steps
Models       : 1

[endof: stats after solve call]
Solving Time:	 352.69s
Memory:		 1727MB (+211MB)
UNKNOWN
Iteration Time:	 359.85s

Iteration 7
Queue:		 [(6,30,0,True)]
Grounded Until:	 25
Expected Memory: 1969.0MB
Grounding...	 [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])]
Grounding Time:	 4.46s
Memory:		 1727MB (+0MB)
Unblocking actions...
Solving...
*** Info : (planner): INTERRUPTED by signal!
UNKNOWN

INTERRUPTED  : 1

Models       : 0+
Calls        : 8
Time         : 858.288s (Solving: 650.30s 1st Model: 40.59s Unsat: 13.76s)
CPU Time     : 857.592s

Choices      : 23916124 (Domain: 23915923)
Conflicts    : 210236   (Analyzed: 210235)
Restarts     : 484      (Average: 434.37 Last: 750)
Model-Level  : 5526.0  
Problems     : 8        (Average Length: 17.62 Splits: 0)
Lemmas       : 210235   (Deleted: 184383)
  Binary     : 2454     (Ratio:   1.17%)
  Ternary    : 1611     (Ratio:   0.77%)
  Conflict   : 210235   (Average Length: 1294.6 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 210235   (Average: 110.35 Max: 7862 Sum: 23199685)
  Executed   : 209843   (Average: 110.31 Max: 7862 Sum: 23191177 Ratio:  99.96%)
  Bounded    : 392      (Average: 21.70 Max:  32 Sum:   8508 Ratio:   0.04%)

Rules        : 1937791  (Original: 1937710)
Atoms        : 578640  
Bodies       : 911402   (Original: 911320)
  Count      : 1104     (Original: 1116)
Equivalences : 460060   (Atom=Atom: 31 Body=Body: 0 Other: 460029)
Tight        : Yes
Variables    : 923578   (Eliminated:   93 Frozen: 923485)
Constraints  : 8783140  (Binary:  99.0% Ternary:   0.5% Other:   0.5%)

Memory Peak  : 1847MB
Max. Length  : 25 steps
Models       : 1


