Trace number 3688364\nSAT 4j PB RES // CP 2.3.2 Snapshot ? (TO) 1800.48 900.327\n0.11/0.10\tc solving HOME/instance-3688364-1337876964.opb\n5.81/4.26\tc ... done. Wall clock time 4.156s.\n5.81/4.26\tc declared #vars     126251\n5.81/4.26\tc #constraints  376502\n5.81/4.26\tc constraints type\n5.81/4.26\tc org.sat4j.minisat.constraints.cnf.OriginalWLClause => 125500\n5.81/4.26\tc org.sat4j.minisat.constraints.cnf.OriginalBinaryClause => 251000\n5.81/4.26\tc org.sat4j.pb.constraints.pb.MaxWatchPb => 2\n5.81/4.26\tc 376502 constraints processed.\n5.81/4.26\tc org.sat4j.pb.constraints.pb.OriginalBinaryClausePB => 251000\n5.81/4.26\tc org.sat4j.pb.constraints.pb.OriginalHTClausePB => 125500\n5.81/4.26\tc objective function length is 251 literals\n1800.03/900.31\tc starts\t\t: 5\n1800.03/900.31\tc conflicts\t\t: 453\n1800.03/900.31\tc decisions\t\t: 3586\n1800.03/900.31\tc propagations\t\t: 1172223\n1800.03/900.31\tc inspects\t\t: 4191608\n1800.03/900.31\tc shortcuts\t\t: 0\n1800.03/900.31\tc learnt literals\t: 0\n1800.03/900.31\tc learnt binary clauses\t: 0\n1800.03/900.31\tc learnt ternary clauses\t: 0\n1800.03/900.31\tc learnt constraints\t: 453\n1800.03/900.31\tc ignored constraints\t: 0\n1800.03/900.31\tc root simplifications\t: 0\n1800.03/900.31\tc removed literals (reason simplification)\t: 12152437\n1800.03/900.31\tc reason swapping (by a shorter reason)\t: 0\n1800.03/900.31\tc Calls to reduceDB\t: 0\n1800.03/900.31\tc number of reductions to clauses (during analyze)\t: 0\n1800.03/900.31\tc number of learned constraints concerned by reduction\t: 0\n1800.03/900.31\tc number of learning phase by resolution\t: 0\n1800.03/900.31\tc number of learning phase by cutting planes\t: 0\n1800.03/900.31\tc speed (assignments/second)\t: 1308.3119973392322\n1800.03/900.31\tc non guided choices\t223\n1800.03/900.31\tc learnt constraints type\n1800.03/900.31\tc org.sat4j.minisat.constraints.cnf.LearntWLClause => 453\n1800.03/900.31\tc constraints type\n1800.03/900.31\tc org.sat4j.minisat.constraints.cnf.OriginalWLClause => 125500\n1800.03/900.31\tc org.sat4j.minisat.constraints.cnf.OriginalBinaryClause => 251000\n1800.03/900.31\tc org.sat4j.pb.constraints.pb.MaxWatchPb => 2\n1800.03/900.31\tc 376502 constraints processed.\n1800.03/900.31\tc org.sat4j.pb.constraints.pb.OriginalBinaryClausePB => 251000\n1800.03/900.31\tc org.sat4j.pb.constraints.pb.OriginalHTClausePB => 125500\n1800.03/900.31\ts UNKNOWN\n1800.03/900.31\tc Total wall clock time (in seconds): 900.209\ncommand line: BIN/runsolver --timestamp -w /tmp/evaluation-result-3688364-1337876964/watcher-3688364-1337876964 -o /tmp/evaluation-result-3688364-1337876964/solver-3688364-1337876964 -C 1800 -W 1900 -M 15500 java -jar HOME/sat4j-pb.jar Both HOME/instance-3688364-1337876964.opb\n/proc/loadavg: 3.22 3.59 3.75 4/197 32356\n/proc/meminfo: memFree=27829708/32873648 swapFree=7104/7104\n[pid=32356] ppid=32354 vsize=23172 CPUtime=0 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) R 32354 32356 30487 0 -1 4202496 1135 0 0 0 0 0 0 0 20 0 1 0 253821938 23728128 856 33554432000 1073741824 1073778376 140735783809552 140735783798616 214528257879 0 0 4096 0 0 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 5793 862 652 9 0 128 0\n[pid=32356/tid=32357] ppid=32354 vsize=24200 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 2 0 253821939 24780800 869 33554432000 1073741824 1073778376 140735783809552 140735783798616 214537039824 0 0 4096 0 0 0 0 -1 3 0 0 0 0 0\n[pid=32356] ppid=32354 vsize=9558412 CPUtime=0.09 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 4386 0 1 0 8 1 0 0 20 0 18 0 253821938 9787813888 6080 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2389603 6081 2109 9 0 2380296 0\n[pid=32356/tid=32357] ppid=32354 vsize=9558412 CPUtime=0.07 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 2410 0 1 0 5 2 0 0 20 0 18 0 253821939 9787813888 6081 33554432000 1073741824 1073778376 140735783809552 140001294398200 214537047956 0 4 0 16800975 0 0 0 -1 3 0 0 0 0 0\n[pid=32356] ppid=32354 vsize=9558412 CPUtime=0.11 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 4490 0 1 0 10 1 0 0 20 0 18 0 253821938 9787813888 6146 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2389603 6146 2142 9 0 2380296 0\n[pid=32356/tid=32357] ppid=32354 vsize=9558412 CPUtime=0.08 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 2430 0 1 0 6 2 0 0 20 0 18 0 253821939 9787813888 6146 33554432000 1073741824 1073778376 140735783809552 140001294398200 214540805389 0 4 0 16800975 0 0 0 -1 3 0 0 0 0 0\n[pid=32356] ppid=32354 vsize=9558412 CPUtime=0.53 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 6191 0 1 0 52 1 0 0 20 0 18 0 253821938 9787813888 10841 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2389603 10841 2218 9 0 2380296 0\n[pid=32356/tid=32357] ppid=32354 vsize=9558412 CPUtime=0.28 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 2448 0 1 0 26 2 0 0 20 0 18 0 253821939 9787813888 10841 33554432000 1073741824 1073778376 140735783809552 140001294396488 140001119681669 0 4 0 16800975 0 0 0 -1 3 0 0 0 0 0\n[pid=32356] ppid=32354 vsize=9558412 CPUtime=1.52 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 14001 0 1 0 145 7 0 0 20 0 18 0 253821938 9787813888 48570 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2389603 48570 2246 9 0 2380296 0\n[pid=32356/tid=32357] ppid=32354 vsize=9558412 CPUtime=0.65 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 2507 0 1 0 61 4 0 0 20 0 18 0 253821939 9787813888 48570 33554432000 1073741824 1073778376 140735783809552 140001294396488 214540805389 0 4 0 16800975 0 0 0 -1 3 0 0 0 0 0\n/proc/loadavg: 3.22 3.59 3.75 4/215 32374\n/proc/meminfo: memFree=27610428/32873648 swapFree=7104/7104\n[pid=32356] ppid=32354 vsize=9558412 CPUtime=2.65 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 14789 0 1 0 246 19 0 0 20 0 18 0 253821938 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2389603 96256 2246 9 0 2380296 0\n[pid=32356/tid=32357] ppid=32354 vsize=9558412 CPUtime=1.33 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 2562 0 1 0 128 5 0 0 20 0 18 0 253821939 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001294396504 140001120125074 0 4 0 16800975 0 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32359] ppid=32354 vsize=9558412 CPUtime=0.04 cores=1,3,5,7\n/proc/32356/task/32359/stat : 32359 (java) S 32354 32356 30487 0 -1 4202560 21 0 0 0 4 0 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001255889448 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32360] ppid=32354 vsize=9558412 CPUtime=0.05 cores=1,3,5,7\n/proc/32356/task/32360/stat : 32360 (java) S 32354 32356 30487 0 -1 4202560 107 0 0 0 4 1 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001254836648 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32361] ppid=32354 vsize=9558412 CPUtime=0.06 cores=1,3,5,7\n/proc/32356/task/32361/stat : 32361 (java) S 32354 32356 30487 0 -1 4202560 95 0 0 0 4 2 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001253783848 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32362] ppid=32354 vsize=9558412 CPUtime=0.07 cores=1,3,5,7\n/proc/32356/task/32362/stat : 32362 (java) S 32354 32356 30487 0 -1 4202560 156 0 0 0 5 2 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001252731048 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32363] ppid=32354 vsize=9558412 CPUtime=0.04 cores=1,3,5,7\n/proc/32356/task/32363/stat : 32363 (java) S 32354 32356 30487 0 -1 4202560 119 0 0 0 2 2 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001251679272 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32364] ppid=32354 vsize=9558412 CPUtime=0.05 cores=1,3,5,7\n/proc/32356/task/32364/stat : 32364 (java) S 32354 32356 30487 0 -1 4202560 92 0 0 0 3 2 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001250626472 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32365] ppid=32354 vsize=9558412 CPUtime=0.05 cores=1,3,5,7\n/proc/32356/task/32365/stat : 32365 (java) S 32354 32356 30487 0 -1 4202560 34 0 0 0 3 2 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001249573672 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32366] ppid=32354 vsize=9558412 CPUtime=0.07 cores=1,3,5,7\n/proc/32356/task/32366/stat : 32366 (java) S 32354 32356 30487 0 -1 4202560 44 0 0 0 5 2 0 0 20 0 18 0 253821940 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001248520872 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32367] ppid=32354 vsize=9558412 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32367/stat : 32367 (java) S 32354 32356 30487 0 -1 4202560 196 0 0 0 0 0 0 0 20 0 18 0 253821941 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001118509544 214540793769 0 0 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32368] ppid=32354 vsize=9558412 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32368/stat : 32368 (java) S 32354 32356 30487 0 -1 4202560 17 0 0 0 0 0 0 0 20 0 18 0 253821941 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001117455608 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32369] ppid=32354 vsize=9558412 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32369/stat : 32369 (java) S 32354 32356 30487 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 18 0 253821941 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001116402696 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32370] ppid=32354 vsize=9558412 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32370/stat : 32370 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 18 0 253821943 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001115351184 214540801824 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32371] ppid=32354 vsize=9558412 CPUtime=0.36 cores=1,3,5,7\n/proc/32356/task/32371/stat : 32371 (java) S 32354 32356 30487 0 -1 4202560 4781 0 0 0 36 0 0 0 20 0 18 0 253821943 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001114299320 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32372] ppid=32354 vsize=9558412 CPUtime=0.37 cores=1,3,5,7\n/proc/32356/task/32372/stat : 32372 (java) S 32354 32356 30487 0 -1 4202560 5409 0 0 0 37 0 0 0 20 0 18 0 253821943 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001113246520 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32373] ppid=32354 vsize=9558412 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32373/stat : 32373 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 18 0 253821943 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001112193912 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32374] ppid=32354 vsize=9558412 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32374/stat : 32374 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 18 0 253821943 9787813888 96256 33554432000 1073741824 1073778376 140735783809552 140001111141320 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n/proc/meminfo: memFree=27412452/32873648 swapFree=7104/7104\n[pid=32356] ppid=32354 vsize=9558412 CPUtime=4.48 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 15010 0 1 0 423 25 0 0 20 0 18 0 253821938 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2389603 108674 2248 9 0 2380296 0\n[pid=32356/tid=32357] ppid=32354 vsize=9558412 CPUtime=2.85 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) R 32354 32356 30487 0 -1 4202560 2563 0 1 0 280 5 0 0 20 0 18 0 253821939 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001294389184 214540805389 0 4 0 16800975 0 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32359] ppid=32354 vsize=9558412 CPUtime=0.07 cores=1,3,5,7\n/proc/32356/task/32359/stat : 32359 (java) S 32354 32356 30487 0 -1 4202560 37 0 0 0 5 2 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001255889448 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32360] ppid=32354 vsize=9558412 CPUtime=0.08 cores=1,3,5,7\n/proc/32356/task/32360/stat : 32360 (java) S 32354 32356 30487 0 -1 4202560 131 0 0 0 6 2 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001254836648 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32361] ppid=32354 vsize=9558412 CPUtime=0.1 cores=1,3,5,7\n/proc/32356/task/32361/stat : 32361 (java) S 32354 32356 30487 0 -1 4202560 115 0 0 0 7 3 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001253783848 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32362] ppid=32354 vsize=9558412 CPUtime=0.1 cores=1,3,5,7\n/proc/32356/task/32362/stat : 32362 (java) S 32354 32356 30487 0 -1 4202560 168 0 0 0 8 2 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001252731048 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32363] ppid=32354 vsize=9558412 CPUtime=0.12 cores=1,3,5,7\n/proc/32356/task/32363/stat : 32363 (java) S 32354 32356 30487 0 -1 4202560 153 0 0 0 9 3 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001251679272 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32364] ppid=32354 vsize=9558412 CPUtime=0.09 cores=1,3,5,7\n/proc/32356/task/32364/stat : 32364 (java) S 32354 32356 30487 0 -1 4202560 111 0 0 0 7 2 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001250626472 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32365] ppid=32354 vsize=9558412 CPUtime=0.09 cores=1,3,5,7\n/proc/32356/task/32365/stat : 32365 (java) S 32354 32356 30487 0 -1 4202560 119 0 0 0 7 2 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001249573672 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32366] ppid=32354 vsize=9558412 CPUtime=0.1 cores=1,3,5,7\n/proc/32356/task/32366/stat : 32366 (java) S 32354 32356 30487 0 -1 4202560 52 0 0 0 8 2 0 0 20 0 18 0 253821940 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001248520872 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n/proc/32356/task/32367/stat : 32367 (java) S 32354 32356 30487 0 -1 4202560 196 0 0 0 0 0 0 0 20 0 18 0 253821941 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001118509544 214540793769 0 0 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n/proc/32356/task/32368/stat : 32368 (java) S 32354 32356 30487 0 -1 4202560 17 0 0 0 0 0 0 0 20 0 18 0 253821941 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001117455608 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32369/stat : 32369 (java) S 32354 32356 30487 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 18 0 253821941 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001116402696 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32370/stat : 32370 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 18 0 253821943 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001115351184 214540801824 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32371/stat : 32371 (java) S 32354 32356 30487 0 -1 4202560 4783 0 0 0 36 0 0 0 20 0 18 0 253821943 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001114299320 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32372/stat : 32372 (java) S 32354 32356 30487 0 -1 4202560 5409 0 0 0 37 0 0 0 20 0 18 0 253821943 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001113246520 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n/proc/32356/task/32373/stat : 32373 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 18 0 253821943 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001112193912 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n/proc/32356/task/32374/stat : 32374 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 18 0 253821943 9787813888 108674 33554432000 1073741824 1073778376 140735783809552 140001111141320 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n/proc/loadavg: 3.20 3.58 3.74 5/219 32378\n/proc/meminfo: memFree=27113340/32873648 swapFree=7104/7104\n[pid=32356] ppid=32354 vsize=9824668 CPUtime=10.73 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 15969 0 1 0 1034 39 0 0 20 0 22 0 253821938 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2456167 184496 2268 9 0 2446860 0\n[pid=32356/tid=32357] ppid=32354 vsize=9824668 CPUtime=4.01 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) S 32354 32356 30487 0 -1 4202560 2848 0 1 0 393 8 0 0 20 0 22 0 253821939 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001294398456 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32359] ppid=32354 vsize=9824668 CPUtime=0.14 cores=1,3,5,7\n/proc/32356/task/32359/stat : 32359 (java) S 32354 32356 30487 0 -1 4202560 82 0 0 0 11 3 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001255889448 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32360] ppid=32354 vsize=9824668 CPUtime=0.15 cores=1,3,5,7\n/proc/32356/task/32360/stat : 32360 (java) S 32354 32356 30487 0 -1 4202560 153 0 0 0 11 4 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001254836648 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32361] ppid=32354 vsize=9824668 CPUtime=0.16 cores=1,3,5,7\n/proc/32356/task/32361/stat : 32361 (java) S 32354 32356 30487 0 -1 4202560 145 0 0 0 12 4 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001253783848 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32362] ppid=32354 vsize=9824668 CPUtime=0.17 cores=1,3,5,7\n/proc/32356/task/32362/stat : 32362 (java) S 32354 32356 30487 0 -1 4202560 194 0 0 0 13 4 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001252731048 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32363] ppid=32354 vsize=9824668 CPUtime=0.18 cores=1,3,5,7\n/proc/32356/task/32363/stat : 32363 (java) S 32354 32356 30487 0 -1 4202560 175 0 0 0 14 4 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001251679272 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32364] ppid=32354 vsize=9824668 CPUtime=0.15 cores=1,3,5,7\n/proc/32356/task/32364/stat : 32364 (java) S 32354 32356 30487 0 -1 4202560 131 0 0 0 12 3 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001250626472 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32365] ppid=32354 vsize=9824668 CPUtime=0.16 cores=1,3,5,7\n/proc/32356/task/32365/stat : 32365 (java) S 32354 32356 30487 0 -1 4202560 139 0 0 0 13 3 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001249573672 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32366] ppid=32354 vsize=9824668 CPUtime=0.18 cores=1,3,5,7\n/proc/32356/task/32366/stat : 32366 (java) S 32354 32356 30487 0 -1 4202560 77 0 0 0 14 4 0 0 20 0 22 0 253821940 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001248520872 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32367] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32367/stat : 32367 (java) S 32354 32356 30487 0 -1 4202560 524 0 0 0 0 0 0 0 20 0 22 0 253821941 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001118509544 214540793769 0 0 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32368] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32368/stat : 32368 (java) S 32354 32356 30487 0 -1 4202560 17 0 0 0 0 0 0 0 20 0 22 0 253821941 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001117455608 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32369] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32369/stat : 32369 (java) S 32354 32356 30487 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 22 0 253821941 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001116402696 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32370] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32370/stat : 32370 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 22 0 253821943 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001115351184 214540801824 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32371] ppid=32354 vsize=9824668 CPUtime=0.79 cores=1,3,5,7\n/proc/32356/task/32371/stat : 32371 (java) S 32354 32356 30487 0 -1 4202560 4794 0 0 0 79 0 0 0 20 0 22 0 253821943 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001114299320 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32372] ppid=32354 vsize=9824668 CPUtime=0.71 cores=1,3,5,7\n/proc/32356/task/32372/stat : 32372 (java) S 32354 32356 30487 0 -1 4202560 5413 0 0 0 71 0 0 0 20 0 22 0 253821943 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001113246520 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32373] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32373/stat : 32373 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 22 0 253821943 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001112193912 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32374] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32374/stat : 32374 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 22 0 253821943 10060460032 184496 33554432000 1073741824 1073778376 140735783809552 140001111141320 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32376] ppid=32354 vsize=9824668 CPUtime=816.21 cores=1,3,5,7\n/proc/32356/task/32376/stat : 32376 (java) R 32354 32356 30487 0 -1 4202560 790 0 0 0 81600 21 0 0 20 0 22 0 253822365 10060460032 759252 33554432000 1073741824 1073778376 140735783809552 140001109031840 140001120578582 0 4 0 16800975 0 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32377] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32377/stat : 32377 (java) S 32354 32356 30487 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 22 0 253822372 10060460032 759252 33554432000 1073741824 1073778376 140735783809552 140001107981224 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n[pid=32356/tid=32378] ppid=32354 vsize=9824668 CPUtime=0 cores=1,3,5,7\n/proc/32356/task/32378/stat : 32378 (java) S 32354 32356 30487 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 22 0 253822373 10060460032 759252 33554432000 1073741824 1073778376 140735783809552 140001106928424 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n/proc/loadavg: 4.16 4.05 3.90 5/219 32509\n/proc/meminfo: memFree=26069784/32873648 swapFree=7104/7104\n[pid=32356] ppid=32354 vsize=9824668 CPUtime=1764.03 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 19795 0 1 0 176267 136 0 0 20 0 22 0 253821938 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n/proc/32356/statm: 2456167 748257 2277 9 0 2446860 0\n[pid=32356/tid=32357] ppid=32354 vsize=9824668 CPUtime=4.05 cores=1,3,5,7\n/proc/32356/task/32357/stat : 32357 (java) S 32354 32356 30487 0 -1 4202560 2856 0 1 0 395 10 0 0 20 0 22 0 253821939 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001294398456 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32359] ppid=32354 vsize=9824668 CPUtime=0.54 cores=1,3,5,7\n/proc/32356/task/32359/stat : 32359 (java) S 32354 32356 30487 0 -1 4202560 207 0 0 0 46 8 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001255889448 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32360] ppid=32354 vsize=9824668 CPUtime=0.69 cores=1,3,5,7\n/proc/32356/task/32360/stat : 32360 (java) S 32354 32356 30487 0 -1 4202560 498 0 0 0 56 13 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001254836648 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32361] ppid=32354 vsize=9824668 CPUtime=0.5 cores=1,3,5,7\n/proc/32356/task/32361/stat : 32361 (java) S 32354 32356 30487 0 -1 4202560 284 0 0 0 40 10 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001253783848 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32362] ppid=32354 vsize=9824668 CPUtime=0.69 cores=1,3,5,7\n/proc/32356/task/32362/stat : 32362 (java) S 32354 32356 30487 0 -1 4202560 344 0 0 0 57 12 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001252731048 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32363] ppid=32354 vsize=9824668 CPUtime=0.57 cores=1,3,5,7\n/proc/32356/task/32363/stat : 32363 (java) S 32354 32356 30487 0 -1 4202560 326 0 0 0 46 11 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001251679272 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32364] ppid=32354 vsize=9824668 CPUtime=0.42 cores=1,3,5,7\n/proc/32356/task/32364/stat : 32364 (java) S 32354 32356 30487 0 -1 4202560 386 0 0 0 35 7 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001250626472 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32365] ppid=32354 vsize=9824668 CPUtime=0.38 cores=1,3,5,7\n/proc/32356/task/32365/stat : 32365 (java) S 32354 32356 30487 0 -1 4202560 227 0 0 0 32 6 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001249573672 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32366] ppid=32354 vsize=9824668 CPUtime=0.66 cores=1,3,5,7\n/proc/32356/task/32366/stat : 32366 (java) S 32354 32356 30487 0 -1 4202560 261 0 0 0 55 11 0 0 20 0 22 0 253821940 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001248520872 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32367] ppid=32354 vsize=9824668 CPUtime=0.86 cores=1,3,5,7\n/proc/32356/task/32367/stat : 32367 (java) S 32354 32356 30487 0 -1 4202560 1502 0 0 0 82 4 0 0 20 0 22 0 253821941 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001118509544 214540793769 0 0 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n/proc/32356/task/32368/stat : 32368 (java) S 32354 32356 30487 0 -1 4202560 17 0 0 0 0 0 0 0 20 0 22 0 253821941 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001117455608 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32369/stat : 32369 (java) S 32354 32356 30487 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 22 0 253821941 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001116402696 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32370/stat : 32370 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 22 0 253821943 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001115351184 214540801824 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32371] ppid=32354 vsize=9824668 CPUtime=1.16 cores=1,3,5,7\n/proc/32356/task/32371/stat : 32371 (java) S 32354 32356 30487 0 -1 4202560 4805 0 0 0 116 0 0 0 20 0 22 0 253821943 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001114299320 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 5 0 0 0 0 0\n[pid=32356/tid=32372] ppid=32354 vsize=9824668 CPUtime=1.05 cores=1,3,5,7\n/proc/32356/task/32372/stat : 32372 (java) S 32354 32356 30487 0 -1 4202560 5439 0 0 0 105 0 0 0 20 0 22 0 253821943 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001113246520 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n/proc/32356/task/32373/stat : 32373 (java) S 32354 32356 30487 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 22 0 253821943 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001112193912 214540792892 0 4 0 16800975 18446744071579510697 0 0 -1 7 0 0 0 0 0\n[pid=32356/tid=32374] ppid=32354 vsize=9824668 CPUtime=0.34 cores=1,3,5,7\n/proc/32356/task/32374/stat : 32374 (java) S 32354 32356 30487 0 -1 4202560 51 0 0 0 14 20 0 0 20 0 22 0 253821943 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001111141320 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32375] ppid=32354 vsize=9824668 CPUtime=876.08 cores=1,3,5,7\n/proc/32356/task/32375/stat : 32375 (java) R 32354 32356 30487 0 -1 4202560 633 0 0 0 87577 31 0 0 20 0 22 0 253822365 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001110084944 140001120370437 0 4 0 16800975 0 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32376] ppid=32354 vsize=9824668 CPUtime=876.16 cores=1,3,5,7\n/proc/32356/task/32376/stat : 32376 (java) R 32354 32356 30487 0 -1 4202560 790 0 0 0 87594 22 0 0 20 0 22 0 253822365 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001109031424 140001120370320 0 4 0 16800975 0 0 0 -1 5 0 0 0 0 0\n/proc/32356/task/32377/stat : 32377 (java) S 32354 32356 30487 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 22 0 253822372 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001107981224 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n/proc/32356/task/32378/stat : 32378 (java) S 32354 32356 30487 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 22 0 253822373 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001106928424 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 3 0 0 0 0 0\n/proc/loadavg: 4.11 4.04 3.90 5/219 32509\n/proc/meminfo: memFree=26037020/32873648 swapFree=7104/7104\n[pid=32356] ppid=32354 vsize=9824668 CPUtime=1800.03 cores=1,3,5,7\n/proc/32356/stat : 32356 (java) S 32354 32356 30487 0 -1 4202496 19795 0 1 0 179867 136 0 0 20 0 22 0 253821938 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140735783800688 214540779581 0 0 0 16800975 18446744073709551615 0 0 17 1 0 0 0 0 0\n[pid=32356/tid=32374] ppid=32354 vsize=9824668 CPUtime=0.35 cores=1,3,5,7\n/proc/32356/task/32374/stat : 32374 (java) S 32354 32356 30487 0 -1 4202560 51 0 0 0 14 21 0 0 20 0 22 0 253821943 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001111141320 214540793769 0 4 0 16800975 18446744071579510697 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32375] ppid=32354 vsize=9824668 CPUtime=894.08 cores=1,3,5,7\n/proc/32356/task/32375/stat : 32375 (java) R 32354 32356 30487 0 -1 4202560 633 0 0 0 89377 31 0 0 20 0 22 0 253822365 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001110084944 140001120578582 0 4 0 16800975 0 0 0 -1 1 0 0 0 0 0\n[pid=32356/tid=32376] ppid=32354 vsize=9824668 CPUtime=894.16 cores=1,3,5,7\n/proc/32356/task/32376/stat : 32376 (java) R 32354 32356 30487 0 -1 4202560 790 0 0 0 89394 22 0 0 20 0 22 0 253822365 10060460032 748257 33554432000 1073741824 1073778376 140735783809552 140001109031424 140001120578522 0 4 0 16800975 0 0 0 -1 5 0 0 0 0 0\nmaximum resident set size= 3068304\npage reclaims= 19826\nvoluntary context switches= 21639\nrunsolver used 3.2815 second user time and 8.63269 second system time\nBegin job on node127 at 2012-05-24 18:29:25\nRUNSOLVER COMMAND LINE= BIN/runsolver --timestamp -w /tmp/evaluation-result-3688364-1337876964/watcher-3688364-1337876964 -o /tmp/evaluation-result-3688364-1337876964/solver-3688364-1337876964 -C 1800 -W 1900 -M 15500  java -jar HOME/sat4j-pb.jar Both HOME/instance-3688364-1337876964.opb\nMemFree:        27829460 kB\nBuffers:          235116 kB\nCached:          2393204 kB\nActive:          2055340 kB\nInactive:        2205380 kB\nActive(anon):    1634672 kB\nActive(file):     420668 kB\nInactive(file):  2205380 kB\nAnonPages:       1645352 kB\nSlab:             639776 kB\nSReclaimable:      83120 kB\nCommitted_AS:    1885872 kB\nEnd job on node127 at 2012-05-24 18:44:27