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/58a4a468-38a5-11df-a561-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/debian-dudf//58a4a468-38a5-11df-a561-00163e7a6f5e.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/58a4a468-38a5-11df-a561-00163e7a6f5e.cudf.debian-dudf.result 

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


[startup+0 s]
/proc/loadavg: 0.85 1.14 1.16 3/46 22829
/proc/meminfo: memFree=880848/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) R 22827 22828 2059 34821 2220 4202496 360 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2576 CPUtime=0
/proc/22829/stat : 22829 (p2cudf-paranoid) R 22828 22828 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 41 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/22829/statm: 644 41 0 194 0 30 0

[startup+0.162778 s]
/proc/loadavg: 0.85 1.14 1.16 3/46 22829
/proc/meminfo: memFree=880848/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+0.212817 s]
/proc/loadavg: 0.85 1.14 1.16 3/46 22829
/proc/meminfo: memFree=880848/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+0.302821 s]
/proc/loadavg: 0.85 1.14 1.16 3/46 22829
/proc/meminfo: memFree=880848/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+0.706056 s]
/proc/loadavg: 0.85 1.14 1.16 3/46 22829
/proc/meminfo: memFree=880848/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5148

[startup+1.50622 s]
/proc/loadavg: 0.85 1.14 1.16 5/57 22851
/proc/meminfo: memFree=843608/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=869340 CPUtime=1.43
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 17515 0 1 0 136 7 0 0 25 0 10 0 876074570 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 217335 15634 1741 10 0 214425 0
[pid=22842/tid=22843] ppid=22829 vsize=869340 CPUtime=0.66
/proc/22842/task/22843/stat : 22843 (java) R 22829 22828 2059 34821 2220 4202560 10900 0 1 0 60 6 0 0 25 0 10 0 876074570 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4095219309 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876074572 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876074572 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876074572 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876074575 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=869340 CPUtime=0.29
/proc/22842/task/22848/stat : 22848 (java) R 22829 22828 2059 34821 2220 4202560 2626 0 0 0 29 0 0 0 25 0 10 0 876074575 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=869340 CPUtime=0.46
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2875 0 0 0 46 0 0 0 21 0 10 0 876074575 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876074575 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876074575 890204160 15634 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.45
Current children cumulated vsize (KiB) 874488

[startup+3.12532 s]
/proc/loadavg: 0.86 1.14 1.16 2/57 22851
/proc/meminfo: memFree=707384/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=869340 CPUtime=2.92
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 69879 0 1 0 266 26 0 0 25 0 10 0 876074570 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 217335 67998 1746 10 0 214425 0
[pid=22842/tid=22843] ppid=22829 vsize=869340 CPUtime=1.68
/proc/22842/task/22843/stat : 22843 (java) R 22829 22828 2059 34821 2220 4202560 50278 0 1 0 146 22 0 0 25 0 10 0 876074570 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=869340 CPUtime=0.27
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 12968 0 0 0 25 2 0 0 16 0 10 0 876074572 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 10 0 876074572 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 10 0 876074572 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876074575 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=869340 CPUtime=0.45
/proc/22842/task/22848/stat : 22848 (java) R 22829 22828 2059 34821 2220 4202560 2644 0 0 0 45 0 0 0 15 0 10 0 876074575 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4152875162 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=869340 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 10 0 876074575 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876074575 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=869340 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876074575 890204160 67998 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.94
Current children cumulated vsize (KiB) 874488

[startup+6.30593 s]
/proc/loadavg: 0.86 1.14 1.16 2/57 22851
/proc/meminfo: memFree=539648/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=872304 CPUtime=6.08
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 85898 0 1 0 578 30 0 0 25 0 11 0 876074570 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 218076 81705 1786 10 0 215166 0
[pid=22842/tid=22843] ppid=22829 vsize=872304 CPUtime=2.67
/proc/22842/task/22843/stat : 22843 (java) R 22829 22828 2059 34821 2220 4202560 50443 0 1 0 245 22 0 0 25 0 11 0 876074570 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=872304 CPUtime=0.57
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 25676 0 0 0 53 4 0 0 16 0 11 0 876074572 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=872304 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876074572 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=872304 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876074572 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=872304 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=872304 CPUtime=2.33
/proc/22842/task/22848/stat : 22848 (java) R 22829 22828 2059 34821 2220 4202560 5790 0 0 0 231 2 0 0 16 0 11 0 876074575 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4151221656 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=872304 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 11 0 876074575 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=872304 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=872304 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876074575 893239296 81705 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.1
Current children cumulated vsize (KiB) 877452

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

[startup+6.41592 s]
/proc/loadavg: 0.86 1.14 1.16 2/57 22851
/proc/meminfo: memFree=539648/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=871408 CPUtime=6.2
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 85906 0 1 0 590 30 0 0 25 0 11 0 876074570 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 217852 81521 1786 10 0 214942 0
[pid=22842/tid=22843] ppid=22829 vsize=871408 CPUtime=2.69
/proc/22842/task/22843/stat : 22843 (java) R 22829 22828 2059 34821 2220 4202560 50443 0 1 0 247 22 0 0 25 0 11 0 876074570 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=871408 CPUtime=0.57
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 25676 0 0 0 53 4 0 0 16 0 11 0 876074572 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=871408 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876074572 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=871408 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876074572 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=871408 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=871408 CPUtime=2.42
/proc/22842/task/22848/stat : 22848 (java) R 22829 22828 2059 34821 2220 4202560 5798 0 0 0 240 2 0 0 15 0 11 0 876074575 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4152019698 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=871408 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 11 0 876074575 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=871408 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=871408 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876074575 892321792 81521 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.22
Current children cumulated vsize (KiB) 876556

[startup+8.00625 s]
/proc/loadavg: 0.87 1.13 1.16 5/59 22860
/proc/meminfo: memFree=539384/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=872380 CPUtime=7.78
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 85956 0 1 0 748 30 0 0 25 0 11 0 876074570 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 218095 81571 1792 10 0 215185 0
[pid=22842/tid=22843] ppid=22829 vsize=872380 CPUtime=3.95
/proc/22842/task/22843/stat : 22843 (java) R 22829 22828 2059 34821 2220 4202560 50448 0 1 0 373 22 0 0 25 0 11 0 876074570 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4094900426 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=872380 CPUtime=0.57
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 25676 0 0 0 53 4 0 0 15 0 11 0 876074572 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876074572 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876074572 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=872380 CPUtime=2.75
/proc/22842/task/22848/stat : 22848 (java) S 22829 22828 2059 34821 2220 4202560 5827 0 0 0 273 2 0 0 15 0 11 0 876074575 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=872380 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 11 0 876074575 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876074575 893317120 81571 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.8
Current children cumulated vsize (KiB) 877528

[startup+8.70728 s]
/proc/loadavg: 0.87 1.13 1.16 1/58 22866
/proc/meminfo: memFree=539392/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=872380 CPUtime=8.1
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 85962 0 1 0 780 30 0 0 25 0 11 0 876074570 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 218095 81577 1792 10 0 215185 0
[pid=22842/tid=22843] ppid=22829 vsize=872380 CPUtime=4.18
/proc/22842/task/22843/stat : 22843 (java) S 22829 22828 2059 34821 2220 4202560 50454 0 1 0 396 22 0 0 25 0 11 0 876074570 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=872380 CPUtime=0.57
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 25676 0 0 0 53 4 0 0 15 0 11 0 876074572 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876074572 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876074572 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=872380 CPUtime=2.84
/proc/22842/task/22848/stat : 22848 (java) S 22829 22828 2059 34821 2220 4202560 5827 0 0 0 282 2 0 0 15 0 11 0 876074575 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=872380 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 11 0 876074575 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876074575 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22866] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22866/stat : 22866 (java) S 22829 22828 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 876075399 893317120 81577 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.12
Current children cumulated vsize (KiB) 877528

[startup+8.91532 s]
/proc/loadavg: 0.87 1.13 1.16 1/58 22866
/proc/meminfo: memFree=539392/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=872380 CPUtime=8.18
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 85964 0 1 0 786 32 0 0 25 0 11 0 876074570 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 218095 81579 1792 10 0 215185 0
[pid=22842/tid=22843] ppid=22829 vsize=872380 CPUtime=4.18
/proc/22842/task/22843/stat : 22843 (java) S 22829 22828 2059 34821 2220 4202560 50454 0 1 0 396 22 0 0 25 0 11 0 876074570 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=872380 CPUtime=0.57
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 25676 0 0 0 53 4 0 0 15 0 11 0 876074572 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876074572 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876074572 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=872380 CPUtime=2.85
/proc/22842/task/22848/stat : 22848 (java) S 22829 22828 2059 34821 2220 4202560 5827 0 0 0 283 2 0 0 15 0 11 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=872380 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 11 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22866] ppid=22829 vsize=872380 CPUtime=0.07
/proc/22842/task/22866/stat : 22866 (java) R 22829 22828 2059 34821 2220 4202560 2 0 0 0 5 2 0 0 20 0 11 0 876075399 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4094867022 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.2
Current children cumulated vsize (KiB) 877528

[startup+9.01052 s]
/proc/loadavg: 0.87 1.13 1.16 1/58 22866
/proc/meminfo: memFree=539392/1048576 swapFree=2078744/2097144
[pid=22828] ppid=22827 vsize=2576 CPUtime=0
/proc/22828/stat : 22828 (p2cudf-paranoid) S 22827 22828 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 876074566 2637824 274 1283457024 134512640 135304128 4289516720 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22828/statm: 644 274 233 194 0 30 0
[pid=22829] ppid=22828 vsize=2572 CPUtime=0.02
/proc/22829/stat : 22829 (p2cudf) S 22828 22828 2059 34821 2220 4202496 392 3000 0 1 0 0 2 0 25 0 1 0 876074566 2633728 282 1283457024 134512640 135304128 4288314272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/22829/statm: 643 282 241 194 0 29 0
[pid=22842] ppid=22829 vsize=872380 CPUtime=8.28
/proc/22842/stat : 22842 (java) S 22829 22828 2059 34821 2220 4202496 85964 0 1 0 792 36 0 0 25 0 10 0 876074570 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/22842/statm: 218095 81579 1792 10 0 215185 0
[pid=22842/tid=22843] ppid=22829 vsize=872380 CPUtime=4.18
/proc/22842/task/22843/stat : 22843 (java) S 22829 22828 2059 34821 2220 4202560 50454 0 1 0 396 22 0 0 19 0 10 0 876074570 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22844] ppid=22829 vsize=872380 CPUtime=0.57
/proc/22842/task/22844/stat : 22844 (java) S 22829 22828 2059 34821 2220 4202560 25676 0 0 0 53 4 0 0 15 0 10 0 876074572 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22845] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22845/stat : 22845 (java) S 22829 22828 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 10 0 876074572 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22846] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22846/stat : 22846 (java) S 22829 22828 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 10 0 876074572 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22847] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22847/stat : 22847 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22848] ppid=22829 vsize=872380 CPUtime=2.88
/proc/22842/task/22848/stat : 22848 (java) S 22829 22828 2059 34821 2220 4202560 5827 0 0 0 286 2 0 0 15 0 10 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22849] ppid=22829 vsize=872380 CPUtime=0.48
/proc/22842/task/22849/stat : 22849 (java) S 22829 22828 2059 34821 2220 4202560 2877 0 0 0 48 0 0 0 21 0 10 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22850] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22850/stat : 22850 (java) S 22829 22828 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
[pid=22842/tid=22851] ppid=22829 vsize=872380 CPUtime=0
/proc/22842/task/22851/stat : 22851 (java) S 22829 22828 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876074575 893317120 81579 1283457024 134512640 134550932 4290921648 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.3
Current children cumulated vsize (KiB) 877528

Child status: 0
Real time (s): 9.0642
CPU time (s): 8.33252
CPU user time (s): 7.9485
CPU system time (s): 0.384024
CPU usage (%): 91.9278
Max. virtual memory (cumulated for all children) (KiB): 879564

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 7.9485
system time used= 0.384024
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 89757
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= 1221
involuntary context switches= 1191

runsolver used 0.012 second user time and 0 second system time

The end