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-3.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-3.pddl
Parsing...
Parsing: [0.060s CPU, 0.064s wall-clock]
Normalizing task... [0.010s CPU, 0.003s wall-clock]
Instantiating...
Generating Datalog program... [0.010s CPU, 0.016s wall-clock]
Normalizing Datalog program...
Normalizing Datalog program: [0.030s CPU, 0.027s wall-clock]
Preparing model... [0.040s CPU, 0.048s wall-clock]
Generated 46 rules.
Computing model... [1.400s CPU, 1.397s wall-clock]
12114 relevant atoms
6216 auxiliary atoms
18330 final queue length
38523 total queue pushes
Completing instantiation... [3.860s CPU, 3.860s wall-clock]
Instantiating: [5.350s CPU, 5.366s wall-clock]
Computing fact groups...
Finding invariants...
12 initial candidates
Finding invariants: [0.070s CPU, 0.071s wall-clock]
Checking invariant weight... [0.010s CPU, 0.004s wall-clock]
Instantiating groups... [0.050s CPU, 0.049s wall-clock]
Collecting mutex groups... [0.000s CPU, 0.006s wall-clock]
Choosing groups...
0 uncovered facts
Choosing groups: [0.020s CPU, 0.017s wall-clock]
Building translation key... [0.010s CPU, 0.010s wall-clock]
Computing fact groups: [0.200s CPU, 0.199s wall-clock]
Building STRIPS to SAS dictionary... [0.010s CPU, 0.005s wall-clock]
Building dictionary for full mutex groups... [0.000s CPU, 0.004s wall-clock]
Building mutex information...
Building mutex information: [0.010s CPU, 0.005s wall-clock]
Translating task...
Processing axioms...
Simplifying axioms... [0.000s CPU, 0.000s wall-clock]
Processing axioms: [0.170s CPU, 0.173s wall-clock]
Translating task: [3.410s CPU, 3.407s wall-clock]
0 effect conditions simplified
0 implied preconditions added
Detecting unreachable propositions...
0 operators removed
0 axioms removed
37 propositions removed
Detecting unreachable propositions: [1.720s CPU, 1.716s wall-clock]
Reordering and filtering variables...
37 of 37 variables necessary.
0 of 37 mutex groups necessary.
10668 of 10668 operators necessary.
0 of 0 axiom rules necessary.
Reordering and filtering variables: [0.500s CPU, 0.497s wall-clock]
Translator variables: 37
Translator derived variables: 0
Translator facts: 880
Translator goal facts: 27
Translator mutex groups: 0
Translator total mutex groups size: 0
Translator operators: 10668
Translator axioms: 0
Translator task size: 64052
Translator peak memory: 78028 KB
Writing output... [1.080s CPU, 1.155s wall-clock]
Done! [12.490s CPU, 12.565s wall-clock]
planner.py version 0.0.1
Time: 2.65s
Memory: 226MB
Iteration 1
Queue: [(0,45,0,True)]
Grounded Until: 0
Expected Memory: 226MB
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]), ('check', [45])]
Grounding Time: 8.54s
Memory: 876MB (+650MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 1+
Calls : 1
Time : 24.226s (Solving: 6.70s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 23.804s
Choices : 255940 (Domain: 255904)
Conflicts : 3619 (Analyzed: 3619)
Restarts : 18 (Average: 201.06 Last: 136)
Model-Level : 2911.0
Problems : 1 (Average Length: 47.00 Splits: 0)
Lemmas : 3619 (Deleted: 904)
Binary : 152 (Ratio: 4.20%)
Ternary : 73 (Ratio: 2.02%)
Conflict : 3619 (Average Length: 488.4 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 3619 (Average: 66.45 Max: 2085 Sum: 240481)
Executed : 3619 (Average: 66.45 Max: 2085 Sum: 240481 Ratio: 100.00%)
Bounded : 0 (Average: 0.00 Max: 0 Sum: 0 Ratio: 0.00%)
Rules : 0
Atoms : 0
Bodies : 0
Tight : Yes
Variables : 549484 (Eliminated: 0 Frozen: 38101)
Constraints : 3969452 (Binary: 98.1% Ternary: 0.9% Other: 1.0%)
Memory Peak : 1236MB
Max. Length : 0 steps
Models : 1
[endof: stats after solve call]
Solving Time: 7.12s
Memory: 1172MB (+296MB)
SAT
Testing...
NOT SERIALIZABLE
Testing Time: 5.98s
Memory: 1172MB (+0MB)
Solving...
[start: stats after solve call]
Models : 0+
Calls : 2
Time : 189.809s (Solving: 167.94s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 189.460s
Choices : 3848143 (Domain: 3848107)
Conflicts : 26542 (Analyzed: 26542)
Restarts : 118 (Average: 224.93 Last: 147)
Model-Level : 2911.0
Problems : 2 (Average Length: 47.00 Splits: 0)
Lemmas : 26542 (Deleted: 21221)
Binary : 536 (Ratio: 2.02%)
Ternary : 300 (Ratio: 1.13%)
Conflict : 26542 (Average Length: 665.9 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 26542 (Average: 143.39 Max: 5384 Sum: 3805954)
Executed : 26439 (Average: 143.21 Max: 5384 Sum: 3801113 Ratio: 99.87%)
Bounded : 103 (Average: 47.00 Max: 47 Sum: 4841 Ratio: 0.13%)
Rules : 1312670 (Original: 1312578)
Atoms : 24476
Bodies : 866601 (Original: 866509)
Count : 1610 (Original: 1618)
Equivalences : 439984 (Atom=Atom: 58 Body=Body: 0 Other: 439926)
Tight : Yes
Variables : 552838 (Eliminated: 0 Frozen: 477899)
Constraints : 5262878 (Binary: 98.5% Ternary: 0.7% Other: 0.8%)
Memory Peak : 1239MB
Max. Length : 0 steps
Models : 1
[endof: stats after solve call]
Solving Time: 162.18s
Memory: 1185MB (+13MB)
UNKNOWN
Iteration Time: 189.33s
Iteration 2
Queue: [(0,45,1,True)]
Grounded Until: 45
Solving...
[start: stats after solve call]
Models : 0+
Calls : 3
Time : 375.480s (Solving: 353.48s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 375.212s
Choices : 8715613 (Domain: 8715577)
Conflicts : 56646 (Analyzed: 56646)
Restarts : 218 (Average: 259.84 Last: 173)
Model-Level : 2911.0
Problems : 3 (Average Length: 47.00 Splits: 0)
Lemmas : 56646 (Deleted: 48246)
Binary : 942 (Ratio: 1.66%)
Ternary : 502 (Ratio: 0.89%)
Conflict : 56646 (Average Length: 813.6 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 56646 (Average: 152.33 Max: 5384 Sum: 8629080)
Executed : 56502 (Average: 152.21 Max: 5384 Sum: 8622312 Ratio: 99.92%)
Bounded : 144 (Average: 47.00 Max: 47 Sum: 6768 Ratio: 0.08%)
Rules : 1312670 (Original: 1312578)
Atoms : 24476
Bodies : 866601 (Original: 866509)
Count : 1610 (Original: 1618)
Equivalences : 439984 (Atom=Atom: 58 Body=Body: 0 Other: 439926)
Tight : Yes
Variables : 552838 (Eliminated: 0 Frozen: 477899)
Constraints : 5214730 (Binary: 98.5% Ternary: 0.7% Other: 0.8%)
Memory Peak : 1239MB
Max. Length : 45 steps
Models : 1
[endof: stats after solve call]
Solving Time: 185.76s
Memory: 1204MB (+19MB)
UNKNOWN
Iteration Time: 185.76s
Iteration 3
Queue: [(0,45,2,True)]
Grounded Until: 45
Solving...
[start: stats after solve call]
Models : 0+
Calls : 4
Time : 527.335s (Solving: 505.18s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 527.128s
Choices : 13074797 (Domain: 13074761)
Conflicts : 85527 (Analyzed: 85527)
Restarts : 318 (Average: 268.95 Last: 207)
Model-Level : 2911.0
Problems : 4 (Average Length: 47.00 Splits: 0)
Lemmas : 85527 (Deleted: 76681)
Binary : 1068 (Ratio: 1.25%)
Ternary : 631 (Ratio: 0.74%)
Conflict : 85527 (Average Length: 928.2 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 85527 (Average: 151.10 Max: 5384 Sum: 12923001)
Executed : 85363 (Average: 151.01 Max: 5384 Sum: 12915293 Ratio: 99.94%)
Bounded : 164 (Average: 47.00 Max: 47 Sum: 7708 Ratio: 0.06%)
Rules : 1312670 (Original: 1312578)
Atoms : 24476
Bodies : 866601 (Original: 866509)
Count : 1610 (Original: 1618)
Equivalences : 439984 (Atom=Atom: 58 Body=Body: 0 Other: 439926)
Tight : Yes
Variables : 552838 (Eliminated: 0 Frozen: 477899)
Constraints : 5181331 (Binary: 98.5% Ternary: 0.7% Other: 0.8%)
Memory Peak : 1239MB
Max. Length : 45 steps
Models : 1
[endof: stats after solve call]
Solving Time: 151.92s
Memory: 1204MB (+0MB)
UNKNOWN
Iteration Time: 151.92s
Iteration 4
Queue: [(0,45,3,True)]
Grounded Until: 45
Solving...
[start: stats after solve call]
Models : 0+
Calls : 5
Time : 667.594s (Solving: 645.34s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 667.448s
Choices : 16567441 (Domain: 16567405)
Conflicts : 110659 (Analyzed: 110659)
Restarts : 418 (Average: 264.73 Last: 267)
Model-Level : 2911.0
Problems : 5 (Average Length: 47.00 Splits: 0)
Lemmas : 110659 (Deleted: 100889)
Binary : 1205 (Ratio: 1.09%)
Ternary : 805 (Ratio: 0.73%)
Conflict : 110659 (Average Length: 938.9 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 110659 (Average: 148.00 Max: 5384 Sum: 16377205)
Executed : 110440 (Average: 147.90 Max: 5384 Sum: 16366912 Ratio: 99.94%)
Bounded : 219 (Average: 47.00 Max: 47 Sum: 10293 Ratio: 0.06%)
Rules : 1312670 (Original: 1312578)
Atoms : 24476
Bodies : 866601 (Original: 866509)
Count : 1610 (Original: 1618)
Equivalences : 439984 (Atom=Atom: 58 Body=Body: 0 Other: 439926)
Tight : Yes
Variables : 552838 (Eliminated: 0 Frozen: 477899)
Constraints : 5175491 (Binary: 98.5% Ternary: 0.7% Other: 0.8%)
Memory Peak : 1239MB
Max. Length : 45 steps
Models : 1
[endof: stats after solve call]
Solving Time: 140.32s
Memory: 1204MB (+0MB)
UNKNOWN
Iteration Time: 140.32s
Iteration 5
Queue: [(0,45,4,True)]
Grounded Until: 45
Solving...
[start: stats after solve call]
Models : 0+
Calls : 6
Time : 811.465s (Solving: 789.11s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 811.380s
Choices : 20668068 (Domain: 20668032)
Conflicts : 138499 (Analyzed: 138499)
Restarts : 518 (Average: 267.37 Last: 267)
Model-Level : 2911.0
Problems : 6 (Average Length: 47.00 Splits: 0)
Lemmas : 138499 (Deleted: 124352)
Binary : 1352 (Ratio: 0.98%)
Ternary : 967 (Ratio: 0.70%)
Conflict : 138499 (Average Length: 971.7 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 138499 (Average: 147.46 Max: 5384 Sum: 20422967)
Executed : 138268 (Average: 147.38 Max: 5384 Sum: 20412110 Ratio: 99.95%)
Bounded : 231 (Average: 47.00 Max: 47 Sum: 10857 Ratio: 0.05%)
Rules : 1312670 (Original: 1312578)
Atoms : 24476
Bodies : 866601 (Original: 866509)
Count : 1610 (Original: 1618)
Equivalences : 439984 (Atom=Atom: 58 Body=Body: 0 Other: 439926)
Tight : Yes
Variables : 552838 (Eliminated: 0 Frozen: 477899)
Constraints : 5169457 (Binary: 98.5% Ternary: 0.7% Other: 0.8%)
Memory Peak : 1239MB
Max. Length : 45 steps
Models : 1
[endof: stats after solve call]
Solving Time: 143.94s
Memory: 1204MB (+0MB)
UNKNOWN
Iteration Time: 143.94s
Iteration 6
Queue: [(0,45,5,True)]
Grounded Until: 45
Solving...
*** Info : (planner): INTERRUPTED by signal!
UNKNOWN
INTERRUPTED : 1
Models : 0+
Calls : 7
Time : 968.945s (Solving: 946.47s 1st Model: 6.65s Unsat: 0.00s)
CPU Time : 968.916s
Choices : 25066091 (Domain: 25066055)
Conflicts : 166929 (Analyzed: 166929)
Restarts : 618 (Average: 270.11 Last: 360)
Model-Level : 2911.0
Problems : 7 (Average Length: 47.00 Splits: 0)
Lemmas : 166929 (Deleted: 150124)
Binary : 1480 (Ratio: 0.89%)
Ternary : 1103 (Ratio: 0.66%)
Conflict : 166929 (Average Length: 1034.3 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 166929 (Average: 148.35 Max: 5384 Sum: 24763234)
Executed : 166694 (Average: 148.28 Max: 5384 Sum: 24752189 Ratio: 99.96%)
Bounded : 235 (Average: 47.00 Max: 47 Sum: 11045 Ratio: 0.04%)
Rules : 1312670 (Original: 1312578)
Atoms : 24476
Bodies : 866601 (Original: 866509)
Count : 1610 (Original: 1618)
Equivalences : 439984 (Atom=Atom: 58 Body=Body: 0 Other: 439926)
Tight : Yes
Variables : 552838 (Eliminated: 0 Frozen: 477899)
Constraints : 5169001 (Binary: 98.5% Ternary: 0.7% Other: 0.8%)
Memory Peak : 1332MB
Max. Length : 45 steps
Models : 1