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/4e539b28-d46c-11df-8f4f-00163e3d3b7c.cudf.dudf-real.log.runsolver ./gj-trendy-solver-1.0 /home/misc2010/data/2011/dudf-real//4e539b28-d46c-11df-8f4f-00163e3d3b7c.cudf /home/misc2010/tmp/201108241238/gj-trendy-solver-1.0/4e539b28-d46c-11df-8f4f-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.01 1.17 1.11 4/34 22540 /proc/meminfo: memFree=328908/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=11040 CPUtime=0 /proc/22540/stat : 22540 (java) R 22539 22539 1511 34817 1511 4202496 656 0 0 0 0 0 0 0 25 0 1 0 4653483 11304960 393 1283457024 134512640 134550932 4294924544 18446744073709551615 4159977186 0 0 0 0 0 0 0 17 0 0 0 0 /proc/22540/statm: 2760 393 177 10 0 1102 0 [startup+0.155222 s] /proc/loadavg: 1.01 1.17 1.11 4/34 22540 /proc/meminfo: memFree=328908/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=409796 CPUtime=0.15 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 3965 0 1 0 11 4 0 0 25 0 9 0 4653483 419631104 3393 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102449 3393 1922 10 0 96597 0 Current children cumulated CPU time (s) 0.15 Current children cumulated vsize (KiB) 412360 [startup+0.205225 s] /proc/loadavg: 1.01 1.17 1.11 4/34 22540 /proc/meminfo: memFree=328908/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=409796 CPUtime=0.2 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 4075 0 1 0 16 4 0 0 25 0 9 0 4653483 419631104 3503 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102449 3503 1922 10 0 96597 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 412360 [startup+0.305254 s] /proc/loadavg: 1.01 1.17 1.11 4/34 22540 /proc/meminfo: memFree=328908/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=410380 CPUtime=0.3 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 4420 0 1 0 26 4 0 0 25 0 9 0 4653483 420229120 3848 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102595 3848 1944 10 0 96743 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 412944 [startup+0.705392 s] /proc/loadavg: 1.01 1.17 1.11 4/34 22540 /proc/meminfo: memFree=328908/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=410648 CPUtime=0.7 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 12111 0 1 0 62 8 0 0 25 0 9 0 4653483 420503552 11352 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102662 11352 2901 10 0 96810 0 Current children cumulated CPU time (s) 0.7 Current children cumulated vsize (KiB) 413212 [startup+1.5057 s] /proc/loadavg: 1.01 1.17 1.11 2/43 22549 /proc/meminfo: memFree=270172/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=410780 CPUtime=1.5 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 26405 0 1 0 133 17 0 0 25 0 9 0 4653483 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102695 25646 2901 10 0 96843 0 [pid=22540/tid=22542] ppid=22539 vsize=410780 CPUtime=0.82 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 6818 0 1 0 74 8 0 0 25 0 9 0 4653483 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=410780 CPUtime=0.62 /proc/22540/task/22543/stat : 22543 (java) R 22539 22539 1511 34817 1511 4202560 18202 0 0 0 54 8 0 0 18 0 9 0 4653484 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4150855608 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=410780 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 4653484 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=410780 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 4653484 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=410780 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4653485 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=410780 CPUtime=0.04 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 447 0 0 0 4 0 0 0 15 0 9 0 4653485 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=410780 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 4653486 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=410780 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 4653486 420638720 25646 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 413344 [startup+3.10942 s] /proc/loadavg: 1.01 1.17 1.11 2/43 22549 /proc/meminfo: memFree=195524/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=410912 CPUtime=3.1 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 48416 0 1 0 284 26 0 0 25 0 9 0 4653483 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102728 47651 2903 10 0 96876 0 [pid=22540/tid=22542] ppid=22539 vsize=410912 CPUtime=1.48 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 13625 0 1 0 135 13 0 0 25 0 9 0 4653483 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4114581041 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=410912 CPUtime=1.55 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 33398 0 0 0 143 12 0 0 16 0 9 0 4653484 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=410912 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 20 0 9 0 4653484 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=410912 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 20 0 9 0 4653484 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=410912 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4653485 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=410912 CPUtime=0.05 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 455 0 0 0 5 0 0 0 15 0 9 0 4653485 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=410912 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 4653486 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=410912 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) R 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 4653486 420773888 47651 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 413476 [startup+6.31081 s] /proc/loadavg: 1.01 1.17 1.11 2/44 22550 /proc/meminfo: memFree=83180/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=411512 CPUtime=6.28 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 66491 0 1 0 596 32 0 0 25 0 10 0 4653483 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102878 65638 2920 10 0 97023 0 [pid=22540/tid=22542] ppid=22539 vsize=411512 CPUtime=3.71 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 14547 0 1 0 358 13 0 0 25 0 10 0 4653483 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4115070060 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=411512 CPUtime=2.39 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 50450 0 0 0 222 17 0 0 16 0 10 0 4653484 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4653485 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=411512 CPUtime=0.17 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 551 0 0 0 16 1 0 0 15 0 10 0 4653485 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4653486 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 4653486 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22550] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22550/stat : 22550 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4653909 421388288 65638 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.28 Current children cumulated vsize (KiB) 414076 [startup+12.7025 s] /proc/loadavg: 1.01 1.17 1.11 2/44 22550 /proc/meminfo: memFree=77848/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=411512 CPUtime=12.65 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 66501 0 1 0 1233 32 0 0 25 0 10 0 4653483 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102878 65648 2920 10 0 97023 0 [pid=22540/tid=22542] ppid=22539 vsize=411512 CPUtime=9.99 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 14548 0 1 0 986 13 0 0 25 0 10 0 4653483 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4115386416 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=411512 CPUtime=2.47 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 50450 0 0 0 230 17 0 0 15 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4653485 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=411512 CPUtime=0.17 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 560 0 0 0 16 1 0 0 15 0 10 0 4653485 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4653486 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 4653486 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22550] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22550/stat : 22550 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4653909 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.65 Current children cumulated vsize (KiB) 414076 Solver just ended. Dumping a history of the last processes samples [startup+12.8125 s] /proc/loadavg: 1.01 1.17 1.11 2/44 22550 /proc/meminfo: memFree=77848/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=411512 CPUtime=12.76 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 66501 0 1 0 1244 32 0 0 25 0 10 0 4653483 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102878 65648 2920 10 0 97023 0 [pid=22540/tid=22542] ppid=22539 vsize=411512 CPUtime=10.11 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 14548 0 1 0 998 13 0 0 25 0 10 0 4653483 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4115497760 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=411512 CPUtime=2.47 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 50450 0 0 0 230 17 0 0 15 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4653485 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=411512 CPUtime=0.17 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 560 0 0 0 16 1 0 0 15 0 10 0 4653485 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4653486 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 4653486 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22550] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22550/stat : 22550 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4653909 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 12.76 Current children cumulated vsize (KiB) 414076 [startup+13.6127 s] /proc/loadavg: 1.01 1.16 1.11 3/44 22550 /proc/meminfo: memFree=77848/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=411512 CPUtime=13.56 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 66501 0 1 0 1324 32 0 0 25 0 10 0 4653483 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102878 65648 2920 10 0 97023 0 [pid=22540/tid=22542] ppid=22539 vsize=411512 CPUtime=10.91 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 14548 0 1 0 1078 13 0 0 25 0 10 0 4653483 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4115470616 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=411512 CPUtime=2.47 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 50450 0 0 0 230 17 0 0 16 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4653485 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=411512 CPUtime=0.17 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 560 0 0 0 16 1 0 0 15 0 10 0 4653485 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4653486 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 4653486 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22550] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22550/stat : 22550 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4653909 421388288 65648 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 13.56 Current children cumulated vsize (KiB) 414076 [startup+14.0128 s] /proc/loadavg: 1.01 1.16 1.11 3/44 22550 /proc/meminfo: memFree=77848/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=411512 CPUtime=13.96 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 66502 0 1 0 1364 32 0 0 25 0 10 0 4653483 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102878 65649 2920 10 0 97023 0 [pid=22540/tid=22542] ppid=22539 vsize=411512 CPUtime=11.31 /proc/22540/task/22542/stat : 22542 (java) R 22539 22539 1511 34817 1511 4202560 14548 0 1 0 1118 13 0 0 25 0 10 0 4653483 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4115594249 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=411512 CPUtime=2.47 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 50450 0 0 0 230 17 0 0 16 0 10 0 4653484 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4653484 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4653485 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=411512 CPUtime=0.17 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 561 0 0 0 16 1 0 0 15 0 10 0 4653485 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4653486 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 10 0 4653486 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22550] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22550/stat : 22550 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 25 0 10 0 4653909 421388288 65649 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 13.96 Current children cumulated vsize (KiB) 414076 [startup+14.4123 s] /proc/loadavg: 1.01 1.16 1.11 2/44 22550 /proc/meminfo: memFree=77848/1048576 swapFree=0/0 [pid=22539] ppid=22538 vsize=2564 CPUtime=0 /proc/22539/stat : 22539 (gj-trendy-solve) S 22538 22539 1511 34817 1511 4202496 370 0 0 0 0 0 0 0 18 0 1 0 4653483 2625536 268 1283457024 134512640 135304128 4292960256 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/22539/statm: 641 268 229 194 0 28 0 [pid=22540] ppid=22539 vsize=411512 CPUtime=14.35 /proc/22540/stat : 22540 (java) S 22539 22539 1511 34817 1511 4202496 66511 0 1 0 1403 32 0 0 25 0 9 0 4653483 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/22540/statm: 102878 65658 2921 10 0 97023 0 [pid=22540/tid=22542] ppid=22539 vsize=411512 CPUtime=11.66 /proc/22540/task/22542/stat : 22542 (java) S 22539 22539 1511 34817 1511 4202560 14548 0 1 0 1153 13 0 0 25 0 9 0 4653483 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22543] ppid=22539 vsize=411512 CPUtime=2.5 /proc/22540/task/22543/stat : 22543 (java) S 22539 22539 1511 34817 1511 4202560 50450 0 0 0 233 17 0 0 16 0 9 0 4653484 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 0 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22544] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22544/stat : 22544 (java) S 22539 22539 1511 34817 1511 4202560 15 0 0 0 0 0 0 0 18 0 9 0 4653484 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22545] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22545/stat : 22545 (java) S 22539 22539 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 9 0 4653484 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22546] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22546/stat : 22546 (java) S 22539 22539 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 9 0 4653485 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22547] ppid=22539 vsize=411512 CPUtime=0.17 /proc/22540/task/22547/stat : 22547 (java) S 22539 22539 1511 34817 1511 4202560 569 0 0 0 16 1 0 0 16 0 9 0 4653485 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22548] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22548/stat : 22548 (java) S 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 9 0 4653486 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 18446612132974982080 0 0 -1 0 0 0 0 [pid=22540/tid=22549] ppid=22539 vsize=411512 CPUtime=0 /proc/22540/task/22549/stat : 22549 (java) R 22539 22539 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 15 0 9 0 4653486 421388288 65658 1283457024 134512640 134550932 4294924544 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 14.35 Current children cumulated vsize (KiB) 414076 Child status: 0 Real time (s): 14.4311 CPU time (s): 14.3649 CPU user time (s): 14.0329 CPU system time (s): 0.33202 CPU usage (%): 99.5413 Max. virtual memory (cumulated for all children) (KiB): 414076 getrusage(RUSAGE_CHILDREN,...) data: user time used= 14.0329 system time used= 0.33202 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 66896 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= 1056 involuntary context switches= 1291 runsolver used 0 second user time and 0 second system time The end