INFO     Running translator.
INFO     translator input: ['/home/pluehne/Documents/ASP/pddl-instances/ipc-2004/domains/pipesworld-no-tankage-nontemporal-strips/domain.pddl', '/home/pluehne/Documents/ASP/pddl-instances/ipc-2004/domains/pipesworld-no-tankage-nontemporal-strips/instances/instance-32.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-2004/domains/pipesworld-no-tankage-nontemporal-strips/domain.pddl /home/pluehne/Documents/ASP/pddl-instances/ipc-2004/domains/pipesworld-no-tankage-nontemporal-strips/instances/instance-32.pddl
Parsing...
Parsing: [0.040s CPU, 0.039s wall-clock]
Normalizing task... [0.000s CPU, 0.003s wall-clock]
Instantiating...
Generating Datalog program... [0.000s CPU, 0.007s wall-clock]
Normalizing Datalog program...
Normalizing Datalog program: [0.070s CPU, 0.064s wall-clock]
Preparing model... [0.020s CPU, 0.022s wall-clock]
Generated 91 rules.
Computing model... [0.630s CPU, 0.632s wall-clock]
2950 relevant atoms
4556 auxiliary atoms
7506 final queue length
14583 total queue pushes
Completing instantiation... [1.200s CPU, 1.201s wall-clock]
Instantiating: [1.940s CPU, 1.934s wall-clock]
Computing fact groups...
Finding invariants...
18 initial candidates
Finding invariants: [0.120s CPU, 0.119s wall-clock]
Checking invariant weight... [0.000s CPU, 0.001s wall-clock]
Instantiating groups... [0.000s CPU, 0.000s wall-clock]
Collecting mutex groups... [0.000s CPU, 0.000s wall-clock]
Choosing groups...
345 uncovered facts
Choosing groups: [0.000s CPU, 0.001s wall-clock]
Building translation key... [0.020s CPU, 0.012s wall-clock]
Computing fact groups: [0.160s CPU, 0.158s wall-clock]
Building STRIPS to SAS dictionary... [0.000s CPU, 0.003s wall-clock]
Building dictionary for full mutex groups... [0.000s CPU, 0.003s wall-clock]
Building mutex information...
Building mutex information: [0.000s CPU, 0.002s wall-clock]
Translating task...
Processing axioms...
Simplifying axioms... [0.000s CPU, 0.000s wall-clock]
Processing axioms: [0.050s CPU, 0.047s wall-clock]
Translating task: [1.120s CPU, 1.114s wall-clock]
5068 effect conditions simplified
0 implied preconditions added
Detecting unreachable propositions...
0 operators removed
0 axioms removed
7 propositions removed
Detecting unreachable propositions: [0.670s CPU, 0.667s wall-clock]
Reordering and filtering variables...
348 of 348 variables necessary.
0 of 3 mutex groups necessary.
2470 of 2470 operators necessary.
0 of 0 axiom rules necessary.
Reordering and filtering variables: [0.780s CPU, 0.780s wall-clock]
Translator variables: 348
Translator derived variables: 0
Translator facts: 699
Translator goal facts: 5
Translator mutex groups: 0
Translator total mutex groups size: 0
Translator operators: 2470
Translator axioms: 0
Translator task size: 22862
Translator peak memory: 50140 KB
Writing output... [0.400s CPU, 0.438s wall-clock]
Done! [5.160s CPU, 5.189s wall-clock]
planner.py version 0.0.1

Time:	 0.95s
Memory: 113MB

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...
Solving Time:	 0.00s
Memory:		 114MB (+1MB)
UNSAT
Iteration Time:	 0.00s

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: 114MB
Grounding...	 [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])]
Grounding Time:	 0.31s
Memory:		 114MB (+0MB)
Unblocking actions...
Solving...
Solving Time:	 0.02s
Memory:		 117MB (+3MB)
UNSAT
Iteration Time:	 0.47s

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: 120.0MB
Grounding...	 [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])]
Grounding Time:	 0.38s
Memory:		 129MB (+12MB)
Unblocking actions...
Solving...
Solving Time:	 0.50s
Memory:		 139MB (+10MB)
SAT
Testing...
NOT SERIALIZABLE
Testing Time:	 1.07s
Memory:		 178MB (+39MB)
Solving...
Solving Time:	 1.93s
Memory:		 173MB (+-5MB)
UNSAT
Iteration Time:	 4.07s

Iteration 4
Queue:		 [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 10
Expected Memory: 195.0MB
Grounding...	 [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])]
Grounding Time:	 0.67s
Memory:		 177MB (+4MB)
Unblocking actions...
Solving...
Solving Time:	 6.02s
Memory:		 194MB (+17MB)
UNKNOWN
Iteration Time:	 6.89s

Iteration 5
Queue:		 [(4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until:	 15
Expected Memory: 216.0MB
Grounding...	 [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])]
Grounding Time:	 0.60s
Memory:		 208MB (+14MB)
Unblocking actions...
Solving...
Solving Time:	 8.06s
Memory:		 224MB (+16MB)
UNKNOWN
Iteration Time:	 8.87s

Iteration 6
Queue:		 [(5,25,0,True), (6,30,0,True)]
Grounded Until:	 20
Expected Memory: 254.0MB
Grounding...	 [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])]
Grounding Time:	 0.62s
Memory:		 240MB (+16MB)
Unblocking actions...
Solving...
Solving Time:	 5.91s
Memory:		 250MB (+10MB)
UNKNOWN
Iteration Time:	 6.75s

Iteration 7
Queue:		 [(6,30,0,True)]
Grounded Until:	 25
Expected Memory: 280.0MB
Grounding...	 [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])]
Grounding Time:	 0.64s
Memory:		 266MB (+16MB)
Unblocking actions...
Solving...
Solving Time:	 11.60s
Memory:		 342MB (+76MB)
UNKNOWN
Iteration Time:	 12.48s

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...
Solving Time:	 7.03s
Memory:		 342MB (+0MB)
UNKNOWN
Iteration Time:	 7.05s

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...
Solving Time:	 7.35s
Memory:		 342MB (+0MB)
UNKNOWN
Iteration Time:	 7.37s

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...
Solving Time:	 9.03s
Memory:		 342MB (+0MB)
UNKNOWN
Iteration Time:	 9.05s

Iteration 11
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)]
Grounded Until:	 30
Unblocking actions...
Solving...
Solving Time:	 10.43s
Memory:		 342MB (+0MB)
UNKNOWN
Iteration Time:	 10.45s

Iteration 12
Queue:		 [(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
Expected Memory: 434.0MB
Grounding...	 [('step', [31]), ('step', [32]), ('step', [33]), ('step', [34]), ('step', [35]), ('check', [35])]
Grounding Time:	 0.78s
Memory:		 342MB (+0MB)
Unblocking actions...
Solving...
Solving Time:	 8.86s
Memory:		 356MB (+14MB)
UNKNOWN
Iteration Time:	 9.91s

Iteration 13
Queue:		 [(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:	 35
Expected Memory: 448.0MB
Grounding...	 [('step', [36]), ('step', [37]), ('step', [38]), ('step', [39]), ('step', [40]), ('check', [40])]
Grounding Time:	 0.62s
Memory:		 356MB (+0MB)
Unblocking actions...
Solving...
Solving Time:	 16.92s
Memory:		 431MB (+75MB)
UNKNOWN
Iteration Time:	 17.82s

Iteration 14
Queue:		 [(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:	 40
Expected Memory: 523.0MB
Grounding...	 [('step', [41]), ('step', [42]), ('step', [43]), ('step', [44]), ('step', [45]), ('check', [45])]
Grounding Time:	 0.62s
Memory:		 431MB (+0MB)
Unblocking actions...
Solving...
Solving Time:	 13.92s
Memory:		 483MB (+52MB)
SAT
Testing...
SERIALIZABLE?
Testing Time:	 0.00s
Memory:		 483MB (+0MB)
Answer: 2
occurs(action(("push-start","s12","b1","a1","a2","b5","gasoleo","rat-a")),1) occurs(action(("push-start","s23","b12","a2","a3","b2","gasoleo","rat-a")),1) occurs(action(("pop-unitarypipe","s34","b0","a3","a4","b3","oca1","gasoleo")),1) occurs(action(("push-end","s23","a2","a3","b11","b13")),2) occurs(action(("push-end","s12","a1","a2","b7","b5")),2) occurs(action(("push-start","s12","b4","a1","a2","b1","oc1b","gasoleo")),3) occurs(action(("push-start","s23","b7","a2","a3","b12","oc1b","gasoleo")),3) occurs(action(("pop-start","s13","b11","a1","a3","b9","gasoleo","oc1b")),3) occurs(action(("push-end","s12","a1","a2","b5","b1")),4) occurs(action(("push-end","s23","a2","a3","b13","b2")),4) occurs(action(("pop-end","s13","a1","a3","b10","b9")),4) occurs(action(("push-start","s12","b10","a1","a2","b4","oca1","oc1b")),5) occurs(action(("pop-start","s13","b6","a1","a3","b11","gasoleo","gasoleo")),5) occurs(action(("push-unitarypipe","s34","b13","a3","a4","b0","lco","oca1")),5) occurs(action(("push-end","s12","a1","a2","b1","b4")),6) occurs(action(("pop-end","s13","a1","a3","b9","b11")),6) occurs(action(("push-start","s12","b8","a1","a2","b10","lco","oca1")),7) occurs(action(("pop-start","s13","b3","a1","a3","b6","gasoleo","gasoleo")),7) occurs(action(("pop-end","s13","a1","a3","b11","b6")),8) occurs(action(("push-end","s12","a1","a2","b4","b10")),8) occurs(action(("push-start","s13","b9","a1","a3","b6","oc1b","gasoleo")),9) occurs(action(("push-start","s23","b4","a2","a3","b7","oc1b","oc1b")),9) occurs(action(("push-end","s23","a2","a3","b2","b12")),10) occurs(action(("push-end","s13","a1","a3","b3","b6")),10) occurs(action(("push-unitarypipe","s34","b3","a3","a4","b13","gasoleo","lco")),11) occurs(action(("pop-unitarypipe","s34","b0","a3","a4","b3","oca1","gasoleo")),12) occurs(action(("pop-start","s13","b3","a1","a3","b6","gasoleo","gasoleo")),13) occurs(action(("pop-end","s13","a1","a3","b9","b6")),14) occurs(action(("push-start","s12","b9","a1","a2","b8","oc1b","lco")),15) occurs(action(("push-end","s12","a1","a2","b10","b8")),16) occurs(action(("push-start","s23","b10","a2","a3","b4","oca1","oc1b")),17) occurs(action(("push-end","s23","a2","a3","b12","b7")),18) occurs(action(("pop-start","s13","b12","a1","a3","b3","gasoleo","gasoleo")),19) occurs(action(("pop-end","s13","a1","a3","b6","b3")),20) occurs(action(("push-start","s12","b6","a1","a2","b9","gasoleo","oc1b")),21) occurs(action(("push-end","s12","a1","a2","b8","b9")),22) occurs(action(("push-start","s23","b8","a2","a3","b10","lco","oca1")),23) occurs(action(("push-end","s23","a2","a3","b7","b4")),24) occurs(action(("push-start","s23","b5","a2","a3","b8","rat-a","lco")),25) occurs(action(("push-end","s23","a2","a3","b4","b10")),26) occurs(action(("push-unitarypipe","s34","b4","a3","a4","b0","oc1b","oca1")),27) occurs(action(("push-unitarypipe","s34","b7","a3","a4","b4","oc1b","oc1b")),28) occurs(action(("pop-unitarypipe","s34","b0","a3","a4","b7","oca1","oc1b")),29) occurs(action(("push-unitarypipe","s34","b7","a3","a4","b0","oc1b","oca1")),30) occurs(action(("pop-unitarypipe","s34","b0","a3","a4","b7","oca1","oc1b")),31) occurs(action(("pop-start","s23","b7","a2","a3","b10","oc1b","oca1")),32) occurs(action(("pop-end","s23","a2","a3","b5","b8")),33) occurs(action(("push-start","s23","b5","a2","a3","b8","rat-a","lco")),34) occurs(action(("push-end","s23","a2","a3","b7","b10")),35) occurs(action(("pop-start","s23","b7","a2","a3","b10","oc1b","oca1")),36) occurs(action(("pop-end","s23","a2","a3","b5","b8")),37) occurs(action(("push-start","s23","b5","a2","a3","b8","rat-a","lco")),38) occurs(action(("push-end","s23","a2","a3","b7","b10")),39) occurs(action(("pop-start","s13","b7","a1","a3","b12","oc1b","gasoleo")),40) occurs(action(("pop-end","s13","a1","a3","b3","b12")),41) occurs(action(("push-start","s13","b3","a1","a3","b12","gasoleo","gasoleo")),42) occurs(action(("push-end","s13","a1","a3","b7","b12")),43) occurs(action(("pop-start","s23","b7","a2","a3","b10","oc1b","oca1")),44) occurs(action(("pop-end","s23","a2","a3","b5","b8")),45)
SATISFIABLE

Models       : 1+
Calls        : 15
Time         : 116.185s (Solving: 107.00s 1st Model: 14.30s Unsat: 1.88s)
CPU Time     : 116.168s

Choices      : 364118   (Domain: 315009)
Conflicts    : 104574   (Analyzed: 104573)
Restarts     : 1126     (Average: 92.87 Last: 179)
Model-Level  : 187.0   
Problems     : 15       (Average Length: 25.67 Splits: 0)
Lemmas       : 104573   (Deleted: 99612)
  Binary     : 356      (Ratio:   0.34%)
  Ternary    : 299      (Ratio:   0.29%)
  Conflict   : 104573   (Average Length: 2346.3 Ratio: 100.00%) 
  Loop       : 0        (Average Length:    0.0 Ratio:   0.00%) 
  Other      : 0        (Average Length:    0.0 Ratio:   0.00%) 
Backjumps    : 104573   (Average:  2.60 Max: 466 Sum: 272275)
  Executed   : 104374   (Average:  2.57 Max: 466 Sum: 269012 Ratio:  98.80%)
  Bounded    : 199      (Average: 16.40 Max:  32 Sum:   3263 Ratio:   1.20%)

Rules        : 119778   (Original: 117913)
Atoms        : 83552   
Bodies       : 20953    (Original: 19179)
  Count      : 1890     (Original: 2164)
Equivalences : 14437    (Atom=Atom: 51 Body=Body: 1 Other: 14385)
Tight        : Yes
Variables    : 216789   (Eliminated:    0 Frozen: 112806)
Constraints  : 1855062  (Binary:  95.3% Ternary:   1.7% Other:   3.0%)

Memory Peak  : 483MB
Max. Length  : 45 steps
Sol. Length  : 45 steps
Models       : 2