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/d5026b8e-3477-11e0-986e-00163e3d3b7c.cudf.dudf-real.log.runsolver ./gj-user-solver-1.0 /home/misc2010/data/2011/dudf-real//d5026b8e-3477-11e0-986e-00163e3d3b7c.cudf /home/misc2010/tmp/201109011757/gj-user-solver-1.0/d5026b8e-3477-11e0-986e-00163e3d3b7c.cudf.dudf-real.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.01 1.00 5/34 3023 /proc/meminfo: memFree=246112/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=360 CPUtime=0.01 /proc/3023/stat : 3023 (java) R 3022 3022 22717 34817 22717 4194304 302 0 0 0 0 1 0 0 25 0 1 0 73562235 368640 48 1283457024 134512640 134550932 4290231392 18446744073709551615 4160297812 0 0 0 0 0 0 0 17 0 0 0 0 /proc/3023/statm: 90 48 34 10 0 10 0 [startup+0.153774 s] /proc/loadavg: 1.00 1.01 1.00 5/34 3023 /proc/meminfo: memFree=246112/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=409924 CPUtime=0.15 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 3964 0 1 0 11 4 0 0 25 0 9 0 73562235 419762176 3391 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102481 3391 1918 10 0 96629 0 Current children cumulated CPU time (s) 0.15 Current children cumulated vsize (KiB) 412496 [startup+0.203775 s] /proc/loadavg: 1.00 1.01 1.00 5/34 3023 /proc/meminfo: memFree=246112/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=409924 CPUtime=0.2 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 4069 0 1 0 16 4 0 0 25 0 9 0 73562235 419762176 3496 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102481 3496 1918 10 0 96629 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 412496 [startup+0.313798 s] /proc/loadavg: 1.00 1.01 1.00 5/34 3023 /proc/meminfo: memFree=246112/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=410480 CPUtime=0.31 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 4481 0 1 0 27 4 0 0 25 0 9 0 73562235 420331520 3908 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102620 3908 1951 10 0 96768 0 Current children cumulated CPU time (s) 0.31 Current children cumulated vsize (KiB) 413052 [startup+0.713891 s] /proc/loadavg: 1.00 1.01 1.00 5/34 3023 /proc/meminfo: memFree=246112/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=410624 CPUtime=0.71 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 12603 0 1 0 66 5 0 0 25 0 9 0 73562235 420478976 11843 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102656 11843 2901 10 0 96804 0 Current children cumulated CPU time (s) 0.71 Current children cumulated vsize (KiB) 413196 [startup+1.50408 s] /proc/loadavg: 1.00 1.01 1.00 2/43 3032 /proc/meminfo: memFree=186508/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=410756 CPUtime=1.5 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 25941 0 1 0 140 10 0 0 25 0 9 0 73562235 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102689 25181 2901 10 0 96837 0 [pid=3023/tid=3025] ppid=3022 vsize=410756 CPUtime=0.86 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 6840 0 1 0 82 4 0 0 25 0 9 0 73562235 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=410756 CPUtime=0.6 /proc/3023/task/3026/stat : 3026 (java) R 3022 3022 22717 34817 22717 4202560 17716 0 0 0 55 5 0 0 18 0 9 0 73562236 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4151428000 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=410756 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 20 0 9 0 73562236 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=410756 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 20 0 9 0 73562236 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=410756 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 73562237 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=410756 CPUtime=0.02 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 445 0 0 0 2 0 0 0 18 0 9 0 73562237 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=410756 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 73562237 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=410756 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 73562237 420614144 25181 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 413328 [startup+3.12457 s] /proc/loadavg: 1.00 1.01 1.00 2/43 3032 /proc/meminfo: memFree=111364/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=410900 CPUtime=3.12 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 48964 0 1 0 294 18 0 0 25 0 9 0 73562235 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102725 48120 2925 10 0 96870 0 [pid=3023/tid=3025] ppid=3022 vsize=410900 CPUtime=1.45 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14336 0 1 0 138 7 0 0 25 0 9 0 73562235 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=410900 CPUtime=1.57 /proc/3023/task/3026/stat : 3026 (java) R 3022 3022 22717 34817 22717 4202560 33200 0 0 0 147 10 0 0 15 0 9 0 73562236 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4152824073 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=410900 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 18 0 9 0 73562236 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=410900 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 18 0 9 0 73562236 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=410900 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 73562237 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=410900 CPUtime=0.07 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 488 0 0 0 7 0 0 0 15 0 9 0 73562237 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=410900 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 9 0 73562237 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=410900 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) R 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 9 0 73562237 420761600 48120 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.12 Current children cumulated vsize (KiB) 413472 [startup+6.30531 s] /proc/loadavg: 1.00 1.01 1.00 2/44 3033 /proc/meminfo: memFree=49728/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=411224 CPUtime=6.28 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 56788 0 1 0 607 21 0 0 25 0 10 0 73562235 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102806 55943 2927 10 0 96951 0 [pid=3023/tid=3025] ppid=3022 vsize=411224 CPUtime=4.37 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14362 0 1 0 430 7 0 0 25 0 10 0 73562235 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4115812344 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=411224 CPUtime=1.75 /proc/3023/task/3026/stat : 3026 (java) S 3022 3022 22717 34817 22717 4202560 40932 0 0 0 163 12 0 0 16 0 10 0 73562236 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=411224 CPUtime=0.14 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 549 0 0 0 14 0 0 0 15 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3033] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3033/stat : 3033 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73562571 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.28 Current children cumulated vsize (KiB) 413796 Solver just ended. Dumping a history of the last processes samples [startup+6.40532 s] /proc/loadavg: 1.00 1.01 1.00 2/44 3033 /proc/meminfo: memFree=49728/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=411224 CPUtime=6.38 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 56788 0 1 0 617 21 0 0 25 0 10 0 73562235 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102806 55943 2927 10 0 96951 0 [pid=3023/tid=3025] ppid=3022 vsize=411224 CPUtime=4.47 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14362 0 1 0 440 7 0 0 25 0 10 0 73562235 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4115812357 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=411224 CPUtime=1.75 /proc/3023/task/3026/stat : 3026 (java) S 3022 3022 22717 34817 22717 4202560 40932 0 0 0 163 12 0 0 16 0 10 0 73562236 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=411224 CPUtime=0.14 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 549 0 0 0 14 0 0 0 15 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73562237 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3033] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3033/stat : 3033 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73562571 421093376 55943 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.38 Current children cumulated vsize (KiB) 413796 [startup+8.00569 s] /proc/loadavg: 1.00 1.01 1.00 2/44 3033 /proc/meminfo: memFree=22580/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=411224 CPUtime=7.98 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 59482 0 1 0 776 22 0 0 25 0 10 0 73562235 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102806 58637 2927 10 0 96951 0 [pid=3023/tid=3025] ppid=3022 vsize=411224 CPUtime=6 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14362 0 1 0 593 7 0 0 25 0 10 0 73562235 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4115651816 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=411224 CPUtime=1.81 /proc/3023/task/3026/stat : 3026 (java) S 3022 3022 22717 34817 22717 4202560 43623 0 0 0 168 13 0 0 16 0 10 0 73562236 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=411224 CPUtime=0.14 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 552 0 0 0 14 0 0 0 15 0 10 0 73562237 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73562237 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3033] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3033/stat : 3033 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73562571 421093376 58637 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.98 Current children cumulated vsize (KiB) 413796 [startup+9.60614 s] /proc/loadavg: 1.00 1.01 1.00 2/44 3033 /proc/meminfo: memFree=18736/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=411224 CPUtime=9.58 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 60424 0 1 0 934 24 0 0 25 0 10 0 73562235 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102806 59579 2927 10 0 96951 0 [pid=3023/tid=3025] ppid=3022 vsize=411224 CPUtime=7.58 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14362 0 1 0 751 7 0 0 25 0 10 0 73562235 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4115757281 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=411224 CPUtime=1.83 /proc/3023/task/3026/stat : 3026 (java) S 3022 3022 22717 34817 22717 4202560 44565 0 0 0 169 14 0 0 16 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=411224 CPUtime=0.14 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 552 0 0 0 14 0 0 0 15 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3033] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3033/stat : 3033 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73562571 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 9.58 Current children cumulated vsize (KiB) 413796 [startup+10.4063 s] /proc/loadavg: 1.00 1.01 1.00 2/44 3033 /proc/meminfo: memFree=18736/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=411224 CPUtime=10.38 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 60424 0 1 0 1014 24 0 0 25 0 10 0 73562235 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102806 59579 2927 10 0 96951 0 [pid=3023/tid=3025] ppid=3022 vsize=411224 CPUtime=8.38 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14362 0 1 0 831 7 0 0 25 0 10 0 73562235 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4115760650 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=411224 CPUtime=1.83 /proc/3023/task/3026/stat : 3026 (java) S 3022 3022 22717 34817 22717 4202560 44565 0 0 0 169 14 0 0 16 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=411224 CPUtime=0.14 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 552 0 0 0 14 0 0 0 15 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3033] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3033/stat : 3033 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73562571 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 10.38 Current children cumulated vsize (KiB) 413796 [startup+10.6064 s] /proc/loadavg: 1.00 1.01 1.00 2/44 3033 /proc/meminfo: memFree=18736/1048576 swapFree=0/0 [pid=3022] ppid=3021 vsize=2572 CPUtime=0 /proc/3022/stat : 3022 (gj-user-solver-) S 3021 3022 22717 34817 22717 4202496 373 0 0 0 0 0 0 0 18 0 1 0 73562234 2633728 270 1283457024 134512640 135304128 4291368464 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3022/statm: 643 270 229 194 0 30 0 [pid=3023] ppid=3022 vsize=411224 CPUtime=10.58 /proc/3023/stat : 3023 (java) S 3022 3022 22717 34817 22717 4202496 60424 0 1 0 1034 24 0 0 25 0 10 0 73562235 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3023/statm: 102806 59579 2927 10 0 96951 0 [pid=3023/tid=3025] ppid=3022 vsize=411224 CPUtime=8.58 /proc/3023/task/3025/stat : 3025 (java) R 3022 3022 22717 34817 22717 4202560 14362 0 1 0 851 7 0 0 25 0 10 0 73562235 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4115708853 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3023/tid=3026] ppid=3022 vsize=411224 CPUtime=1.83 /proc/3023/task/3026/stat : 3026 (java) S 3022 3022 22717 34817 22717 4202560 44565 0 0 0 169 14 0 0 16 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3027] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3027/stat : 3027 (java) S 3022 3022 22717 34817 22717 4202560 15 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3028] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3028/stat : 3028 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 15 0 10 0 73562236 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3029] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3029/stat : 3029 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3030] ppid=3022 vsize=411224 CPUtime=0.14 /proc/3023/task/3030/stat : 3030 (java) S 3022 3022 22717 34817 22717 4202560 552 0 0 0 14 0 0 0 15 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3031] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3031/stat : 3031 (java) S 3022 3022 22717 34817 22717 4202560 1 0 0 0 0 0 0 0 25 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3032] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3032/stat : 3032 (java) S 3022 3022 22717 34817 22717 4202560 0 0 0 0 0 0 0 0 15 0 10 0 73562237 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=3023/tid=3033] ppid=3022 vsize=411224 CPUtime=0 /proc/3023/task/3033/stat : 3033 (java) S 3022 3022 22717 34817 22717 4202560 5 0 0 0 0 0 0 0 25 0 10 0 73562571 421093376 59579 1283457024 134512640 134550932 4290231392 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 10.58 Current children cumulated vsize (KiB) 413796 Child status: 0 Real time (s): 10.6768 CPU time (s): 10.6407 CPU user time (s): 10.3846 CPU system time (s): 0.256016 CPU usage (%): 99.6619 Max. virtual memory (cumulated for all children) (KiB): 413796 getrusage(RUSAGE_CHILDREN,...) data: user time used= 10.3846 system time used= 0.256016 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 60822 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= 1014 involuntary context switches= 1273 runsolver used 0 second user time and 0 second system time The end