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/201012070034/p2cudf-trendy-1.9/rand2fb7ab.cudf.easy.log.runsolver ./p2cudf-trendy-1.9 /home/misc2010/data/2010/easy//rand2fb7ab.cudf /home/misc2010/tmp/201012070034/p2cudf-trendy-1.9/rand2fb7ab.cudf.easy.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: 0.99 0.99 0.99 5/41 14047 /proc/meminfo: memFree=702340/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2576 CPUtime=0 /proc/14047/stat : 14047 (p2cudf) R 14046 14046 4159 34819 4229 4202496 350 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 275 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/14047/statm: 644 275 234 194 0 30 0 [pid=14048] ppid=14047 vsize=2576 CPUtime=0 /proc/14048/stat : 14048 (p2cudf) R 14047 14046 4159 34819 4229 4202560 0 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 41 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/14048/statm: 644 41 0 194 0 30 0 [startup+0.141913 s] /proc/loadavg: 0.99 0.99 0.99 5/41 14047 /proc/meminfo: memFree=702340/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5156 [startup+0.211929 s] /proc/loadavg: 0.99 0.99 0.99 5/41 14047 /proc/meminfo: memFree=702340/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5156 [startup+0.311949 s] /proc/loadavg: 0.99 0.99 0.99 5/41 14047 /proc/meminfo: memFree=702340/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5156 [startup+0.712074 s] /proc/loadavg: 0.99 0.99 0.99 5/41 14047 /proc/meminfo: memFree=702340/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5156 [startup+1.50858 s] /proc/loadavg: 1.15 1.02 1.01 3/52 14069 /proc/meminfo: memFree=663764/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=863936 CPUtime=1.44 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 20612 0 1 0 136 8 0 0 25 0 10 0 879900889 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 215984 19937 1732 10 0 213074 0 [pid=14060/tid=14061] ppid=14047 vsize=863936 CPUtime=0.88 /proc/14060/task/14061/stat : 14061 (java) R 14047 14046 4159 34819 4229 4202560 16582 0 1 0 82 6 0 0 25 0 10 0 879900889 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4095615705 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=863936 CPUtime=0 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 17 0 10 0 879900891 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=863936 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 879900891 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=863936 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 879900891 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=863936 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=863936 CPUtime=0.32 /proc/14060/task/14066/stat : 14066 (java) R 14047 14046 4159 34819 4229 4202560 2765 0 0 0 31 1 0 0 25 0 10 0 879900894 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4148704897 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=863936 CPUtime=0.22 /proc/14060/task/14067/stat : 14067 (java) S 14047 14046 4159 34819 4229 4202560 150 0 0 0 22 0 0 0 20 0 10 0 879900894 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=863936 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=863936 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) S 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 879900894 884670464 19937 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 869092 [startup+3.10894 s] /proc/loadavg: 1.15 1.02 1.01 4/52 14069 /proc/meminfo: memFree=578080/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=872320 CPUtime=3.03 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 44265 0 1 0 286 17 0 0 25 0 10 0 879900889 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 218080 41853 1760 10 0 215170 0 [pid=14060/tid=14061] ppid=14047 vsize=872320 CPUtime=1.63 /proc/14060/task/14061/stat : 14061 (java) R 14047 14046 4159 34819 4229 4202560 36346 0 1 0 150 13 0 0 25 0 10 0 879900889 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4149304789 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 16 0 10 0 879900891 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 879900891 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 879900891 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=872320 CPUtime=0.74 /proc/14060/task/14066/stat : 14066 (java) R 14047 14046 4159 34819 4229 4202560 6041 0 0 0 72 2 0 0 24 0 10 0 879900894 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=872320 CPUtime=0.64 /proc/14060/task/14067/stat : 14067 (java) R 14047 14046 4159 34819 4229 4202560 763 0 0 0 64 0 0 0 16 0 10 0 879900894 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4152093490 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) S 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 879900894 893255680 41853 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.06 Current children cumulated vsize (KiB) 877476 Solver just ended. Dumping a history of the last processes samples [startup+3.22897 s] /proc/loadavg: 1.15 1.02 1.01 4/52 14069 /proc/meminfo: memFree=578080/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=872320 CPUtime=3.14 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 44937 0 1 0 297 17 0 0 25 0 10 0 879900889 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 218080 42525 1762 10 0 215170 0 [pid=14060/tid=14061] ppid=14047 vsize=872320 CPUtime=1.67 /proc/14060/task/14061/stat : 14061 (java) R 14047 14046 4159 34819 4229 4202560 37014 0 1 0 154 13 0 0 25 0 10 0 879900889 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 15 0 10 0 879900891 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 10 0 879900891 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 10 0 879900891 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=872320 CPUtime=0.78 /proc/14060/task/14066/stat : 14066 (java) R 14047 14046 4159 34819 4229 4202560 6045 0 0 0 76 2 0 0 24 0 10 0 879900894 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4152029497 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=872320 CPUtime=0.67 /proc/14060/task/14067/stat : 14067 (java) R 14047 14046 4159 34819 4229 4202560 763 0 0 0 67 0 0 0 15 0 10 0 879900894 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4151682672 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=872320 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) S 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 879900894 893255680 42525 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.17 Current children cumulated vsize (KiB) 877476 [startup+4.8094 s] /proc/loadavg: 1.15 1.02 1.01 3/53 14070 /proc/meminfo: memFree=495364/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=872308 CPUtime=4.72 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 57733 0 1 0 450 22 0 0 25 0 11 0 879900889 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 218077 53831 1784 10 0 215167 0 [pid=14060/tid=14061] ppid=14047 vsize=872308 CPUtime=2.23 /proc/14060/task/14061/stat : 14061 (java) R 14047 14046 4159 34819 4229 4202560 48241 0 1 0 206 17 0 0 25 0 11 0 879900889 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4095271842 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=872308 CPUtime=0 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 5 0 0 0 0 0 0 0 16 0 11 0 879900891 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=872308 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 9 0 0 0 0 0 0 0 25 0 11 0 879900891 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=872308 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 4 0 0 0 0 0 0 0 25 0 11 0 879900891 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=872308 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879900894 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=872308 CPUtime=1.02 /proc/14060/task/14066/stat : 14066 (java) R 14047 14046 4159 34819 4229 4202560 6083 0 0 0 100 2 0 0 24 0 11 0 879900894 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4147437212 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=872308 CPUtime=1.44 /proc/14060/task/14067/stat : 14067 (java) S 14047 14046 4159 34819 4229 4202560 2290 0 0 0 142 2 0 0 15 0 11 0 879900894 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=872308 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879900894 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=872308 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) R 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879900894 893243392 53831 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.75 Current children cumulated vsize (KiB) 877464 [startup+5.60404 s] /proc/loadavg: 1.15 1.02 1.01 4/53 14075 /proc/meminfo: memFree=440796/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=872956 CPUtime=5.45 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 70796 0 1 0 518 27 0 0 25 0 11 0 879900889 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 218239 66894 1789 10 0 215329 0 [pid=14060/tid=14061] ppid=14047 vsize=872956 CPUtime=2.45 /proc/14060/task/14061/stat : 14061 (java) S 14047 14046 4159 34819 4229 4202560 50452 0 1 0 227 18 0 0 25 0 11 0 879900889 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=872956 CPUtime=0.26 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 10814 0 0 0 22 4 0 0 15 0 11 0 879900891 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 879900891 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 879900891 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=872956 CPUtime=1.23 /proc/14060/task/14066/stat : 14066 (java) S 14047 14046 4159 34819 4229 4202560 6106 0 0 0 121 2 0 0 22 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=872956 CPUtime=1.49 /proc/14060/task/14067/stat : 14067 (java) S 14047 14046 4159 34819 4229 4202560 2299 0 0 0 147 2 0 0 15 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) S 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.48 Current children cumulated vsize (KiB) 878112 [startup+6.00405 s] /proc/loadavg: 1.15 1.02 1.01 4/53 14075 /proc/meminfo: memFree=440796/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=872956 CPUtime=5.45 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 70796 0 1 0 518 27 0 0 25 0 11 0 879900889 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 218239 66894 1789 10 0 215329 0 [pid=14060/tid=14061] ppid=14047 vsize=872956 CPUtime=2.45 /proc/14060/task/14061/stat : 14061 (java) S 14047 14046 4159 34819 4229 4202560 50452 0 1 0 227 18 0 0 25 0 11 0 879900889 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=872956 CPUtime=0.26 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 10814 0 0 0 22 4 0 0 15 0 11 0 879900891 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 11 0 879900891 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 11 0 879900891 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=872956 CPUtime=1.23 /proc/14060/task/14066/stat : 14066 (java) S 14047 14046 4159 34819 4229 4202560 6106 0 0 0 121 2 0 0 22 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=872956 CPUtime=1.49 /proc/14060/task/14067/stat : 14067 (java) S 14047 14046 4159 34819 4229 4202560 2299 0 0 0 147 2 0 0 15 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) S 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 11 0 879900894 893906944 66894 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.48 Current children cumulated vsize (KiB) 878112 [startup+6.20408 s] /proc/loadavg: 1.15 1.02 1.01 4/53 14075 /proc/meminfo: memFree=440796/1048576 swapFree=2076380/2097144 [pid=14046] ppid=14045 vsize=2576 CPUtime=0 /proc/14046/stat : 14046 (p2cudf-trendy-1) S 14045 14046 4159 34819 4229 4202496 379 0 0 0 0 0 0 0 25 0 1 0 879900885 2637824 274 1283457024 134512640 135304128 4289561872 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14046/statm: 644 274 233 194 0 30 0 [pid=14047] ppid=14046 vsize=2580 CPUtime=0.03 /proc/14047/stat : 14047 (p2cudf) S 14046 14046 4159 34819 4229 4202496 396 2995 0 1 0 0 0 3 25 0 1 0 879900885 2641920 283 1283457024 134512640 135304128 4288648224 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/14047/statm: 645 283 241 194 0 31 0 [pid=14060] ppid=14047 vsize=872956 CPUtime=5.57 /proc/14060/stat : 14060 (java) S 14047 14046 4159 34819 4229 4202496 70802 0 1 0 529 28 0 0 25 0 10 0 879900889 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/14060/statm: 218239 66900 1789 10 0 215329 0 [pid=14060/tid=14061] ppid=14047 vsize=872956 CPUtime=2.45 /proc/14060/task/14061/stat : 14061 (java) S 14047 14046 4159 34819 4229 4202560 50452 0 1 0 227 18 0 0 19 0 10 0 879900889 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14062] ppid=14047 vsize=872956 CPUtime=0.26 /proc/14060/task/14062/stat : 14062 (java) S 14047 14046 4159 34819 4229 4202560 10814 0 0 0 22 4 0 0 15 0 10 0 879900891 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14063] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14063/stat : 14063 (java) S 14047 14046 4159 34819 4229 4202560 10 0 0 0 0 0 0 0 18 0 10 0 879900891 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14064] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14064/stat : 14064 (java) S 14047 14046 4159 34819 4229 4202560 6 0 0 0 0 0 0 0 18 0 10 0 879900891 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14065] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14065/stat : 14065 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14066] ppid=14047 vsize=872956 CPUtime=1.29 /proc/14060/task/14066/stat : 14066 (java) S 14047 14046 4159 34819 4229 4202560 6111 0 0 0 127 2 0 0 16 0 10 0 879900894 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14067] ppid=14047 vsize=872956 CPUtime=1.49 /proc/14060/task/14067/stat : 14067 (java) S 14047 14046 4159 34819 4229 4202560 2299 0 0 0 147 2 0 0 15 0 10 0 879900894 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14068] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14068/stat : 14068 (java) S 14047 14046 4159 34819 4229 4202560 1 0 0 0 0 0 0 0 25 0 10 0 879900894 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=14060/tid=14069] ppid=14047 vsize=872956 CPUtime=0 /proc/14060/task/14069/stat : 14069 (java) S 14047 14046 4159 34819 4229 4202560 2 0 0 0 0 0 0 0 15 0 10 0 879900894 893906944 66900 1283457024 134512640 134550932 4293937088 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.6 Current children cumulated vsize (KiB) 878112 Child status: 0 Real time (s): 6.2356 CPU time (s): 5.62435 CPU user time (s): 5.29233 CPU system time (s): 0.33202 CPU usage (%): 90.1975 Max. virtual memory (cumulated for all children) (KiB): 880364 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.29233 system time used= 0.33202 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 74595 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= 834 involuntary context switches= 798 runsolver used 0 second user time and 0.008 second system time The end