tplp-planning-benchmark/gc-ta1-tt1-jumpy/ipc-2011_elevator-sequential-satisficing_11.out

536 lines
19 KiB
Plaintext
Raw Normal View History

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-11.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-11.pddl
Parsing...
Parsing: [0.050s CPU, 0.061s 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.030s CPU, 0.025s wall-clock]
Preparing model... [0.050s CPU, 0.055s wall-clock]
Generated 46 rules.
Computing model... [3.100s CPU, 3.097s wall-clock]
28784 relevant atoms
11208 auxiliary atoms
39992 final queue length
91552 total queue pushes
Completing instantiation... [10.120s CPU, 10.109s wall-clock]
Instantiating: [13.360s CPU, 13.341s wall-clock]
Computing fact groups...
Finding invariants...
12 initial candidates
Finding invariants: [0.140s CPU, 0.148s wall-clock]
Checking invariant weight... [0.010s CPU, 0.005s wall-clock]
Instantiating groups... [0.090s CPU, 0.095s wall-clock]
Collecting mutex groups... [0.010s CPU, 0.010s wall-clock]
Choosing groups...
0 uncovered facts
Choosing groups: [0.030s CPU, 0.026s wall-clock]
Building translation key... [0.010s CPU, 0.017s wall-clock]
Computing fact groups: [0.360s CPU, 0.370s wall-clock]
Building STRIPS to SAS dictionary... [0.010s CPU, 0.008s wall-clock]
Building dictionary for full mutex groups... [0.010s CPU, 0.006s wall-clock]
Building mutex information...
Building mutex information: [0.010s CPU, 0.012s wall-clock]
Translating task...
Processing axioms...
Simplifying axioms... [0.000s CPU, 0.000s wall-clock]
Processing axioms: [0.440s CPU, 0.439s wall-clock]
Translating task: [8.250s CPU, 8.249s 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: [4.260s CPU, 4.250s wall-clock]
Reordering and filtering variables...
56 of 56 variables necessary.
0 of 56 mutex groups necessary.
26696 of 26696 operators necessary.
0 of 0 axiom rules necessary.
Reordering and filtering variables: [1.250s CPU, 1.251s wall-clock]
Translator variables: 56
Translator derived variables: 0
Translator facts: 1432
Translator goal facts: 40
Translator mutex groups: 0
Translator total mutex groups size: 0
Translator operators: 26696
Translator axioms: 0
Translator task size: 160336
Translator peak memory: 140708 KB
Writing output... [2.710s CPU, 2.898s wall-clock]
Done! [30.620s CPU, 30.804s wall-clock]
planner.py version 0.0.1
Time: 7.00s
Memory: 494MB
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 : 8.134s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s)
CPU Time : 7.036s
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 : 483386
Atoms : 483386
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 : 630MB
Max. Length : 0 steps
Models : 0
[endof: stats after solve call]
Solving Time: 0.04s
Memory: 566MB (+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: 566MB
Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])]
Grounding Time: 2.27s
Memory: 566MB (+0MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 0
Calls : 2
Time : 23.829s (Solving: 0.00s 1st Model: 0.00s Unsat: 0.00s)
CPU Time : 22.736s
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 : 483386
Atoms : 483386
Bodies : 1 (Original: 0)
Tight : Yes
Variables : 120011 (Eliminated: 0 Frozen: 120011)
Constraints : 286727 (Binary: 98.1% Ternary: 0.8% Other: 1.1%)
Memory Peak : 630MB
Max. Length : 0 steps
Models : 0
[endof: stats after solve call]
Solving Time: 11.90s
Memory: 602MB (+36MB)
UNSAT
Iteration Time: 15.71s
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: 638.0MB
Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])]
Grounding Time: 2.56s
Memory: 607MB (+5MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 0
Calls : 3
Time : 56.639s (Solving: 8.70s 1st Model: 0.00s Unsat: 8.70s)
CPU Time : 55.556s
Choices : 192212 (Domain: 192212)
Conflicts : 21810 (Analyzed: 21809)
Restarts : 77 (Average: 283.23 Last: 136)
Problems : 3 (Average Length: 7.00 Splits: 0)
Lemmas : 21809 (Deleted: 16714)
Binary : 463 (Ratio: 2.12%)
Ternary : 307 (Ratio: 1.41%)
Conflict : 21809 (Average Length: 463.8 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 21809 (Average: 8.46 Max: 739 Sum: 184569)
Executed : 21802 (Average: 8.46 Max: 739 Sum: 184562 Ratio: 100.00%)
Bounded : 7 (Average: 1.00 Max: 1 Sum: 7 Ratio: 0.00%)
Rules : 483386
Atoms : 483386
Bodies : 1 (Original: 0)
Tight : Yes
Variables : 265286 (Eliminated: 0 Frozen: 265286)
Constraints : 1465677 (Binary: 98.6% Ternary: 0.6% Other: 0.7%)
Memory Peak : 704MB
Max. Length : 5 steps
Models : 0
[endof: stats after solve call]
Solving Time: 28.95s
Memory: 704MB (+97MB)
UNSAT
Iteration Time: 32.84s
Iteration 4
Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until: 10
Expected Memory: 806.0MB
Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])]
Grounding Time: 2.25s
Memory: 723MB (+19MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 0+
Calls : 4
Time : 102.282s (Solving: 29.79s 1st Model: 0.00s Unsat: 8.70s)
CPU Time : 101.220s
Choices : 948460 (Domain: 948460)
Conflicts : 49948 (Analyzed: 49947)
Restarts : 177 (Average: 282.19 Last: 200)
Problems : 4 (Average Length: 9.50 Splits: 0)
Lemmas : 49947 (Deleted: 37244)
Binary : 859 (Ratio: 1.72%)
Ternary : 542 (Ratio: 1.09%)
Conflict : 49947 (Average Length: 574.5 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 49947 (Average: 17.68 Max: 1328 Sum: 883275)
Executed : 49930 (Average: 17.68 Max: 1328 Sum: 883258 Ratio: 100.00%)
Bounded : 17 (Average: 1.00 Max: 1 Sum: 17 Ratio: 0.00%)
Rules : 483386
Atoms : 483386
Bodies : 1 (Original: 0)
Tight : Yes
Variables : 413377 (Eliminated: 0 Frozen: 413377)
Constraints : 2672947 (Binary: 98.7% Ternary: 0.6% Other: 0.7%)
Memory Peak : 858MB
Max. Length : 10 steps
Models : 0
[endof: stats after solve call]
Solving Time: 42.15s
Memory: 858MB (+135MB)
UNKNOWN
Iteration Time: 45.67s
Iteration 5
Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until: 15
Expected Memory: 1012.0MB
Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])]
Grounding Time: 2.32s
Memory: 875MB (+17MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 0+
Calls : 5
Time : 161.285s (Solving: 64.07s 1st Model: 0.00s Unsat: 8.70s)
CPU Time : 160.248s
Choices : 2141713 (Domain: 2141713)
Conflicts : 78079 (Analyzed: 78078)
Restarts : 277 (Average: 281.87 Last: 201)
Problems : 5 (Average Length: 12.00 Splits: 0)
Lemmas : 78078 (Deleted: 63749)
Binary : 1003 (Ratio: 1.28%)
Ternary : 649 (Ratio: 0.83%)
Conflict : 78078 (Average Length: 945.6 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 78078 (Average: 24.86 Max: 2092 Sum: 1940770)
Executed : 78044 (Average: 24.85 Max: 2092 Sum: 1940442 Ratio: 99.98%)
Bounded : 34 (Average: 9.65 Max: 22 Sum: 328 Ratio: 0.02%)
Rules : 483386
Atoms : 483386
Bodies : 1 (Original: 0)
Tight : Yes
Variables : 561468 (Eliminated: 0 Frozen: 561468)
Constraints : 3880217 (Binary: 98.7% Ternary: 0.6% Other: 0.7%)
Memory Peak : 1159MB
Max. Length : 15 steps
Models : 0
[endof: stats after solve call]
Solving Time: 55.37s
Memory: 1031MB (+156MB)
UNKNOWN
Iteration Time: 59.04s
Iteration 6
Queue: [(5,25,0,True), (6,30,0,True)]
Grounded Until: 20
Expected Memory: 1204.0MB
Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])]
Grounding Time: 2.46s
Memory: 1053MB (+22MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 1+
Calls : 6
Time : 202.138s (Solving: 79.88s 1st Model: 15.74s Unsat: 8.70s)
CPU Time : 201.116s
Choices : 2745931 (Domain: 2745875)
Conflicts : 87363 (Analyzed: 87362)
Restarts : 315 (Average: 277.34 Last: 201)
Model-Level : 2884.0
Problems : 6 (Average Length: 14.50 Splits: 0)
Lemmas : 87362 (Deleted: 63749)
Binary : 1061 (Ratio: 1.21%)
Ternary : 672 (Ratio: 0.77%)
Conflict : 87362 (Average Length: 989.0 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 87362 (Average: 28.16 Max: 2959 Sum: 2460356)
Executed : 87323 (Average: 28.16 Max: 2959 Sum: 2459971 Ratio: 99.98%)
Bounded : 39 (Average: 9.87 Max: 27 Sum: 385 Ratio: 0.02%)
Rules : 483386
Atoms : 483386
Bodies : 1 (Original: 0)
Tight : Yes
Variables : 709559 (Eliminated: 0 Frozen: 709559)
Constraints : 5087361 (Binary: 98.7% Ternary: 0.6% Other: 0.7%)
Memory Peak : 1166MB
Max. Length : 20 steps
Models : 1
[endof: stats after solve call]
Solving Time: 36.99s
Memory: 1166MB (+113MB)
SAT
Testing...
NOT SERIALIZABLE
Testing Time: 11.92s
Memory: 1421MB (+255MB)
Solving...
[start: stats after solve call]
Models : 0+
Calls : 7
Time : 421.343s (Solving: 287.51s 1st Model: 15.74s Unsat: 8.70s)
CPU Time : 420.416s
Choices : 8855404 (Domain: 8855348)
Conflicts : 115430 (Analyzed: 115429)
Restarts : 415 (Average: 278.14 Last: 201)
Model-Level : 2884.0
Problems : 7 (Average Length: 16.29 Splits: 0)
Lemmas : 115429 (Deleted: 98812)
Binary : 1543 (Ratio: 1.34%)
Ternary : 1067 (Ratio: 0.92%)
Conflict : 115429 (Average Length: 892.3 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 115429 (Average: 73.18 Max: 6635 Sum: 8446667)
Executed : 115194 (Average: 73.13 Max: 6635 Sum: 8440990 Ratio: 99.93%)
Bounded : 235 (Average: 24.16 Max: 27 Sum: 5677 Ratio: 0.07%)
Rules : 2205435 (Original: 2205370)
Atoms : 539260
Bodies : 1117283 (Original: 1117217)
Count : 1329 (Original: 1336)
Equivalences : 564178 (Atom=Atom: 43 Body=Body: 0 Other: 564135)
Tight : Yes
Variables : 712343 (Eliminated: 72 Frozen: 712271)
Constraints : 6757686 (Binary: 99.0% Ternary: 0.5% Other: 0.5%)
Memory Peak : 1565MB
Max. Length : 20 steps
Models : 1
[endof: stats after solve call]
Solving Time: 213.07s
Memory: 1501MB (+80MB)
UNKNOWN
Iteration Time: 265.87s
Iteration 7
Queue: [(6,30,0,True)]
Grounded Until: 25
Expected Memory: 1674.0MB
Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])]
Grounding Time: 4.02s
Memory: 1501MB (+0MB)
Unblocking actions...
Solving...
[start: stats after solve call]
Models : 0+
Calls : 8
Time : 695.710s (Solving: 523.36s 1st Model: 15.74s Unsat: 8.70s)
CPU Time : 694.900s
Choices : 14942503 (Domain: 14942447)
Conflicts : 143529 (Analyzed: 143528)
Restarts : 515 (Average: 278.70 Last: 201)
Model-Level : 2884.0
Problems : 8 (Average Length: 18.25 Splits: 0)
Lemmas : 143528 (Deleted: 125792)
Binary : 1729 (Ratio: 1.20%)
Ternary : 1230 (Ratio: 0.86%)
Conflict : 143528 (Average Length: 1000.2 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 143528 (Average: 100.37 Max: 7016 Sum: 14405205)
Executed : 143222 (Average: 100.31 Max: 7016 Sum: 14397256 Ratio: 99.94%)
Bounded : 306 (Average: 25.98 Max: 32 Sum: 7949 Ratio: 0.06%)
Rules : 2205435 (Original: 2205370)
Atoms : 539260
Bodies : 1117283 (Original: 1117217)
Count : 1329 (Original: 1336)
Equivalences : 564178 (Atom=Atom: 43 Body=Body: 0 Other: 564135)
Tight : Yes
Variables : 860994 (Eliminated: 72 Frozen: 860922)
Constraints : 8213763 (Binary: 99.0% Ternary: 0.5% Other: 0.5%)
Memory Peak : 1651MB
Max. Length : 25 steps
Models : 1
[endof: stats after solve call]
Solving Time: 268.70s
Memory: 1572MB (+71MB)
UNKNOWN
Iteration Time: 274.49s
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 : 778.992s (Solving: 606.38s 1st Model: 15.74s Unsat: 8.70s)
CPU Time : 778.216s
Choices : 16234949 (Domain: 16234893)
Conflicts : 171629 (Analyzed: 171628)
Restarts : 615 (Average: 279.07 Last: 201)
Model-Level : 2884.0
Problems : 9 (Average Length: 19.78 Splits: 0)
Lemmas : 171628 (Deleted: 152398)
Binary : 1916 (Ratio: 1.12%)
Ternary : 1354 (Ratio: 0.79%)
Conflict : 171628 (Average Length: 1194.5 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 171628 (Average: 91.26 Max: 7016 Sum: 15663144)
Executed : 171295 (Average: 91.21 Max: 7016 Sum: 15654331 Ratio: 99.94%)
Bounded : 333 (Average: 26.47 Max: 32 Sum: 8813 Ratio: 0.06%)
Rules : 2205435 (Original: 2205370)
Atoms : 539260
Bodies : 1117283 (Original: 1117217)
Count : 1329 (Original: 1336)
Equivalences : 564178 (Atom=Atom: 43 Body=Body: 0 Other: 564135)
Tight : Yes
Variables : 860994 (Eliminated: 72 Frozen: 860922)
Constraints : 8189260 (Binary: 99.0% Ternary: 0.5% Other: 0.5%)
Memory Peak : 1651MB
Max. Length : 30 steps
Models : 1
[endof: stats after solve call]
Solving Time: 83.22s
Memory: 1598MB (+26MB)
UNKNOWN
Iteration Time: 83.32s
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...
*** Info : (planner): INTERRUPTED by signal!
UNKNOWN
INTERRUPTED : 1
Models : 0+
Calls : 10
Time : 861.405s (Solving: 688.54s 1st Model: 15.74s Unsat: 8.70s)
CPU Time : 860.644s
Choices : 17880144 (Domain: 17880088)
Conflicts : 191308 (Analyzed: 191307)
Restarts : 681 (Average: 280.92 Last: 201)
Model-Level : 2884.0
Problems : 10 (Average Length: 21.00 Splits: 0)
Lemmas : 191307 (Deleted: 167968)
Binary : 2062 (Ratio: 1.08%)
Ternary : 1487 (Ratio: 0.78%)
Conflict : 191307 (Average Length: 1104.6 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 191307 (Average: 90.18 Max: 7016 Sum: 17252343)
Executed : 190915 (Average: 90.13 Max: 7016 Sum: 17241642 Ratio: 99.94%)
Bounded : 392 (Average: 27.30 Max: 32 Sum: 10701 Ratio: 0.06%)
Rules : 2205435 (Original: 2205370)
Atoms : 539260
Bodies : 1117283 (Original: 1117217)
Count : 1329 (Original: 1336)
Equivalences : 564178 (Atom=Atom: 43 Body=Body: 0 Other: 564135)
Tight : Yes
Variables : 860994 (Eliminated: 72 Frozen: 860922)
Constraints : 8156694 (Binary: 99.0% Ternary: 0.5% Other: 0.5%)
Memory Peak : 1651MB
Max. Length : 30 steps
Models : 1