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/201108300820/p2cudf-user-1.13/1aabfc32-d491-11df-9a24-00163e3d3b7c.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//1aabfc32-d491-11df-9a24-00163e3d3b7c.cudf /home/misc2010/tmp/201108300820/p2cudf-user-1.13/1aabfc32-d491-11df-9a24-00163e3d3b7c.cudf.dudf-real.result +sum(installedsize) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds
Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB
Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 0.85 0.98 0.99 2/36 4033
/proc/meminfo: memFree=933304/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=3020 CPUtime=0
/proc/4033/stat : 4033 (runsolver) D 4032 4033 2160 34817 2160 4202560 73 0 0 0 0 0 0 0 25 0 1 0 51864618 3092480 94 1283457024 134512640 134586868 4294121520 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/4033/statm: 755 94 62 19 0 54 0

[startup+0.19379 s]
/proc/loadavg: 0.85 0.98 0.99 2/36 4033
/proc/meminfo: memFree=933304/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.205824 s]
/proc/loadavg: 0.85 0.98 0.99 2/36 4033
/proc/meminfo: memFree=933304/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.305858 s]
/proc/loadavg: 0.85 0.98 0.99 2/36 4033
/proc/meminfo: memFree=933304/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.705839 s]
/proc/loadavg: 0.85 0.98 0.99 2/36 4033
/proc/meminfo: memFree=933304/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.5081 s]
/proc/loadavg: 0.85 0.98 0.99 3/48 4056
/proc/meminfo: memFree=910200/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=872920 CPUtime=0.74
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 12131 0 109 0 70 4 0 0 23 0 10 0 51864622 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218230 10456 1731 10 0 215321 0
[pid=4047/tid=4048] ppid=4035 vsize=872920 CPUtime=0.26
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 4994 0 15 0 24 2 0 0 25 0 10 0 51864652 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4095588035 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=872920 CPUtime=0
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 10 0 51864666 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=872920 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 25 0 10 0 51864667 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=872920 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 51864667 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=872920 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51864679 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=872920 CPUtime=0.18
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1616 0 1 0 18 0 0 0 21 0 10 0 51864679 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=872920 CPUtime=0.28
/proc/4047/task/4054/stat : 4054 (java) R 4035 4033 2160 34817 2160 4202560 4527 0 8 0 28 0 0 0 24 0 10 0 51864679 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4152526058 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=872920 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 10 0 51864679 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=872920 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 17 0 10 0 51864679 893870080 10456 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.75
Current children cumulated vsize (KiB) 878060

[startup+3.12048 s]
/proc/loadavg: 0.94 0.99 0.99 3/48 4056
/proc/meminfo: memFree=812240/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=878084 CPUtime=2.35
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 71845 0 109 0 207 28 0 0 23 0 10 0 51864622 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 219521 68856 1746 10 0 216612 0
[pid=4047/tid=4048] ppid=4035 vsize=878084 CPUtime=1.29
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50040 0 15 0 107 22 0 0 25 0 10 0 51864652 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=878084 CPUtime=0.25
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 11926 0 0 0 22 3 0 0 15 0 10 0 51864666 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=878084 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 25 0 10 0 51864667 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=878084 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 51864667 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=878084 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51864679 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=878084 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 10 0 51864679 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=878084 CPUtime=0.54
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7253 0 8 0 54 0 0 0 23 0 10 0 51864679 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=878084 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 10 0 51864679 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=878084 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) R 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 10 0 51864679 899158016 68856 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.36
Current children cumulated vsize (KiB) 883224
heavy processes:

[startup+6.30336 s]
/proc/loadavg: 0.94 0.99 0.99 2/49 4057
/proc/meminfo: memFree=652296/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=878408 CPUtime=5.38
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 72291 0 110 0 508 30 0 0 23 0 11 0 51864622 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 219602 69303 1781 10 0 216693 0
[pid=4047/tid=4048] ppid=4035 vsize=878408 CPUtime=2.8
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50276 0 15 0 256 24 0 0 25 0 11 0 51864652 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4096634206 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=878408 CPUtime=0.25
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 11926 0 0 0 22 3 0 0 15 0 11 0 51864666 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=878408 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=878408 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=878408 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=878408 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=878408 CPUtime=2.06
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7457 0 9 0 206 0 0 0 15 0 11 0 51864679 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=878408 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=878408 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=878408 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 899489792 69303 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.39
Current children cumulated vsize (KiB) 883548

[startup+12.7044 s]
/proc/loadavg: 0.95 0.99 0.99 2/49 4057
/proc/meminfo: memFree=606920/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=11.78
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 84820 0 110 0 1146 32 0 0 23 0 11 0 51864622 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 80551 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=8.64
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 840 24 0 0 25 0 11 0 51864652 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4096586778 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=0.71
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 24438 0 0 0 66 5 0 0 15 0 11 0 51864666 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.16
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7473 0 9 0 216 0 0 0 16 0 11 0 51864679 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 80551 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 11.79
Current children cumulated vsize (KiB) 878352

[startup+25.5074 s]
/proc/loadavg: 0.95 0.99 0.99 2/49 4057
/proc/meminfo: memFree=597000/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=24.55
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 87279 0 110 0 2422 33 0 0 23 0 11 0 51864622 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 83010 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=20.8
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 2056 24 0 0 25 0 11 0 51864652 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4096355398 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=1.29
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 26891 0 0 0 123 6 0 0 15 0 11 0 51864666 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.2
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7479 0 9 0 220 0 0 0 15 0 11 0 51864679 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 83010 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.56
Current children cumulated vsize (KiB) 878352

[startup+51.1024 s]
/proc/loadavg: 0.97 0.99 0.99 2/49 4057
/proc/meminfo: memFree=585096/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=50.13
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 90235 0 110 0 4979 34 0 0 23 0 11 0 51864622 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 85966 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=45.16
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 4491 25 0 0 25 0 11 0 51864652 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4096509794 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=2.47
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 29839 0 0 0 241 6 0 0 15 0 11 0 51864666 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 85966 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.14
Current children cumulated vsize (KiB) 878352

[startup+102.305 s]
/proc/loadavg: 0.99 0.99 0.99 2/49 4057
/proc/meminfo: memFree=567488/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=101.28
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 94593 0 110 0 10093 35 0 0 23 0 11 0 51864622 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 90324 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=95.14
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 9489 25 0 0 25 0 11 0 51864652 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4095994671 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=3.64
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 34197 0 0 0 357 7 0 0 15 0 11 0 51864666 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 90324 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 101.29
Current children cumulated vsize (KiB) 878352

[startup+162.309 s]
/proc/loadavg: 0.99 0.99 0.99 2/49 4057
/proc/meminfo: memFree=560296/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=161.25
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 96351 0 110 0 16089 36 0 0 23 0 11 0 51864622 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 92082 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=153.56
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 15331 25 0 0 25 0 11 0 51864652 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4096571193 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=5.19
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 35955 0 0 0 511 8 0 0 15 0 11 0 51864666 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 92082 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.26
Current children cumulated vsize (KiB) 878352

[startup+222.311 s]
/proc/loadavg: 0.99 0.99 0.99 2/51 4063
/proc/meminfo: memFree=538192/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=221.21
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 101815 0 110 0 22082 39 0 0 23 0 11 0 51864622 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 97546 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=212.63
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 21236 27 0 0 25 0 11 0 51864652 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4096433163 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=6.08
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 41419 0 0 0 599 9 0 0 15 0 11 0 51864666 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 97546 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.22
Current children cumulated vsize (KiB) 878352

[startup+282.303 s]
/proc/loadavg: 0.99 0.99 0.99 2/50 4068
/proc/meminfo: memFree=533256/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=281.17
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 107793 0 110 0 28077 40 0 0 23 0 11 0 51864622 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 103524 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=271.32
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 27104 28 0 0 25 0 11 0 51864652 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4096571106 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=7.36
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 47397 0 0 0 727 9 0 0 16 0 11 0 51864666 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.18
Current children cumulated vsize (KiB) 878352

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

[startup+283.214 s]
/proc/loadavg: 0.99 0.99 0.99 2/50 4068
/proc/meminfo: memFree=514408/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=282.08
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 107793 0 110 0 28168 40 0 0 23 0 11 0 51864622 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 103524 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=272.23
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 27195 28 0 0 25 0 11 0 51864652 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4096509794 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=7.36
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 47397 0 0 0 727 9 0 0 16 0 11 0 51864666 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) R 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.09
Current children cumulated vsize (KiB) 878352

[startup+284.814 s]
/proc/loadavg: 0.99 0.99 0.99 3/50 4068
/proc/meminfo: memFree=514408/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873212 CPUtime=283.68
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 107793 0 110 0 28328 40 0 0 23 0 11 0 51864622 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218303 103524 1787 10 0 215394 0
[pid=4047/tid=4048] ppid=4035 vsize=873212 CPUtime=273.83
/proc/4047/task/4048/stat : 4048 (java) R 4035 4033 2160 34817 2160 4202560 50277 0 15 0 27355 28 0 0 25 0 11 0 51864652 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4096571106 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873212 CPUtime=7.36
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 47397 0 0 0 727 9 0 0 15 0 11 0 51864666 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873212 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873212 CPUtime=2.23
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 223 0 0 0 16 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4057] ppid=4035 vsize=873212 CPUtime=0
/proc/4047/task/4057/stat : 4057 (java) S 4035 4033 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 24 0 11 0 51865117 894169088 103524 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.69
Current children cumulated vsize (KiB) 878352

[startup+285.612 s]
/proc/loadavg: 0.99 0.99 0.99 2/50 4068
/proc/meminfo: memFree=514408/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873536 CPUtime=283.96
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 107806 0 110 0 28356 40 0 0 23 0 11 0 51864622 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218384 103537 1788 10 0 215475 0
[pid=4047/tid=4048] ppid=4035 vsize=873536 CPUtime=274.04
/proc/4047/task/4048/stat : 4048 (java) S 4035 4033 2160 34817 2160 4202560 50285 0 15 0 27376 28 0 0 25 0 11 0 51864652 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873536 CPUtime=7.36
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 47397 0 0 0 727 9 0 0 16 0 11 0 51864666 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873536 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873536 CPUtime=2.28
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 228 0 0 0 15 0 11 0 51864679 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894500864 103537 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.97
Current children cumulated vsize (KiB) 878676

[startup+285.812 s]
/proc/loadavg: 0.99 0.99 0.99 2/50 4068
/proc/meminfo: memFree=514408/1048576 swapFree=0/0
[pid=4033] ppid=4032 vsize=2568 CPUtime=0
/proc/4033/stat : 4033 (p2cudf-user-1.1) S 4032 4033 2160 34817 2160 4202496 374 0 0 0 0 0 0 0 25 0 1 0 51864618 2629632 270 1283457024 134512640 135304128 4292333392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4033/statm: 642 270 230 194 0 29 0
[pid=4035] ppid=4033 vsize=2572 CPUtime=0.01
/proc/4035/stat : 4035 (p2cudf) S 4033 4033 2160 34817 2160 4202496 387 3014 0 1 0 0 1 0 25 0 1 0 51864618 2633728 279 1283457024 134512640 135304128 4290633184 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/4035/statm: 643 279 239 194 0 30 0
[pid=4047] ppid=4035 vsize=873536 CPUtime=284.16
/proc/4047/stat : 4047 (java) S 4035 4033 2160 34817 2160 4202496 107808 0 110 0 28372 44 0 0 23 0 11 0 51864622 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/4047/statm: 218384 103539 1788 10 0 215475 0
[pid=4047/tid=4048] ppid=4035 vsize=873536 CPUtime=274.04
/proc/4047/task/4048/stat : 4048 (java) S 4035 4033 2160 34817 2160 4202560 50285 0 15 0 27376 28 0 0 25 0 11 0 51864652 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4049] ppid=4035 vsize=873536 CPUtime=7.36
/proc/4047/task/4049/stat : 4049 (java) S 4035 4033 2160 34817 2160 4202560 47397 0 0 0 727 9 0 0 16 0 11 0 51864666 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 0 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4050] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4050/stat : 4050 (java) S 4035 4033 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 51864667 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4051] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4051/stat : 4051 (java) S 4035 4033 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51864667 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4052] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4052/stat : 4052 (java) S 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51864679 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4053] ppid=4035 vsize=873536 CPUtime=0.22
/proc/4047/task/4053/stat : 4053 (java) S 4035 4033 2160 34817 2160 4202560 1638 0 1 0 22 0 0 0 15 0 11 0 51864679 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4054] ppid=4035 vsize=873536 CPUtime=2.35
/proc/4047/task/4054/stat : 4054 (java) S 4035 4033 2160 34817 2160 4202560 7487 0 9 0 235 0 0 0 16 0 11 0 51864679 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4055] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4055/stat : 4055 (java) S 4035 4033 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 51864679 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 18446612133395905408 0 0 -1 0 0 0 0
[pid=4047/tid=4056] ppid=4035 vsize=873536 CPUtime=0
/proc/4047/task/4056/stat : 4056 (java) R 4035 4033 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 51864679 894500864 103539 1283457024 134512640 134550932 4288885456 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.17
Current children cumulated vsize (KiB) 878676

Child status: 0
Real time (s): 285.892
CPU time (s): 284.206
CPU user time (s): 283.738
CPU system time (s): 0.468029
CPU usage (%): 99.4102
Max. virtual memory (cumulated for all children) (KiB): 884212

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 283.738
system time used= 0.468029
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 111606
page faults= 111
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 6510
involuntary context switches= 8678

runsolver used 0 second user time and 0 second system time

The end