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/201108251442/gj-paranoid-solver-1.0/4f84e9c6-a79c-11e0-9eb7-00163e1e087d.cudf.dudf-real.log.runsolver ./gj-paranoid-solver-1.0 /home/misc2010/data/2011/dudf-real//4f84e9c6-a79c-11e0-9eb7-00163e1e087d.cudf /home/misc2010/tmp/201108251442/gj-paranoid-solver-1.0/4f84e9c6-a79c-11e0-9eb7-00163e1e087d.cudf.dudf-real.result 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: 1.55 1.45 1.24 5/35 22255 /proc/meminfo: memFree=300152/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=11356 CPUtime=0.01 /proc/22254/stat : 22254 (java) R 22253 22253 4778 34817 4778 4202496 918 0 0 0 0 1 0 0 25 0 2 0 11150962 11628544 651 1283457024 134512640 134550932 4292638448 18446744073709551615 4159372904 0 0 0 0 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 2839 651 285 10 0 1185 0 [pid=22254/tid=22255] ppid=22253 vsize=11356 CPUtime=0 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 2 0 11150963 11628544 651 1283457024 134512640 134550932 4292638448 18446744073709551615 4159372904 0 0 0 0 0 0 0 -1 0 0 0 0 [startup+0.115408 s] /proc/loadavg: 1.55 1.45 1.24 5/35 22255 /proc/meminfo: memFree=300152/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=409796 CPUtime=0.12 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 3712 0 1 0 10 2 0 0 25 0 9 0 11150962 419631104 3140 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102449 3140 1791 10 0 96597 0 [pid=22254/tid=22255] ppid=22253 vsize=409796 CPUtime=0.1 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 2746 0 1 0 10 0 0 0 25 0 9 0 11150963 419631104 3140 1283457024 134512640 134550932 4292638448 18446744073709551615 4114714052 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.12 Current children cumulated vsize (KiB) 412360 [startup+0.205418 s] /proc/loadavg: 1.55 1.45 1.24 5/35 22255 /proc/meminfo: memFree=300152/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410100 CPUtime=0.21 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 4268 0 1 0 19 2 0 0 25 0 9 0 11150962 419942400 3696 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102525 3696 1932 10 0 96673 0 [pid=22254/tid=22255] ppid=22253 vsize=410100 CPUtime=0.18 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 3075 0 1 0 18 0 0 0 25 0 9 0 11150963 419942400 3696 1283457024 134512640 134550932 4292638448 18446744073709551615 4114703227 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 412664 [startup+0.305437 s] /proc/loadavg: 1.55 1.45 1.24 5/35 22255 /proc/meminfo: memFree=300152/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410100 CPUtime=0.31 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 5299 0 1 0 29 2 0 0 25 0 9 0 11150962 419942400 4725 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102525 4725 1949 10 0 96673 0 [pid=22254/tid=22255] ppid=22253 vsize=410100 CPUtime=0.27 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 3524 0 1 0 27 0 0 0 25 0 9 0 11150963 419942400 4725 1283457024 134512640 134550932 4292638448 18446744073709551615 4114703216 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 412664 [startup+0.705515 s] /proc/loadavg: 1.55 1.45 1.24 5/35 22255 /proc/meminfo: memFree=300152/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410684 CPUtime=0.71 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 11452 0 1 0 66 5 0 0 25 0 9 0 11150962 420540416 10692 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102671 10692 2901 10 0 96819 0 [pid=22254/tid=22255] ppid=22253 vsize=410684 CPUtime=0.52 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 4214 0 1 0 51 1 0 0 25 0 9 0 11150963 420540416 10692 1283457024 134512640 134550932 4292638448 18446744073709551615 4115180763 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.71 Current children cumulated vsize (KiB) 413248 [startup+1.50572 s] /proc/loadavg: 1.55 1.45 1.24 3/43 22263 /proc/meminfo: memFree=242416/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410684 CPUtime=1.5 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 23645 0 1 0 139 11 0 0 25 0 9 0 11150962 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102671 22885 2901 10 0 96819 0 [pid=22254/tid=22255] ppid=22253 vsize=410684 CPUtime=0.87 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 6758 0 1 0 85 2 0 0 25 0 9 0 11150963 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4115154944 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22254/tid=22257] ppid=22253 vsize=410684 CPUtime=0.57 /proc/22254/task/22257/stat : 22257 (java) S 22253 22253 4778 34817 4778 4202560 15502 0 0 0 50 7 0 0 19 0 9 0 11150964 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22258] ppid=22253 vsize=410684 CPUtime=0 /proc/22254/task/22258/stat : 22258 (java) S 22253 22253 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 19 0 9 0 11150964 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22259] ppid=22253 vsize=410684 CPUtime=0 /proc/22254/task/22259/stat : 22259 (java) S 22253 22253 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 20 0 9 0 11150964 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22260] ppid=22253 vsize=410684 CPUtime=0 /proc/22254/task/22260/stat : 22260 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22261] ppid=22253 vsize=410684 CPUtime=0.03 /proc/22254/task/22261/stat : 22261 (java) S 22253 22253 4778 34817 4778 4202560 445 0 0 0 3 0 0 0 20 0 9 0 11150965 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22262] ppid=22253 vsize=410684 CPUtime=0 /proc/22254/task/22262/stat : 22262 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22263] ppid=22253 vsize=410684 CPUtime=0 /proc/22254/task/22263/stat : 22263 (java) S 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 15 0 9 0 11150965 420540416 22885 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 413248 [startup+3.10615 s] /proc/loadavg: 1.55 1.45 1.24 2/43 22263 /proc/meminfo: memFree=168884/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410816 CPUtime=3.1 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 44972 0 1 0 290 20 0 0 25 0 9 0 11150962 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102704 44212 2901 10 0 96852 0 [pid=22254/tid=22255] ppid=22253 vsize=410816 CPUtime=1.46 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 10542 0 1 0 141 5 0 0 25 0 9 0 11150963 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4115154944 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22254/tid=22257] ppid=22253 vsize=410816 CPUtime=1.58 /proc/22254/task/22257/stat : 22257 (java) S 22253 22253 4778 34817 4778 4202560 33041 0 0 0 145 13 0 0 16 0 9 0 11150964 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22258] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22258/stat : 22258 (java) S 22253 22253 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 19 0 9 0 11150964 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22259] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22259/stat : 22259 (java) S 22253 22253 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 20 0 9 0 11150964 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22260] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22260/stat : 22260 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22261] ppid=22253 vsize=410816 CPUtime=0.03 /proc/22254/task/22261/stat : 22261 (java) S 22253 22253 4778 34817 4778 4202560 449 0 0 0 3 0 0 0 15 0 9 0 11150965 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22262] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22262/stat : 22262 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22263] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22263/stat : 22263 (java) S 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 15 0 9 0 11150965 420675584 44212 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 413380 Solver just ended. Dumping a history of the last processes samples [startup+3.21618 s] /proc/loadavg: 1.55 1.45 1.24 2/43 22263 /proc/meminfo: memFree=168884/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410816 CPUtime=3.21 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 48707 0 1 0 301 20 0 0 25 0 9 0 11150962 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102704 47947 2901 10 0 96852 0 [pid=22254/tid=22255] ppid=22253 vsize=410816 CPUtime=1.53 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 14119 0 1 0 148 5 0 0 25 0 9 0 11150963 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22254/tid=22257] ppid=22253 vsize=410816 CPUtime=1.62 /proc/22254/task/22257/stat : 22257 (java) R 22253 22253 4778 34817 4778 4202560 33199 0 0 0 149 13 0 0 15 0 9 0 11150964 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4152672526 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=22254/tid=22258] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22258/stat : 22258 (java) S 22253 22253 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 19 0 9 0 11150964 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22259] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22259/stat : 22259 (java) S 22253 22253 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 20 0 9 0 11150964 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22260] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22260/stat : 22260 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22261] ppid=22253 vsize=410816 CPUtime=0.03 /proc/22254/task/22261/stat : 22261 (java) S 22253 22253 4778 34817 4778 4202560 449 0 0 0 3 0 0 0 15 0 9 0 11150965 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22262] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22262/stat : 22262 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22263] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22263/stat : 22263 (java) R 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 15 0 9 0 11150965 420675584 47947 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.21 Current children cumulated vsize (KiB) 413380 [startup+4.00561 s] /proc/loadavg: 1.51 1.44 1.24 3/43 22263 /proc/meminfo: memFree=106884/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410816 CPUtime=3.98 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 64808 0 1 0 372 26 0 0 25 0 9 0 11150962 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102704 63970 2918 10 0 96852 0 [pid=22254/tid=22255] ppid=22253 vsize=410816 CPUtime=1.93 /proc/22254/task/22255/stat : 22255 (java) D 22253 22253 4778 34817 4778 4202560 14312 0 1 0 187 6 0 0 25 0 9 0 11150963 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446744071564252162 0 0 -1 0 0 0 0 [pid=22254/tid=22257] ppid=22253 vsize=410816 CPUtime=1.98 /proc/22254/task/22257/stat : 22257 (java) S 22253 22253 4778 34817 4778 4202560 49087 0 0 0 180 18 0 0 16 0 9 0 11150964 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22258] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22258/stat : 22258 (java) S 22253 22253 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 19 0 9 0 11150964 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22259] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22259/stat : 22259 (java) S 22253 22253 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 20 0 9 0 11150964 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22260] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22260/stat : 22260 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22261] ppid=22253 vsize=410816 CPUtime=0.04 /proc/22254/task/22261/stat : 22261 (java) S 22253 22253 4778 34817 4778 4202560 469 0 0 0 4 0 0 0 15 0 9 0 11150965 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22262] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22262/stat : 22262 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22263] ppid=22253 vsize=410816 CPUtime=0 /proc/22254/task/22263/stat : 22263 (java) S 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 15 0 9 0 11150965 420675584 63970 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.98 Current children cumulated vsize (KiB) 413380 [startup+4.80993 s] /proc/loadavg: 1.51 1.44 1.24 3/43 22263 /proc/meminfo: memFree=56292/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=410960 CPUtime=4.78 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 69382 0 1 0 449 29 0 0 25 0 9 0 11150962 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102740 68533 2917 10 0 96885 0 [pid=22254/tid=22255] ppid=22253 vsize=410960 CPUtime=2.1 /proc/22254/task/22255/stat : 22255 (java) R 22253 22253 4778 34817 4778 4202560 14358 0 1 0 204 6 0 0 25 0 9 0 11150963 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4115127658 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22254/tid=22257] ppid=22253 vsize=410960 CPUtime=2.61 /proc/22254/task/22257/stat : 22257 (java) S 22253 22253 4778 34817 4778 4202560 53608 0 0 0 239 22 0 0 16 0 9 0 11150964 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22258] ppid=22253 vsize=410960 CPUtime=0 /proc/22254/task/22258/stat : 22258 (java) S 22253 22253 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 9 0 11150964 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22259] ppid=22253 vsize=410960 CPUtime=0 /proc/22254/task/22259/stat : 22259 (java) S 22253 22253 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 9 0 11150964 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22260] ppid=22253 vsize=410960 CPUtime=0 /proc/22254/task/22260/stat : 22260 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22261] ppid=22253 vsize=410960 CPUtime=0.05 /proc/22254/task/22261/stat : 22261 (java) S 22253 22253 4778 34817 4778 4202560 476 0 0 0 5 0 0 0 15 0 9 0 11150965 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22262] ppid=22253 vsize=410960 CPUtime=0 /proc/22254/task/22262/stat : 22262 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11150965 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22263] ppid=22253 vsize=410960 CPUtime=0 /proc/22254/task/22263/stat : 22263 (java) S 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 15 0 9 0 11150965 420823040 68533 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.78 Current children cumulated vsize (KiB) 413524 [startup+5.21003 s] /proc/loadavg: 1.51 1.44 1.24 3/43 22263 /proc/meminfo: memFree=56292/1048576 swapFree=0/0 [pid=22253] ppid=22252 vsize=2564 CPUtime=0 /proc/22253/stat : 22253 (gj-paranoid-sol) S 22252 22253 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 18 0 1 0 11150962 2625536 268 1283457024 134512640 135304128 4293677216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22253/statm: 641 268 229 194 0 28 0 [pid=22254] ppid=22253 vsize=411284 CPUtime=5.18 /proc/22254/stat : 22254 (java) S 22253 22253 4778 34817 4778 4202496 69426 0 1 0 489 29 0 0 25 0 10 0 11150962 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22254/statm: 102821 68573 2918 10 0 96966 0 [pid=22254/tid=22255] ppid=22253 vsize=411284 CPUtime=2.28 /proc/22254/task/22255/stat : 22255 (java) S 22253 22253 4778 34817 4778 4202560 14379 0 1 0 222 6 0 0 25 0 10 0 11150963 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22257] ppid=22253 vsize=411284 CPUtime=2.81 /proc/22254/task/22257/stat : 22257 (java) R 22253 22253 4778 34817 4778 4202560 53608 0 0 0 259 22 0 0 16 0 10 0 11150964 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4153089588 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=22254/tid=22258] ppid=22253 vsize=411284 CPUtime=0 /proc/22254/task/22258/stat : 22258 (java) S 22253 22253 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11150964 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22259] ppid=22253 vsize=411284 CPUtime=0 /proc/22254/task/22259/stat : 22259 (java) S 22253 22253 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11150964 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22260] ppid=22253 vsize=411284 CPUtime=0 /proc/22254/task/22260/stat : 22260 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11150965 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22261] ppid=22253 vsize=411284 CPUtime=0.07 /proc/22254/task/22261/stat : 22261 (java) S 22253 22253 4778 34817 4778 4202560 493 0 0 0 7 0 0 0 15 0 10 0 11150965 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22262] ppid=22253 vsize=411284 CPUtime=0 /proc/22254/task/22262/stat : 22262 (java) S 22253 22253 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11150965 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0 [pid=22254/tid=22263] ppid=22253 vsize=411284 CPUtime=0 /proc/22254/task/22263/stat : 22263 (java) R 22253 22253 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 15 0 10 0 11150965 421154816 68573 1283457024 134512640 134550932 4292638448 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.18 Current children cumulated vsize (KiB) 413848 Child status: 0 Real time (s): 5.28907 CPU time (s): 5.22833 CPU user time (s): 4.91231 CPU system time (s): 0.316019 CPU usage (%): 98.8516 Max. virtual memory (cumulated for all children) (KiB): 413848 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.91231 system time used= 0.316019 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 69811 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= 536 involuntary context switches= 575 runsolver used 0 second user time and 0 second system time The end