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-paranoid-1.12/1021.cudf.caixa.log.runsolver ./p2cudf-paranoid-1.12 /home/misc2010/data/misc4/caixa//1021.cudf /home/misc2010/tmp/201103031013/p2cudf-paranoid-1.12/1021.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.17 1.09 0.83 5/41 7977 /proc/meminfo: memFree=709040/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2568 CPUtime=0 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 352 0 0 0 0 0 0 0 18 0 1 0 74761075 2629632 271 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/7975/statm: 642 271 231 194 0 29 0 [pid=7976] ppid=7975 vsize=2568 CPUtime=0 /proc/7976/stat : 7976 (p2cudf) R 7975 7974 29493 34817 29493 4202560 94 0 0 0 0 0 0 0 25 0 1 0 74761075 2629632 118 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/7976/statm: 642 118 77 194 0 29 0 [pid=7977] ppid=7976 vsize=2568 CPUtime=0 /proc/7977/stat : 7977 (p2cudf) R 7976 7974 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 74761075 2629632 41 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/7977/statm: 642 41 0 194 0 29 0 [startup+0.165829 s] /proc/loadavg: 1.17 1.09 0.83 5/41 7977 /proc/meminfo: memFree=709040/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5140 [startup+0.205831 s] /proc/loadavg: 1.17 1.09 0.83 5/41 7977 /proc/meminfo: memFree=709040/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5140 [startup+0.305859 s] /proc/loadavg: 1.17 1.09 0.83 5/41 7977 /proc/meminfo: memFree=709040/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5140 [startup+0.705948 s] /proc/loadavg: 1.17 1.09 0.83 5/41 7977 /proc/meminfo: memFree=709040/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5140 [startup+1.50617 s] /proc/loadavg: 1.24 1.11 0.84 3/50 7997 /proc/meminfo: memFree=671952/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 [pid=7988] ppid=7975 vsize=878264 CPUtime=1.45 /proc/7988/stat : 7988 (java) S 7975 7974 29493 34817 29493 4202496 16917 0 1 0 131 14 0 0 25 0 10 0 74761078 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/7988/statm: 219566 13079 1725 10 0 216657 0 [pid=7988/tid=7989] ppid=7975 vsize=878264 CPUtime=0.62 /proc/7988/task/7989/stat : 7989 (java) R 7975 7974 29493 34817 29493 4202560 6177 0 1 0 56 6 0 0 25 0 10 0 74761079 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4095206006 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=7988/tid=7990] ppid=7975 vsize=878264 CPUtime=0 /proc/7988/task/7990/stat : 7990 (java) S 7975 7974 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 16 0 10 0 74761080 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7991] ppid=7975 vsize=878264 CPUtime=0 /proc/7988/task/7991/stat : 7991 (java) S 7975 7974 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 74761081 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7992] ppid=7975 vsize=878264 CPUtime=0 /proc/7988/task/7992/stat : 7992 (java) S 7975 7974 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 74761081 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7993] ppid=7975 vsize=878264 CPUtime=0 /proc/7988/task/7993/stat : 7993 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74761083 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7994] ppid=7975 vsize=878264 CPUtime=0.55 /proc/7988/task/7994/stat : 7994 (java) R 7975 7974 29493 34817 29493 4202560 8206 0 0 0 47 8 0 0 19 0 10 0 74761083 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4150053699 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=7988/tid=7995] ppid=7975 vsize=878264 CPUtime=0.27 /proc/7988/task/7995/stat : 7995 (java) R 7975 7974 29493 34817 29493 4202560 1453 0 0 0 27 0 0 0 23 0 10 0 74761083 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4151693443 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=7988/tid=7996] ppid=7975 vsize=878264 CPUtime=0 /proc/7988/task/7996/stat : 7996 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 74761083 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7997] ppid=7975 vsize=878264 CPUtime=0 /proc/7988/task/7997/stat : 7997 (java) S 7975 7974 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 74761083 899342336 13079 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 883404 [startup+3.106 s] /proc/loadavg: 1.24 1.11 0.84 4/50 7997 /proc/meminfo: memFree=604744/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 [pid=7988] ppid=7975 vsize=879172 CPUtime=2.86 /proc/7988/stat : 7988 (java) S 7975 7974 29493 34817 29493 4202496 55635 0 1 0 259 27 0 0 25 0 11 0 74761078 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/7988/statm: 219793 51040 1744 10 0 216884 0 [pid=7988/tid=7989] ppid=7975 vsize=879172 CPUtime=1.48 /proc/7988/task/7989/stat : 7989 (java) S 7975 7974 29493 34817 29493 4202560 43867 0 1 0 130 18 0 0 25 0 11 0 74761079 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7990] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7990/stat : 7990 (java) S 7975 7974 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 11 0 74761080 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7991] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7991/stat : 7991 (java) S 7975 7974 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 11 0 74761081 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7992] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7992/stat : 7992 (java) S 7975 7974 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74761081 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7993] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7993/stat : 7993 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7994] ppid=7975 vsize=879172 CPUtime=0.94 /proc/7988/task/7994/stat : 7994 (java) S 7975 7974 29493 34817 29493 4202560 9212 0 0 0 85 9 0 0 15 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7995] ppid=7975 vsize=879172 CPUtime=0.42 /proc/7988/task/7995/stat : 7995 (java) S 7975 7974 29493 34817 29493 4202560 1471 0 0 0 42 0 0 0 24 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7996] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7996/stat : 7996 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7997] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7997/stat : 7997 (java) S 7975 7974 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 2.9 Current children cumulated vsize (KiB) 884312 Solver just ended. Dumping a history of the last processes samples [startup+3.20603 s] /proc/loadavg: 1.24 1.11 0.84 4/50 7997 /proc/meminfo: memFree=604744/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 [pid=7988] ppid=7975 vsize=879172 CPUtime=2.86 /proc/7988/stat : 7988 (java) S 7975 7974 29493 34817 29493 4202496 55635 0 1 0 259 27 0 0 25 0 11 0 74761078 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/7988/statm: 219793 51040 1744 10 0 216884 0 [pid=7988/tid=7989] ppid=7975 vsize=879172 CPUtime=1.48 /proc/7988/task/7989/stat : 7989 (java) S 7975 7974 29493 34817 29493 4202560 43867 0 1 0 130 18 0 0 25 0 11 0 74761079 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7990] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7990/stat : 7990 (java) S 7975 7974 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 11 0 74761080 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7991] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7991/stat : 7991 (java) S 7975 7974 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 11 0 74761081 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7992] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7992/stat : 7992 (java) S 7975 7974 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74761081 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7993] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7993/stat : 7993 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7994] ppid=7975 vsize=879172 CPUtime=0.94 /proc/7988/task/7994/stat : 7994 (java) S 7975 7974 29493 34817 29493 4202560 9212 0 0 0 85 9 0 0 15 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7995] ppid=7975 vsize=879172 CPUtime=0.42 /proc/7988/task/7995/stat : 7995 (java) S 7975 7974 29493 34817 29493 4202560 1471 0 0 0 42 0 0 0 24 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7996] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7996/stat : 7996 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7997] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7997/stat : 7997 (java) S 7975 7974 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 2.9 Current children cumulated vsize (KiB) 884312 [startup+3.40602 s] /proc/loadavg: 1.24 1.11 0.84 2/51 7998 /proc/meminfo: memFree=511240/1048576 swapFree=0/0 [pid=7974] ppid=7973 vsize=2564 CPUtime=0 /proc/7974/stat : 7974 (p2cudf-paranoid) S 7973 7974 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 74761074 2625536 270 1283457024 134512640 135304128 4294314384 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7974/statm: 641 270 230 194 0 28 0 [pid=7975] ppid=7974 vsize=2576 CPUtime=0.04 /proc/7975/stat : 7975 (p2cudf) S 7974 7974 29493 34817 29493 4202496 393 3015 0 1 0 0 2 2 25 0 1 0 74761075 2637824 281 1283457024 134512640 135304128 4293044336 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/7975/statm: 644 281 239 194 0 31 0 [pid=7988] ppid=7975 vsize=879172 CPUtime=2.86 /proc/7988/stat : 7988 (java) S 7975 7974 29493 34817 29493 4202496 55635 0 1 0 259 27 0 0 25 0 11 0 74761078 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/7988/statm: 219793 51040 1744 10 0 216884 0 [pid=7988/tid=7989] ppid=7975 vsize=879172 CPUtime=1.48 /proc/7988/task/7989/stat : 7989 (java) S 7975 7974 29493 34817 29493 4202560 43867 0 1 0 130 18 0 0 25 0 11 0 74761079 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7990] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7990/stat : 7990 (java) S 7975 7974 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 11 0 74761080 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 0 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7991] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7991/stat : 7991 (java) S 7975 7974 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 11 0 74761081 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7992] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7992/stat : 7992 (java) S 7975 7974 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74761081 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7993] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7993/stat : 7993 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7994] ppid=7975 vsize=879172 CPUtime=0.94 /proc/7988/task/7994/stat : 7994 (java) S 7975 7974 29493 34817 29493 4202560 9212 0 0 0 85 9 0 0 15 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7995] ppid=7975 vsize=879172 CPUtime=0.42 /proc/7988/task/7995/stat : 7995 (java) S 7975 7974 29493 34817 29493 4202560 1471 0 0 0 42 0 0 0 24 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7996] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7996/stat : 7996 (java) S 7975 7974 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7997] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7997/stat : 7997 (java) S 7975 7974 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 74761083 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 [pid=7988/tid=7998] ppid=7975 vsize=879172 CPUtime=0 /proc/7988/task/7998/stat : 7998 (java) S 7975 7974 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 74761367 900272128 51040 1283457024 134512640 134550932 4292283984 18446744073709551615 4294960130 0 4 0 16800975 18446612132910123712 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 2.9 Current children cumulated vsize (KiB) 884312 Child status: 0 Real time (s): 3.50412 CPU time (s): 2.93618 CPU user time (s): 2.61216 CPU system time (s): 0.32402 CPU usage (%): 83.7924 Max. virtual memory (cumulated for all children) (KiB): 885284 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.61216 system time used= 0.32402 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 59445 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= 247 involuntary context switches= 219 runsolver used 0 second user time and 0 second system time The end