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/randf406d5.cudf.difficult.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/difficult//randf406d5.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/randf406d5.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.24 1.38 1.31 3/39 3349 /proc/meminfo: memFree=624876/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) R 3347 3348 2059 34821 2220 4202496 358 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2568 CPUtime=0 /proc/3349/stat : 3349 (p2cudf-paranoid) R 3348 3348 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 39 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/3349/statm: 642 39 0 194 0 28 0 [startup+0.16353 s] /proc/loadavg: 1.24 1.38 1.31 3/39 3349 /proc/meminfo: memFree=624876/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5144 [startup+0.21355 s] /proc/loadavg: 1.24 1.38 1.31 3/39 3349 /proc/meminfo: memFree=624876/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5144 [startup+0.303567 s] /proc/loadavg: 1.24 1.38 1.31 3/39 3349 /proc/meminfo: memFree=624876/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5144 [startup+0.703667 s] /proc/loadavg: 1.24 1.38 1.31 3/39 3349 /proc/meminfo: memFree=624876/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5144 [startup+1.50392 s] /proc/loadavg: 1.24 1.38 1.31 4/50 3371 /proc/meminfo: memFree=577792/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=884640 CPUtime=1.45 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 20862 0 1 0 130 15 0 0 25 0 10 0 876740906 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 221160 18205 1727 10 0 218250 0 [pid=3362/tid=3363] ppid=3349 vsize=884640 CPUtime=0.86 /proc/3362/task/3363/stat : 3363 (java) R 3349 3348 2059 34821 2220 4202560 10558 0 1 0 78 8 0 0 25 0 10 0 876740906 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=884640 CPUtime=0 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876740908 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=884640 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876740908 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=884640 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876740908 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=884640 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=884640 CPUtime=0.31 /proc/3362/task/3368/stat : 3368 (java) R 3349 3348 2059 34821 2220 4202560 3629 0 0 0 29 2 0 0 21 0 10 0 876740911 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4150050704 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=884640 CPUtime=0.26 /proc/3362/task/3369/stat : 3369 (java) R 3349 3348 2059 34821 2220 4202560 5561 0 0 0 22 4 0 0 25 0 10 0 876740911 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4151564096 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=884640 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=884640 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) S 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876740911 905871360 18205 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 889784 [startup+3.1043 s] /proc/loadavg: 1.24 1.38 1.31 4/50 3371 /proc/meminfo: memFree=537716/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=914068 CPUtime=3.04 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 38346 0 1 0 280 24 0 0 25 0 10 0 876740906 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 228517 31092 1733 10 0 225607 0 [pid=3362/tid=3363] ppid=3349 vsize=914068 CPUtime=1.09 /proc/3362/task/3363/stat : 3363 (java) R 3349 3348 2059 34821 2220 4202560 15798 0 1 0 99 10 0 0 25 0 10 0 876740906 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876740908 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876740908 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876740908 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=914068 CPUtime=1.17 /proc/3362/task/3368/stat : 3368 (java) R 3349 3348 2059 34821 2220 4202560 10542 0 0 0 110 7 0 0 24 0 10 0 876740911 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4151654626 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=914068 CPUtime=0.76 /proc/3362/task/3369/stat : 3369 (java) R 3349 3348 2059 34821 2220 4202560 10892 0 0 0 70 6 0 0 24 0 10 0 876740911 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4150022313 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) S 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876740911 936005632 31092 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.06 Current children cumulated vsize (KiB) 919212 Solver just ended. Dumping a history of the last processes samples [startup+3.20432 s] /proc/loadavg: 1.24 1.38 1.31 4/50 3371 /proc/meminfo: memFree=537716/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=914068 CPUtime=3.14 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 38359 0 1 0 290 24 0 0 25 0 10 0 876740906 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 228517 31105 1733 10 0 225607 0 [pid=3362/tid=3363] ppid=3349 vsize=914068 CPUtime=1.09 /proc/3362/task/3363/stat : 3363 (java) R 3349 3348 2059 34821 2220 4202560 15798 0 1 0 99 10 0 0 25 0 10 0 876740906 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876740908 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876740908 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876740908 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=914068 CPUtime=1.21 /proc/3362/task/3368/stat : 3368 (java) S 3349 3348 2059 34821 2220 4202560 10551 0 0 0 114 7 0 0 24 0 10 0 876740911 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=914068 CPUtime=0.82 /proc/3362/task/3369/stat : 3369 (java) R 3349 3348 2059 34821 2220 4202560 10896 0 0 0 76 6 0 0 24 0 10 0 876740911 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4152354857 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=914068 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) S 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876740911 936005632 31105 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.16 Current children cumulated vsize (KiB) 919212 [startup+4.83378 s] /proc/loadavg: 1.22 1.38 1.31 4/50 3371 /proc/meminfo: memFree=374120/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=910728 CPUtime=4.7 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 74108 0 1 0 428 42 0 0 25 0 10 0 876740906 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 227682 64991 1763 10 0 224772 0 [pid=3362/tid=3363] ppid=3349 vsize=910728 CPUtime=1.91 /proc/3362/task/3363/stat : 3363 (java) R 3349 3348 2059 34821 2220 4202560 49946 0 1 0 163 28 0 0 25 0 10 0 876740906 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=910728 CPUtime=0 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 876740908 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=910728 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876740908 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=910728 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876740908 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=910728 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=910728 CPUtime=1.51 /proc/3362/task/3368/stat : 3368 (java) R 3349 3348 2059 34821 2220 4202560 11134 0 0 0 144 7 0 0 16 0 10 0 876740911 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4150022954 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=910728 CPUtime=1.26 /proc/3362/task/3369/stat : 3369 (java) R 3349 3348 2059 34821 2220 4202560 11914 0 0 0 120 6 0 0 19 0 10 0 876740911 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4151564308 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=910728 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=910728 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) S 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876740911 932585472 64991 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.72 Current children cumulated vsize (KiB) 915872 [startup+5.60394 s] /proc/loadavg: 1.22 1.38 1.31 3/50 3371 /proc/meminfo: memFree=303200/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=909216 CPUtime=5.47 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 88671 0 1 0 502 45 0 0 25 0 10 0 876740906 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 227304 78712 1767 10 0 224394 0 [pid=3362/tid=3363] ppid=3349 vsize=909216 CPUtime=2.07 /proc/3362/task/3363/stat : 3363 (java) R 3349 3348 2059 34821 2220 4202560 50384 0 1 0 179 28 0 0 25 0 10 0 876740906 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4095558815 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=909216 CPUtime=0.31 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 13564 0 0 0 28 3 0 0 17 0 10 0 876740908 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=909216 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 10 0 876740908 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=909216 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 10 0 876740908 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=909216 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=909216 CPUtime=1.8 /proc/3362/task/3368/stat : 3368 (java) S 3349 3348 2059 34821 2220 4202560 11696 0 0 0 173 7 0 0 15 0 10 0 876740911 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=909216 CPUtime=1.26 /proc/3362/task/3369/stat : 3369 (java) S 3349 3348 2059 34821 2220 4202560 11915 0 0 0 120 6 0 0 19 0 10 0 876740911 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=909216 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876740911 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=909216 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) S 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876740911 931037184 78712 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.49 Current children cumulated vsize (KiB) 914360 [startup+6.00784 s] /proc/loadavg: 1.22 1.38 1.31 3/50 3371 /proc/meminfo: memFree=303200/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=909540 CPUtime=5.61 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 88729 0 1 0 516 45 0 0 25 0 11 0 876740906 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 227385 78770 1767 10 0 224475 0 [pid=3362/tid=3363] ppid=3349 vsize=909540 CPUtime=2.18 /proc/3362/task/3363/stat : 3363 (java) S 3349 3348 2059 34821 2220 4202560 50435 0 1 0 190 28 0 0 25 0 11 0 876740906 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=909540 CPUtime=0.31 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 13564 0 0 0 28 3 0 0 17 0 11 0 876740908 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876740908 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876740908 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=909540 CPUtime=1.8 /proc/3362/task/3368/stat : 3368 (java) S 3349 3348 2059 34821 2220 4202560 11696 0 0 0 173 7 0 0 15 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=909540 CPUtime=1.29 /proc/3362/task/3369/stat : 3369 (java) S 3349 3348 2059 34821 2220 4202560 11918 0 0 0 123 6 0 0 18 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) S 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.63 Current children cumulated vsize (KiB) 914684 [startup+6.20791 s] /proc/loadavg: 1.22 1.38 1.31 3/50 3371 /proc/meminfo: memFree=303200/1048576 swapFree=2084004/2097144 [pid=3348] ppid=3347 vsize=2568 CPUtime=0 /proc/3348/stat : 3348 (p2cudf-paranoid) S 3347 3348 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876740901 2629632 272 1283457024 134512640 135304128 4291083888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3348/statm: 642 272 233 194 0 28 0 [pid=3349] ppid=3348 vsize=2576 CPUtime=0.02 /proc/3349/stat : 3349 (p2cudf) S 3348 3348 2059 34821 2220 4202496 391 2987 0 1 0 0 2 0 25 0 1 0 876740901 2637824 282 1283457024 134512640 135304128 4292902960 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/3349/statm: 644 282 241 194 0 30 0 [pid=3362] ppid=3349 vsize=909540 CPUtime=5.61 /proc/3362/stat : 3362 (java) S 3349 3348 2059 34821 2220 4202496 88729 0 1 0 516 45 0 0 25 0 11 0 876740906 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/3362/statm: 227385 78770 1767 10 0 224475 0 [pid=3362/tid=3363] ppid=3349 vsize=909540 CPUtime=2.18 /proc/3362/task/3363/stat : 3363 (java) S 3349 3348 2059 34821 2220 4202560 50435 0 1 0 190 28 0 0 25 0 11 0 876740906 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3364] ppid=3349 vsize=909540 CPUtime=0.31 /proc/3362/task/3364/stat : 3364 (java) S 3349 3348 2059 34821 2220 4202560 13564 0 0 0 28 3 0 0 17 0 11 0 876740908 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3365] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3365/stat : 3365 (java) S 3349 3348 2059 34821 2220 4202560 10 0 0 0 0 0 0 0 18 0 11 0 876740908 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3366] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3366/stat : 3366 (java) S 3349 3348 2059 34821 2220 4202560 6 0 0 0 0 0 0 0 18 0 11 0 876740908 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3367] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3367/stat : 3367 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3368] ppid=3349 vsize=909540 CPUtime=1.8 /proc/3362/task/3368/stat : 3368 (java) S 3349 3348 2059 34821 2220 4202560 11696 0 0 0 173 7 0 0 15 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3369] ppid=3349 vsize=909540 CPUtime=1.29 /proc/3362/task/3369/stat : 3369 (java) S 3349 3348 2059 34821 2220 4202560 11918 0 0 0 123 6 0 0 18 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3370] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3370/stat : 3370 (java) S 3349 3348 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=3362/tid=3371] ppid=3349 vsize=909540 CPUtime=0 /proc/3362/task/3371/stat : 3371 (java) R 3349 3348 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876740911 931368960 78770 1283457024 134512640 134550932 4294150144 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.63 Current children cumulated vsize (KiB) 914684 Child status: 0 Real time (s): 6.28878 CPU time (s): 5.66435 CPU user time (s): 5.18432 CPU system time (s): 0.48003 CPU usage (%): 90.0707 Max. virtual memory (cumulated for all children) (KiB): 919212 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.18432 system time used= 0.48003 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 92506 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= 454 involuntary context switches= 487 runsolver used 0.012 second user time and 0 second system time The end