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/218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf.debian-dudf.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/debian-dudf//218091ce-4bcc-11df-bc4e-00163e7a6f5e.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/218091ce-4bcc-11df-bc4e-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.84 0.97 1.01 6/47 7603
/proc/meminfo: memFree=897344/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2576 CPUtime=0.01
/proc/7602/stat : 7602 (p2cudf) R 7601 7601 2059 34821 2220 4202496 350 0 0 0 1 0 0 0 25 0 1 0 874944770 2637824 275 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/7602/statm: 644 275 234 194 0 30 0
[pid=7603] ppid=7602 vsize=2576 CPUtime=0
/proc/7603/stat : 7603 (p2cudf) R 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 874944770 2637824 41 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/7603/statm: 644 41 0 194 0 30 0

[startup+0.202628 s]
/proc/loadavg: 0.84 0.97 1.01 6/47 7603
/proc/meminfo: memFree=897344/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5152

[startup+0.312644 s]
/proc/loadavg: 0.84 0.97 1.01 6/47 7603
/proc/meminfo: memFree=897344/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5152

[startup+0.408751 s]
/proc/loadavg: 0.84 0.97 1.01 6/47 7603
/proc/meminfo: memFree=897344/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5152

[startup+0.709078 s]
/proc/loadavg: 0.84 0.97 1.01 6/47 7603
/proc/meminfo: memFree=897344/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.03
Current children cumulated vsize (KiB) 5152

[startup+1.50924 s]
/proc/loadavg: 0.84 0.97 1.01 4/57 7624
/proc/meminfo: memFree=853160/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=867000 CPUtime=1.44
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 18955 0 1 0 138 6 0 0 25 0 10 0 874944773 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 216750 17822 1741 10 0 213840 0
[pid=7615/tid=7616] ppid=7602 vsize=867000 CPUtime=0.72
/proc/7615/task/7616/stat : 7616 (java) R 7602 7601 2059 34821 2220 4202560 13812 0 1 0 68 4 0 0 25 0 10 0 874944774 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 874944775 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 874944776 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 874944776 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874944778 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=867000 CPUtime=0.3
/proc/7615/task/7621/stat : 7621 (java) R 7602 7601 2059 34821 2220 4202560 2618 0 0 0 29 1 0 0 25 0 10 0 874944778 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4152418711 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=867000 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 10 0 874944778 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 10 0 874944779 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 10 0 874944779 887808000 17822 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 872152

[startup+3.10889 s]
/proc/loadavg: 0.84 0.97 1.01 2/57 7624
/proc/meminfo: memFree=697028/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=867000 CPUtime=2.81
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 68439 0 1 0 249 32 0 0 25 0 10 0 874944773 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 216750 67306 1746 10 0 213840 0
[pid=7615/tid=7616] ppid=7602 vsize=867000 CPUtime=1.67
/proc/7615/task/7616/stat : 7616 (java) R 7602 7601 2059 34821 2220 4202560 50290 0 1 0 142 25 0 0 25 0 10 0 874944774 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4095741699 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=867000 CPUtime=0.27
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 12987 0 0 0 22 5 0 0 16 0 10 0 874944775 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 10 0 874944776 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 10 0 874944776 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874944778 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=867000 CPUtime=0.45
/proc/7615/task/7621/stat : 7621 (java) S 7602 7601 2059 34821 2220 4202560 2639 0 0 0 44 1 0 0 15 0 10 0 874944778 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=867000 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 10 0 874944778 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 10 0 874944779 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=867000 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 10 0 874944779 887808000 67306 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.84
Current children cumulated vsize (KiB) 872152

[startup+6.31009 s]
/proc/loadavg: 0.93 0.98 1.02 3/57 7624
/proc/meminfo: memFree=554336/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=872832 CPUtime=6.02
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 85047 0 1 0 562 40 0 0 25 0 11 0 874944773 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 218208 82042 1786 10 0 215298 0
[pid=7615/tid=7616] ppid=7602 vsize=872832 CPUtime=2.77
/proc/7615/task/7616/stat : 7616 (java) R 7602 7601 2059 34821 2220 4202560 50455 0 1 0 249 28 0 0 25 0 11 0 874944774 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=872832 CPUtime=0.59
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 25854 0 0 0 51 8 0 0 16 0 11 0 874944775 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 874944776 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 874944776 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874944778 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=872832 CPUtime=2.23
/proc/7615/task/7621/stat : 7621 (java) R 7602 7601 2059 34821 2220 4202560 6211 0 0 0 221 2 0 0 16 0 11 0 874944778 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4151573787 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=872832 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 11 0 874944778 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874944779 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 11 0 874944779 893779968 82042 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.05
Current children cumulated vsize (KiB) 877984

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

[startup+7.91059 s]
/proc/loadavg: 0.93 0.98 1.02 2/58 7628
/proc/meminfo: memFree=553832/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=872832 CPUtime=7.62
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 85080 0 1 0 721 41 0 0 25 0 11 0 874944773 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 218208 82075 1792 10 0 215298 0
[pid=7615/tid=7616] ppid=7602 vsize=872832 CPUtime=4.11
/proc/7615/task/7616/stat : 7616 (java) R 7602 7601 2059 34821 2220 4202560 50456 0 1 0 382 29 0 0 25 0 11 0 874944774 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4096316195 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=872832 CPUtime=0.59
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 25854 0 0 0 51 8 0 0 16 0 11 0 874944775 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 874944776 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 874944776 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874944778 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=872832 CPUtime=2.5
/proc/7615/task/7621/stat : 7621 (java) S 7602 7601 2059 34821 2220 4202560 6243 0 0 0 248 2 0 0 15 0 11 0 874944778 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=872832 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 11 0 874944778 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874944779 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 11 0 874944779 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7628] ppid=7602 vsize=872832 CPUtime=0
/proc/7615/task/7628/stat : 7628 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874945520 893779968 82075 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 7.65
Current children cumulated vsize (KiB) 877984

[startup+8.7008 s]
/proc/loadavg: 0.93 0.98 1.02 3/58 7632
/proc/meminfo: memFree=553708/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=873480 CPUtime=8.4
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 85094 0 1 0 799 41 0 0 25 0 11 0 874944773 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 218370 82089 1792 10 0 215460 0
[pid=7615/tid=7616] ppid=7602 vsize=873480 CPUtime=4.79
/proc/7615/task/7616/stat : 7616 (java) R 7602 7601 2059 34821 2220 4202560 50459 0 1 0 450 29 0 0 25 0 11 0 874944774 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4096325005 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=873480 CPUtime=0.59
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 25854 0 0 0 51 8 0 0 16 0 11 0 874944775 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=873480 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 874944776 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=873480 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 874944776 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=873480 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874944778 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=873480 CPUtime=2.6
/proc/7615/task/7621/stat : 7621 (java) S 7602 7601 2059 34821 2220 4202560 6246 0 0 0 258 2 0 0 16 0 11 0 874944778 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=873480 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 11 0 874944778 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=873480 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874944779 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=873480 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 11 0 874944779 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7632] ppid=7602 vsize=873480 CPUtime=0
/proc/7615/task/7632/stat : 7632 (java) R 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874945632 894443520 82089 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.43
Current children cumulated vsize (KiB) 878632

[startup+9.50719 s]
/proc/loadavg: 0.93 0.98 1.02 3/58 7632
/proc/meminfo: memFree=553708/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=873804 CPUtime=8.93
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 90172 0 1 0 852 41 0 0 25 0 11 0 874944773 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 218451 87167 1792 10 0 215541 0
[pid=7615/tid=7616] ppid=7602 vsize=873804 CPUtime=5.08
/proc/7615/task/7616/stat : 7616 (java) S 7602 7601 2059 34821 2220 4202560 50466 0 1 0 479 29 0 0 25 0 11 0 874944774 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=873804 CPUtime=0.8
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 30921 0 0 0 72 8 0 0 16 0 11 0 874944775 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 874944776 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 17 0 11 0 874944776 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874944778 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=873804 CPUtime=2.62
/proc/7615/task/7621/stat : 7621 (java) S 7602 7601 2059 34821 2220 4202560 6246 0 0 0 260 2 0 0 15 0 11 0 874944778 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=873804 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 11 0 874944778 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874944779 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 11 0 874944779 894775296 87167 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 8.96
Current children cumulated vsize (KiB) 878956

[startup+9.90806 s]
/proc/loadavg: 1.26 1.05 1.04 2/58 7638
/proc/meminfo: memFree=533248/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=873804 CPUtime=9.05
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 90174 0 1 0 862 43 0 0 25 0 11 0 874944773 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 218451 87169 1792 10 0 215541 0
[pid=7615/tid=7616] ppid=7602 vsize=873804 CPUtime=5.08
/proc/7615/task/7616/stat : 7616 (java) S 7602 7601 2059 34821 2220 4202560 50466 0 1 0 479 29 0 0 25 0 11 0 874944774 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=873804 CPUtime=0.8
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 30921 0 0 0 72 8 0 0 16 0 11 0 874944775 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 874944776 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 17 0 11 0 874944776 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 874944778 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=873804 CPUtime=2.66
/proc/7615/task/7621/stat : 7621 (java) R 7602 7601 2059 34821 2220 4202560 6246 0 0 0 264 2 0 0 16 0 11 0 874944778 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4148901309 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=873804 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 11 0 874944778 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 874944779 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 11 0 874944779 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7638] ppid=7602 vsize=873804 CPUtime=0.08
/proc/7615/task/7638/stat : 7638 (java) R 7602 7601 2059 34821 2220 4202560 6 0 0 0 6 2 0 0 20 0 11 0 874945693 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 9.08
Current children cumulated vsize (KiB) 878956

[startup+10.0032 s]
/proc/loadavg: 1.26 1.05 1.04 2/58 7638
/proc/meminfo: memFree=533248/1048576 swapFree=2078760/2097144
[pid=7601] ppid=7600 vsize=2572 CPUtime=0
/proc/7601/stat : 7601 (p2cudf-paranoid) S 7600 7601 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 25 0 1 0 874944769 2633728 274 1283457024 134512640 135304128 4289115216 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7601/statm: 643 274 233 194 0 29 0
[pid=7602] ppid=7601 vsize=2580 CPUtime=0.03
/proc/7602/stat : 7602 (p2cudf) S 7601 7601 2059 34821 2220 4202496 394 2994 0 1 1 0 2 0 25 0 1 0 874944770 2641920 283 1283457024 134512640 135304128 4289074272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/7602/statm: 645 283 241 194 0 31 0
[pid=7615] ppid=7602 vsize=873804 CPUtime=9.12
/proc/7615/stat : 7615 (java) S 7602 7601 2059 34821 2220 4202496 90174 0 1 0 866 46 0 0 25 0 10 0 874944773 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/7615/statm: 218451 87169 1792 10 0 215541 0
[pid=7615/tid=7616] ppid=7602 vsize=873804 CPUtime=5.08
/proc/7615/task/7616/stat : 7616 (java) S 7602 7601 2059 34821 2220 4202560 50466 0 1 0 479 29 0 0 17 0 10 0 874944774 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7617] ppid=7602 vsize=873804 CPUtime=0.8
/proc/7615/task/7617/stat : 7617 (java) S 7602 7601 2059 34821 2220 4202560 30921 0 0 0 72 8 0 0 16 0 10 0 874944775 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 0 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7618] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7618/stat : 7618 (java) S 7602 7601 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 10 0 874944776 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7619] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7619/stat : 7619 (java) S 7602 7601 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 17 0 10 0 874944776 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7620] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7620/stat : 7620 (java) S 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 874944778 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7621] ppid=7602 vsize=873804 CPUtime=2.68
/proc/7615/task/7621/stat : 7621 (java) S 7602 7601 2059 34821 2220 4202560 6246 0 0 0 266 2 0 0 15 0 10 0 874944778 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7622] ppid=7602 vsize=873804 CPUtime=0.4
/proc/7615/task/7622/stat : 7622 (java) S 7602 7601 2059 34821 2220 4202560 1410 0 0 0 40 0 0 0 20 0 10 0 874944778 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7623] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7623/stat : 7623 (java) S 7602 7601 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 10 0 874944779 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 18446612133395920000 0 0 -1 0 0 0 0
[pid=7615/tid=7624] ppid=7602 vsize=873804 CPUtime=0
/proc/7615/task/7624/stat : 7624 (java) R 7602 7601 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 15 0 10 0 874944779 894775296 87169 1283457024 134512640 134550932 4287279424 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 9.15
Current children cumulated vsize (KiB) 878956

Child status: 0
Real time (s): 10.0307
CPU time (s): 9.18857
CPU user time (s): 8.70054
CPU system time (s): 0.48803
CPU usage (%): 91.6041
Max. virtual memory (cumulated for all children) (KiB): 878956

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 8.70054
system time used= 0.48803
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 93962
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= 1330
involuntary context switches= 1308

runsolver used 0 second user time and 0 second system time

The end