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/56e31304-c17a-11df-b070-00163e3d3b7c.cudf.dudf-real.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/dudf-real//56e31304-c17a-11df-b070-00163e3d3b7c.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/56e31304-c17a-11df-b070-00163e3d3b7c.cudf.dudf-real.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.01 1.11 1.12 5/34 25898
/proc/meminfo: memFree=346640/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) R 25896 25897 1511 34817 1511 4202496 355 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 268 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/25897/statm: 641 268 228 194 0 28 0
[pid=25898] ppid=25897 vsize=2564 CPUtime=0
/proc/25898/stat : 25898 (gj-trendy-solve) R 25897 25897 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 40 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0
/proc/25898/statm: 641 40 0 194 0 28 0

[startup+0.115191 s]
/proc/loadavg: 1.01 1.11 1.12 5/34 25898
/proc/meminfo: memFree=346640/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=409796 CPUtime=0.11
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 3877 0 1 0 9 2 0 0 25 0 9 0 4799692 419631104 3304 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102449 3304 1922 10 0 96597 0
Current children cumulated CPU time (s) 0.11
Current children cumulated vsize (KiB) 412360

[startup+0.205204 s]
/proc/loadavg: 1.01 1.11 1.12 5/34 25898
/proc/meminfo: memFree=346640/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=409796 CPUtime=0.2
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 4078 0 1 0 18 2 0 0 25 0 9 0 4799692 419631104 3505 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102449 3505 1922 10 0 96597 0
Current children cumulated CPU time (s) 0.2
Current children cumulated vsize (KiB) 412360

[startup+0.305229 s]
/proc/loadavg: 1.01 1.11 1.12 5/34 25898
/proc/meminfo: memFree=346640/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=410380 CPUtime=0.3
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 4438 0 1 0 28 2 0 0 25 0 9 0 4799692 420229120 3865 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102595 3865 1944 10 0 96743 0
Current children cumulated CPU time (s) 0.3
Current children cumulated vsize (KiB) 412944

[startup+0.705357 s]
/proc/loadavg: 1.01 1.11 1.12 5/34 25898
/proc/meminfo: memFree=346640/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=410648 CPUtime=0.7
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 11940 0 1 0 64 6 0 0 25 0 9 0 4799692 420503552 11180 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102662 11180 2901 10 0 96810 0
Current children cumulated CPU time (s) 0.7
Current children cumulated vsize (KiB) 413212

[startup+1.50562 s]
/proc/loadavg: 1.01 1.11 1.11 2/43 25907
/proc/meminfo: memFree=287160/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=410780 CPUtime=1.5
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 25970 0 1 0 138 12 0 0 25 0 9 0 4799692 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102695 25210 2901 10 0 96843 0
[pid=25898/tid=25900] ppid=25897 vsize=410780 CPUtime=0.84
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 6818 0 1 0 80 4 0 0 25 0 9 0 4799693 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=410780 CPUtime=0.61
/proc/25898/task/25901/stat : 25901 (java) R 25897 25897 1511 34817 1511 4202560 17764 0 0 0 54 7 0 0 18 0 9 0 4799694 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4151303166 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=410780 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 4799694 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=410780 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 4799694 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=410780 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4799695 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=410780 CPUtime=0.03
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 447 0 0 0 3 0 0 0 17 0 9 0 4799695 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=410780 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4799695 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=410780 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 4799695 420638720 25210 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 413344

[startup+3.10609 s]
/proc/loadavg: 1.01 1.11 1.11 2/43 25907
/proc/meminfo: memFree=212512/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=410912 CPUtime=3.1
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 48184 0 1 0 289 21 0 0 25 0 9 0 4799692 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102728 47424 2901 10 0 96876 0
[pid=25898/tid=25900] ppid=25897 vsize=410912 CPUtime=1.51
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 13439 0 1 0 139 12 0 0 25 0 9 0 4799693 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4115585275 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=410912 CPUtime=1.54
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 33354 0 0 0 146 8 0 0 16 0 9 0 4799694 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=410912 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 4799694 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=410912 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 4799694 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=410912 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4799695 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=410912 CPUtime=0.03
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 450 0 0 0 3 0 0 0 15 0 9 0 4799695 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=410912 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4799695 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=410912 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 4799695 420773888 47424 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 413476

[startup+6.30714 s]
/proc/loadavg: 1.01 1.11 1.11 2/44 25908
/proc/meminfo: memFree=83296/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=6.28
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69415 0 1 0 601 27 0 0 25 0 10 0 4799692 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68561 2918 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=3.65
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14576 0 1 0 351 14 0 0 25 0 10 0 4799693 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4116021036 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=2.54
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53345 0 0 0 242 12 0 0 16 0 10 0 4799694 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 548 0 0 0 8 0 0 0 15 0 10 0 4799695 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4799695 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25908] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25908/stat : 25908 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4800175 421117952 68561 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.28
Current children cumulated vsize (KiB) 413812

[startup+12.7086 s]
/proc/loadavg: 1.00 1.11 1.11 2/44 25908
/proc/meminfo: memFree=83056/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=12.67
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69429 0 1 0 1240 27 0 0 25 0 10 0 4799692 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68575 2920 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=9.62
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14577 0 1 0 948 14 0 0 25 0 10 0 4799693 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4115853731 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=2.95
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53347 0 0 0 283 12 0 0 16 0 10 0 4799694 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 559 0 0 0 8 0 0 0 15 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25908] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25908/stat : 25908 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4800175 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.67
Current children cumulated vsize (KiB) 413812

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

[startup+12.8086 s]
/proc/loadavg: 1.00 1.11 1.11 2/44 25908
/proc/meminfo: memFree=83056/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=12.77
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69429 0 1 0 1250 27 0 0 25 0 10 0 4799692 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68575 2920 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=9.72
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14577 0 1 0 958 14 0 0 25 0 10 0 4799693 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4116025404 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=2.95
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53347 0 0 0 283 12 0 0 16 0 10 0 4799694 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 559 0 0 0 8 0 0 0 15 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4799695 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25908] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25908/stat : 25908 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4800175 421117952 68575 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.77
Current children cumulated vsize (KiB) 413812

[startup+16.0093 s]
/proc/loadavg: 1.00 1.11 1.11 2/44 25908
/proc/meminfo: memFree=81816/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=15.96
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69768 0 1 0 1569 27 0 0 25 0 10 0 4799692 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68914 2920 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=12.85
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14577 0 1 0 1271 14 0 0 25 0 10 0 4799693 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4116030149 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=3.01
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53684 0 0 0 289 12 0 0 16 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 561 0 0 0 8 0 0 0 15 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25908] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25908/stat : 25908 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4800175 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 15.96
Current children cumulated vsize (KiB) 413812

[startup+16.8095 s]
/proc/loadavg: 1.00 1.10 1.11 2/44 25908
/proc/meminfo: memFree=81816/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=16.76
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69768 0 1 0 1649 27 0 0 25 0 10 0 4799692 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68914 2920 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=13.65
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14577 0 1 0 1351 14 0 0 25 0 10 0 4799693 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4115436439 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=3.01
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53684 0 0 0 289 12 0 0 15 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 561 0 0 0 8 0 0 0 15 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25908] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25908/stat : 25908 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4800175 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 16.76
Current children cumulated vsize (KiB) 413812

[startup+17.2095 s]
/proc/loadavg: 1.00 1.10 1.11 2/44 25908
/proc/meminfo: memFree=81816/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=17.16
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69768 0 1 0 1689 27 0 0 25 0 10 0 4799692 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68914 2920 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=14.05
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14577 0 1 0 1391 14 0 0 25 0 10 0 4799693 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4115579312 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=3.01
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53684 0 0 0 289 12 0 0 15 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4799694 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 561 0 0 0 8 0 0 0 15 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4799695 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25908] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25908/stat : 25908 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4800175 421117952 68914 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 17.16
Current children cumulated vsize (KiB) 413812

[startup+17.6117 s]
/proc/loadavg: 1.00 1.10 1.11 2/43 25908
/proc/meminfo: memFree=81816/1048576 swapFree=0/0
[pid=25897] ppid=25896 vsize=2564 CPUtime=0
/proc/25897/stat : 25897 (gj-trendy-solve) S 25896 25897 1511 34817 1511 4202496 372 0 0 0 0 0 0 0 25 0 1 0 4799692 2625536 269 1283457024 134512640 135304128 4293920992 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/25897/statm: 641 269 229 194 0 28 0
[pid=25898] ppid=25897 vsize=411248 CPUtime=17.55
/proc/25898/stat : 25898 (java) S 25897 25897 1511 34817 1511 4202496 69771 0 1 0 1728 27 0 0 25 0 9 0 4799692 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/25898/statm: 102812 68917 2921 10 0 96957 0
[pid=25898/tid=25900] ppid=25897 vsize=411248 CPUtime=14.44
/proc/25898/task/25900/stat : 25900 (java) R 25897 25897 1511 34817 1511 4202560 14577 0 1 0 1430 14 0 0 25 0 9 0 4799693 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4115058519 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=25898/tid=25901] ppid=25897 vsize=411248 CPUtime=3.01
/proc/25898/task/25901/stat : 25901 (java) S 25897 25897 1511 34817 1511 4202560 53684 0 0 0 289 12 0 0 15 0 9 0 4799694 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 0 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25902] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25902/stat : 25902 (java) S 25897 25897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 9 0 4799694 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25903] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25903/stat : 25903 (java) S 25897 25897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 9 0 4799694 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25904] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25904/stat : 25904 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4799695 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25905] ppid=25897 vsize=411248 CPUtime=0.08
/proc/25898/task/25905/stat : 25905 (java) S 25897 25897 1511 34817 1511 4202560 563 0 0 0 8 0 0 0 15 0 9 0 4799695 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25906] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25906/stat : 25906 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4799695 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
[pid=25898/tid=25907] ppid=25897 vsize=411248 CPUtime=0
/proc/25898/task/25907/stat : 25907 (java) S 25897 25897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 4799695 421117952 68917 1283457024 134512640 134550932 4290405552 18446744073709551615 4294960130 0 4 0 16800975 18446612133395358784 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 17.55
Current children cumulated vsize (KiB) 413812

Child status: 0
Real time (s): 17.6745
CPU time (s): 17.5851
CPU user time (s): 17.2971
CPU system time (s): 0.288018
CPU usage (%): 99.4943
Max. virtual memory (cumulated for all children) (KiB): 413812

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 17.2971
system time used= 0.288018
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 70166
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= 1130
involuntary context switches= 1421

runsolver used 0 second user time and 0 second system time

The end