runsolver Copyright (C) 2010 Olivier ROUSSEL

This is runsolver version 3.2.9a (svn: 651)

This program is distributed in the hope that it will be useful,
but WITHOUT ANY WARRANTY; without even the implied warranty of
MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
GNU General Public License for more details.

command line: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201108311143/gj-user-solver-1.0/cb0e73b0-0ffd-11df-9e59-00163e2c8d72.cudf.dudf-real.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/dudf-real//cb0e73b0-0ffd-11df-9e59-00163e2c8d72.cudf /home/misc2010/tmp/201108311143/gj-user-solver-1.0/cb0e73b0-0ffd-11df-9e59-00163e2c8d72.cudf.dudf-real.result -sum(installedsize) 

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


[startup+0 s]
/proc/loadavg: 1.02 1.01 1.00 4/36 1299
/proc/meminfo: memFree=602284/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=11044 CPUtime=0
/proc/1299/stat : 1299 (java) R 1298 1298 19908 34817 19908 4202496 681 0 0 0 0 0 0 0 25 0 1 0 67607747 11309056 417 1283457024 134512640 134550932 4290110608 18446744073709551615 4160493282 0 0 0 0 0 0 0 17 0 0 0 0
/proc/1299/statm: 2761 417 179 10 0 1103 0

[startup+0.195414 s]
/proc/loadavg: 1.02 1.01 1.00 4/36 1299
/proc/meminfo: memFree=602284/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=409920 CPUtime=0.19
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 4005 0 1 0 17 2 0 0 25 0 9 0 67607747 419758080 3436 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102480 3436 1900 10 0 96628 0
Current children cumulated CPU time (s) 0.19
Current children cumulated vsize (KiB) 412488

[startup+0.20541 s]
/proc/loadavg: 1.02 1.01 1.00 4/36 1299
/proc/meminfo: memFree=602284/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=409920 CPUtime=0.2
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 4027 0 1 0 18 2 0 0 25 0 9 0 67607747 419758080 3458 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102480 3458 1900 10 0 96628 0
Current children cumulated CPU time (s) 0.2
Current children cumulated vsize (KiB) 412488

[startup+0.305451 s]
/proc/loadavg: 1.02 1.01 1.00 4/36 1299
/proc/meminfo: memFree=602284/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=410472 CPUtime=0.3
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 4394 0 1 0 28 2 0 0 25 0 9 0 67607747 420323328 3825 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102618 3825 1928 10 0 96766 0
Current children cumulated CPU time (s) 0.3
Current children cumulated vsize (KiB) 413040

[startup+0.705573 s]
/proc/loadavg: 1.02 1.01 1.00 4/36 1299
/proc/meminfo: memFree=602284/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=410620 CPUtime=0.7
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 12454 0 1 0 68 2 0 0 25 0 9 0 67607747 420474880 11702 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102655 11702 2895 10 0 96803 0
Current children cumulated CPU time (s) 0.7
Current children cumulated vsize (KiB) 413188

[startup+1.50583 s]
/proc/loadavg: 1.02 1.01 1.00 2/45 1308
/proc/meminfo: memFree=543488/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=410752 CPUtime=1.5
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 26756 0 1 0 144 6 0 0 25 0 9 0 67607747 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102688 26004 2895 10 0 96836 0
[pid=1299/tid=1301] ppid=1298 vsize=410752 CPUtime=0.82
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 6792 0 1 0 80 2 0 0 25 0 9 0 67607748 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=410752 CPUtime=0.64
/proc/1299/task/1302/stat : 1302 (java) R 1298 1298 19908 34817 19908 4202560 18576 0 0 0 60 4 0 0 18 0 9 0 67607749 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4153871927 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=410752 CPUtime=0
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 0 0 0 0 20 0 9 0 67607749 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=410752 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 20 0 9 0 67607749 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=410752 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 9 0 67607750 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=410752 CPUtime=0.02
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 447 0 0 0 2 0 0 0 16 0 9 0 67607750 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=410752 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 9 0 67607750 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=410752 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 9 0 67607750 420610048 26004 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.5
Current children cumulated vsize (KiB) 413320

[startup+3.11448 s]
/proc/loadavg: 1.02 1.01 1.00 2/46 1309
/proc/meminfo: memFree=496112/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=2.89
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 36707 0 1 0 262 27 0 0 25 0 10 0 67607747 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 35875 2917 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=1.73
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 153 20 0 0 25 0 10 0 67607748 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=1.05
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 25349 0 0 0 99 6 0 0 15 0 10 0 67607749 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.09
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 570 0 0 0 9 0 0 0 16 0 10 0 67607750 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 35875 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 2.89
Current children cumulated vsize (KiB) 413788

[startup+6.30672 s]
/proc/loadavg: 1.10 1.03 1.01 2/46 1309
/proc/meminfo: memFree=463880/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=5.32
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 36869 0 1 0 467 65 0 0 25 0 10 0 67607747 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 36037 2917 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=4.13
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 355 58 0 0 25 0 10 0 67607748 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4151897760 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=1.06
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 25508 0 0 0 100 6 0 0 16 0 10 0 67607749 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.01
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 1 0 0 0 15 0 10 0 67607749 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 36037 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 5.32
Current children cumulated vsize (KiB) 413788

[startup+12.7062 s]
/proc/loadavg: 1.17 1.04 1.01 2/46 1310
/proc/meminfo: memFree=449372/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=10.17
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 37237 0 1 0 885 132 0 0 25 0 10 0 67607747 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 36405 2917 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=8.91
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 766 125 0 0 25 0 10 0 67607748 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4151897767 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=1.13
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 25876 0 0 0 107 6 0 0 16 0 10 0 67607749 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.01
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 1 0 0 0 15 0 10 0 67607749 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 36405 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 10.17
Current children cumulated vsize (KiB) 413788

[startup+25.5053 s]
/proc/loadavg: 1.21 1.06 1.01 2/46 1311
/proc/meminfo: memFree=420604/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=20.02
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 37922 0 1 0 1750 252 0 0 25 0 10 0 67607747 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 37090 2917 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=18.59
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 1614 245 0 0 25 0 10 0 67607748 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4153945882 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=1.28
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 26561 0 0 0 122 6 0 0 16 0 10 0 67607749 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.02
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 2 0 0 0 15 0 10 0 67607749 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 37090 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 20.02
Current children cumulated vsize (KiB) 413788
heavy processes:

[startup+51.1082 s]
/proc/loadavg: 1.27 1.08 1.02 2/46 1314
/proc/meminfo: memFree=363564/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=39.23
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 39929 0 1 0 3391 532 0 0 25 0 10 0 67607747 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 39097 2917 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=37.46
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 3222 524 0 0 25 0 10 0 67607748 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4150789893 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=1.6
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 28568 0 0 0 153 7 0 0 16 0 10 0 67607749 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.05
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 5 0 0 0 15 0 10 0 67607749 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 39097 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 39.23
Current children cumulated vsize (KiB) 413788
heavy processes:

[startup+102.307 s]
/proc/loadavg: 1.25 1.11 1.03 2/45 1318
/proc/meminfo: memFree=249872/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=77.87
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43532 0 1 0 6716 1071 0 0 25 0 10 0 67607747 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42666 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=75.34
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 6472 1062 0 0 25 0 10 0 67607748 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4153945942 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=2.27
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 219 8 0 0 16 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.13
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 13 0 0 0 15 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 77.87
Current children cumulated vsize (KiB) 413788
heavy processes:

[startup+162.304 s]
/proc/loadavg: 1.29 1.15 1.05 2/44 1318
/proc/meminfo: memFree=134064/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=123.46
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43532 0 1 0 10640 1706 0 0 25 0 10 0 67607747 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42666 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=120.14
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 10317 1697 0 0 25 0 10 0 67607748 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=2.91
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 283 8 0 0 16 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.28
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 28 0 0 0 15 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 123.46
Current children cumulated vsize (KiB) 413788
heavy processes:

[startup+222.307 s]
/proc/loadavg: 1.17 1.14 1.05 2/44 1318
/proc/meminfo: memFree=17132/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=168.99
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43532 0 1 0 14530 2369 0 0 25 0 10 0 67607747 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42666 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=164.85
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 14126 2359 0 0 25 0 10 0 67607748 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4115751440 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=3.65
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 356 9 0 0 16 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.37
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 37 0 0 0 15 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 573 0 0 0 10 0 0 0 15 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42666 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 168.99
Current children cumulated vsize (KiB) 413788
heavy processes:

[startup+282.306 s]
/proc/loadavg: 1.29 1.18 1.07 2/44 1318
/proc/meminfo: memFree=5964/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=214.82
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43536 0 1 0 18471 3011 0 0 25 0 10 0 67607747 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42670 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=209.87
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 17986 3001 0 0 25 0 10 0 67607748 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4151897772 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=4.38
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 429 9 0 0 16 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.45
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 45 0 0 0 15 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 577 0 0 0 10 0 0 0 16 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 214.82
Current children cumulated vsize (KiB) 413788
heavy processes:

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

[startup+283.006 s]
/proc/loadavg: 1.29 1.18 1.07 2/44 1318
/proc/meminfo: memFree=5672/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=215.39
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43536 0 1 0 18519 3020 0 0 25 0 10 0 67607747 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42670 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=210.44
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 18034 3010 0 0 25 0 10 0 67607748 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=4.38
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 429 9 0 0 16 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.45
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 45 0 0 0 15 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 577 0 0 0 10 0 0 0 16 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 215.39
Current children cumulated vsize (KiB) 413788

[startup+283.806 s]
/proc/loadavg: 1.29 1.18 1.07 2/44 1318
/proc/meminfo: memFree=5960/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=216
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43536 0 1 0 18576 3024 0 0 25 0 10 0 67607747 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42670 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=211.04
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 18090 3014 0 0 25 0 10 0 67607748 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=4.39
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 430 9 0 0 16 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.45
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 45 0 0 0 15 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 577 0 0 0 10 0 0 0 16 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216
Current children cumulated vsize (KiB) 413788

[startup+284.606 s]
/proc/loadavg: 1.29 1.18 1.07 2/44 1318
/proc/meminfo: memFree=5960/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=216.63
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43536 0 1 0 18627 3036 0 0 25 0 10 0 67607747 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42670 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=211.67
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 18141 3026 0 0 25 0 10 0 67607748 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4153585083 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=4.39
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 430 9 0 0 16 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.45
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 45 0 0 0 15 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 577 0 0 0 10 0 0 0 16 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216.63
Current children cumulated vsize (KiB) 413788

[startup+285.007 s]
/proc/loadavg: 1.29 1.18 1.07 2/44 1318
/proc/meminfo: memFree=6184/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=411220 CPUtime=216.93
/proc/1299/stat : 1299 (java) S 1298 1298 19908 34817 19908 4202496 43536 0 1 0 18653 3040 0 0 25 0 10 0 67607747 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 102805 42670 2912 10 0 96950 0
[pid=1299/tid=1301] ppid=1298 vsize=411220 CPUtime=211.97
/proc/1299/task/1301/stat : 1301 (java) R 1298 1298 19908 34817 19908 4202560 9842 0 1 0 18167 3030 0 0 25 0 10 0 67607748 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=1299/tid=1302] ppid=1298 vsize=411220 CPUtime=4.39
/proc/1299/task/1302/stat : 1302 (java) S 1298 1298 19908 34817 19908 4202560 32171 0 0 0 430 9 0 0 16 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1303] ppid=1298 vsize=411220 CPUtime=0.45
/proc/1299/task/1303/stat : 1303 (java) S 1298 1298 19908 34817 19908 4202560 15 0 0 0 45 0 0 0 15 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1304] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1304/stat : 1304 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 18 0 10 0 67607749 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1305] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1305/stat : 1305 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1306] ppid=1298 vsize=411220 CPUtime=0.1
/proc/1299/task/1306/stat : 1306 (java) S 1298 1298 19908 34817 19908 4202560 577 0 0 0 10 0 0 0 16 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1307/stat : 1307 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 25 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1308] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1308/stat : 1308 (java) S 1298 1298 19908 34817 19908 4202560 1 0 0 0 0 0 0 0 15 0 10 0 67607750 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
[pid=1299/tid=1309] ppid=1298 vsize=411220 CPUtime=0
/proc/1299/task/1309/stat : 1309 (java) S 1298 1298 19908 34817 19908 4202560 5 0 0 0 0 0 0 0 25 0 10 0 67607937 421089280 42670 1283457024 134512640 134550932 4290110608 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216.93
Current children cumulated vsize (KiB) 413788

[startup+285.109 s]
/proc/loadavg: 1.29 1.18 1.07 2/44 1318
/proc/meminfo: memFree=6184/1048576 swapFree=0/0
[pid=1298] ppid=1297 vsize=2568 CPUtime=0
/proc/1298/stat : 1298 (gj-user-solver-) S 1297 1298 19908 34817 19908 4202496 370 0 0 0 0 0 0 0 18 0 1 0 67607747 2629632 269 1283457024 134512640 135304128 4292337472 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/1298/statm: 642 269 229 194 0 29 0
[pid=1299] ppid=1298 vsize=0 CPUtime=216.96
/proc/1299/stat : 1299 (java) Z 1298 1298 19908 34817 19908 4202508 43542 0 1 0 18655 3041 0 0 18 0 2 0 67607747 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/1299/statm: 0 0 0 0 0 0 0
[pid=1299/tid=1307] ppid=1298 vsize=0 CPUtime=0.01
/proc/1299/task/1307/stat : 1307 (java) R 1298 1298 19908 34817 19908 4203588 1 0 0 0 0 1 0 0 18 0 2 0 67607750 0 0 1283457024 0 0 0 0 0 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 216.96
Current children cumulated vsize (KiB) 2568

Child status: 0
Real time (s): 285.112
CPU time (s): 216.978
CPU user time (s): 186.56
CPU system time (s): 30.4179
CPU usage (%): 76.1027
Max. virtual memory (cumulated for all children) (KiB): 413788

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 186.56
system time used= 30.4179
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 43924
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= 8738
involuntary context switches= 4468523

runsolver used 0 second user time and 0 second system time

The end