tplp-planning-benchmark/gc-ta1-tt1/ipc-2004_promela-optical-telegraph-strips_10.out

369 lines
35 KiB
Plaintext
Raw Normal View History

INFO Running translator.
INFO translator input: ['/home/pluehne/Documents/ASP/pddl-instances/ipc-2004/domains/promela-optical-telegraph-strips/domains/domain-10.pddl', '/home/pluehne/Documents/ASP/pddl-instances/ipc-2004/domains/promela-optical-telegraph-strips/instances/instance-10.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/promela-optical-telegraph-strips/domains/domain-10.pddl /home/pluehne/Documents/ASP/pddl-instances/ipc-2004/domains/promela-optical-telegraph-strips/instances/instance-10.pddl
Parsing...
Parsing: [64.730s CPU, 64.703s wall-clock]
Normalizing task... [3.010s CPU, 3.004s wall-clock]
Instantiating...
Generating Datalog program... [0.740s CPU, 0.740s wall-clock]
Normalizing Datalog program...
Normalizing Datalog program: [1.010s CPU, 1.024s wall-clock]
Preparing model... [1.590s CPU, 1.590s wall-clock]
Generated 7822 rules.
Computing model... [0.730s CPU, 0.726s wall-clock]
4005 relevant atoms
0 auxiliary atoms
4005 final queue length
8031 total queue pushes
Completing instantiation... [4.870s CPU, 4.872s wall-clock]
Instantiating: [9.020s CPU, 9.020s wall-clock]
Computing fact groups...
Finding invariants...
1551 initial candidates
Time limit reached, aborting invariant generation
Finding invariants: [300.560s CPU, 300.428s wall-clock]
Checking invariant weight... [0.000s CPU, 0.004s wall-clock]
Instantiating groups... [0.000s CPU, 0.001s wall-clock]
Collecting mutex groups... [0.010s CPU, 0.002s wall-clock]
Choosing groups...
1485 uncovered facts
Choosing groups: [0.000s CPU, 0.004s wall-clock]
Building translation key... [0.050s CPU, 0.049s wall-clock]
Computing fact groups: [300.750s CPU, 300.621s wall-clock]
Building STRIPS to SAS dictionary... [0.010s CPU, 0.013s wall-clock]
Building dictionary for full mutex groups... [0.020s CPU, 0.014s 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.180s CPU, 0.181s wall-clock]
Translating task: [5.730s CPU, 5.731s wall-clock]
57618 effect conditions simplified
0 implied preconditions added
Detecting unreachable propositions...
0 operators removed
0 axioms removed
165 propositions removed
Detecting unreachable propositions: [3.160s CPU, 3.156s wall-clock]
Reordering and filtering variables...
1452 of 1452 variables necessary.
0 of 33 mutex groups necessary.
2453 of 2453 operators necessary.
0 of 0 axiom rules necessary.
Reordering and filtering variables: [22.110s CPU, 22.104s wall-clock]
Translator variables: 1452
Translator derived variables: 0
Translator facts: 2904
Translator goal facts: 22
Translator mutex groups: 0
Translator total mutex groups size: 0
Translator operators: 2453
Translator axioms: 0
Translator task size: 101992
Translator peak memory: 218968 KB
Writing output... [1.860s CPU, 1.979s wall-clock]
Done! [410.560s CPU, 410.507s wall-clock]
planner.py version 0.0.1
Time: 2.72s
Memory: 159MB
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.02s
Memory: 159MB (+0MB)
UNSAT
Iteration Time: 0.02s
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: 159MB
Grounding... [('step', [1]), ('step', [2]), ('step', [3]), ('step', [4]), ('step', [5]), ('check', [5])]
Grounding Time: 1.45s
Memory: 269MB (+110MB)
Unblocking actions...
Solving...
Solving Time: 0.08s
Memory: 278MB (+9MB)
UNSAT
Iteration Time: 1.98s
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: 397.0MB
Grounding... [('step', [6]), ('step', [7]), ('step', [8]), ('step', [9]), ('step', [10]), ('check', [10])]
Grounding Time: 1.77s
Memory: 360MB (+82MB)
Unblocking actions...
Solving...
Solving Time: 0.53s
Memory: 376MB (+16MB)
UNSAT
Iteration Time: 2.80s
Iteration 4
Queue: [(3,15,0,True), (4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until: 10
Expected Memory: 495.0MB
Grounding... [('step', [11]), ('step', [12]), ('step', [13]), ('step', [14]), ('step', [15]), ('check', [15])]
Grounding Time: 1.96s
Memory: 445MB (+69MB)
Unblocking actions...
Solving...
Solving Time: 4.26s
Memory: 465MB (+20MB)
UNKNOWN
Iteration Time: 6.81s
Iteration 5
Queue: [(4,20,0,True), (5,25,0,True), (6,30,0,True)]
Grounded Until: 15
Expected Memory: 584.0MB
Grounding... [('step', [16]), ('step', [17]), ('step', [18]), ('step', [19]), ('step', [20]), ('check', [20])]
Grounding Time: 1.60s
Memory: 503MB (+38MB)
Unblocking actions...
Solving...
Solving Time: 3.48s
Memory: 532MB (+29MB)
SAT
Testing...
NOT SERIALIZABLE
Testing Time: 2.94s
Memory: 550MB (+18MB)
Solving...
Solving Time: 15.44s
Memory: 561MB (+11MB)
UNKNOWN
Iteration Time: 24.09s
Iteration 6
Queue: [(5,25,0,True), (6,30,0,True)]
Grounded Until: 20
Expected Memory: 680.0MB
Grounding... [('step', [21]), ('step', [22]), ('step', [23]), ('step', [24]), ('step', [25]), ('check', [25])]
Grounding Time: 2.06s
Memory: 631MB (+70MB)
Unblocking actions...
Solving...
Solving Time: 18.66s
Memory: 664MB (+33MB)
UNKNOWN
Iteration Time: 21.53s
Iteration 7
Queue: [(6,30,0,True)]
Grounded Until: 25
Expected Memory: 783.0MB
Grounding... [('step', [26]), ('step', [27]), ('step', [28]), ('step', [29]), ('step', [30]), ('check', [30])]
Grounding Time: 2.83s
Memory: 759MB (+95MB)
Unblocking actions...
Solving...
Solving Time: 20.88s
Memory: 799MB (+40MB)
UNKNOWN
Iteration Time: 24.42s
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: 13.80s
Memory: 799MB (+0MB)
UNKNOWN
Iteration Time: 13.84s
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: 17.72s
Memory: 799MB (+0MB)
UNKNOWN
Iteration Time: 17.76s
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: 20.31s
Memory: 799MB (+0MB)
UNKNOWN
Iteration Time: 20.34s
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: 20.96s
Memory: 799MB (+0MB)
UNKNOWN
Iteration Time: 20.99s
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: 934.0MB
Grounding... [('step', [31]), ('step', [32]), ('step', [33]), ('step', [34]), ('step', [35]), ('check', [35])]
Grounding Time: 2.03s
Memory: 851MB (+52MB)
Unblocking actions...
Solving...
Solving Time: 20.61s
Memory: 868MB (+17MB)
UNKNOWN
Iteration Time: 23.39s
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: 1003.0MB
Grounding... [('step', [36]), ('step', [37]), ('step', [38]), ('step', [39]), ('step', [40]), ('check', [40])]
Grounding Time: 2.09s
Memory: 928MB (+60MB)
Unblocking actions...
Solving...
Solving Time: 27.05s
Memory: 981MB (+53MB)
UNKNOWN
Iteration Time: 29.94s
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: 1116.0MB
Grounding... [('step', [41]), ('step', [42]), ('step', [43]), ('step', [44]), ('step', [45]), ('check', [45])]
Grounding Time: 2.12s
Memory: 1023MB (+42MB)
Unblocking actions...
Solving...
Solving Time: 32.12s
Memory: 1043MB (+20MB)
UNKNOWN
Iteration Time: 35.07s
Iteration 15
Queue: [(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: 45
Expected Memory: 1178.0MB
Grounding... [('step', [46]), ('step', [47]), ('step', [48]), ('step', [49]), ('step', [50]), ('check', [50])]
Grounding Time: 2.17s
Memory: 1115MB (+72MB)
Unblocking actions...
Solving...
Solving Time: 37.92s
Memory: 1135MB (+20MB)
UNKNOWN
Iteration Time: 40.96s
Iteration 16
Queue: [(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: 50
Expected Memory: 1270.0MB
Grounding... [('step', [51]), ('step', [52]), ('step', [53]), ('step', [54]), ('step', [55]), ('check', [55])]
Grounding Time: 2.20s
Memory: 1198MB (+63MB)
Unblocking actions...
Solving...
Solving Time: 35.27s
Memory: 1283MB (+85MB)
UNKNOWN
Iteration Time: 38.40s
Iteration 17
Queue: [(12,60,0,True), (13,65,0,True), (14,70,0,True), (15,75,0,True), (16,80,0,True)]
Grounded Until: 55
Expected Memory: 1431.0MB
Grounding... [('step', [56]), ('step', [57]), ('step', [58]), ('step', [59]), ('step', [60]), ('check', [60])]
Grounding Time: 2.25s
Memory: 1304MB (+21MB)
Unblocking actions...
Solving...
Solving Time: 45.49s
Memory: 1330MB (+26MB)
UNKNOWN
Iteration Time: 48.69s
Iteration 18
Queue: [(13,65,0,True), (14,70,0,True), (15,75,0,True), (16,80,0,True)]
Grounded Until: 60
Expected Memory: 1478.0MB
Grounding... [('step', [61]), ('step', [62]), ('step', [63]), ('step', [64]), ('step', [65]), ('check', [65])]
Grounding Time: 3.70s
Memory: 1490MB (+160MB)
Unblocking actions...
Solving...
Solving Time: 43.27s
Memory: 1508MB (+18MB)
UNKNOWN
Iteration Time: 47.96s
Iteration 19
Queue: [(14,70,0,True), (15,75,0,True), (16,80,0,True)]
Grounded Until: 65
Expected Memory: 1686.0MB
Grounding... [('step', [66]), ('step', [67]), ('step', [68]), ('step', [69]), ('step', [70]), ('check', [70])]
Grounding Time: 2.11s
Memory: 1575MB (+67MB)
Unblocking actions...
Solving...
Solving Time: 31.68s
Memory: 1605MB (+30MB)
SAT
Testing...
SERIALIZABLE?
Testing Time: 0.00s
Memory: 1605MB (+0MB)
Answer: 2
occurs(action("activate-trans-down_station-0-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-1-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-10-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-2-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-3-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-4-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-5-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-6-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-7-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-8-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-down_station-9-down_station-station-control-_-pid_11_-wcontrol-state-25-state-14-0"),1) occurs(action("activate-trans-up_station-11-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-12-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-13-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-14-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-15-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-16-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-17-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-18-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-19-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-20-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("activate-trans-up_station-21-up_station-up-__-pidp1__11_-rstart-state-25-state-2-0"),1) occurs(action("queue-write-down_station-0-station-control-_-pid_11_-wcontrol-station-control-0--control-0"),2) occurs(action("queue-write-down_station-1-station-control-_-pid_11_-wcontrol-station-control-1--control-0"),2) occurs(action("queue-write-down_station-10-station-control-_-pid_11_-wcontrol-station-control-10--control-0"),2) occurs(action("queue-write-down_station-2-station-control-_-pid_11_-wcontrol-station-control-2--control-0"),2) occurs(action("queue-write-down_station-3-station-control-_-pid_11_-wcontrol-station-control-3--control-0"),2) occurs(action("queue-write-down_station-4-station-control-_-pid_11_-wcontrol-station-control-4--control-0"),2) occurs(action("queue-write-down_station-5-station-control-_-pid_11_-wcontrol-station-control-5--control-0"),2) occurs(action("queue-write-down_station-6-station-control-_-pid_11_-wcontrol-station-control-6--control-0"),2) occurs(action("queue-write-down_station-7-station-control-_-pid_11_-wcontrol-station-control-7--control-0"),2) occurs(action("queue-write-down_station-8-station-control-_-pid_11_-wcontrol-station-control-8--control-0"),2) occurs(action("queue-write-down_station-9-station-control-_-pid_11_-wcontrol-station-control-9--control-0"),2) occurs(action("advance-empty-queue-tail-station-control-0--queue-1-qs-0-qs-0-control-empty-zero-one-0"),3) occurs(action("advance-empty-queue-tail-station-control-1--queue-1-qs-0-qs-0-control-empty-zero-one-0"),3) occurs(action("advance-empty-queue-tail-station-control-2--queue-1-qs-0-qs-0-control-empty-zero-one-0"),3) occurs(action("advance-empty-queue-tail-station-control-3--queue-1-qs-0-qs-0-control-empty-zero-one-0"),3) occurs(actio
SATISFIABLE
Models : 1+
Calls : 20
Time : 454.598s (Solving: 406.19s 1st Model: 34.67s Unsat: 0.44s)
CPU Time : 454.640s
Choices : 5020917 (Domain: 4986049)
Conflicts : 149908 (Analyzed: 149906)
Restarts : 1628 (Average: 92.08 Last: 160)
Model-Level : 1679.0
Problems : 20 (Average Length: 35.25 Splits: 0)
Lemmas : 149906 (Deleted: 131414)
Binary : 4117 (Ratio: 2.75%)
Ternary : 2180 (Ratio: 1.45%)
Conflict : 149906 (Average Length: 168.2 Ratio: 100.00%)
Loop : 0 (Average Length: 0.0 Ratio: 0.00%)
Other : 0 (Average Length: 0.0 Ratio: 0.00%)
Backjumps : 149906 (Average: 31.39 Max: 1732 Sum: 4705585)
Executed : 149739 (Average: 31.37 Max: 1732 Sum: 4702425 Ratio: 99.93%)
Bounded : 167 (Average: 18.92 Max: 42 Sum: 3160 Ratio: 0.07%)
Rules : 531971 (Original: 504474)
Atoms : 327744
Bodies : 224296 (Original: 204323)
Count : 1150 (Original: 6819)
Equivalences : 20210 (Atom=Atom: 2563 Body=Body: 0 Other: 17647)
Tight : Yes
Variables : 813845 (Eliminated: 0 Frozen: 140894)
Constraints : 8948896 (Binary: 94.6% Ternary: 3.8% Other: 1.6%)
Memory Peak : 1693MB
Max. Length : 70 steps
Sol. Length : 70 steps
Models : 2