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/rand485.cudf.s-e.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/incremental/s-e/rand485.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/rand485.cudf.s-e.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.18 1.06 1.01 4/34 14098 /proc/meminfo: memFree=492124/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=11048 CPUtime=0 /proc/14098/stat : 14098 (java) R 14097 14097 1511 34817 1511 4202496 692 0 0 0 0 0 0 0 25 0 1 0 3319857 11313152 428 1283457024 134512640 134550932 4287488560 18446744073709551615 4159743714 0 0 0 0 0 0 0 17 0 0 0 0 /proc/14098/statm: 2762 428 180 10 0 1104 0 [startup+0.165457 s] /proc/loadavg: 1.18 1.06 1.01 4/34 14098 /proc/meminfo: memFree=492124/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=409804 CPUtime=0.16 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 3981 0 1 0 15 1 0 0 25 0 9 0 3319857 419639296 3412 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102451 3412 1906 10 0 96599 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 412372 [startup+0.205454 s] /proc/loadavg: 1.18 1.06 1.01 4/34 14098 /proc/meminfo: memFree=492124/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=409804 CPUtime=0.2 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 4078 0 1 0 19 1 0 0 25 0 9 0 3319857 419639296 3509 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102451 3509 1906 10 0 96599 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 412372 [startup+0.305477 s] /proc/loadavg: 1.18 1.06 1.01 4/34 14098 /proc/meminfo: memFree=492124/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=410248 CPUtime=0.3 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 4431 0 1 0 29 1 0 0 25 0 9 0 3319857 420093952 3862 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102562 3862 1927 10 0 96710 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 412816 [startup+0.705583 s] /proc/loadavg: 1.18 1.06 1.01 4/34 14098 /proc/meminfo: memFree=492124/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=410688 CPUtime=0.7 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 12858 0 1 0 69 1 0 0 25 0 9 0 3319857 420544512 12104 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102672 12104 2895 10 0 96820 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 413256 [startup+1.50577 s] /proc/loadavg: 1.18 1.06 1.01 2/43 14107 /proc/meminfo: memFree=433948/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=410820 CPUtime=1.5 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 27234 0 1 0 145 5 0 0 25 0 9 0 3319857 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102705 26480 2896 10 0 96853 0 [pid=14098/tid=14100] ppid=14097 vsize=410820 CPUtime=0.78 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 6728 0 1 0 78 0 0 0 25 0 9 0 3319858 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=410820 CPUtime=0.66 /proc/14098/task/14101/stat : 14101 (java) R 14097 14097 1511 34817 1511 4202560 19125 0 0 0 63 3 0 0 19 0 9 0 3319858 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4150622221 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=410820 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 3319859 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=410820 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 3319859 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=410820 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 3319860 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=410820 CPUtime=0.03 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 438 0 0 0 3 0 0 0 16 0 9 0 3319860 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=410820 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 3319860 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=410820 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 9 0 3319860 420679680 26480 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 413388 [startup+3.10607 s] /proc/loadavg: 1.18 1.06 1.01 2/43 14107 /proc/meminfo: memFree=369096/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411156 CPUtime=3.1 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 39441 0 1 0 300 10 0 0 25 0 10 0 3319857 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102789 38605 2921 10 0 96934 0 [pid=14098/tid=14100] ppid=14097 vsize=411156 CPUtime=1.81 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 180 1 0 0 25 0 10 0 3319858 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4115272931 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411156 CPUtime=1.15 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 28249 0 0 0 108 7 0 0 16 0 10 0 3319858 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411156 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411156 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411156 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411156 CPUtime=0.12 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 530 0 0 0 12 0 0 0 15 0 10 0 3319860 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411156 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411156 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421023744 38605 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 413724 [startup+6.3068 s] /proc/loadavg: 1.16 1.06 1.01 2/44 14108 /proc/meminfo: memFree=340452/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411288 CPUtime=6.3 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 41780 0 1 0 620 10 0 0 25 0 10 0 3319857 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102822 40935 2915 10 0 96967 0 [pid=14098/tid=14100] ppid=14097 vsize=411288 CPUtime=4.75 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 474 1 0 0 25 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4115153834 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411288 CPUtime=1.4 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 30579 0 0 0 133 7 0 0 16 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411288 CPUtime=0.13 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 539 0 0 0 13 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14108] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14108/stat : 14108 (java) S 14097 14097 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3320106 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.3 Current children cumulated vsize (KiB) 413856 Solver just ended. Dumping a history of the last processes samples [startup+6.40682 s] /proc/loadavg: 1.16 1.06 1.01 2/44 14108 /proc/meminfo: memFree=340452/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411288 CPUtime=6.4 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 41780 0 1 0 630 10 0 0 25 0 10 0 3319857 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102822 40935 2915 10 0 96967 0 [pid=14098/tid=14100] ppid=14097 vsize=411288 CPUtime=4.85 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 484 1 0 0 25 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4115159034 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411288 CPUtime=1.4 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 30579 0 0 0 133 7 0 0 16 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411288 CPUtime=0.13 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 539 0 0 0 13 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14108] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14108/stat : 14108 (java) S 14097 14097 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3320106 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.4 Current children cumulated vsize (KiB) 413856 [startup+8.00713 s] /proc/loadavg: 1.16 1.06 1.01 2/44 14108 /proc/meminfo: memFree=340328/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411288 CPUtime=8 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 41780 0 1 0 790 10 0 0 25 0 10 0 3319857 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102822 40935 2915 10 0 96967 0 [pid=14098/tid=14100] ppid=14097 vsize=411288 CPUtime=6.45 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 644 1 0 0 25 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4115300118 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411288 CPUtime=1.4 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 30579 0 0 0 133 7 0 0 15 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411288 CPUtime=0.13 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 539 0 0 0 13 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14108] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14108/stat : 14108 (java) S 14097 14097 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3320106 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 8 Current children cumulated vsize (KiB) 413856 [startup+8.80729 s] /proc/loadavg: 1.15 1.06 1.01 2/44 14108 /proc/meminfo: memFree=340204/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411288 CPUtime=8.8 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 41780 0 1 0 870 10 0 0 25 0 10 0 3319857 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102822 40935 2915 10 0 96967 0 [pid=14098/tid=14100] ppid=14097 vsize=411288 CPUtime=7.25 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 724 1 0 0 25 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4115169728 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411288 CPUtime=1.4 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 30579 0 0 0 133 7 0 0 15 0 10 0 3319858 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411288 CPUtime=0.13 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 539 0 0 0 13 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14108] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14108/stat : 14108 (java) S 14097 14097 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3320106 421158912 40935 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 8.8 Current children cumulated vsize (KiB) 413856 [startup+9.20922 s] /proc/loadavg: 1.15 1.06 1.01 2/44 14108 /proc/meminfo: memFree=340204/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411288 CPUtime=9.2 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 41781 0 1 0 910 10 0 0 25 0 10 0 3319857 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102822 40936 2915 10 0 96967 0 [pid=14098/tid=14100] ppid=14097 vsize=411288 CPUtime=7.51 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 750 1 0 0 25 0 10 0 3319858 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411288 CPUtime=1.53 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 30579 0 0 0 146 7 0 0 15 0 10 0 3319858 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411288 CPUtime=0.14 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 540 0 0 0 14 0 0 0 15 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14108] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14108/stat : 14108 (java) S 14097 14097 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3320106 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 9.2 Current children cumulated vsize (KiB) 413856 [startup+9.40742 s] /proc/loadavg: 1.15 1.06 1.01 2/44 14108 /proc/meminfo: memFree=340204/1048576 swapFree=0/0 [pid=14097] ppid=14096 vsize=2568 CPUtime=0 /proc/14097/stat : 14097 (gj-trendy-solve) S 14096 14097 1511 34817 1511 4202496 371 0 0 0 0 0 0 0 18 0 1 0 3319857 2629632 269 1283457024 134512640 135304128 4292022096 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14097/statm: 642 269 229 194 0 29 0 [pid=14098] ppid=14097 vsize=411288 CPUtime=9.4 /proc/14098/stat : 14098 (java) S 14097 14097 1511 34817 1511 4202496 41781 0 1 0 930 10 0 0 25 0 10 0 3319857 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14098/statm: 102822 40936 2915 10 0 96967 0 [pid=14098/tid=14100] ppid=14097 vsize=411288 CPUtime=7.71 /proc/14098/task/14100/stat : 14100 (java) R 14097 14097 1511 34817 1511 4202560 9715 0 1 0 770 1 0 0 25 0 10 0 3319858 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4114348372 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14098/tid=14101] ppid=14097 vsize=411288 CPUtime=1.53 /proc/14098/task/14101/stat : 14101 (java) S 14097 14097 1511 34817 1511 4202560 30579 0 0 0 146 7 0 0 16 0 10 0 3319858 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 0 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14102] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14102/stat : 14102 (java) S 14097 14097 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14103] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14103/stat : 14103 (java) S 14097 14097 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 3319859 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14104] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14104/stat : 14104 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14105] ppid=14097 vsize=411288 CPUtime=0.14 /proc/14098/task/14105/stat : 14105 (java) S 14097 14097 1511 34817 1511 4202560 540 0 0 0 14 0 0 0 15 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14106] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14106/stat : 14106 (java) S 14097 14097 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14107] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14107/stat : 14107 (java) S 14097 14097 1511 34817 1511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 3319860 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 [pid=14098/tid=14108] ppid=14097 vsize=411288 CPUtime=0 /proc/14098/task/14108/stat : 14108 (java) S 14097 14097 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 3320106 421158912 40936 1283457024 134512640 134550932 4287488560 18446744073709551615 4294960130 0 4 0 16800975 18446612133097740160 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 9.4 Current children cumulated vsize (KiB) 413856 Child status: 0 Real time (s): 9.50269 CPU time (s): 9.48859 CPU user time (s): 9.38059 CPU system time (s): 0.108006 CPU usage (%): 99.8516 Max. virtual memory (cumulated for all children) (KiB): 413856 getrusage(RUSAGE_CHILDREN,...) data: user time used= 9.38059 system time used= 0.108006 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 42169 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= 906 involuntary context switches= 1044 runsolver used 0 second user time and 0 second system time The end