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/201103031013/p2cudf-trendy-1.12/3889.cudf.caixa.log.runsolver ./p2cudf-trendy-1.12 /home/misc2010/data/misc4/caixa//3889.cudf /home/misc2010/tmp/201103031013/p2cudf-trendy-1.12/3889.cudf.caixa.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.77 1.66 1.56 5/37 9674 /proc/meminfo: memFree=294976/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2568 CPUtime=0 /proc/9673/stat : 9673 (p2cudf) R 9672 9672 29493 34817 29493 4202496 346 0 0 0 0 0 0 0 25 0 1 0 75966833 2629632 272 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/9673/statm: 642 272 231 194 0 29 0 [pid=9674] ppid=9673 vsize=2568 CPUtime=0 /proc/9674/stat : 9674 (p2cudf) R 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75966833 2629632 41 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/9674/statm: 642 41 0 194 0 29 0 [startup+0.175819 s] /proc/loadavg: 1.77 1.66 1.56 5/37 9674 /proc/meminfo: memFree=294976/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.205815 s] /proc/loadavg: 1.77 1.66 1.56 5/37 9674 /proc/meminfo: memFree=294976/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.305828 s] /proc/loadavg: 1.77 1.66 1.56 5/37 9674 /proc/meminfo: memFree=294976/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.705895 s] /proc/loadavg: 1.77 1.66 1.56 5/37 9674 /proc/meminfo: memFree=294976/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+1.50606 s] /proc/loadavg: 1.77 1.66 1.56 5/47 9695 /proc/meminfo: memFree=251796/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 [pid=9686] ppid=9673 vsize=880492 CPUtime=1.46 /proc/9686/stat : 9686 (java) S 9673 9672 29493 34817 29493 4202496 18695 0 1 0 136 10 0 0 25 0 10 0 75966837 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/9686/statm: 220123 14311 1730 10 0 217214 0 [pid=9686/tid=9687] ppid=9673 vsize=880492 CPUtime=0.69 /proc/9686/task/9687/stat : 9687 (java) R 9673 9672 29493 34817 29493 4202560 6867 0 1 0 65 4 0 0 25 0 10 0 75966837 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9688] ppid=9673 vsize=880492 CPUtime=0 /proc/9686/task/9688/stat : 9688 (java) S 9673 9672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 16 0 10 0 75966839 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9689] ppid=9673 vsize=880492 CPUtime=0 /proc/9686/task/9689/stat : 9689 (java) S 9673 9672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75966839 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9690] ppid=9673 vsize=880492 CPUtime=0 /proc/9686/task/9690/stat : 9690 (java) S 9673 9672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75966839 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9691] ppid=9673 vsize=880492 CPUtime=0 /proc/9686/task/9691/stat : 9691 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75966842 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9692] ppid=9673 vsize=880492 CPUtime=0.55 /proc/9686/task/9692/stat : 9692 (java) R 9673 9672 29493 34817 29493 4202560 9074 0 0 0 50 5 0 0 22 0 10 0 75966842 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4148457679 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9693] ppid=9673 vsize=880492 CPUtime=0.21 /proc/9686/task/9693/stat : 9693 (java) R 9673 9672 29493 34817 29493 4202560 1676 0 0 0 20 1 0 0 24 0 10 0 75966842 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4151185473 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9694] ppid=9673 vsize=880492 CPUtime=0 /proc/9686/task/9694/stat : 9694 (java) S 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75966842 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9695] ppid=9673 vsize=880492 CPUtime=0 /proc/9686/task/9695/stat : 9695 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75966842 901623808 14311 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 885632 [startup+3.10636 s] /proc/loadavg: 1.77 1.66 1.56 4/47 9695 /proc/meminfo: memFree=193516/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 [pid=9686] ppid=9673 vsize=878960 CPUtime=3.06 /proc/9686/stat : 9686 (java) S 9673 9672 29493 34817 29493 4202496 55199 0 1 0 286 20 0 0 25 0 10 0 75966837 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/9686/statm: 219740 49131 1743 10 0 216831 0 [pid=9686/tid=9687] ppid=9673 vsize=878960 CPUtime=1.5 /proc/9686/task/9687/stat : 9687 (java) R 9673 9672 29493 34817 29493 4202560 41926 0 1 0 137 13 0 0 25 0 10 0 75966837 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9688] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9688/stat : 9688 (java) S 9673 9672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 15 0 10 0 75966839 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9689] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9689/stat : 9689 (java) S 9673 9672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75966839 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9690] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9690/stat : 9690 (java) S 9673 9672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75966839 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9691] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9691/stat : 9691 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75966842 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9692] ppid=9673 vsize=878960 CPUtime=1.05 /proc/9686/task/9692/stat : 9692 (java) R 9673 9672 29493 34817 29493 4202560 10492 0 0 0 100 5 0 0 20 0 10 0 75966842 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4151346948 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9693] ppid=9673 vsize=878960 CPUtime=0.49 /proc/9686/task/9693/stat : 9693 (java) R 9673 9672 29493 34817 29493 4202560 1703 0 0 0 48 1 0 0 18 0 10 0 75966842 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4149552278 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9694] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9694/stat : 9694 (java) S 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75966842 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9695] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9695/stat : 9695 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75966842 900055040 49131 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 884100 Solver just ended. Dumping a history of the last processes samples [startup+3.20642 s] /proc/loadavg: 1.77 1.66 1.56 4/47 9695 /proc/meminfo: memFree=193516/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 [pid=9686] ppid=9673 vsize=878960 CPUtime=3.16 /proc/9686/stat : 9686 (java) S 9673 9672 29493 34817 29493 4202496 59646 0 1 0 296 20 0 0 25 0 10 0 75966837 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/9686/statm: 219740 53578 1743 10 0 216831 0 [pid=9686/tid=9687] ppid=9673 vsize=878960 CPUtime=1.56 /proc/9686/task/9687/stat : 9687 (java) R 9673 9672 29493 34817 29493 4202560 46366 0 1 0 143 13 0 0 25 0 10 0 75966837 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4095376957 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9688] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9688/stat : 9688 (java) S 9673 9672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 15 0 10 0 75966839 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9689] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9689/stat : 9689 (java) S 9673 9672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75966839 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9690] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9690/stat : 9690 (java) S 9673 9672 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75966839 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9691] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9691/stat : 9691 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75966842 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9692] ppid=9673 vsize=878960 CPUtime=1.09 /proc/9686/task/9692/stat : 9692 (java) S 9673 9672 29493 34817 29493 4202560 10497 0 0 0 104 5 0 0 20 0 10 0 75966842 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9693] ppid=9673 vsize=878960 CPUtime=0.49 /proc/9686/task/9693/stat : 9693 (java) S 9673 9672 29493 34817 29493 4202560 1705 0 0 0 48 1 0 0 18 0 10 0 75966842 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9694] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9694/stat : 9694 (java) S 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75966842 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9695] ppid=9673 vsize=878960 CPUtime=0 /proc/9686/task/9695/stat : 9695 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75966842 900055040 53578 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.19 Current children cumulated vsize (KiB) 884100 [startup+4.01307 s] /proc/loadavg: 1.77 1.66 1.56 3/47 9695 /proc/meminfo: memFree=80924/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 [pid=9686] ppid=9673 vsize=879668 CPUtime=3.96 /proc/9686/stat : 9686 (java) S 9673 9672 29493 34817 29493 4202496 79621 0 1 0 366 30 0 0 25 0 10 0 75966837 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/9686/statm: 219917 73410 1748 10 0 217008 0 [pid=9686/tid=9687] ppid=9673 vsize=879668 CPUtime=1.68 /proc/9686/task/9687/stat : 9687 (java) R 9673 9672 29493 34817 29493 4202560 50180 0 1 0 152 16 0 0 25 0 10 0 75966837 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9688] ppid=9673 vsize=879668 CPUtime=0.38 /proc/9686/task/9688/stat : 9688 (java) S 9673 9672 29493 34817 29493 4202560 15821 0 0 0 32 6 0 0 16 0 10 0 75966839 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9689] ppid=9673 vsize=879668 CPUtime=0 /proc/9686/task/9689/stat : 9689 (java) S 9673 9672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 75966839 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9690] ppid=9673 vsize=879668 CPUtime=0 /proc/9686/task/9690/stat : 9690 (java) S 9673 9672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75966839 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9691] ppid=9673 vsize=879668 CPUtime=0 /proc/9686/task/9691/stat : 9691 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75966842 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9692] ppid=9673 vsize=879668 CPUtime=1.3 /proc/9686/task/9692/stat : 9692 (java) R 9673 9672 29493 34817 29493 4202560 10832 0 0 0 124 6 0 0 20 0 10 0 75966842 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4151089401 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=9686/tid=9693] ppid=9673 vsize=879668 CPUtime=0.58 /proc/9686/task/9693/stat : 9693 (java) S 9673 9672 29493 34817 29493 4202560 1714 0 0 0 57 1 0 0 15 0 10 0 75966842 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9694] ppid=9673 vsize=879668 CPUtime=0 /proc/9686/task/9694/stat : 9694 (java) S 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75966842 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9695] ppid=9673 vsize=879668 CPUtime=0 /proc/9686/task/9695/stat : 9695 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75966842 900780032 73410 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.99 Current children cumulated vsize (KiB) 884808 [startup+4.80606 s] /proc/loadavg: 1.87 1.69 1.57 2/47 9695 /proc/meminfo: memFree=7524/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 [pid=9686] ppid=9673 vsize=879284 CPUtime=4.42 /proc/9686/stat : 9686 (java) S 9673 9672 29493 34817 29493 4202496 80050 0 1 0 412 30 0 0 25 0 11 0 75966837 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/9686/statm: 219821 73298 1761 10 0 216912 0 [pid=9686/tid=9687] ppid=9673 vsize=879284 CPUtime=1.85 /proc/9686/task/9687/stat : 9687 (java) S 9673 9672 29493 34817 29493 4202560 50192 0 1 0 169 16 0 0 25 0 11 0 75966837 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9688] ppid=9673 vsize=879284 CPUtime=0.38 /proc/9686/task/9688/stat : 9688 (java) S 9673 9672 29493 34817 29493 4202560 15821 0 0 0 32 6 0 0 16 0 11 0 75966839 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9689] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9689/stat : 9689 (java) S 9673 9672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75966839 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9690] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9690/stat : 9690 (java) S 9673 9672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75966839 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9691] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9691/stat : 9691 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75966842 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9692] ppid=9673 vsize=879284 CPUtime=1.48 /proc/9686/task/9692/stat : 9692 (java) S 9673 9672 29493 34817 29493 4202560 11219 0 0 0 142 6 0 0 20 0 11 0 75966842 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9693] ppid=9673 vsize=879284 CPUtime=0.69 /proc/9686/task/9693/stat : 9693 (java) S 9673 9672 29493 34817 29493 4202560 1740 0 0 0 68 1 0 0 16 0 11 0 75966842 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9694] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9694/stat : 9694 (java) S 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 75966842 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9695] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9695/stat : 9695 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75966842 900386816 73298 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.45 Current children cumulated vsize (KiB) 884424 [startup+5.00605 s] /proc/loadavg: 1.87 1.69 1.57 2/47 9695 /proc/meminfo: memFree=7524/1048576 swapFree=0/0 [pid=9672] ppid=9671 vsize=2568 CPUtime=0 /proc/9672/stat : 9672 (p2cudf-trendy-1) S 9671 9672 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75966833 2629632 270 1283457024 134512640 135304128 4293173376 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9672/statm: 642 270 230 194 0 29 0 [pid=9673] ppid=9672 vsize=2572 CPUtime=0.03 /proc/9673/stat : 9673 (p2cudf) S 9672 9672 29493 34817 29493 4202496 393 3009 0 1 1 0 2 0 25 0 1 0 75966833 2633728 281 1283457024 134512640 135304128 4289287360 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/9673/statm: 643 281 239 194 0 30 0 [pid=9686] ppid=9673 vsize=879284 CPUtime=4.42 /proc/9686/stat : 9686 (java) S 9673 9672 29493 34817 29493 4202496 80052 0 1 0 412 30 0 0 25 0 10 0 75966837 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/9686/statm: 219821 73300 1763 10 0 216912 0 [pid=9686/tid=9687] ppid=9673 vsize=879284 CPUtime=1.85 /proc/9686/task/9687/stat : 9687 (java) S 9673 9672 29493 34817 29493 4202560 50192 0 1 0 169 16 0 0 20 0 10 0 75966837 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9688] ppid=9673 vsize=879284 CPUtime=0.38 /proc/9686/task/9688/stat : 9688 (java) S 9673 9672 29493 34817 29493 4202560 15822 0 0 0 32 6 0 0 16 0 10 0 75966839 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 0 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9689] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9689/stat : 9689 (java) S 9673 9672 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 75966839 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9690] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9690/stat : 9690 (java) S 9673 9672 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75966839 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9691] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9691/stat : 9691 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75966842 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9692] ppid=9673 vsize=879284 CPUtime=1.48 /proc/9686/task/9692/stat : 9692 (java) S 9673 9672 29493 34817 29493 4202560 11219 0 0 0 142 6 0 0 20 0 10 0 75966842 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9693] ppid=9673 vsize=879284 CPUtime=0.69 /proc/9686/task/9693/stat : 9693 (java) S 9673 9672 29493 34817 29493 4202560 1740 0 0 0 68 1 0 0 16 0 10 0 75966842 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9694] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9694/stat : 9694 (java) S 9673 9672 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75966842 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 [pid=9686/tid=9695] ppid=9673 vsize=879284 CPUtime=0 /proc/9686/task/9695/stat : 9695 (java) S 9673 9672 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75966842 900386816 73300 1283457024 134512640 134550932 4292691616 18446744073709551615 4294960130 0 4 0 16800975 18446612133395009856 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.45 Current children cumulated vsize (KiB) 884424 Child status: 0 Real time (s): 5.06432 CPU time (s): 4.48828 CPU user time (s): 4.16026 CPU system time (s): 0.32802 CPU usage (%): 88.6256 Max. virtual memory (cumulated for all children) (KiB): 887364 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.16026 system time used= 0.32802 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 83851 page faults= 2 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 412 involuntary context switches= 393 runsolver used 0 second user time and 0 second system time The end