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/201012061533/p2cudf-paranoid-1.9/randf17677.cudf.difficult.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/difficult//randf17677.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/randf17677.cudf.difficult.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.50 1.45 1.32 5/41 2846 /proc/meminfo: memFree=649052/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2572 CPUtime=0 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 357 0 0 0 0 0 0 0 25 0 1 0 876731009 2633728 275 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/2845/statm: 643 275 234 194 0 29 0 [pid=2846] ppid=2845 vsize=2572 CPUtime=0 /proc/2846/stat : 2846 (p2cudf) R 2845 2844 2059 34821 2220 4202560 92 0 0 0 0 0 0 0 25 0 1 0 876731009 2633728 117 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/2846/statm: 643 117 75 194 0 29 0 [pid=2847] ppid=2846 vsize=2572 CPUtime=0 /proc/2847/stat : 2847 (p2cudf) R 2846 2844 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 876731009 2633728 42 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/2847/statm: 643 42 0 194 0 29 0 [startup+0.123645 s] /proc/loadavg: 1.50 1.45 1.32 5/41 2846 /proc/meminfo: memFree=649052/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+0.213658 s] /proc/loadavg: 1.50 1.45 1.32 5/41 2846 /proc/meminfo: memFree=649052/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+0.30366 s] /proc/loadavg: 1.50 1.45 1.32 5/41 2846 /proc/meminfo: memFree=649052/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+0.703717 s] /proc/loadavg: 1.50 1.45 1.32 5/41 2846 /proc/meminfo: memFree=649052/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5148 [startup+1.5139 s] /proc/loadavg: 1.50 1.45 1.32 4/51 2867 /proc/meminfo: memFree=602352/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=882752 CPUtime=1.45 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 19047 0 1 0 138 7 0 0 25 0 10 0 876731014 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 220688 15648 1732 10 0 217778 0 [pid=2858/tid=2859] ppid=2845 vsize=882752 CPUtime=0.71 /proc/2858/task/2859/stat : 2859 (java) R 2845 2844 2059 34821 2220 4202560 8140 0 1 0 68 3 0 0 25 0 10 0 876731015 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=882752 CPUtime=0 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876731016 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=882752 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876731017 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=882752 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876731017 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=882752 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876731019 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=882752 CPUtime=0.48 /proc/2858/task/2864/stat : 2864 (java) R 2845 2844 2059 34821 2220 4202560 4226 0 0 0 48 0 0 0 21 0 10 0 876731019 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4152344525 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=882752 CPUtime=0.24 /proc/2858/task/2865/stat : 2865 (java) R 2845 2844 2059 34821 2220 4202560 5563 0 0 0 21 3 0 0 25 0 10 0 876731019 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4151912192 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=882752 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876731019 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=882752 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876731019 903938048 15648 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 887900 [startup+3.10436 s] /proc/loadavg: 1.50 1.45 1.32 4/51 2867 /proc/meminfo: memFree=555720/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=921348 CPUtime=3.04 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 41464 0 1 0 282 22 0 0 25 0 10 0 876731014 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 230337 33036 1732 10 0 227427 0 [pid=2858/tid=2859] ppid=2845 vsize=921348 CPUtime=1.06 /proc/2858/task/2859/stat : 2859 (java) R 2845 2844 2059 34821 2220 4202560 15803 0 1 0 95 11 0 0 25 0 10 0 876731015 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=921348 CPUtime=0 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876731016 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=921348 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876731017 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=921348 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876731017 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=921348 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876731019 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=921348 CPUtime=1 /proc/2858/task/2864/stat : 2864 (java) R 2845 2844 2059 34821 2220 4202560 10324 0 0 0 96 4 0 0 24 0 10 0 876731019 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4152711140 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=921348 CPUtime=0.96 /proc/2858/task/2865/stat : 2865 (java) R 2845 2844 2059 34821 2220 4202560 14219 0 0 0 90 6 0 0 25 0 10 0 876731019 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4152415402 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=921348 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876731019 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=921348 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876731019 943460352 33036 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.07 Current children cumulated vsize (KiB) 926496 [startup+6.30693 s] /proc/loadavg: 1.62 1.48 1.33 2/50 2867 /proc/meminfo: memFree=318340/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=919724 CPUtime=6.17 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 92003 0 1 0 587 30 0 0 25 0 13 0 876731014 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 229931 81111 1789 10 0 227021 0 [pid=2858/tid=2859] ppid=2845 vsize=919724 CPUtime=2.22 /proc/2858/task/2859/stat : 2859 (java) R 2845 2844 2059 34821 2220 4202560 50448 0 1 0 206 16 0 0 25 0 13 0 876731015 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4096699818 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=919724 CPUtime=0.32 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 13655 0 0 0 30 2 0 0 15 0 13 0 876731016 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=919724 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 13 0 876731017 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=919724 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 13 0 876731017 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=919724 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 13 0 876731019 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=919724 CPUtime=1.77 /proc/2858/task/2864/stat : 2864 (java) S 2845 2844 2059 34821 2220 4202560 12427 0 0 0 173 4 0 0 16 0 13 0 876731019 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=919724 CPUtime=1.83 /proc/2858/task/2865/stat : 2865 (java) S 2845 2844 2059 34821 2220 4202560 14341 0 0 0 177 6 0 0 17 0 13 0 876731019 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=919724 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 13 0 876731019 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=919724 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 13 0 876731019 941797376 81111 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.2 Current children cumulated vsize (KiB) 924872 Solver just ended. Dumping a history of the last processes samples [startup+6.40662 s] /proc/loadavg: 1.62 1.48 1.33 2/50 2867 /proc/meminfo: memFree=318340/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=918840 CPUtime=6.27 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 92012 0 1 0 597 30 0 0 25 0 15 0 876731014 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 229710 80906 1790 10 0 226800 0 [pid=2858/tid=2859] ppid=2845 vsize=918840 CPUtime=2.26 /proc/2858/task/2859/stat : 2859 (java) R 2845 2844 2059 34821 2220 4202560 50449 0 1 0 210 16 0 0 25 0 15 0 876731015 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4096700017 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=918840 CPUtime=0.32 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 13655 0 0 0 30 2 0 0 15 0 15 0 876731016 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=918840 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 15 0 876731017 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=918840 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 15 0 876731017 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=918840 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 15 0 876731019 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=918840 CPUtime=1.77 /proc/2858/task/2864/stat : 2864 (java) S 2845 2844 2059 34821 2220 4202560 12427 0 0 0 173 4 0 0 16 0 15 0 876731019 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=918840 CPUtime=1.89 /proc/2858/task/2865/stat : 2865 (java) S 2845 2844 2059 34821 2220 4202560 14349 0 0 0 183 6 0 0 16 0 15 0 876731019 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=918840 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 15 0 876731019 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=918840 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 15 0 876731019 940892160 80906 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.3 Current children cumulated vsize (KiB) 923988 [startup+6.80401 s] /proc/loadavg: 1.62 1.48 1.33 4/54 2879 /proc/meminfo: memFree=318060/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=920136 CPUtime=6.61 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 92062 0 1 0 629 32 0 0 25 0 11 0 876731014 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 230034 80956 1792 10 0 227124 0 [pid=2858/tid=2859] ppid=2845 vsize=920136 CPUtime=2.44 /proc/2858/task/2859/stat : 2859 (java) S 2845 2844 2059 34821 2220 4202560 50459 0 1 0 226 18 0 0 25 0 11 0 876731015 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=920136 CPUtime=0.32 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 13655 0 0 0 30 2 0 0 15 0 11 0 876731016 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876731017 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876731017 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=920136 CPUtime=1.77 /proc/2858/task/2864/stat : 2864 (java) S 2845 2844 2059 34821 2220 4202560 12427 0 0 0 173 4 0 0 16 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=920136 CPUtime=2.04 /proc/2858/task/2865/stat : 2865 (java) S 2845 2844 2059 34821 2220 4202560 14370 0 0 0 198 6 0 0 15 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.64 Current children cumulated vsize (KiB) 925284 [startup+7.20404 s] /proc/loadavg: 1.62 1.48 1.33 4/54 2879 /proc/meminfo: memFree=318060/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=920136 CPUtime=6.61 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 92062 0 1 0 629 32 0 0 25 0 11 0 876731014 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 230034 80956 1792 10 0 227124 0 [pid=2858/tid=2859] ppid=2845 vsize=920136 CPUtime=2.44 /proc/2858/task/2859/stat : 2859 (java) S 2845 2844 2059 34821 2220 4202560 50459 0 1 0 226 18 0 0 25 0 11 0 876731015 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=920136 CPUtime=0.32 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 13655 0 0 0 30 2 0 0 15 0 11 0 876731016 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876731017 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876731017 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=920136 CPUtime=1.77 /proc/2858/task/2864/stat : 2864 (java) S 2845 2844 2059 34821 2220 4202560 12427 0 0 0 173 4 0 0 16 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=920136 CPUtime=2.04 /proc/2858/task/2865/stat : 2865 (java) S 2845 2844 2059 34821 2220 4202560 14370 0 0 0 198 6 0 0 15 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876731019 942219264 80956 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.64 Current children cumulated vsize (KiB) 925284 [startup+7.31184 s] /proc/loadavg: 1.62 1.48 1.33 4/54 2879 /proc/meminfo: memFree=318060/1048576 swapFree=2084004/2097144 [pid=2844] ppid=2843 vsize=2572 CPUtime=0 /proc/2844/stat : 2844 (p2cudf-paranoid) S 2843 2844 2059 34821 2220 4202496 378 0 0 0 0 0 0 0 22 0 1 0 876731009 2633728 273 1283457024 134512640 135304128 4289359040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2844/statm: 643 273 233 194 0 29 0 [pid=2845] ppid=2844 vsize=2576 CPUtime=0.03 /proc/2845/stat : 2845 (p2cudf) S 2844 2844 2059 34821 2220 4202496 393 2988 0 1 0 0 1 2 25 0 1 0 876731009 2637824 283 1283457024 134512640 135304128 4290663952 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/2845/statm: 644 283 241 194 0 30 0 [pid=2858] ppid=2845 vsize=920136 CPUtime=6.66 /proc/2858/stat : 2858 (java) S 2845 2844 2059 34821 2220 4202496 92063 0 1 0 634 32 0 0 25 0 11 0 876731014 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/2858/statm: 230034 80957 1792 10 0 227124 0 [pid=2858/tid=2859] ppid=2845 vsize=920136 CPUtime=2.44 /proc/2858/task/2859/stat : 2859 (java) S 2845 2844 2059 34821 2220 4202560 50459 0 1 0 226 18 0 0 25 0 11 0 876731015 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2860] ppid=2845 vsize=920136 CPUtime=0.32 /proc/2858/task/2860/stat : 2860 (java) S 2845 2844 2059 34821 2220 4202560 13655 0 0 0 30 2 0 0 15 0 11 0 876731016 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 0 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2861] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2861/stat : 2861 (java) S 2845 2844 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876731017 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2862] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2862/stat : 2862 (java) S 2845 2844 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876731017 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2863] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2863/stat : 2863 (java) S 2845 2844 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876731019 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2864] ppid=2845 vsize=920136 CPUtime=1.77 /proc/2858/task/2864/stat : 2864 (java) S 2845 2844 2059 34821 2220 4202560 12427 0 0 0 173 4 0 0 16 0 11 0 876731019 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2865] ppid=2845 vsize=920136 CPUtime=2.08 /proc/2858/task/2865/stat : 2865 (java) S 2845 2844 2059 34821 2220 4202560 14370 0 0 0 202 6 0 0 15 0 11 0 876731019 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2866] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2866/stat : 2866 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876731019 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 [pid=2858/tid=2867] ppid=2845 vsize=920136 CPUtime=0 /proc/2858/task/2867/stat : 2867 (java) S 2845 2844 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876731019 942219264 80957 1283457024 134512640 134550932 4288291440 18446744073709551615 4294960130 0 4 0 16800975 18446612132680193024 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.69 Current children cumulated vsize (KiB) 925284 Child status: 0 Real time (s): 7.36835 CPU time (s): 6.72842 CPU user time (s): 6.3644 CPU system time (s): 0.364022 CPU usage (%): 91.3151 Max. virtual memory (cumulated for all children) (KiB): 931136 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.3644 system time used= 0.364022 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 95846 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= 858 involuntary context switches= 854 runsolver used 0.008 second user time and 0 second system time The end