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/80cfe9a6-9b1b-11df-965e-00163e46d37a.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/real/80cfe9a6-9b1b-11df-965e-00163e46d37a.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/upgrade/real/80cfe9a6-9b1b-11df-965e-00163e46d37a.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
Current StackSize limit: 8192 KiB


[startup+0 s]
/proc/loadavg: 1.00 1.05 1.01 2/59 28286
/proc/meminfo: memFree=610436/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9168 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 425 0 0 0 0 0 0 0 20 0 1 0 117530000 9388032 325 18446744073709551615 4194304 5129932 140734448727008 140734448724680 139685284628256 0 0 16781316 65536 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2292 325 275 229 0 52 0

[startup+0.175956 s]
/proc/loadavg: 1.00 1.05 1.01 2/59 28286
/proc/meminfo: memFree=610436/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.200313 s]
/proc/loadavg: 1.00 1.05 1.01 2/59 28286
/proc/meminfo: memFree=610436/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.300368 s]
/proc/loadavg: 1.00 1.05 1.01 2/59 28286
/proc/meminfo: memFree=610436/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+0.703624 s]
/proc/loadavg: 1.00 1.05 1.01 2/59 28286
/proc/meminfo: memFree=610436/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9172

[startup+1.50079 s]
/proc/loadavg: 1.00 1.05 1.01 4/70 28298
/proc/meminfo: memFree=565204/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047640 CPUtime=1.45
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 30538 0 0 0 131 14 0 0 20 0 10 0 117530001 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261910 27227 2347 9 0 249260 0
[pid=28289/tid=28290] ppid=28286 vsize=1047640 CPUtime=0.68
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 13103 0 0 0 61 7 0 0 20 0 10 0 117530002 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893859918496 139893757762453 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047640 CPUtime=0
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 117530005 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047640 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 117530006 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047640 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 117530006 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047640 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047640 CPUtime=0.39
/proc/28289/task/28295/stat : 28295 (java) R 28286 28286 1733 0 -1 4202560 7401 0 0 0 36 3 0 0 20 0 10 0 117530010 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893748341912 139893836533392 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047640 CPUtime=0.34
/proc/28289/task/28296/stat : 28296 (java) R 28286 28286 1733 0 -1 4202560 8743 0 0 0 31 3 0 0 20 0 10 0 117530010 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893747287096 139893836637633 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047640 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047640 CPUtime=0
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1072783360 27227 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.45
Current children cumulated vsize (KiB) 1056812

[startup+3.1008 s]
/proc/loadavg: 1.00 1.05 1.01 4/70 28298
/proc/meminfo: memFree=471832/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1045852 CPUtime=3.02
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 75857 0 0 0 274 28 0 0 20 0 10 0 117530001 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261463 70399 2367 9 0 248813 0
[pid=28289/tid=28290] ppid=28286 vsize=1045852 CPUtime=1.54
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47416 0 0 0 138 16 0 0 20 0 10 0 117530002 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893859918528 139893757793270 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1045852 CPUtime=0.18
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 8752 0 0 0 16 2 0 0 20 0 10 0 117530005 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 117530006 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 117530006 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1045852 CPUtime=0.65
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 8931 0 0 0 60 5 0 0 20 0 10 0 117530010 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1045852 CPUtime=0.61
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9468 0 0 0 57 4 0 0 20 0 10 0 117530010 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1070952448 70399 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 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) 1055024

[startup+6.30081 s]
/proc/loadavg: 1.00 1.05 1.01 4/70 28298
/proc/meminfo: memFree=197544/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1045852 CPUtime=6.17
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 107764 0 0 0 577 40 0 0 20 0 10 0 117530001 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261463 100666 2371 9 0 248813 0
[pid=28289/tid=28290] ppid=28286 vsize=1045852 CPUtime=3.13
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47493 0 0 0 295 18 0 0 20 0 10 0 117530002 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893859911840 139893855606614 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1045852 CPUtime=0.89
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 40341 0 0 0 77 12 0 0 20 0 10 0 117530005 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 117530006 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 117530006 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1045852 CPUtime=1.08
/proc/28289/task/28295/stat : 28295 (java) R 28286 28286 1733 0 -1 4202560 9089 0 0 0 103 5 0 0 20 0 10 0 117530010 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893748353808 139893837223254 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1045852 CPUtime=1.03
/proc/28289/task/28296/stat : 28296 (java) R 28286 28286 1733 0 -1 4202560 9551 0 0 0 99 4 0 0 20 0 10 0 117530010 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893747301136 139893845921592 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1045852 CPUtime=0
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 117530010 1070952448 100666 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 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) 1055024

[startup+12.7006 s]
/proc/loadavg: 1.00 1.04 1.01 2/71 28299
/proc/meminfo: memFree=161824/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=12.48
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 124399 0 0 0 1204 44 0 0 20 0 11 0 117530001 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 117301 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=6.57
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47808 0 0 0 638 19 0 0 20 0 11 0 117530002 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893859926200 139893758128362 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=1.55
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 56423 0 0 0 140 15 0 0 20 0 11 0 117530005 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.23
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 218 5 0 0 20 0 11 0 117530010 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.1
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9709 0 0 0 206 4 0 0 20 0 11 0 117530010 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 117301 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.48
Current children cumulated vsize (KiB) 1056204

[startup+25.5007 s]
/proc/loadavg: 1.00 1.04 1.01 2/71 28299
/proc/meminfo: memFree=113092/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=25.16
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 130169 0 0 0 2470 46 0 0 20 0 11 0 117530001 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 123071 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=16.87
/proc/28289/task/28290/stat : 28290 (java) S 28286 28286 1733 0 -1 4202560 47809 0 0 0 1668 19 0 0 20 0 11 0 117530002 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893859925792 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=3.8
/proc/28289/task/28291/stat : 28291 (java) R 28286 28286 1733 0 -1 4202560 62187 0 0 0 364 16 0 0 20 0 11 0 117530005 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893752563824 139893839212082 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.17
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 213 4 0 0 20 0 11 0 117530010 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.01
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 1 0 0 0 20 0 11 0 117530010 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 123071 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 25.16
Current children cumulated vsize (KiB) 1056204

[startup+51.1007 s]
/proc/loadavg: 1.00 1.04 1.01 2/71 28299
/proc/meminfo: memFree=67708/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=50.48
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 139957 0 0 0 5001 47 0 0 20 0 11 0 117530001 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 132855 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=39.98
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 3979 19 0 0 20 0 11 0 117530002 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893758803649 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=6.02
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 71972 0 0 0 584 18 0 0 20 0 11 0 117530005 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.17
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 213 4 0 0 20 0 11 0 117530010 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.02
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 3 0 0 0 2 0 0 0 20 0 11 0 117530010 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 132855 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.48
Current children cumulated vsize (KiB) 1056204

[startup+102.301 s]
/proc/loadavg: 1.00 1.03 1.00 2/71 28299
/proc/meminfo: memFree=50596/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=101.14
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 144166 0 0 0 10066 48 0 0 20 0 11 0 117530001 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 137057 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=87.03
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 8684 19 0 0 20 0 11 0 117530002 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893758664299 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=9.58
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 76175 0 0 0 940 18 0 0 20 0 11 0 117530005 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.04
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 9 0 0 0 4 0 0 0 20 0 11 0 117530010 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 137057 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 101.14
Current children cumulated vsize (KiB) 1056204

[startup+162.301 s]
/proc/loadavg: 1.08 1.04 1.01 2/71 28299
/proc/meminfo: memFree=26788/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=160.5
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 150084 0 0 0 16002 48 0 0 20 0 11 0 117530001 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 142970 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=143.23
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 14304 19 0 0 20 0 11 0 117530002 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893758746802 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=12.73
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 82090 0 0 0 1254 19 0 0 20 0 11 0 117530005 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.07
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 12 0 0 0 7 0 0 0 20 0 11 0 117530010 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 142970 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.5
Current children cumulated vsize (KiB) 1056204

[startup+222.305 s]
/proc/loadavg: 1.03 1.03 1.00 2/71 28299
/proc/meminfo: memFree=9976/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=219.68
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 158478 0 0 0 21916 52 0 0 20 0 11 0 117530001 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 151356 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=199.73
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 19954 19 0 0 20 0 11 0 117530002 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893757913644 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=15.37
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 90478 0 0 0 1515 22 0 0 20 0 11 0 117530005 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.1
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 18 0 0 0 10 0 0 0 20 0 11 0 117530010 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 151356 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.68
Current children cumulated vsize (KiB) 1056204

[startup+282.301 s]
/proc/loadavg: 1.01 1.02 1.00 2/71 28299
/proc/meminfo: memFree=10360/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=278.83
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 159309 0 0 0 27831 52 0 0 20 0 11 0 117530001 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 152179 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=256.88
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 25669 19 0 0 20 0 11 0 117530002 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893757913900 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=17.34
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 91303 0 0 0 1712 22 0 0 20 0 11 0 117530005 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.13
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 24 0 0 0 13 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.83
Current children cumulated vsize (KiB) 1056204

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

[startup+288.001 s]
/proc/loadavg: 1.01 1.02 1.00 2/71 28299
/proc/meminfo: memFree=10236/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=284.46
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 159309 0 0 0 28394 52 0 0 20 0 11 0 117530001 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 152179 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=262.06
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 26187 19 0 0 20 0 11 0 117530002 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893758141790 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=17.78
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 91303 0 0 0 1756 22 0 0 20 0 11 0 117530005 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.14
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 284.46
Current children cumulated vsize (KiB) 1056204

[startup+291.201 s]
/proc/loadavg: 1.01 1.02 1.00 2/71 28299
/proc/meminfo: memFree=10236/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=287.63
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 159309 0 0 0 28711 52 0 0 20 0 11 0 117530001 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 152179 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=265.23
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 26504 19 0 0 20 0 11 0 117530002 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893758141908 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=17.78
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 91303 0 0 0 1756 22 0 0 20 0 11 0 117530005 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.14
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.63
Current children cumulated vsize (KiB) 1056204

[startup+292.801 s]
/proc/loadavg: 1.01 1.02 1.00 2/71 28299
/proc/meminfo: memFree=10236/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=289.21
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 159309 0 0 0 28869 52 0 0 20 0 11 0 117530001 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 152179 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=266.81
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 26662 19 0 0 20 0 11 0 117530002 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893757913644 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=17.78
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 91303 0 0 0 1756 22 0 0 20 0 11 0 117530005 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.14
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 289.21
Current children cumulated vsize (KiB) 1056204

[startup+293.601 s]
/proc/loadavg: 1.01 1.02 1.00 2/71 28299
/proc/meminfo: memFree=10236/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1047032 CPUtime=290.01
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 159309 0 0 0 28949 52 0 0 20 0 11 0 117530001 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 261758 152179 2393 9 0 249108 0
[pid=28289/tid=28290] ppid=28286 vsize=1047032 CPUtime=267.6
/proc/28289/task/28290/stat : 28290 (java) R 28286 28286 1733 0 -1 4202560 47809 0 0 0 26741 19 0 0 20 0 11 0 117530002 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893859925992 139893758450511 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1047032 CPUtime=17.78
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 91303 0 0 0 1756 22 0 0 20 0 11 0 117530005 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1047032 CPUtime=2.25
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 220 5 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1047032 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1047032 CPUtime=0.14
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 117530010 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28299] ppid=28286 vsize=1047032 CPUtime=0
/proc/28289/task/28299/stat : 28299 (java) S 28286 28286 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 117530896 1072160768 152179 18446744073709551615 4194304 4228012 140737291288720 139893743990624 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.01
Current children cumulated vsize (KiB) 1056204

[startup+294.401 s]
/proc/loadavg: 1.01 1.02 1.00 2/71 28299
/proc/meminfo: memFree=10236/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=1048060 CPUtime=290.41
/proc/28289/stat : 28289 (java) S 28286 28286 1733 0 -1 4202496 159321 0 0 0 28989 52 0 0 20 0 11 0 117530001 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 140737291279744 139893853392501 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 262015 152189 2393 9 0 249365 0
[pid=28289/tid=28290] ppid=28286 vsize=1048060 CPUtime=267.99
/proc/28289/task/28290/stat : 28290 (java) S 28286 28286 1733 0 -1 4202560 47815 0 0 0 26780 19 0 0 20 0 11 0 117530002 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893859926960 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0
[pid=28289/tid=28291] ppid=28286 vsize=1048060 CPUtime=17.78
/proc/28289/task/28291/stat : 28291 (java) S 28286 28286 1733 0 -1 4202560 91303 0 0 0 1756 22 0 0 20 0 11 0 117530005 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893752564864 139893853406795 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28292] ppid=28286 vsize=1048060 CPUtime=0
/proc/28289/task/28292/stat : 28292 (java) S 28286 28286 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 117530006 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893751511056 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28293] ppid=28286 vsize=1048060 CPUtime=0
/proc/28289/task/28293/stat : 28293 (java) S 28286 28286 1733 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 117530006 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893750458272 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28294] ppid=28286 vsize=1048060 CPUtime=0
/proc/28289/task/28294/stat : 28294 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893749406704 139893853414432 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28295] ppid=28286 vsize=1048060 CPUtime=2.27
/proc/28289/task/28295/stat : 28295 (java) S 28286 28286 1733 0 -1 4202560 9163 0 0 0 222 5 0 0 20 0 11 0 117530010 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893748354064 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28296] ppid=28286 vsize=1048060 CPUtime=2.19
/proc/28289/task/28296/stat : 28296 (java) S 28286 28286 1733 0 -1 4202560 9714 0 0 0 215 4 0 0 20 0 11 0 117530010 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893747301392 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28297] ppid=28286 vsize=1048060 CPUtime=0
/proc/28289/task/28297/stat : 28297 (java) S 28286 28286 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 117530010 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893746248912 139893853405908 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=28289/tid=28298] ppid=28286 vsize=1048060 CPUtime=0.14
/proc/28289/task/28298/stat : 28298 (java) S 28286 28286 1733 0 -1 4202560 24 0 0 0 14 0 0 0 20 0 11 0 117530010 1073213440 152189 18446744073709551615 4194304 4228012 140737291288720 139893745196416 139893853406795 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.41
Current children cumulated vsize (KiB) 1057232

[startup+294.801 s]
/proc/loadavg: 1.17 1.05 1.01 2/62 28300
/proc/meminfo: memFree=177956/1022884 swapFree=0/0
[pid=28286] ppid=28285 vsize=9172 CPUtime=0
/proc/28286/stat : 28286 (p2cudf-full-1.1) S 28285 28286 1733 0 -1 4202496 458 292 0 0 0 0 0 0 20 0 1 0 117530000 9392128 331 18446744073709551615 4194304 5129932 140734448727008 140734448725152 139685284484190 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/28286/statm: 2293 331 280 229 0 53 0
[pid=28289] ppid=28286 vsize=0 CPUtime=290.7
/proc/28289/stat : 28289 (java) Z 28286 28286 1733 0 -1 4203532 159341 0 0 0 29010 60 0 0 20 0 2 0 117530001 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0
/proc/28289/statm: 0 0 0 0 0 0 0
[pid=28289/tid=28300] ppid=28286 vsize=0 CPUtime=0.19
/proc/28289/task/28300/stat : 28300 (java) R 28286 28286 1733 0 -1 4203588 9 0 0 0 11 8 0 0 20 0 2 0 117559401 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) 290.7
Current children cumulated vsize (KiB) 9172

Child status: 0
Real time (s): 294.847
CPU time (s): 290.75
CPU user time (s): 290.114
CPU system time (s): 0.636039
CPU usage (%): 98.6106
Max. virtual memory (cumulated for all children) (KiB): 1059988

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 290.114
system time used= 0.636039
maximum resident set size= 608828
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 160104
page faults= 0
swaps= 0
block input operations= 37880
block output operations= 632
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7253
involuntary context switches= 41456

runsolver used 0.892055 second user time and 2.00413 second system time

The end