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/201108291842/p2cudf-user-1.13/rand172.cudf.user-upgrades.log.runsolver ./p2cudf-user-1.13 /home/misc2010/data/2011/user-upgrades/rand172.cudf /home/misc2010/tmp/201108291842/p2cudf-user-1.13/rand172.cudf.user-upgrades.result -notuptodate,-removed,-changed Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 seconds Enforcing VSIZE limit (soft limit, will send signal-name then SIGKILL): 1150976 KiB Enforcing VSIZE limit (hard limit, stack expansion will fail with SIGSEGV, brk() and mmap() will return ENOMEM): 1202176 KiB Current StackSize limit: 8192 KiB [startup+0 s] /proc/loadavg: 1.65 1.20 0.94 5/39 18869 /proc/meminfo: memFree=730592/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2572 CPUtime=0 /proc/18868/stat : 18868 (p2cudf) R 18867 18867 17511 34816 17511 4202496 346 0 0 0 0 0 0 0 25 0 1 0 46024564 2633728 272 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/18868/statm: 643 272 231 194 0 30 0 [pid=18869] ppid=18868 vsize=2572 CPUtime=0 /proc/18869/stat : 18869 (p2cudf) R 18868 18867 17511 34816 17511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 46024564 2633728 41 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/18869/statm: 643 41 0 194 0 30 0 [startup+0.115603 s] /proc/loadavg: 1.65 1.20 0.94 5/39 18869 /proc/meminfo: memFree=730592/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+0.205616 s] /proc/loadavg: 1.65 1.20 0.94 5/39 18869 /proc/meminfo: memFree=730592/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+0.30563 s] /proc/loadavg: 1.65 1.20 0.94 5/39 18869 /proc/meminfo: memFree=730592/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+0.70584 s] /proc/loadavg: 1.65 1.20 0.94 5/39 18869 /proc/meminfo: memFree=730592/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+1.50596 s] /proc/loadavg: 1.65 1.20 0.94 5/49 18890 /proc/meminfo: memFree=678716/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 [pid=18881] ppid=18868 vsize=896620 CPUtime=1.46 /proc/18881/stat : 18881 (java) S 18868 18867 17511 34816 17511 4202496 22168 0 1 0 137 9 0 0 25 0 10 0 46024568 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18881/statm: 224155 18811 1720 10 0 221246 0 [pid=18881/tid=18882] ppid=18868 vsize=896620 CPUtime=0.78 /proc/18881/task/18882/stat : 18882 (java) R 18868 18867 17511 34816 17511 4202560 8257 0 1 0 72 6 0 0 25 0 10 0 46024568 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4095064803 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18881/tid=18883] ppid=18868 vsize=896620 CPUtime=0 /proc/18881/task/18883/stat : 18883 (java) S 18868 18867 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 16 0 10 0 46024570 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18884] ppid=18868 vsize=896620 CPUtime=0 /proc/18881/task/18884/stat : 18884 (java) S 18868 18867 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 46024570 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18885] ppid=18868 vsize=896620 CPUtime=0 /proc/18881/task/18885/stat : 18885 (java) S 18868 18867 17511 34816 17511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 46024570 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18886] ppid=18868 vsize=896620 CPUtime=0 /proc/18881/task/18886/stat : 18886 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46024573 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18887] ppid=18868 vsize=896620 CPUtime=0.32 /proc/18881/task/18887/stat : 18887 (java) S 18868 18867 17511 34816 17511 4202560 5622 0 0 0 30 2 0 0 21 0 10 0 46024573 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18888] ppid=18868 vsize=896620 CPUtime=0.34 /proc/18881/task/18888/stat : 18888 (java) R 18868 18867 17511 34816 17511 4202560 7209 0 0 0 34 0 0 0 25 0 10 0 46024573 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18881/tid=18889] ppid=18868 vsize=896620 CPUtime=0 /proc/18881/task/18889/stat : 18889 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46024573 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18890] ppid=18868 vsize=896620 CPUtime=0 /proc/18881/task/18890/stat : 18890 (java) S 18868 18867 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46024573 918138880 18811 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 901768 [startup+3.10633 s] /proc/loadavg: 1.65 1.20 0.94 2/49 18890 /proc/meminfo: memFree=585468/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 [pid=18881] ppid=18868 vsize=893676 CPUtime=3.06 /proc/18881/stat : 18881 (java) S 18868 18867 17511 34816 17511 4202496 75092 0 1 0 279 27 0 0 25 0 10 0 46024568 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18881/statm: 223419 71549 1732 10 0 220510 0 [pid=18881/tid=18882] ppid=18868 vsize=893676 CPUtime=1.8 /proc/18881/task/18882/stat : 18882 (java) R 18868 18867 17511 34816 17511 4202560 50039 0 1 0 158 22 0 0 25 0 10 0 46024568 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4095274704 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=18881/tid=18883] ppid=18868 vsize=893676 CPUtime=0.22 /proc/18881/task/18883/stat : 18883 (java) S 18868 18867 17511 34816 17511 4202560 10770 0 0 0 20 2 0 0 16 0 10 0 46024570 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18884] ppid=18868 vsize=893676 CPUtime=0 /proc/18881/task/18884/stat : 18884 (java) S 18868 18867 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 10 0 46024570 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18885] ppid=18868 vsize=893676 CPUtime=0 /proc/18881/task/18885/stat : 18885 (java) S 18868 18867 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 46024570 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18886] ppid=18868 vsize=893676 CPUtime=0 /proc/18881/task/18886/stat : 18886 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46024573 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18887] ppid=18868 vsize=893676 CPUtime=0.57 /proc/18881/task/18887/stat : 18887 (java) S 18868 18867 17511 34816 17511 4202560 5965 0 0 0 55 2 0 0 16 0 10 0 46024573 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18888] ppid=18868 vsize=893676 CPUtime=0.44 /proc/18881/task/18888/stat : 18888 (java) S 18868 18867 17511 34816 17511 4202560 7242 0 0 0 44 0 0 0 15 0 10 0 46024573 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18889] ppid=18868 vsize=893676 CPUtime=0 /proc/18881/task/18889/stat : 18889 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 46024573 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18890] ppid=18868 vsize=893676 CPUtime=0 /proc/18881/task/18890/stat : 18890 (java) S 18868 18867 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 10 0 46024573 915124224 71549 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 898824 [startup+6.30592 s] /proc/loadavg: 1.60 1.19 0.94 2/48 18891 /proc/meminfo: memFree=449488/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 [pid=18881] ppid=18868 vsize=894324 CPUtime=6.03 /proc/18881/stat : 18881 (java) S 18868 18867 17511 34816 17511 4202496 76233 0 1 0 575 28 0 0 25 0 11 0 46024568 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18881/statm: 223581 71990 1777 10 0 220672 0 [pid=18881/tid=18882] ppid=18868 vsize=894324 CPUtime=3.02 /proc/18881/task/18882/stat : 18882 (java) S 18868 18867 17511 34816 17511 4202560 50285 0 1 0 279 23 0 0 25 0 11 0 46024568 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18883] ppid=18868 vsize=894324 CPUtime=0.22 /proc/18881/task/18883/stat : 18883 (java) S 18868 18867 17511 34816 17511 4202560 10770 0 0 0 20 2 0 0 16 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18884] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18884/stat : 18884 (java) S 18868 18867 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18885] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18885/stat : 18885 (java) S 18868 18867 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18886] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18886/stat : 18886 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18887] ppid=18868 vsize=894324 CPUtime=0.57 /proc/18881/task/18887/stat : 18887 (java) S 18868 18867 17511 34816 17511 4202560 5965 0 0 0 55 2 0 0 16 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18888] ppid=18868 vsize=894324 CPUtime=2.19 /proc/18881/task/18888/stat : 18888 (java) S 18868 18867 17511 34816 17511 4202560 8127 0 0 0 219 0 0 0 16 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18889] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18889/stat : 18889 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18890] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18890/stat : 18890 (java) S 18868 18867 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.06 Current children cumulated vsize (KiB) 899472 Solver just ended. Dumping a history of the last processes samples [startup+6.40593 s] /proc/loadavg: 1.60 1.19 0.94 2/48 18891 /proc/meminfo: memFree=449488/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 [pid=18881] ppid=18868 vsize=894324 CPUtime=6.03 /proc/18881/stat : 18881 (java) S 18868 18867 17511 34816 17511 4202496 76233 0 1 0 575 28 0 0 25 0 11 0 46024568 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18881/statm: 223581 71990 1777 10 0 220672 0 [pid=18881/tid=18882] ppid=18868 vsize=894324 CPUtime=3.02 /proc/18881/task/18882/stat : 18882 (java) S 18868 18867 17511 34816 17511 4202560 50285 0 1 0 279 23 0 0 25 0 11 0 46024568 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18883] ppid=18868 vsize=894324 CPUtime=0.22 /proc/18881/task/18883/stat : 18883 (java) S 18868 18867 17511 34816 17511 4202560 10770 0 0 0 20 2 0 0 16 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18884] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18884/stat : 18884 (java) S 18868 18867 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18885] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18885/stat : 18885 (java) S 18868 18867 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18886] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18886/stat : 18886 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18887] ppid=18868 vsize=894324 CPUtime=0.57 /proc/18881/task/18887/stat : 18887 (java) S 18868 18867 17511 34816 17511 4202560 5965 0 0 0 55 2 0 0 16 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18888] ppid=18868 vsize=894324 CPUtime=2.19 /proc/18881/task/18888/stat : 18888 (java) S 18868 18867 17511 34816 17511 4202560 8127 0 0 0 219 0 0 0 16 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18889] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18889/stat : 18889 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18890] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18890/stat : 18890 (java) S 18868 18867 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.06 Current children cumulated vsize (KiB) 899472 [startup+6.60796 s] /proc/loadavg: 1.60 1.19 0.94 2/48 18892 /proc/meminfo: memFree=449364/1048576 swapFree=0/0 [pid=18867] ppid=18866 vsize=2572 CPUtime=0 /proc/18867/stat : 18867 (p2cudf-user-1.1) S 18866 18867 17511 34816 17511 4202496 375 0 0 0 0 0 0 0 18 0 1 0 46024564 2633728 270 1283457024 134512640 135304128 4287541984 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18867/statm: 643 270 230 194 0 30 0 [pid=18868] ppid=18867 vsize=2576 CPUtime=0.03 /proc/18868/stat : 18868 (p2cudf) S 18867 18867 17511 34816 17511 4202496 393 3013 0 1 0 0 3 0 25 0 1 0 46024564 2637824 281 1283457024 134512640 135304128 4290471392 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/18868/statm: 644 281 239 194 0 31 0 [pid=18881] ppid=18868 vsize=894324 CPUtime=6.05 /proc/18881/stat : 18881 (java) S 18868 18867 17511 34816 17511 4202496 76233 0 1 0 577 28 0 0 25 0 11 0 46024568 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/18881/statm: 223581 71990 1777 10 0 220672 0 [pid=18881/tid=18882] ppid=18868 vsize=894324 CPUtime=3.02 /proc/18881/task/18882/stat : 18882 (java) S 18868 18867 17511 34816 17511 4202560 50285 0 1 0 279 23 0 0 25 0 11 0 46024568 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18883] ppid=18868 vsize=894324 CPUtime=0.22 /proc/18881/task/18883/stat : 18883 (java) S 18868 18867 17511 34816 17511 4202560 10770 0 0 0 20 2 0 0 16 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 0 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18884] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18884/stat : 18884 (java) S 18868 18867 17511 34816 17511 4202560 8 0 0 0 0 0 0 0 18 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18885] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18885/stat : 18885 (java) S 18868 18867 17511 34816 17511 4202560 6 0 0 0 0 0 0 0 18 0 11 0 46024570 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18886] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18886/stat : 18886 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18887] ppid=18868 vsize=894324 CPUtime=0.57 /proc/18881/task/18887/stat : 18887 (java) S 18868 18867 17511 34816 17511 4202560 5965 0 0 0 55 2 0 0 16 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18888] ppid=18868 vsize=894324 CPUtime=2.2 /proc/18881/task/18888/stat : 18888 (java) S 18868 18867 17511 34816 17511 4202560 8127 0 0 0 220 0 0 0 15 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18889] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18889/stat : 18889 (java) S 18868 18867 17511 34816 17511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18890] ppid=18868 vsize=894324 CPUtime=0 /proc/18881/task/18890/stat : 18890 (java) S 18868 18867 17511 34816 17511 4202560 2 0 0 0 0 0 0 0 15 0 11 0 46024573 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4294960130 0 4 0 16800975 18446612133395904512 0 0 -1 0 0 0 0 [pid=18881/tid=18892] ppid=18868 vsize=894324 CPUtime=0.01 /proc/18881/task/18892/stat : 18892 (java) R 18868 18867 17511 34816 17511 4202560 5 0 0 0 1 0 0 0 20 0 11 0 46025172 915787776 71990 1283457024 134512640 134550932 4293371712 18446744073709551615 4094959516 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.08 Current children cumulated vsize (KiB) 899472 Child status: 0 Real time (s): 6.6593 CPU time (s): 6.12838 CPU user time (s): 5.81636 CPU system time (s): 0.312019 CPU usage (%): 92.0274 Max. virtual memory (cumulated for all children) (KiB): 901908 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.81636 system time used= 0.312019 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 80038 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= 723 involuntary context switches= 716 runsolver used 0 second user time and 0 second system time The end