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 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/8afdd89e-51b8-11e0-acd7-00163e1e087d.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/real/8afdd89e-51b8-11e0-acd7-00163e1e087d.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/8afdd89e-51b8-11e0-acd7-00163e1e087d.cudf.result -count(down),-notuptodate(solution),-aligned(solution,source,sourceversion),-unsat_recommends(solution),-count(new) 

Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 300 seconds
Enforcing CPUTime limit (hard limit, will send SIGXCPU): 330 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.01 1.06 1.01 2/59 28240
/proc/meminfo: memFree=574272/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=3152 CPUtime=0
/proc/28240/stat : 28240 (runsolver) R 28239 1733 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 117469905 3227648 32 18446744073709551615 134512640 134586868 4294119616 4294117664 4152103984 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 788 32 0 19 0 73 0
Current StackSize limit: 8192 KiB


[startup+0.205337 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 28240
/proc/meminfo: memFree=574272/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.30033 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 28240
/proc/meminfo: memFree=574272/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.400309 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 28240
/proc/meminfo: memFree=574272/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.700226 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 28240
/proc/meminfo: memFree=574272/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+1.50084 s]
/proc/loadavg: 1.01 1.06 1.01 4/70 28252
/proc/meminfo: memFree=513168/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1048192 CPUtime=1.46
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 29889 0 0 0 128 18 0 0 20 0 10 0 117469906 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 262048 26909 2348 9 0 249398 0
[pid=28243/tid=28244] ppid=28240 vsize=1048192 CPUtime=0.67
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 12885 0 0 0 58 9 0 0 20 0 10 0 117469907 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140054064489120 140053962011653 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1048192 CPUtime=0
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 117469910 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1048192 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 117469911 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1048192 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 10 0 117469911 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1048192 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1048192 CPUtime=0.4
/proc/28243/task/28249/stat : 28249 (java) R 28240 28240 1733 0 -1 4202560 9007 0 0 0 36 4 0 0 20 0 10 0 117469915 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053952910120 140054044113141 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1048192 CPUtime=0.36
/proc/28243/task/28250/stat : 28250 (java) R 28240 28240 1733 0 -1 4202560 6706 0 0 0 32 4 0 0 20 0 10 0 117469915 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053951859816 140054042237189 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1048192 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1048192 CPUtime=0
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1073348608 26909 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.46
Current children cumulated vsize (KiB) 1057364

[startup+3.10078 s]
/proc/loadavg: 1.01 1.06 1.01 2/70 28252
/proc/meminfo: memFree=424260/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1048276 CPUtime=3.02
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 74583 0 0 0 269 33 0 0 20 0 10 0 117469906 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 262069 70312 2368 9 0 249419 0
[pid=28243/tid=28244] ppid=28240 vsize=1048276 CPUtime=1.54
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47420 0 0 0 134 20 0 0 20 0 10 0 117469907 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140054064489152 140053962289847 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1048276 CPUtime=0.18
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 8802 0 0 0 14 4 0 0 20 0 10 0 117469910 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1048276 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 117469911 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1048276 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 117469911 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1048276 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1048276 CPUtime=0.66
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 9730 0 0 0 61 5 0 0 20 0 10 0 117469915 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1048276 CPUtime=0.61
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7340 0 0 0 57 4 0 0 20 0 10 0 117469915 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1048276 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1048276 CPUtime=0
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1073434624 70312 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 3.02
Current children cumulated vsize (KiB) 1057448

[startup+6.30078 s]
/proc/loadavg: 1.01 1.06 1.01 4/70 28252
/proc/meminfo: memFree=155056/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1045488 CPUtime=6.17
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 107703 0 0 0 570 47 0 0 20 0 10 0 117469906 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261372 102789 2374 9 0 248722 0
[pid=28243/tid=28244] ppid=28240 vsize=1045488 CPUtime=3.09
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47717 0 0 0 288 21 0 0 20 0 10 0 117469907 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140054064496888 140054044168807 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1045488 CPUtime=0.94
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 41472 0 0 0 78 16 0 0 20 0 10 0 117469910 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1045488 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 117469911 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1045488 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 117469911 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1045488 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1045488 CPUtime=1.05
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 9846 0 0 0 100 5 0 0 20 0 10 0 117469915 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1045488 CPUtime=1.05
/proc/28243/task/28250/stat : 28250 (java) R 28240 28240 1733 0 -1 4202560 7377 0 0 0 101 4 0 0 20 0 10 0 117469915 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053951871760 140054044693966 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1045488 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1045488 CPUtime=0
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117469915 1070579712 102789 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.17
Current children cumulated vsize (KiB) 1054660

[startup+12.7008 s]
/proc/loadavg: 1.17 1.09 1.02 2/71 28253
/proc/meminfo: memFree=152444/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=12.49
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 118967 0 0 0 1198 51 0 0 20 0 11 0 117469906 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 113319 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=5.94
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 572 22 0 0 20 0 11 0 117469907 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140054064496808 140053963357636 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=1.72
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 51658 0 0 0 153 19 0 0 20 0 11 0 117469910 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.44
/proc/28243/task/28249/stat : 28249 (java) R 28240 28240 1733 0 -1 4202560 10651 0 0 0 238 6 0 0 20 0 11 0 117469915 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053952912392 140054041105666 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.36
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7542 0 0 0 232 4 0 0 20 0 11 0 117469915 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 113319 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.49
Current children cumulated vsize (KiB) 1055688

[startup+25.5036 s]
/proc/loadavg: 1.20 1.10 1.03 2/71 28253
/proc/meminfo: memFree=89948/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=25.13
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 124928 0 0 0 2460 53 0 0 20 0 11 0 117469906 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 118762 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=15.55
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 1533 22 0 0 20 0 11 0 117469907 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053963244890 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=4.46
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 57077 0 0 0 426 20 0 0 20 0 11 0 117469910 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.66
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 260 6 0 0 20 0 11 0 117469915 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.42
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 238 4 0 0 20 0 11 0 117469915 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.01
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 117469915 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 118762 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.13
Current children cumulated vsize (KiB) 1055688

[startup+51.1006 s]
/proc/loadavg: 1.13 1.09 1.03 2/71 28253
/proc/meminfo: memFree=59320/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=50.4
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 132544 0 0 0 4985 55 0 0 20 0 11 0 117469906 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 126374 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=37.38
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 3716 22 0 0 20 0 11 0 117469907 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140054064496760 140053962744922 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=7.84
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 64690 0 0 0 762 22 0 0 20 0 11 0 117469910 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.68
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 262 6 0 0 20 0 11 0 117469915 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.44
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 240 4 0 0 20 0 11 0 117469915 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.02
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 117469915 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 126374 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.4
Current children cumulated vsize (KiB) 1055688

[startup+102.301 s]
/proc/loadavg: 1.10 1.09 1.03 2/71 28253
/proc/meminfo: memFree=27328/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=101.06
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 140473 0 0 0 10049 57 0 0 20 0 11 0 117469906 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 134299 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=83.22
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 8300 22 0 0 20 0 11 0 117469907 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140054064496792 140053962744809 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=12.6
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 72616 0 0 0 1236 24 0 0 20 0 11 0 117469910 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.04
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 4 0 0 0 20 0 11 0 117469915 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 134299 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 101.06
Current children cumulated vsize (KiB) 1055688

[startup+162.301 s]
/proc/loadavg: 1.04 1.07 1.02 2/71 28253
/proc/meminfo: memFree=10092/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=160.4
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 145071 0 0 0 15982 58 0 0 20 0 11 0 117469906 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 138889 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=138.62
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 13840 22 0 0 20 0 11 0 117469907 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053962744825 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=16.53
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 77208 0 0 0 1628 25 0 0 20 0 11 0 117469910 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.07
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 12 0 0 0 7 0 0 0 20 0 11 0 117469915 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 138889 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.4
Current children cumulated vsize (KiB) 1055688

[startup+222.301 s]
/proc/loadavg: 1.11 1.08 1.02 2/71 28253
/proc/meminfo: memFree=9320/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=219.76
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 149616 0 0 0 21917 59 0 0 20 0 11 0 117469906 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 143426 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=194.83
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 19461 22 0 0 20 0 11 0 117469907 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053962744804 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=19.65
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 81747 0 0 0 1939 26 0 0 20 0 11 0 117469910 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.1
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 18 0 0 0 10 0 0 0 20 0 11 0 117469915 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 143426 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.76
Current children cumulated vsize (KiB) 1055688

[startup+282.301 s]
/proc/loadavg: 1.04 1.07 1.02 2/71 28253
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=279.08
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158205 0 0 0 27847 61 0 0 20 0 11 0 117469906 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 152004 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=251.37
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 25115 22 0 0 20 0 11 0 117469907 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053963347981 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=22.39
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90327 0 0 0 2211 28 0 0 20 0 11 0 117469910 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 279.08
Current children cumulated vsize (KiB) 1055688

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

[startup+288.101 s]
/proc/loadavg: 1.03 1.06 1.02 2/71 28253
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=284.81
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158206 0 0 0 28420 61 0 0 20 0 11 0 117469906 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 152004 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=256.67
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 25645 22 0 0 20 0 11 0 117469907 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053963244867 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=22.83
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90328 0 0 0 2255 28 0 0 20 0 11 0 117469910 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.81
Current children cumulated vsize (KiB) 1055688

[startup+291.301 s]
/proc/loadavg: 1.03 1.06 1.02 2/71 28253
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=287.98
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158206 0 0 0 28737 61 0 0 20 0 11 0 117469906 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 152004 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=259.83
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 25961 22 0 0 20 0 11 0 117469907 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053963348058 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=22.83
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90328 0 0 0 2255 28 0 0 20 0 11 0 117469910 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.98
Current children cumulated vsize (KiB) 1055688

[startup+292.901 s]
/proc/loadavg: 1.03 1.06 1.02 2/71 28253
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=289.56
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158206 0 0 0 28895 61 0 0 20 0 11 0 117469906 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 152004 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=261.41
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 26119 22 0 0 20 0 11 0 117469907 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053962693653 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=22.83
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90328 0 0 0 2255 28 0 0 20 0 11 0 117469910 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.56
Current children cumulated vsize (KiB) 1055688

[startup+293.701 s]
/proc/loadavg: 1.03 1.06 1.02 2/71 28253
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1046516 CPUtime=290.35
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158206 0 0 0 28974 61 0 0 20 0 11 0 117469906 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261629 152004 2393 9 0 248979 0
[pid=28243/tid=28244] ppid=28240 vsize=1046516 CPUtime=262.2
/proc/28243/task/28244/stat : 28244 (java) R 28240 28240 1733 0 -1 4202560 47819 0 0 0 26198 22 0 0 20 0 11 0 117469907 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140054064496600 140053963092289 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1046516 CPUtime=22.83
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90328 0 0 0 2255 28 0 0 20 0 11 0 117469910 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1046516 CPUtime=2.7
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 264 6 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1046516 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1046516 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28253] ppid=28240 vsize=1046516 CPUtime=0
/proc/28243/task/28253/stat : 28253 (java) S 28240 28240 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117470819 1071632384 152004 18446744073709551615 4194304 4228012 140736892788304 140053948561248 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.35
Current children cumulated vsize (KiB) 1055688

[startup+294.501 s]
/proc/loadavg: 1.03 1.06 1.02 2/71 28253
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1047544 CPUtime=290.82
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158219 0 0 0 29021 61 0 0 20 0 11 0 117469906 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261886 152015 2393 9 0 249236 0
[pid=28243/tid=28244] ppid=28240 vsize=1047544 CPUtime=262.66
/proc/28243/task/28244/stat : 28244 (java) S 28240 28240 1733 0 -1 4202560 47826 0 0 0 26244 22 0 0 20 0 11 0 117469907 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140054064497584 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1047544 CPUtime=22.83
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90328 0 0 0 2255 28 0 0 20 0 11 0 117469910 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1047544 CPUtime=2.71
/proc/28243/task/28249/stat : 28249 (java) S 28240 28240 1733 0 -1 4202560 11192 0 0 0 265 6 0 0 20 0 11 0 117469915 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053952924688 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1047544 CPUtime=2.45
/proc/28243/task/28250/stat : 28250 (java) S 28240 28240 1733 0 -1 4202560 7543 0 0 0 241 4 0 0 20 0 11 0 117469915 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053951872016 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1047544 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1072685056 152015 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.82
Current children cumulated vsize (KiB) 1056716

[startup+294.901 s]
/proc/loadavg: 1.03 1.06 1.02 4/71 28254
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=1047544 CPUtime=291.06
/proc/28243/stat : 28243 (java) S 28240 28240 1733 0 -1 4202496 158223 0 0 0 29037 69 0 0 20 0 11 0 117469906 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140736892779328 140054057963125 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 261886 152019 2394 9 0 249236 0
[pid=28243/tid=28244] ppid=28240 vsize=1047544 CPUtime=262.66
/proc/28243/task/28244/stat : 28244 (java) S 28240 28240 1733 0 -1 4202560 47826 0 0 0 26244 22 0 0 20 0 11 0 117469907 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140054064497584 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 10 0 0
[pid=28243/tid=28245] ppid=28240 vsize=1047544 CPUtime=22.83
/proc/28243/task/28245/stat : 28245 (java) S 28240 28240 1733 0 -1 4202560 90328 0 0 0 2255 28 0 0 20 0 11 0 117469910 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053957135488 140054057977419 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28246] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28246/stat : 28246 (java) S 28240 28240 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117469911 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053956081680 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28247] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28247/stat : 28247 (java) S 28240 28240 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 117469911 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053955028896 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28248] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28248/stat : 28248 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053953977328 140054057985056 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28249] ppid=28240 vsize=1047544 CPUtime=2.74
/proc/28243/task/28249/stat : 28249 (java) R 28240 28240 1733 0 -1 4202560 11193 0 0 0 268 6 0 0 20 0 11 0 117469915 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053952924432 140054042239339 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28250] ppid=28240 vsize=1047544 CPUtime=2.48
/proc/28243/task/28250/stat : 28250 (java) R 28240 28240 1733 0 -1 4202560 7543 0 0 0 244 4 0 0 20 0 11 0 117469915 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053951871760 140054044664310 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28251] ppid=28240 vsize=1047544 CPUtime=0
/proc/28243/task/28251/stat : 28251 (java) S 28240 28240 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117469915 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053950819536 140054057976532 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28252] ppid=28240 vsize=1047544 CPUtime=0.13
/proc/28243/task/28252/stat : 28252 (java) S 28240 28240 1733 0 -1 4202560 27 0 0 0 13 0 0 0 20 0 11 0 117469915 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053949767040 140054057977419 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28243/tid=28254] ppid=28240 vsize=1047544 CPUtime=0.16
/proc/28243/task/28254/stat : 28254 (java) R 28240 28240 1733 0 -1 4202560 9 0 0 0 9 7 0 0 20 0 11 0 117499323 1072685056 152019 18446744073709551615 4194304 4228012 140736892788304 140053947500144 140054057988397 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.06
Current children cumulated vsize (KiB) 1056716

[startup+295.018 s]
/proc/loadavg: 1.03 1.06 1.02 4/71 28254
/proc/meminfo: memFree=10224/1022884 swapFree=0/0
[pid=28240] ppid=28239 vsize=9172 CPUtime=0
/proc/28240/stat : 28240 (p2cudf-full-1.1) S 28239 28240 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117469905 9392128 331 18446744073709551615 4194304 5129932 140734812886384 140734812884528 140124005090398 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28240/statm: 2293 331 280 229 0 53 0
[pid=28243] ppid=28240 vsize=0 CPUtime=291.16
/proc/28243/stat : 28243 (java) Z 28240 28240 1733 0 -1 4203532 158236 0 0 0 29045 71 0 0 20 0 2 0 117469906 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28243/statm: 0 0 0 0 0 0 0
[pid=28243/tid=28254] ppid=28240 vsize=0 CPUtime=0.22
/proc/28243/task/28254/stat : 28254 (java) R 28240 28240 1733 0 -1 4203588 9 0 0 0 12 10 0 0 20 0 2 0 117499323 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) 291.16
Current children cumulated vsize (KiB) 9172

Child status: 0
Real time (s): 295.084
CPU time (s): 291.238
CPU user time (s): 290.458
CPU system time (s): 0.780048
CPU usage (%): 98.6969
Max. virtual memory (cumulated for all children) (KiB): 1057920

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 290.458
system time used= 0.780048
maximum resident set size= 608120
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 158998
page faults= 0
swaps= 0
block input operations= 34072
block output operations= 736
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7347
involuntary context switches= 41948

runsolver used 0.796049 second user time and 1.77211 second system time

The end