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/201108251442/p2cudf-paranoid-1.13/d0cc7514-c730-11df-a040-00163e3d3b7c.cudf.dudf-real.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/dudf-real//d0cc7514-c730-11df-a040-00163e3d3b7c.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/d0cc7514-c730-11df-a040-00163e3d3b7c.cudf.dudf-real.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.64 1.52 1.37 5/35 28583 /proc/meminfo: memFree=247736/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2572 CPUtime=0 /proc/28582/stat : 28582 (p2cudf) R 28581 28581 4778 34817 4778 4202496 347 0 0 0 0 0 0 0 25 0 1 0 11238919 2633728 272 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/28582/statm: 643 272 231 194 0 30 0 [pid=28583] ppid=28582 vsize=2572 CPUtime=0 /proc/28583/stat : 28583 (p2cudf) R 28582 28581 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 11238919 2633728 41 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/28583/statm: 643 41 0 194 0 30 0 [startup+0.185694 s] /proc/loadavg: 1.64 1.52 1.37 5/35 28583 /proc/meminfo: memFree=247736/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5148 [startup+0.205693 s] /proc/loadavg: 1.64 1.52 1.37 5/35 28583 /proc/meminfo: memFree=247736/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5148 [startup+0.305712 s] /proc/loadavg: 1.64 1.52 1.37 5/35 28583 /proc/meminfo: memFree=247736/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5148 [startup+0.705829 s] /proc/loadavg: 1.64 1.52 1.37 5/35 28583 /proc/meminfo: memFree=247736/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5148 [startup+1.50604 s] /proc/loadavg: 1.64 1.52 1.37 3/47 28606 /proc/meminfo: memFree=194736/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 [pid=28595] ppid=28582 vsize=878940 CPUtime=1.46 /proc/28595/stat : 28595 (java) S 28582 28581 4778 34817 4778 4202496 25965 0 1 0 136 10 0 0 25 0 10 0 11238923 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/28595/statm: 219735 23214 1723 10 0 216826 0 [pid=28595/tid=28596] ppid=28582 vsize=878940 CPUtime=0.78 /proc/28595/task/28596/stat : 28596 (java) R 28582 28581 4778 34817 4778 4202560 16144 0 1 0 71 7 0 0 25 0 10 0 11238923 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4152946083 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=28595/tid=28597] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28597/stat : 28597 (java) S 28582 28581 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 17 0 10 0 11238925 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28598] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28598/stat : 28598 (java) S 28582 28581 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11238925 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28599] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28599/stat : 28599 (java) S 28582 28581 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11238925 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28600] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28600/stat : 28600 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11238928 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28601] ppid=28582 vsize=878940 CPUtime=0.54 /proc/28595/task/28601/stat : 28601 (java) R 28582 28581 4778 34817 4778 4202560 7623 0 0 0 52 2 0 0 24 0 10 0 11238928 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4150019695 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=28595/tid=28602] ppid=28582 vsize=878940 CPUtime=0.13 /proc/28595/task/28602/stat : 28602 (java) S 28582 28581 4778 34817 4778 4202560 1117 0 0 0 12 1 0 0 20 0 10 0 11238928 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28603] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28603/stat : 28603 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11238928 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28604] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28604/stat : 28604 (java) S 28582 28581 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 11238928 900034560 23214 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 884088 [startup+3.10647 s] /proc/loadavg: 1.64 1.52 1.37 2/47 28606 /proc/meminfo: memFree=65900/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 [pid=28595] ppid=28582 vsize=878940 CPUtime=3.04 /proc/28595/stat : 28595 (java) S 28582 28581 4778 34817 4778 4202496 72066 0 1 0 275 29 0 0 25 0 10 0 11238923 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/28595/statm: 219735 69315 1743 10 0 216826 0 [pid=28595/tid=28596] ppid=28582 vsize=878940 CPUtime=1.67 /proc/28595/task/28596/stat : 28596 (java) R 28582 28581 4778 34817 4778 4202560 50090 0 1 0 145 22 0 0 25 0 10 0 11238923 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=28595/tid=28597] ppid=28582 vsize=878940 CPUtime=0.24 /proc/28595/task/28597/stat : 28597 (java) S 28582 28581 4778 34817 4778 4202560 12100 0 0 0 20 4 0 0 16 0 10 0 11238925 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28598] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28598/stat : 28598 (java) S 28582 28581 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 10 0 11238925 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28599] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28599/stat : 28599 (java) S 28582 28581 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 11238925 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28600] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28600/stat : 28600 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11238928 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28601] ppid=28582 vsize=878940 CPUtime=0.62 /proc/28595/task/28601/stat : 28601 (java) S 28582 28581 4778 34817 4778 4202560 7647 0 0 0 60 2 0 0 25 0 10 0 11238928 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28602] ppid=28582 vsize=878940 CPUtime=0.49 /proc/28595/task/28602/stat : 28602 (java) R 28582 28581 4778 34817 4778 4202560 1152 0 0 0 48 1 0 0 16 0 10 0 11238928 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4149533661 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=28595/tid=28603] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28603/stat : 28603 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11238928 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28604] ppid=28582 vsize=878940 CPUtime=0 /proc/28595/task/28604/stat : 28604 (java) S 28582 28581 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 11238928 900034560 69315 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 884088 [startup+6.30599 s] /proc/loadavg: 1.75 1.54 1.37 3/48 28607 /proc/meminfo: memFree=5188/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 [pid=28595] ppid=28582 vsize=879588 CPUtime=5.83 /proc/28595/stat : 28595 (java) S 28582 28581 4778 34817 4778 4202496 72471 0 1 0 553 30 0 0 25 0 11 0 11238923 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/28595/statm: 219897 69720 1780 10 0 216988 0 [pid=28595/tid=28596] ppid=28582 vsize=879588 CPUtime=2.79 /proc/28595/task/28596/stat : 28596 (java) S 28582 28581 4778 34817 4778 4202560 50288 0 1 0 256 23 0 0 25 0 11 0 11238923 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28597] ppid=28582 vsize=879588 CPUtime=0.24 /proc/28595/task/28597/stat : 28597 (java) S 28582 28581 4778 34817 4778 4202560 12100 0 0 0 20 4 0 0 16 0 11 0 11238925 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28598] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28598/stat : 28598 (java) S 28582 28581 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 11 0 11238925 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28599] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28599/stat : 28599 (java) S 28582 28581 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11238925 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28600] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28600/stat : 28600 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28601] ppid=28582 vsize=879588 CPUtime=0.62 /proc/28595/task/28601/stat : 28601 (java) S 28582 28581 4778 34817 4778 4202560 7647 0 0 0 60 2 0 0 25 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28602] ppid=28582 vsize=879588 CPUtime=2.16 /proc/28595/task/28602/stat : 28602 (java) S 28582 28581 4778 34817 4778 4202560 1350 0 0 0 215 1 0 0 15 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28603] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28603/stat : 28603 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28604] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28604/stat : 28604 (java) S 28582 28581 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.87 Current children cumulated vsize (KiB) 884736 Solver just ended. Dumping a history of the last processes samples [startup+6.406 s] /proc/loadavg: 1.75 1.54 1.37 3/48 28607 /proc/meminfo: memFree=5188/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 [pid=28595] ppid=28582 vsize=879588 CPUtime=5.83 /proc/28595/stat : 28595 (java) S 28582 28581 4778 34817 4778 4202496 72471 0 1 0 553 30 0 0 25 0 11 0 11238923 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/28595/statm: 219897 69720 1780 10 0 216988 0 [pid=28595/tid=28596] ppid=28582 vsize=879588 CPUtime=2.79 /proc/28595/task/28596/stat : 28596 (java) S 28582 28581 4778 34817 4778 4202560 50288 0 1 0 256 23 0 0 25 0 11 0 11238923 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28597] ppid=28582 vsize=879588 CPUtime=0.24 /proc/28595/task/28597/stat : 28597 (java) S 28582 28581 4778 34817 4778 4202560 12100 0 0 0 20 4 0 0 16 0 11 0 11238925 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28598] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28598/stat : 28598 (java) S 28582 28581 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 11 0 11238925 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28599] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28599/stat : 28599 (java) S 28582 28581 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11238925 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28600] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28600/stat : 28600 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28601] ppid=28582 vsize=879588 CPUtime=0.62 /proc/28595/task/28601/stat : 28601 (java) S 28582 28581 4778 34817 4778 4202560 7647 0 0 0 60 2 0 0 25 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28602] ppid=28582 vsize=879588 CPUtime=2.16 /proc/28595/task/28602/stat : 28602 (java) S 28582 28581 4778 34817 4778 4202560 1350 0 0 0 215 1 0 0 15 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28603] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28603/stat : 28603 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28604] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28604/stat : 28604 (java) S 28582 28581 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 11238928 900698112 69720 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.87 Current children cumulated vsize (KiB) 884736 [startup+6.60811 s] /proc/loadavg: 1.75 1.54 1.37 3/48 28608 /proc/meminfo: memFree=6280/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 [pid=28595] ppid=28582 vsize=879588 CPUtime=6.04 /proc/28595/stat : 28595 (java) S 28582 28581 4778 34817 4778 4202496 77900 0 1 0 572 32 0 0 25 0 11 0 11238923 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/28595/statm: 219897 75149 1780 10 0 216988 0 [pid=28595/tid=28596] ppid=28582 vsize=879588 CPUtime=2.79 /proc/28595/task/28596/stat : 28596 (java) S 28582 28581 4778 34817 4778 4202560 50288 0 1 0 256 23 0 0 25 0 11 0 11238923 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28597] ppid=28582 vsize=879588 CPUtime=0.4 /proc/28595/task/28597/stat : 28597 (java) S 28582 28581 4778 34817 4778 4202560 17526 0 0 0 35 5 0 0 16 0 11 0 11238925 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28598] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28598/stat : 28598 (java) S 28582 28581 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 11 0 11238925 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28599] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28599/stat : 28599 (java) S 28582 28581 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11238925 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28600] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28600/stat : 28600 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28601] ppid=28582 vsize=879588 CPUtime=0.62 /proc/28595/task/28601/stat : 28601 (java) S 28582 28581 4778 34817 4778 4202560 7647 0 0 0 60 2 0 0 25 0 11 0 11238928 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28602] ppid=28582 vsize=879588 CPUtime=2.18 /proc/28595/task/28602/stat : 28602 (java) S 28582 28581 4778 34817 4778 4202560 1353 0 0 0 217 1 0 0 15 0 11 0 11238928 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28603] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28603/stat : 28603 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28604] ppid=28582 vsize=879588 CPUtime=0 /proc/28595/task/28604/stat : 28604 (java) R 28582 28581 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 11238928 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=28595/tid=28608] ppid=28582 vsize=879588 CPUtime=0.02 /proc/28595/task/28608/stat : 28608 (java) R 28582 28581 4778 34817 4778 4202560 4 0 0 0 2 0 0 0 20 0 11 0 11239509 900698112 75149 1283457024 134512640 134550932 4292507200 18446744073709551615 4095144840 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.08 Current children cumulated vsize (KiB) 884736 [startup+6.70814 s] /proc/loadavg: 1.75 1.54 1.37 3/48 28608 /proc/meminfo: memFree=6280/1048576 swapFree=0/0 [pid=28581] ppid=28580 vsize=2572 CPUtime=0 /proc/28581/stat : 28581 (p2cudf-paranoid) S 28580 28581 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11238919 2633728 271 1283457024 134512640 135304128 4288177008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28581/statm: 643 271 230 194 0 30 0 [pid=28582] ppid=28581 vsize=2576 CPUtime=0.04 /proc/28582/stat : 28582 (p2cudf) S 28581 28581 4778 34817 4778 4202496 392 3015 0 1 0 0 2 2 25 0 1 0 11238919 2637824 281 1283457024 134512640 135304128 4289815824 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/28582/statm: 644 281 239 194 0 31 0 [pid=28595] ppid=28582 vsize=877392 CPUtime=6.13 /proc/28595/stat : 28595 (java) S 28582 28581 4778 34817 4778 4202496 77905 0 1 0 578 35 0 0 25 0 11 0 11238923 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/28595/statm: 219348 74634 1780 10 0 216439 0 [pid=28595/tid=28596] ppid=28582 vsize=877392 CPUtime=2.79 /proc/28595/task/28596/stat : 28596 (java) S 28582 28581 4778 34817 4778 4202560 50288 0 1 0 256 23 0 0 25 0 11 0 11238923 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28597] ppid=28582 vsize=877392 CPUtime=0.4 /proc/28595/task/28597/stat : 28597 (java) S 28582 28581 4778 34817 4778 4202560 17526 0 0 0 35 5 0 0 16 0 11 0 11238925 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 0 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28598] ppid=28582 vsize=877392 CPUtime=0 /proc/28595/task/28598/stat : 28598 (java) S 28582 28581 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 11 0 11238925 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28599] ppid=28582 vsize=877392 CPUtime=0 /proc/28595/task/28599/stat : 28599 (java) S 28582 28581 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 11238925 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28600] ppid=28582 vsize=877392 CPUtime=0 /proc/28595/task/28600/stat : 28600 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28601] ppid=28582 vsize=877392 CPUtime=0.62 /proc/28595/task/28601/stat : 28601 (java) S 28582 28581 4778 34817 4778 4202560 7647 0 0 0 60 2 0 0 25 0 11 0 11238928 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28602] ppid=28582 vsize=877392 CPUtime=2.22 /proc/28595/task/28602/stat : 28602 (java) R 28582 28581 4778 34817 4778 4202560 1356 0 0 0 221 1 0 0 16 0 11 0 11238928 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4152372476 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=28595/tid=28603] ppid=28582 vsize=877392 CPUtime=0 /proc/28595/task/28603/stat : 28603 (java) S 28582 28581 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11238928 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28604] ppid=28582 vsize=877392 CPUtime=0 /proc/28595/task/28604/stat : 28604 (java) S 28582 28581 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 11238928 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 18446612133392889664 0 0 -1 0 0 0 0 [pid=28595/tid=28608] ppid=28582 vsize=877392 CPUtime=0.07 /proc/28595/task/28608/stat : 28608 (java) R 28582 28581 4778 34817 4778 4202560 6 0 0 0 4 3 0 0 21 0 11 0 11239509 898449408 74634 1283457024 134512640 134550932 4292507200 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.17 Current children cumulated vsize (KiB) 882540 Child status: 0 Real time (s): 6.80968 CPU time (s): 6.26839 CPU user time (s): 5.86037 CPU system time (s): 0.408025 CPU usage (%): 92.0512 Max. virtual memory (cumulated for all children) (KiB): 884736 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.86037 system time used= 0.408025 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 81715 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= 940 involuntary context switches= 961 runsolver used 0 second user time and 0 second system time The end