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/rand946.cudf.s-e-l-s-s.log.runsolver ./gj-paranoid-solver-1.0 /home/misc2010/data/2011/incremental/s-e-l-s-s//rand946.cudf /home/misc2010/tmp/201108251442/gj-paranoid-solver-1.0/rand946.cudf.s-e-l-s-s.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.06 1.04 1.00 4/36 16892
/proc/meminfo: memFree=422636/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) R 16890 16891 4778 34817 4778 4202496 353 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 267 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/16891/statm: 641 267 228 194 0 28 0
[pid=16892] ppid=16891 vsize=2564 CPUtime=0
/proc/16892/stat : 16892 (gj-paranoid-sol) R 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 39 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/16892/statm: 641 39 0 194 0 28 0

[startup+0.19996 s]
/proc/loadavg: 1.06 1.04 1.00 4/36 16892
/proc/meminfo: memFree=422636/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=410100 CPUtime=0.19
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 4469 0 1 0 17 2 0 0 25 0 9 0 11038446 419942400 3900 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 102525 3900 1928 10 0 96673 0
Current children cumulated CPU time (s) 0.19
Current children cumulated vsize (KiB) 412664

[startup+0.209957 s]
/proc/loadavg: 1.06 1.04 1.00 4/36 16892
/proc/meminfo: memFree=422636/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=410100 CPUtime=0.2
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 4521 0 1 0 18 2 0 0 25 0 9 0 11038446 419942400 3952 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 102525 3952 1928 10 0 96673 0
Current children cumulated CPU time (s) 0.2
Current children cumulated vsize (KiB) 412664

[startup+0.309977 s]
/proc/loadavg: 1.06 1.04 1.00 4/36 16892
/proc/meminfo: memFree=422636/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=410100 CPUtime=0.3
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 5285 0 1 0 28 2 0 0 25 0 9 0 11038446 419942400 4715 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 102525 4715 1934 10 0 96673 0
Current children cumulated CPU time (s) 0.3
Current children cumulated vsize (KiB) 412664

[startup+0.710049 s]
/proc/loadavg: 1.06 1.04 1.00 4/36 16892
/proc/meminfo: memFree=422636/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=410688 CPUtime=0.71
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 11741 0 1 0 66 5 0 0 25 0 9 0 11038446 420544512 10987 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 102672 10987 2895 10 0 96820 0
Current children cumulated CPU time (s) 0.71
Current children cumulated vsize (KiB) 413252

[startup+1.51016 s]
/proc/loadavg: 1.06 1.04 1.00 3/45 16901
/proc/meminfo: memFree=362936/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=410820 CPUtime=1.5
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 25561 0 1 0 142 8 0 0 25 0 9 0 11038446 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 102705 24807 2896 10 0 96853 0
[pid=16892/tid=16894] ppid=16891 vsize=410820 CPUtime=0.83
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 6831 0 1 0 81 2 0 0 25 0 9 0 11038447 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=410820 CPUtime=0.64
/proc/16892/task/16895/stat : 16895 (java) R 16891 16891 4778 34817 4778 4202560 17357 0 0 0 58 6 0 0 18 0 9 0 11038447 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4150716350 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 20 0 9 0 11038448 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 20 0 9 0 11038448 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11038449 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=410820 CPUtime=0.03
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 432 0 0 0 3 0 0 0 17 0 9 0 11038449 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 9 0 11038449 420679680 24807 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 9 0 11038449 420679680 24807 1283457024 134512640 134550932 4287459888 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) 413384

[startup+3.11043 s]
/proc/loadavg: 1.06 1.04 1.00 2/45 16901
/proc/meminfo: memFree=287668/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=410820 CPUtime=3.1
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 47247 0 1 0 293 17 0 0 25 0 9 0 11038446 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 102705 46493 2896 10 0 96853 0
[pid=16892/tid=16894] ppid=16891 vsize=410820 CPUtime=1.42
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 12313 0 1 0 138 4 0 0 25 0 9 0 11038447 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4114892993 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=410820 CPUtime=1.63
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 33558 0 0 0 151 12 0 0 16 0 9 0 11038447 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 20 0 9 0 11038448 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 20 0 9 0 11038448 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11038449 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=410820 CPUtime=0.04
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 435 0 0 0 4 0 0 0 16 0 9 0 11038449 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 9 0 11038449 420679680 46493 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=410820 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 9 0 11038449 420679680 46493 1283457024 134512640 134550932 4287459888 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) 413384

[startup+6.3114 s]
/proc/loadavg: 1.13 1.06 1.01 3/45 16901
/proc/meminfo: memFree=152516/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=431148 CPUtime=6.3
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 75992 0 1 0 604 26 0 0 25 0 9 0 11038446 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 107787 75235 2897 10 0 101935 0
[pid=16892/tid=16894] ppid=16891 vsize=431148 CPUtime=2.38
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14265 0 1 0 232 6 0 0 25 0 9 0 11038447 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=431148 CPUtime=3.85
/proc/16892/task/16895/stat : 16895 (java) R 16891 16891 4778 34817 4778 4202560 60332 0 0 0 366 19 0 0 16 0 9 0 11038447 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4151094696 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=431148 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 9 0 11038448 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=431148 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 9 0 11038448 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=431148 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11038449 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=431148 CPUtime=0.05
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 454 0 0 0 5 0 0 0 15 0 9 0 11038449 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=431148 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 9 0 11038449 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=431148 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 9 0 11038449 441495552 75235 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 433712

[startup+12.7034 s]
/proc/loadavg: 1.20 1.08 1.01 2/46 16903
/proc/meminfo: memFree=140976/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=12.67
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90003 0 1 0 1236 31 0 0 25 0 10 0 11038446 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73033 2912 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=5.25
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14516 0 1 0 518 7 0 0 25 0 10 0 11038447 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4115266276 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=7.3
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74020 0 0 0 708 22 0 0 16 0 10 0 11038447 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.11
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 522 0 0 0 10 1 0 0 15 0 10 0 11038449 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73033 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.67
Current children cumulated vsize (KiB) 424128

[startup+25.5068 s]
/proc/loadavg: 1.16 1.07 1.01 2/46 16904
/proc/meminfo: memFree=140976/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=25.47
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90019 0 1 0 2516 31 0 0 25 0 10 0 11038446 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73049 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=17.63
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 1756 7 0 0 25 0 10 0 11038447 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4114979782 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=7.7
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 748 22 0 0 15 0 10 0 11038447 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 535 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73049 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.47
Current children cumulated vsize (KiB) 424128

[startup+51.1132 s]
/proc/loadavg: 1.10 1.06 1.01 2/46 16907
/proc/meminfo: memFree=140976/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=51.08
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 5077 31 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=42.92
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 4285 7 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115324014 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=8.02
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 780 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.08
Current children cumulated vsize (KiB) 424128

[startup+102.307 s]
/proc/loadavg: 1.04 1.05 1.01 2/45 16911
/proc/meminfo: memFree=140744/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=102.24
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 10192 32 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=93.8
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 9372 8 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115272432 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=8.3
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 808 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.24
Current children cumulated vsize (KiB) 424128

[startup+162.305 s]
/proc/loadavg: 1.01 1.04 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=162.18
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 16186 32 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=153.15
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 15307 8 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115266276 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=8.88
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 866 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 162.18
Current children cumulated vsize (KiB) 424128

[startup+222.312 s]
/proc/loadavg: 1.00 1.03 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=222.16
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 22184 32 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=212.85
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 21277 8 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115281400 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.16
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 894 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) R 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 222.16
Current children cumulated vsize (KiB) 424128

[startup+282.312 s]
/proc/loadavg: 1.00 1.02 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=282.07
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 28172 35 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=272.48
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 27237 11 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115266276 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.45
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 923 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.07
Current children cumulated vsize (KiB) 424128

Solver just ended. Dumping a history of the last processes samples

[startup+283.212 s]
/proc/loadavg: 1.00 1.02 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=282.97
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 28262 35 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=273.37
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 27326 11 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4114979741 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.45
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 923 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.97
Current children cumulated vsize (KiB) 424128

[startup+284.012 s]
/proc/loadavg: 1.00 1.02 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=283.77
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 28342 35 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=274.17
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 27406 11 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115301856 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.45
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 923 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.77
Current children cumulated vsize (KiB) 424128

[startup+284.812 s]
/proc/loadavg: 1.00 1.02 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=284.57
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 28422 35 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=274.97
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 27486 11 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115281232 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.45
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 923 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.57
Current children cumulated vsize (KiB) 424128

[startup+285.012 s]
/proc/loadavg: 1.00 1.02 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=284.77
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90023 0 1 0 28442 35 0 0 25 0 10 0 11038446 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73053 2914 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=275.17
/proc/16892/task/16894/stat : 16894 (java) R 16891 16891 4778 34817 4778 4202560 14517 0 1 0 27506 11 0 0 25 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4115272573 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.45
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 923 22 0 0 15 0 10 0 11038447 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 10 0 11038448 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 539 0 0 0 12 1 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11038449 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16903] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16903/stat : 16903 (java) S 16891 16891 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11039512 431681536 73053 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.77
Current children cumulated vsize (KiB) 424128

[startup+285.108 s]
/proc/loadavg: 1.00 1.02 1.00 2/44 16911
/proc/meminfo: memFree=140752/1048576 swapFree=0/0
[pid=16891] ppid=16890 vsize=2564 CPUtime=0
/proc/16891/stat : 16891 (gj-paranoid-sol) S 16890 16891 4778 34817 4778 4202496 370 0 0 0 0 0 0 0 25 0 1 0 11038446 2625536 268 1283457024 134512640 135304128 4293878016 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/16891/statm: 641 268 229 194 0 28 0
[pid=16892] ppid=16891 vsize=421564 CPUtime=284.86
/proc/16892/stat : 16892 (java) S 16891 16891 4778 34817 4778 4202496 90025 0 1 0 28451 35 0 0 25 0 9 0 11038446 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/16892/statm: 105391 73055 2915 10 0 99536 0
[pid=16892/tid=16894] ppid=16891 vsize=421564 CPUtime=275.26
/proc/16892/task/16894/stat : 16894 (java) S 16891 16891 4778 34817 4778 4202560 14517 0 1 0 27515 11 0 0 25 0 9 0 11038447 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16895] ppid=16891 vsize=421564 CPUtime=9.45
/proc/16892/task/16895/stat : 16895 (java) S 16891 16891 4778 34817 4778 4202560 74022 0 0 0 923 22 0 0 15 0 9 0 11038447 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16896] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16896/stat : 16896 (java) S 16891 16891 4778 34817 4778 4202560 15 0 0 0 0 0 0 0 18 0 9 0 11038448 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16897] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16897/stat : 16897 (java) S 16891 16891 4778 34817 4778 4202560 5 0 0 0 0 0 0 0 18 0 9 0 11038448 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16898] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16898/stat : 16898 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 9 0 11038449 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16899] ppid=16891 vsize=421564 CPUtime=0.13
/proc/16892/task/16899/stat : 16899 (java) S 16891 16891 4778 34817 4778 4202560 540 0 0 0 12 1 0 0 15 0 9 0 11038449 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16900] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16900/stat : 16900 (java) S 16891 16891 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 9 0 11038449 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=16892/tid=16901] ppid=16891 vsize=421564 CPUtime=0
/proc/16892/task/16901/stat : 16901 (java) S 16891 16891 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 9 0 11038449 431681536 73055 1283457024 134512640 134550932 4287459888 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.86
Current children cumulated vsize (KiB) 424128

Child status: 0
Real time (s): 285.165
CPU time (s): 284.89
CPU user time (s): 284.514
CPU system time (s): 0.376023
CPU usage (%): 99.9037
Max. virtual memory (cumulated for all children) (KiB): 445328

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 284.514
system time used= 0.376023
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 90411
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= 5898
involuntary context switches= 8683

runsolver used 0 second user time and 0 second system time

The end