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/201108291842/p2cudf-user-1.13/d0cc7514-c730-11df-a040-00163e3d3b7c.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//d0cc7514-c730-11df-a040-00163e3d3b7c.cudf /home/misc2010/tmp/201108291842/p2cudf-user-1.13/d0cc7514-c730-11df-a040-00163e3d3b7c.cudf.dudf-real.result -notuptodate,-removed,-changed 

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


[startup+0 s]
/proc/loadavg: 1.11 1.17 1.12 5/34 29852
/proc/meminfo: memFree=758512/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) R 29850 29851 17511 34816 17511 4202496 358 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2572 CPUtime=0
/proc/29852/stat : 29852 (p2cudf-user-1.1) R 29851 29851 17511 34816 17511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 46611241 2633728 41 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/29852/statm: 643 41 0 194 0 30 0

[startup+0.13417 s]
/proc/loadavg: 1.11 1.17 1.12 5/34 29852
/proc/meminfo: memFree=758512/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+0.214188 s]
/proc/loadavg: 1.11 1.17 1.12 5/34 29852
/proc/meminfo: memFree=758512/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+0.314207 s]
/proc/loadavg: 1.11 1.17 1.12 5/34 29852
/proc/meminfo: memFree=758512/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+0.714352 s]
/proc/loadavg: 1.11 1.17 1.12 5/34 29852
/proc/meminfo: memFree=758512/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5140

[startup+1.51447 s]
/proc/loadavg: 1.11 1.17 1.12 4/45 29874
/proc/meminfo: memFree=700384/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=879764 CPUtime=1.47
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 22413 0 1 0 134 13 0 0 25 0 10 0 46611244 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 219941 19662 1723 10 0 217032 0
[pid=29865/tid=29866] ppid=29852 vsize=879764 CPUtime=0.8
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 12420 0 1 0 69 11 0 0 25 0 10 0 46611245 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4095339163 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=879764 CPUtime=0
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 7 0 0 0 0 0 0 0 17 0 10 0 46611246 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=879764 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 46611246 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=879764 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 46611247 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=879764 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46611249 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=879764 CPUtime=0.55
/proc/29865/task/29871/stat : 29871 (java) R 29852 29851 17511 34816 17511 4202560 7551 0 0 0 54 1 0 0 25 0 10 0 46611249 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4147212416 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=879764 CPUtime=0.11
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1362 0 0 0 11 0 0 0 19 0 10 0 46611249 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=879764 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46611249 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=879764 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46611249 900878336 19662 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 884904

[startup+3.1049 s]
/proc/loadavg: 1.18 1.18 1.12 2/45 29874
/proc/meminfo: memFree=597712/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=878748 CPUtime=3.06
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 72256 0 1 0 268 38 0 0 25 0 10 0 46611244 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 219687 69280 1743 10 0 216778 0
[pid=29865/tid=29866] ppid=29852 vsize=878748 CPUtime=1.77
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50089 0 1 0 146 31 0 0 25 0 10 0 46611245 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=878748 CPUtime=0.25
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 12100 0 0 0 20 5 0 0 16 0 10 0 46611246 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=878748 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 46611246 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=878748 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 46611247 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=878748 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46611249 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=878748 CPUtime=0.79
/proc/29865/task/29871/stat : 29871 (java) R 29852 29851 17511 34816 17511 4202560 7597 0 0 0 78 1 0 0 17 0 10 0 46611249 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4151966033 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=878748 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 10 0 46611249 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=878748 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46611249 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=878748 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46611249 899837952 69280 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 883888

[startup+6.30572 s]
/proc/loadavg: 1.18 1.18 1.12 2/46 29875
/proc/meminfo: memFree=486104/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=879072 CPUtime=6.26
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 72620 0 1 0 586 40 0 0 25 0 11 0 46611244 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 219768 69644 1779 10 0 216859 0
[pid=29865/tid=29866] ppid=29852 vsize=879072 CPUtime=3.49
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50270 0 1 0 316 33 0 0 25 0 11 0 46611245 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4096028872 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=879072 CPUtime=0.25
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 12100 0 0 0 20 5 0 0 16 0 11 0 46611246 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=879072 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=879072 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=879072 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=879072 CPUtime=2.26
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7776 0 0 0 225 1 0 0 16 0 11 0 46611249 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=879072 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=879072 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=879072 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=879072 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 900169728 69644 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 884212

[startup+12.7072 s]
/proc/loadavg: 1.15 1.17 1.12 2/46 29875
/proc/meminfo: memFree=439860/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=12.66
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 85483 0 1 0 1222 44 0 0 25 0 11 0 46611244 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 81158 1782 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=9.16
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50277 0 1 0 883 33 0 0 25 0 11 0 46611245 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4096124848 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=0.58
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 24920 0 0 0 49 9 0 0 16 0 11 0 46611246 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=2.67
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7812 0 0 0 266 1 0 0 15 0 11 0 46611249 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 81158 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 878740

[startup+25.5109 s]
/proc/loadavg: 1.13 1.17 1.12 2/46 29875
/proc/meminfo: memFree=439736/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=25.46
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 85490 0 1 0 2502 44 0 0 25 0 11 0 46611244 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 81165 1784 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=21.79
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50278 0 1 0 2146 33 0 0 25 0 11 0 46611245 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4096108028 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=0.58
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 24920 0 0 0 49 9 0 0 16 0 11 0 46611246 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=2.83
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7818 0 0 0 282 1 0 0 15 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.49
Current children cumulated vsize (KiB) 878740

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

[startup+25.7109 s]
/proc/loadavg: 1.13 1.17 1.12 2/46 29875
/proc/meminfo: memFree=439736/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=25.66
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 85490 0 1 0 2522 44 0 0 25 0 11 0 46611244 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 81165 1784 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=21.99
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50278 0 1 0 2166 33 0 0 25 0 11 0 46611245 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4096016369 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=0.58
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 24920 0 0 0 49 9 0 0 16 0 11 0 46611246 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=2.83
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7818 0 0 0 282 1 0 0 15 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 81165 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.69
Current children cumulated vsize (KiB) 878740

[startup+38.5047 s]
/proc/loadavg: 1.10 1.16 1.12 2/46 29875
/proc/meminfo: memFree=414812/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=38.43
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91723 0 1 0 3797 46 0 0 25 0 11 0 46611244 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 87398 1788 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=33.65
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50279 0 1 0 3332 33 0 0 25 0 11 0 46611245 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4096015846 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 15 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=3.35
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 333 2 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 38.46
Current children cumulated vsize (KiB) 878740

[startup+41.7055 s]
/proc/loadavg: 1.10 1.16 1.12 2/46 29875
/proc/meminfo: memFree=414688/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=41.63
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91723 0 1 0 4117 46 0 0 25 0 11 0 46611244 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 87398 1788 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=36.86
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50279 0 1 0 3653 33 0 0 25 0 11 0 46611245 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4096044816 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 15 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=3.35
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 333 2 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 41.66
Current children cumulated vsize (KiB) 878740

[startup+44.9063 s]
/proc/loadavg: 1.09 1.16 1.12 2/46 29875
/proc/meminfo: memFree=414688/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=44.83
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91723 0 1 0 4437 46 0 0 25 0 11 0 46611244 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 87398 1788 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=40.06
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50279 0 1 0 3973 33 0 0 25 0 11 0 46611245 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4096045099 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 15 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=3.35
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 333 2 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 44.86
Current children cumulated vsize (KiB) 878740

[startup+46.5066 s]
/proc/loadavg: 1.09 1.16 1.12 2/46 29875
/proc/meminfo: memFree=414688/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873600 CPUtime=46.43
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91723 0 1 0 4597 46 0 0 25 0 11 0 46611244 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218400 87398 1788 10 0 215491 0
[pid=29865/tid=29866] ppid=29852 vsize=873600 CPUtime=41.61
/proc/29865/task/29866/stat : 29866 (java) R 29852 29851 17511 34816 17511 4202560 50279 0 1 0 4128 33 0 0 25 0 11 0 46611245 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4096016299 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873600 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 16 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873600 CPUtime=3.4
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 338 2 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873600 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29875] ppid=29852 vsize=873600 CPUtime=0
/proc/29865/task/29875/stat : 29875 (java) S 29852 29851 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 46611747 894566400 87398 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 46.46
Current children cumulated vsize (KiB) 878740

[startup+47.3042 s]
/proc/loadavg: 1.09 1.16 1.12 2/46 29875
/proc/meminfo: memFree=414688/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873924 CPUtime=46.82
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91736 0 1 0 4636 46 0 0 25 0 11 0 46611244 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218481 87411 1789 10 0 215572 0
[pid=29865/tid=29866] ppid=29852 vsize=873924 CPUtime=41.93
/proc/29865/task/29866/stat : 29866 (java) S 29852 29851 17511 34816 17511 4202560 50287 0 1 0 4160 33 0 0 25 0 11 0 46611245 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873924 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 16 0 11 0 46611246 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873924 CPUtime=3.46
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 344 2 0 0 16 0 11 0 46611249 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873924 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894898176 87411 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 46.85
Current children cumulated vsize (KiB) 879064

[startup+47.5123 s]
/proc/loadavg: 1.08 1.15 1.12 1/46 29876
/proc/meminfo: memFree=414564/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873924 CPUtime=46.91
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91738 0 1 0 4645 46 0 0 25 0 11 0 46611244 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218481 87413 1789 10 0 215572 0
[pid=29865/tid=29866] ppid=29852 vsize=873924 CPUtime=41.93
/proc/29865/task/29866/stat : 29866 (java) S 29852 29851 17511 34816 17511 4202560 50287 0 1 0 4160 33 0 0 25 0 11 0 46611245 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873924 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 16 0 11 0 46611246 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 46611246 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46611247 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873924 CPUtime=3.49
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 347 2 0 0 15 0 11 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873924 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 11 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29876] ppid=29852 vsize=873924 CPUtime=0.06
/proc/29865/task/29876/stat : 29876 (java) R 29852 29851 17511 34816 17511 4202560 6 0 0 0 6 0 0 0 20 0 11 0 46615931 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4095007911 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 46.94
Current children cumulated vsize (KiB) 879064

[startup+47.6075 s]
/proc/loadavg: 1.08 1.15 1.12 1/46 29876
/proc/meminfo: memFree=414564/1048576 swapFree=0/0
[pid=29851] ppid=29850 vsize=2572 CPUtime=0
/proc/29851/stat : 29851 (p2cudf-user-1.1) S 29850 29851 17511 34816 17511 4202496 377 0 0 0 0 0 0 0 25 0 1 0 46611240 2633728 271 1283457024 134512640 135304128 4289799392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29851/statm: 643 271 230 194 0 30 0
[pid=29852] ppid=29851 vsize=2568 CPUtime=0.03
/proc/29852/stat : 29852 (p2cudf) S 29851 29851 17511 34816 17511 4202496 391 3014 0 1 1 0 1 1 25 0 1 0 46611241 2629632 280 1283457024 134512640 135304128 4293458048 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/29852/statm: 642 280 239 194 0 29 0
[pid=29865] ppid=29852 vsize=873924 CPUtime=47.01
/proc/29865/stat : 29865 (java) S 29852 29851 17511 34816 17511 4202496 91738 0 1 0 4650 51 0 0 25 0 10 0 46611244 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/29865/statm: 218481 87413 1789 10 0 215572 0
[pid=29865/tid=29866] ppid=29852 vsize=873924 CPUtime=41.93
/proc/29865/task/29866/stat : 29866 (java) S 29852 29851 17511 34816 17511 4202560 50287 0 1 0 4160 33 0 0 18 0 10 0 46611245 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29867] ppid=29852 vsize=873924 CPUtime=1.17
/proc/29865/task/29867/stat : 29867 (java) S 29852 29851 17511 34816 17511 4202560 31113 0 0 0 107 10 0 0 16 0 10 0 46611246 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 0 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29868] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29868/stat : 29868 (java) S 29852 29851 17511 34816 17511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 46611246 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29869] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29869/stat : 29869 (java) S 29852 29851 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 46611247 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29870] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29870/stat : 29870 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29871] ppid=29852 vsize=873924 CPUtime=3.52
/proc/29865/task/29871/stat : 29871 (java) S 29852 29851 17511 34816 17511 4202560 7857 0 0 0 350 2 0 0 15 0 10 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29872] ppid=29852 vsize=873924 CPUtime=0.24
/proc/29865/task/29872/stat : 29872 (java) S 29852 29851 17511 34816 17511 4202560 1394 0 0 0 24 0 0 0 15 0 10 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29873] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29873/stat : 29873 (java) S 29852 29851 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
[pid=29865/tid=29874] ppid=29852 vsize=873924 CPUtime=0
/proc/29865/task/29874/stat : 29874 (java) S 29852 29851 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46611249 894898176 87413 1283457024 134512640 134550932 4293625664 18446744073709551615 4294960130 0 4 0 16800975 18446612133194179776 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 47.04
Current children cumulated vsize (KiB) 879064

Child status: 0
Real time (s): 47.6578
CPU time (s): 47.0789
CPU user time (s): 46.5309
CPU system time (s): 0.548034
CPU usage (%): 98.7854
Max. virtual memory (cumulated for all children) (KiB): 884904

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

runsolver used 0 second user time and 0 second system time

The end