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/201012061533/p2cudf-paranoid-1.9/rand915.sarge-etch-lenny-squeeze.cudf.sarge-etch-lenny-squeeze.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/icse/sarge-etch-lenny-squeeze/rand915.sarge-etch-lenny-squeeze.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/rand915.sarge-etch-lenny-squeeze.cudf.sarge-etch-lenny-squeeze.result 

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


[startup+0 s]
/proc/loadavg: 1.31 1.27 1.20 5/46 24102
/proc/meminfo: memFree=623704/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) R 24100 24101 2059 34821 2220 4202496 358 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2572 CPUtime=0
/proc/24102/stat : 24102 (p2cudf-paranoid) R 24101 24101 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 39 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/24102/statm: 643 39 0 194 0 29 0

[startup+0.117532 s]
/proc/loadavg: 1.31 1.27 1.20 5/46 24102
/proc/meminfo: memFree=623704/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.207561 s]
/proc/loadavg: 1.31 1.27 1.20 5/46 24102
/proc/meminfo: memFree=623704/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.307584 s]
/proc/loadavg: 1.31 1.27 1.20 5/46 24102
/proc/meminfo: memFree=623704/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+0.707668 s]
/proc/loadavg: 1.31 1.27 1.20 5/46 24102
/proc/meminfo: memFree=623704/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5148

[startup+1.50788 s]
/proc/loadavg: 1.31 1.27 1.20 4/57 24124
/proc/meminfo: memFree=584608/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=874672 CPUtime=1.47
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 19409 0 1 0 136 11 0 0 25 0 10 0 876148030 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 218668 16665 1732 10 0 215758 0
[pid=24115/tid=24116] ppid=24102 vsize=874672 CPUtime=0.79
/proc/24115/task/24116/stat : 24116 (java) R 24102 24101 2059 34821 2220 4202560 11102 0 1 0 72 7 0 0 25 0 10 0 876148031 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4095649367 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=874672 CPUtime=0
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876148033 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=874672 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876148033 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=874672 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876148033 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=874672 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876148036 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=874672 CPUtime=0.38
/proc/24115/task/24121/stat : 24121 (java) R 24102 24101 2059 34821 2220 4202560 6202 0 0 0 34 4 0 0 23 0 10 0 876148036 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4150463428 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=874672 CPUtime=0.28
/proc/24115/task/24122/stat : 24122 (java) R 24102 24101 2059 34821 2220 4202560 988 0 0 0 28 0 0 0 25 0 10 0 876148036 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4152088639 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=874672 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876148036 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=874672 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876148036 895664128 16665 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 879820

[startup+3.10811 s]
/proc/loadavg: 1.31 1.27 1.20 4/57 24124
/proc/meminfo: memFree=515276/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=896968 CPUtime=3.07
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 52504 0 1 0 282 25 0 0 25 0 10 0 876148030 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 224242 45432 1732 10 0 221332 0
[pid=24115/tid=24116] ppid=24102 vsize=896968 CPUtime=1.39
/proc/24115/task/24116/stat : 24116 (java) R 24102 24101 2059 34821 2220 4202560 33988 0 1 0 122 17 0 0 25 0 10 0 876148031 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4095996859 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=896968 CPUtime=0
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876148033 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=896968 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876148033 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=896968 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876148033 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=896968 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876148036 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=896968 CPUtime=0.89
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 11754 0 0 0 84 5 0 0 24 0 10 0 876148036 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=896968 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 10 0 876148036 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=896968 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876148036 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=896968 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876148036 918495232 45432 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 902116

[startup+6.31636 s]
/proc/loadavg: 1.52 1.32 1.21 2/57 24124
/proc/meminfo: memFree=306692/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=897488 CPUtime=6.18
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 99374 0 1 0 576 42 0 0 25 0 11 0 876148030 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 224372 89881 1771 10 0 221462 0
[pid=24115/tid=24116] ppid=24102 vsize=897488 CPUtime=2.64
/proc/24115/task/24116/stat : 24116 (java) R 24102 24101 2059 34821 2220 4202560 50444 0 1 0 243 21 0 0 25 0 11 0 876148031 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=897488 CPUtime=0.64
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 27716 0 0 0 54 10 0 0 16 0 11 0 876148033 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876148033 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876148033 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876148036 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=897488 CPUtime=2.12
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 14453 0 0 0 204 8 0 0 15 0 11 0 876148036 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=897488 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 11 0 876148036 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876148036 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876148036 919027712 89881 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.21
Current children cumulated vsize (KiB) 902636

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

[startup+6.40031 s]
/proc/loadavg: 1.52 1.32 1.21 2/57 24124
/proc/meminfo: memFree=306692/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=897488 CPUtime=6.27
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 99386 0 1 0 585 42 0 0 25 0 11 0 876148030 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 224372 89893 1773 10 0 221462 0
[pid=24115/tid=24116] ppid=24102 vsize=897488 CPUtime=2.66
/proc/24115/task/24116/stat : 24116 (java) R 24102 24101 2059 34821 2220 4202560 50445 0 1 0 245 21 0 0 25 0 11 0 876148031 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4096796496 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=897488 CPUtime=0.64
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 27716 0 0 0 54 10 0 0 16 0 11 0 876148033 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876148033 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876148033 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876148036 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=897488 CPUtime=2.19
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 14464 0 0 0 211 8 0 0 16 0 11 0 876148036 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=897488 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 11 0 876148036 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876148036 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=897488 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876148036 919027712 89893 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 902636

[startup+7.21369 s]
/proc/loadavg: 1.52 1.32 1.21 3/58 24125
/proc/meminfo: memFree=254604/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=893904 CPUtime=7.08
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 99475 0 1 0 666 42 0 0 25 0 13 0 876148030 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 223476 88956 1790 10 0 220566 0
[pid=24115/tid=24116] ppid=24102 vsize=893904 CPUtime=3.13
/proc/24115/task/24116/stat : 24116 (java) R 24102 24101 2059 34821 2220 4202560 50452 0 1 0 292 21 0 0 25 0 13 0 876148031 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=893904 CPUtime=0.64
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 27716 0 0 0 54 10 0 0 16 0 13 0 876148033 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=893904 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 13 0 876148033 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=893904 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 13 0 876148033 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=893904 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 13 0 876148036 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=893904 CPUtime=2.52
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 14539 0 0 0 244 8 0 0 15 0 13 0 876148036 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=893904 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 13 0 876148036 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=893904 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 13 0 876148036 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=893904 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 13 0 876148036 915357696 88956 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.11
Current children cumulated vsize (KiB) 899052

[startup+7.60597 s]
/proc/loadavg: 1.52 1.32 1.21 3/58 24125
/proc/meminfo: memFree=254604/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=894228 CPUtime=7.15
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 99492 0 1 0 673 42 0 0 25 0 11 0 876148030 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 223557 88973 1790 10 0 220647 0
[pid=24115/tid=24116] ppid=24102 vsize=894228 CPUtime=3.18
/proc/24115/task/24116/stat : 24116 (java) S 24102 24101 2059 34821 2220 4202560 50460 0 1 0 297 21 0 0 25 0 11 0 876148031 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=894228 CPUtime=0.64
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 27716 0 0 0 54 10 0 0 16 0 11 0 876148033 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876148033 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876148033 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876148036 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=894228 CPUtime=2.55
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 14540 0 0 0 247 8 0 0 15 0 11 0 876148036 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=894228 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 11 0 876148036 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876148036 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876148036 915689472 88973 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.18
Current children cumulated vsize (KiB) 899376

[startup+7.81203 s]
/proc/loadavg: 1.40 1.30 1.21 1/58 24130
/proc/meminfo: memFree=254604/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=894228 CPUtime=7.18
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 99495 0 1 0 675 43 0 0 25 0 11 0 876148030 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 223557 88976 1790 10 0 220647 0
[pid=24115/tid=24116] ppid=24102 vsize=894228 CPUtime=3.18
/proc/24115/task/24116/stat : 24116 (java) S 24102 24101 2059 34821 2220 4202560 50460 0 1 0 297 21 0 0 25 0 11 0 876148031 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=894228 CPUtime=0.64
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 27716 0 0 0 54 10 0 0 16 0 11 0 876148033 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876148033 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876148033 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876148036 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=894228 CPUtime=2.56
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 14543 0 0 0 248 8 0 0 15 0 11 0 876148036 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=894228 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 11 0 876148036 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876148036 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) R 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876148036 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=24115/tid=24130] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24130/stat : 24130 (java) R 24102 24101 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 20 0 11 0 876148755 915689472 88976 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.21
Current children cumulated vsize (KiB) 899376

[startup+7.90199 s]
/proc/loadavg: 1.40 1.30 1.21 1/58 24130
/proc/meminfo: memFree=254604/1048576 swapFree=2078744/2097144
[pid=24101] ppid=24100 vsize=2572 CPUtime=0
/proc/24101/stat : 24101 (p2cudf-paranoid) S 24100 24101 2059 34821 2220 4202496 375 0 0 0 0 0 0 0 25 0 1 0 876148027 2633728 272 1283457024 134512640 135304128 4290895344 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24101/statm: 643 272 233 194 0 29 0
[pid=24102] ppid=24101 vsize=2576 CPUtime=0.03
/proc/24102/stat : 24102 (p2cudf) S 24101 24101 2059 34821 2220 4202496 394 2999 0 1 0 0 1 2 25 0 1 0 876148027 2637824 283 1283457024 134512640 135304128 4286773776 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/24102/statm: 644 283 241 194 0 30 0
[pid=24115] ppid=24102 vsize=894228 CPUtime=7.23
/proc/24115/stat : 24115 (java) S 24102 24101 2059 34821 2220 4202496 99497 0 1 0 678 45 0 0 25 0 10 0 876148030 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/24115/statm: 223557 88978 1790 10 0 220647 0
[pid=24115/tid=24116] ppid=24102 vsize=894228 CPUtime=3.18
/proc/24115/task/24116/stat : 24116 (java) S 24102 24101 2059 34821 2220 4202560 50460 0 1 0 297 21 0 0 20 0 10 0 876148031 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24117] ppid=24102 vsize=894228 CPUtime=0.64
/proc/24115/task/24117/stat : 24117 (java) S 24102 24101 2059 34821 2220 4202560 27716 0 0 0 54 10 0 0 16 0 10 0 876148033 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 0 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24118] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24118/stat : 24118 (java) S 24102 24101 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 10 0 876148033 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24119] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24119/stat : 24119 (java) S 24102 24101 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 10 0 876148033 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24120] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24120/stat : 24120 (java) S 24102 24101 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876148036 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24121] ppid=24102 vsize=894228 CPUtime=2.59
/proc/24115/task/24121/stat : 24121 (java) S 24102 24101 2059 34821 2220 4202560 14544 0 0 0 251 8 0 0 16 0 10 0 876148036 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24122] ppid=24102 vsize=894228 CPUtime=0.76
/proc/24115/task/24122/stat : 24122 (java) S 24102 24101 2059 34821 2220 4202560 5645 0 0 0 74 2 0 0 25 0 10 0 876148036 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24123] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24123/stat : 24123 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876148036 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
[pid=24115/tid=24124] ppid=24102 vsize=894228 CPUtime=0
/proc/24115/task/24124/stat : 24124 (java) S 24102 24101 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876148036 915689472 88978 1283457024 134512640 134550932 4287656336 18446744073709551615 4294960130 0 4 0 16800975 18446612133393159168 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.26
Current children cumulated vsize (KiB) 899376

Child status: 0
Real time (s): 7.95054
CPU time (s): 7.29646
CPU user time (s): 6.80042
CPU system time (s): 0.496031
CPU usage (%): 91.7731
Max. virtual memory (cumulated for all children) (KiB): 906784

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 6.80042
system time used= 0.496031
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 103290
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= 835
involuntary context switches= 887

runsolver used 0 second user time and 0 second system time

The end