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/201109011757/gj-user-solver-1.0/rand105.cudf.user-upgrades.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/user-upgrades/rand105.cudf /home/misc2010/tmp/201109011757/gj-user-solver-1.0/rand105.cudf.user-upgrades.result -sum(pin-priority-1),-sum(pin-priority-500),-sum(pin-priority-700),-notuptodate,-removed,-changed 

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


[startup+0 s]
/proc/loadavg: 1.03 0.89 0.50 3/40 23073
/proc/meminfo: memFree=841548/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=11356 CPUtime=0.01
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 917 0 0 0 0 1 0 0 24 0 2 0 71660116 11628544 651 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 0 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 2839 651 285 10 0 1185 0
[pid=23072/tid=23073] ppid=23071 vsize=11356 CPUtime=0
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 2 0 71660117 11628544 651 1283457024 134512640 134550932 4289104768 18446744073709551615 4159307368 0 0 0 0 0 0 0 -1 0 0 0 0

[startup+0.15564 s]
/proc/loadavg: 1.03 0.89 0.50 3/40 23073
/proc/meminfo: memFree=841548/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=410068 CPUtime=0.16
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 4053 0 1 0 14 2 0 0 24 0 9 0 71660116 419909632 3481 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102517 3481 1942 10 0 96665 0
[pid=23072/tid=23073] ppid=23071 vsize=410068 CPUtime=0.13
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 2851 0 1 0 13 0 0 0 25 0 9 0 71660117 419909632 3481 1283457024 134512640 134550932 4289104768 18446744073709551615 4114648548 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.16
Current children cumulated vsize (KiB) 412632

[startup+0.205641 s]
/proc/loadavg: 1.03 0.89 0.50 3/40 23073
/proc/meminfo: memFree=841548/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=410068 CPUtime=0.2
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 4409 0 1 0 18 2 0 0 24 0 9 0 71660116 419909632 3837 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102517 3837 1946 10 0 96665 0
[pid=23072/tid=23073] ppid=23071 vsize=410068 CPUtime=0.18
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 3207 0 1 0 17 1 0 0 25 0 9 0 71660117 419909632 3837 1283457024 134512640 134550932 4289104768 18446744073709551615 4114645981 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.2
Current children cumulated vsize (KiB) 412632

[startup+0.305669 s]
/proc/loadavg: 1.03 0.89 0.50 3/40 23073
/proc/meminfo: memFree=841548/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=410372 CPUtime=0.3
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 5404 0 1 0 28 2 0 0 24 0 9 0 71660116 420220928 4831 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102593 4831 1957 10 0 96741 0
[pid=23072/tid=23073] ppid=23071 vsize=410372 CPUtime=0.25
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 3527 0 1 0 24 1 0 0 25 0 9 0 71660117 420220928 4831 1283457024 134512640 134550932 4289104768 18446744073709551615 4115068831 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.3
Current children cumulated vsize (KiB) 412936

[startup+0.705766 s]
/proc/loadavg: 1.03 0.89 0.50 3/40 23073
/proc/meminfo: memFree=841548/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=410644 CPUtime=0.7
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 12758 0 1 0 66 4 0 0 24 0 9 0 71660116 420499456 11999 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102661 11999 2901 10 0 96809 0
[pid=23072/tid=23073] ppid=23071 vsize=410644 CPUtime=0.46
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 4183 0 1 0 45 1 0 0 25 0 9 0 71660117 420499456 11999 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 0.7
Current children cumulated vsize (KiB) 413208

[startup+1.50603 s]
/proc/loadavg: 1.03 0.89 0.50 3/48 23081
/proc/meminfo: memFree=784372/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=410776 CPUtime=1.51
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 27430 0 1 0 145 6 0 0 24 0 9 0 71660116 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102694 26671 2901 10 0 96842 0
[pid=23072/tid=23073] ppid=23071 vsize=410776 CPUtime=0.78
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 6711 0 1 0 77 1 0 0 25 0 9 0 71660117 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=410776 CPUtime=0.67
/proc/23072/task/23075/stat : 23075 (java) R 23071 23071 22717 34817 22717 4202560 19333 0 0 0 64 3 0 0 19 0 9 0 71660118 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4151291304 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 21 0 9 0 71660118 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 21 0 9 0 71660118 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=410776 CPUtime=0.02
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 446 0 0 0 2 0 0 0 17 0 9 0 71660119 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 9 0 71660119 420634624 26671 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.51
Current children cumulated vsize (KiB) 413340

[startup+3.11639 s]
/proc/loadavg: 1.03 0.89 0.50 3/48 23082
/proc/meminfo: memFree=710592/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=410776 CPUtime=3.11
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 52754 0 1 0 300 11 0 0 24 0 9 0 71660116 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102694 51995 2901 10 0 96842 0
[pid=23072/tid=23073] ppid=23071 vsize=410776 CPUtime=1.41
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 13863 0 1 0 136 5 0 0 25 0 9 0 71660117 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=410776 CPUtime=1.66
/proc/23072/task/23075/stat : 23075 (java) R 23071 23071 22717 34817 22717 4202560 37501 0 0 0 162 4 0 0 15 0 9 0 71660118 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4150912952 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 21 0 9 0 71660118 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 21 0 9 0 71660118 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=410776 CPUtime=0.02
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 450 0 0 0 2 0 0 0 15 0 9 0 71660119 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=410776 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) R 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 9 0 71660119 420634624 51995 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.11
Current children cumulated vsize (KiB) 413340

[startup+6.3075 s]
/proc/loadavg: 1.10 0.91 0.51 2/48 23082
/proc/meminfo: memFree=575680/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=439948 CPUtime=6.3
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 78235 0 1 0 610 20 0 0 24 0 9 0 71660116 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 109987 77470 2902 10 0 104135 0
[pid=23072/tid=23073] ppid=23071 vsize=439948 CPUtime=2.28
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 13880 0 1 0 222 6 0 0 25 0 9 0 71660117 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=439948 CPUtime=3.96
/proc/23072/task/23075/stat : 23075 (java) R 23071 23071 22717 34817 22717 4202560 62947 0 0 0 384 12 0 0 16 0 9 0 71660118 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4152404507 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=439948 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 21 0 9 0 71660118 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=439948 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 21 0 9 0 71660118 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=439948 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=439948 CPUtime=0.03
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 468 0 0 0 3 0 0 0 15 0 9 0 71660119 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=439948 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=439948 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 9 0 71660119 450506752 77470 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 442512

[startup+12.71 s]
/proc/loadavg: 1.18 0.93 0.52 2/49 23084
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=12.69
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99928 0 1 0 1242 27 0 0 24 0 10 0 71660116 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70654 2919 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=4.5
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 14133 0 1 0 444 6 0 0 25 0 10 0 71660117 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4115500519 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.09
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84299 0 0 0 789 20 0 0 16 0 10 0 71660118 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.07
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 551 0 0 0 7 0 0 0 15 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23083] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23083/stat : 23083 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71661234 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 414424

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

[startup+12.8101 s]
/proc/loadavg: 1.18 0.93 0.52 2/49 23084
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=12.79
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99928 0 1 0 1252 27 0 0 24 0 10 0 71660116 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70654 2919 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=4.6
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 14133 0 1 0 454 6 0 0 25 0 10 0 71660117 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4115519049 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.09
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84299 0 0 0 789 20 0 0 16 0 10 0 71660118 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.07
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 551 0 0 0 7 0 0 0 15 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 10 0 71660119 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23083] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23083/stat : 23083 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71661234 421744640 70654 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.79
Current children cumulated vsize (KiB) 414424

[startup+19.2121 s]
/proc/loadavg: 1.15 0.93 0.52 2/49 23084
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=19.19
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99935 0 1 0 1891 28 0 0 24 0 10 0 71660116 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70661 2921 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=10.92
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 14134 0 1 0 1085 7 0 0 25 0 10 0 71660117 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4115042216 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.17
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84301 0 0 0 797 20 0 0 15 0 10 0 71660118 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.08
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 555 0 0 0 8 0 0 0 15 0 10 0 71660119 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 10 0 71660119 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23083] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23083/stat : 23083 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71661234 421744640 70661 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 19.19
Current children cumulated vsize (KiB) 414424

[startup+22.4129 s]
/proc/loadavg: 1.15 0.93 0.52 2/49 23085
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=22.38
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99938 0 1 0 2210 28 0 0 24 0 10 0 71660116 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70664 2921 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=14.11
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 14134 0 1 0 1404 7 0 0 25 0 10 0 71660117 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4115500708 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.17
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84301 0 0 0 797 20 0 0 15 0 10 0 71660118 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.08
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 558 0 0 0 8 0 0 0 15 0 10 0 71660119 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 10 0 71660119 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23083] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23083/stat : 23083 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71661234 421744640 70664 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 22.38
Current children cumulated vsize (KiB) 414424

[startup+24.0133 s]
/proc/loadavg: 1.15 0.93 0.52 2/49 23085
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=23.98
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99940 0 1 0 2370 28 0 0 24 0 10 0 71660116 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70666 2921 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=15.66
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 14135 0 1 0 1559 7 0 0 25 0 10 0 71660117 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4115527600 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.22
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84301 0 0 0 802 20 0 0 16 0 10 0 71660118 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.08
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 559 0 0 0 8 0 0 0 15 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23083] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23083/stat : 23083 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71661234 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 23.98
Current children cumulated vsize (KiB) 414424

[startup+24.8135 s]
/proc/loadavg: 1.14 0.93 0.53 2/49 23085
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=24.78
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99940 0 1 0 2450 28 0 0 24 0 10 0 71660116 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70666 2921 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=16.46
/proc/23072/task/23073/stat : 23073 (java) R 23071 23071 22717 34817 22717 4202560 14135 0 1 0 1639 7 0 0 25 0 10 0 71660117 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4115651721 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.22
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84301 0 0 0 802 20 0 0 15 0 10 0 71660118 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71660118 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.08
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 559 0 0 0 8 0 0 0 15 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 10 0 71660119 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23083] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23083/stat : 23083 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71661234 421744640 70666 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 24.78
Current children cumulated vsize (KiB) 414424

[startup+25.1982 s]
/proc/loadavg: 1.14 0.93 0.53 2/49 23085
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=25.16
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99942 0 1 0 2488 28 0 0 24 0 9 0 71660116 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70668 2922 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=16.83
/proc/23072/task/23073/stat : 23073 (java) S 23071 23071 22717 34817 22717 4202560 14135 0 1 0 1676 7 0 0 25 0 9 0 71660117 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.23
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84301 0 0 0 803 20 0 0 16 0 9 0 71660118 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 9 0 71660118 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 9 0 71660118 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.08
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 560 0 0 0 8 0 0 0 15 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.16
Current children cumulated vsize (KiB) 414424

[startup+25.2032 s]
/proc/loadavg: 1.14 0.93 0.53 1/48 23086
/proc/meminfo: memFree=570712/1048576 swapFree=0/0
[pid=23071] ppid=23070 vsize=2564 CPUtime=0
/proc/23071/stat : 23071 (gj-user-solver-) S 23070 23071 22717 34817 22717 4202496 371 0 0 0 0 0 0 0 24 0 1 0 71660116 2625536 268 1283457024 134512640 135304128 4294869392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/23071/statm: 641 268 229 194 0 28 0
[pid=23072] ppid=23071 vsize=411860 CPUtime=25.16
/proc/23072/stat : 23072 (java) S 23071 23071 22717 34817 22717 4202496 99942 0 1 0 2488 28 0 0 24 0 9 0 71660116 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/23072/statm: 102965 70668 2922 10 0 97110 0
[pid=23072/tid=23073] ppid=23071 vsize=411860 CPUtime=16.83
/proc/23072/task/23073/stat : 23073 (java) S 23071 23071 22717 34817 22717 4202560 14135 0 1 0 1676 7 0 0 25 0 9 0 71660117 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23075] ppid=23071 vsize=411860 CPUtime=8.23
/proc/23072/task/23075/stat : 23075 (java) S 23071 23071 22717 34817 22717 4202560 84301 0 0 0 803 20 0 0 16 0 9 0 71660118 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 0 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23076] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23076/stat : 23076 (java) S 23071 23071 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 9 0 71660118 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23077] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23077/stat : 23077 (java) S 23071 23071 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 9 0 71660118 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23078] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23078/stat : 23078 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23079] ppid=23071 vsize=411860 CPUtime=0.08
/proc/23072/task/23079/stat : 23079 (java) S 23071 23071 22717 34817 22717 4202560 560 0 0 0 8 0 0 0 15 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23080] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23080/stat : 23080 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
[pid=23072/tid=23081] ppid=23071 vsize=411860 CPUtime=0
/proc/23072/task/23081/stat : 23081 (java) S 23071 23071 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 15 0 9 0 71660119 421744640 70668 1283457024 134512640 134550932 4289104768 18446744073709551615 4294960130 0 4 0 16800975 18446612133256534208 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 25.16
Current children cumulated vsize (KiB) 414424

Child status: 0
Real time (s): 25.2393
CPU time (s): 25.1856
CPU user time (s): 24.8816
CPU system time (s): 0.304019
CPU usage (%): 99.787
Max. virtual memory (cumulated for all children) (KiB): 442512

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 24.8816
system time used= 0.304019
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 100329
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= 1323
involuntary context switches= 1713

runsolver used 0 second user time and 0 second system time

The end