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-9.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-9.pddl Parsing... Parsing: [0.070s CPU, 0.073s wall-clock] Normalizing task... [0.010s CPU, 0.003s wall-clock] Instantiating... Generating Datalog program... [0.020s CPU, 0.019s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.020s CPU, 0.027s wall-clock] Preparing model... [0.060s CPU, 0.053s wall-clock] Generated 46 rules. Computing model... [1.800s CPU, 1.808s wall-clock] 15867 relevant atoms 7332 auxiliary atoms 23199 final queue length 50043 total queue pushes Completing instantiation... [5.260s CPU, 5.251s wall-clock] Instantiating: [7.180s CPU, 7.182s wall-clock] Computing fact groups... Finding invariants... 12 initial candidates Finding invariants: [0.090s CPU, 0.087s wall-clock] Checking invariant weight... [0.000s CPU, 0.004s wall-clock] Instantiating groups... [0.070s CPU, 0.075s wall-clock] Collecting mutex groups... [0.010s CPU, 0.008s wall-clock] Choosing groups... 0 uncovered facts Choosing groups: [0.020s CPU, 0.021s wall-clock] Building translation key... [0.010s CPU, 0.013s wall-clock] Computing fact groups: [0.260s CPU, 0.264s wall-clock] Building STRIPS to SAS dictionary... [0.010s CPU, 0.006s wall-clock] Building dictionary for full mutex groups... [0.010s CPU, 0.005s wall-clock] Building mutex information... Building mutex information: [0.000s CPU, 0.007s wall-clock] Translating task... Processing axioms... Simplifying axioms... [0.000s CPU, 0.000s wall-clock] Processing axioms: [0.240s CPU, 0.232s wall-clock] Translating task: [4.350s CPU, 4.349s wall-clock] 0 effect conditions simplified 0 implied preconditions added Detecting unreachable propositions... 0 operators removed 0 axioms removed 46 propositions removed Detecting unreachable propositions: [2.270s CPU, 2.258s wall-clock] Reordering and filtering variables... 46 of 46 variables necessary. 0 of 46 mutex groups necessary. 14124 of 14124 operators necessary. 0 of 0 axiom rules necessary. Reordering and filtering variables: [0.660s CPU, 0.660s wall-clock] Translator variables: 46 Translator derived variables: 0 Translator facts: 1150 Translator goal facts: 36 Translator mutex groups: 0 Translator total mutex groups size: 0 Translator operators: 14124 Translator axioms: 0 Translator task size: 85076 Translator peak memory: 91596 KB Writing output... [1.430s CPU, 1.533s wall-clock] Done! [16.440s CPU, 16.532s wall-clock] planner.py version 0.0.1 Time: 3.54s Memory: 284MB Iteration 1 Queue: [(0,50,0,True)] Grounded Until: 0 Expected Memory: 284MB 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]), ('check', [50])] Grounding Time: 13.18s Memory: 1266MB (+982MB) Unblocking actions... Solving... [start: stats after solve call] Models : 1+ Calls : 1 Time : 28.102s (Solving: 1.34s 1st Model: 1.27s Unsat: 0.00s) CPU Time : 27.504s Choices : 52380 (Domain: 52344) Conflicts : 683 (Analyzed: 683) Restarts : 5 (Average: 136.60 Last: 44) Model-Level : 4762.0 Problems : 1 (Average Length: 52.00 Splits: 0) Lemmas : 683 (Deleted: 0) Binary : 82 (Ratio: 12.01%) Ternary : 18 (Ratio: 2.64%) Conflict : 683 (Average Length: 285.1 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 683 (Average: 57.75 Max: 2998 Sum: 39443) Executed : 683 (Average: 57.75 Max: 2998 Sum: 39443 Ratio: 100.00%) Bounded : 0 (Average: 0.00 Max: 0 Sum: 0 Ratio: 0.00%) Rules : 0 Atoms : 0 Bodies : 0 Tight : Yes Variables : 808347 (Eliminated: 0 Frozen: 55662) Constraints : 5913344 (Binary: 98.1% Ternary: 0.9% Other: 1.0%) Memory Peak : 1703MB Max. Length : 0 steps Models : 1 [endof: stats after solve call] Solving Time: 1.97s Memory: 1639MB (+373MB) SAT Testing... NOT SERIALIZABLE Testing Time: 8.63s Memory: 1651MB (+12MB) Solving... [start: stats after solve call] Models : 0+ Calls : 2 Time : 338.677s (Solving: 305.21s 1st Model: 1.27s Unsat: 0.00s) CPU Time : 338.212s Choices : 6949531 (Domain: 6949495) Conflicts : 29283 (Analyzed: 29283) Restarts : 105 (Average: 278.89 Last: 158) Model-Level : 4762.0 Problems : 2 (Average Length: 52.00 Splits: 0) Lemmas : 29283 (Deleted: 23630) Binary : 740 (Ratio: 2.53%) Ternary : 448 (Ratio: 1.53%) Conflict : 29283 (Average Length: 438.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 29283 (Average: 235.31 Max: 9291 Sum: 6890543) Executed : 29142 (Average: 235.06 Max: 9291 Sum: 6883211 Ratio: 99.89%) Bounded : 141 (Average: 52.00 Max: 52 Sum: 7332 Ratio: 0.11%) Rules : 1956029 (Original: 1955927) Atoms : 32724 Bodies : 1291785 (Original: 1291683) Count : 2231 (Original: 2239) Equivalences : 653333 (Atom=Atom: 64 Body=Body: 0 Other: 653269) Tight : Yes Variables : 812971 (Eliminated: 0 Frozen: 708777) Constraints : 7843823 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1765MB Max. Length : 0 steps Models : 1 [endof: stats after solve call] Solving Time: 305.30s Memory: 1685MB (+34MB) UNKNOWN Iteration Time: 337.90s Iteration 2 Queue: [(0,50,1,True)] Grounded Until: 50 Solving... [start: stats after solve call] Models : 0+ Calls : 3 Time : 650.546s (Solving: 616.86s 1st Model: 1.27s Unsat: 0.00s) CPU Time : 650.216s Choices : 14207917 (Domain: 14207881) Conflicts : 58304 (Analyzed: 58304) Restarts : 205 (Average: 284.41 Last: 158) Model-Level : 4762.0 Problems : 3 (Average Length: 52.00 Splits: 0) Lemmas : 58304 (Deleted: 49237) Binary : 1197 (Ratio: 2.05%) Ternary : 739 (Ratio: 1.27%) Conflict : 58304 (Average Length: 533.4 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 58304 (Average: 242.01 Max: 9291 Sum: 14109968) Executed : 58102 (Average: 241.83 Max: 9291 Sum: 14099464 Ratio: 99.93%) Bounded : 202 (Average: 52.00 Max: 52 Sum: 10504 Ratio: 0.07%) Rules : 1956029 (Original: 1955927) Atoms : 32724 Bodies : 1291785 (Original: 1291683) Count : 2231 (Original: 2239) Equivalences : 653333 (Atom=Atom: 64 Body=Body: 0 Other: 653269) Tight : Yes Variables : 812971 (Eliminated: 0 Frozen: 708777) Constraints : 7795058 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1765MB Max. Length : 50 steps Models : 1 [endof: stats after solve call] Solving Time: 312.01s Memory: 1700MB (+15MB) UNKNOWN Iteration Time: 312.01s Iteration 3 Queue: [(0,50,2,True)] Grounded Until: 50 Solving... *** Info : (planner): INTERRUPTED by signal! UNKNOWN INTERRUPTED : 1 Models : 0+ Calls : 4 Time : 952.825s (Solving: 918.93s 1st Model: 1.27s Unsat: 0.00s) CPU Time : 952.596s Choices : 22159503 (Domain: 22159467) Conflicts : 86154 (Analyzed: 86154) Restarts : 305 (Average: 282.47 Last: 158) Model-Level : 4762.0 Problems : 4 (Average Length: 52.00 Splits: 0) Lemmas : 86154 (Deleted: 76019) Binary : 1461 (Ratio: 1.70%) Ternary : 1024 (Ratio: 1.19%) Conflict : 86154 (Average Length: 537.3 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 86154 (Average: 255.56 Max: 9291 Sum: 22017506) Executed : 85895 (Average: 255.40 Max: 9291 Sum: 22004038 Ratio: 99.94%) Bounded : 259 (Average: 52.00 Max: 52 Sum: 13468 Ratio: 0.06%) Rules : 1956029 (Original: 1955927) Atoms : 32724 Bodies : 1291785 (Original: 1291683) Count : 2231 (Original: 2239) Equivalences : 653333 (Atom=Atom: 64 Body=Body: 0 Other: 653269) Tight : Yes Variables : 812971 (Eliminated: 0 Frozen: 708777) Constraints : 7749317 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1765MB Max. Length : 50 steps Models : 1