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/201108241238/p2cudf-trendy-1.13/rand763.cudf.user-upgrades.log.runsolver ./p2cudf-trendy-1.13 /home/misc2010/data/2011/user-upgrades/rand763.cudf /home/misc2010/tmp/201108241238/p2cudf-trendy-1.13/rand763.cudf.user-upgrades.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.11 1.16 1.10 6/35 24715 /proc/meminfo: memFree=333616/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2564 CPUtime=0 /proc/24714/stat : 24714 (p2cudf) R 24713 24713 1511 34817 1511 4202496 344 0 0 0 0 0 0 0 25 0 1 0 4738817 2625536 270 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/24714/statm: 641 270 231 194 0 28 0 [pid=24715] ppid=24714 vsize=2564 CPUtime=0 /proc/24715/stat : 24715 (p2cudf) R 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 1 0 4738817 2625536 39 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/24715/statm: 641 39 0 194 0 28 0 [startup+0.177806 s] /proc/loadavg: 1.11 1.16 1.10 6/35 24715 /proc/meminfo: memFree=333616/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5132 [startup+0.207883 s] /proc/loadavg: 1.11 1.16 1.10 6/35 24715 /proc/meminfo: memFree=333616/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5132 [startup+0.307835 s] /proc/loadavg: 1.11 1.16 1.10 6/35 24715 /proc/meminfo: memFree=333616/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5132 [startup+0.707957 s] /proc/loadavg: 1.11 1.16 1.10 6/35 24715 /proc/meminfo: memFree=333616/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5132 [startup+1.50829 s] /proc/loadavg: 1.11 1.16 1.10 3/47 24738 /proc/meminfo: memFree=301448/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875168 CPUtime=1.47 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 20288 0 1 0 144 3 0 0 25 0 10 0 4738821 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218792 17099 1726 10 0 215883 0 [pid=24727/tid=24728] ppid=24714 vsize=875168 CPUtime=0.8 /proc/24727/task/24728/stat : 24728 (java) R 24714 24713 1511 34817 1511 4202560 11041 0 1 0 78 2 0 0 25 0 10 0 4738821 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4095071989 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 6 0 0 0 0 0 0 0 18 0 10 0 4738823 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 8 0 0 0 0 0 0 0 25 0 10 0 4738823 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 10 0 4738823 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4738826 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875168 CPUtime=0.27 /proc/24727/task/24733/stat : 24733 (java) R 24714 24713 1511 34817 1511 4202560 3071 0 0 0 27 0 0 0 25 0 10 0 4738826 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4149679745 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875168 CPUtime=0.38 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 38 0 0 0 18 0 10 0 4738826 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4738826 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4738826 896172032 17099 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 880300 [startup+3.10896 s] /proc/loadavg: 1.11 1.16 1.10 2/47 24738 /proc/meminfo: memFree=147316/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875168 CPUtime=3.07 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 69858 0 1 0 283 24 0 0 25 0 10 0 4738821 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218792 66669 1743 10 0 215883 0 [pid=24727/tid=24728] ppid=24714 vsize=875168 CPUtime=1.95 /proc/24727/task/24728/stat : 24728 (java) R 24714 24713 1511 34817 1511 4202560 50093 0 1 0 177 18 0 0 25 0 10 0 4738821 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875168 CPUtime=0.2 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 10467 0 0 0 16 4 0 0 16 0 10 0 4738823 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 4738823 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4738823 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4738826 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875168 CPUtime=0.48 /proc/24727/task/24733/stat : 24733 (java) R 24714 24713 1511 34817 1511 4202560 3126 0 0 0 48 0 0 0 16 0 10 0 4738826 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4152018625 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875168 CPUtime=0.4 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 40 0 0 0 15 0 10 0 4738826 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4738826 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875168 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4738826 896172032 66669 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 880300 [startup+6.31015 s] /proc/loadavg: 1.18 1.17 1.11 2/48 24739 /proc/meminfo: memFree=11908/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875492 CPUtime=6.27 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 85141 0 1 0 598 29 0 0 25 0 11 0 4738821 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218873 81952 1779 10 0 215964 0 [pid=24727/tid=24728] ppid=24714 vsize=875492 CPUtime=3.3 /proc/24727/task/24728/stat : 24728 (java) R 24714 24713 1511 34817 1511 4202560 50286 0 1 0 312 18 0 0 25 0 11 0 4738821 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4095848144 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875492 CPUtime=0.55 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 25306 0 0 0 48 7 0 0 15 0 11 0 4738823 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4738823 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 11 0 4738823 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4738826 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875492 CPUtime=1.97 /proc/24727/task/24733/stat : 24733 (java) S 24714 24713 1511 34817 1511 4202560 3373 0 0 0 195 2 0 0 15 0 11 0 4738826 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875492 CPUtime=0.4 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 40 0 0 0 15 0 11 0 4738826 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 11 0 4738826 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 4738826 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24739] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24739/stat : 24739 (java) S 24714 24713 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4739319 896503808 81952 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.29 Current children cumulated vsize (KiB) 880624 Solver just ended. Dumping a history of the last processes samples [startup+9.41105 s] /proc/loadavg: 1.18 1.17 1.11 2/48 24739 /proc/meminfo: memFree=11660/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875492 CPUtime=9.37 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 85169 0 1 0 908 29 0 0 25 0 11 0 4738821 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218873 81980 1783 10 0 215964 0 [pid=24727/tid=24728] ppid=24714 vsize=875492 CPUtime=6.23 /proc/24727/task/24728/stat : 24728 (java) R 24714 24713 1511 34817 1511 4202560 50287 0 1 0 605 18 0 0 25 0 11 0 4738821 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4095664529 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875492 CPUtime=0.55 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 25306 0 0 0 48 7 0 0 16 0 11 0 4738823 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4738823 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 11 0 4738823 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4738826 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875492 CPUtime=2.14 /proc/24727/task/24733/stat : 24733 (java) S 24714 24713 1511 34817 1511 4202560 3400 0 0 0 212 2 0 0 15 0 11 0 4738826 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875492 CPUtime=0.4 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 40 0 0 0 15 0 11 0 4738826 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 11 0 4738826 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) R 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 4738826 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24739] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24739/stat : 24739 (java) S 24714 24713 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4739319 896503808 81980 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 9.39 Current children cumulated vsize (KiB) 880624 [startup+10.2141 s] /proc/loadavg: 1.18 1.17 1.11 3/48 24739 /proc/meminfo: memFree=11660/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875492 CPUtime=10.17 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 85174 0 1 0 988 29 0 0 25 0 11 0 4738821 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218873 81985 1783 10 0 215964 0 [pid=24727/tid=24728] ppid=24714 vsize=875492 CPUtime=6.97 /proc/24727/task/24728/stat : 24728 (java) R 24714 24713 1511 34817 1511 4202560 50287 0 1 0 679 18 0 0 25 0 11 0 4738821 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875492 CPUtime=0.55 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 25306 0 0 0 48 7 0 0 16 0 11 0 4738823 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4738823 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 11 0 4738823 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4738826 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875492 CPUtime=2.2 /proc/24727/task/24733/stat : 24733 (java) S 24714 24713 1511 34817 1511 4202560 3405 0 0 0 218 2 0 0 15 0 11 0 4738826 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875492 CPUtime=0.4 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 40 0 0 0 15 0 11 0 4738826 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 11 0 4738826 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 4738826 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24739] ppid=24714 vsize=875492 CPUtime=0 /proc/24727/task/24739/stat : 24739 (java) S 24714 24713 1511 34817 1511 4202560 4 0 0 0 0 0 0 0 25 0 11 0 4739319 896503808 81985 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 10.19 Current children cumulated vsize (KiB) 880624 [startup+10.6065 s] /proc/loadavg: 1.18 1.17 1.11 3/48 24739 /proc/meminfo: memFree=11660/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875816 CPUtime=10.2 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 85190 0 1 0 991 29 0 0 25 0 11 0 4738821 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218954 82001 1784 10 0 216045 0 [pid=24727/tid=24728] ppid=24714 vsize=875816 CPUtime=6.98 /proc/24727/task/24728/stat : 24728 (java) S 24714 24713 1511 34817 1511 4202560 50295 0 1 0 680 18 0 0 25 0 11 0 4738821 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875816 CPUtime=0.55 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 25306 0 0 0 48 7 0 0 16 0 11 0 4738823 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 11 0 4738823 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 11 0 4738823 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 11 0 4738826 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875816 CPUtime=2.22 /proc/24727/task/24733/stat : 24733 (java) S 24714 24713 1511 34817 1511 4202560 3407 0 0 0 220 2 0 0 15 0 11 0 4738826 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875816 CPUtime=0.4 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 40 0 0 0 15 0 11 0 4738826 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 11 0 4738826 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 11 0 4738826 896835584 82001 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 10.22 Current children cumulated vsize (KiB) 880948 [startup+10.8065 s] /proc/loadavg: 1.17 1.17 1.11 2/47 24740 /proc/meminfo: memFree=11544/1048576 swapFree=0/0 [pid=24713] ppid=24712 vsize=2564 CPUtime=0 /proc/24713/stat : 24713 (p2cudf-trendy-1) S 24712 24713 1511 34817 1511 4202496 374 0 0 0 0 0 0 0 18 0 1 0 4738817 2625536 269 1283457024 134512640 135304128 4290522592 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24713/statm: 641 269 230 194 0 28 0 [pid=24714] ppid=24713 vsize=2568 CPUtime=0.02 /proc/24714/stat : 24714 (p2cudf) S 24713 24713 1511 34817 1511 4202496 389 3013 0 1 0 0 2 0 25 0 1 0 4738817 2629632 279 1283457024 134512640 135304128 4291718912 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24714/statm: 642 279 239 194 0 29 0 [pid=24727] ppid=24714 vsize=875816 CPUtime=10.25 /proc/24727/stat : 24727 (java) S 24714 24713 1511 34817 1511 4202496 85191 0 1 0 995 30 0 0 25 0 10 0 4738821 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24727/statm: 218954 82002 1784 10 0 216045 0 [pid=24727/tid=24728] ppid=24714 vsize=875816 CPUtime=6.98 /proc/24727/task/24728/stat : 24728 (java) S 24714 24713 1511 34817 1511 4202560 50295 0 1 0 680 18 0 0 20 0 10 0 4738821 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24729] ppid=24714 vsize=875816 CPUtime=0.55 /proc/24727/task/24729/stat : 24729 (java) S 24714 24713 1511 34817 1511 4202560 25306 0 0 0 48 7 0 0 16 0 10 0 4738823 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 0 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24730] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24730/stat : 24730 (java) S 24714 24713 1511 34817 1511 4202560 9 0 0 0 0 0 0 0 18 0 10 0 4738823 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24731] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24731/stat : 24731 (java) S 24714 24713 1511 34817 1511 4202560 5 0 0 0 0 0 0 0 18 0 10 0 4738823 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24732] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24732/stat : 24732 (java) S 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 25 0 10 0 4738826 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24733] ppid=24714 vsize=875816 CPUtime=2.23 /proc/24727/task/24733/stat : 24733 (java) S 24714 24713 1511 34817 1511 4202560 3407 0 0 0 221 2 0 0 16 0 10 0 4738826 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24734] ppid=24714 vsize=875816 CPUtime=0.4 /proc/24727/task/24734/stat : 24734 (java) S 24714 24713 1511 34817 1511 4202560 5099 0 0 0 40 0 0 0 15 0 10 0 4738826 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24735] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24735/stat : 24735 (java) S 24714 24713 1511 34817 1511 4202560 0 0 0 0 0 0 0 0 25 0 10 0 4738826 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 18446612132974981184 0 0 -1 0 0 0 0 [pid=24727/tid=24736] ppid=24714 vsize=875816 CPUtime=0 /proc/24727/task/24736/stat : 24736 (java) R 24714 24713 1511 34817 1511 4202560 1 0 0 0 0 0 0 0 15 0 10 0 4738826 896835584 82002 1283457024 134512640 134550932 4293334816 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 10.27 Current children cumulated vsize (KiB) 880948 Child status: 0 Real time (s): 10.8306 CPU time (s): 10.3086 CPU user time (s): 9.98062 CPU system time (s): 0.32802 CPU usage (%): 95.1806 Max. virtual memory (cumulated for all children) (KiB): 880948 getrusage(RUSAGE_CHILDREN,...) data: user time used= 9.98062 system time used= 0.32802 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 88990 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= 1005 involuntary context switches= 1019 runsolver used 0 second user time and 0 second system time The end