INFO Running translator. INFO translator input: ['/home/pluehne/Documents/ASP/pddl-instances/ipc-2011/domains/tidybot-sequential-satisficing/domain.pddl', '/home/pluehne/Documents/ASP/pddl-instances/ipc-2011/domains/tidybot-sequential-satisficing/instances/instance-18.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/tidybot-sequential-satisficing/domain.pddl /home/pluehne/Documents/ASP/pddl-instances/ipc-2011/domains/tidybot-sequential-satisficing/instances/instance-18.pddl Parsing... Parsing: [0.070s CPU, 0.073s wall-clock] Normalizing task... [0.010s CPU, 0.006s wall-clock] Instantiating... Generating Datalog program... [0.010s CPU, 0.014s wall-clock] Normalizing Datalog program... Normalizing Datalog program: [0.210s CPU, 0.211s wall-clock] Preparing model... [0.070s CPU, 0.068s wall-clock] Generated 400 rules. Computing model... [12.600s CPU, 12.589s wall-clock] 63291 relevant atoms 47672 auxiliary atoms 110963 final queue length 328224 total queue pushes Completing instantiation... [38.840s CPU, 38.825s wall-clock] Instantiating: [51.860s CPU, 51.832s wall-clock] Computing fact groups... Finding invariants... 41 initial candidates Finding invariants: [1.120s CPU, 1.127s wall-clock] Checking invariant weight... [0.000s CPU, 0.001s wall-clock] Instantiating groups... [0.010s CPU, 0.001s wall-clock] Collecting mutex groups... [0.000s CPU, 0.000s wall-clock] Choosing groups... 547 uncovered facts Choosing groups: [0.000s CPU, 0.001s wall-clock] Building translation key... [0.020s CPU, 0.021s wall-clock] Computing fact groups: [1.190s CPU, 1.195s wall-clock] Building STRIPS to SAS dictionary... [0.010s CPU, 0.005s wall-clock] Building dictionary for full mutex groups... [0.000s CPU, 0.004s wall-clock] Building mutex information... Building mutex information: [0.000s CPU, 0.004s wall-clock] Translating task... Processing axioms... Simplifying axioms... [0.000s CPU, 0.000s wall-clock] Processing axioms: [2.380s CPU, 2.370s wall-clock] Translating task: [46.010s CPU, 45.996s wall-clock] 182223 effect conditions simplified 0 implied preconditions added Detecting unreachable propositions... 0 operators removed 0 axioms removed 2 propositions removed Detecting unreachable propositions: [20.920s CPU, 20.907s wall-clock] Reordering and filtering variables... 549 of 549 variables necessary. 1 of 3 mutex groups necessary. 62529 of 62529 operators necessary. 0 of 0 axiom rules necessary. Reordering and filtering variables: [15.950s CPU, 15.952s wall-clock] Translator variables: 549 Translator derived variables: 0 Translator facts: 1101 Translator goal facts: 4 Translator mutex groups: 1 Translator total mutex groups size: 2 Translator operators: 62529 Translator axioms: 0 Translator task size: 777730 Translator peak memory: 450104 KB Writing output... [12.910s CPU, 13.873s wall-clock] Done! [150.360s CPU, 151.271s wall-clock] planner.py version 0.0.1 Time: 36.83s Memory: 2370MB 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 : 43.627s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 37.008s 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 : 2156951 Atoms : 2156951 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 : 2559MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 0.18s Memory: 2442MB (+72MB) UNSAT Iteration Time: 0.18s 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: 2442MB Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])] Grounding Time: 8.13s Memory: 2442MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 2 Time : 56.855s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 50.240s 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 : 2156951 Atoms : 2156951 Bodies : 1 (Original: 0) Tight : Yes Variables : 160662 (Eliminated: 0 Frozen: 0) Constraints : 9689 (Binary: 95.1% Ternary: 2.7% Other: 2.1%) Memory Peak : 2559MB Max. Length : 0 steps Models : 0 [endof: stats after solve call] Solving Time: 1.39s Memory: 2450MB (+8MB) UNSAT Iteration Time: 13.24s 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: 2458.0MB Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])] Grounding Time: 7.79s Memory: 2450MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 3 Time : 70.058s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s) CPU Time : 63.440s Choices : 0 Conflicts : 0 (Analyzed: 0) Restarts : 0 Problems : 3 (Average Length: 7.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 : 2156951 Atoms : 2156951 Bodies : 1 (Original: 0) Tight : Yes Variables : 322291 (Eliminated: 0 Frozen: 0) Constraints : 253536 (Binary: 98.8% Ternary: 0.6% Other: 0.6%) Memory Peak : 2559MB Max. Length : 5 steps Models : 0 [endof: stats after solve call] Solving Time: 1.67s Memory: 2507MB (+57MB) UNSAT Iteration Time: 13.22s Iteration 4 Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 10 Expected Memory: 2564.0MB Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])] Grounding Time: 9.22s Memory: 2507MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 1+ Calls : 4 Time : 84.971s (Solving: 0.09s 1st Model: 0.04s Unsat: 0.00s) CPU Time : 78.360s Choices : 1646 (Domain: 415) Conflicts : 78 (Analyzed: 78) Restarts : 0 Model-Level : 845.0 Problems : 4 (Average Length: 9.50 Splits: 0) Lemmas : 78 (Deleted: 0) Binary : 3 (Ratio: 3.85%) Ternary : 4 (Ratio: 5.13%) Conflict : 78 (Average Length: 59.9 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 78 (Average: 10.71 Max: 196 Sum: 835) Executed : 77 (Average: 10.49 Max: 196 Sum: 818 Ratio: 97.96%) Bounded : 1 (Average: 17.00 Max: 17 Sum: 17 Ratio: 2.04%) Rules : 2156951 Atoms : 2156951 Bodies : 1 (Original: 0) Tight : Yes Variables : 492329 (Eliminated: 0 Frozen: 0) Constraints : 1576947 (Binary: 99.4% Ternary: 0.3% Other: 0.3%) Memory Peak : 2559MB Max. Length : 10 steps Models : 1 [endof: stats after solve call] Solving Time: 1.54s Memory: 2524MB (+17MB) SAT Testing... NOT SERIALIZABLE Testing Time: 43.78s Memory: 3784MB (+1260MB) Solving... [start: stats after solve call] Models : 0 Calls : 5 Time : 99.612s (Solving: 2.30s 1st Model: 0.04s Unsat: 2.22s) CPU Time : 93.004s Choices : 5000 (Domain: 2919) Conflicts : 859 (Analyzed: 858) Restarts : 8 (Average: 107.25 Last: 78) Model-Level : 845.0 Problems : 5 (Average Length: 11.00 Splits: 0) Lemmas : 858 (Deleted: 0) Binary : 57 (Ratio: 6.64%) Ternary : 30 (Ratio: 3.50%) Conflict : 858 (Average Length: 164.0 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 858 (Average: 5.27 Max: 196 Sum: 4518) Executed : 834 (Average: 4.82 Max: 196 Sum: 4138 Ratio: 91.59%) Bounded : 24 (Average: 15.83 Max: 17 Sum: 380 Ratio: 8.41%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 498186 (Eliminated: 0 Frozen: 90007) Constraints : 2027026 (Binary: 99.2% Ternary: 0.3% Other: 0.5%) Memory Peak : 4009MB Max. Length : 10 steps Models : 1 [endof: stats after solve call] Solving Time: 2.72s Memory: 3784MB (+0MB) UNSAT Iteration Time: 61.44s Iteration 5 Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)] Grounded Until: 15 Expected Memory: 3841.0MB Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])] Grounding Time: 15.36s Memory: 3784MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 6 Time : 212.488s (Solving: 93.83s 1st Model: 0.04s Unsat: 2.22s) CPU Time : 205.896s Choices : 46965 (Domain: 23473) Conflicts : 9959 (Analyzed: 9958) Restarts : 108 (Average: 92.20 Last: 78) Model-Level : 845.0 Problems : 6 (Average Length: 12.83 Splits: 0) Lemmas : 9958 (Deleted: 7027) Binary : 150 (Ratio: 1.51%) Ternary : 89 (Ratio: 0.89%) Conflict : 9958 (Average Length: 2322.4 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 9958 (Average: 4.05 Max: 707 Sum: 40347) Executed : 9894 (Average: 3.93 Max: 707 Sum: 39128 Ratio: 96.98%) Bounded : 64 (Average: 19.05 Max: 22 Sum: 1219 Ratio: 3.02%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 690069 (Eliminated: 0 Frozen: 267111) Constraints : 5677258 (Binary: 99.5% Ternary: 0.2% Other: 0.3%) Memory Peak : 4009MB Max. Length : 15 steps Models : 1 [endof: stats after solve call] Solving Time: 92.53s Memory: 3849MB (+65MB) UNKNOWN Iteration Time: 112.90s Iteration 6 Queue: [(5,25,0,True), (6,30,0,True)] Grounded Until: 20 Expected Memory: 3914.0MB Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])] Grounding Time: 16.41s Memory: 3849MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 7 Time : 362.253s (Solving: 222.82s 1st Model: 0.04s Unsat: 2.22s) CPU Time : 355.544s Choices : 67248 (Domain: 41821) Conflicts : 18615 (Analyzed: 18614) Restarts : 208 (Average: 89.49 Last: 94) Model-Level : 845.0 Problems : 7 (Average Length: 14.86 Splits: 0) Lemmas : 18614 (Deleted: 15848) Binary : 157 (Ratio: 0.84%) Ternary : 93 (Ratio: 0.50%) Conflict : 18614 (Average Length: 2956.9 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 18614 (Average: 2.99 Max: 713 Sum: 55720) Executed : 18548 (Average: 2.93 Max: 713 Sum: 54455 Ratio: 97.73%) Bounded : 66 (Average: 19.17 Max: 27 Sum: 1265 Ratio: 2.27%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 894184 (Eliminated: 0 Frozen: 456260) Constraints : 9974026 (Binary: 99.6% Ternary: 0.1% Other: 0.3%) Memory Peak : 4009MB Max. Length : 20 steps Models : 1 [endof: stats after solve call] Solving Time: 129.24s Memory: 3933MB (+84MB) UNKNOWN Iteration Time: 149.66s Iteration 7 Queue: [(6,30,0,True)] Grounded Until: 25 Expected Memory: 4017.0MB Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])] Grounding Time: 19.10s Memory: 3933MB (+0MB) Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 8 Time : 504.955s (Solving: 342.04s 1st Model: 0.04s Unsat: 2.22s) CPU Time : 498.296s Choices : 89683 (Domain: 62920) Conflicts : 26720 (Analyzed: 26719) Restarts : 308 (Average: 86.75 Last: 94) Model-Level : 845.0 Problems : 8 (Average Length: 17.00 Splits: 0) Lemmas : 26719 (Deleted: 22269) Binary : 162 (Ratio: 0.61%) Ternary : 97 (Ratio: 0.36%) Conflict : 26719 (Average Length: 3736.5 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 26719 (Average: 2.67 Max: 713 Sum: 71207) Executed : 26652 (Average: 2.62 Max: 713 Sum: 69910 Ratio: 98.18%) Bounded : 67 (Average: 19.36 Max: 32 Sum: 1297 Ratio: 1.82%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 1098700 (Eliminated: 0 Frozen: 645805) Constraints : 14540608 (Binary: 99.6% Ternary: 0.1% Other: 0.3%) Memory Peak : 4009MB Max. Length : 25 steps Models : 1 [endof: stats after solve call] Solving Time: 119.71s Memory: 3970MB (+37MB) UNKNOWN Iteration Time: 142.77s Iteration 8 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), (17,85,0,True)] Grounded Until: 30 Blocking actions... Solving... [start: stats after solve call] Models : 0 Calls : 9 Time : 579.654s (Solving: 416.38s 1st Model: 0.04s Unsat: 76.56s) CPU Time : 573.024s Choices : 105132 (Domain: 77069) Conflicts : 34215 (Analyzed: 34213) Restarts : 391 (Average: 87.50 Last: 94) Model-Level : 845.0 Problems : 9 (Average Length: 18.67 Splits: 0) Lemmas : 34213 (Deleted: 30138) Binary : 175 (Ratio: 0.51%) Ternary : 105 (Ratio: 0.31%) Conflict : 34213 (Average Length: 4131.1 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 34213 (Average: 2.48 Max: 713 Sum: 84860) Executed : 34140 (Average: 2.44 Max: 713 Sum: 83464 Ratio: 98.35%) Bounded : 73 (Average: 19.12 Max: 32 Sum: 1396 Ratio: 1.65%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 1098700 (Eliminated: 0 Frozen: 645805) Constraints : 14539499 (Binary: 99.6% Ternary: 0.1% Other: 0.3%) Memory Peak : 4009MB Max. Length : 30 steps Models : 1 [endof: stats after solve call] Solving Time: 74.57s Memory: 3970MB (+0MB) UNSAT Iteration Time: 74.75s Iteration 9 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), (17,85,0,True)] Grounded Until: 30 Unblocking actions... Solving... [start: stats after solve call] Models : 0+ Calls : 10 Time : 681.419s (Solving: 517.79s 1st Model: 0.04s Unsat: 76.56s) CPU Time : 674.832s Choices : 129632 (Domain: 98080) Conflicts : 42787 (Analyzed: 42785) Restarts : 491 (Average: 87.14 Last: 97) Model-Level : 845.0 Problems : 10 (Average Length: 20.00 Splits: 0) Lemmas : 42785 (Deleted: 37651) Binary : 195 (Ratio: 0.46%) Ternary : 126 (Ratio: 0.29%) Conflict : 42785 (Average Length: 4054.8 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 42785 (Average: 2.43 Max: 713 Sum: 104093) Executed : 42702 (Average: 2.39 Max: 713 Sum: 102389 Ratio: 98.36%) Bounded : 83 (Average: 20.53 Max: 32 Sum: 1704 Ratio: 1.64%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 1098700 (Eliminated: 0 Frozen: 645805) Constraints : 14539033 (Binary: 99.6% Ternary: 0.1% Other: 0.3%) Memory Peak : 4009MB Max. Length : 30 steps Models : 1 [endof: stats after solve call] Solving Time: 101.65s Memory: 3977MB (+7MB) UNKNOWN Iteration Time: 101.82s Iteration 10 Queue: [(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), (17,85,0,True)] Grounded Until: 30 Unblocking actions... Solving... *** Info : (planner): INTERRUPTED by signal! UNKNOWN INTERRUPTED : 1 Models : 0+ Calls : 11 Time : 713.837s (Solving: 549.89s 1st Model: 0.04s Unsat: 76.56s) CPU Time : 707.188s Choices : 139180 (Domain: 105870) Conflicts : 45325 (Analyzed: 45323) Restarts : 518 (Average: 87.50 Last: 97) Model-Level : 845.0 Problems : 11 (Average Length: 21.09 Splits: 0) Lemmas : 45323 (Deleted: 40913) Binary : 203 (Ratio: 0.45%) Ternary : 130 (Ratio: 0.29%) Conflict : 45323 (Average Length: 4136.6 Ratio: 100.00%) Loop : 0 (Average Length: 0.0 Ratio: 0.00%) Other : 0 (Average Length: 0.0 Ratio: 0.00%) Backjumps : 45323 (Average: 2.46 Max: 713 Sum: 111562) Executed : 45240 (Average: 2.42 Max: 713 Sum: 109858 Ratio: 98.47%) Bounded : 83 (Average: 20.53 Max: 32 Sum: 1704 Ratio: 1.53%) Rules : 2733754 (Original: 2733040) Atoms : 2297535 Bodies : 273069 (Original: 272435) Count : 2467 (Original: 2580) Equivalences : 90378 (Atom=Atom: 112 Body=Body: 0 Other: 90266) Tight : Yes Variables : 1098700 (Eliminated: 0 Frozen: 645805) Constraints : 14538201 (Binary: 99.6% Ternary: 0.1% Other: 0.3%) Memory Peak : 4009MB Max. Length : 30 steps Models : 1