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/201108241238/p2cudf-trendy-1.13/rand230.cudf.s-e-l-s-s.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/incremental/s-e-l-s-s//rand230.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/rand230.cudf.s-e-l-s-s.result 

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 1.00 0.99 5/35 3984
/proc/meminfo: memFree=621052/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2572 CPUtime=0
/proc/3983/stat : 3983 (p2cudf) R 3982 3982 1511 34817 1511 4202496 346 0 0 0 0 0 0 0 25 0 1 0 1008359 2633728 272 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/3983/statm: 643 272 231 194 0 30 0
[pid=3984] ppid=3983 vsize=2572 CPUtime=0
/proc/3984/stat : 3984 (p2cudf) R 3983 3982 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 1008359 2633728 41 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/3984/statm: 643 41 0 194 0 30 0

[startup+0.185613 s]
/proc/loadavg: 0.85 1.00 0.99 5/35 3984
/proc/meminfo: memFree=621052/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+0.205609 s]
/proc/loadavg: 0.85 1.00 0.99 5/35 3984
/proc/meminfo: memFree=621052/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+0.305634 s]
/proc/loadavg: 0.85 1.00 0.99 5/35 3984
/proc/meminfo: memFree=621052/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+0.705738 s]
/proc/loadavg: 0.85 1.00 0.99 5/35 3984
/proc/meminfo: memFree=621052/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+1.50596 s]
/proc/loadavg: 0.85 1.00 0.99 5/47 4007
/proc/meminfo: memFree=560736/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=898484 CPUtime=1.48
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 25922 0 1 0 141 7 0 0 25 0 10 0 1008362 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 224621 21962 1719 10 0 221712 0
[pid=3996/tid=3997] ppid=3983 vsize=898484 CPUtime=0.78
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 10932 0 1 0 74 4 0 0 25 0 10 0 1008363 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4094675305 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=898484 CPUtime=0
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 17 0 10 0 1008364 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=898484 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 1008365 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=898484 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 1008365 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=898484 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1008367 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=898484 CPUtime=0.46
/proc/3996/task/4002/stat : 4002 (java) R 3983 3982 1511 34817 1511 4202560 9321 0 0 0 44 2 0 0 25 0 10 0 1008367 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=898484 CPUtime=0.21
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 4590 0 0 0 21 0 0 0 22 0 10 0 1008367 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=898484 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1008367 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=898484 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 1008367 920047616 21962 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 903632

[startup+3.10638 s]
/proc/loadavg: 0.86 1.00 0.99 2/47 4007
/proc/meminfo: memFree=466372/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=896436 CPUtime=3.07
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 79124 0 1 0 286 21 0 0 25 0 10 0 1008362 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 224109 74029 1732 10 0 221200 0
[pid=3996/tid=3997] ppid=3983 vsize=896436 CPUtime=1.72
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50038 0 1 0 156 16 0 0 25 0 10 0 1008363 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4094941475 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=896436 CPUtime=0.26
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 12679 0 0 0 24 2 0 0 16 0 10 0 1008364 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=896436 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 1008365 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=896436 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 1008365 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=896436 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1008367 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=896436 CPUtime=0.47
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9346 0 0 0 45 2 0 0 25 0 10 0 1008367 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=896436 CPUtime=0.58
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 5985 0 0 0 58 0 0 0 15 0 10 0 1008367 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=896436 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1008367 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=896436 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 1008367 917950464 74029 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 901584

[startup+6.31743 s]
/proc/loadavg: 0.86 1.00 0.99 3/47 4007
/proc/meminfo: memFree=270832/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=896824 CPUtime=6.28
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 96424 0 1 0 602 26 0 0 25 0 11 0 1008362 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 224206 89040 1770 10 0 221297 0
[pid=3996/tid=3997] ppid=3983 vsize=896824 CPUtime=3.03
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50270 0 1 0 284 19 0 0 25 0 11 0 1008363 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=896824 CPUtime=0.6
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 27181 0 0 0 56 4 0 0 16 0 11 0 1008364 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=896824 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=896824 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=896824 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=896824 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=896824 CPUtime=2.14
/proc/3996/task/4003/stat : 4003 (java) R 3983 3982 1511 34817 1511 4202560 8545 0 0 0 213 1 0 0 15 0 11 0 1008367 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4158604807 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=896824 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=896824 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 918347776 89040 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 901972

[startup+12.7097 s]
/proc/loadavg: 0.88 1.00 0.99 2/48 4009
/proc/meminfo: memFree=245280/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=893716 CPUtime=12.67
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 103488 0 1 0 1241 26 0 0 25 0 11 0 1008362 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 223429 95339 1782 10 0 220520 0
[pid=3996/tid=3997] ppid=3983 vsize=893716 CPUtime=8.67
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50280 0 1 0 848 19 0 0 25 0 11 0 1008363 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4095790945 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=893716 CPUtime=0.89
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 34161 0 0 0 85 4 0 0 16 0 11 0 1008364 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=893716 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=893716 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=893716 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=893716 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=893716 CPUtime=2.61
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8619 0 0 0 260 1 0 0 15 0 11 0 1008367 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=893716 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=893716 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=893716 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 915165184 95339 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 898864

[startup+25.5132 s]
/proc/loadavg: 0.90 1.00 0.99 2/48 4010
/proc/meminfo: memFree=223208/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=25.46
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 109717 0 1 0 2518 28 0 0 25 0 11 0 1008362 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 100868 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=20.64
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50281 0 1 0 2044 20 0 0 25 0 11 0 1008363 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4095748516 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=1.35
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40372 0 0 0 130 5 0 0 16 0 11 0 1008364 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=2.97
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 296 1 0 0 16 0 11 0 1008367 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 100868 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.48
Current children cumulated vsize (KiB) 896064

[startup+51.1083 s]
/proc/loadavg: 0.93 1.00 0.99 2/48 4012
/proc/meminfo: memFree=221720/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=51.05
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 5077 28 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=44.81
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 4461 20 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4095883165 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=2.7
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 265 5 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.03
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 302 1 0 0 16 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) R 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.07
Current children cumulated vsize (KiB) 896064

[startup+102.311 s]
/proc/loadavg: 0.97 1.00 0.99 3/47 4017
/proc/meminfo: memFree=221612/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=102.24
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 10194 30 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=94.73
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 9453 20 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4095723536 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=3.92
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 386 6 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.07
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 306 1 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.26
Current children cumulated vsize (KiB) 896064

[startup+162.307 s]
/proc/loadavg: 0.99 1.00 0.99 2/46 4017
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=162.19
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 16187 32 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=153.44
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 15322 22 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4095729838 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=5.16
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 510 6 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.07
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 306 1 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 162.21
Current children cumulated vsize (KiB) 896064

[startup+222.311 s]
/proc/loadavg: 1.05 1.01 0.99 3/46 4017
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=222.17
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 22182 35 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=212.18
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 21193 25 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4095912846 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=6.4
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 634 6 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.07
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 306 1 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) R 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 222.19
Current children cumulated vsize (KiB) 896064

[startup+282.306 s]
/proc/loadavg: 1.01 1.00 0.99 2/46 4017
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=282.1
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 28175 35 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=271.27
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 27102 25 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4095723499 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=7.24
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 718 6 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.07
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 306 1 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.12
Current children cumulated vsize (KiB) 896064

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

[startup+284.306 s]
/proc/loadavg: 1.01 1.00 0.99 2/46 4017
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=284.1
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 28375 35 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=273.27
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 27302 25 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4094892864 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=7.24
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 718 6 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.07
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 306 1 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.12
Current children cumulated vsize (KiB) 896064

[startup+285.907 s]
/proc/loadavg: 1.01 1.00 0.99 2/46 4017
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=890916 CPUtime=285.7
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110039 0 1 0 28535 35 0 0 25 0 11 0 1008362 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222729 101190 1788 10 0 219820 0
[pid=3996/tid=3997] ppid=3983 vsize=890916 CPUtime=274.87
/proc/3996/task/3997/stat : 3997 (java) R 3983 3982 1511 34817 1511 4202560 50282 0 1 0 27462 25 0 0 25 0 11 0 1008363 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4095924461 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=890916 CPUtime=7.24
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 718 6 0 0 15 0 11 0 1008364 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=890916 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=890916 CPUtime=3.07
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 306 1 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4008] ppid=3983 vsize=890916 CPUtime=0
/proc/3996/task/4008/stat : 4008 (java) S 3983 3982 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 11 0 1008971 912297984 101190 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 285.72
Current children cumulated vsize (KiB) 896064

[startup+286.607 s]
/proc/loadavg: 1.01 1.00 0.99 2/46 4018
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=891240 CPUtime=286
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110052 0 1 0 28565 35 0 0 25 0 11 0 1008362 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222810 101203 1789 10 0 219901 0
[pid=3996/tid=3997] ppid=3983 vsize=891240 CPUtime=275.1
/proc/3996/task/3997/stat : 3997 (java) S 3983 3982 1511 34817 1511 4202560 50290 0 1 0 27485 25 0 0 25 0 11 0 1008363 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=891240 CPUtime=7.24
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 718 6 0 0 16 0 11 0 1008364 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 1008365 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 1008365 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=891240 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 11 0 1008367 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=891240 CPUtime=3.14
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 313 1 0 0 15 0 11 0 1008367 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 1008367 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 1008367 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4018] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4018/stat : 4018 (java) S 3983 3982 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 1036980 912629760 101203 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 286.02
Current children cumulated vsize (KiB) 896388

[startup+286.811 s]
/proc/loadavg: 1.01 1.00 0.99 2/46 4018
/proc/meminfo: memFree=221620/1048576 swapFree=0/0
[pid=3982] ppid=3981 vsize=2572 CPUtime=0
/proc/3982/stat : 3982 (p2cudf-trendy-1) S 3981 3982 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 1008359 2633728 270 1283457024 134512640 135304128 4287173296 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3982/statm: 643 270 230 194 0 30 0
[pid=3983] ppid=3982 vsize=2576 CPUtime=0.02
/proc/3983/stat : 3983 (p2cudf) S 3982 3982 1511 34817 1511 4202496 392 3016 0 1 0 0 0 2 25 0 1 0 1008359 2637824 281 1283457024 134512640 135304128 4292069200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3983/statm: 644 281 239 194 0 31 0
[pid=3996] ppid=3983 vsize=891240 CPUtime=286.08
/proc/3996/stat : 3996 (java) S 3983 3982 1511 34817 1511 4202496 110053 0 1 0 28570 38 0 0 25 0 10 0 1008362 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3996/statm: 222810 101204 1789 10 0 219901 0
[pid=3996/tid=3997] ppid=3983 vsize=891240 CPUtime=275.1
/proc/3996/task/3997/stat : 3997 (java) S 3983 3982 1511 34817 1511 4202560 50290 0 1 0 27485 25 0 0 20 0 10 0 1008363 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3998] ppid=3983 vsize=891240 CPUtime=7.24
/proc/3996/task/3998/stat : 3998 (java) S 3983 3982 1511 34817 1511 4202560 40693 0 0 0 718 6 0 0 16 0 10 0 1008364 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=3999] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/3999/stat : 3999 (java) S 3983 3982 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 1008365 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4000] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4000/stat : 4000 (java) S 3983 3982 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 1008365 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4001] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4001/stat : 4001 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1008367 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4002] ppid=3983 vsize=891240 CPUtime=0.48
/proc/3996/task/4002/stat : 4002 (java) S 3983 3982 1511 34817 1511 4202560 9347 0 0 0 46 2 0 0 18 0 10 0 1008367 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4003] ppid=3983 vsize=891240 CPUtime=3.15
/proc/3996/task/4003/stat : 4003 (java) S 3983 3982 1511 34817 1511 4202560 8636 0 0 0 314 1 0 0 15 0 10 0 1008367 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4004] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4004/stat : 4004 (java) S 3983 3982 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 1008367 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=3996/tid=4005] ppid=3983 vsize=891240 CPUtime=0
/proc/3996/task/4005/stat : 4005 (java) S 3983 3982 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 1008367 912629760 101204 1283457024 134512640 134550932 4292038112 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 286.1
Current children cumulated vsize (KiB) 896388

Child status: 0
Real time (s): 286.874
CPU time (s): 286.138
CPU user time (s): 285.71
CPU system time (s): 0.428026
CPU usage (%): 99.7435
Max. virtual memory (cumulated for all children) (KiB): 906728

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 285.71
system time used= 0.428026
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 113860
page faults= 2
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 6168
involuntary context switches= 8752

runsolver used 0 second user time and 0.008 second system time

The end