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/201108241238/gj-trendy-solver-1.0/rand115.cudf.s-e-l.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/incremental/s-e-l/rand115.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/rand115.cudf.s-e-l.result 

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


[startup+0 s]
/proc/loadavg: 1.03 1.01 0.92 5/35 2701
/proc/meminfo: memFree=729704/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=11360 CPUtime=0.01
/proc/2700/stat : 2700 (java) R 2699 2699 1511 34817 1511 4202496 919 0 0 0 0 1 0 0 25 0 2 0 843333 11632640 652 1283457024 134512640 134550932 4293267376 18446744073709551615 4159811176 0 0 0 0 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 2840 652 285 10 0 1186 0
[pid=2700/tid=2701] ppid=2699 vsize=11360 CPUtime=0
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 2 0 843333 11632640 652 1283457024 134512640 134550932 4293267376 18446744073709551615 4159811176 0 0 0 0 0 0 0 -1 0 0 0 0

[startup+0.145464 s]
/proc/loadavg: 1.03 1.01 0.92 5/35 2701
/proc/meminfo: memFree=729704/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=409800 CPUtime=0.15
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 3790 0 1 0 12 3 0 0 25 0 9 0 843333 419635200 3221 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102450 3221 1787 10 0 96598 0
[pid=2700/tid=2701] ppid=2699 vsize=409800 CPUtime=0.14
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 2823 0 1 0 12 2 0 0 25 0 9 0 843333 419635200 3221 1283457024 134512640 134550932 4293267376 18446744073709551615 4115158961 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.15
Current children cumulated vsize (KiB) 412364

[startup+0.205469 s]
/proc/loadavg: 1.03 1.01 0.92 5/35 2701
/proc/meminfo: memFree=729704/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=410104 CPUtime=0.21
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 4359 0 1 0 18 3 0 0 25 0 9 0 843333 419946496 3790 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102526 3790 1929 10 0 96674 0
[pid=2700/tid=2701] ppid=2699 vsize=410104 CPUtime=0.19
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 3166 0 1 0 17 2 0 0 25 0 9 0 843333 419946496 3790 1283457024 134512640 134550932 4293267376 18446744073709551615 4153042672 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.21
Current children cumulated vsize (KiB) 412668

[startup+0.305492 s]
/proc/loadavg: 1.03 1.01 0.92 5/35 2701
/proc/meminfo: memFree=729704/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=410252 CPUtime=0.31
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 5342 0 1 0 28 3 0 0 25 0 9 0 843333 420098048 4772 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102563 4772 1938 10 0 96711 0
[pid=2700/tid=2701] ppid=2699 vsize=410252 CPUtime=0.27
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 3503 0 1 0 25 2 0 0 25 0 9 0 843333 420098048 4772 1283457024 134512640 134550932 4293267376 18446744073709551615 4115656880 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.31
Current children cumulated vsize (KiB) 412816

[startup+0.705583 s]
/proc/loadavg: 1.03 1.01 0.92 5/35 2701
/proc/meminfo: memFree=729704/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=410684 CPUtime=0.7
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 12882 0 1 0 66 4 0 0 25 0 9 0 843333 420540416 12128 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102671 12128 2895 10 0 96819 0
[pid=2700/tid=2701] ppid=2699 vsize=410684 CPUtime=0.45
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 4191 0 1 0 43 2 0 0 25 0 9 0 843333 420540416 12128 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.7
Current children cumulated vsize (KiB) 413248

[startup+1.50572 s]
/proc/loadavg: 1.03 1.01 0.92 2/43 2709
/proc/meminfo: memFree=671288/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=410816 CPUtime=1.5
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 28048 0 1 0 143 7 0 0 25 0 9 0 843333 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102704 27294 2896 10 0 96852 0
[pid=2700/tid=2701] ppid=2699 vsize=410816 CPUtime=0.76
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 6815 0 1 0 73 3 0 0 25 0 9 0 843333 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=410816 CPUtime=0.71
/proc/2700/task/2703/stat : 2703 (java) R 2699 2699 1511 34817 1511 4202560 19864 0 0 0 68 3 0 0 19 0 9 0 843334 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4151219735 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 21 0 9 0 843334 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 21 0 9 0 843334 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 843335 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=410816 CPUtime=0.01
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 429 0 0 0 1 0 0 0 17 0 9 0 843335 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 843335 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 843335 420675584 27294 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 413380

[startup+3.11613 s]
/proc/loadavg: 1.03 1.01 0.92 2/43 2709
/proc/meminfo: memFree=596888/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=410816 CPUtime=3.11
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 49367 0 1 0 293 18 0 0 25 0 9 0 843333 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102704 48610 2897 10 0 96852 0
[pid=2700/tid=2701] ppid=2699 vsize=410816 CPUtime=1.35
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14266 0 1 0 129 6 0 0 25 0 9 0 843333 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=410816 CPUtime=1.7
/proc/2700/task/2703/stat : 2703 (java) R 2699 2699 1511 34817 1511 4202560 33711 0 0 0 160 10 0 0 15 0 9 0 843334 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4151795991 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 21 0 9 0 843334 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 21 0 9 0 843334 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 843335 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=410816 CPUtime=0.03
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 450 0 0 0 3 0 0 0 15 0 9 0 843335 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 843335 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=410816 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 843335 420675584 48610 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.11
Current children cumulated vsize (KiB) 413380

[startup+6.30713 s]
/proc/loadavg: 1.03 1.01 0.92 2/44 2710
/proc/meminfo: memFree=494952/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=6.3
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 62576 0 1 0 610 20 0 0 25 0 10 0 843333 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 61740 2921 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=4.15
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 408 7 0 0 25 0 10 0 843333 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4115147556 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=2.01
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 46575 0 0 0 189 12 0 0 16 0 10 0 843334 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.12
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 534 0 0 0 12 0 0 0 15 0 10 0 843335 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 61740 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 413716

[startup+12.7095 s]
/proc/loadavg: 1.11 1.03 0.93 2/44 2710
/proc/meminfo: memFree=476856/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=12.69
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67070 0 1 0 1248 21 0 0 25 0 10 0 843333 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66225 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=10.07
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 1000 7 0 0 25 0 10 0 843333 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4116019588 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=2.48
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 235 13 0 0 16 0 10 0 843334 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.12
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 540 0 0 0 12 0 0 0 15 0 10 0 843335 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66225 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 413716

[startup+25.5126 s]
/proc/loadavg: 1.08 1.03 0.93 3/44 2710
/proc/meminfo: memFree=476856/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=25.49
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67074 0 1 0 2528 21 0 0 25 0 10 0 843333 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66229 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=22.34
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 2227 7 0 0 25 0 10 0 843333 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4115995185 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=3
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 287 13 0 0 15 0 10 0 843334 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.14
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 544 0 0 0 14 0 0 0 15 0 10 0 843335 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66229 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.49
Current children cumulated vsize (KiB) 413716

[startup+51.1093 s]
/proc/loadavg: 1.05 1.02 0.93 2/46 2712
/proc/meminfo: memFree=476584/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=51.08
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 5086 22 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=47.44
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 4737 7 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4116020247 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=3.46
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 333 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) R 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.08
Current children cumulated vsize (KiB) 413716

[startup+102.309 s]
/proc/loadavg: 1.02 1.02 0.93 2/46 2717
/proc/meminfo: memFree=476460/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=102.25
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 10203 22 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=97.9
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 9782 8 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4116024746 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=4.18
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 405 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.25
Current children cumulated vsize (KiB) 413716

[startup+162.312 s]
/proc/loadavg: 1.01 1.01 0.93 2/45 2721
/proc/meminfo: memFree=476476/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=162.21
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 16199 22 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=157.39
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 15731 8 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4116157189 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=4.65
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 452 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 162.21
Current children cumulated vsize (KiB) 413716

[startup+222.305 s]
/proc/loadavg: 1.00 1.01 0.93 2/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=222.17
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 22193 24 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=216.64
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 21655 9 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4115999715 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.35
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 522 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 222.17
Current children cumulated vsize (KiB) 413716

[startup+282.31 s]
/proc/loadavg: 1.00 1.00 0.93 3/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=282.13
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 28189 24 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=276.09
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 27600 9 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4116020247 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.87
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 574 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.13
Current children cumulated vsize (KiB) 413716

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

[startup+283.21 s]
/proc/loadavg: 1.00 1.00 0.93 2/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=283.03
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 28279 24 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=276.99
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 27690 9 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4115547669 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.87
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 574 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.03
Current children cumulated vsize (KiB) 413716

[startup+284.01 s]
/proc/loadavg: 1.00 1.00 0.93 2/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=283.83
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 28359 24 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=277.79
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 27770 9 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4116072821 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.87
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 574 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) R 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.83
Current children cumulated vsize (KiB) 413716

[startup+284.811 s]
/proc/loadavg: 1.00 1.00 0.93 2/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=284.63
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 28439 24 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=278.59
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 27850 9 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4115983053 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.87
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 574 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.63
Current children cumulated vsize (KiB) 413716

[startup+285.011 s]
/proc/loadavg: 1.00 1.00 0.93 3/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=284.83
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67075 0 1 0 28459 24 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66230 2915 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=278.79
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 27870 9 0 0 25 0 10 0 843333 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4115942035 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.87
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 574 13 0 0 15 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 843334 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 843335 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2710] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2710/stat : 2710 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 843732 421019648 66230 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.83
Current children cumulated vsize (KiB) 413716

[startup+285.111 s]
/proc/loadavg: 1.00 1.00 0.93 3/44 2721
/proc/meminfo: memFree=476484/1048576 swapFree=0/0
[pid=2699] ppid=2698 vsize=2564 CPUtime=0
/proc/2699/stat : 2699 (gj-trendy-solve) S 2698 2699 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 843332 2625536 268 1283457024 134512640 135304128 4291272336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/2699/statm: 641 268 229 194 0 28 0
[pid=2700] ppid=2699 vsize=411152 CPUtime=284.93
/proc/2700/stat : 2700 (java) S 2699 2699 1511 34817 1511 4202496 67076 0 1 0 28469 24 0 0 25 0 9 0 843333 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/2700/statm: 102788 66231 2916 10 0 96933 0
[pid=2700/tid=2701] ppid=2699 vsize=411152 CPUtime=278.89
/proc/2700/task/2701/stat : 2701 (java) R 2699 2699 1511 34817 1511 4202560 14522 0 1 0 27880 9 0 0 25 0 9 0 843333 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4116157189 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=2700/tid=2703] ppid=2699 vsize=411152 CPUtime=5.87
/proc/2700/task/2703/stat : 2703 (java) S 2699 2699 1511 34817 1511 4202560 51063 0 0 0 574 13 0 0 15 0 9 0 843334 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 0 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2704] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2704/stat : 2704 (java) S 2699 2699 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 9 0 843334 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2705] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2705/stat : 2705 (java) S 2699 2699 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 9 0 843334 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2706] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2706/stat : 2706 (java) S 2699 2699 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 843335 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2707] ppid=2699 vsize=411152 CPUtime=0.15
/proc/2700/task/2707/stat : 2707 (java) S 2699 2699 1511 34817 1511 4202560 545 0 0 0 15 0 0 0 15 0 9 0 843335 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2708] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2708/stat : 2708 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 843335 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
[pid=2700/tid=2709] ppid=2699 vsize=411152 CPUtime=0
/proc/2700/task/2709/stat : 2709 (java) S 2699 2699 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 843335 421019648 66231 1283457024 134512640 134550932 4293267376 18446744073709551615 4294960130 0 4 0 16800975 18446612133275972032 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.93
Current children cumulated vsize (KiB) 413716

Child status: 0
Real time (s): 285.158
CPU time (s): 284.954
CPU user time (s): 284.71
CPU system time (s): 0.244015
CPU usage (%): 99.9283
Max. virtual memory (cumulated for all children) (KiB): 413716

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 284.71
system time used= 0.244015
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 67461
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= 5935
involuntary context switches= 8802

runsolver used 0 second user time and 0 second system time

The end