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/rand408.cudf.user-upgrades.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/user-upgrades/rand408.cudf /home/misc2010/tmp/201109011757/gj-user-solver-1.0/rand408.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.00 1.03 1.00 4/37 26204
/proc/meminfo: memFree=545272/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=11044 CPUtime=0.01
/proc/26204/stat : 26204 (java) R 26203 26203 22717 34817 22717 4202496 729 0 0 0 1 0 0 0 25 0 1 0 72095766 11309056 466 1283457024 134512640 134550932 4288051840 18446744073709551615 4160030434 0 0 0 0 0 0 0 17 0 0 0 0
/proc/26204/statm: 2761 466 185 10 0 1103 0

[startup+0.125705 s]
/proc/loadavg: 1.00 1.03 1.00 4/37 26204
/proc/meminfo: memFree=545272/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=409924 CPUtime=0.13
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 3902 0 1 0 10 3 0 0 25 0 9 0 72095766 419762176 3330 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102481 3330 1918 10 0 96629 0
Current children cumulated CPU time (s) 0.13
Current children cumulated vsize (KiB) 412496

[startup+0.205734 s]
/proc/loadavg: 1.00 1.03 1.00 4/37 26204
/proc/meminfo: memFree=545272/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=409924 CPUtime=0.21
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 4080 0 1 0 18 3 0 0 25 0 9 0 72095766 419762176 3508 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102481 3508 1918 10 0 96629 0
Current children cumulated CPU time (s) 0.21
Current children cumulated vsize (KiB) 412496

[startup+0.305751 s]
/proc/loadavg: 1.00 1.03 1.00 4/37 26204
/proc/meminfo: memFree=545272/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=410480 CPUtime=0.31
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 4454 0 1 0 28 3 0 0 25 0 9 0 72095766 420331520 3882 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102620 3882 1945 10 0 96768 0
Current children cumulated CPU time (s) 0.31
Current children cumulated vsize (KiB) 413052

[startup+0.705869 s]
/proc/loadavg: 1.00 1.03 1.00 4/37 26204
/proc/meminfo: memFree=545272/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=410616 CPUtime=0.7
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 11901 0 1 0 62 8 0 0 25 0 9 0 72095766 420470784 11142 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102654 11142 2901 10 0 96802 0
Current children cumulated CPU time (s) 0.7
Current children cumulated vsize (KiB) 413188

[startup+1.50611 s]
/proc/loadavg: 1.00 1.03 1.00 2/46 26213
/proc/meminfo: memFree=488024/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=410748 CPUtime=1.51
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 27289 0 1 0 137 14 0 0 25 0 9 0 72095766 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102687 26530 2901 10 0 96835 0
[pid=26204/tid=26206] ppid=26203 vsize=410748 CPUtime=0.81
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 6712 0 1 0 75 6 0 0 25 0 9 0 72095767 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=410748 CPUtime=0.63
/proc/26204/task/26207/stat : 26207 (java) R 26203 26203 22717 34817 22717 4202560 19193 0 0 0 56 7 0 0 18 0 9 0 72095767 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4153022894 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=410748 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 72095768 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=410748 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 72095768 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=410748 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 72095769 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=410748 CPUtime=0.04
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 446 0 0 0 4 0 0 0 16 0 9 0 72095769 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=410748 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 72095769 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=410748 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 72095769 420605952 26530 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 1.51
Current children cumulated vsize (KiB) 413320

[startup+3.1064 s]
/proc/loadavg: 1.00 1.03 1.00 2/46 26213
/proc/meminfo: memFree=416228/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=410880 CPUtime=3.1
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 50546 0 1 0 290 20 0 0 25 0 9 0 72095766 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102720 49787 2901 10 0 96868 0
[pid=26204/tid=26206] ppid=26203 vsize=410880 CPUtime=1.44
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 13865 0 1 0 135 9 0 0 25 0 9 0 72095767 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=410880 CPUtime=1.6
/proc/26204/task/26207/stat : 26207 (java) R 26203 26203 22717 34817 22717 4202560 35294 0 0 0 149 11 0 0 16 0 9 0 72095767 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4150908856 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=410880 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 72095768 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=410880 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 72095768 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=410880 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 72095769 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=410880 CPUtime=0.04
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 449 0 0 0 4 0 0 0 15 0 9 0 72095769 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=410880 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 72095769 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=410880 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 72095769 420741120 49787 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 3.1
Current children cumulated vsize (KiB) 413452

[startup+6.30732 s]
/proc/loadavg: 1.08 1.04 1.01 2/46 26213
/proc/meminfo: memFree=279332/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=439920 CPUtime=6.3
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 78233 0 1 0 604 26 0 0 25 0 9 0 72095766 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 109980 77468 2902 10 0 104128 0
[pid=26204/tid=26206] ppid=26203 vsize=439920 CPUtime=2.29
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 13882 0 1 0 220 9 0 0 25 0 9 0 72095767 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=439920 CPUtime=3.93
/proc/26204/task/26207/stat : 26207 (java) R 26203 26203 22717 34817 22717 4202560 62946 0 0 0 377 16 0 0 16 0 9 0 72095767 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4152400411 0 0 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=439920 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 72095768 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=439920 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 72095768 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=439920 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 72095769 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=439920 CPUtime=0.05
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 467 0 0 0 5 0 0 0 15 0 9 0 72095769 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=439920 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 72095769 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=439920 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 72095769 450478080 77468 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 6.3
Current children cumulated vsize (KiB) 442492

[startup+12.7088 s]
/proc/loadavg: 1.15 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=12.69
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99923 0 1 0 1233 36 0 0 25 0 10 0 72095766 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70649 2919 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=4.45
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14135 0 1 0 435 10 0 0 25 0 10 0 72095767 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4115651013 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.1
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84296 0 0 0 784 26 0 0 16 0 10 0 72095767 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 549 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.69
Current children cumulated vsize (KiB) 414428

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

[startup+12.8088 s]
/proc/loadavg: 1.15 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=12.79
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99923 0 1 0 1243 36 0 0 25 0 10 0 72095766 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70649 2919 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=4.55
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14135 0 1 0 445 10 0 0 25 0 10 0 72095767 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4115495268 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.1
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84296 0 0 0 784 26 0 0 16 0 10 0 72095767 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 549 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70649 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 12.79
Current children cumulated vsize (KiB) 414428

[startup+16.0095 s]
/proc/loadavg: 1.14 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=15.98
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99930 0 1 0 1562 36 0 0 25 0 10 0 72095766 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70656 2921 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=7.68
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14135 0 1 0 758 10 0 0 25 0 10 0 72095767 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4115522071 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.16
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84298 0 0 0 790 26 0 0 16 0 10 0 72095767 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 554 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70656 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 15.98
Current children cumulated vsize (KiB) 414428

[startup+16.8098 s]
/proc/loadavg: 1.14 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=16.76
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99933 0 1 0 1640 36 0 0 25 0 10 0 72095766 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70659 2921 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=8.46
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14135 0 1 0 836 10 0 0 25 0 10 0 72095767 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4115041617 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.16
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84298 0 0 0 790 26 0 0 16 0 10 0 72095767 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 557 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70659 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 16.76
Current children cumulated vsize (KiB) 414428

[startup+17.61 s]
/proc/loadavg: 1.13 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=17.56
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99934 0 1 0 1720 36 0 0 25 0 10 0 72095766 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70660 2921 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=9.23
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14136 0 1 0 913 10 0 0 25 0 10 0 72095767 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4115584867 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.19
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84298 0 0 0 793 26 0 0 16 0 10 0 72095767 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 557 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 17.56
Current children cumulated vsize (KiB) 414428

[startup+18.0101 s]
/proc/loadavg: 1.13 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=17.96
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99934 0 1 0 1760 36 0 0 25 0 10 0 72095766 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70660 2921 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=9.63
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14136 0 1 0 953 10 0 0 25 0 10 0 72095767 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4115524206 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.19
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84298 0 0 0 793 26 0 0 16 0 10 0 72095767 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 557 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 17.96
Current children cumulated vsize (KiB) 414428

[startup+18.1102 s]
/proc/loadavg: 1.13 1.06 1.01 2/47 26214
/proc/meminfo: memFree=274240/1048576 swapFree=0/0
[pid=26203] ppid=26202 vsize=2572 CPUtime=0
/proc/26203/stat : 26203 (gj-user-solver-) S 26202 26203 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 72095766 2633728 269 1283457024 134512640 135304128 4287033888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0
/proc/26203/statm: 643 269 229 194 0 30 0
[pid=26204] ppid=26203 vsize=411856 CPUtime=18.06
/proc/26204/stat : 26204 (java) S 26203 26203 22717 34817 22717 4202496 99934 0 1 0 1770 36 0 0 25 0 10 0 72095766 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0
/proc/26204/statm: 102964 70660 2921 10 0 97109 0
[pid=26204/tid=26206] ppid=26203 vsize=411856 CPUtime=9.71
/proc/26204/task/26206/stat : 26206 (java) R 26203 26203 22717 34817 22717 4202560 14136 0 1 0 961 10 0 0 25 0 10 0 72095767 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4115651013 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26207] ppid=26203 vsize=411856 CPUtime=8.21
/proc/26204/task/26207/stat : 26207 (java) S 26203 26203 22717 34817 22717 4202560 84298 0 0 0 795 26 0 0 16 0 10 0 72095767 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 0 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26208] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26208/stat : 26208 (java) S 26203 26203 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26209] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26209/stat : 26209 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 72095768 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26210] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26210/stat : 26210 (java) S 26203 26203 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26211] ppid=26203 vsize=411856 CPUtime=0.12
/proc/26204/task/26211/stat : 26211 (java) S 26203 26203 22717 34817 22717 4202560 557 0 0 0 12 0 0 0 15 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26212] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26212/stat : 26212 (java) S 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
[pid=26204/tid=26213] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26213/stat : 26213 (java) R 26203 26203 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 72095769 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0
[pid=26204/tid=26214] ppid=26203 vsize=411856 CPUtime=0
/proc/26204/task/26214/stat : 26214 (java) S 26203 26203 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 72096888 421740544 70660 1283457024 134512640 134550932 4288051840 18446744073709551615 4294960130 0 4 0 16800975 18446612132933241536 0 0 -1 0 0 0 0
Current children cumulated CPU time (s) 18.06
Current children cumulated vsize (KiB) 414428

Child status: 0
Real time (s): 18.1839
CPU time (s): 18.1011
CPU user time (s): 17.7131
CPU system time (s): 0.388024
CPU usage (%): 99.5447
Max. virtual memory (cumulated for all children) (KiB): 442492

getrusage(RUSAGE_CHILDREN,...) data:
user time used= 17.7131
system time used= 0.388024
maximum resident set size= 0
integral shared memory size= 0
integral unshared data size= 0
integral unshared stack size= 0
page reclaims= 100323
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= 1167
involuntary context switches= 1458

runsolver used 0 second user time and 0 second system time

The end