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/201012091704/p2cudf-user-1.10/randa870aa.cudf.difficult.log.runsolver ./p2cudf-user-1.10 /home/misc2010/data/2010/difficult//randa870aa.cudf /home/misc2010/tmp/201012091704/p2cudf-user-1.10/randa870aa.cudf.difficult.result -changed,-notuptodate,-removed,-new 

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: 1.24 1.06 1.01 5/38 9458
/proc/meminfo: memFree=379700/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) R 9456 9457 25521 34816 2180 4202496 360 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2568 CPUtime=0
/proc/9458/stat : 9458 (p2cudf-user-1.1) R 9457 9457 25521 34816 2180 4202560 0 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 40 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/9458/statm: 642 40 0 194 0 28 0

[startup+0.19496 s]
/proc/loadavg: 1.24 1.06 1.01 5/38 9458
/proc/meminfo: memFree=379700/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.204955 s]
/proc/loadavg: 1.24 1.06 1.01 5/38 9458
/proc/meminfo: memFree=379700/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.304984 s]
/proc/loadavg: 1.24 1.06 1.01 5/38 9458
/proc/meminfo: memFree=379700/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+0.705274 s]
/proc/loadavg: 1.24 1.06 1.01 5/38 9458
/proc/meminfo: memFree=379700/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5144

[startup+1.50557 s]
/proc/loadavg: 1.24 1.06 1.01 4/49 9480
/proc/meminfo: memFree=334380/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=883500 CPUtime=1.45
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 18791 0 1 0 136 9 0 0 25 0 10 0 902124735 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220875 15223 1729 10 0 217965 0
[pid=9471/tid=9472] ppid=9458 vsize=883500 CPUtime=0.61
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4202560 7399 0 1 0 55 6 0 0 25 0 10 0 902124735 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4150458661 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=883500 CPUtime=0
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 5 0 0 0 0 0 0 0 18 0 10 0 902124737 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=883500 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 25 0 10 0 902124737 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=883500 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 10 0 902124737 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=883500 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 10 0 902124740 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=883500 CPUtime=0.56
/proc/9471/task/9477/stat : 9477 (java) R 9458 9457 25521 34816 2180 4202560 8205 0 0 0 54 2 0 0 23 0 10 0 902124740 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4149381335 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=883500 CPUtime=0.27
/proc/9471/task/9478/stat : 9478 (java) R 9458 9457 25521 34816 2180 4202560 2072 0 0 0 27 0 0 0 23 0 10 0 902124740 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4150425567 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=883500 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 10 0 902124740 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=883500 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 10 0 902124740 904704000 15223 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 888644

[startup+3.10494 s]
/proc/loadavg: 1.38 1.09 1.02 3/49 9480
/proc/meminfo: memFree=283952/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=881596 CPUtime=2.99
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 50975 0 1 0 279 20 0 0 25 0 10 0 902124735 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220399 43435 1732 10 0 217489 0
[pid=9471/tid=9472] ppid=9458 vsize=881596 CPUtime=1.35
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4202560 35602 0 1 0 117 18 0 0 25 0 10 0 902124735 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4095999061 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=881596 CPUtime=0
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 5 0 0 0 0 0 0 0 18 0 10 0 902124737 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=881596 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 25 0 10 0 902124737 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=881596 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 10 0 902124737 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=881596 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 10 0 902124740 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=881596 CPUtime=0.62
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8261 0 0 0 60 2 0 0 22 0 10 0 902124740 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=881596 CPUtime=1.02
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 5997 0 0 0 102 0 0 0 24 0 10 0 902124740 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=881596 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 10 0 902124740 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=881596 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 10 0 902124740 902754304 43435 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.02
Current children cumulated vsize (KiB) 886740

[startup+6.30777 s]
/proc/loadavg: 1.38 1.09 1.02 2/50 9481
/proc/meminfo: memFree=84340/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=881920 CPUtime=6.16
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 81172 0 1 0 582 34 0 0 25 0 11 0 902124735 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220480 72111 1791 10 0 217570 0
[pid=9471/tid=9472] ppid=9458 vsize=881920 CPUtime=2.73
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4202560 50465 0 1 0 247 26 0 0 25 0 11 0 902124735 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4096788288 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=881920 CPUtime=0.31
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 13632 0 0 0 28 3 0 0 16 0 11 0 902124737 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 6 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=881920 CPUtime=1.04
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8317 0 0 0 102 2 0 0 15 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=881920 CPUtime=2.06
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 7642 0 0 0 204 2 0 0 16 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9481] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9481/stat : 9481 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 11 0 902125247 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.19
Current children cumulated vsize (KiB) 887064

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

[startup+6.40785 s]
/proc/loadavg: 1.38 1.09 1.02 2/50 9481
/proc/meminfo: memFree=84340/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=881920 CPUtime=6.26
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 81172 0 1 0 592 34 0 0 25 0 11 0 902124735 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220480 72111 1791 10 0 217570 0
[pid=9471/tid=9472] ppid=9458 vsize=881920 CPUtime=2.83
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4202560 50465 0 1 0 257 26 0 0 25 0 11 0 902124735 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4096742505 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=881920 CPUtime=0.31
/proc/9471/task/9473/stat : 9473 (java) R 9458 9457 25521 34816 2180 4202560 13632 0 0 0 28 3 0 0 15 0 11 0 902124737 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 6 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=881920 CPUtime=1.04
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8317 0 0 0 102 2 0 0 15 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=881920 CPUtime=2.06
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 7642 0 0 0 204 2 0 0 16 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 11 0 902124740 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9481] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9481/stat : 9481 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 11 0 902125247 903086080 72111 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.29
Current children cumulated vsize (KiB) 887064

[startup+9.50854 s]
/proc/loadavg: 1.35 1.09 1.02 2/50 9481
/proc/meminfo: memFree=55200/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=881920 CPUtime=9.36
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 88653 0 1 0 899 37 0 0 25 0 11 0 902124735 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220480 79592 1793 10 0 217570 0
[pid=9471/tid=9472] ppid=9458 vsize=881920 CPUtime=5.16
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4202560 50467 0 1 0 488 28 0 0 25 0 11 0 902124735 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4096846805 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=881920 CPUtime=0.83
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 21081 0 0 0 78 5 0 0 16 0 11 0 902124737 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 6 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=881920 CPUtime=1.04
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8317 0 0 0 102 2 0 0 15 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=881920 CPUtime=2.31
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 7672 0 0 0 229 2 0 0 15 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9481] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9481/stat : 9481 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 11 0 902125247 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 9.39
Current children cumulated vsize (KiB) 887064

[startup+10.3087 s]
/proc/loadavg: 1.35 1.09 1.02 2/50 9481
/proc/meminfo: memFree=54580/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=881920 CPUtime=10.16
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 88653 0 1 0 979 37 0 0 25 0 11 0 902124735 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220480 79592 1793 10 0 217570 0
[pid=9471/tid=9472] ppid=9458 vsize=881920 CPUtime=5.96
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4202560 50467 0 1 0 568 28 0 0 25 0 11 0 902124735 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4096757912 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=881920 CPUtime=0.83
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 21081 0 0 0 78 5 0 0 16 0 11 0 902124737 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 6 0 0 0 0 0 0 0 18 0 11 0 902124737 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=881920 CPUtime=1.04
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8317 0 0 0 102 2 0 0 15 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=881920 CPUtime=2.31
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 7672 0 0 0 229 2 0 0 15 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 11 0 902124740 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9481] ppid=9458 vsize=881920 CPUtime=0
/proc/9471/task/9481/stat : 9481 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 11 0 902125247 903086080 79592 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.19
Current children cumulated vsize (KiB) 887064

[startup+10.6054 s]
/proc/loadavg: 1.35 1.09 1.02 2/50 9481
/proc/meminfo: memFree=54580/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=882244 CPUtime=10.38
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 88673 0 1 0 1001 37 0 0 25 0 12 0 902124735 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220561 79612 1793 10 0 217651 0
[pid=9471/tid=9472] ppid=9458 vsize=882244 CPUtime=6.12
/proc/9471/task/9472/stat : 9472 (java) S 9458 9457 25521 34816 2180 4202560 50475 0 1 0 584 28 0 0 25 0 12 0 902124735 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=882244 CPUtime=0.83
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 21081 0 0 0 78 5 0 0 16 0 12 0 902124737 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 18 0 12 0 902124737 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 6 0 0 0 0 0 0 0 18 0 12 0 902124737 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=882244 CPUtime=1.04
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8317 0 0 0 102 2 0 0 15 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=882244 CPUtime=2.37
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 7680 0 0 0 235 2 0 0 16 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9481] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9481/stat : 9481 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 12 0 902125247 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.41
Current children cumulated vsize (KiB) 887388

[startup+11.0055 s]
/proc/loadavg: 1.35 1.09 1.02 1/51 9482
/proc/meminfo: memFree=54448/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=882244 CPUtime=10.38
/proc/9471/stat : 9471 (java) S 9458 9457 25521 34816 2180 4202496 88673 0 1 0 1001 37 0 0 25 0 12 0 902124735 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 220561 79612 1793 10 0 217651 0
[pid=9471/tid=9472] ppid=9458 vsize=882244 CPUtime=6.12
/proc/9471/task/9472/stat : 9472 (java) S 9458 9457 25521 34816 2180 4202560 50475 0 1 0 584 28 0 0 25 0 12 0 902124735 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9473] ppid=9458 vsize=882244 CPUtime=0.83
/proc/9471/task/9473/stat : 9473 (java) S 9458 9457 25521 34816 2180 4202560 21081 0 0 0 78 5 0 0 16 0 12 0 902124737 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 0 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9474] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9474/stat : 9474 (java) S 9458 9457 25521 34816 2180 4202560 9 0 0 0 0 0 0 0 18 0 12 0 902124737 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9475] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9475/stat : 9475 (java) S 9458 9457 25521 34816 2180 4202560 6 0 0 0 0 0 0 0 18 0 12 0 902124737 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9476] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9476/stat : 9476 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9477] ppid=9458 vsize=882244 CPUtime=1.04
/proc/9471/task/9477/stat : 9477 (java) S 9458 9457 25521 34816 2180 4202560 8317 0 0 0 102 2 0 0 15 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9478] ppid=9458 vsize=882244 CPUtime=2.37
/proc/9471/task/9478/stat : 9478 (java) S 9458 9457 25521 34816 2180 4202560 7680 0 0 0 235 2 0 0 16 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9479] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9479/stat : 9479 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 25 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9480] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9480/stat : 9480 (java) S 9458 9457 25521 34816 2180 4202560 1 0 0 0 0 0 0 0 15 0 12 0 902124740 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9481] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9481/stat : 9481 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 12 0 902125247 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
[pid=9471/tid=9482] ppid=9458 vsize=882244 CPUtime=0
/proc/9471/task/9482/stat : 9482 (java) S 9458 9457 25521 34816 2180 4202560 4 0 0 0 0 0 0 0 25 0 12 0 902125785 903417856 79612 1283457024 134512640 134550932 4288238160 18446744073709551615 4294960130 0 4 0 16800975 18446612133395367872 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.41
Current children cumulated vsize (KiB) 887388

[startup+11.1137 s]
/proc/loadavg: 1.35 1.09 1.02 1/51 9482
/proc/meminfo: memFree=54448/1048576 swapFree=2095372/2097144
[pid=9457] ppid=9456 vsize=2568 CPUtime=0
/proc/9457/stat : 9457 (p2cudf-user-1.1) S 9456 9457 25521 34816 2180 4202496 378 0 0 0 0 0 0 0 25 0 1 0 902124731 2629632 273 1283457024 134512640 135304128 4294576528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9457/statm: 642 273 233 194 0 28 0
[pid=9458] ppid=9457 vsize=2576 CPUtime=0.03
/proc/9458/stat : 9458 (p2cudf) S 9457 9457 25521 34816 2180 4202496 393 2997 0 1 0 1 0 2 25 0 1 0 902124731 2637824 282 1283457024 134512640 135304128 4287593248 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9458/statm: 644 282 241 194 0 30 0
[pid=9471] ppid=9458 vsize=0 CPUtime=10.44
/proc/9471/stat : 9471 (java) Z 9458 9457 25521 34816 2180 4203532 88678 0 1 0 1004 40 0 0 18 0 2 0 902124735 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9471/statm: 0 0 0 0 0 0 0
[pid=9471/tid=9472] ppid=9458 vsize=0 CPUtime=6.14
/proc/9471/task/9472/stat : 9472 (java) R 9458 9457 25521 34816 2180 4203588 50476 0 1 0 584 30 0 0 20 0 2 0 902124735 0 0 1283457024 0 0 0 0 0 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.47
Current children cumulated vsize (KiB) 5144

Child status: 0
Real time (s): 11.1266
CPU time (s): 10.4967
CPU user time (s): 10.0566
CPU system time (s): 0.440027
CPU usage (%): 94.3388
Max. virtual memory (cumulated for all children) (KiB): 893040

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 10.0566
system time used= 0.440027
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 92466
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= 916
involuntary context switches= 1003

runsolver used 0 second user time and 0.004 second system time

The end