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/rand0.cudf.s.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/incremental/s/rand0.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/rand0.cudf.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.05 1.02 0.96 5/37 3160
/proc/meminfo: memFree=714848/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=405436 CPUtime=0
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 1585 0 1 0 0 0 0 0 25 0 3 0 937299 415166464 1244 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16784584 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 101359 1244 694 10 0 95925 0
[pid=3159/tid=3160] ppid=3158 vsize=405436 CPUtime=0
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 665 0 1 0 0 0 0 0 25 0 3 0 937299 415166464 1244 1283457024 134512640 134550932 4286929328 18446744073709551615 4158905960 0 4 0 16784584 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=405436 CPUtime=0
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 3 0 0 0 0 0 0 0 25 0 3 0 937300 415166464 1244 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16784584 18446612133393236096 0 0 -1 0 0 0 0

[startup+0.137832 s]
/proc/loadavg: 1.05 1.02 0.96 5/37 3160
/proc/meminfo: memFree=714848/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=409796 CPUtime=0.13
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 3782 0 1 0 10 3 0 0 25 0 9 0 937299 419631104 3211 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102449 3211 1791 10 0 96597 0
[pid=3159/tid=3160] ppid=3158 vsize=409796 CPUtime=0.13
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 2815 0 1 0 10 3 0 0 25 0 9 0 937299 419631104 3211 1283457024 134512640 134550932 4286929328 18446744073709551615 4114263197 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=409796 CPUtime=0
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 9 0 937300 419631104 3211 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.13
Current children cumulated vsize (KiB) 412360

[startup+0.207839 s]
/proc/loadavg: 1.05 1.02 0.96 5/37 3160
/proc/meminfo: memFree=714848/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=410100 CPUtime=0.19
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 4401 0 1 0 16 3 0 0 25 0 9 0 937299 419942400 3830 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102525 3830 1932 10 0 96673 0
[pid=3159/tid=3160] ppid=3158 vsize=410100 CPUtime=0.18
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 3207 0 1 0 15 3 0 0 25 0 9 0 937299 419942400 3830 1283457024 134512640 134550932 4286929328 18446744073709551615 4114210768 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=410100 CPUtime=0
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 9 0 937300 419942400 3830 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.19
Current children cumulated vsize (KiB) 412664

[startup+0.307859 s]
/proc/loadavg: 1.05 1.02 0.96 5/37 3160
/proc/meminfo: memFree=714848/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=410388 CPUtime=0.29
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 5391 0 1 0 25 4 0 0 25 0 9 0 937299 420237312 4819 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102597 4819 1945 10 0 96745 0
[pid=3159/tid=3160] ppid=3158 vsize=410388 CPUtime=0.25
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 3514 0 1 0 22 3 0 0 25 0 9 0 937299 420237312 4819 1283457024 134512640 134550932 4286929328 18446744073709551615 4114678722 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=410388 CPUtime=0.01
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 575 0 0 0 0 1 0 0 23 0 9 0 937300 420237312 4819 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.29
Current children cumulated vsize (KiB) 412952

[startup+0.707934 s]
/proc/loadavg: 1.05 1.02 0.96 5/37 3160
/proc/meminfo: memFree=714848/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=410784 CPUtime=0.69
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 12712 0 1 0 63 6 0 0 25 0 9 0 937299 420642816 11956 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102696 11956 2896 10 0 96844 0
[pid=3159/tid=3160] ppid=3158 vsize=410784 CPUtime=0.43
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 4154 0 1 0 40 3 0 0 25 0 9 0 937299 420642816 11956 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=410784 CPUtime=0.23
/proc/3159/task/3161/stat : 3161 (java) R 3158 3158 1511 34817 1511 4202560 7183 0 0 0 20 3 0 0 21 0 9 0 937300 420642816 11956 1283457024 134512640 134550932 4286929328 18446744073709551615 4152620380 0 0 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.69
Current children cumulated vsize (KiB) 413348

[startup+1.50802 s]
/proc/loadavg: 1.05 1.02 0.96 3/45 3168
/proc/meminfo: memFree=657796/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=1.5
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23350 0 1 0 138 12 0 0 25 0 10 0 937299 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 22512 2921 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=0.8
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6935 0 1 0 76 4 0 0 25 0 10 0 937299 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4115107064 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=0.58
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 14949 0 0 0 50 8 0 0 19 0 10 0 937300 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.12
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 526 0 0 0 12 0 0 0 16 0 10 0 937302 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 22512 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 413684

[startup+3.10821 s]
/proc/loadavg: 1.05 1.02 0.96 2/46 3169
/proc/meminfo: memFree=635096/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=3.1
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23944 0 1 0 298 12 0 0 25 0 10 0 937299 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 23097 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=2.3
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 226 4 0 0 25 0 10 0 937299 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4115089904 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=0.66
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15528 0 0 0 58 8 0 0 16 0 10 0 937300 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 536 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 23097 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 413684

[startup+6.30883 s]
/proc/loadavg: 1.04 1.02 0.96 3/46 3169
/proc/meminfo: memFree=634972/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=6.3
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 618 12 0 0 25 0 10 0 937299 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 22923 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=5.45
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 541 4 0 0 25 0 10 0 937299 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4114381303 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=0.7
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 62 8 0 0 15 0 10 0 937300 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 22923 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 413684

[startup+12.7101 s]
/proc/loadavg: 1.04 1.02 0.96 2/45 3169
/proc/meminfo: memFree=640080/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=12.69
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 1257 12 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=11.64
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 1160 4 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115089904 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=0.9
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 82 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 413684

[startup+25.5129 s]
/proc/loadavg: 1.03 1.02 0.96 2/45 3169
/proc/meminfo: memFree=640080/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=25.48
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 2536 12 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=24.16
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 2412 4 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115133153 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=1.17
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 109 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.48
Current children cumulated vsize (KiB) 413684

[startup+51.108 s]
/proc/loadavg: 1.02 1.02 0.96 2/45 3169
/proc/meminfo: memFree=640080/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=51.06
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 5094 12 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=49.43
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 4939 4 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4114990208 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=1.49
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 141 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) R 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 51.06
Current children cumulated vsize (KiB) 413684

[startup+102.309 s]
/proc/loadavg: 1.01 1.01 0.96 3/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=102.24
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 10212 12 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=99.91
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 9987 4 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4114789321 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.19
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 211 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) R 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.24
Current children cumulated vsize (KiB) 413684

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

[startup+102.409 s]
/proc/loadavg: 1.01 1.01 0.96 3/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=102.34
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 10222 12 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=100.01
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 9997 4 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115083330 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.19
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 211 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 102.34
Current children cumulated vsize (KiB) 413684

[startup+128.015 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=127.93
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 12781 12 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=125.24
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 12520 4 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115069629 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.54
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 246 8 0 0 16 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) R 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 127.93
Current children cumulated vsize (KiB) 413684

[startup+140.808 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=140.72
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 14059 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=137.91
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 13786 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115089825 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.66
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 258 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 140.72
Current children cumulated vsize (KiB) 413684

[startup+147.209 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=147.12
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 14699 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=144.24
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 14419 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115089900 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.74
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 266 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 147.12
Current children cumulated vsize (KiB) 413684

[startup+150.31 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=150.22
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 15009 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=147.27
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 14722 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115046121 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.81
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 273 8 0 0 16 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 150.22
Current children cumulated vsize (KiB) 413684

[startup+151.91 s]
/proc/loadavg: 1.00 1.01 0.96 3/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=151.82
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 15169 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=148.87
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 14882 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115069615 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.81
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 273 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 151.82
Current children cumulated vsize (KiB) 413684

[startup+153.51 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=153.42
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 15329 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=150.47
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 15042 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115052548 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.81
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 273 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) R 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 153.42
Current children cumulated vsize (KiB) 413684

[startup+153.91 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=153.82
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 15369 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=150.87
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 15082 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115075736 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.81
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 273 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 153.82
Current children cumulated vsize (KiB) 413684

[startup+154.11 s]
/proc/loadavg: 1.00 1.01 0.96 2/44 3169
/proc/meminfo: memFree=640088/1048576 swapFree=0/0
[pid=3158] ppid=3157 vsize=2564 CPUtime=0
/proc/3158/stat : 3158 (gj-trendy-solve) S 3157 3158 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 937299 2625536 268 1283457024 134512640 135304128 4293546192 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/3158/statm: 641 268 229 194 0 28 0
[pid=3159] ppid=3158 vsize=411120 CPUtime=154.02
/proc/3159/stat : 3159 (java) S 3158 3158 1511 34817 1511 4202496 23947 0 1 0 15389 13 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/3159/statm: 102780 21668 2916 10 0 96925 0
[pid=3159/tid=3160] ppid=3158 vsize=411120 CPUtime=151.07
/proc/3159/task/3160/stat : 3160 (java) R 3158 3158 1511 34817 1511 4202560 6936 0 1 0 15102 5 0 0 25 0 10 0 937299 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4115167813 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3161] ppid=3158 vsize=411120 CPUtime=2.81
/proc/3159/task/3161/stat : 3161 (java) S 3158 3158 1511 34817 1511 4202560 15530 0 0 0 273 8 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 0 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3163] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3163/stat : 3163 (java) S 3158 3158 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3164] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3164/stat : 3164 (java) S 3158 3158 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 15 0 10 0 937300 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3165] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3165/stat : 3165 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3166] ppid=3158 vsize=411120 CPUtime=0.14
/proc/3159/task/3166/stat : 3166 (java) S 3158 3158 1511 34817 1511 4202560 537 0 0 0 14 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3167] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3167/stat : 3167 (java) S 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
[pid=3159/tid=3168] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3168/stat : 3168 (java) R 3158 3158 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 937302 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=3159/tid=3169] ppid=3158 vsize=411120 CPUtime=0
/proc/3159/task/3169/stat : 3169 (java) S 3158 3158 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 937437 420986880 21668 1283457024 134512640 134550932 4286929328 18446744073709551615 4294960130 0 4 0 16800975 18446612133393236096 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 154.02
Current children cumulated vsize (KiB) 413684

Child status: 0
Real time (s): 154.169
CPU time (s): 154.066
CPU user time (s): 153.93
CPU system time (s): 0.136008
CPU usage (%): 99.9326
Max. virtual memory (cumulated for all children) (KiB): 413684

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 153.93
system time used= 0.136008
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 24334
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= 3550
involuntary context switches= 5124

runsolver used 0.008 second user time and 0.008 second system time

The end