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

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


[startup+0 s]
/proc/loadavg: 0.24 0.14 0.07 3/39 25675
/proc/meminfo: memFree=248332/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) R 25673 25674 25521 34816 25544 4202496 359 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2572 CPUtime=0
/proc/25675/stat : 25675 (p2cudf-user-1.1) R 25674 25674 25521 34816 25544 4202560 0 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 40 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/25675/statm: 643 40 0 194 0 29 0

[startup+0.105836 s]
/proc/loadavg: 0.24 0.14 0.07 3/39 25675
/proc/meminfo: memFree=248332/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+0.211956 s]
/proc/loadavg: 0.24 0.14 0.07 3/39 25675
/proc/meminfo: memFree=248332/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+0.311997 s]
/proc/loadavg: 0.24 0.14 0.07 3/39 25675
/proc/meminfo: memFree=248332/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+0.712103 s]
/proc/loadavg: 0.24 0.14 0.07 3/39 25675
/proc/meminfo: memFree=248332/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
Current children cumulated CPU time (s) 0.02
Current children cumulated vsize (KiB) 5152

[startup+1.5123 s]
/proc/loadavg: 0.24 0.14 0.07 4/50 25697
/proc/meminfo: memFree=198548/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=872348 CPUtime=1.46
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 17979 0 1 0 137 9 0 0 25 0 10 0 899822572 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218087 15724 1742 10 0 215177 0
[pid=25688/tid=25689] ppid=25675 vsize=872348 CPUtime=0.63
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 10455 0 1 0 58 5 0 0 25 0 10 0 899822573 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4095624782 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=872348 CPUtime=0
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 5 0 0 0 0 0 0 0 17 0 10 0 899822574 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=872348 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 25 0 10 0 899822575 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=872348 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 10 0 899822575 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=872348 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 10 0 899822578 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=872348 CPUtime=0.31
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 3018 0 0 0 28 3 0 0 22 0 10 0 899822578 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=872348 CPUtime=0.5
/proc/25688/task/25695/stat : 25695 (java) R 25675 25674 25521 34816 25544 4202560 3391 0 0 0 50 0 0 0 25 0 10 0 899822578 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4150436587 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=872348 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 10 0 899822578 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=872348 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 10 0 899822578 893284352 15724 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.48
Current children cumulated vsize (KiB) 877500

[startup+3.13273 s]
/proc/loadavg: 0.38 0.17 0.08 2/50 25697
/proc/meminfo: memFree=83732/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=871744 CPUtime=3.05
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 61541 0 1 0 284 21 0 0 25 0 10 0 899822572 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 217936 57737 1761 10 0 215026 0
[pid=25688/tid=25689] ppid=25675 vsize=871744 CPUtime=1.56
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50288 0 1 0 142 14 0 0 25 0 10 0 899822573 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=871744 CPUtime=0.04
/proc/25688/task/25690/stat : 25690 (java) R 25675 25674 25521 34816 25544 4202560 2067 0 0 0 3 1 0 0 15 0 10 0 899822574 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4149718301 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=871744 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 25 0 10 0 899822575 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=871744 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 10 0 899822575 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=871744 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 10 0 899822578 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=871744 CPUtime=0.65
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 4648 0 0 0 61 4 0 0 18 0 10 0 899822578 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=871744 CPUtime=0.76
/proc/25688/task/25695/stat : 25695 (java) R 25675 25674 25521 34816 25544 4202560 3428 0 0 0 76 0 0 0 19 0 10 0 899822578 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4150441237 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=871744 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 10 0 899822578 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=871744 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) R 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 10 0 899822578 892665856 57737 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.07
Current children cumulated vsize (KiB) 876896

[startup+6.30361 s]
/proc/loadavg: 0.38 0.17 0.08 2/51 25698
/proc/meminfo: memFree=7060/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=875248 CPUtime=6.2
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 76700 0 1 0 592 28 0 0 25 0 11 0 899822572 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218812 70729 1792 10 0 215902 0
[pid=25688/tid=25689] ppid=25675 vsize=875248 CPUtime=3.21
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50453 0 1 0 307 14 0 0 25 0 11 0 899822573 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4096704546 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=875248 CPUtime=0.33
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 13968 0 0 0 25 8 0 0 15 0 11 0 899822574 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=875248 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=875248 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=875248 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=875248 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=875248 CPUtime=1.47
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3536 0 0 0 146 1 0 0 15 0 11 0 899822578 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=875248 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=875248 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=875248 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 896253952 70729 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.22
Current children cumulated vsize (KiB) 880400

[startup+12.7154 s]
/proc/loadavg: 0.55 0.22 0.09 4/51 25698
/proc/meminfo: memFree=5632/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=12.61
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 85769 0 1 0 1228 33 0 0 25 0 11 0 899822572 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79497 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=9.18
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50454 0 1 0 904 14 0 0 25 0 11 0 899822573 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4096753594 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=0.71
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23030 0 0 0 59 12 0 0 16 0 11 0 899822574 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.51
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3542 0 0 0 150 1 0 0 15 0 11 0 899822578 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) R 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79497 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.63
Current children cumulated vsize (KiB) 879100

[startup+25.5086 s]
/proc/loadavg: 0.70 0.26 0.11 2/51 25698
/proc/meminfo: memFree=6016/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=25.4
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86053 0 1 0 2507 33 0 0 25 0 11 0 899822572 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79781 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=21.27
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50455 0 1 0 2113 14 0 0 25 0 11 0 899822573 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4096718860 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=1.26
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 114 12 0 0 16 0 11 0 899822574 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.66
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3555 0 0 0 165 1 0 0 15 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.42
Current children cumulated vsize (KiB) 879100

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

[startup+25.6086 s]
/proc/loadavg: 0.70 0.26 0.11 2/51 25698
/proc/meminfo: memFree=6016/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=25.5
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86053 0 1 0 2517 33 0 0 25 0 11 0 899822572 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79781 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=21.37
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50455 0 1 0 2123 14 0 0 25 0 11 0 899822573 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4096732046 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=1.26
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 114 12 0 0 16 0 11 0 899822574 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.66
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3555 0 0 0 165 1 0 0 15 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79781 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.52
Current children cumulated vsize (KiB) 879100

[startup+38.4119 s]
/proc/loadavg: 0.76 0.29 0.12 2/51 25698
/proc/meminfo: memFree=6016/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=38.28
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86056 0 1 0 3795 33 0 0 25 0 11 0 899822572 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79784 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=33.79
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50455 0 1 0 3365 14 0 0 25 0 11 0 899822573 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4096859676 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=1.59
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 147 12 0 0 15 0 11 0 899822574 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.69
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3558 0 0 0 168 1 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 38.3
Current children cumulated vsize (KiB) 879100

[startup+41.6127 s]
/proc/loadavg: 0.76 0.29 0.12 2/51 25698
/proc/meminfo: memFree=5892/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=41.47
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86056 0 1 0 4114 33 0 0 25 0 11 0 899822572 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79784 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=36.97
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50455 0 1 0 3683 14 0 0 25 0 11 0 899822573 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4096687184 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=1.59
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 147 12 0 0 15 0 11 0 899822574 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.71
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3558 0 0 0 170 1 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 41.49
Current children cumulated vsize (KiB) 879100

[startup+44.8135 s]
/proc/loadavg: 0.78 0.31 0.12 2/51 25698
/proc/meminfo: memFree=5892/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=44.67
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86056 0 1 0 4434 33 0 0 25 0 11 0 899822572 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79784 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=40.17
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50455 0 1 0 4003 14 0 0 25 0 11 0 899822573 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4096753394 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=1.59
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 147 12 0 0 16 0 11 0 899822574 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.71
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3558 0 0 0 170 1 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79784 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 44.69
Current children cumulated vsize (KiB) 879100

[startup+45.6138 s]
/proc/loadavg: 0.78 0.31 0.12 3/51 25698
/proc/meminfo: memFree=5892/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=873948 CPUtime=45.46
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86060 0 1 0 4513 33 0 0 25 0 11 0 899822572 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218487 79788 1793 10 0 215577 0
[pid=25688/tid=25689] ppid=25675 vsize=873948 CPUtime=40.68
/proc/25688/task/25689/stat : 25689 (java) R 25675 25674 25521 34816 25544 4202560 50455 0 1 0 4054 14 0 0 25 0 11 0 899822573 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4096704797 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=873948 CPUtime=1.85
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 173 12 0 0 16 0 11 0 899822574 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 11 0 899822575 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=873948 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 11 0 899822578 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=873948 CPUtime=1.73
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3562 0 0 0 172 1 0 0 16 0 11 0 899822578 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 11 0 899822578 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 11 0 899822578 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=873948 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 11 0 899823041 894922752 79788 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 45.48
Current children cumulated vsize (KiB) 879100

[startup+46.3048 s]
/proc/loadavg: 0.78 0.31 0.12 1/52 25699
/proc/meminfo: memFree=5884/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=874272 CPUtime=45.87
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86072 0 1 0 4554 33 0 0 25 0 12 0 899822572 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218568 79800 1793 10 0 215658 0
[pid=25688/tid=25689] ppid=25675 vsize=874272 CPUtime=41.07
/proc/25688/task/25689/stat : 25689 (java) S 25675 25674 25521 34816 25544 4202560 50462 0 1 0 4093 14 0 0 25 0 12 0 899822573 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=874272 CPUtime=1.85
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 173 12 0 0 16 0 12 0 899822574 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 12 0 899822575 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 12 0 899822575 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=874272 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=874272 CPUtime=1.75
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3563 0 0 0 174 1 0 0 15 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 12 0 899823041 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25699] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25699/stat : 25699 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 12 0 899827169 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 45.89
Current children cumulated vsize (KiB) 879424

[startup+46.5048 s]
/proc/loadavg: 0.78 0.31 0.12 1/52 25699
/proc/meminfo: memFree=5884/1048576 swapFree=2095372/2097144
[pid=25674] ppid=25673 vsize=2572 CPUtime=0
/proc/25674/stat : 25674 (p2cudf-user-1.1) S 25673 25674 25521 34816 25544 4202496 377 0 0 0 0 0 0 0 25 0 1 0 899822568 2633728 273 1283457024 134512640 135304128 4286956112 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25674/statm: 643 273 233 194 0 29 0
[pid=25675] ppid=25674 vsize=2580 CPUtime=0.02
/proc/25675/stat : 25675 (p2cudf) S 25674 25674 25521 34816 25544 4202496 394 2989 0 2 0 0 1 1 25 0 1 0 899822568 2641920 283 1283457024 134512640 135304128 4292257680 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25675/statm: 645 283 241 194 0 31 0
[pid=25688] ppid=25675 vsize=874272 CPUtime=45.87
/proc/25688/stat : 25688 (java) S 25675 25674 25521 34816 25544 4202496 86072 0 1 0 4554 33 0 0 25 0 12 0 899822572 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25688/statm: 218568 79800 1793 10 0 215658 0
[pid=25688/tid=25689] ppid=25675 vsize=874272 CPUtime=41.07
/proc/25688/task/25689/stat : 25689 (java) S 25675 25674 25521 34816 25544 4202560 50462 0 1 0 4093 14 0 0 25 0 12 0 899822573 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25690] ppid=25675 vsize=874272 CPUtime=1.85
/proc/25688/task/25690/stat : 25690 (java) S 25675 25674 25521 34816 25544 4202560 23300 0 0 0 173 12 0 0 15 0 12 0 899822574 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 0 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25691] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25691/stat : 25691 (java) S 25675 25674 25521 34816 25544 4202560 9 0 0 0 0 0 0 0 18 0 12 0 899822575 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25692] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25692/stat : 25692 (java) S 25675 25674 25521 34816 25544 4202560 6 0 0 0 0 0 0 0 18 0 12 0 899822575 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25693] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25693/stat : 25693 (java) S 25675 25674 25521 34816 25544 4202560 1 0 0 0 0 0 0 0 25 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25694] ppid=25675 vsize=874272 CPUtime=1.18
/proc/25688/task/25694/stat : 25694 (java) S 25675 25674 25521 34816 25544 4202560 7627 0 0 0 114 4 0 0 15 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25695] ppid=25675 vsize=874272 CPUtime=1.75
/proc/25688/task/25695/stat : 25695 (java) S 25675 25674 25521 34816 25544 4202560 3563 0 0 0 174 1 0 0 15 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25696] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25696/stat : 25696 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 25 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25697] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25697/stat : 25697 (java) S 25675 25674 25521 34816 25544 4202560 2 0 0 0 0 0 0 0 15 0 12 0 899822578 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25698] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25698/stat : 25698 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 12 0 899823041 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
[pid=25688/tid=25699] ppid=25675 vsize=874272 CPUtime=0
/proc/25688/task/25699/stat : 25699 (java) S 25675 25674 25521 34816 25544 4202560 4 0 0 0 0 0 0 0 25 0 12 0 899827169 895254528 79800 1283457024 134512640 134550932 4292406848 18446744073709551615 4294960130 0 4 0 16800975 18446612133077892032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 45.89
Current children cumulated vsize (KiB) 879424

Child status: 0
Real time (s): 46.6146
CPU time (s): 45.9789
CPU user time (s): 45.5988
CPU system time (s): 0.380023
CPU usage (%): 98.6363
Max. virtual memory (cumulated for all children) (KiB): 882244

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 45.5988
system time used= 0.380023
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 89857
page faults= 3
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 1643
involuntary context switches= 2158

runsolver used 0.008 second user time and 0.008 second system time

The end