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/201108241238/p2cudf-trendy-1.13/19890cfe-db9f-11df-9e6c-00163e3d3b7c.cudf.dudf-real.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/dudf-real//19890cfe-db9f-11df-9e6c-00163e3d3b7c.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/19890cfe-db9f-11df-9e6c-00163e3d3b7c.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.16 1.11 1.03 3/33 5599
/proc/meminfo: memFree=493432/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2516 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) R 5598 5599 1511 34817 1511 4194304 201 0 0 0 0 0 0 0 25 0 1 0 1326273 2576384 121 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 0 0 0 0 0 0 17 0 0 0 0
/proc/5599/statm: 629 121 100 194 0 16 0

[startup+0.130911 s]
/proc/loadavg: 1.16 1.11 1.03 3/33 5599
/proc/meminfo: memFree=493432/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.210917 s]
/proc/loadavg: 1.16 1.11 1.03 3/33 5599
/proc/meminfo: memFree=493432/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.310935 s]
/proc/loadavg: 1.16 1.11 1.03 3/33 5599
/proc/meminfo: memFree=493432/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.711047 s]
/proc/loadavg: 1.16 1.11 1.03 3/33 5599
/proc/meminfo: memFree=493432/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.51123 s]
/proc/loadavg: 1.16 1.11 1.03 4/45 5622
/proc/meminfo: memFree=450256/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=884720 CPUtime=1.46
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 21167 0 1 0 142 4 0 0 25 0 10 0 1326277 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 221180 18048 1723 10 0 218271 0
[pid=5613/tid=5614] ppid=5601 vsize=884720 CPUtime=0.76
/proc/5613/task/5614/stat : 5614 (java) R 5601 5599 1511 34817 1511 4202560 10004 0 1 0 72 4 0 0 25 0 10 0 1326277 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4095011557 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=884720 CPUtime=0
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 15 0 10 0 1326279 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=884720 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 1326279 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=884720 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 1326279 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=884720 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1326282 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=884720 CPUtime=0.39
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8296 0 0 0 39 0 0 0 21 0 10 0 1326282 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=884720 CPUtime=0.3
/proc/5613/task/5620/stat : 5620 (java) R 5601 5599 1511 34817 1511 4202560 1786 0 0 0 30 0 0 0 25 0 10 0 1326282 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=884720 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1326282 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=884720 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 1326282 905953280 18048 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.49
Current children cumulated vsize (KiB) 889864

[startup+3.11163 s]
/proc/loadavg: 1.16 1.11 1.03 3/45 5622
/proc/meminfo: memFree=329108/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882568 CPUtime=3.06
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 56745 0 1 0 294 12 0 0 25 0 10 0 1326277 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220642 53496 1744 10 0 217733 0
[pid=5613/tid=5614] ppid=5601 vsize=882568 CPUtime=1.53
/proc/5613/task/5614/stat : 5614 (java) R 5601 5599 1511 34817 1511 4202560 45451 0 1 0 141 12 0 0 25 0 10 0 1326277 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882568 CPUtime=0
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 15 0 10 0 1326279 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882568 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 1326279 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882568 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 1326279 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882568 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1326282 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882568 CPUtime=0.94
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8394 0 0 0 94 0 0 0 15 0 10 0 1326282 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882568 CPUtime=0.58
/proc/5613/task/5620/stat : 5620 (java) R 5601 5599 1511 34817 1511 4202560 1819 0 0 0 58 0 0 0 21 0 10 0 1326282 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4151443190 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882568 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1326282 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882568 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 1326282 903749632 53496 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 887712

[startup+6.31023 s]
/proc/loadavg: 1.23 1.12 1.03 3/46 5623
/proc/meminfo: memFree=223592/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882412 CPUtime=6.25
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 74030 0 1 0 608 17 0 0 25 0 11 0 1326277 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220603 68935 1781 10 0 217694 0
[pid=5613/tid=5614] ppid=5601 vsize=882412 CPUtime=3.29
/proc/5613/task/5614/stat : 5614 (java) R 5601 5599 1511 34817 1511 4202560 50280 0 1 0 316 13 0 0 25 0 11 0 1326277 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882412 CPUtime=0.24
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 10525 0 0 0 20 4 0 0 16 0 11 0 1326279 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882412 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882412 CPUtime=1.48
/proc/5613/task/5620/stat : 5620 (java) S 5601 5599 1511 34817 1511 4202560 3429 0 0 0 148 0 0 0 15 0 11 0 1326282 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5623] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5623/stat : 5623 (java) S 5601 5599 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1326735 903589888 68935 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 887556

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

[startup+6.40232 s]
/proc/loadavg: 1.23 1.12 1.03 3/46 5623
/proc/meminfo: memFree=223592/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882412 CPUtime=6.35
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 74032 0 1 0 618 17 0 0 25 0 11 0 1326277 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220603 68937 1781 10 0 217694 0
[pid=5613/tid=5614] ppid=5601 vsize=882412 CPUtime=3.35
/proc/5613/task/5614/stat : 5614 (java) R 5601 5599 1511 34817 1511 4202560 50280 0 1 0 322 13 0 0 25 0 11 0 1326277 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4095690327 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882412 CPUtime=0.24
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 10525 0 0 0 20 4 0 0 16 0 11 0 1326279 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882412 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882412 CPUtime=1.52
/proc/5613/task/5620/stat : 5620 (java) S 5601 5599 1511 34817 1511 4202560 3431 0 0 0 152 0 0 0 15 0 11 0 1326282 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5623] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5623/stat : 5623 (java) S 5601 5599 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1326735 903589888 68937 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.38
Current children cumulated vsize (KiB) 887556

[startup+8.01252 s]
/proc/loadavg: 1.23 1.12 1.03 2/46 5623
/proc/meminfo: memFree=223592/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882412 CPUtime=7.96
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 74054 0 1 0 779 17 0 0 25 0 11 0 1326277 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220603 68959 1784 10 0 217694 0
[pid=5613/tid=5614] ppid=5601 vsize=882412 CPUtime=4.76
/proc/5613/task/5614/stat : 5614 (java) R 5601 5599 1511 34817 1511 4202560 50281 0 1 0 463 13 0 0 25 0 11 0 1326277 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4095787111 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882412 CPUtime=0.24
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 10525 0 0 0 20 4 0 0 15 0 11 0 1326279 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882412 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882412 CPUtime=1.72
/proc/5613/task/5620/stat : 5620 (java) S 5601 5599 1511 34817 1511 4202560 3452 0 0 0 172 0 0 0 15 0 11 0 1326282 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5623] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5623/stat : 5623 (java) S 5601 5599 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1326735 903589888 68959 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.99
Current children cumulated vsize (KiB) 887556

[startup+9.61282 s]
/proc/loadavg: 1.21 1.12 1.03 3/46 5623
/proc/meminfo: memFree=223592/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882412 CPUtime=9.56
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 81613 0 1 0 936 20 0 0 25 0 11 0 1326277 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220603 76518 1788 10 0 217694 0
[pid=5613/tid=5614] ppid=5601 vsize=882412 CPUtime=6.02
/proc/5613/task/5614/stat : 5614 (java) R 5601 5599 1511 34817 1511 4202560 50282 0 1 0 589 13 0 0 25 0 11 0 1326277 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4094974025 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882412 CPUtime=0.51
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 18071 0 0 0 44 7 0 0 16 0 11 0 1326279 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882412 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882412 CPUtime=1.78
/proc/5613/task/5620/stat : 5620 (java) S 5601 5599 1511 34817 1511 4202560 3464 0 0 0 178 0 0 0 16 0 11 0 1326282 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) R 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5623] ppid=5601 vsize=882412 CPUtime=0
/proc/5613/task/5623/stat : 5623 (java) S 5601 5599 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1326735 903589888 76518 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 9.59
Current children cumulated vsize (KiB) 887556

[startup+10.4075 s]
/proc/loadavg: 1.21 1.12 1.03 3/46 5623
/proc/meminfo: memFree=193336/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882736 CPUtime=10.22
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 81631 0 1 0 1002 20 0 0 25 0 11 0 1326277 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220684 76536 1789 10 0 217775 0
[pid=5613/tid=5614] ppid=5601 vsize=882736 CPUtime=6.64
/proc/5613/task/5614/stat : 5614 (java) S 5601 5599 1511 34817 1511 4202560 50290 0 1 0 651 13 0 0 25 0 11 0 1326277 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882736 CPUtime=0.51
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 18071 0 0 0 44 7 0 0 16 0 11 0 1326279 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882736 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882736 CPUtime=1.82
/proc/5613/task/5620/stat : 5620 (java) S 5601 5599 1511 34817 1511 4202560 3468 0 0 0 182 0 0 0 15 0 11 0 1326282 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903921664 76536 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.25
Current children cumulated vsize (KiB) 887880

[startup+10.8135 s]
/proc/loadavg: 1.21 1.12 1.03 3/46 5623
/proc/meminfo: memFree=193336/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882736 CPUtime=10.25
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 81632 0 1 0 1004 21 0 0 25 0 11 0 1326277 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220684 76537 1789 10 0 217775 0
[pid=5613/tid=5614] ppid=5601 vsize=882736 CPUtime=6.64
/proc/5613/task/5614/stat : 5614 (java) S 5601 5599 1511 34817 1511 4202560 50290 0 1 0 651 13 0 0 25 0 11 0 1326277 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882736 CPUtime=0.51
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 18071 0 0 0 44 7 0 0 16 0 11 0 1326279 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882736 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882736 CPUtime=1.82
/proc/5613/task/5620/stat : 5620 (java) S 5601 5599 1511 34817 1511 4202560 3468 0 0 0 182 0 0 0 15 0 11 0 1326282 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903921664 76537 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.28
Current children cumulated vsize (KiB) 887880

[startup+10.9036 s]
/proc/loadavg: 1.21 1.12 1.03 3/46 5623
/proc/meminfo: memFree=193336/1048576 swapFree=0/0
[pid=5599] ppid=5598 vsize=2568 CPUtime=0
/proc/5599/stat : 5599 (p2cudf-trendy-1) S 5598 5599 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 1326273 2629632 270 1283457024 134512640 135304128 4288572368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5599/statm: 642 270 230 194 0 29 0
[pid=5601] ppid=5599 vsize=2576 CPUtime=0.03
/proc/5601/stat : 5601 (p2cudf) S 5599 5599 1511 34817 1511 4202496 392 3013 0 1 0 0 2 1 25 0 1 0 1326274 2637824 280 1283457024 134512640 135304128 4287173232 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5601/statm: 644 280 239 194 0 31 0
[pid=5613] ppid=5601 vsize=882736 CPUtime=10.34
/proc/5613/stat : 5613 (java) S 5601 5599 1511 34817 1511 4202496 81635 0 1 0 1012 22 0 0 25 0 11 0 1326277 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5613/statm: 220684 76540 1789 10 0 217775 0
[pid=5613/tid=5614] ppid=5601 vsize=882736 CPUtime=6.64
/proc/5613/task/5614/stat : 5614 (java) S 5601 5599 1511 34817 1511 4202560 50290 0 1 0 651 13 0 0 25 0 11 0 1326277 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5615] ppid=5601 vsize=882736 CPUtime=0.51
/proc/5613/task/5615/stat : 5615 (java) S 5601 5599 1511 34817 1511 4202560 18071 0 0 0 44 7 0 0 16 0 11 0 1326279 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5616] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5616/stat : 5616 (java) S 5601 5599 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 1326279 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5617] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5617/stat : 5617 (java) S 5601 5599 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1326279 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5618] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5618/stat : 5618 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5619] ppid=5601 vsize=882736 CPUtime=1.23
/proc/5613/task/5619/stat : 5619 (java) S 5601 5599 1511 34817 1511 4202560 8715 0 0 0 123 0 0 0 15 0 11 0 1326282 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5620] ppid=5601 vsize=882736 CPUtime=1.86
/proc/5613/task/5620/stat : 5620 (java) R 5601 5599 1511 34817 1511 4202560 3470 0 0 0 186 0 0 0 16 0 11 0 1326282 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4151420923 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5613/tid=5621] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5621/stat : 5621 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1326282 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=5613/tid=5622] ppid=5601 vsize=882736 CPUtime=0
/proc/5613/task/5622/stat : 5622 (java) S 5601 5599 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 1326282 903921664 76540 1283457024 134512640 134550932 4293357296 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.37
Current children cumulated vsize (KiB) 887880

Child status: 0
Real time (s): 10.9764
CPU time (s): 10.4447
CPU user time (s): 10.1646
CPU system time (s): 0.280017
CPU usage (%): 95.1554
Max. virtual memory (cumulated for all children) (KiB): 890240

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 10.1646
system time used= 0.280017
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 85442
page faults= 2
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 989
involuntary context switches= 1019

runsolver used 0 second user time and 0 second system time

The end