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-14.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-14.pddl Parsing... Parsing: [0.060s CPU, 0.061s wall-clock] Normalizing task... [0.000s CPU, 0.003s wall-clock] Instantiating... Generating Datalog program... [0.020s CPU, 0.018s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.020s CPU, 0.023s wall-clock] Preparing model... [0.050s CPU, 0.053s wall-clock] Generated 46 rules. Computing model... [3.710s CPU, 3.699s wall-clock] 35012 relevant atoms 12873 auxiliary atoms 47885 final queue length 110965 total queue pushes Completing instantiation... [12.470s CPU, 12.477s wall-clock] Instantiating: [16.320s CPU, 16.316s wall-clock] Computing fact groups... Finding invariants... 12 initial candidates Finding invariants: [0.180s CPU, 0.177s wall-clock] Checking invariant weight... [0.000s CPU, 0.005s wall-clock] Instantiating groups... [0.130s CPU, 0.124s wall-clock] Collecting mutex groups... [0.000s CPU, 0.011s wall-clock] Choosing groups... 0 uncovered facts Choosing groups: [0.030s CPU, 0.034s wall-clock] Building translation key... [0.020s CPU, 0.020s wall-clock] Computing fact groups: [0.460s CPU, 0.456s wall-clock] Building STRIPS to SAS dictionary... [0.010s CPU, 0.010s wall-clock] Building dictionary for full mutex groups... [0.000s CPU, 0.008s wall-clock] Building mutex information... Building mutex information: [0.020s CPU, 0.013s wall-clock] Translating task... Processing axioms... Simplifying axioms... [0.000s CPU, 0.000s wall-clock] Processing axioms: [0.530s CPU, 0.533s wall-clock] Translating task: [10.090s CPU, 10.083s wall-clock] 0 effect conditions simplified 0 implied preconditions added Detecting unreachable propositions... 0 operators removed 0 axioms removed 65 propositions removed Detecting unreachable propositions: [5.220s CPU, 5.223s wall-clock] Reordering and filtering variables... 65 of 65 variables necessary. 0 of 65 mutex groups necessary. 32600 of 32600 operators necessary. 0 of 0 axiom rules necessary. Reordering and filtering variables: [1.520s CPU, 1.518s wall-clock] Translator variables: 65 Translator derived variables: 0 Translator facts: 1729 Translator goal facts: 49 Translator mutex groups: 0 Translator total mutex groups size: 0 Translator operators: 32600 Translator axioms: 0 Translator task size: 196075 Translator peak memory: 164576 KB Writing output... [3.290s CPU, 3.530s wall-clock] Done! [37.430s CPU, 37.658s wall-clock] planner.py version 0.0.1 Time: 8.67s Memory: 593MB 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 : 9.933s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 8.724s 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 : 590900 Atoms : 590900 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 : 729MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 0.05s Memory: 665MB (+72MB) UNSAT Iteration Time: 0.05s 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: 665MB Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])] Grounding Time: 3.17s Memory: 665MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 2 Time : 32.524s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 31.324s 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 : 590900 Atoms : 590900 Bodies : 1 (Original: 0) Tight : Yes Variables : 146325 (Eliminated: 0 Frozen: 146325) Constraints : 311013 (Binary: 98.1% Ternary: 0.8% Other: 1.1%) Memory Peak : 729MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 17.41s Memory: 709MB (+44MB) UNSAT Iteration Time: 22.61s 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: 753.0MB Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])] Grounding Time: 3.14s Memory: 719MB (+10MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 3 Time : 85.659s (Solving: 18.31s 1st Model: 0.00s Unsat: 18.31s) CPU Time : 84.484s Choices : 226951 (Domain: 226951) Conflicts : 33937 (Analyzed: 33936) Restarts : 74 (Average: 458.59 Last: 1074) Problems : 3 (Average Length: 7.00 Splits: 0) Lemmas : 33936 (Deleted: 12759) Binary : 613 (Ratio: 1.81%) Ternary : 328 (Ratio: 0.97%) Conflict : 33936 (Average Length: 688.5 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 33936 (Average: 6.45 Max: 847 Sum: 218860) Executed : 33902 (Average: 6.45 Max: 847 Sum: 218793 Ratio: 99.97%) Bounded : 34 (Average: 1.97 Max: 12 Sum: 67 Ratio: 0.03%) Rules : 590900 Atoms : 590900 Bodies : 1 (Original: 0) Tight : Yes Variables : 323091 (Eliminated: 0 Frozen: 323091) Constraints : 1747576 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 946MB Max. Length : 5 steps Models : 0 [endof: stats after solve call] Solving Time: 48.33s Memory: 882MB (+163MB) UNSAT Iteration Time: 53.17s Iteration 4 Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 10 Expected Memory: 1055.0MB Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])] Grounding Time: 2.81s Memory: 906MB (+24MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 4 Time : 146.718s (Solving: 43.24s 1st Model: 0.00s Unsat: 18.31s) CPU Time : 145.572s Choices : 1428769 (Domain: 1428769) Conflicts : 56339 (Analyzed: 56338) Restarts : 174 (Average: 323.78 Last: 1074) Problems : 4 (Average Length: 9.50 Splits: 0) Lemmas : 56338 (Deleted: 40438) Binary : 916 (Ratio: 1.63%) Ternary : 548 (Ratio: 0.97%) Conflict : 56338 (Average Length: 670.7 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 56338 (Average: 22.97 Max: 3515 Sum: 1294223) Executed : 56283 (Average: 22.97 Max: 3515 Sum: 1294135 Ratio: 99.99%) Bounded : 55 (Average: 1.60 Max: 12 Sum: 88 Ratio: 0.01%) Rules : 590900 Atoms : 590900 Bodies : 1 (Original: 0) Tight : Yes Variables : 503717 (Eliminated: 0 Frozen: 503717) Constraints : 3223559 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 1067MB Max. Length : 10 steps Models : 0 [endof: stats after solve call] Solving Time: 56.68s Memory: 1067MB (+161MB) UNKNOWN Iteration Time: 61.10s Iteration 5 Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 15 Expected Memory: 1252.0MB Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])] Grounding Time: 3.64s Memory: 1168MB (+101MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 5 Time : 233.124s (Solving: 92.63s 1st Model: 0.00s Unsat: 18.31s) CPU Time : 232.012s Choices : 5432486 (Domain: 5432486) Conflicts : 82215 (Analyzed: 82214) Restarts : 274 (Average: 300.05 Last: 1074) Problems : 5 (Average Length: 12.00 Splits: 0) Lemmas : 82214 (Deleted: 63346) Binary : 1247 (Ratio: 1.52%) Ternary : 811 (Ratio: 0.99%) Conflict : 82214 (Average Length: 702.3 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 82214 (Average: 60.25 Max: 7927 Sum: 4953404) Executed : 82145 (Average: 60.25 Max: 7927 Sum: 4953281 Ratio: 100.00%) Bounded : 69 (Average: 1.78 Max: 22 Sum: 123 Ratio: 0.00%) Rules : 590900 Atoms : 590900 Bodies : 1 (Original: 0) Tight : Yes Variables : 684343 (Eliminated: 0 Frozen: 684343) Constraints : 4699569 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 1252MB Max. Length : 15 steps Models : 0 [endof: stats after solve call] Solving Time: 81.21s Memory: 1236MB (+68MB) UNKNOWN Iteration Time: 86.45s Iteration 6 Queue: [(5,25,0,True), (6,30,0,True)] Grounded Until: 20 Expected Memory: 1421.0MB Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])] Grounding Time: 2.78s Memory: 1268MB (+32MB) Unblocking actions... Solving... [start: stats after solve call] Models : 1+ Calls : 6 Time : 333.384s (Solving: 156.60s 1st Model: 63.87s Unsat: 18.31s) CPU Time : 332.316s Choices : 10360164 (Domain: 10360013) Conflicts : 108811 (Analyzed: 108810) Restarts : 324 (Average: 335.83 Last: 1074) Model-Level : 9267.0 Problems : 6 (Average Length: 14.50 Splits: 0) Lemmas : 108810 (Deleted: 84643) Binary : 1511 (Ratio: 1.39%) Ternary : 1042 (Ratio: 0.96%) Conflict : 108810 (Average Length: 799.7 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 108810 (Average: 88.17 Max: 9626 Sum: 9594042) Executed : 108732 (Average: 88.17 Max: 9626 Sum: 9593910 Ratio: 100.00%) Bounded : 78 (Average: 1.69 Max: 22 Sum: 132 Ratio: 0.00%) Rules : 590900 Atoms : 590900 Bodies : 1 (Original: 0) Tight : Yes Variables : 864969 (Eliminated: 0 Frozen: 864969) Constraints : 6175570 (Binary: 98.8% Ternary: 0.6% Other: 0.7%) Memory Peak : 1558MB Max. Length : 20 steps Models : 1 [endof: stats after solve call] Solving Time: 95.80s Memory: 1494MB (+226MB) SAT Testing... NOT SERIALIZABLE Testing Time: 13.60s Memory: 1788MB (+294MB) Solving... [start: stats after solve call] Models : 0+ Calls : 7 Time : 575.709s (Solving: 385.26s 1st Model: 63.87s Unsat: 18.31s) CPU Time : 574.744s Choices : 20832893 (Domain: 20832742) Conflicts : 142245 (Analyzed: 142244) Restarts : 424 (Average: 335.48 Last: 1074) Model-Level : 9267.0 Problems : 7 (Average Length: 16.29 Splits: 0) Lemmas : 142244 (Deleted: 111975) Binary : 2094 (Ratio: 1.47%) Ternary : 1460 (Ratio: 1.03%) Conflict : 142244 (Average Length: 850.9 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 142244 (Average: 139.45 Max: 11022 Sum: 19836232) Executed : 142069 (Average: 139.43 Max: 11022 Sum: 19833507 Ratio: 99.99%) Bounded : 175 (Average: 15.57 Max: 27 Sum: 2725 Ratio: 0.01%) Rules : 2683756 (Original: 2683696) Atoms : 659083 Bodies : 1356727 (Original: 1356666) Count : 1537 (Original: 1543) Equivalences : 683887 (Atom=Atom: 37 Body=Body: 0 Other: 683850) Tight : Yes Variables : 868179 (Eliminated: 66 Frozen: 868113) Constraints : 8205088 (Binary: 99.0% Ternary: 0.4% Other: 0.5%) Memory Peak : 1788MB Max. Length : 20 steps Models : 1 [endof: stats after solve call] Solving Time: 235.82s Memory: 1699MB (+-89MB) UNKNOWN Iteration Time: 349.73s Iteration 7 Queue: [(6,30,0,True)] Grounded Until: 25 Expected Memory: 1957.0MB Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])] Grounding Time: 5.06s Memory: 1707MB (+8MB) Unblocking actions... Solving... *** Info : (planner): INTERRUPTED by signal! UNKNOWN INTERRUPTED : 1 Models : 0+ Calls : 8 Time : 1323.909s (Solving: 1077.87s 1st Model: 63.87s Unsat: 18.31s) CPU Time : 1323.232s Choices : 46666982 (Domain: 46666831) Conflicts : 233086 (Analyzed: 233085) Restarts : 524 (Average: 444.82 Last: 1074) Model-Level : 9267.0 Problems : 8 (Average Length: 18.25 Splits: 0) Lemmas : 233085 (Deleted: 204479) Binary : 3017 (Ratio: 1.29%) Ternary : 2205 (Ratio: 0.95%) Conflict : 233085 (Average Length: 880.6 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 233085 (Average: 194.50 Max: 11022 Sum: 45334704) Executed : 232641 (Average: 194.45 Max: 11022 Sum: 45323371 Ratio: 99.98%) Bounded : 444 (Average: 25.52 Max: 32 Sum: 11333 Ratio: 0.02%) Rules : 2683756 (Original: 2683696) Atoms : 659083 Bodies : 1356727 (Original: 1356666) Count : 1537 (Original: 1543) Equivalences : 683887 (Atom=Atom: 37 Body=Body: 0 Other: 683850) Tight : Yes Variables : 1049455 (Eliminated: 66 Frozen: 1049389) Constraints : 10105650 (Binary: 99.0% Ternary: 0.4% Other: 0.5%) Memory Peak : 2030MB Max. Length : 25 steps Models : 1