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-16.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-16.pddl Parsing... Parsing: [0.110s CPU, 0.115s wall-clock] Normalizing task... [0.010s CPU, 0.004s wall-clock] Instantiating... Generating Datalog program... [0.020s CPU, 0.030s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.030s CPU, 0.024s wall-clock] Preparing model... [0.090s CPU, 0.096s wall-clock] Generated 46 rules. Computing model... [4.090s CPU, 4.076s wall-clock] 35432 relevant atoms 16424 auxiliary atoms 51856 final queue length 113272 total queue pushes Completing instantiation... [13.080s CPU, 13.078s wall-clock] Instantiating: [17.360s CPU, 17.354s wall-clock] Computing fact groups... Finding invariants... 12 initial candidates Finding invariants: [0.170s CPU, 0.173s wall-clock] Checking invariant weight... [0.010s CPU, 0.007s wall-clock] Instantiating groups... [0.120s CPU, 0.117s wall-clock] Collecting mutex groups... [0.020s CPU, 0.013s wall-clock] Choosing groups... 0 uncovered facts Choosing groups: [0.040s CPU, 0.041s wall-clock] Building translation key... [0.030s CPU, 0.025s wall-clock] Computing fact groups: [0.500s CPU, 0.498s wall-clock] Building STRIPS to SAS dictionary... [0.010s CPU, 0.011s 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.013s wall-clock] Translating task... Processing axioms... Simplifying axioms... [0.000s CPU, 0.000s wall-clock] Processing axioms: [0.530s CPU, 0.527s wall-clock] Translating task: [9.910s CPU, 9.897s wall-clock] 0 effect conditions simplified 0 implied preconditions added Detecting unreachable propositions... 0 operators removed 0 axioms removed 56 propositions removed Detecting unreachable propositions: [5.120s CPU, 5.125s wall-clock] Reordering and filtering variables... 56 of 56 variables necessary. 0 of 56 mutex groups necessary. 32088 of 32088 operators necessary. 0 of 0 axiom rules necessary. Reordering and filtering variables: [1.510s CPU, 1.506s wall-clock] Translator variables: 56 Translator derived variables: 0 Translator facts: 2088 Translator goal facts: 40 Translator mutex groups: 0 Translator total mutex groups size: 0 Translator operators: 32088 Translator axioms: 0 Translator task size: 192528 Translator peak memory: 162824 KB Writing output... [3.320s CPU, 3.552s wall-clock] Done! [38.290s CPU, 38.512s wall-clock] planner.py version 0.0.1 Time: 8.29s Memory: 583MB 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.551s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 8.324s 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 : 581162 Atoms : 581162 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 : 719MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 0.04s Memory: 655MB (+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: 655MB Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])] Grounding Time: 2.64s Memory: 655MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 2 Time : 30.219s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 29.004s 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 : 581162 Atoms : 581162 Bodies : 1 (Original: 0) Tight : Yes Variables : 147475 (Eliminated: 0 Frozen: 147475) Constraints : 251204 (Binary: 97.6% Ternary: 1.0% Other: 1.3%) Memory Peak : 719MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 16.07s Memory: 699MB (+44MB) UNSAT Iteration Time: 20.68s 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: 743.0MB Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])] Grounding Time: 3.11s Memory: 708MB (+9MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 3 Time : 70.207s (Solving: 7.76s 1st Model: 0.00s Unsat: 7.76s) CPU Time : 69.008s Choices : 104801 (Domain: 104801) Conflicts : 18076 (Analyzed: 18075) Restarts : 62 (Average: 291.53 Last: 2013) Problems : 3 (Average Length: 7.00 Splits: 0) Lemmas : 18075 (Deleted: 7053) Binary : 394 (Ratio: 2.18%) Ternary : 206 (Ratio: 1.14%) Conflict : 18075 (Average Length: 296.6 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 18075 (Average: 5.52 Max: 1020 Sum: 99691) Executed : 18063 (Average: 5.51 Max: 1020 Sum: 99602 Ratio: 99.91%) Bounded : 12 (Average: 7.42 Max: 12 Sum: 89 Ratio: 0.09%) Rules : 581162 Atoms : 581162 Bodies : 1 (Original: 0) Tight : Yes Variables : 322160 (Eliminated: 0 Frozen: 322160) Constraints : 1622570 (Binary: 98.4% Ternary: 0.8% Other: 0.9%) Memory Peak : 811MB Max. Length : 5 steps Models : 0 [endof: stats after solve call] Solving Time: 35.11s Memory: 811MB (+103MB) UNSAT Iteration Time: 40.01s Iteration 4 Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 10 Expected Memory: 923.0MB Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])] Grounding Time: 2.80s Memory: 837MB (+26MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 4 Time : 126.148s (Solving: 29.94s 1st Model: 0.00s Unsat: 7.76s) CPU Time : 124.972s Choices : 492372 (Domain: 492372) Conflicts : 46198 (Analyzed: 46197) Restarts : 162 (Average: 285.17 Last: 2013) Problems : 4 (Average Length: 9.50 Splits: 0) Lemmas : 46197 (Deleted: 33614) Binary : 655 (Ratio: 1.42%) Ternary : 418 (Ratio: 0.90%) Conflict : 46197 (Average Length: 886.7 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 46197 (Average: 9.63 Max: 1605 Sum: 445074) Executed : 46181 (Average: 9.63 Max: 1605 Sum: 444949 Ratio: 99.97%) Bounded : 16 (Average: 7.81 Max: 17 Sum: 125 Ratio: 0.03%) Rules : 581162 Atoms : 581162 Bodies : 1 (Original: 0) Tight : Yes Variables : 503771 (Eliminated: 0 Frozen: 503771) Constraints : 3073537 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1123MB Max. Length : 10 steps Models : 0 [endof: stats after solve call] Solving Time: 51.62s Memory: 1059MB (+222MB) UNKNOWN Iteration Time: 55.98s Iteration 5 Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 15 Expected Memory: 1307.0MB Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])] Grounding Time: 2.95s Memory: 1080MB (+21MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 5 Time : 192.800s (Solving: 62.62s 1st Model: 0.00s Unsat: 7.76s) CPU Time : 191.656s Choices : 1577112 (Domain: 1577112) Conflicts : 74275 (Analyzed: 74274) Restarts : 262 (Average: 283.49 Last: 2013) Problems : 5 (Average Length: 12.00 Splits: 0) Lemmas : 74274 (Deleted: 59754) Binary : 998 (Ratio: 1.34%) Ternary : 683 (Ratio: 0.92%) Conflict : 74274 (Average Length: 1095.3 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 74274 (Average: 18.80 Max: 2666 Sum: 1396324) Executed : 74244 (Average: 18.80 Max: 2666 Sum: 1396185 Ratio: 99.99%) Bounded : 30 (Average: 4.63 Max: 17 Sum: 139 Ratio: 0.01%) Rules : 581162 Atoms : 581162 Bodies : 1 (Original: 0) Tight : Yes Variables : 685382 (Eliminated: 0 Frozen: 685382) Constraints : 4524549 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1304MB Max. Length : 15 steps Models : 0 [endof: stats after solve call] Solving Time: 62.12s Memory: 1240MB (+160MB) UNKNOWN Iteration Time: 66.69s Iteration 6 Queue: [(5,25,0,True), (6,30,0,True)] Grounded Until: 20 Expected Memory: 1488.0MB Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])] Grounding Time: 3.46s Memory: 1340MB (+100MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 6 Time : 285.994s (Solving: 121.23s 1st Model: 0.00s Unsat: 7.76s) CPU Time : 284.892s Choices : 3609941 (Domain: 3609941) Conflicts : 102386 (Analyzed: 102385) Restarts : 362 (Average: 282.83 Last: 2013) Problems : 6 (Average Length: 14.50 Splits: 0) Lemmas : 102385 (Deleted: 85609) Binary : 1258 (Ratio: 1.23%) Ternary : 805 (Ratio: 0.79%) Conflict : 102385 (Average Length: 1320.1 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 102385 (Average: 31.21 Max: 3961 Sum: 3195578) Executed : 102346 (Average: 31.21 Max: 3961 Sum: 3195378 Ratio: 99.99%) Bounded : 39 (Average: 5.13 Max: 27 Sum: 200 Ratio: 0.01%) Rules : 581162 Atoms : 581162 Bodies : 1 (Original: 0) Tight : Yes Variables : 866993 (Eliminated: 0 Frozen: 866993) Constraints : 5975579 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1484MB Max. Length : 20 steps Models : 0 [endof: stats after solve call] Solving Time: 88.08s Memory: 1484MB (+144MB) UNKNOWN Iteration Time: 93.24s Iteration 7 Queue: [(6,30,0,True)] Grounded Until: 25 Expected Memory: 1732.0MB Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])] Grounding Time: 2.81s Memory: 1503MB (+19MB) Unblocking actions... Solving... [start: stats after solve call] Models : 1+ Calls : 7 Time : 375.837s (Solving: 177.06s 1st Model: 55.71s Unsat: 7.76s) CPU Time : 374.776s Choices : 5030419 (Domain: 5029947) Conflicts : 125455 (Analyzed: 125454) Restarts : 444 (Average: 282.55 Last: 2013) Model-Level : 5401.0 Problems : 7 (Average Length: 17.00 Splits: 0) Lemmas : 125454 (Deleted: 112946) Binary : 1347 (Ratio: 1.07%) Ternary : 841 (Ratio: 0.67%) Conflict : 125454 (Average Length: 1994.1 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 125454 (Average: 34.92 Max: 5972 Sum: 4381267) Executed : 125414 (Average: 34.92 Max: 5972 Sum: 4381035 Ratio: 99.99%) Bounded : 40 (Average: 5.80 Max: 32 Sum: 232 Ratio: 0.01%) Rules : 581162 Atoms : 581162 Bodies : 1 (Original: 0) Tight : Yes Variables : 1048604 (Eliminated: 0 Frozen: 1048604) Constraints : 7426591 (Binary: 98.5% Ternary: 0.7% Other: 0.8%) Memory Peak : 1807MB Max. Length : 25 steps Models : 1 [endof: stats after solve call] Solving Time: 85.32s Memory: 1743MB (+240MB) SAT Testing... NOT SERIALIZABLE Testing Time: 15.36s Memory: 2101MB (+358MB) Solving... [start: stats after solve call] Models : 0+ Calls : 8 Time : 649.119s (Solving: 434.36s 1st Model: 55.71s Unsat: 7.76s) CPU Time : 648.172s Choices : 10872653 (Domain: 10872181) Conflicts : 153513 (Analyzed: 153512) Restarts : 544 (Average: 282.19 Last: 2013) Model-Level : 5401.0 Problems : 8 (Average Length: 18.88 Splits: 0) Lemmas : 153512 (Deleted: 134294) Binary : 1721 (Ratio: 1.12%) Ternary : 1070 (Ratio: 0.70%) Conflict : 153512 (Average Length: 1780.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 153512 (Average: 64.93 Max: 5972 Sum: 9968256) Executed : 153249 (Average: 64.89 Max: 5972 Sum: 9960888 Ratio: 99.93%) Bounded : 263 (Average: 28.02 Max: 32 Sum: 7368 Ratio: 0.07%) Rules : 3071171 (Original: 3071169) Atoms : 648112 Bodies : 1625810 (Original: 1625807) Count : 1603 (Original: 1604) Equivalences : 823564 (Atom=Atom: 39 Body=Body: 0 Other: 823525) Tight : Yes Variables : 1051873 (Eliminated: 3 Frozen: 1051870) Constraints : 9854639 (Binary: 98.8% Ternary: 0.5% Other: 0.6%) Memory Peak : 2101MB Max. Length : 25 steps Models : 1 [endof: stats after solve call] Solving Time: 265.51s Memory: 2019MB (+-82MB) UNKNOWN Iteration Time: 370.76s 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 : 672.084s (Solving: 456.98s 1st Model: 55.71s Unsat: 30.37s) CPU Time : 671.148s Choices : 10901508 (Domain: 10901036) Conflicts : 161453 (Analyzed: 161451) Restarts : 574 (Average: 281.27 Last: 2013) Model-Level : 5401.0 Problems : 9 (Average Length: 20.33 Splits: 0) Lemmas : 161451 (Deleted: 134294) Binary : 1851 (Ratio: 1.15%) Ternary : 1158 (Ratio: 0.72%) Conflict : 161451 (Average Length: 1732.4 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 161451 (Average: 61.92 Max: 5972 Sum: 9996343) Executed : 161161 (Average: 61.86 Max: 5972 Sum: 9988142 Ratio: 99.92%) Bounded : 290 (Average: 28.28 Max: 32 Sum: 8201 Ratio: 0.08%) Rules : 3071171 (Original: 3071169) Atoms : 648112 Bodies : 1625810 (Original: 1625807) Count : 1603 (Original: 1604) Equivalences : 823564 (Atom=Atom: 39 Body=Body: 0 Other: 823525) Tight : Yes Variables : 1051873 (Eliminated: 3 Frozen: 1051870) Constraints : 9743652 (Binary: 98.8% Ternary: 0.5% Other: 0.6%) Memory Peak : 2101MB Max. Length : 30 steps Models : 1 [endof: stats after solve call] Solving Time: 22.81s Memory: 2019MB (+0MB) UNSAT Iteration Time: 22.98s 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 : 773.438s (Solving: 557.98s 1st Model: 55.71s Unsat: 30.37s) CPU Time : 772.544s Choices : 11298759 (Domain: 11298287) Conflicts : 189575 (Analyzed: 189573) Restarts : 674 (Average: 281.27 Last: 2013) Model-Level : 5401.0 Problems : 10 (Average Length: 21.50 Splits: 0) Lemmas : 189573 (Deleted: 167224) Binary : 1980 (Ratio: 1.04%) Ternary : 1320 (Ratio: 0.70%) Conflict : 189573 (Average Length: 2056.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 189573 (Average: 54.63 Max: 5972 Sum: 10357246) Executed : 189262 (Average: 54.59 Max: 5972 Sum: 10348497 Ratio: 99.92%) Bounded : 311 (Average: 28.13 Max: 32 Sum: 8749 Ratio: 0.08%) Rules : 3071171 (Original: 3071169) Atoms : 648112 Bodies : 1625810 (Original: 1625807) Count : 1603 (Original: 1604) Equivalences : 823564 (Atom=Atom: 39 Body=Body: 0 Other: 823525) Tight : Yes Variables : 1051873 (Eliminated: 3 Frozen: 1051870) Constraints : 9714292 (Binary: 98.8% Ternary: 0.5% Other: 0.6%) Memory Peak : 2101MB Max. Length : 30 steps Models : 1 [endof: stats after solve call] Solving Time: 101.23s Memory: 2019MB (+0MB) UNKNOWN Iteration Time: 101.40s 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 : 851.648s (Solving: 635.88s 1st Model: 55.71s Unsat: 30.37s) CPU Time : 850.772s Choices : 12994422 (Domain: 12993950) Conflicts : 201809 (Analyzed: 201807) Restarts : 726 (Average: 277.97 Last: 2013) Model-Level : 5401.0 Problems : 11 (Average Length: 22.45 Splits: 0) Lemmas : 201807 (Deleted: 183130) Binary : 2074 (Ratio: 1.03%) Ternary : 1431 (Ratio: 0.71%) Conflict : 201807 (Average Length: 1998.3 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 201807 (Average: 59.34 Max: 5972 Sum: 11974898) Executed : 201468 (Average: 59.29 Max: 5972 Sum: 11965253 Ratio: 99.92%) Bounded : 339 (Average: 28.45 Max: 32 Sum: 9645 Ratio: 0.08%) Rules : 3071171 (Original: 3071169) Atoms : 648112 Bodies : 1625810 (Original: 1625807) Count : 1603 (Original: 1604) Equivalences : 823564 (Atom=Atom: 39 Body=Body: 0 Other: 823525) Tight : Yes Variables : 1051873 (Eliminated: 3 Frozen: 1051870) Constraints : 9691251 (Binary: 98.8% Ternary: 0.5% Other: 0.6%) Memory Peak : 2101MB Max. Length : 30 steps Models : 1