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/201108291842/p2cudf-user-1.13/rand83.cudf.dudf-random.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/dudf-random//rand83.cudf /home/misc2010/tmp/201108291842/p2cudf-user-1.13/rand83.cudf.dudf-random.result -notuptodate,-removed,-changed 

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.14 1.26 1.20 5/34 24886
/proc/meminfo: memFree=421948/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) R 24884 24885 17511 34816 17511 4202496 355 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2564 CPUtime=0
/proc/24886/stat : 24886 (p2cudf-user-1.1) R 24885 24885 17511 34816 17511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 39 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/24886/statm: 641 39 0 194 0 28 0

[startup+0.185411 s]
/proc/loadavg: 1.14 1.26 1.20 5/34 24886
/proc/meminfo: memFree=421948/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+0.205405 s]
/proc/loadavg: 1.14 1.26 1.20 5/34 24886
/proc/meminfo: memFree=421948/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+0.305419 s]
/proc/loadavg: 1.14 1.26 1.20 5/34 24886
/proc/meminfo: memFree=421948/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+0.705516 s]
/proc/loadavg: 1.14 1.26 1.20 5/34 24886
/proc/meminfo: memFree=421948/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5136

[startup+1.50572 s]
/proc/loadavg: 1.14 1.26 1.20 5/45 24908
/proc/meminfo: memFree=386128/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=871168 CPUtime=1.46
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 27912 0 1 0 136 10 0 0 25 0 10 0 46330961 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217792 26187 1724 10 0 214883 0
[pid=24899/tid=24900] ppid=24886 vsize=871168 CPUtime=0.96
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 21089 0 1 0 86 10 0 0 25 0 10 0 46330961 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4095740303 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 7 0 0 0 0 0 0 0 17 0 10 0 46330963 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 46330963 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 46330963 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46330966 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=871168 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 10 0 46330966 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=871168 CPUtime=0.11
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 399 0 0 0 11 0 0 0 23 0 10 0 46330966 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46330966 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46330966 892076032 26187 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 876304

[startup+3.10621 s]
/proc/loadavg: 1.14 1.26 1.20 3/45 24908
/proc/meminfo: memFree=198640/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=871168 CPUtime=3.05
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 68295 0 1 0 278 27 0 0 25 0 10 0 46330961 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217792 66570 1743 10 0 214883 0
[pid=24899/tid=24900] ppid=24886 vsize=871168 CPUtime=1.86
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50092 0 1 0 164 22 0 0 25 0 10 0 46330961 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=871168 CPUtime=0.24
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 11338 0 0 0 20 4 0 0 16 0 10 0 46330963 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 10 0 46330963 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 46330963 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46330966 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=871168 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 10 0 46330966 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=871168 CPUtime=0.55
/proc/24899/task/24906/stat : 24906 (java) R 24886 24885 17511 34816 17511 4202560 446 0 0 0 55 0 0 0 16 0 10 0 46330966 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4149356290 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46330966 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=871168 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46330966 892076032 66570 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.07
Current children cumulated vsize (KiB) 876304

[startup+6.30721 s]
/proc/loadavg: 1.29 1.29 1.21 2/46 24909
/proc/meminfo: memFree=107996/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870936 CPUtime=6.24
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 82925 0 1 0 593 31 0 0 25 0 11 0 46330961 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217734 80030 1779 10 0 214825 0
[pid=24899/tid=24900] ppid=24886 vsize=870936 CPUtime=3.51
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50278 0 1 0 329 22 0 0 25 0 11 0 46330961 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4096368898 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870936 CPUtime=0.56
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 24503 0 0 0 48 8 0 0 16 0 11 0 46330963 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870936 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870936 CPUtime=1.77
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 1721 0 0 0 177 0 0 0 15 0 11 0 46330966 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891838464 80030 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.26
Current children cumulated vsize (KiB) 876072

[startup+12.7093 s]
/proc/loadavg: 1.27 1.29 1.21 2/46 24909
/proc/meminfo: memFree=107500/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870936 CPUtime=12.64
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 82976 0 1 0 1232 32 0 0 25 0 11 0 46330961 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217734 80081 1784 10 0 214825 0
[pid=24899/tid=24900] ppid=24886 vsize=870936 CPUtime=9.43
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50279 0 1 0 921 22 0 0 25 0 11 0 46330961 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4096366322 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870936 CPUtime=0.56
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 24503 0 0 0 48 8 0 0 15 0 11 0 46330963 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870936 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870936 CPUtime=2.25
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 1771 0 0 0 225 0 0 0 15 0 11 0 46330966 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870936 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891838464 80081 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.66
Current children cumulated vsize (KiB) 876072

[startup+25.5133 s]
/proc/loadavg: 1.21 1.27 1.21 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870904 CPUtime=25.44
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91733 0 1 0 2510 34 0 0 25 0 11 0 46330961 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217726 88286 1788 10 0 214817 0
[pid=24899/tid=24900] ppid=24886 vsize=870904 CPUtime=21.36
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50280 0 1 0 2114 22 0 0 25 0 11 0 46330961 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4096475286 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870904 CPUtime=0.94
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32673 0 0 0 84 10 0 0 16 0 11 0 46330963 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870904 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870904 CPUtime=2.73
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 273 0 0 0 15 0 11 0 46330966 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891805696 88286 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.46
Current children cumulated vsize (KiB) 876040

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

[startup+31.9054 s]
/proc/loadavg: 1.19 1.27 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870904 CPUtime=31.84
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91848 0 1 0 3150 34 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217726 88401 1788 10 0 214817 0
[pid=24899/tid=24900] ppid=24886 vsize=870904 CPUtime=27.41
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50281 0 1 0 2719 22 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4096369139 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870904 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 15 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870904 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870904 CPUtime=2.76
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 276 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 31.86
Current children cumulated vsize (KiB) 876040

[startup+35.1064 s]
/proc/loadavg: 1.17 1.26 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870904 CPUtime=35.04
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91848 0 1 0 3469 35 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217726 88401 1788 10 0 214817 0
[pid=24899/tid=24900] ppid=24886 vsize=870904 CPUtime=30.61
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50281 0 1 0 3038 23 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4096368961 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870904 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 15 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870904 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870904 CPUtime=2.76
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 276 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 35.06
Current children cumulated vsize (KiB) 876040

[startup+38.3073 s]
/proc/loadavg: 1.17 1.26 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870904 CPUtime=38.24
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91848 0 1 0 3789 35 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217726 88401 1788 10 0 214817 0
[pid=24899/tid=24900] ppid=24886 vsize=870904 CPUtime=33.78
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50281 0 1 0 3355 23 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4096525605 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870904 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 16 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870904 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870904 CPUtime=2.79
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 279 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 38.26
Current children cumulated vsize (KiB) 876040

[startup+39.1076 s]
/proc/loadavg: 1.17 1.26 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=870904 CPUtime=39.04
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91848 0 1 0 3869 35 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217726 88401 1788 10 0 214817 0
[pid=24899/tid=24900] ppid=24886 vsize=870904 CPUtime=34.57
/proc/24899/task/24900/stat : 24900 (java) R 24886 24885 17511 34816 17511 4202560 50281 0 1 0 3434 23 0 0 25 0 11 0 46330961 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4096389608 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=870904 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 16 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=870904 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=870904 CPUtime=2.8
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 280 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24909] ppid=24886 vsize=870904 CPUtime=0
/proc/24899/task/24909/stat : 24909 (java) S 24886 24885 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 24 0 11 0 46331455 891805696 88401 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39.06
Current children cumulated vsize (KiB) 876040

[startup+39.9079 s]
/proc/loadavg: 1.16 1.26 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=871228 CPUtime=39.52
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91861 0 1 0 3916 36 0 0 25 0 11 0 46330961 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217807 88414 1789 10 0 214898 0
[pid=24899/tid=24900] ppid=24886 vsize=871228 CPUtime=34.99
/proc/24899/task/24900/stat : 24900 (java) S 24886 24885 17511 34816 17511 4202560 50289 0 1 0 3475 24 0 0 25 0 11 0 46330961 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=871228 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 16 0 11 0 46330963 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=871228 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=871228 CPUtime=2.86
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 286 0 0 0 16 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39.54
Current children cumulated vsize (KiB) 876364

[startup+40.1099 s]
/proc/loadavg: 1.16 1.26 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=871228 CPUtime=39.53
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91861 0 1 0 3917 36 0 0 25 0 11 0 46330961 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217807 88414 1789 10 0 214898 0
[pid=24899/tid=24900] ppid=24886 vsize=871228 CPUtime=34.99
/proc/24899/task/24900/stat : 24900 (java) S 24886 24885 17511 34816 17511 4202560 50289 0 1 0 3475 24 0 0 25 0 11 0 46330961 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=871228 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 16 0 11 0 46330963 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=871228 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=871228 CPUtime=2.86
/proc/24899/task/24906/stat : 24906 (java) S 24886 24885 17511 34816 17511 4202560 2357 0 0 0 286 0 0 0 16 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 892137472 88414 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39.55
Current children cumulated vsize (KiB) 876364

[startup+40.2099 s]
/proc/loadavg: 1.16 1.26 1.20 2/46 24909
/proc/meminfo: memFree=74020/1048576 swapFree=0/0
[pid=24885] ppid=24884 vsize=2564 CPUtime=0
/proc/24885/stat : 24885 (p2cudf-user-1.1) S 24884 24885 17511 34816 17511 4202496 373 0 0 0 0 0 0 0 25 0 1 0 46330957 2625536 269 1283457024 134512640 135304128 4290334096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24885/statm: 641 269 230 194 0 28 0
[pid=24886] ppid=24885 vsize=2572 CPUtime=0.02
/proc/24886/stat : 24886 (p2cudf) S 24885 24885 17511 34816 17511 4202496 391 3017 0 1 0 0 0 2 25 0 1 0 46330957 2633728 280 1283457024 134512640 135304128 4294547904 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24886/statm: 643 280 239 194 0 30 0
[pid=24899] ppid=24886 vsize=871228 CPUtime=39.63
/proc/24899/stat : 24899 (java) S 24886 24885 17511 34816 17511 4202496 91862 0 1 0 3925 38 0 0 25 0 11 0 46330961 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24899/statm: 217807 88415 1789 10 0 214898 0
[pid=24899/tid=24900] ppid=24886 vsize=871228 CPUtime=34.99
/proc/24899/task/24900/stat : 24900 (java) S 24886 24885 17511 34816 17511 4202560 50289 0 1 0 3475 24 0 0 25 0 11 0 46330961 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24901] ppid=24886 vsize=871228 CPUtime=1.26
/proc/24899/task/24901/stat : 24901 (java) S 24886 24885 17511 34816 17511 4202560 32787 0 0 0 116 10 0 0 16 0 11 0 46330963 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 0 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24902] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24902/stat : 24902 (java) S 24886 24885 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46330963 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24903] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24903/stat : 24903 (java) S 24886 24885 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46330963 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24904] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24904/stat : 24904 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24905] ppid=24886 vsize=871228 CPUtime=0.38
/proc/24899/task/24905/stat : 24905 (java) S 24886 24885 17511 34816 17511 4202560 5344 0 0 0 38 0 0 0 21 0 11 0 46330966 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24906] ppid=24886 vsize=871228 CPUtime=2.9
/proc/24899/task/24906/stat : 24906 (java) R 24886 24885 17511 34816 17511 4202560 2357 0 0 0 290 0 0 0 16 0 11 0 46330966 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4150136075 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24899/tid=24907] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24907/stat : 24907 (java) S 24886 24885 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46330966 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
[pid=24899/tid=24908] ppid=24886 vsize=871228 CPUtime=0
/proc/24899/task/24908/stat : 24908 (java) S 24886 24885 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46330966 892137472 88415 1283457024 134512640 134550932 4291968496 18446744073709551615 4294960130 0 4 0 16800975 18446612133395359680 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39.65
Current children cumulated vsize (KiB) 876364

Child status: 0
Real time (s): 40.303
CPU time (s): 39.7545
CPU user time (s): 39.3145
CPU system time (s): 0.440027
CPU usage (%): 98.639
Max. virtual memory (cumulated for all children) (KiB): 878332

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 39.3145
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= 95668
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= 1696
involuntary context switches= 2041

runsolver used 0 second user time and 0.020001 second system time

The end