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-13.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-13.pddl Parsing... Parsing: [0.050s CPU, 0.058s wall-clock] Normalizing task... [0.000s CPU, 0.003s wall-clock] Instantiating... Generating Datalog program... [0.020s CPU, 0.017s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.020s CPU, 0.022s wall-clock] Preparing model... [0.050s CPU, 0.052s wall-clock] Generated 46 rules. Computing model... [3.530s CPU, 3.525s wall-clock] 32936 relevant atoms 12318 auxiliary atoms 45254 final queue length 104494 total queue pushes Completing instantiation... [11.640s CPU, 11.630s wall-clock] Instantiating: [15.300s CPU, 15.289s wall-clock] Computing fact groups... Finding invariants... 12 initial candidates Finding invariants: [0.160s CPU, 0.167s wall-clock] Checking invariant weight... [0.010s CPU, 0.005s wall-clock] Instantiating groups... [0.110s CPU, 0.114s wall-clock] Collecting mutex groups... [0.010s CPU, 0.011s wall-clock] Choosing groups... 0 uncovered facts Choosing groups: [0.030s CPU, 0.031s wall-clock] Building translation key... [0.020s CPU, 0.019s wall-clock] Computing fact groups: [0.420s CPU, 0.425s wall-clock] Building STRIPS to SAS dictionary... [0.010s CPU, 0.012s wall-clock] Building dictionary for full mutex groups... [0.010s CPU, 0.008s 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.500s CPU, 0.503s wall-clock] Translating task: [9.830s CPU, 9.825s wall-clock] 0 effect conditions simplified 0 implied preconditions added Detecting unreachable propositions... 0 operators removed 0 axioms removed 62 propositions removed Detecting unreachable propositions: [4.920s CPU, 4.923s wall-clock] Reordering and filtering variables... 62 of 62 variables necessary. 0 of 62 mutex groups necessary. 30632 of 30632 operators necessary. 0 of 0 axiom rules necessary. Reordering and filtering variables: [1.440s CPU, 1.442s wall-clock] Translator variables: 62 Translator derived variables: 0 Translator facts: 1630 Translator goal facts: 46 Translator mutex groups: 0 Translator total mutex groups size: 0 Translator operators: 30632 Translator axioms: 0 Translator task size: 184162 Translator peak memory: 156688 KB Writing output... [3.140s CPU, 3.360s wall-clock] Done! [35.540s CPU, 35.764s wall-clock] planner.py version 0.0.1 Time: 7.77s Memory: 560MB 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.063s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 7.800s 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 : 555062 Atoms : 555062 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 : 696MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 0.04s Memory: 632MB (+72MB) UNSAT Iteration Time: 0.04s 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: 632MB Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])] Grounding Time: 2.64s Memory: 632MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 2 Time : 28.894s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 27.640s 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 : 555062 Atoms : 555062 Bodies : 1 (Original: 0) Tight : Yes Variables : 137565 (Eliminated: 0 Frozen: 137565) Constraints : 291918 (Binary: 98.0% Ternary: 0.9% Other: 1.1%) Memory Peak : 696MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 15.37s Memory: 672MB (+40MB) UNSAT Iteration Time: 19.84s 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: 712.0MB Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])] Grounding Time: 2.97s Memory: 695MB (+23MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 3 Time : 70.061s (Solving: 9.94s 1st Model: 0.00s Unsat: 9.94s) CPU Time : 68.824s Choices : 319217 (Domain: 319217) Conflicts : 19800 (Analyzed: 19799) Restarts : 67 (Average: 295.51 Last: 38) Problems : 3 (Average Length: 7.00 Splits: 0) Lemmas : 19799 (Deleted: 6831) Binary : 608 (Ratio: 3.07%) Ternary : 336 (Ratio: 1.70%) Conflict : 19799 (Average Length: 400.2 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 19799 (Average: 15.53 Max: 875 Sum: 307551) Executed : 19789 (Average: 15.53 Max: 875 Sum: 307530 Ratio: 99.99%) Bounded : 10 (Average: 2.10 Max: 12 Sum: 21 Ratio: 0.01%) Rules : 555062 Atoms : 555062 Bodies : 1 (Original: 0) Tight : Yes Variables : 303354 (Eliminated: 0 Frozen: 303354) Constraints : 1637246 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 773MB Max. Length : 5 steps Models : 0 [endof: stats after solve call] Solving Time: 36.64s Memory: 773MB (+78MB) UNSAT Iteration Time: 41.19s Iteration 4 Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 10 Expected Memory: 874.0MB Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])] Grounding Time: 2.62s Memory: 812MB (+39MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 4 Time : 124.453s (Solving: 32.64s 1st Model: 0.00s Unsat: 9.94s) CPU Time : 123.240s Choices : 1023674 (Domain: 1023674) Conflicts : 47872 (Analyzed: 47871) Restarts : 167 (Average: 286.65 Last: 201) Problems : 4 (Average Length: 9.50 Splits: 0) Lemmas : 47871 (Deleted: 34634) Binary : 905 (Ratio: 1.89%) Ternary : 510 (Ratio: 1.07%) Conflict : 47871 (Average Length: 644.2 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 47871 (Average: 20.21 Max: 1564 Sum: 967344) Executed : 47849 (Average: 20.21 Max: 1564 Sum: 967263 Ratio: 99.99%) Bounded : 22 (Average: 3.68 Max: 17 Sum: 81 Ratio: 0.01%) Rules : 555062 Atoms : 555062 Bodies : 1 (Original: 0) Tight : Yes Variables : 473135 (Eliminated: 0 Frozen: 473135) Constraints : 3023667 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 1092MB Max. Length : 10 steps Models : 0 [endof: stats after solve call] Solving Time: 50.31s Memory: 1028MB (+216MB) UNKNOWN Iteration Time: 54.42s Iteration 5 Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 15 Expected Memory: 1283.0MB Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])] Grounding Time: 2.75s Memory: 1047MB (+19MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 5 Time : 198.473s (Solving: 73.82s 1st Model: 0.00s Unsat: 9.94s) CPU Time : 197.292s Choices : 2779222 (Domain: 2779222) Conflicts : 75968 (Analyzed: 75967) Restarts : 267 (Average: 284.52 Last: 201) Problems : 5 (Average Length: 12.00 Splits: 0) Lemmas : 75967 (Deleted: 61538) Binary : 1173 (Ratio: 1.54%) Ternary : 654 (Ratio: 0.86%) Conflict : 75967 (Average Length: 639.9 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 75967 (Average: 33.82 Max: 2771 Sum: 2569149) Executed : 75932 (Average: 33.82 Max: 2771 Sum: 2569013 Ratio: 99.99%) Bounded : 35 (Average: 3.89 Max: 22 Sum: 136 Ratio: 0.01%) Rules : 555062 Atoms : 555062 Bodies : 1 (Original: 0) Tight : Yes Variables : 642916 (Eliminated: 0 Frozen: 642916) Constraints : 4410070 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 1162MB Max. Length : 15 steps Models : 0 [endof: stats after solve call] Solving Time: 69.78s Memory: 1151MB (+104MB) UNKNOWN Iteration Time: 74.06s Iteration 6 Queue: [(5,25,0,True), (6,30,0,True)] Grounded Until: 20 Expected Memory: 1406.0MB Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])] Grounding Time: 3.31s Memory: 1252MB (+101MB) Unblocking actions... Solving... [start: stats after solve call] Models : 1+ Calls : 6 Time : 238.672s (Solving: 81.12s 1st Model: 7.22s Unsat: 9.94s) CPU Time : 237.508s Choices : 3135951 (Domain: 3135895) Conflicts : 79407 (Analyzed: 79406) Restarts : 283 (Average: 280.59 Last: 201) Model-Level : 3625.0 Problems : 6 (Average Length: 14.50 Splits: 0) Lemmas : 79406 (Deleted: 61538) Binary : 1209 (Ratio: 1.52%) Ternary : 680 (Ratio: 0.86%) Conflict : 79406 (Average Length: 657.4 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 79406 (Average: 36.34 Max: 3628 Sum: 2885795) Executed : 79369 (Average: 36.34 Max: 3628 Sum: 2885631 Ratio: 99.99%) Bounded : 37 (Average: 4.43 Max: 27 Sum: 164 Ratio: 0.01%) Rules : 555062 Atoms : 555062 Bodies : 1 (Original: 0) Tight : Yes Variables : 812697 (Eliminated: 0 Frozen: 812697) Constraints : 5796482 (Binary: 98.7% Ternary: 0.6% Other: 0.7%) Memory Peak : 1375MB Max. Length : 20 steps Models : 1 [endof: stats after solve call] Solving Time: 35.32s Memory: 1375MB (+123MB) SAT Testing... NOT SERIALIZABLE Testing Time: 12.63s Memory: 1647MB (+272MB) Solving... [start: stats after solve call] Models : 0+ Calls : 7 Time : 450.966s (Solving: 280.89s 1st Model: 7.22s Unsat: 9.94s) CPU Time : 449.892s Choices : 8061375 (Domain: 8061319) Conflicts : 107484 (Analyzed: 107483) Restarts : 383 (Average: 280.63 Last: 201) Model-Level : 3625.0 Problems : 7 (Average Length: 16.29 Splits: 0) Lemmas : 107483 (Deleted: 90978) Binary : 1644 (Ratio: 1.53%) Ternary : 1038 (Ratio: 0.97%) Conflict : 107483 (Average Length: 650.7 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 107483 (Average: 71.31 Max: 4437 Sum: 7664142) Executed : 107279 (Average: 71.26 Max: 4437 Sum: 7659469 Ratio: 99.94%) Bounded : 204 (Average: 22.91 Max: 27 Sum: 4673 Ratio: 0.06%) Rules : 2518910 (Original: 2518849) Atoms : 619152 Bodies : 1273303 (Original: 1273241) Count : 1465 (Original: 1470) Equivalences : 642183 (Atom=Atom: 39 Body=Body: 0 Other: 642144) Tight : Yes Variables : 815771 (Eliminated: 66 Frozen: 815705) Constraints : 7700871 (Binary: 99.0% Ternary: 0.5% Other: 0.5%) Memory Peak : 1647MB Max. Length : 20 steps Models : 1 [endof: stats after solve call] Solving Time: 206.21s Memory: 1564MB (+-83MB) UNKNOWN Iteration Time: 259.06s Iteration 7 Queue: [(6,30,0,True)] Grounded Until: 25 Expected Memory: 1819.0MB Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])] Grounding Time: 4.73s Memory: 1588MB (+24MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 8 Time : 674.271s (Solving: 454.70s 1st Model: 7.22s Unsat: 9.94s) CPU Time : 673.288s Choices : 13005942 (Domain: 13005886) Conflicts : 135569 (Analyzed: 135568) Restarts : 483 (Average: 280.68 Last: 204) Model-Level : 3625.0 Problems : 8 (Average Length: 18.25 Splits: 0) Lemmas : 135568 (Deleted: 117380) Binary : 1933 (Ratio: 1.43%) Ternary : 1247 (Ratio: 0.92%) Conflict : 135568 (Average Length: 682.5 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 135568 (Average: 91.19 Max: 5749 Sum: 12362695) Executed : 135246 (Average: 91.13 Max: 5749 Sum: 12354246 Ratio: 99.93%) Bounded : 322 (Average: 26.24 Max: 32 Sum: 8449 Ratio: 0.07%) Rules : 2518910 (Original: 2518849) Atoms : 619152 Bodies : 1273303 (Original: 1273241) Count : 1465 (Original: 1470) Equivalences : 642183 (Atom=Atom: 39 Body=Body: 0 Other: 642144) Tight : Yes Variables : 986172 (Eliminated: 66 Frozen: 986106) Constraints : 9405265 (Binary: 99.0% Ternary: 0.5% Other: 0.5%) Memory Peak : 1763MB Max. Length : 25 steps Models : 1 [endof: stats after solve call] Solving Time: 216.66s Memory: 1763MB (+175MB) UNKNOWN Iteration Time: 223.41s Iteration 8 Queue: [(3,15,1,True), (4,20,1,True), (5,25,1,True), (6,30,1,True), (7,35,0,True), (8,40,0,True), (9,45,0,True), (10,50,0,True), (11,55,0,True), (12,60,0,True), (13,65,0,True), (14,70,0,True), (15,75,0,True), (16,80,0,True)] Grounded Until: 30 Blocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 9 Time : 732.411s (Solving: 512.53s 1st Model: 7.22s Unsat: 9.94s) CPU Time : 731.452s Choices : 13107157 (Domain: 13107101) Conflicts : 163677 (Analyzed: 163676) Restarts : 583 (Average: 280.75 Last: 204) Model-Level : 3625.0 Problems : 9 (Average Length: 19.78 Splits: 0) Lemmas : 163676 (Deleted: 143978) Binary : 2189 (Ratio: 1.34%) Ternary : 1331 (Ratio: 0.81%) Conflict : 163676 (Average Length: 705.3 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 163676 (Average: 76.11 Max: 5749 Sum: 12456686) Executed : 163326 (Average: 76.05 Max: 5749 Sum: 12447403 Ratio: 99.93%) Bounded : 350 (Average: 26.52 Max: 32 Sum: 9283 Ratio: 0.07%) Rules : 2518910 (Original: 2518849) Atoms : 619152 Bodies : 1273303 (Original: 1273241) Count : 1465 (Original: 1470) Equivalences : 642183 (Atom=Atom: 39 Body=Body: 0 Other: 642144) Tight : Yes Variables : 986172 (Eliminated: 66 Frozen: 986106) Constraints : 9362921 (Binary: 99.0% Ternary: 0.4% Other: 0.5%) Memory Peak : 1763MB Max. Length : 30 steps Models : 1 [endof: stats after solve call] Solving Time: 58.03s Memory: 1763MB (+0MB) UNKNOWN Iteration Time: 58.17s Iteration 9 Queue: [(4,20,1,True), (5,25,1,True), (6,30,1,True), (7,35,0,True), (8,40,0,True), (9,45,0,True), (10,50,0,True), (11,55,0,True), (12,60,0,True), (13,65,0,True), (14,70,0,True), (15,75,0,True), (16,80,0,True)] Grounded Until: 30 Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 10 Time : 822.857s (Solving: 602.65s 1st Model: 7.22s Unsat: 9.94s) CPU Time : 821.936s Choices : 13730368 (Domain: 13730312) Conflicts : 191755 (Analyzed: 191754) Restarts : 683 (Average: 280.75 Last: 204) Model-Level : 3625.0 Problems : 10 (Average Length: 21.00 Splits: 0) Lemmas : 191754 (Deleted: 169712) Binary : 2347 (Ratio: 1.22%) Ternary : 1485 (Ratio: 0.77%) Conflict : 191754 (Average Length: 1032.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 191754 (Average: 68.07 Max: 5749 Sum: 13052296) Executed : 191388 (Average: 68.02 Max: 5749 Sum: 13042501 Ratio: 99.92%) Bounded : 366 (Average: 26.76 Max: 32 Sum: 9795 Ratio: 0.08%) Rules : 2518910 (Original: 2518849) Atoms : 619152 Bodies : 1273303 (Original: 1273241) Count : 1465 (Original: 1470) Equivalences : 642183 (Atom=Atom: 39 Body=Body: 0 Other: 642144) Tight : Yes Variables : 986172 (Eliminated: 66 Frozen: 986106) Constraints : 9313831 (Binary: 99.0% Ternary: 0.5% Other: 0.5%) Memory Peak : 1955MB Max. Length : 30 steps Models : 1 [endof: stats after solve call] Solving Time: 90.32s Memory: 1891MB (+128MB) UNKNOWN Iteration Time: 90.49s Iteration 10 Queue: [(5,25,1,True), (6,30,1,True), (7,35,0,True), (8,40,0,True), (9,45,0,True), (10,50,0,True), (11,55,0,True), (12,60,0,True), (13,65,0,True), (14,70,0,True), (15,75,0,True), (16,80,0,True)] Grounded Until: 30 Unblocking actions... Solving... *** Info : (planner): INTERRUPTED by signal! UNKNOWN INTERRUPTED : 1 Models : 0+ Calls : 11 Time : 855.393s (Solving: 634.87s 1st Model: 7.22s Unsat: 9.94s) CPU Time : 854.468s Choices : 14803229 (Domain: 14803173) Conflicts : 196261 (Analyzed: 196260) Restarts : 706 (Average: 277.99 Last: 204) Model-Level : 3625.0 Problems : 11 (Average Length: 22.00 Splits: 0) Lemmas : 196260 (Deleted: 169712) Binary : 2389 (Ratio: 1.22%) Ternary : 1542 (Ratio: 0.79%) Conflict : 196260 (Average Length: 1015.1 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 196260 (Average: 71.73 Max: 5749 Sum: 14077541) Executed : 195886 (Average: 71.68 Max: 5749 Sum: 14067490 Ratio: 99.93%) Bounded : 374 (Average: 26.87 Max: 32 Sum: 10051 Ratio: 0.07%) Rules : 2518910 (Original: 2518849) Atoms : 619152 Bodies : 1273303 (Original: 1273241) Count : 1465 (Original: 1470) Equivalences : 642183 (Atom=Atom: 39 Body=Body: 0 Other: 642144) Tight : Yes Variables : 986172 (Eliminated: 66 Frozen: 986106) Constraints : 9312358 (Binary: 99.0% Ternary: 0.5% Other: 0.5%) Memory Peak : 1955MB Max. Length : 30 steps Models : 1