runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201108300820/p2cudf-user-1.13/19890cfe-db9f-11df-9e6c-00163e3d3b7c.cudf.dudf-real.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-real//19890cfe-db9f-11df-9e6c-00163e3d3b7c.cudf /home/misc2010/tmp/201108300820/p2cudf-user-1.13/19890cfe-db9f-11df-9e6c-00163e3d3b7c.cudf.dudf-real.result +sum(installedsize) 

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


[startup+0 s]
/proc/loadavg: 0.85 0.97 0.99 2/36 3531
/proc/meminfo: memFree=940816/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=3020 CPUtime=0
/proc/3531/stat : 3531 (runsolver) D 3530 3531 2160 34817 2160 4202560 72 0 0 0 0 0 0 0 24 0 1 0 51585316 3092480 94 1283457024 134512640 134586868 4287648256 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0
/proc/3531/statm: 755 94 62 19 0 54 0

[startup+0.110184 s]
/proc/loadavg: 0.85 0.97 0.99 2/36 3531
/proc/meminfo: memFree=940816/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.206195 s]
/proc/loadavg: 0.85 0.97 0.99 2/36 3531
/proc/meminfo: memFree=940816/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.306194 s]
/proc/loadavg: 0.85 0.97 0.99 2/36 3531
/proc/meminfo: memFree=940816/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+0.711955 s]
/proc/loadavg: 0.85 0.97 0.99 2/36 3531
/proc/meminfo: memFree=940816/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2568

[startup+1.51212 s]
/proc/loadavg: 0.85 0.97 0.99 4/48 3554
/proc/meminfo: memFree=900724/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884588 CPUtime=0.87
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 15746 0 107 0 81 6 0 0 23 0 10 0 51585320 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221147 13407 1731 10 0 218238 0
[pid=3545/tid=3546] ppid=3533 vsize=884588 CPUtime=0.22
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 5168 0 15 0 22 0 0 0 25 0 10 0 51585347 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4095479167 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 7 0 0 0 0 0 0 0 18 0 10 0 51585357 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 51585358 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 51585358 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51585367 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884588 CPUtime=0.31
/proc/3545/task/3551/stat : 3551 (java) R 3533 3531 2160 34817 2160 4202560 4963 0 1 0 29 2 0 0 20 0 10 0 51585367 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4159142678 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884588 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 10 0 51585367 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51585367 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) R 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 16 0 10 0 51585367 905818112 13407 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.89
Current children cumulated vsize (KiB) 889732

[startup+3.11777 s]
/proc/loadavg: 0.85 0.97 0.99 3/48 3554
/proc/meminfo: memFree=789992/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884588 CPUtime=2.39
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 54524 0 107 0 219 20 0 0 23 0 10 0 51585320 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221147 52185 1754 10 0 218238 0
[pid=3545/tid=3546] ppid=3533 vsize=884588 CPUtime=1.1
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 43731 0 15 0 95 15 0 0 25 0 10 0 51585347 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 7 0 0 0 0 0 0 0 15 0 10 0 51585357 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 25 0 10 0 51585358 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 10 0 51585358 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51585367 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884588 CPUtime=0.96
/proc/3545/task/3551/stat : 3551 (java) R 3533 3531 2160 34817 2160 4202560 5178 0 1 0 94 2 0 0 15 0 10 0 51585367 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4150052724 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884588 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 10 0 51585367 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 10 0 51585367 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884588 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 10 0 51585367 905818112 52185 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.41
Current children cumulated vsize (KiB) 889732
heavy processes:

[startup+6.31209 s]
/proc/loadavg: 0.86 0.97 0.99 3/49 3555
/proc/meminfo: memFree=660304/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=5.52
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 71472 0 108 0 526 26 0 0 23 0 11 0 51585320 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 69134 1786 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=3.09
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 290 19 0 0 25 0 11 0 51585347 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=0.24
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 10231 0 0 0 22 2 0 0 15 0 11 0 51585357 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=1.85
/proc/3545/task/3551/stat : 3551 (java) R 3533 3531 2160 34817 2160 4202560 5357 0 2 0 183 2 0 0 16 0 11 0 51585367 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4149844403 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 69134 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.54
Current children cumulated vsize (KiB) 890056

[startup+12.7135 s]
/proc/loadavg: 0.87 0.97 0.99 2/49 3555
/proc/meminfo: memFree=624476/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=11.92
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 80631 0 108 0 1161 31 0 0 23 0 11 0 51585320 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 78293 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=8.61
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 842 19 0 0 25 0 11 0 51585347 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4096105868 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=0.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 19368 0 0 0 92 6 0 0 16 0 11 0 51585357 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=1.99
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5379 0 2 0 197 2 0 0 16 0 11 0 51585367 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 78293 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 11.94
Current children cumulated vsize (KiB) 890056

[startup+25.5056 s]
/proc/loadavg: 0.90 0.97 0.99 2/49 3555
/proc/meminfo: memFree=607736/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=24.7
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 84555 0 108 0 2438 32 0 0 23 0 11 0 51585320 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 82217 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=20.67
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 2048 19 0 0 25 0 11 0 51585347 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4096133183 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=1.67
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 23289 0 0 0 161 6 0 0 15 0 11 0 51585357 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.01
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5382 0 2 0 199 2 0 0 15 0 11 0 51585367 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 82217 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.72
Current children cumulated vsize (KiB) 890056

[startup+51.1113 s]
/proc/loadavg: 0.93 0.97 0.99 2/49 3555
/proc/meminfo: memFree=599552/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=50.3
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 86579 0 108 0 4997 33 0 0 23 0 11 0 51585320 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 84241 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=45.18
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 4498 20 0 0 25 0 11 0 51585347 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4096138949 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=2.74
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 25311 0 0 0 268 6 0 0 15 0 11 0 51585357 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 84241 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.32
Current children cumulated vsize (KiB) 890056

[startup+102.312 s]
/proc/loadavg: 1.03 0.99 0.99 2/49 3555
/proc/meminfo: memFree=583432/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=101.46
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 90558 0 108 0 10112 34 0 0 23 0 11 0 51585320 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 88220 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=94.82
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 9461 21 0 0 25 0 11 0 51585347 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4095171781 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=4.26
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 29290 0 0 0 419 7 0 0 15 0 11 0 51585357 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 88220 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 101.48
Current children cumulated vsize (KiB) 890056

[startup+162.309 s]
/proc/loadavg: 1.12 1.02 1.00 3/51 3557
/proc/meminfo: memFree=558476/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=161.39
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 96721 0 108 0 16102 37 0 0 23 0 11 0 51585320 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 94383 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=153.52
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 15330 22 0 0 25 0 11 0 51585347 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4095490435 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=5.5
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 35453 0 0 0 541 9 0 0 15 0 11 0 51585357 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) R 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 94383 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 161.41
Current children cumulated vsize (KiB) 890056

[startup+222.311 s]
/proc/loadavg: 1.10 1.03 1.00 2/51 3563
/proc/meminfo: memFree=555252/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=221.35
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97496 0 108 0 22098 37 0 0 23 0 11 0 51585320 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 95158 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=212.28
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 21206 22 0 0 25 0 11 0 51585347 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4095834932 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=6.69
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 660 9 0 0 15 0 11 0 51585357 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 221.37
Current children cumulated vsize (KiB) 890056

[startup+282.308 s]
/proc/loadavg: 1.04 1.02 1.00 4/50 3566
/proc/meminfo: memFree=555276/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=281.27
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97496 0 108 0 28089 38 0 0 23 0 11 0 51585320 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 95158 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=270.91
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 27069 22 0 0 25 0 11 0 51585347 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4096211862 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=7.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 789 9 0 0 16 0 11 0 51585357 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 281.29
Current children cumulated vsize (KiB) 890056

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

[startup+283.208 s]
/proc/loadavg: 1.04 1.02 1.00 2/50 3566
/proc/meminfo: memFree=555276/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=282.17
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97496 0 108 0 28179 38 0 0 23 0 11 0 51585320 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 95158 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=271.81
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 27159 22 0 0 25 0 11 0 51585347 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4096138955 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=7.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 789 9 0 0 15 0 11 0 51585357 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.19
Current children cumulated vsize (KiB) 890056

[startup+284.008 s]
/proc/loadavg: 1.04 1.02 1.00 2/50 3566
/proc/meminfo: memFree=555276/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=884912 CPUtime=282.97
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97496 0 108 0 28258 39 0 0 23 0 11 0 51585320 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221228 95158 1789 10 0 218319 0
[pid=3545/tid=3546] ppid=3533 vsize=884912 CPUtime=272.62
/proc/3545/task/3546/stat : 3546 (java) R 3533 3531 2160 34817 2160 4202560 50270 0 15 0 27238 24 0 0 25 0 11 0 51585347 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4096080861 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=884912 CPUtime=7.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 789 9 0 0 15 0 11 0 51585357 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=884912 CPUtime=2.03
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 201 2 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=884912 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) R 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3545/tid=3555] ppid=3533 vsize=884912 CPUtime=0
/proc/3545/task/3555/stat : 3555 (java) S 3533 3531 2160 34817 2160 4202560 4 0 0 0 0 0 0 0 25 0 11 0 51585735 906149888 95158 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.99
Current children cumulated vsize (KiB) 890056

[startup+284.408 s]
/proc/loadavg: 1.04 1.02 1.00 2/50 3566
/proc/meminfo: memFree=555276/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=885236 CPUtime=283.18
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97509 0 108 0 28279 39 0 0 23 0 11 0 51585320 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221309 95171 1790 10 0 218400 0
[pid=3545/tid=3546] ppid=3533 vsize=885236 CPUtime=272.81
/proc/3545/task/3546/stat : 3546 (java) S 3533 3531 2160 34817 2160 4202560 50278 0 15 0 27257 24 0 0 25 0 11 0 51585347 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=885236 CPUtime=7.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 789 9 0 0 16 0 11 0 51585357 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=885236 CPUtime=2.05
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5384 0 2 0 203 2 0 0 15 0 11 0 51585367 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=885236 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906481664 95171 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.2
Current children cumulated vsize (KiB) 890380

[startup+284.816 s]
/proc/loadavg: 1.04 1.02 1.00 2/50 3566
/proc/meminfo: memFree=555276/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=885236 CPUtime=283.24
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97516 0 108 0 28284 40 0 0 23 0 11 0 51585320 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221309 95178 1790 10 0 218400 0
[pid=3545/tid=3546] ppid=3533 vsize=885236 CPUtime=272.81
/proc/3545/task/3546/stat : 3546 (java) S 3533 3531 2160 34817 2160 4202560 50278 0 15 0 27257 24 0 0 25 0 11 0 51585347 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=885236 CPUtime=7.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 789 9 0 0 16 0 11 0 51585357 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=885236 CPUtime=2.08
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5390 0 2 0 206 2 0 0 15 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=885236 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.26
Current children cumulated vsize (KiB) 890380

[startup+284.904 s]
/proc/loadavg: 1.03 1.02 1.00 2/50 3567
/proc/meminfo: memFree=555216/1048576 swapFree=0/0
[pid=3531] ppid=3530 vsize=2568 CPUtime=0
/proc/3531/stat : 3531 (p2cudf-user-1.1) S 3530 3531 2160 34817 2160 4202496 373 0 0 0 0 0 0 0 25 0 1 0 51585316 2629632 270 1283457024 134512640 135304128 4294433104 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3531/statm: 642 270 230 194 0 29 0
[pid=3533] ppid=3531 vsize=2576 CPUtime=0.02
/proc/3533/stat : 3533 (p2cudf) S 3531 3531 2160 34817 2160 4202496 390 3014 0 1 0 0 1 1 25 0 1 0 51585317 2637824 280 1283457024 134512640 135304128 4287337136 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3533/statm: 644 280 239 194 0 31 0
[pid=3545] ppid=3533 vsize=885236 CPUtime=283.33
/proc/3545/stat : 3545 (java) S 3533 3531 2160 34817 2160 4202496 97516 0 108 0 28291 42 0 0 23 0 11 0 51585320 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3545/statm: 221309 95178 1790 10 0 218400 0
[pid=3545/tid=3546] ppid=3533 vsize=885236 CPUtime=272.81
/proc/3545/task/3546/stat : 3546 (java) S 3533 3531 2160 34817 2160 4202560 50278 0 15 0 27257 24 0 0 25 0 11 0 51585347 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3547] ppid=3533 vsize=885236 CPUtime=7.98
/proc/3545/task/3547/stat : 3547 (java) S 3533 3531 2160 34817 2160 4202560 36228 0 0 0 789 9 0 0 16 0 11 0 51585357 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 0 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3548] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3548/stat : 3548 (java) S 3533 3531 2160 34817 2160 4202560 7 0 1 0 0 0 0 0 18 0 11 0 51585358 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3549] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3549/stat : 3549 (java) S 3533 3531 2160 34817 2160 4202560 6 0 0 0 0 0 0 0 18 0 11 0 51585358 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3550] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3550/stat : 3550 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3551] ppid=3533 vsize=885236 CPUtime=2.1
/proc/3545/task/3551/stat : 3551 (java) S 3533 3531 2160 34817 2160 4202560 5390 0 2 0 208 2 0 0 16 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3552] ppid=3533 vsize=885236 CPUtime=0.32
/proc/3545/task/3552/stat : 3552 (java) S 3533 3531 2160 34817 2160 4202560 4616 0 8 0 29 3 0 0 25 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3553] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3553/stat : 3553 (java) S 3533 3531 2160 34817 2160 4202560 1 0 0 0 0 0 0 0 25 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3554] ppid=3533 vsize=885236 CPUtime=0
/proc/3545/task/3554/stat : 3554 (java) S 3533 3531 2160 34817 2160 4202560 2 0 0 0 0 0 0 0 15 0 11 0 51585367 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 18446612132658811136 0 0 -1 0 0 0 0
[pid=3545/tid=3567] ppid=3533 vsize=885236 CPUtime=0.09
/proc/3545/task/3567/stat : 3567 (java) R 3533 3531 2160 34817 2160 4202560 5 0 0 0 6 3 0 0 20 0 11 0 51613740 906481664 95178 1283457024 134512640 134550932 4293158624 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.35
Current children cumulated vsize (KiB) 890380

Child status: 0
Real time (s): 284.963
CPU time (s): 283.41
CPU user time (s): 282.934
CPU system time (s): 0.476029
CPU usage (%): 99.455
Max. virtual memory (cumulated for all children) (KiB): 890440

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 282.934
system time used= 0.476029
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 101317
page faults= 109
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 6487
involuntary context switches= 8686

runsolver used 0 second user time and 0 second system time

The end