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/rand194.cudf.user-upgrades.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/user-upgrades/rand194.cudf /home/misc2010/tmp/201109011757/gj-user-solver-1.0/rand194.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.00 0.92 5/37 24868 /proc/meminfo: memFree=596788/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) R 24866 24867 22717 34817 22717 4202496 355 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 268 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/24867/statm: 641 268 228 194 0 28 0 [pid=24868] ppid=24867 vsize=2564 CPUtime=0 /proc/24868/stat : 24868 (gj-user-solver-) R 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 40 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/24868/statm: 641 40 0 194 0 28 0 [startup+0.111614 s] /proc/loadavg: 1.00 1.00 0.92 5/37 24868 /proc/meminfo: memFree=596788/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=410064 CPUtime=0.11 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 4051 0 1 0 10 1 0 0 25 0 9 0 71924200 419905536 3479 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102516 3479 1946 10 0 96664 0 Current children cumulated CPU time (s) 0.11 Current children cumulated vsize (KiB) 412628 [startup+0.211652 s] /proc/loadavg: 1.00 1.00 0.92 5/37 24868 /proc/meminfo: memFree=596788/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=410064 CPUtime=0.21 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 4870 0 1 0 20 1 0 0 25 0 9 0 71924200 419905536 4298 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102516 4298 1950 10 0 96664 0 Current children cumulated CPU time (s) 0.21 Current children cumulated vsize (KiB) 412628 [startup+0.311665 s] /proc/loadavg: 1.00 1.00 0.92 5/37 24868 /proc/meminfo: memFree=596788/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=410064 CPUtime=0.31 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 5296 0 1 0 30 1 0 0 25 0 9 0 71924200 419905536 4723 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102516 4723 1952 10 0 96664 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 412628 [startup+0.711775 s] /proc/loadavg: 1.00 1.00 0.92 5/37 24868 /proc/meminfo: memFree=596788/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=410748 CPUtime=0.7 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 12754 0 1 0 68 2 0 0 25 0 9 0 71924200 420605952 11995 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102687 11995 2901 10 0 96835 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 413312 [startup+1.51193 s] /proc/loadavg: 1.00 1.00 0.92 2/46 24877 /proc/meminfo: memFree=538424/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=410748 CPUtime=1.5 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 27426 0 1 0 142 8 0 0 25 0 9 0 71924200 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102687 26667 2901 10 0 96835 0 [pid=24868/tid=24870] ppid=24867 vsize=410748 CPUtime=0.77 /proc/24868/task/24870/stat : 24870 (java) S 24867 24867 22717 34817 22717 4202560 6713 0 1 0 74 3 0 0 25 0 9 0 71924201 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=410748 CPUtime=0.67 /proc/24868/task/24871/stat : 24871 (java) R 24867 24867 22717 34817 22717 4202560 19329 0 0 0 63 4 0 0 19 0 9 0 71924202 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4151307642 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=410748 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 71924202 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=410748 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 71924202 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=410748 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71924203 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=410748 CPUtime=0.04 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 446 0 0 0 4 0 0 0 20 0 9 0 71924203 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=410748 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 71924203 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=410748 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) R 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 71924203 420605952 26667 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 413312 [startup+3.10239 s] /proc/loadavg: 1.00 1.00 0.92 3/46 24877 /proc/meminfo: memFree=466628/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=410880 CPUtime=3.09 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 51818 0 1 0 294 15 0 0 25 0 9 0 71924200 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102720 51059 2901 10 0 96868 0 [pid=24868/tid=24870] ppid=24867 vsize=410880 CPUtime=1.38 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 13866 0 1 0 132 6 0 0 25 0 9 0 71924201 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=410880 CPUtime=1.64 /proc/24868/task/24871/stat : 24871 (java) R 24867 24867 22717 34817 22717 4202560 36565 0 0 0 156 8 0 0 16 0 9 0 71924202 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4153043860 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=410880 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 71924202 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=410880 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 71924202 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=410880 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71924203 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=410880 CPUtime=0.04 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 449 0 0 0 4 0 0 0 15 0 9 0 71924203 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=410880 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 71924203 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=410880 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 71924203 420741120 51059 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 413444 [startup+6.30334 s] /proc/loadavg: 1.08 1.02 0.93 2/46 24877 /proc/meminfo: memFree=329608/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=439920 CPUtime=6.29 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 78232 0 1 0 602 27 0 0 25 0 9 0 71924200 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 109980 77467 2902 10 0 104128 0 [pid=24868/tid=24870] ppid=24867 vsize=439920 CPUtime=2.25 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 13883 0 1 0 218 7 0 0 25 0 9 0 71924201 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=439920 CPUtime=3.95 /proc/24868/task/24871/stat : 24871 (java) R 24867 24867 22717 34817 22717 4202560 62944 0 0 0 376 19 0 0 16 0 9 0 71924202 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4152420891 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=439920 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 71924202 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=439920 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 71924202 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=439920 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71924203 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=439920 CPUtime=0.07 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 467 0 0 0 7 0 0 0 15 0 9 0 71924203 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=439920 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 71924203 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=439920 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 71924203 450478080 77467 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.29 Current children cumulated vsize (KiB) 442484 [startup+12.705 s] /proc/loadavg: 1.15 1.03 0.93 2/47 24878 /proc/meminfo: memFree=324764/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=411860 CPUtime=12.68 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 99919 0 1 0 1236 32 0 0 25 0 10 0 71924200 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102965 70647 2919 10 0 97110 0 [pid=24868/tid=24870] ppid=24867 vsize=411860 CPUtime=4.46 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 14136 0 1 0 438 8 0 0 25 0 10 0 71924201 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4150345220 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=411860 CPUtime=8.09 /proc/24868/task/24871/stat : 24871 (java) S 24867 24867 22717 34817 22717 4202560 84294 0 0 0 786 23 0 0 16 0 10 0 71924202 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=411860 CPUtime=0.12 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 546 0 0 0 12 0 0 0 15 0 10 0 71924203 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 71924203 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24878] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24878/stat : 24878 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71925319 421744640 70647 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.68 Current children cumulated vsize (KiB) 414424 Solver just ended. Dumping a history of the last processes samples [startup+12.805 s] /proc/loadavg: 1.15 1.03 0.93 2/47 24878 /proc/meminfo: memFree=324764/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=411860 CPUtime=12.78 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 99920 0 1 0 1246 32 0 0 25 0 10 0 71924200 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102965 70648 2919 10 0 97110 0 [pid=24868/tid=24870] ppid=24867 vsize=411860 CPUtime=4.56 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 14136 0 1 0 448 8 0 0 25 0 10 0 71924201 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4114655608 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=411860 CPUtime=8.09 /proc/24868/task/24871/stat : 24871 (java) S 24867 24867 22717 34817 22717 4202560 84294 0 0 0 786 23 0 0 16 0 10 0 71924202 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=411860 CPUtime=0.12 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 547 0 0 0 12 0 0 0 15 0 10 0 71924203 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 71924203 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24878] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24878/stat : 24878 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71925319 421744640 70648 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.78 Current children cumulated vsize (KiB) 414424 [startup+16.0061 s] /proc/loadavg: 1.14 1.03 0.93 2/47 24878 /proc/meminfo: memFree=324764/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=411860 CPUtime=15.98 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 99931 0 1 0 1565 33 0 0 25 0 10 0 71924200 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102965 70659 2921 10 0 97110 0 [pid=24868/tid=24870] ppid=24867 vsize=411860 CPUtime=7.69 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 14138 0 1 0 760 9 0 0 25 0 10 0 71924201 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4115595433 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=411860 CPUtime=8.15 /proc/24868/task/24871/stat : 24871 (java) S 24867 24867 22717 34817 22717 4202560 84296 0 0 0 792 23 0 0 16 0 10 0 71924202 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=411860 CPUtime=0.13 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 554 0 0 0 13 0 0 0 15 0 10 0 71924203 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 71924203 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24878] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24878/stat : 24878 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71925319 421744640 70659 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 15.98 Current children cumulated vsize (KiB) 414424 [startup+16.8064 s] /proc/loadavg: 1.14 1.03 0.93 2/47 24878 /proc/meminfo: memFree=324764/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=411860 CPUtime=16.78 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 99935 0 1 0 1645 33 0 0 25 0 10 0 71924200 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102965 70663 2922 10 0 97110 0 [pid=24868/tid=24870] ppid=24867 vsize=411860 CPUtime=8.49 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 14139 0 1 0 840 9 0 0 25 0 10 0 71924201 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4115366532 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=411860 CPUtime=8.15 /proc/24868/task/24871/stat : 24871 (java) S 24867 24867 22717 34817 22717 4202560 84296 0 0 0 792 23 0 0 16 0 10 0 71924202 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=411860 CPUtime=0.13 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 557 0 0 0 13 0 0 0 15 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24878] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24878/stat : 24878 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71925319 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 16.78 Current children cumulated vsize (KiB) 414424 [startup+17.6066 s] /proc/loadavg: 1.14 1.03 0.93 2/47 24878 /proc/meminfo: memFree=324764/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=411860 CPUtime=17.58 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 99935 0 1 0 1725 33 0 0 25 0 10 0 71924200 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102965 70663 2922 10 0 97110 0 [pid=24868/tid=24870] ppid=24867 vsize=411860 CPUtime=9.26 /proc/24868/task/24870/stat : 24870 (java) R 24867 24867 22717 34817 22717 4202560 14139 0 1 0 917 9 0 0 25 0 10 0 71924201 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4115447468 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=411860 CPUtime=8.17 /proc/24868/task/24871/stat : 24871 (java) S 24867 24867 22717 34817 22717 4202560 84296 0 0 0 794 23 0 0 16 0 10 0 71924202 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 10 0 71924202 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=411860 CPUtime=0.13 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 557 0 0 0 13 0 0 0 15 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 71924203 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24878] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24878/stat : 24878 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 71925319 421744640 70663 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 17.58 Current children cumulated vsize (KiB) 414424 [startup+18.0067 s] /proc/loadavg: 1.14 1.03 0.93 2/47 24878 /proc/meminfo: memFree=324764/1048576 swapFree=0/0 [pid=24867] ppid=24866 vsize=2564 CPUtime=0 /proc/24867/stat : 24867 (gj-user-solver-) S 24866 24867 22717 34817 22717 4202496 372 0 0 0 0 0 0 0 25 0 1 0 71924200 2625536 269 1283457024 134512640 135304128 4289264704 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24867/statm: 641 269 229 194 0 28 0 [pid=24868] ppid=24867 vsize=411860 CPUtime=17.94 /proc/24868/stat : 24868 (java) S 24867 24867 22717 34817 22717 4202496 99937 0 1 0 1761 33 0 0 25 0 9 0 71924200 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24868/statm: 102965 70665 2923 10 0 97110 0 [pid=24868/tid=24870] ppid=24867 vsize=411860 CPUtime=9.61 /proc/24868/task/24870/stat : 24870 (java) S 24867 24867 22717 34817 22717 4202560 14139 0 1 0 952 9 0 0 25 0 9 0 71924201 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24871] ppid=24867 vsize=411860 CPUtime=8.18 /proc/24868/task/24871/stat : 24871 (java) S 24867 24867 22717 34817 22717 4202560 84296 0 0 0 795 23 0 0 16 0 9 0 71924202 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 0 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24872] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24872/stat : 24872 (java) S 24867 24867 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 9 0 71924202 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24873] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24873/stat : 24873 (java) S 24867 24867 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 9 0 71924202 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24874] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24874/stat : 24874 (java) S 24867 24867 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 71924203 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24875] ppid=24867 vsize=411860 CPUtime=0.13 /proc/24868/task/24875/stat : 24875 (java) S 24867 24867 22717 34817 22717 4202560 558 0 0 0 13 0 0 0 15 0 9 0 71924203 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24876] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24876/stat : 24876 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 25 0 9 0 71924203 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 [pid=24868/tid=24877] ppid=24867 vsize=411860 CPUtime=0 /proc/24868/task/24877/stat : 24877 (java) S 24867 24867 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 71924203 421744640 70665 1283457024 134512640 134550932 4288834352 18446744073709551615 4294960130 0 4 0 16800975 18446612132933240640 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 17.94 Current children cumulated vsize (KiB) 414424 Child status: 0 Real time (s): 18.0381 CPU time (s): 17.9651 CPU user time (s): 17.6171 CPU system time (s): 0.348021 CPU usage (%): 99.5954 Max. virtual memory (cumulated for all children) (KiB): 442484 getrusage(RUSAGE_CHILDREN,...) data: user time used= 17.6171 system time used= 0.348021 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 100325 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= 1149 involuntary context switches= 1418 runsolver used 0 second user time and 0 second system time The end