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/slowlink/install/rand937.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/install/rand937.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/install/rand937.cudf.result -sum(changed,installedsize),-count(removed),-notuptodate(solution),-count(changed) 

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.01 1.06 1.01 2/59 6126
/proc/meminfo: memFree=617372/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 425 0 0 0 0 0 0 0 20 0 1 0 120732164 9388032 326 18446744073709551615 4194304 5129932 140734245816000 140734245813672 140670166030112 0 0 16781316 65536 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 326 275 229 0 52 0

[startup+0.132026 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 6126
/proc/meminfo: memFree=617372/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.200301 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 6126
/proc/meminfo: memFree=617372/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.300287 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 6126
/proc/meminfo: memFree=617372/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+0.700228 s]
/proc/loadavg: 1.01 1.06 1.01 2/59 6126
/proc/meminfo: memFree=617372/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 9168

[startup+1.50071 s]
/proc/loadavg: 1.01 1.06 1.01 4/70 6138
/proc/meminfo: memFree=570032/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046508 CPUtime=1.35
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 33638 0 15 0 125 10 0 0 20 0 10 0 120732165 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261627 29916 2346 9 0 248977 0
[pid=6129/tid=6130] ppid=6126 vsize=1046508 CPUtime=0.63
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 14411 0 3 0 59 4 0 0 20 0 10 0 120732170 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707525333664 140707422837290 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046508 CPUtime=0
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 120732174 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046508 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120732175 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046508 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 7 0 0 0 0 0 0 0 20 0 10 0 120732175 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046508 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732179 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046508 CPUtime=0.34
/proc/6129/task/6135/stat : 6135 (java) R 6126 6126 1733 0 -1 4202560 9774 0 0 0 31 3 0 0 20 0 10 0 120732179 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707413754696 140707504474815 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046508 CPUtime=0.36
/proc/6129/task/6136/stat : 6136 (java) R 6126 6126 1733 0 -1 4202560 8173 0 0 0 34 2 0 0 20 0 10 0 120732179 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707412704408 140707500715747 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046508 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732180 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046508 CPUtime=0
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732180 1071624192 29916 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 1.35
Current children cumulated vsize (KiB) 1055676

[startup+3.10083 s]
/proc/loadavg: 1.01 1.06 1.01 4/70 6138
/proc/meminfo: memFree=461532/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1045964 CPUtime=2.93
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 76111 0 15 0 268 25 0 0 20 0 10 0 120732165 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261491 71297 2366 9 0 248841 0
[pid=6129/tid=6130] ppid=6126 vsize=1045964 CPUtime=1.5
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47386 0 3 0 134 16 0 0 20 0 10 0 120732170 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707525333696 140707423183971 0 4 0 16800975 0 0 0 -1 0 0 0 8 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1045964 CPUtime=0.18
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 7950 0 0 0 14 4 0 0 20 0 10 0 120732174 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120732175 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 10 0 120732175 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732179 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1045964 CPUtime=0.6
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10642 0 0 0 57 3 0 0 20 0 10 0 120732179 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1045964 CPUtime=0.63
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 8852 0 0 0 61 2 0 0 20 0 10 0 120732179 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732180 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732180 1071067136 71297 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 2.93
Current children cumulated vsize (KiB) 1055132

[startup+6.30084 s]
/proc/loadavg: 1.01 1.06 1.01 2/70 6138
/proc/meminfo: memFree=161948/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1045964 CPUtime=6.04
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 129464 0 15 0 561 43 0 0 20 0 10 0 120732165 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261491 123072 2368 9 0 248841 0
[pid=6129/tid=6130] ppid=6126 vsize=1045964 CPUtime=3.27
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47389 0 3 0 309 18 0 0 20 0 10 0 120732170 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707525333696 140707423318945 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1045964 CPUtime=1.45
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 61289 0 0 0 126 19 0 0 20 0 10 0 120732174 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120732175 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 10 0 120732175 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732179 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1045964 CPUtime=0.63
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10648 0 0 0 60 3 0 0 20 0 10 0 120732179 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1045964 CPUtime=0.66
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 8856 0 0 0 64 2 0 0 20 0 10 0 120732179 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120732180 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1045964 CPUtime=0
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120732180 1071067136 123072 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 6.04
Current children cumulated vsize (KiB) 1055132

[startup+12.7008 s]
/proc/loadavg: 1.17 1.09 1.02 2/71 6139
/proc/meminfo: memFree=9496/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=12.35
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 152973 0 15 0 1180 55 0 0 20 0 11 0 120732165 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 146581 2381 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=6.68
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47771 0 3 0 648 20 0 0 20 0 11 0 120732170 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707525339368 140707424037232 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=2.07
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 84022 0 0 0 180 27 0 0 20 0 11 0 120732174 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10775 0 0 0 178 4 0 0 20 0 11 0 120732179 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.74
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9117 0 0 0 172 2 0 0 20 0 11 0 120732179 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 146581 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 12.35
Current children cumulated vsize (KiB) 1056160

[startup+25.5008 s]
/proc/loadavg: 1.13 1.09 1.02 2/71 6139
/proc/meminfo: memFree=10104/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=24.93
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 160273 0 15 0 2437 56 0 0 20 0 11 0 120732165 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 153881 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=17.13
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 1693 20 0 0 20 0 11 0 120732170 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707525341320 140707424040592 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=4.11
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 91311 0 0 0 382 29 0 0 20 0 11 0 120732174 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.86
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 182 4 0 0 20 0 11 0 120732179 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.78
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 176 2 0 0 20 0 11 0 120732179 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.01
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 1 0 0 0 1 0 0 0 20 0 11 0 120732180 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 153881 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 24.93
Current children cumulated vsize (KiB) 1056160

[startup+51.1008 s]
/proc/loadavg: 1.08 1.08 1.02 2/71 6139
/proc/meminfo: memFree=10352/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=50.19
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 160710 0 15 0 4962 57 0 0 20 0 11 0 120732165 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 154314 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=38.93
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 3873 20 0 0 20 0 11 0 120732170 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707525341320 140707424040605 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=7.46
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 91745 0 0 0 717 29 0 0 20 0 11 0 120732174 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.9
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 186 4 0 0 20 0 11 0 120732179 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.02
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 4 0 0 0 2 0 0 0 20 0 11 0 120732180 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 154314 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 50.19
Current children cumulated vsize (KiB) 1056160

[startup+102.301 s]
/proc/loadavg: 1.09 1.08 1.02 2/71 6139
/proc/meminfo: memFree=9484/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=100.84
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 162580 0 15 0 10027 57 0 0 20 0 11 0 120732165 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 156177 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=85.14
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 8494 20 0 0 20 0 11 0 120732170 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707423007475 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=11.88
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 93609 0 0 0 1158 30 0 0 20 0 11 0 120732174 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.9
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 186 4 0 0 20 0 11 0 120732179 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.05
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 5 0 0 0 20 0 11 0 120732180 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 156177 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 100.84
Current children cumulated vsize (KiB) 1056160

[startup+162.301 s]
/proc/loadavg: 1.03 1.06 1.01 2/71 6139
/proc/meminfo: memFree=10104/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=160.16
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 163241 0 15 0 15958 58 0 0 20 0 11 0 120732165 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 156829 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=140.67
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 14047 20 0 0 20 0 11 0 120732170 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707424019284 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=15.63
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 94264 0 0 0 1533 30 0 0 20 0 11 0 120732174 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.9
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 186 4 0 0 20 0 11 0 120732179 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.08
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 8 0 0 0 20 0 11 0 120732180 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 156829 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 160.16
Current children cumulated vsize (KiB) 1056160

[startup+222.309 s]
/proc/loadavg: 1.07 1.06 1.01 2/71 6139
/proc/meminfo: memFree=10228/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=219.5
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 165927 0 15 0 21892 58 0 0 20 0 11 0 120732165 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 159511 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=196.97
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 19676 21 0 0 20 0 11 0 120732170 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707424013491 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=18.65
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 96947 0 0 0 1835 30 0 0 20 0 11 0 120732174 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.9
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 186 4 0 0 20 0 11 0 120732179 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.11
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 19 0 0 0 11 0 0 0 20 0 11 0 120732180 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 159511 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 219.5
Current children cumulated vsize (KiB) 1056160

[startup+282.301 s]
/proc/loadavg: 1.02 1.05 1.01 2/71 6139
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=278.82
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171184 0 15 0 27820 62 0 0 20 0 11 0 120732165 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 164760 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=253.98
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 25377 21 0 0 20 0 11 0 120732170 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707424019284 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=20.91
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102198 0 0 0 2057 34 0 0 20 0 11 0 120732174 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.92
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 188 4 0 0 20 0 11 0 120732179 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 120732180 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 164760 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 278.82
Current children cumulated vsize (KiB) 1056160

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

[startup+288.001 s]
/proc/loadavg: 1.02 1.05 1.01 2/71 6139
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=284.46
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171189 0 15 0 28384 62 0 0 20 0 11 0 120732165 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 164765 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=258.86
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 25865 21 0 0 20 0 11 0 120732170 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707423988040 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=21.67
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102203 0 0 0 2133 34 0 0 20 0 11 0 120732174 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.92
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 188 4 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 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) 1056160

[startup+291.201 s]
/proc/loadavg: 1.02 1.05 1.01 2/71 6139
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=287.62
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171189 0 15 0 28700 62 0 0 20 0 11 0 120732165 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 164765 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=262.03
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 26182 21 0 0 20 0 11 0 120732170 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707424073354 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=21.67
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102203 0 0 0 2133 34 0 0 20 0 11 0 120732174 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.92
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 188 4 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 25 0 0 0 14 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 287.62
Current children cumulated vsize (KiB) 1056160

[startup+294.401 s]
/proc/loadavg: 1.02 1.05 1.01 2/71 6139
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=290.79
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171192 0 15 0 29017 62 0 0 20 0 11 0 120732165 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 164765 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=265.19
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 26498 21 0 0 20 0 11 0 120732170 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707423310906 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=21.67
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102203 0 0 0 2133 34 0 0 20 0 11 0 120732174 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.92
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 188 4 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 28 0 0 0 14 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 290.79
Current children cumulated vsize (KiB) 1056160

[startup+295.201 s]
/proc/loadavg: 1.02 1.05 1.01 2/71 6139
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1046992 CPUtime=291.58
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171192 0 15 0 29096 62 0 0 20 0 11 0 120732165 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 261748 164765 2393 9 0 249098 0
[pid=6129/tid=6130] ppid=6126 vsize=1046992 CPUtime=265.98
/proc/6129/task/6130/stat : 6130 (java) R 6126 6126 1733 0 -1 4202560 47773 0 3 0 26577 21 0 0 20 0 11 0 120732170 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707525341144 140707424073128 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1046992 CPUtime=21.67
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102203 0 0 0 2133 34 0 0 20 0 11 0 120732174 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1046992 CPUtime=1.92
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 188 4 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1046992 CPUtime=1.82
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 180 2 0 0 20 0 11 0 120732179 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1046992 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 28 0 0 0 14 0 0 0 20 0 11 0 120732180 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6139] ppid=6126 vsize=1046992 CPUtime=0
/proc/6129/task/6139/stat : 6139 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120733221 1072119808 164765 18446744073709551615 4194304 4228012 140733763988096 140707409405792 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 291.58
Current children cumulated vsize (KiB) 1056160

[startup+296.004 s]
/proc/loadavg: 1.02 1.05 1.01 1/71 6140
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1048020 CPUtime=292
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171208 0 15 0 29138 62 0 0 20 0 11 0 120732165 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 262005 164778 2393 9 0 249355 0
[pid=6129/tid=6130] ppid=6126 vsize=1048020 CPUtime=266.38
/proc/6129/task/6130/stat : 6130 (java) S 6126 6126 1733 0 -1 4202560 47783 0 3 0 26617 21 0 0 20 0 11 0 120732170 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707525342128 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1048020 CPUtime=21.67
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102203 0 0 0 2133 34 0 0 20 0 11 0 120732174 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1048020 CPUtime=1.94
/proc/6129/task/6135/stat : 6135 (java) S 6126 6126 1733 0 -1 4202560 10782 0 0 0 190 4 0 0 20 0 11 0 120732179 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1048020 CPUtime=1.83
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 181 2 0 0 20 0 11 0 120732179 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1048020 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 28 0 0 0 14 0 0 0 20 0 11 0 120732180 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6140] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6140/stat : 6140 (java) S 6126 6126 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120761727 1073172480 164778 18446744073709551615 4194304 4228012 140733763988096 140707408353456 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292
Current children cumulated vsize (KiB) 1057188

[startup+296.201 s]
/proc/loadavg: 1.02 1.05 1.01 1/71 6140
/proc/meminfo: memFree=10256/1022884 swapFree=0/0
[pid=6126] ppid=6125 vsize=9168 CPUtime=0
/proc/6126/stat : 6126 (p2cudf-full-1.1) S 6125 6126 1733 0 -1 4202496 457 291 0 0 0 0 0 0 20 0 1 0 120732164 9388032 331 18446744073709551615 4194304 5129932 140734245816000 140734245814144 140670165886046 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0
/proc/6126/statm: 2292 331 280 229 0 52 0
[pid=6129] ppid=6126 vsize=1048020 CPUtime=292.07
/proc/6129/stat : 6129 (java) S 6126 6126 1733 0 -1 4202496 171210 0 15 0 29143 64 0 0 20 0 11 0 120732165 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140733763979120 140707518807669 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 4 0 0
/proc/6129/statm: 262005 164780 2393 9 0 249355 0
[pid=6129/tid=6130] ppid=6126 vsize=1048020 CPUtime=266.38
/proc/6129/task/6130/stat : 6130 (java) S 6126 6126 1733 0 -1 4202560 47783 0 3 0 26617 21 0 0 20 0 11 0 120732170 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707525342128 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0
[pid=6129/tid=6131] ppid=6126 vsize=1048020 CPUtime=21.67
/proc/6129/task/6131/stat : 6131 (java) S 6126 6126 1733 0 -1 4202560 102203 0 0 0 2133 34 0 0 20 0 11 0 120732174 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707417980032 140707518821963 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6132] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6132/stat : 6132 (java) S 6126 6126 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120732175 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707416926224 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6133] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6133/stat : 6133 (java) S 6126 6126 1733 0 -1 4202560 10 0 0 0 0 0 0 0 20 0 11 0 120732175 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707415873440 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6134] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6134/stat : 6134 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732179 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707414821872 140707518829600 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6135] ppid=6126 vsize=1048020 CPUtime=1.95
/proc/6129/task/6135/stat : 6135 (java) R 6126 6126 1733 0 -1 4202560 10782 0 0 0 191 4 0 0 20 0 11 0 120732179 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707413769232 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6136] ppid=6126 vsize=1048020 CPUtime=1.84
/proc/6129/task/6136/stat : 6136 (java) S 6126 6126 1733 0 -1 4202560 9119 0 0 0 182 2 0 0 20 0 11 0 120732179 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707412716560 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6137] ppid=6126 vsize=1048020 CPUtime=0
/proc/6129/task/6137/stat : 6137 (java) S 6126 6126 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120732180 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707411664080 140707518821076 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6138] ppid=6126 vsize=1048020 CPUtime=0.14
/proc/6129/task/6138/stat : 6138 (java) S 6126 6126 1733 0 -1 4202560 28 0 0 0 14 0 0 0 20 0 11 0 120732180 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707410611584 140707518821963 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
[pid=6129/tid=6140] ppid=6126 vsize=1048020 CPUtime=0.05
/proc/6129/task/6140/stat : 6140 (java) R 6126 6126 1733 0 -1 4202560 8 0 0 0 3 2 0 0 20 0 11 0 120761727 1073172480 164780 18446744073709551615 4194304 4228012 140733763988096 140707408344256 140707518832941 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0
Current children cumulated CPU time (s) 292.07
Current children cumulated vsize (KiB) 1057188

Child status: 0
Real time (s): 296.275
CPU time (s): 292.162
CPU user time (s): 291.474
CPU system time (s): 0.688043
CPU usage (%): 98.6117
Max. virtual memory (cumulated for all children) (KiB): 1058868

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 291.474
system time used= 0.688043
maximum resident set size= 659156
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 171981
page faults= 15
swaps= 0
block input operations= 70408
block output operations= 568
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 7378
involuntary context switches= 41418

runsolver used 0.892055 second user time and 1.79211 second system time

The end