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/201108311143/gj-user-solver-1.0/412959c6-e965-11de-8ebf-00163e6585dd.cudf.dudf-real.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/dudf-real//412959c6-e965-11de-8ebf-00163e6585dd.cudf /home/misc2010/tmp/201108311143/gj-user-solver-1.0/412959c6-e965-11de-8ebf-00163e6585dd.cudf.dudf-real.result -sum(installedsize) 

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


[startup+0 s]
/proc/loadavg: 1.01 1.03 1.00 4/39 25874
/proc/meminfo: memFree=702740/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=11044 CPUtime=0.01
/proc/25874/stat : 25874 (java) R 25873 25873 19908 34817 19908 4202496 767 0 0 0 0 1 0 0 25 0 1 0 63721561 11309056 504 1283457024 134512640 134550932 4293715968 18446744073709551615 4160030434 0 0 0 0 0 0 0 17 0 0 0 0
/proc/25874/statm: 2761 504 189 10 0 1103 0

[startup+0.205771 s]
/proc/loadavg: 1.01 1.03 1.00 4/39 25874
/proc/meminfo: memFree=702740/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=409924 CPUtime=0.2
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 4058 0 1 0 18 2 0 0 25 0 9 0 63721561 419762176 3490 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102481 3490 1908 10 0 96629 0
Current children cumulated CPU time (s) 0.2
Current children cumulated vsize (KiB) 412496

[startup+0.305817 s]
/proc/loadavg: 1.01 1.03 1.00 4/39 25874
/proc/meminfo: memFree=702740/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=410476 CPUtime=0.3
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 4426 0 1 0 28 2 0 0 25 0 9 0 63721561 420327424 3858 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102619 3858 1928 10 0 96767 0
Current children cumulated CPU time (s) 0.3
Current children cumulated vsize (KiB) 413048

[startup+0.405813 s]
/proc/loadavg: 1.01 1.03 1.00 4/39 25874
/proc/meminfo: memFree=702740/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=410476 CPUtime=0.4
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 6152 0 1 0 37 3 0 0 25 0 9 0 63721561 420327424 5583 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102619 5583 1940 10 0 96767 0
Current children cumulated CPU time (s) 0.4
Current children cumulated vsize (KiB) 413048

[startup+0.705911 s]
/proc/loadavg: 1.01 1.03 1.00 4/39 25874
/proc/meminfo: memFree=702740/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=410624 CPUtime=0.7
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 12031 0 1 0 65 5 0 0 25 0 9 0 63721561 420478976 11280 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102656 11280 2895 10 0 96804 0
Current children cumulated CPU time (s) 0.7
Current children cumulated vsize (KiB) 413196

[startup+1.5061 s]
/proc/loadavg: 1.01 1.03 1.00 2/48 25884
/proc/meminfo: memFree=644564/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=410756 CPUtime=1.5
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 25765 0 1 0 142 8 0 0 25 0 9 0 63721561 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102689 25014 2895 10 0 96837 0
[pid=25874/tid=25876] ppid=25873 vsize=410756 CPUtime=0.84
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 6728 0 1 0 80 4 0 0 25 0 9 0 63721562 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=410756 CPUtime=0.6
/proc/25874/task/25877/stat : 25877 (java) R 25873 25873 19908 34817 19908 4202560 17650 0 0 0 57 3 0 0 18 0 9 0 63721562 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4151206814 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=410756 CPUtime=0
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 0 0 0 0 20 0 9 0 63721563 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=410756 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 20 0 9 0 63721563 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=410756 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 9 0 63721564 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=410756 CPUtime=0.04
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 447 0 0 0 4 0 0 0 16 0 9 0 63721564 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=410756 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 9 0 63721564 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=410756 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 9 0 63721564 420614144 25014 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 413328

[startup+3.1065 s]
/proc/loadavg: 1.01 1.03 1.00 4/49 25885
/proc/meminfo: memFree=570280/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=2.88
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 38760 0 1 0 268 20 0 0 25 0 10 0 63721561 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 37929 2917 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=1.64
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 148 16 0 0 25 0 10 0 63721562 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4115399399 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=1.09
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 27539 0 0 0 106 3 0 0 16 0 10 0 63721562 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 577 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 37929 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.88
Current children cumulated vsize (KiB) 413812

[startup+6.30727 s]
/proc/loadavg: 1.01 1.03 1.00 2/49 25885
/proc/meminfo: memFree=556392/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=5.39
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 38909 0 1 0 492 47 0 0 25 0 10 0 63721561 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 38078 2917 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=4.11
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 368 43 0 0 25 0 10 0 63721562 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4153118808 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=1.11
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 27685 0 0 0 108 3 0 0 16 0 10 0 63721562 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 0 0 0 0 15 0 10 0 63721563 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 580 0 0 0 14 0 0 0 15 0 10 0 63721564 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 38078 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.39
Current children cumulated vsize (KiB) 413812

[startup+12.7111 s]
/proc/loadavg: 1.01 1.03 1.00 1/49 25886
/proc/meminfo: memFree=541016/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=10.1
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 39402 0 1 0 888 122 0 0 25 0 10 0 63721561 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 38571 2917 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=8.73
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 757 116 0 0 25 0 10 0 63721562 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4158940239 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=1.18
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 28178 0 0 0 114 4 0 0 16 0 10 0 63721562 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.02
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 2 0 0 0 15 0 10 0 63721563 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 580 0 0 0 14 0 0 0 15 0 10 0 63721564 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) R 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 38571 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.1
Current children cumulated vsize (KiB) 413812
heavy processes:

[startup+25.5158 s]
/proc/loadavg: 1.15 1.06 1.01 2/49 25887
/proc/meminfo: memFree=513240/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=19.82
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 39998 0 1 0 1715 267 0 0 25 0 10 0 63721561 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 39167 2917 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=18.3
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 1570 260 0 0 25 0 10 0 63721562 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4152574155 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=1.32
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 28774 0 0 0 127 5 0 0 16 0 10 0 63721562 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.03
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 3 0 0 0 15 0 10 0 63721563 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 580 0 0 0 14 0 0 0 15 0 10 0 63721564 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 39167 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 19.82
Current children cumulated vsize (KiB) 413812
heavy processes:

[startup+51.1052 s]
/proc/loadavg: 1.16 1.07 1.01 2/48 25889
/proc/meminfo: memFree=454976/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=39
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 42265 0 1 0 3365 535 0 0 25 0 10 0 63721561 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 41434 2917 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=37.15
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 3190 525 0 0 25 0 10 0 63721562 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=1.65
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 31041 0 0 0 157 8 0 0 16 0 10 0 63721562 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.04
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 4 0 0 0 15 0 10 0 63721563 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 580 0 0 0 14 0 0 0 15 0 10 0 63721564 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 41434 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39
Current children cumulated vsize (KiB) 413812
heavy processes:

[startup+102.312 s]
/proc/loadavg: 1.21 1.10 1.02 2/48 25889
/proc/meminfo: memFree=351932/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=78.23
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43871 0 1 0 6751 1072 0 0 25 0 10 0 63721561 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42449 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=75.63
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 6501 1062 0 0 24 0 10 0 63721562 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4114778712 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=2.38
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 230 8 0 0 16 0 10 0 63721562 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.06
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 6 0 0 0 15 0 10 0 63721563 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 580 0 0 0 14 0 0 0 15 0 10 0 63721564 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42449 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 78.23
Current children cumulated vsize (KiB) 413812
heavy processes:

[startup+162.304 s]
/proc/loadavg: 1.20 1.11 1.03 2/47 25889
/proc/meminfo: memFree=233644/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=123.56
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43872 0 1 0 10635 1721 0 0 25 0 10 0 63721561 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42450 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=120.15
/proc/25874/task/25876/stat : 25876 (java) S 25873 25873 19908 34817 19908 4202560 9699 0 1 0 10306 1709 0 0 25 0 10 0 63721562 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446744071564271167 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=3.12
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 302 10 0 0 16 0 10 0 63721562 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.12
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 12 0 0 0 15 0 10 0 63721563 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 581 0 0 0 14 0 0 0 15 0 10 0 63721564 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42450 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 123.56
Current children cumulated vsize (KiB) 413812
heavy processes:

[startup+222.304 s]
/proc/loadavg: 1.29 1.15 1.04 1/47 25889
/proc/meminfo: memFree=115596/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=169.06
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 14548 2358 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=164.85
/proc/25874/task/25876/stat : 25876 (java) S 25873 25873 19908 34817 19908 4202560 9699 0 1 0 14140 2345 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446744071564271167 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=3.88
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 376 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.16
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 16 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 169.06
Current children cumulated vsize (KiB) 413812
heavy processes:

[startup+282.313 s]
/proc/loadavg: 1.26 1.16 1.05 2/47 25889
/proc/meminfo: memFree=5320/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=214.72
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 18474 2998 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=209.77
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 17992 2985 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4115602472 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=4.54
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 442 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.24
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 24 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 214.72
Current children cumulated vsize (KiB) 413812
heavy processes:

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

[startup+283.309 s]
/proc/loadavg: 1.26 1.16 1.05 2/47 25889
/proc/meminfo: memFree=6164/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=215.3
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 18520 3010 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=210.35
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 18038 2997 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4149554317 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=4.54
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 442 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.24
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 24 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 215.3
Current children cumulated vsize (KiB) 413812

[startup+284.11 s]
/proc/loadavg: 1.26 1.16 1.05 2/47 25889
/proc/meminfo: memFree=5316/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=215.93
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 18571 3022 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=210.96
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 18088 3008 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=4.54
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 442 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.24
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 24 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 215.93
Current children cumulated vsize (KiB) 413812

[startup+284.51 s]
/proc/loadavg: 1.26 1.16 1.05 2/47 25889
/proc/meminfo: memFree=5316/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=216.25
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 18597 3028 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=211.28
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 18113 3015 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4153122248 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=4.56
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 444 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.24
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 24 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216.25
Current children cumulated vsize (KiB) 413812

[startup+284.91 s]
/proc/loadavg: 1.26 1.16 1.05 2/47 25889
/proc/meminfo: memFree=5316/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=216.55
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 18622 3033 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=211.57
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 18137 3020 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4115045650 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=4.56
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 444 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.25
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 25 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216.55
Current children cumulated vsize (KiB) 413812

[startup+285.01 s]
/proc/loadavg: 1.24 1.16 1.05 2/47 25889
/proc/meminfo: memFree=5540/1048576 swapFree=0/0
[pid=25873] ppid=25872 vsize=2572 CPUtime=0
/proc/25873/stat : 25873 (gj-user-solver-) S 25872 25873 19908 34817 19908 4202496 371 0 0 0 0 0 0 0 18 0 1 0 63721561 2633728 270 1283457024 134512640 135304128 4292202272 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25873/statm: 643 270 229 194 0 30 0
[pid=25874] ppid=25873 vsize=411240 CPUtime=216.65
/proc/25874/stat : 25874 (java) S 25873 25873 19908 34817 19908 4202496 43875 0 1 0 18629 3036 0 0 25 0 10 0 63721561 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25874/statm: 102810 42453 2912 10 0 96955 0
[pid=25874/tid=25876] ppid=25873 vsize=411240 CPUtime=211.67
/proc/25874/task/25876/stat : 25876 (java) R 25873 25873 19908 34817 19908 4202560 9699 0 1 0 18144 3023 0 0 25 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4152533862 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25874/tid=25877] ppid=25873 vsize=411240 CPUtime=4.56
/proc/25874/task/25877/stat : 25877 (java) S 25873 25873 19908 34817 19908 4202560 32647 0 0 0 444 12 0 0 16 0 10 0 63721562 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 0 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25878] ppid=25873 vsize=411240 CPUtime=0.25
/proc/25874/task/25878/stat : 25878 (java) S 25873 25873 19908 34817 19908 4202560 15 0 0 0 25 0 0 0 15 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25879] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25879/stat : 25879 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 63721563 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25880] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25880/stat : 25880 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25881] ppid=25873 vsize=411240 CPUtime=0.14
/proc/25874/task/25881/stat : 25881 (java) S 25873 25873 19908 34817 19908 4202560 584 0 0 0 14 0 0 0 16 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25882] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25882/stat : 25882 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25883] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25883/stat : 25883 (java) S 25873 25873 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 63721564 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
[pid=25874/tid=25885] ppid=25873 vsize=411240 CPUtime=0
/proc/25874/task/25885/stat : 25885 (java) S 25873 25873 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 63721778 421109760 42453 1283457024 134512640 134550932 4293715968 18446744073709551615 4294960130 0 4 0 16800975 18446612132933238848 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216.65
Current children cumulated vsize (KiB) 413812

Child status: 0
Real time (s): 285.087
CPU time (s): 216.678
CPU user time (s): 186.3
CPU system time (s): 30.3779
CPU usage (%): 76.004
Max. virtual memory (cumulated for all children) (KiB): 413812

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 186.3
system time used= 30.3779
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 44264
page faults= 1
swaps= 0
block input operations= 0
block output operations= 0
messages sent= 0
messages received= 0
signals received= 0
voluntary context switches= 8780
involuntary context switches= 4448135

runsolver used 0 second user time and 0.004 second system time

The end