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-15.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-15.pddl Parsing... Parsing: [0.060s CPU, 0.067s wall-clock] Normalizing task... [0.000s CPU, 0.003s wall-clock] Instantiating... Generating Datalog program... [0.020s CPU, 0.019s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.030s CPU, 0.025s wall-clock] Preparing model... [0.050s CPU, 0.056s wall-clock] Generated 46 rules. Computing model... [3.920s CPU, 3.911s wall-clock] 37088 relevant atoms 13428 auxiliary atoms 50516 final queue length 117436 total queue pushes Completing instantiation... [13.110s CPU, 13.102s wall-clock] Instantiating: [17.170s CPU, 17.159s wall-clock] Computing fact groups... Finding invariants... 12 initial candidates Finding invariants: [0.190s CPU, 0.186s wall-clock] Checking invariant weight... [0.000s CPU, 0.005s wall-clock] Instantiating groups... [0.140s CPU, 0.134s wall-clock] Collecting mutex groups... [0.000s CPU, 0.011s wall-clock] Choosing groups... 0 uncovered facts Choosing groups: [0.040s CPU, 0.033s wall-clock] Building translation key... [0.020s CPU, 0.021s wall-clock] Computing fact groups: [0.480s CPU, 0.480s wall-clock] Building STRIPS to SAS dictionary... [0.010s CPU, 0.010s wall-clock] Building dictionary for full mutex groups... [0.010s CPU, 0.011s wall-clock] Building mutex information... Building mutex information: [0.010s CPU, 0.011s wall-clock] Translating task... Processing axioms... Simplifying axioms... [0.000s CPU, 0.000s wall-clock] Processing axioms: [0.570s CPU, 0.567s wall-clock] Translating task: [10.630s CPU, 10.619s wall-clock] 0 effect conditions simplified 0 implied preconditions added Detecting unreachable propositions... 0 operators removed 0 axioms removed 68 propositions removed Detecting unreachable propositions: [6.110s CPU, 6.113s wall-clock] Reordering and filtering variables... 68 of 68 variables necessary. 0 of 68 mutex groups necessary. 34568 of 34568 operators necessary. 0 of 0 axiom rules necessary. Reordering and filtering variables: [1.630s CPU, 1.625s wall-clock] Translator variables: 68 Translator derived variables: 0 Translator facts: 1828 Translator goal facts: 52 Translator mutex groups: 0 Translator total mutex groups size: 0 Translator operators: 34568 Translator axioms: 0 Translator task size: 207988 Translator peak memory: 172416 KB Writing output... [3.580s CPU, 4.019s wall-clock] Done! [40.140s CPU, 40.575s wall-clock] planner.py version 0.0.1 Time: 9.00s Memory: 626MB 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 : 10.507s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 9.048s 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 : 626738 Atoms : 626738 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 : 762MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 0.05s Memory: 698MB (+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: 698MB Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])] Grounding Time: 2.95s Memory: 698MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 2 Time : 35.870s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 34.424s 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 : 626738 Atoms : 626738 Bodies : 1 (Original: 0) Tight : Yes Variables : 155118 (Eliminated: 0 Frozen: 155118) Constraints : 393871 (Binary: 98.2% Ternary: 0.8% Other: 1.0%) Memory Peak : 762MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 20.24s Memory: 725MB (+27MB) UNSAT Iteration Time: 25.38s 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: 752.0MB Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])] Grounding Time: 3.16s Memory: 741MB (+16MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 3 Time : 87.587s (Solving: 12.64s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 86.164s Choices : 227081 (Domain: 227081) Conflicts : 28091 (Analyzed: 28091) Restarts : 100 (Average: 280.91 Last: 201) Problems : 3 (Average Length: 7.00 Splits: 0) Lemmas : 28091 (Deleted: 16653) Binary : 615 (Ratio: 2.19%) Ternary : 410 (Ratio: 1.46%) Conflict : 28091 (Average Length: 530.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 28091 (Average: 7.64 Max: 870 Sum: 214490) Executed : 28077 (Average: 7.63 Max: 870 Sum: 214465 Ratio: 99.99%) Bounded : 14 (Average: 1.79 Max: 12 Sum: 25 Ratio: 0.01%) Rules : 626738 Atoms : 626738 Bodies : 1 (Original: 0) Tight : Yes Variables : 343293 (Eliminated: 0 Frozen: 343293) Constraints : 1926949 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 854MB Max. Length : 5 steps Models : 0 [endof: stats after solve call] Solving Time: 46.87s Memory: 854MB (+113MB) UNKNOWN Iteration Time: 51.75s Iteration 4 Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 10 Expected Memory: 983.0MB Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])] Grounding Time: 2.99s Memory: 891MB (+37MB) Unblocking actions... Solving... [start: stats after solve call] Models : 1+ Calls : 4 Time : 148.963s (Solving: 34.53s 1st Model: 21.83s Unsat: 0.00s) CPU Time : 147.568s Choices : 919896 (Domain: 919776) Conflicts : 48509 (Analyzed: 48509) Restarts : 169 (Average: 287.04 Last: 358) Model-Level : 1588.0 Problems : 4 (Average Length: 9.50 Splits: 0) Lemmas : 48509 (Deleted: 31005) Binary : 1054 (Ratio: 2.17%) Ternary : 749 (Ratio: 1.54%) Conflict : 48509 (Average Length: 587.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 48509 (Average: 17.78 Max: 2160 Sum: 862280) Executed : 48489 (Average: 17.78 Max: 2160 Sum: 862249 Ratio: 100.00%) Bounded : 20 (Average: 1.55 Max: 12 Sum: 31 Ratio: 0.00%) Rules : 626738 Atoms : 626738 Bodies : 1 (Original: 0) Tight : Yes Variables : 534764 (Eliminated: 0 Frozen: 534764) Constraints : 3492530 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 1067MB Max. Length : 10 steps Models : 1 [endof: stats after solve call] Solving Time: 56.80s Memory: 1067MB (+176MB) SAT Testing... NOT SERIALIZABLE Testing Time: 11.78s Memory: 1379MB (+312MB) Solving... [start: stats after solve call] Models : 0+ Calls : 5 Time : 279.454s (Solving: 156.43s 1st Model: 21.83s Unsat: 0.00s) CPU Time : 278.116s Choices : 3312295 (Domain: 3312175) Conflicts : 76595 (Analyzed: 76595) Restarts : 269 (Average: 284.74 Last: 358) Model-Level : 1588.0 Problems : 5 (Average Length: 11.00 Splits: 0) Lemmas : 76595 (Deleted: 61026) Binary : 1684 (Ratio: 2.20%) Ternary : 1201 (Ratio: 1.57%) Conflict : 76595 (Average Length: 946.4 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 76595 (Average: 41.94 Max: 2160 Sum: 3212463) Executed : 76466 (Average: 41.92 Max: 2160 Sum: 3210579 Ratio: 99.94%) Bounded : 129 (Average: 14.60 Max: 17 Sum: 1884 Ratio: 0.06%) Rules : 1841972 (Original: 1841912) Atoms : 697680 Bodies : 767067 (Original: 767006) Count : 940 (Original: 946) Equivalences : 386757 (Atom=Atom: 29 Body=Body: 0 Other: 386728) Tight : Yes Variables : 536768 (Eliminated: 66 Frozen: 536702) Constraints : 4639842 (Binary: 99.0% Ternary: 0.5% Other: 0.5%) Memory Peak : 1453MB Max. Length : 10 steps Models : 1 [endof: stats after solve call] Solving Time: 126.11s Memory: 1389MB (+10MB) UNKNOWN Iteration Time: 199.31s Iteration 5 Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 15 Expected Memory: 1602.0MB Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])] Grounding Time: 5.98s Memory: 1389MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 6 Time : 489.784s (Solving: 304.69s 1st Model: 21.83s Unsat: 0.00s) CPU Time : 488.536s Choices : 8249852 (Domain: 8249732) Conflicts : 104685 (Analyzed: 104685) Restarts : 369 (Average: 283.70 Last: 358) Model-Level : 1588.0 Problems : 6 (Average Length: 12.83 Splits: 0) Lemmas : 104685 (Deleted: 86914) Binary : 2270 (Ratio: 2.17%) Ternary : 1435 (Ratio: 1.37%) Conflict : 104685 (Average Length: 867.7 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 104685 (Average: 76.62 Max: 4961 Sum: 8020586) Executed : 104254 (Average: 76.53 Max: 4961 Sum: 8012058 Ratio: 99.89%) Bounded : 431 (Average: 19.79 Max: 22 Sum: 8528 Ratio: 0.11%) Rules : 1841972 (Original: 1841912) Atoms : 697680 Bodies : 767067 (Original: 767006) Count : 940 (Original: 946) Equivalences : 386757 (Atom=Atom: 29 Body=Body: 0 Other: 386728) Tight : Yes Variables : 728919 (Eliminated: 66 Frozen: 728853) Constraints : 6624713 (Binary: 99.0% Ternary: 0.5% Other: 0.5%) Memory Peak : 1637MB Max. Length : 15 steps Models : 1 [endof: stats after solve call] Solving Time: 202.39s Memory: 1573MB (+184MB) UNKNOWN Iteration Time: 210.44s Iteration 6 Queue: [(5,25,0,True), (6,30,0,True)] Grounded Until: 20 Expected Memory: 1786.0MB Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])] Grounding Time: 5.41s Memory: 1609MB (+36MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 7 Time : 702.207s (Solving: 455.40s 1st Model: 21.83s Unsat: 0.00s) CPU Time : 701.048s Choices : 12289075 (Domain: 12288955) Conflicts : 132881 (Analyzed: 132881) Restarts : 469 (Average: 283.33 Last: 358) Model-Level : 1588.0 Problems : 7 (Average Length: 14.86 Splits: 0) Lemmas : 132881 (Deleted: 113392) Binary : 2464 (Ratio: 1.85%) Ternary : 1588 (Ratio: 1.20%) Conflict : 132881 (Average Length: 1454.5 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 132881 (Average: 89.09 Max: 4961 Sum: 11838239) Executed : 132424 (Average: 89.02 Max: 4961 Sum: 11829009 Ratio: 99.92%) Bounded : 457 (Average: 20.20 Max: 27 Sum: 9230 Ratio: 0.08%) Rules : 1841972 (Original: 1841912) Atoms : 697680 Bodies : 767067 (Original: 767006) Count : 940 (Original: 946) Equivalences : 386757 (Atom=Atom: 29 Body=Body: 0 Other: 386728) Tight : Yes Variables : 921070 (Eliminated: 66 Frozen: 921004) Constraints : 8595247 (Binary: 99.0% Ternary: 0.4% Other: 0.5%) Memory Peak : 1938MB Max. Length : 20 steps Models : 1 [endof: stats after solve call] Solving Time: 204.87s Memory: 1874MB (+265MB) UNKNOWN Iteration Time: 212.52s Iteration 7 Queue: [(6,30,0,True)] Grounded Until: 25 Expected Memory: 2175.0MB Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])] Grounding Time: 5.56s Memory: 1949MB (+75MB) Unblocking actions... Solving... *** Info : (planner): INTERRUPTED by signal! UNKNOWN INTERRUPTED : 1 Models : 0+ Calls : 8 Time : 849.978s (Solving: 541.32s 1st Model: 21.83s Unsat: 0.00s) CPU Time : 848.864s Choices : 14676803 (Domain: 14676683) Conflicts : 144705 (Analyzed: 144705) Restarts : 519 (Average: 278.82 Last: 358) Model-Level : 1588.0 Problems : 8 (Average Length: 17.00 Splits: 0) Lemmas : 144705 (Deleted: 128979) Binary : 2530 (Ratio: 1.75%) Ternary : 1626 (Ratio: 1.12%) Conflict : 144705 (Average Length: 1461.6 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 144705 (Average: 97.77 Max: 4961 Sum: 14147086) Executed : 144147 (Average: 97.68 Max: 4961 Sum: 14134624 Ratio: 99.91%) Bounded : 558 (Average: 22.33 Max: 32 Sum: 12462 Ratio: 0.09%) Rules : 1841972 (Original: 1841912) Atoms : 697680 Bodies : 767067 (Original: 767006) Count : 940 (Original: 946) Equivalences : 386757 (Atom=Atom: 29 Body=Body: 0 Other: 386728) Tight : Yes Variables : 1113221 (Eliminated: 66 Frozen: 1113155) Constraints : 10668383 (Binary: 99.0% Ternary: 0.4% Other: 0.5%) Memory Peak : 2122MB Max. Length : 25 steps Models : 1