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/rand375.cudf.user-upgrades.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/user-upgrades/rand375.cudf /home/misc2010/tmp/201108300820/p2cudf-user-1.13/rand375.cudf.user-upgrades.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/37 5792
/proc/meminfo: memFree=921456/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=3024 CPUtime=0
/proc/5792/stat : 5792 (runsolver) D 5791 5792 2160 34817 2160 4202560 73 0 0 0 0 0 0 0 25 0 1 0 52815743 3096576 94 1283457024 134512640 134586868 4293830704 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/5792/statm: 756 94 62 19 0 55 0

[startup+0.173171 s]
/proc/loadavg: 0.85 0.98 0.99 2/37 5792
/proc/meminfo: memFree=921456/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.205205 s]
/proc/loadavg: 0.85 0.98 0.99 2/37 5792
/proc/meminfo: memFree=921456/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.305207 s]
/proc/loadavg: 0.85 0.98 0.99 2/37 5792
/proc/meminfo: memFree=921456/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.705184 s]
/proc/loadavg: 0.85 0.98 0.99 2/37 5792
/proc/meminfo: memFree=921456/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.50969 s]
/proc/loadavg: 0.86 0.98 0.99 4/49 5815
/proc/meminfo: memFree=886324/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=888772 CPUtime=0.88
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 16741 0 105 0 84 4 0 0 23 0 10 0 52815747 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 222193 13923 1722 10 0 219284 0
[pid=5806/tid=5807] ppid=5794 vsize=888772 CPUtime=0.31
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 5305 0 15 0 29 2 0 0 25 0 10 0 52815773 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4095302532 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=888772 CPUtime=0
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 7 0 0 0 0 0 0 0 19 0 10 0 52815783 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=888772 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 52815783 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=888772 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 52815784 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=888772 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 52815793 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=888772 CPUtime=0.3
/proc/5806/task/5812/stat : 5812 (java) R 5794 5792 2160 34817 2160 4202560 4703 0 1 0 29 1 0 0 25 0 10 0 52815793 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=888772 CPUtime=0.26
/proc/5806/task/5813/stat : 5813 (java) R 5794 5792 2160 34817 2160 4202560 5736 0 7 0 26 0 0 0 23 0 10 0 52815793 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4152669863 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=888772 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 10 0 52815793 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=888772 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) R 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 10 0 52815793 910102528 13923 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.89
Current children cumulated vsize (KiB) 893908

[startup+3.12018 s]
/proc/loadavg: 0.86 0.98 0.99 2/49 5815
/proc/meminfo: memFree=804732/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=886392 CPUtime=2.49
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 71879 0 105 0 224 25 0 0 23 0 10 0 52815747 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221598 68826 1740 10 0 218689 0
[pid=5806/tid=5807] ppid=5794 vsize=886392 CPUtime=1.33
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50034 0 15 0 115 18 0 0 25 0 10 0 52815773 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=886392 CPUtime=0.2
/proc/5806/task/5808/stat : 5808 (java) R 5794 5792 2160 34817 2160 4202560 9869 0 0 0 16 4 0 0 15 0 10 0 52815783 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4149428600 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=886392 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 52815783 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=886392 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 52815784 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=886392 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 52815793 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=886392 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 10 0 52815793 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=886392 CPUtime=0.39
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 5785 0 7 0 39 0 0 0 18 0 10 0 52815793 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=886392 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 10 0 52815793 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=886392 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 10 0 52815793 907665408 68826 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.5
Current children cumulated vsize (KiB) 891528

[startup+6.30747 s]
/proc/loadavg: 0.86 0.98 0.99 2/50 5816
/proc/meminfo: memFree=636480/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=5.52
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 73990 0 105 0 524 28 0 0 23 0 11 0 52815747 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 70237 1775 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=2.85
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50273 0 15 0 264 21 0 0 25 0 11 0 52815773 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4096166672 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=0.22
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 10626 0 0 0 18 4 0 0 15 0 11 0 52815783 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=1.88
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6893 0 7 0 188 0 0 0 15 0 11 0 52815793 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 70237 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.53
Current children cumulated vsize (KiB) 892668

[startup+12.7089 s]
/proc/loadavg: 0.96 1.00 0.99 3/50 5816
/proc/meminfo: memFree=584284/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=11.92
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 86972 0 105 0 1158 34 0 0 23 0 11 0 52815747 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 83219 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=7.9
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 768 22 0 0 25 0 11 0 52815773 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4096243632 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=1.42
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 23580 0 0 0 132 10 0 0 16 0 11 0 52815783 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.04
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 204 0 0 0 16 0 11 0 52815793 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 83219 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 11.93
Current children cumulated vsize (KiB) 892668

[startup+25.5123 s]
/proc/loadavg: 0.97 1.00 0.99 2/50 5816
/proc/meminfo: memFree=578084/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=24.7
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 88525 0 105 0 2435 35 0 0 23 0 11 0 52815747 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 84772 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=19.37
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 1915 22 0 0 25 0 11 0 52815773 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4095557968 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=2.73
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 25133 0 0 0 262 11 0 0 16 0 11 0 52815783 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.04
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 204 0 0 0 16 0 11 0 52815793 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 84772 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.71
Current children cumulated vsize (KiB) 892668

[startup+51.1069 s]
/proc/loadavg: 1.11 1.03 1.00 2/49 5816
/proc/meminfo: memFree=569660/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=50.27
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 90601 0 105 0 4991 36 0 0 23 0 11 0 52815747 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 86848 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=42.84
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 4262 22 0 0 25 0 11 0 52815773 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4095924875 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=4.84
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 27209 0 0 0 472 12 0 0 15 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.04
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 204 0 0 0 16 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.28
Current children cumulated vsize (KiB) 892668

[startup+102.308 s]
/proc/loadavg: 1.05 1.02 1.00 3/49 5816
/proc/meminfo: memFree=569536/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=101.42
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 90601 0 105 0 10103 39 0 0 23 0 11 0 52815747 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 86848 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=89.75
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 8952 23 0 0 25 0 11 0 52815773 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4096203580 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=9.06
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 27209 0 0 0 893 13 0 0 15 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.04
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 204 0 0 0 16 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) R 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 101.43
Current children cumulated vsize (KiB) 892668

[startup+162.309 s]
/proc/loadavg: 1.17 1.05 1.01 2/49 5816
/proc/meminfo: memFree=569288/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=161.32
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 90601 0 105 0 16092 40 0 0 23 0 11 0 52815747 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 86848 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=144.65
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 14441 24 0 0 25 0 11 0 52815773 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=14.06
/proc/5806/task/5808/stat : 5808 (java) R 5794 5792 2160 34817 2160 4202560 27209 0 0 0 1393 13 0 0 16 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4150207195 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.04
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 204 0 0 0 16 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) R 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.33
Current children cumulated vsize (KiB) 892668

[startup+222.312 s]
/proc/loadavg: 1.23 1.08 1.02 2/49 5816
/proc/meminfo: memFree=569288/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=221.28
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 90601 0 105 0 22088 40 0 0 23 0 11 0 52815747 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 86848 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=199.74
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 19950 24 0 0 25 0 11 0 52815773 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4096250216 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=18.92
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 27209 0 0 0 1879 13 0 0 16 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.06
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 206 0 0 0 15 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 86848 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.29
Current children cumulated vsize (KiB) 892668

[startup+282.304 s]
/proc/loadavg: 1.16 1.08 1.02 2/49 5816
/proc/meminfo: memFree=565940/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=281.23
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 91413 0 105 0 28081 42 0 0 23 0 11 0 52815747 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 87660 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=254.85
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 25461 24 0 0 25 0 11 0 52815773 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4095924835 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=23.75
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 28021 0 0 0 2360 15 0 0 15 0 11 0 52815783 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.06
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 206 0 0 0 15 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.24
Current children cumulated vsize (KiB) 892668

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

[startup+283.204 s]
/proc/loadavg: 1.16 1.08 1.02 2/49 5816
/proc/meminfo: memFree=565940/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=282.12
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 91413 0 105 0 28170 42 0 0 23 0 11 0 52815747 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 87660 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=255.75
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 25551 24 0 0 25 0 11 0 52815773 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4096273327 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=23.75
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 28021 0 0 0 2360 15 0 0 15 0 11 0 52815783 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.06
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 206 0 0 0 15 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.13
Current children cumulated vsize (KiB) 892668

[startup+284.805 s]
/proc/loadavg: 1.16 1.08 1.02 2/49 5816
/proc/meminfo: memFree=565940/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887532 CPUtime=283.72
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 91413 0 105 0 28330 42 0 0 23 0 11 0 52815747 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221883 87660 1783 10 0 218974 0
[pid=5806/tid=5807] ppid=5794 vsize=887532 CPUtime=257.08
/proc/5806/task/5807/stat : 5807 (java) R 5794 5792 2160 34817 2160 4202560 50274 0 15 0 25684 24 0 0 25 0 11 0 52815773 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4096167060 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887532 CPUtime=24.02
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 28021 0 0 0 2387 15 0 0 16 0 11 0 52815783 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887532 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887532 CPUtime=2.06
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 206 0 0 0 15 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5816] ppid=5794 vsize=887532 CPUtime=0
/proc/5806/task/5816/stat : 5816 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52816225 908832768 87660 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.73
Current children cumulated vsize (KiB) 892668

[startup+285.207 s]
/proc/loadavg: 1.16 1.08 1.02 1/49 5817
/proc/meminfo: memFree=565880/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887856 CPUtime=283.76
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 91425 0 105 0 28334 42 0 0 23 0 11 0 52815747 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221964 87672 1784 10 0 219055 0
[pid=5806/tid=5807] ppid=5794 vsize=887856 CPUtime=257.1
/proc/5806/task/5807/stat : 5807 (java) S 5794 5792 2160 34817 2160 4202560 50281 0 15 0 25686 24 0 0 25 0 11 0 52815773 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887856 CPUtime=24.02
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 28021 0 0 0 2387 15 0 0 16 0 11 0 52815783 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887856 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887856 CPUtime=2.07
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 207 0 0 0 15 0 11 0 52815793 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5817] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5817/stat : 5817 (java) S 5794 5792 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 52844229 909164544 87672 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.77
Current children cumulated vsize (KiB) 892992

[startup+285.409 s]
/proc/loadavg: 1.16 1.08 1.02 1/49 5817
/proc/meminfo: memFree=565880/1048576 swapFree=0/0
[pid=5792] ppid=5791 vsize=2568 CPUtime=0
/proc/5792/stat : 5792 (p2cudf-user-1.1) S 5791 5792 2160 34817 2160 4202496 375 0 0 0 0 0 0 0 25 0 1 0 52815743 2629632 270 1283457024 134512640 135304128 4293050416 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5792/statm: 642 270 230 194 0 29 0
[pid=5794] ppid=5792 vsize=2568 CPUtime=0.01
/proc/5794/stat : 5794 (p2cudf) S 5792 5792 2160 34817 2160 4202496 388 3013 0 1 0 0 0 1 25 0 1 0 52815743 2629632 279 1283457024 134512640 135304128 4293070944 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/5794/statm: 642 279 239 194 0 29 0
[pid=5806] ppid=5794 vsize=887856 CPUtime=283.8
/proc/5806/stat : 5806 (java) S 5794 5792 2160 34817 2160 4202496 91426 0 105 0 28337 43 0 0 23 0 11 0 52815747 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/5806/statm: 221964 87673 1784 10 0 219055 0
[pid=5806/tid=5807] ppid=5794 vsize=887856 CPUtime=257.1
/proc/5806/task/5807/stat : 5807 (java) S 5794 5792 2160 34817 2160 4202560 50281 0 15 0 25686 24 0 0 25 0 11 0 52815773 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5808] ppid=5794 vsize=887856 CPUtime=24.02
/proc/5806/task/5808/stat : 5808 (java) S 5794 5792 2160 34817 2160 4202560 28021 0 0 0 2387 15 0 0 16 0 11 0 52815783 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5809] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5809/stat : 5809 (java) S 5794 5792 2160 34817 2160 4202560 8 0 1 0 0 0 0 0 18 0 11 0 52815783 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5810] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5810/stat : 5810 (java) S 5794 5792 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 52815784 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5811] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5811/stat : 5811 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 52815793 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5812] ppid=5794 vsize=887856 CPUtime=0.54
/proc/5806/task/5812/stat : 5812 (java) S 5794 5792 2160 34817 2160 4202560 5201 0 1 0 53 1 0 0 24 0 11 0 52815793 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5813] ppid=5794 vsize=887856 CPUtime=2.07
/proc/5806/task/5813/stat : 5813 (java) S 5794 5792 2160 34817 2160 4202560 6920 0 7 0 207 0 0 0 15 0 11 0 52815793 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5814] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5814/stat : 5814 (java) S 5794 5792 2160 34817 2160 4202560 0 0 0 0 0 0 0 0 25 0 11 0 52815793 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5815] ppid=5794 vsize=887856 CPUtime=0
/proc/5806/task/5815/stat : 5815 (java) S 5794 5792 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 15 0 11 0 52815793 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0
[pid=5806/tid=5817] ppid=5794 vsize=887856 CPUtime=0.03
/proc/5806/task/5817/stat : 5817 (java) R 5794 5792 2160 34817 2160 4202560 5 0 0 0 2 1 0 0 20 0 11 0 52844229 909164544 87673 1283457024 134512640 134550932 4290829536 18446744073709551615 4095242092 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.81
Current children cumulated vsize (KiB) 892992

Child status: 0
Real time (s): 285.468
CPU time (s): 283.89
CPU user time (s): 283.418
CPU system time (s): 0.472029
CPU usage (%): 99.4472
Max. virtual memory (cumulated for all children) (KiB): 893908

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 283.418
system time used= 0.472029
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 95227
page faults= 106
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 6577
involuntary context switches= 8947

runsolver used 0 second user time and 0.004 second system time

The end