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.050s CPU, 0.077s wall-clock] Normalizing task... [0.010s CPU, 0.003s wall-clock] Instantiating... Generating Datalog program... [0.010s CPU, 0.016s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.030s CPU, 0.034s wall-clock] Preparing model... [0.080s CPU, 0.075s wall-clock] Generated 46 rules. Computing model... [3.710s CPU, 3.712s wall-clock] 35012 relevant atoms 12873 auxiliary atoms 47885 final queue length 110965 total queue pushes Completing instantiation... [12.540s CPU, 12.531s wall-clock] Instantiating: [16.410s CPU, 16.414s 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.010s CPU, 0.012s wall-clock] Choosing groups... 0 uncovered facts Choosing groups: [0.040s CPU, 0.036s wall-clock] Building translation key... [0.020s CPU, 0.020s wall-clock] Computing fact groups: [0.450s CPU, 0.456s wall-clock] Building STRIPS to SAS dictionary... [0.020s 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.536s wall-clock] Translating task: [10.060s CPU, 10.054s 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.200s CPU, 5.201s 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.525s 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.360s CPU, 3.592s wall-clock] Done! [37.540s CPU, 37.785s wall-clock] planner.py version 0.0.1 Time: 8.42s Memory: 593MB Iteration 1 Queue: [(0,80,0,True)] Grounded Until: 0 Expected Memory: 593MB 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]), ('step', [51]), ('step', [52]), ('step', [53]), ('step', [54]), ('step', [55]), ('step', [56]), ('step', [57]), ('step', [58]), ('step', [59]), ('step', [60]), ('step', [61]), ('step', [62]), ('step', [63]), ('step', [64]), ('step', [65]), ('step', [66]), ('step', [67]), ('step', [68]), ('step', [69]), ('step', [70]), ('step', [71]), ('step', [72]), ('step', [73]), ('step', [74]), ('step', [75]), ('step', [76]), ('step', [77]), ('step', [78]), ('step', [79]), ('step', [80]), ('check', [80])] Grounding Time: 53.50s Memory: 4376MB (+3783MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 1 Time : 303.089s (Solving: 183.78s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 302.000s Choices : 5792210 (Domain: 5792210) Conflicts : 32027 (Analyzed: 32027) Restarts : 100 (Average: 320.27 Last: 236) Problems : 1 (Average Length: 82.00 Splits: 0) Lemmas : 32027 (Deleted: 26306) Binary : 670 (Ratio: 2.09%) Ternary : 252 (Ratio: 0.79%) Conflict : 32027 (Average Length: 3672.3 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 32027 (Average: 172.07 Max: 14306 Sum: 5510942) Executed : 32023 (Average: 172.06 Max: 14306 Sum: 5510614 Ratio: 99.99%) Bounded : 4 (Average: 82.00 Max: 82 Sum: 328 Ratio: 0.01%) Rules : 0 Atoms : 0 Bodies : 0 Tight : Yes Variables : 2855700 (Eliminated: 0 Frozen: 135082) Constraints : 22411716 (Binary: 98.8% Ternary: 0.6% Other: 0.6%) Memory Peak : 5644MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 186.40s Memory: 5644MB (+1268MB) UNKNOWN Iteration Time: 293.59s Iteration 2 Queue: [(0,80,1,True)] Grounded Until: 80 Solving... [start: stats after solve call] Models : 0+ Calls : 2 Time : 467.256s (Solving: 347.37s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 466.236s Choices : 11379992 (Domain: 11379992) Conflicts : 62856 (Analyzed: 62856) Restarts : 200 (Average: 314.28 Last: 236) Problems : 2 (Average Length: 82.00 Splits: 0) Lemmas : 62856 (Deleted: 57111) Binary : 1055 (Ratio: 1.68%) Ternary : 490 (Ratio: 0.78%) Conflict : 62856 (Average Length: 2918.1 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 62856 (Average: 172.41 Max: 14520 Sum: 10837238) Executed : 62852 (Average: 172.41 Max: 14520 Sum: 10836910 Ratio: 100.00%) Bounded : 4 (Average: 82.00 Max: 82 Sum: 328 Ratio: 0.00%) Rules : 0 Atoms : 0 Bodies : 0 Tight : Yes Variables : 2855700 (Eliminated: 0 Frozen: 135082) Constraints : 22411680 (Binary: 98.8% Ternary: 0.6% Other: 0.6%) Memory Peak : 5718MB Max. Length : 80 steps Models : 0 [endof: stats after solve call] Solving Time: 164.24s Memory: 5644MB (+0MB) UNKNOWN Iteration Time: 164.24s Iteration 3 Queue: [(0,80,2,True)] Grounded Until: 80 Solving... [start: stats after solve call] Models : 1+ Calls : 3 Time : 494.019s (Solving: 373.57s 1st Model: 25.94s Unsat: 0.00s) CPU Time : 493.008s Choices : 12090436 (Domain: 12088007) Conflicts : 67285 (Analyzed: 67285) Restarts : 214 (Average: 314.42 Last: 1646) Model-Level : 15628.0 Problems : 3 (Average Length: 82.00 Splits: 0) Lemmas : 67285 (Deleted: 61806) Binary : 1077 (Ratio: 1.60%) Ternary : 516 (Ratio: 0.77%) Conflict : 67285 (Average Length: 2819.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 67285 (Average: 170.91 Max: 14520 Sum: 11499801) Executed : 67281 (Average: 170.91 Max: 14520 Sum: 11499473 Ratio: 100.00%) Bounded : 4 (Average: 82.00 Max: 82 Sum: 328 Ratio: 0.00%) Rules : 0 Atoms : 0 Bodies : 0 Tight : Yes Variables : 2855700 (Eliminated: 0 Frozen: 135082) Constraints : 22411680 (Binary: 98.8% Ternary: 0.6% Other: 0.6%) Memory Peak : 5754MB Max. Length : 80 steps Models : 1 [endof: stats after solve call] Solving Time: 26.78s Memory: 5678MB (+34MB) SAT Testing... NOT SERIALIZABLE Testing Time: 28.90s Memory: 5725MB (+47MB) Solving... *** Info : (planner): INTERRUPTED by signal! UNKNOWN INTERRUPTED : 1 Models : 0+ Calls : 4 Time : 1327.893s (Solving: 1180.86s 1st Model: 25.94s Unsat: 0.00s) CPU Time : 1327.204s Choices : 26574288 (Domain: 26571859) Conflicts : 90951 (Analyzed: 90951) Restarts : 314 (Average: 289.65 Last: 1646) Model-Level : 15628.0 Problems : 4 (Average Length: 82.00 Splits: 0) Lemmas : 90951 (Deleted: 81997) Binary : 1567 (Ratio: 1.72%) Ternary : 949 (Ratio: 1.04%) Conflict : 90951 (Average Length: 2224.4 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 90951 (Average: 285.25 Max: 21728 Sum: 25944088) Executed : 90791 (Average: 285.11 Max: 21728 Sum: 25930968 Ratio: 99.95%) Bounded : 160 (Average: 82.00 Max: 82 Sum: 13120 Ratio: 0.05%) Rules : 7428931 (Original: 7428871) Atoms : 75418 Bodies : 4924851 (Original: 4924791) Count : 5112 (Original: 5118) Equivalences : 2480627 (Atom=Atom: 92 Body=Body: 0 Other: 2480535) Tight : Yes Variables : 2866060 (Eliminated: 0 Frozen: 2615381) Constraints : 29780937 (Binary: 99.0% Ternary: 0.4% Other: 0.5%) Memory Peak : 6127MB Max. Length : 80 steps Models : 1