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