runsolver Copyright (C) 2010 Olivier ROUSSEL This is runsolver version 3.2.9a (svn: 651) This program is distributed in the hope that it will be useful, but WITHOUT ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License for more details. command line: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201109011757/gj-user-solver-1.0/eeee44ce-5407-11df-b11f-00163e7a6f5e.cudf.dudf-real.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/dudf-real//eeee44ce-5407-11df-b11f-00163e7a6f5e.cudf /home/misc2010/tmp/201109011757/gj-user-solver-1.0/eeee44ce-5407-11df-b11f-00163e7a6f5e.cudf.dudf-real.result -sum(pin-priority-1),-sum(pin-priority-500),-sum(pin-priority-700),-notuptodate,-removed,-changed Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB Current StackSize limit: 8192 KiB [startup+0 s] /proc/loadavg: 0.99 1.00 1.00 3/35 4656 /proc/meminfo: memFree=225436/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=11356 CPUtime=0 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 918 0 0 0 0 0 0 0 24 0 2 0 73772543 11628544 651 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 0 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 2839 651 285 10 0 1185 0 [pid=4655/tid=4656] ppid=4654 vsize=11356 CPUtime=0 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 2 0 73772543 11628544 651 1283457024 134512640 134550932 4289926160 18446744073709551615 4159864424 0 0 0 0 0 0 0 -1 0 0 0 0 [startup+0.145121 s] /proc/loadavg: 0.99 1.00 1.00 3/35 4656 /proc/meminfo: memFree=225436/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=410068 CPUtime=0.14 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 4238 0 1 0 14 0 0 0 24 0 9 0 73772543 419909632 3666 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102517 3666 1935 10 0 96665 0 [pid=4655/tid=4656] ppid=4654 vsize=410068 CPUtime=0.12 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 3038 0 1 0 12 0 0 0 25 0 9 0 73772543 419909632 3666 1283457024 134512640 134550932 4289926160 18446744073709551615 4115194624 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.14 Current children cumulated vsize (KiB) 412636 [startup+0.205131 s] /proc/loadavg: 0.99 1.00 1.00 3/35 4656 /proc/meminfo: memFree=225436/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=410068 CPUtime=0.2 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 4643 0 1 0 19 1 0 0 24 0 9 0 73772543 419909632 4070 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102517 4070 1946 10 0 96665 0 [pid=4655/tid=4656] ppid=4654 vsize=410068 CPUtime=0.18 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 3443 0 1 0 17 1 0 0 25 0 9 0 73772543 419909632 4070 1283457024 134512640 134550932 4289926160 18446744073709551615 4115203383 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 412636 [startup+0.305153 s] /proc/loadavg: 0.99 1.00 1.00 3/35 4656 /proc/meminfo: memFree=225436/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=410212 CPUtime=0.3 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 5307 0 1 0 29 1 0 0 24 0 9 0 73772543 420057088 4733 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102553 4733 1952 10 0 96701 0 [pid=4655/tid=4656] ppid=4654 vsize=410212 CPUtime=0.27 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 3528 0 1 0 26 1 0 0 25 0 9 0 73772543 420057088 4733 1283457024 134512640 134550932 4289926160 18446744073709551615 4153095946 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 412780 [startup+0.705296 s] /proc/loadavg: 0.99 1.00 1.00 3/35 4656 /proc/meminfo: memFree=225436/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=410748 CPUtime=0.69 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 12910 0 1 0 67 2 0 0 24 0 9 0 73772543 420605952 12150 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102687 12150 2901 10 0 96835 0 [pid=4655/tid=4656] ppid=4654 vsize=410748 CPUtime=0.47 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 4217 0 1 0 46 1 0 0 25 0 9 0 73772543 420605952 12150 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 413316 [startup+1.50554 s] /proc/loadavg: 1.15 1.03 1.01 2/43 4664 /proc/meminfo: memFree=167020/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=410748 CPUtime=1.49 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 27425 0 1 0 143 6 0 0 24 0 9 0 73772543 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102687 26665 2901 10 0 96835 0 [pid=4655/tid=4656] ppid=4654 vsize=410748 CPUtime=0.82 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 6841 0 1 0 80 2 0 0 25 0 9 0 73772543 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=410748 CPUtime=0.63 /proc/4655/task/4658/stat : 4658 (java) R 4654 4654 22717 34817 22717 4202560 19198 0 0 0 59 4 0 0 18 0 9 0 73772544 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4151467402 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=410748 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 73772545 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=410748 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 73772545 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=410748 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 73772546 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=410748 CPUtime=0.03 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 446 0 0 0 3 0 0 0 21 0 9 0 73772546 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=410748 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 73772546 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=410748 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 73772546 420605952 26665 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 413316 [startup+3.10601 s] /proc/loadavg: 1.15 1.03 1.01 2/43 4664 /proc/meminfo: memFree=92744/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=411084 CPUtime=3.09 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 40193 0 1 0 298 11 0 0 24 0 10 0 73772543 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102771 39351 2927 10 0 96916 0 [pid=4655/tid=4656] ppid=4654 vsize=411084 CPUtime=1.91 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 9935 0 1 0 188 3 0 0 25 0 10 0 73772543 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4116081416 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=411084 CPUtime=1.1 /proc/4655/task/4658/stat : 4658 (java) S 4654 4654 22717 34817 22717 4202560 28791 0 0 0 103 7 0 0 16 0 10 0 73772544 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=411084 CPUtime=0.05 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 522 0 0 0 5 0 0 0 15 0 10 0 73772546 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73772546 420950016 39351 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 413652 [startup+6.3067 s] /proc/loadavg: 1.15 1.03 1.01 2/44 4665 /proc/meminfo: memFree=71664/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=411084 CPUtime=6.29 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 43480 0 1 0 617 12 0 0 24 0 10 0 73772543 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102771 42638 2927 10 0 96916 0 [pid=4655/tid=4656] ppid=4654 vsize=411084 CPUtime=4.99 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 9938 0 1 0 495 4 0 0 25 0 10 0 73772543 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4116058740 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=411084 CPUtime=1.18 /proc/4655/task/4658/stat : 4658 (java) S 4654 4654 22717 34817 22717 4202560 32048 0 0 0 111 7 0 0 16 0 10 0 73772544 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73772545 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=411084 CPUtime=0.09 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 549 0 0 0 9 0 0 0 15 0 10 0 73772546 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73772546 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4665] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4665/stat : 4665 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73772791 420950016 42638 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.29 Current children cumulated vsize (KiB) 413652 [startup+12.7084 s] /proc/loadavg: 1.13 1.03 1.01 2/44 4665 /proc/meminfo: memFree=63356/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=411084 CPUtime=12.67 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 44584 0 1 0 1254 13 0 0 24 0 10 0 73772543 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102771 43458 2921 10 0 96916 0 [pid=4655/tid=4656] ppid=4654 vsize=411084 CPUtime=11.07 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 9938 0 1 0 1102 5 0 0 25 0 10 0 73772543 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4116087245 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=411084 CPUtime=1.47 /proc/4655/task/4658/stat : 4658 (java) S 4654 4654 22717 34817 22717 4202560 33142 0 0 0 140 7 0 0 16 0 10 0 73772544 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73772545 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=411084 CPUtime=0.1 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 559 0 0 0 10 0 0 0 15 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4665] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4665/stat : 4665 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73772791 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.67 Current children cumulated vsize (KiB) 413652 Solver just ended. Dumping a history of the last processes samples [startup+12.8085 s] /proc/loadavg: 1.13 1.03 1.01 2/44 4665 /proc/meminfo: memFree=63356/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=411084 CPUtime=12.77 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 44584 0 1 0 1264 13 0 0 24 0 10 0 73772543 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102771 43458 2921 10 0 96916 0 [pid=4655/tid=4656] ppid=4654 vsize=411084 CPUtime=11.17 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 9938 0 1 0 1112 5 0 0 25 0 10 0 73772543 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4116126136 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=411084 CPUtime=1.47 /proc/4655/task/4658/stat : 4658 (java) S 4654 4654 22717 34817 22717 4202560 33142 0 0 0 140 7 0 0 16 0 10 0 73772544 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73772545 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=411084 CPUtime=0.1 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 559 0 0 0 10 0 0 0 15 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4665] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4665/stat : 4665 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73772791 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.77 Current children cumulated vsize (KiB) 413652 [startup+13.0085 s] /proc/loadavg: 1.13 1.03 1.01 2/44 4665 /proc/meminfo: memFree=63356/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=411084 CPUtime=12.97 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 44584 0 1 0 1284 13 0 0 24 0 10 0 73772543 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102771 43458 2921 10 0 96916 0 [pid=4655/tid=4656] ppid=4654 vsize=411084 CPUtime=11.37 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 9938 0 1 0 1132 5 0 0 25 0 10 0 73772543 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4115976048 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=411084 CPUtime=1.47 /proc/4655/task/4658/stat : 4658 (java) S 4654 4654 22717 34817 22717 4202560 33142 0 0 0 140 7 0 0 16 0 10 0 73772544 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73772545 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=411084 CPUtime=0.1 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 559 0 0 0 10 0 0 0 15 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73772546 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4665] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4665/stat : 4665 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73772791 420950016 43458 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.97 Current children cumulated vsize (KiB) 413652 [startup+13.1085 s] /proc/loadavg: 1.13 1.03 1.01 2/44 4665 /proc/meminfo: memFree=63356/1048576 swapFree=0/0 [pid=4654] ppid=4653 vsize=2568 CPUtime=0 /proc/4654/stat : 4654 (gj-user-solver-) S 4653 4654 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 73772543 2629632 269 1283457024 134512640 135304128 4292730720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/4654/statm: 642 269 229 194 0 29 0 [pid=4655] ppid=4654 vsize=411084 CPUtime=13.07 /proc/4655/stat : 4655 (java) S 4654 4654 22717 34817 22717 4202496 44585 0 1 0 1294 13 0 0 24 0 10 0 73772543 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/4655/statm: 102771 43459 2921 10 0 96916 0 [pid=4655/tid=4656] ppid=4654 vsize=411084 CPUtime=11.47 /proc/4655/task/4656/stat : 4656 (java) R 4654 4654 22717 34817 22717 4202560 9938 0 1 0 1142 5 0 0 25 0 10 0 73772543 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4115688591 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=4655/tid=4658] ppid=4654 vsize=411084 CPUtime=1.47 /proc/4655/task/4658/stat : 4658 (java) S 4654 4654 22717 34817 22717 4202560 33142 0 0 0 140 7 0 0 16 0 10 0 73772544 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4659] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4659/stat : 4659 (java) S 4654 4654 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73772545 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4660] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4660/stat : 4660 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 73772545 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4661] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4661/stat : 4661 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4662] ppid=4654 vsize=411084 CPUtime=0.1 /proc/4655/task/4662/stat : 4662 (java) S 4654 4654 22717 34817 22717 4202560 560 0 0 0 10 0 0 0 15 0 10 0 73772546 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4663] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4663/stat : 4663 (java) S 4654 4654 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73772546 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4664] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4664/stat : 4664 (java) S 4654 4654 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73772546 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=4655/tid=4665] ppid=4654 vsize=411084 CPUtime=0 /proc/4655/task/4665/stat : 4665 (java) S 4654 4654 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73772791 420950016 43459 1283457024 134512640 134550932 4289926160 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 13.07 Current children cumulated vsize (KiB) 413652 Child status: 0 Real time (s): 13.166 CPU time (s): 13.1048 CPU user time (s): 12.9568 CPU system time (s): 0.148009 CPU usage (%): 99.5354 Max. virtual memory (cumulated for all children) (KiB): 413652 getrusage(RUSAGE_CHILDREN,...) data: user time used= 12.9568 system time used= 0.148009 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 44973 page faults= 1 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1077 involuntary context switches= 1503 runsolver used 0 second user time and 0.012 second system time The end