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/rand348.cudf.s-e-l-s.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/incremental/s-e-l-s/rand348.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/rand348.cudf.s-e-l-s.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.04 1.00 3/33 9897
/proc/meminfo: memFree=686728/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2556 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) D 9896 9897 1511 34817 1511 4202496 351 0 0 0 0 0 0 0 18 0 1 0 2352883 2617344 265 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 0 4 65536 18446744071564252162 0 0 17 0 0 0 0
/proc/9897/statm: 639 265 227 194 0 26 0

[startup+0.199767 s]
/proc/loadavg: 1.01 1.04 1.00 3/33 9897
/proc/meminfo: memFree=686728/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+0.209759 s]
/proc/loadavg: 1.01 1.04 1.00 3/33 9897
/proc/meminfo: memFree=686728/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+0.309795 s]
/proc/loadavg: 1.01 1.04 1.00 3/33 9897
/proc/meminfo: memFree=686728/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+0.709898 s]
/proc/loadavg: 1.01 1.04 1.00 3/33 9897
/proc/meminfo: memFree=686728/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
Current children cumulated CPU time (s) 0
Current children cumulated vsize (KiB) 2564

[startup+1.51 s]
/proc/loadavg: 1.01 1.04 1.00 3/43 9907
/proc/meminfo: memFree=626960/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=410712 CPUtime=1.47
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 27536 0 1 0 139 8 0 0 25 0 9 0 2352886 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 102678 26783 2896 10 0 96826 0
[pid=9899/tid=9900] ppid=9897 vsize=410712 CPUtime=0.77
/proc/9899/task/9900/stat : 9900 (java) S 9897 9897 1511 34817 1511 4202560 6833 0 1 0 76 1 0 0 24 0 9 0 2352886 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=410712 CPUtime=0.66
/proc/9899/task/9901/stat : 9901 (java) R 9897 9897 1511 34817 1511 4202560 19332 0 0 0 59 7 0 0 19 0 9 0 2352887 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4151388088 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=410712 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 2352887 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=410712 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 2352887 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=410712 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=410712 CPUtime=0.04
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 431 0 0 0 4 0 0 0 17 0 9 0 2352888 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=410712 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=410712 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 2352888 420569088 26783 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.47
Current children cumulated vsize (KiB) 413276

[startup+3.13084 s]
/proc/loadavg: 1.01 1.04 1.00 2/43 9907
/proc/meminfo: memFree=551692/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=410844 CPUtime=3.09
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 49340 0 1 0 297 12 0 0 25 0 9 0 2352886 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 102711 48587 2896 10 0 96859 0
[pid=9899/tid=9900] ppid=9897 vsize=410844 CPUtime=1.4
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14258 0 1 0 136 4 0 0 25 0 9 0 2352886 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=410844 CPUtime=1.64
/proc/9899/task/9901/stat : 9901 (java) R 9897 9897 1511 34817 1511 4202560 33708 0 0 0 156 8 0 0 15 0 9 0 2352887 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4153495499 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=410844 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 2352887 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=410844 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 2352887 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=410844 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=410844 CPUtime=0.04
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 434 0 0 0 4 0 0 0 16 0 9 0 2352888 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=410844 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=410844 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 2352888 420704256 48587 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.09
Current children cumulated vsize (KiB) 413408

[startup+6.33471 s]
/proc/loadavg: 1.09 1.05 1.01 3/43 9907
/proc/meminfo: memFree=416160/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=410988 CPUtime=6.24
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 71214 0 1 0 604 20 0 0 25 0 9 0 2352886 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 102747 70374 2912 10 0 96892 0
[pid=9899/tid=9900] ppid=9897 vsize=410988 CPUtime=2.54
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14570 0 1 0 250 4 0 0 25 0 9 0 2352886 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=410988 CPUtime=3.63
/proc/9899/task/9901/stat : 9901 (java) R 9897 9897 1511 34817 1511 4202560 55228 0 0 0 347 16 0 0 16 0 9 0 2352887 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4153081504 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=410988 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 9 0 2352887 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=410988 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 9 0 2352887 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=410988 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=410988 CPUtime=0.06
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 476 0 0 0 6 0 0 0 15 0 9 0 2352888 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=410988 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=410988 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 2352888 420851712 70374 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.24
Current children cumulated vsize (KiB) 413552

[startup+12.7092 s]
/proc/loadavg: 1.16 1.07 1.01 2/44 9908
/proc/meminfo: memFree=412440/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=12.6
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83078 0 1 0 1236 24 0 0 25 0 10 0 2352886 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71178 2913 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=6.11
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14602 0 1 0 607 4 0 0 25 0 10 0 2352886 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4115982793 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=6.38
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66992 0 0 0 618 20 0 0 15 0 10 0 2352887 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.1
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 539 0 0 0 10 0 0 0 16 0 10 0 2352888 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71178 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.6
Current children cumulated vsize (KiB) 416608

[startup+25.5123 s]
/proc/loadavg: 1.13 1.06 1.01 2/44 9908
/proc/meminfo: memFree=412440/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=25.36
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83090 0 1 0 2512 24 0 0 25 0 10 0 2352886 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71190 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=18.31
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 1827 4 0 0 25 0 10 0 2352886 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4115114295 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=6.92
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 672 20 0 0 16 0 10 0 2352887 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 547 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) R 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71190 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.36
Current children cumulated vsize (KiB) 416608

[startup+51.11 s]
/proc/loadavg: 1.08 1.06 1.01 2/44 9908
/proc/meminfo: memFree=412440/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=50.94
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 5070 24 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=43.38
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 4334 4 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4116083849 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=7.44
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 724 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) R 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 50.94
Current children cumulated vsize (KiB) 416608

[startup+102.313 s]
/proc/loadavg: 1.03 1.05 1.00 2/44 9908
/proc/meminfo: memFree=412440/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=102.13
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 10185 28 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=93.71
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 9363 8 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4116000067 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=8.29
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 809 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.13
Current children cumulated vsize (KiB) 416608

[startup+162.308 s]
/proc/loadavg: 1.01 1.03 1.00 2/44 9908
/proc/meminfo: memFree=412316/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=162.07
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 16179 28 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=153.21
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 15313 8 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4116096069 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=8.74
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 854 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 162.07
Current children cumulated vsize (KiB) 416608

[startup+222.306 s]
/proc/loadavg: 1.00 1.03 1.00 2/46 9912
/proc/meminfo: memFree=412168/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=222.03
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 22174 29 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=212.66
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 21257 9 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4116083836 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=9.24
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 904 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 222.03
Current children cumulated vsize (KiB) 416608

[startup+282.311 s]
/proc/loadavg: 1.00 1.02 1.00 2/46 9918
/proc/meminfo: memFree=412044/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=282
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 28171 29 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=271.82
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 27173 9 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4115954192 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=10.06
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 986 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282
Current children cumulated vsize (KiB) 416608

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

[startup+283.011 s]
/proc/loadavg: 1.00 1.02 1.00 2/46 9918
/proc/meminfo: memFree=412044/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=282.7
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 28241 29 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=272.51
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 27242 9 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4115969835 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=10.06
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 986 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 282.7
Current children cumulated vsize (KiB) 416608

[startup+283.811 s]
/proc/loadavg: 1.00 1.02 1.00 2/46 9918
/proc/meminfo: memFree=412044/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=283.5
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 28321 29 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=273.31
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 27322 9 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4115895132 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=10.06
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 986 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 283.5
Current children cumulated vsize (KiB) 416608

[startup+284.612 s]
/proc/loadavg: 1.00 1.02 1.00 2/46 9918
/proc/meminfo: memFree=412044/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=284.3
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 28401 29 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=274.11
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 27402 9 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4116102759 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=10.06
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 986 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.3
Current children cumulated vsize (KiB) 416608

[startup+285.012 s]
/proc/loadavg: 1.00 1.02 1.00 2/46 9919
/proc/meminfo: memFree=412044/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=284.7
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83093 0 1 0 28441 29 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71193 2916 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=274.51
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 27442 9 0 0 25 0 10 0 2352886 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4115954228 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=10.06
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 986 20 0 0 15 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 2352887 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 2352888 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9908] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9908/stat : 9908 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 2353849 423981056 71193 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.7
Current children cumulated vsize (KiB) 416608

[startup+285.112 s]
/proc/loadavg: 1.00 1.02 1.00 2/46 9919
/proc/meminfo: memFree=412044/1048576 swapFree=0/0
[pid=9897] ppid=9896 vsize=2564 CPUtime=0
/proc/9897/stat : 9897 (gj-trendy-solve) S 9896 9897 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 25 0 1 0 2352883 2625536 269 1283457024 134512640 135304128 4289281200 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/9897/statm: 641 269 229 194 0 28 0
[pid=9899] ppid=9897 vsize=414044 CPUtime=284.8
/proc/9899/stat : 9899 (java) S 9897 9897 1511 34817 1511 4202496 83094 0 1 0 28451 29 0 0 25 0 9 0 2352886 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/9899/statm: 103511 71194 2917 10 0 97656 0
[pid=9899/tid=9900] ppid=9897 vsize=414044 CPUtime=274.61
/proc/9899/task/9900/stat : 9900 (java) R 9897 9897 1511 34817 1511 4202560 14604 0 1 0 27452 9 0 0 25 0 9 0 2352886 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4115142876 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=9899/tid=9901] ppid=9897 vsize=414044 CPUtime=10.06
/proc/9899/task/9901/stat : 9901 (java) S 9897 9897 1511 34817 1511 4202560 66994 0 0 0 986 20 0 0 15 0 9 0 2352887 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 0 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9902] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9902/stat : 9902 (java) S 9897 9897 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 9 0 2352887 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9903] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9903/stat : 9903 (java) S 9897 9897 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 9 0 2352887 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9904] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9904/stat : 9904 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9905] ppid=9897 vsize=414044 CPUtime=0.12
/proc/9899/task/9905/stat : 9905 (java) S 9897 9897 1511 34817 1511 4202560 550 0 0 0 12 0 0 0 15 0 9 0 2352888 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9906] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9906/stat : 9906 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 2352888 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
[pid=9899/tid=9907] ppid=9897 vsize=414044 CPUtime=0
/proc/9899/task/9907/stat : 9907 (java) S 9897 9897 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 9 0 2352888 423981056 71194 1283457024 134512640 134550932 4291218864 18446744073709551615 4294960130 0 4 0 16800975 18446612133173261632 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 284.8
Current children cumulated vsize (KiB) 416608

Child status: 0
Real time (s): 285.18
CPU time (s): 284.834
CPU user time (s): 284.526
CPU system time (s): 0.308019
CPU usage (%): 99.8787
Max. virtual memory (cumulated for all children) (KiB): 437048

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 284.526
system time used= 0.308019
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 83480
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= 6160
involuntary context switches= 9134

runsolver used 0 second user time and 0 second system time

The end