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/201108241238/gj-trendy-solver-1.0/dd08e73e-d489-11df-b9cf-00163e3d3b7c.cudf.dudf-real.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/dudf-real//dd08e73e-d489-11df-b9cf-00163e3d3b7c.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/dd08e73e-d489-11df-b9cf-00163e3d3b7c.cudf.dudf-real.result 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.29 1.19 1.12 4/34 300 /proc/meminfo: memFree=328024/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=11044 CPUtime=0.01 /proc/300/stat : 300 (java) R 32767 32767 1511 34817 1511 4202496 665 0 0 0 0 1 0 0 25 0 1 0 5130019 11309056 402 1283457024 134512640 134550932 4288502496 18446744073709551615 4159858402 0 0 0 0 0 0 0 17 0 0 0 0 /proc/300/statm: 2761 402 178 10 0 1103 0 [startup+0.145346 s] /proc/loadavg: 1.29 1.19 1.12 4/34 300 /proc/meminfo: memFree=328024/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=409800 CPUtime=0.14 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 3945 0 1 0 10 4 0 0 25 0 9 0 5130019 419635200 3373 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102450 3373 1922 10 0 96598 0 Current children cumulated CPU time (s) 0.14 Current children cumulated vsize (KiB) 412372 [startup+0.205348 s] /proc/loadavg: 1.29 1.19 1.12 4/34 300 /proc/meminfo: memFree=328024/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=409800 CPUtime=0.2 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 4080 0 1 0 16 4 0 0 25 0 9 0 5130019 419635200 3508 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102450 3508 1922 10 0 96598 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 412372 [startup+0.305367 s] /proc/loadavg: 1.29 1.19 1.12 4/34 300 /proc/meminfo: memFree=328024/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=410384 CPUtime=0.3 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 4426 0 1 0 26 4 0 0 25 0 9 0 5130019 420233216 3854 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102596 3854 1944 10 0 96744 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 412956 [startup+0.705453 s] /proc/loadavg: 1.29 1.19 1.12 4/34 300 /proc/meminfo: memFree=328024/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=410652 CPUtime=0.7 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 12230 0 1 0 62 8 0 0 25 0 9 0 5130019 420507648 11471 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102663 11471 2901 10 0 96811 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 413224 [startup+1.50565 s] /proc/loadavg: 1.29 1.19 1.12 2/43 309 /proc/meminfo: memFree=269288/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=410784 CPUtime=1.51 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 26433 0 1 0 135 16 0 0 25 0 9 0 5130019 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102696 25674 2901 10 0 96844 0 [pid=300/tid=302] ppid=32767 vsize=410784 CPUtime=0.83 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 6818 0 1 0 79 4 0 0 25 0 9 0 5130019 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=410784 CPUtime=0.62 /proc/300/task/303/stat : 303 (java) R 32767 32767 1511 34817 1511 4202560 18230 0 0 0 52 10 0 0 18 0 9 0 5130020 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4150736824 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=410784 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 5130020 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=410784 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 5130020 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=410784 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5130021 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=410784 CPUtime=0.02 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 447 0 0 0 2 0 0 0 16 0 9 0 5130021 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=410784 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 5130021 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=410784 CPUtime=0 /proc/300/task/309/stat : 309 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 5130021 420642816 25674 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.51 Current children cumulated vsize (KiB) 413356 [startup+3.10593 s] /proc/loadavg: 1.29 1.19 1.12 2/43 309 /proc/meminfo: memFree=194764/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=410916 CPUtime=3.1 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 48429 0 1 0 289 21 0 0 25 0 9 0 5130019 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102729 47664 2903 10 0 96877 0 [pid=300/tid=302] ppid=32767 vsize=410916 CPUtime=1.49 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 13638 0 1 0 143 6 0 0 25 0 9 0 5130019 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4114606605 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=410916 CPUtime=1.53 /proc/300/task/303/stat : 303 (java) S 32767 32767 1511 34817 1511 4202560 33398 0 0 0 141 12 0 0 16 0 9 0 5130020 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=410916 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 5130020 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=410916 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 5130020 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=410916 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 5130021 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=410916 CPUtime=0.04 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 455 0 0 0 4 0 0 0 15 0 9 0 5130021 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=410916 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 5130021 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=410916 CPUtime=0 /proc/300/task/309/stat : 309 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 5130021 420777984 47664 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 413488 [startup+6.30682 s] /proc/loadavg: 1.27 1.19 1.12 2/44 310 /proc/meminfo: memFree=82412/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=411516 CPUtime=6.31 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 66491 0 1 0 604 27 0 0 25 0 10 0 5130019 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102879 65638 2920 10 0 97024 0 [pid=300/tid=302] ppid=32767 vsize=411516 CPUtime=3.77 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 14547 0 1 0 371 6 0 0 25 0 10 0 5130019 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4114463010 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=411516 CPUtime=2.36 /proc/300/task/303/stat : 303 (java) S 32767 32767 1511 34817 1511 4202560 50450 0 0 0 218 18 0 0 16 0 10 0 5130020 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=411516 CPUtime=0.13 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 551 0 0 0 13 0 0 0 15 0 10 0 5130021 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/309/stat : 309 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5130021 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=310] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/310/stat : 310 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5130442 421392384 65638 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.31 Current children cumulated vsize (KiB) 414088 [startup+12.7087 s] /proc/loadavg: 1.24 1.18 1.12 3/44 310 /proc/meminfo: memFree=77088/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=411516 CPUtime=12.7 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 66501 0 1 0 1242 28 0 0 25 0 10 0 5130019 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102879 65648 2920 10 0 97024 0 [pid=300/tid=302] ppid=32767 vsize=411516 CPUtime=10.07 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 14548 0 1 0 1001 6 0 0 25 0 10 0 5130019 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4115359124 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=411516 CPUtime=2.45 /proc/300/task/303/stat : 303 (java) S 32767 32767 1511 34817 1511 4202560 50450 0 0 0 227 18 0 0 15 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=411516 CPUtime=0.15 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 560 0 0 0 13 2 0 0 15 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/309/stat : 309 (java) R 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=310] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/310/stat : 310 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5130442 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.7 Current children cumulated vsize (KiB) 414088 Solver just ended. Dumping a history of the last processes samples [startup+12.8087 s] /proc/loadavg: 1.24 1.18 1.12 3/44 310 /proc/meminfo: memFree=77088/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=411516 CPUtime=12.8 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 66501 0 1 0 1252 28 0 0 25 0 10 0 5130019 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102879 65648 2920 10 0 97024 0 [pid=300/tid=302] ppid=32767 vsize=411516 CPUtime=10.17 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 14548 0 1 0 1011 6 0 0 25 0 10 0 5130019 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4115335132 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=411516 CPUtime=2.45 /proc/300/task/303/stat : 303 (java) S 32767 32767 1511 34817 1511 4202560 50450 0 0 0 227 18 0 0 15 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=411516 CPUtime=0.15 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 560 0 0 0 13 2 0 0 15 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/309/stat : 309 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=310] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/310/stat : 310 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5130442 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.8 Current children cumulated vsize (KiB) 414088 [startup+13.6089 s] /proc/loadavg: 1.22 1.18 1.11 2/44 310 /proc/meminfo: memFree=77088/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=411516 CPUtime=13.6 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 66501 0 1 0 1332 28 0 0 25 0 10 0 5130019 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102879 65648 2920 10 0 97024 0 [pid=300/tid=302] ppid=32767 vsize=411516 CPUtime=10.97 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 14548 0 1 0 1091 6 0 0 25 0 10 0 5130019 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4115379322 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=411516 CPUtime=2.45 /proc/300/task/303/stat : 303 (java) S 32767 32767 1511 34817 1511 4202560 50450 0 0 0 227 18 0 0 16 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=411516 CPUtime=0.15 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 560 0 0 0 13 2 0 0 15 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/309/stat : 309 (java) R 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5130021 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=310] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/310/stat : 310 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5130442 421392384 65648 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 13.6 Current children cumulated vsize (KiB) 414088 [startup+14.009 s] /proc/loadavg: 1.22 1.18 1.11 2/44 310 /proc/meminfo: memFree=77088/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=411516 CPUtime=14 /proc/300/stat : 300 (java) S 32767 32767 1511 34817 1511 4202496 66502 0 1 0 1372 28 0 0 25 0 10 0 5130019 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 102879 65649 2920 10 0 97024 0 [pid=300/tid=302] ppid=32767 vsize=411516 CPUtime=11.37 /proc/300/task/302/stat : 302 (java) R 32767 32767 1511 34817 1511 4202560 14548 0 1 0 1131 6 0 0 25 0 10 0 5130019 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4115475794 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=300/tid=303] ppid=32767 vsize=411516 CPUtime=2.45 /proc/300/task/303/stat : 303 (java) S 32767 32767 1511 34817 1511 4202560 50450 0 0 0 227 18 0 0 16 0 10 0 5130020 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=304] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/304/stat : 304 (java) S 32767 32767 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=305] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/305/stat : 305 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 5130020 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=306] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/306/stat : 306 (java) S 32767 32767 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=307] ppid=32767 vsize=411516 CPUtime=0.15 /proc/300/task/307/stat : 307 (java) S 32767 32767 1511 34817 1511 4202560 561 0 0 0 13 2 0 0 15 0 10 0 5130021 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/308/stat : 308 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 5130021 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=309] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/309/stat : 309 (java) S 32767 32767 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 5130021 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=300/tid=310] ppid=32767 vsize=411516 CPUtime=0 /proc/300/task/310/stat : 310 (java) S 32767 32767 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 5130442 421392384 65649 1283457024 134512640 134550932 4288502496 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 14 Current children cumulated vsize (KiB) 414088 [startup+14.4022 s] /proc/loadavg: 1.22 1.18 1.11 2/44 310 /proc/meminfo: memFree=77088/1048576 swapFree=0/0 [pid=32767] ppid=32766 vsize=2572 CPUtime=0 /proc/32767/stat : 32767 (gj-trendy-solve) S 32766 32767 1511 34817 1511 4202496 373 0 0 0 0 0 0 0 18 0 1 0 5130018 2633728 269 1283457024 134512640 135304128 4286997072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32767/statm: 643 269 229 194 0 30 0 [pid=300] ppid=32767 vsize=0 CPUtime=14.38 /proc/300/stat : 300 (java) Z 32767 32767 1511 34817 1511 4202508 66515 0 1 0 1407 31 0 0 18 0 2 0 5130019 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/300/statm: 0 0 0 0 0 0 0 [pid=300/tid=308] ppid=32767 vsize=0 CPUtime=0.02 /proc/300/task/308/stat : 308 (java) R 32767 32767 1511 34817 1511 4203588 0 0 0 0 0 2 0 0 18 0 2 0 5130021 0 0 1283457024 0 0 0 0 0 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 14.38 Current children cumulated vsize (KiB) 2572 Child status: 0 Real time (s): 14.4037 CPU time (s): 14.3889 CPU user time (s): 14.0729 CPU system time (s): 0.316019 CPU usage (%): 99.8974 Max. virtual memory (cumulated for all children) (KiB): 414088 getrusage(RUSAGE_CHILDREN,...) data: user time used= 14.0729 system time used= 0.316019 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 66899 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= 1054 involuntary context switches= 1272 runsolver used 0 second user time and 0 second system time The end