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: runsolver -s SIGUSR1 -M 1124 -C 150 -d 10 -w /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/paranoid-size/install/rand209.cudf.log.runsolver /home/competition/p2cudf-basic-1.15/p2cudf-basic-1.15 /home/competition/data/install/rand209.cudf /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/paranoid-size/install/rand209.cudf.result -count(removed),-sum(solution,installedsize),-count(changed) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 150 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 180 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

[startup+0 s]
/proc/loadavg: 1.04 1.01 0.92 2/60 21900
/proc/meminfo: memFree=336452/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=3152 CPUtime=0
/proc/21900/stat : 21900 (runsolver) R 21899 1733 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 115395261 3227648 32 18446744073709551615 134512640 134586868 4294477232 4294475280 4151669808 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/21900/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.194198 s]
/proc/loadavg: 1.04 1.01 0.92 2/60 21900
/proc/meminfo: memFree=336452/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200422 s]
/proc/loadavg: 1.04 1.01 0.92 2/60 21900
/proc/meminfo: memFree=336452/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300391 s]
/proc/loadavg: 1.04 1.01 0.92 2/60 21900
/proc/meminfo: memFree=336452/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700283 s]
/proc/loadavg: 1.04 1.01 0.92 2/60 21900
/proc/meminfo: memFree=336452/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50044 s]
/proc/loadavg: 1.04 1.01 0.92 1/63 21904
/proc/meminfo: memFree=322780/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=944496 CPUtime=0.08
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 3543 0 91 0 4 4 0 0 20 0 5 0 115395275 967163904 3303 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 4 16800971 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 236124 3303 1781 9 0 223512 0
[pid=21903/tid=21904] ppid=21900 vsize=944496 CPUtime=0.06
/proc/21903/task/21904/stat : 21904 (java) D 21900 21900 1733 0 -1 4202560 2295 0 51 0 3 3 0 0 20 0 5 0 115395322 967163904 3303 18446744073709551615 4194304 4228012 140734343599568 139676995610232 139676981973269 0 4 4 16800971 0 0 0 -1 0 0 0 82 0 0
Current children cumulated CPU time (s) 0.08
Current children cumulated vsize (KiB) 953664

[startup+3.1008 s]
/proc/loadavg: 1.04 1.01 0.92 1/71 21912
/proc/meminfo: memFree=314408/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=958788 CPUtime=0.66
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 9347 0 110 0 59 7 0 0 20 0 10 0 115395275 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 239697 8174 2325 9 0 227047 0
[pid=21903/tid=21904] ppid=21900 vsize=958788 CPUtime=0.4
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 4868 0 59 0 35 5 0 0 20 0 10 0 115395322 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676995621472 139676893421832 0 4 0 16800975 0 0 0 -1 0 0 0 186 0 0
[pid=21903/tid=21905] ppid=21900 vsize=958788 CPUtime=0
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115395386 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21906] ppid=21900 vsize=958788 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 115395398 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=958788 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 115395398 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21908] ppid=21900 vsize=958788 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115395424 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=958788 CPUtime=0.13
/proc/21903/task/21909/stat : 21909 (java) R 21900 21900 1733 0 -1 4202560 1921 0 11 0 12 1 0 0 20 0 10 0 115395424 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676884044632 139676975795920 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=21903/tid=21910] ppid=21900 vsize=958788 CPUtime=0.1
/proc/21903/task/21910/stat : 21910 (java) R 21900 21900 1733 0 -1 4202560 1309 0 0 0 10 0 0 0 20 0 10 0 115395424 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676882989896 139676974787054 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21911] ppid=21900 vsize=958788 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115395424 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=958788 CPUtime=0
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115395424 981798912 8174 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 0.66
Current children cumulated vsize (KiB) 967956
heavy processes:

[startup+6.30077 s]
/proc/loadavg: 1.12 1.03 0.92 2/71 21912
/proc/meminfo: memFree=39996/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1056736 CPUtime=3.58
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 89573 0 111 0 322 36 0 0 20 0 10 0 115395275 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264184 84129 2367 9 0 251534 0
[pid=21903/tid=21904] ppid=21900 vsize=1056736 CPUtime=1.84
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47358 0 59 0 162 22 0 0 20 0 10 0 115395322 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676995621568 139676893505462 0 4 0 16800975 0 0 0 -1 0 0 0 203 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1056736 CPUtime=0.37
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 21300 0 1 0 32 5 0 0 20 0 10 0 115395386 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1056736 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115395398 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1056736 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115395398 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1056736 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115395424 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1056736 CPUtime=0.66
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10281 0 11 0 61 5 0 0 20 0 10 0 115395424 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 17 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1056736 CPUtime=0.67
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 9381 0 0 0 64 3 0 0 20 0 10 0 115395424 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1056736 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115395424 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1056736 CPUtime=0
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115395424 1082097664 84129 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.58
Current children cumulated vsize (KiB) 1065904
heavy processes:

[startup+12.7008 s]
/proc/loadavg: 1.11 1.03 0.92 4/71 21912
/proc/meminfo: memFree=10100/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1058424 CPUtime=8.72
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 147537 0 116 0 817 55 0 0 20 0 10 0 115395275 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264606 141279 2374 9 0 251956 0
[pid=21903/tid=21904] ppid=21900 vsize=1058424 CPUtime=4.52
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47660 0 61 0 426 26 0 0 20 0 10 0 115395322 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676995629760 139676972042670 0 4 0 16800975 0 0 0 -1 0 0 0 306 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1058424 CPUtime=1.66
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 77222 0 1 0 146 20 0 0 20 0 10 0 115395386 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 5 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1058424 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115395398 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1058424 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115395398 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1058424 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115395424 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1058424 CPUtime=1.19
/proc/21903/task/21909/stat : 21909 (java) R 21900 21900 1733 0 -1 4202560 10325 0 13 0 114 5 0 0 20 0 10 0 115395424 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676884056848 139676972328547 0 4 0 16800975 0 0 0 -1 0 0 0 19 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1058424 CPUtime=1.31
/proc/21903/task/21910/stat : 21910 (java) R 21900 21900 1733 0 -1 4202560 11074 0 1 0 127 4 0 0 20 0 10 0 115395424 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676882992008 139676973371008 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1058424 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115395424 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1058424 CPUtime=0
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 115395424 1083826176 141279 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 8.72
Current children cumulated vsize (KiB) 1067592
heavy processes:

[startup+25.5008 s]
/proc/loadavg: 1.15 1.04 0.93 2/72 21913
/proc/meminfo: memFree=10240/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=21.21
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 163576 0 124 0 2061 60 0 0 20 0 11 0 115395275 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 155785 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=13.51
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 1324 27 0 0 20 0 11 0 115395322 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676894242765 0 4 0 16800975 0 0 0 -1 0 0 0 314 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=3.95
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 92449 0 6 0 371 24 0 0 20 0 11 0 115395386 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.81
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 176 5 0 0 20 0 11 0 115395424 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.9
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11760 0 2 0 186 4 0 0 20 0 11 0 115395424 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.01
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 4 0 0 0 1 0 0 0 20 0 11 0 115395424 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 155785 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 21.21
Current children cumulated vsize (KiB) 1066932

[startup+51.1007 s]
/proc/loadavg: 1.16 1.05 0.93 2/72 21913
/proc/meminfo: memFree=10116/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=46.44
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 164463 0 124 0 4583 61 0 0 20 0 11 0 115395275 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 156668 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=34.8
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 3453 27 0 0 20 0 11 0 115395322 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893259271 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=7.82
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 93331 0 6 0 758 24 0 0 20 0 11 0 115395386 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.83
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 178 5 0 0 20 0 11 0 115395424 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.92
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 188 4 0 0 20 0 11 0 115395424 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.02
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 7 0 0 0 2 0 0 0 20 0 11 0 115395424 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 156668 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 46.44
Current children cumulated vsize (KiB) 1066932

[startup+102.301 s]
/proc/loadavg: 1.07 1.04 0.93 2/72 21913
/proc/meminfo: memFree=10148/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=97.12
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 167634 0 124 0 9650 62 0 0 20 0 11 0 115395275 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 159835 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=81.31
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 8104 27 0 0 20 0 11 0 115395322 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893874748 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=11.97
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 96499 0 6 0 1171 26 0 0 20 0 11 0 115395386 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.83
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 178 5 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.92
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 188 4 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.04
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 10 0 0 0 4 0 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 97.12
Current children cumulated vsize (KiB) 1066932

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

[startup+102.404 s]
/proc/loadavg: 1.07 1.04 0.93 2/72 21913
/proc/meminfo: memFree=10148/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=97.22
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 167634 0 124 0 9660 62 0 0 20 0 11 0 115395275 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 159835 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=81.41
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 8114 27 0 0 20 0 11 0 115395322 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893874763 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=11.97
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 96499 0 6 0 1171 26 0 0 20 0 11 0 115395386 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.83
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 178 5 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.92
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 188 4 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.04
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 10 0 0 0 4 0 0 0 20 0 11 0 115395424 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 159835 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 97.22
Current children cumulated vsize (KiB) 1066932

[startup+128.001 s]
/proc/loadavg: 1.12 1.06 0.94 2/72 21913
/proc/meminfo: memFree=9784/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=122.52
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171328 0 124 0 12189 63 0 0 20 0 11 0 115395275 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 163525 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=105.18
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 10491 27 0 0 20 0 11 0 115395322 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893636508 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=13.48
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100190 0 6 0 1322 26 0 0 20 0 11 0 115395386 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.83
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 178 5 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.92
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 188 4 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.06
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 13 0 0 0 6 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 122.52
Current children cumulated vsize (KiB) 1066932

[startup+140.801 s]
/proc/loadavg: 1.09 1.05 0.94 2/72 21913
/proc/meminfo: memFree=9784/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=135.15
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171328 0 124 0 13452 63 0 0 20 0 11 0 115395275 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 163525 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=116.68
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 11641 27 0 0 20 0 11 0 115395322 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893621480 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=14.6
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100190 0 6 0 1434 26 0 0 20 0 11 0 115395386 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.83
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 178 5 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.92
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 188 4 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.06
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 13 0 0 0 6 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 135.15
Current children cumulated vsize (KiB) 1066932

[startup+147.201 s]
/proc/loadavg: 1.08 1.05 0.94 2/72 21913
/proc/meminfo: memFree=9784/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=141.47
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171328 0 124 0 14084 63 0 0 20 0 11 0 115395275 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 163525 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=122.98
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 12271 27 0 0 20 0 11 0 115395322 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676995629176 139676893621752 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=14.6
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100190 0 6 0 1434 26 0 0 20 0 11 0 115395386 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.84
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 179 5 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.93
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 189 4 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.06
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 13 0 0 0 6 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 141.47
Current children cumulated vsize (KiB) 1066932

[startup+150.401 s]
/proc/loadavg: 1.08 1.05 0.94 2/72 21913
/proc/meminfo: memFree=9784/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=144.63
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171331 0 124 0 14400 63 0 0 20 0 11 0 115395275 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 163525 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=126.14
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 12587 27 0 0 20 0 11 0 115395322 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676995629288 139676893667932 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=14.6
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100190 0 6 0 1434 26 0 0 20 0 11 0 115395386 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.84
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 179 5 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.93
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 189 4 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.07
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 7 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 144.63
Current children cumulated vsize (KiB) 1066932

[startup+152.001 s]
/proc/loadavg: 1.08 1.05 0.94 2/72 21913
/proc/meminfo: memFree=9660/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=146.21
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171332 0 124 0 14558 63 0 0 20 0 11 0 115395275 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 163525 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=127
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 12673 27 0 0 20 0 11 0 115395322 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893668747 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=15.32
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100191 0 6 0 1506 26 0 0 20 0 11 0 115395386 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.84
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 179 5 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.93
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 189 4 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.07
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 7 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 146.21
Current children cumulated vsize (KiB) 1066932

[startup+153.601 s]
/proc/loadavg: 1.08 1.05 0.94 2/72 21913
/proc/meminfo: memFree=9660/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1057764 CPUtime=147.79
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171332 0 124 0 14716 63 0 0 20 0 11 0 115395275 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264441 163525 2393 9 0 251791 0
[pid=21903/tid=21904] ppid=21900 vsize=1057764 CPUtime=128.59
/proc/21903/task/21904/stat : 21904 (java) R 21900 21900 1733 0 -1 4202560 47745 0 62 0 12832 27 0 0 20 0 11 0 115395322 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676995629032 139676893868455 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1057764 CPUtime=15.32
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100191 0 6 0 1506 26 0 0 20 0 11 0 115395386 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1057764 CPUtime=1.84
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10360 0 14 0 179 5 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1057764 CPUtime=1.93
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11762 0 2 0 189 4 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1057764 CPUtime=0.07
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 7 0 0 0 20 0 11 0 115395424 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21913] ppid=21900 vsize=1057764 CPUtime=0
/proc/21903/task/21913/stat : 21913 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115396633 1083150336 163525 18446744073709551615 4194304 4228012 140734343599568 139676879693664 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 147.79
Current children cumulated vsize (KiB) 1066932

[startup+154.001 s]
/proc/loadavg: 1.08 1.05 0.94 1/72 21914
/proc/meminfo: memFree=9404/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1058792 CPUtime=148.02
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171352 0 124 0 14739 63 0 0 20 0 11 0 115395275 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264698 163543 2393 9 0 252048 0
[pid=21903/tid=21904] ppid=21900 vsize=1058792 CPUtime=128.75
/proc/21903/task/21904/stat : 21904 (java) S 21900 21900 1733 0 -1 4202560 47754 0 62 0 12848 27 0 0 20 0 11 0 115395322 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676995630000 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1058792 CPUtime=15.32
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100191 0 6 0 1506 26 0 0 20 0 11 0 115395386 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1058792 CPUtime=1.89
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10362 0 14 0 184 5 0 0 20 0 11 0 115395424 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1058792 CPUtime=1.96
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11765 0 2 0 192 4 0 0 20 0 11 0 115395424 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1058792 CPUtime=0.07
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 7 0 0 0 20 0 11 0 115395424 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21914] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21914/stat : 21914 (java) S 21900 21900 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115410643 1084203008 163543 18446744073709551615 4194304 4228012 140734343599568 139676878641328 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 148.02
Current children cumulated vsize (KiB) 1067960

[startup+154.401 s]
/proc/loadavg: 1.08 1.05 0.94 1/72 21914
/proc/meminfo: memFree=9404/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=1058792 CPUtime=148.1
/proc/21903/stat : 21903 (java) S 21900 21900 1733 0 -1 4202496 171354 0 124 0 14744 66 0 0 20 0 11 0 115395275 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 140734343590592 139676989095541 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 264698 163545 2393 9 0 252048 0
[pid=21903/tid=21904] ppid=21900 vsize=1058792 CPUtime=128.75
/proc/21903/task/21904/stat : 21904 (java) S 21900 21900 1733 0 -1 4202560 47754 0 62 0 12848 27 0 0 20 0 11 0 115395322 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676995630000 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 321 0 0
[pid=21903/tid=21905] ppid=21900 vsize=1058792 CPUtime=15.32
/proc/21903/task/21905/stat : 21905 (java) S 21900 21900 1733 0 -1 4202560 100191 0 6 0 1506 26 0 0 20 0 11 0 115395386 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676888267904 139676989109835 0 0 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21906] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21906/stat : 21906 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115395398 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676887214096 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21907] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21907/stat : 21907 (java) S 21900 21900 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115395398 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676886161312 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 9 0 0
[pid=21903/tid=21908] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21908/stat : 21908 (java) S 21900 21900 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115395424 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676885109744 139676989117472 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21909] ppid=21900 vsize=1058792 CPUtime=1.89
/proc/21903/task/21909/stat : 21909 (java) S 21900 21900 1733 0 -1 4202560 10362 0 14 0 184 5 0 0 20 0 11 0 115395424 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676884057104 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 20 0 0
[pid=21903/tid=21910] ppid=21900 vsize=1058792 CPUtime=1.97
/proc/21903/task/21910/stat : 21910 (java) S 21900 21900 1733 0 -1 4202560 11765 0 2 0 193 4 0 0 20 0 11 0 115395424 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676883004432 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=21903/tid=21911] ppid=21900 vsize=1058792 CPUtime=0
/proc/21903/task/21911/stat : 21911 (java) S 21900 21900 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115395424 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676881951952 139676989108948 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21912] ppid=21900 vsize=1058792 CPUtime=0.07
/proc/21903/task/21912/stat : 21912 (java) S 21900 21900 1733 0 -1 4202560 16 0 0 0 7 0 0 0 20 0 11 0 115395424 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676880899456 139676989109835 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=21903/tid=21914] ppid=21900 vsize=1058792 CPUtime=0.06
/proc/21903/task/21914/stat : 21914 (java) R 21900 21900 1733 0 -1 4202560 8 0 0 0 3 3 0 0 20 0 11 0 115410643 1084203008 163545 18446744073709551615 4194304 4228012 140734343599568 139676878632128 139676989120813 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 148.1
Current children cumulated vsize (KiB) 1067960

[startup+154.502 s]
/proc/loadavg: 1.08 1.05 0.94 1/72 21914
/proc/meminfo: memFree=9404/1022884 swapFree=0/0
[pid=21900] ppid=21899 vsize=9168 CPUtime=0
/proc/21900/stat : 21900 (p2cudf-basic-1.) S 21899 21900 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 115395261 9388032 331 18446744073709551615 4194304 5129932 140734242982608 140734242980752 140659515028574 0 65536 16781316 65538 0 0 0 17 0 0 0 12 0 0
/proc/21900/statm: 2292 331 280 229 0 52 0
[pid=21903] ppid=21900 vsize=0 CPUtime=148.2
/proc/21903/stat : 21903 (java) Z 21900 21900 1733 0 -1 4203532 171365 0 124 0 14748 72 0 0 20 0 2 0 115395275 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 45 0 0
/proc/21903/statm: 0 0 0 0 0 0 0
[pid=21903/tid=21914] ppid=21900 vsize=0 CPUtime=0.16
/proc/21903/task/21914/stat : 21914 (java) R 21900 21900 1733 0 -1 4203588 8 0 0 0 6 10 0 0 20 0 2 0 115410643 0 0 18446744073709551615 0 0 0 0 0 0 4 0 16800975 18446744073709551615 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 148.2
Current children cumulated vsize (KiB) 9168

Child status: 0
Real time (s): 154.54
CPU time (s): 148.241
CPU user time (s): 147.489
CPU system time (s): 0.752047
CPU usage (%): 95.924
Max. virtual memory (cumulated for all children) (KiB): 1071980

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 147.489
system time used= 0.752047
maximum resident set size= 654216
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 172128
page faults= 124
swaps= 0
block input operations= 103280
block output operations= 464
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 5063
involuntary context switches= 22101

runsolver used 0.436027 second user time and 1.05607 second system time

The end