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/201103031013/p2cudf-paranoid-1.12/23.cudf.caixa.log.runsolver ./p2cudf-paranoid-1.12 /home/misc2010/data/misc4/caixa//23.cudf /home/misc2010/tmp/201103031013/p2cudf-paranoid-1.12/23.cudf.caixa.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.85 1.70 1.52 5/36 32180 /proc/meminfo: memFree=296796/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2564 CPUtime=0 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 351 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 270 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/32178/statm: 641 270 231 194 0 28 0 [pid=32179] ppid=32178 vsize=2564 CPUtime=0 /proc/32179/stat : 32179 (p2cudf) R 32178 32177 29493 34817 29493 4202560 93 0 0 0 0 0 0 0 25 0 1 0 75898456 2625536 117 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/32179/statm: 641 117 77 194 0 28 0 [pid=32180] ppid=32179 vsize=2564 CPUtime=0 /proc/32180/stat : 32180 (p2cudf) R 32179 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75898456 2625536 40 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/32180/statm: 641 40 0 194 0 28 0 [startup+0.185672 s] /proc/loadavg: 1.85 1.70 1.52 5/36 32180 /proc/meminfo: memFree=296796/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+0.205667 s] /proc/loadavg: 1.85 1.70 1.52 5/36 32180 /proc/meminfo: memFree=296796/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+0.305696 s] /proc/loadavg: 1.85 1.70 1.52 5/36 32180 /proc/meminfo: memFree=296796/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+0.705778 s] /proc/loadavg: 1.85 1.70 1.52 5/36 32180 /proc/meminfo: memFree=296796/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5136 [startup+1.50597 s] /proc/loadavg: 1.85 1.70 1.52 5/45 32200 /proc/meminfo: memFree=255864/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=881720 CPUtime=1.46 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 18033 0 1 0 139 7 0 0 25 0 10 0 75898459 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220430 13484 1727 10 0 217521 0 [pid=32191/tid=32192] ppid=32178 vsize=881720 CPUtime=0.64 /proc/32191/task/32192/stat : 32192 (java) R 32178 32177 29493 34817 29493 4202560 5866 0 1 0 63 1 0 0 25 0 10 0 75898460 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=881720 CPUtime=0 /proc/32191/task/32193/stat : 32193 (java) S 32178 32177 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 10 0 75898462 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=881720 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75898462 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=881720 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75898462 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=881720 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75898465 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=881720 CPUtime=0.55 /proc/32191/task/32197/stat : 32197 (java) R 32178 32177 29493 34817 29493 4202560 8833 0 0 0 50 5 0 0 22 0 10 0 75898465 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4149701293 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=881720 CPUtime=0.25 /proc/32191/task/32198/stat : 32198 (java) R 32178 32177 29493 34817 29493 4202560 2254 0 0 0 25 0 0 0 24 0 10 0 75898465 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4148852513 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=881720 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75898465 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=881720 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75898465 902881280 13484 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 886856 [startup+3.10632 s] /proc/loadavg: 1.85 1.70 1.52 3/45 32200 /proc/meminfo: memFree=212712/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=881260 CPUtime=3.06 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 58562 0 1 0 284 22 0 0 25 0 10 0 75898459 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220315 50137 1742 10 0 217406 0 [pid=32191/tid=32192] ppid=32178 vsize=881260 CPUtime=1.45 /proc/32191/task/32192/stat : 32192 (java) R 32178 32177 29493 34817 29493 4202560 42348 0 1 0 129 16 0 0 25 0 10 0 75898460 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32193/stat : 32193 (java) S 32178 32177 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 10 0 75898462 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75898462 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75898462 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75898465 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=881260 CPUtime=0.85 /proc/32191/task/32197/stat : 32197 (java) S 32178 32177 29493 34817 29493 4202560 9137 0 0 0 80 5 0 0 15 0 10 0 75898465 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=881260 CPUtime=0.74 /proc/32191/task/32198/stat : 32198 (java) R 32178 32177 29493 34817 29493 4202560 5997 0 0 0 74 0 0 0 24 0 10 0 75898465 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4152558007 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75898465 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75898465 902410240 50137 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 886396 Solver just ended. Dumping a history of the last processes samples [startup+3.21638 s] /proc/loadavg: 1.85 1.70 1.52 3/45 32200 /proc/meminfo: memFree=212712/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=881260 CPUtime=3.17 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 59330 0 1 0 295 22 0 0 25 0 10 0 75898459 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220315 50905 1743 10 0 217406 0 [pid=32191/tid=32192] ppid=32178 vsize=881260 CPUtime=1.5 /proc/32191/task/32192/stat : 32192 (java) R 32178 32177 29493 34817 29493 4202560 43114 0 1 0 134 16 0 0 25 0 10 0 75898460 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32193/stat : 32193 (java) S 32178 32177 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 15 0 10 0 75898462 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75898462 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75898462 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75898465 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=881260 CPUtime=0.88 /proc/32191/task/32197/stat : 32197 (java) R 32178 32177 29493 34817 29493 4202560 9138 0 0 0 83 5 0 0 15 0 10 0 75898465 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4151800396 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=881260 CPUtime=0.77 /proc/32191/task/32198/stat : 32198 (java) R 32178 32177 29493 34817 29493 4202560 5998 0 0 0 77 0 0 0 24 0 10 0 75898465 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4152252869 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75898465 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=881260 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75898465 902410240 50905 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 886396 [startup+4.00649 s] /proc/loadavg: 1.78 1.68 1.52 4/45 32200 /proc/meminfo: memFree=99004/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=883288 CPUtime=3.96 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 81908 0 1 0 370 26 0 0 25 0 10 0 75898459 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220822 72338 1747 10 0 217913 0 [pid=32191/tid=32192] ppid=32178 vsize=883288 CPUtime=1.59 /proc/32191/task/32192/stat : 32192 (java) R 32178 32177 29493 34817 29493 4202560 50172 0 1 0 143 16 0 0 25 0 10 0 75898460 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=883288 CPUtime=0.28 /proc/32191/task/32193/stat : 32193 (java) R 32178 32177 29493 34817 29493 4202560 13990 0 0 0 25 3 0 0 16 0 10 0 75898462 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4149440888 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=883288 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75898462 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=883288 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75898462 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=883288 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75898465 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=883288 CPUtime=1.04 /proc/32191/task/32197/stat : 32197 (java) S 32178 32177 29493 34817 29493 4202560 9153 0 0 0 99 5 0 0 15 0 10 0 75898465 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=883288 CPUtime=1.02 /proc/32191/task/32198/stat : 32198 (java) R 32178 32177 29493 34817 29493 4202560 7520 0 0 0 102 0 0 0 25 0 10 0 75898465 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4148803262 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=883288 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75898465 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=883288 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75898465 904486912 72338 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.99 Current children cumulated vsize (KiB) 888424 [startup+4.80588 s] /proc/loadavg: 1.78 1.68 1.52 3/45 32200 /proc/meminfo: memFree=6500/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=881584 CPUtime=4.56 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 84484 0 1 0 430 26 0 0 25 0 11 0 75898459 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220396 73772 1754 10 0 217487 0 [pid=32191/tid=32192] ppid=32178 vsize=881584 CPUtime=1.8 /proc/32191/task/32192/stat : 32192 (java) S 32178 32177 29493 34817 29493 4202560 50195 0 1 0 164 16 0 0 25 0 11 0 75898460 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=881584 CPUtime=0.34 /proc/32191/task/32193/stat : 32193 (java) S 32178 32177 29493 34817 29493 4202560 15718 0 0 0 31 3 0 0 16 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=881584 CPUtime=1.25 /proc/32191/task/32197/stat : 32197 (java) S 32178 32177 29493 34817 29493 4202560 9181 0 0 0 120 5 0 0 15 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=881584 CPUtime=1.14 /proc/32191/task/32198/stat : 32198 (java) S 32178 32177 29493 34817 29493 4202560 8311 0 0 0 114 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.59 Current children cumulated vsize (KiB) 886720 [startup+5.00588 s] /proc/loadavg: 1.78 1.68 1.52 3/45 32200 /proc/meminfo: memFree=6500/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=881584 CPUtime=4.56 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 84484 0 1 0 430 26 0 0 25 0 11 0 75898459 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220396 73772 1754 10 0 217487 0 [pid=32191/tid=32192] ppid=32178 vsize=881584 CPUtime=1.8 /proc/32191/task/32192/stat : 32192 (java) S 32178 32177 29493 34817 29493 4202560 50195 0 1 0 164 16 0 0 25 0 11 0 75898460 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=881584 CPUtime=0.34 /proc/32191/task/32193/stat : 32193 (java) S 32178 32177 29493 34817 29493 4202560 15718 0 0 0 31 3 0 0 16 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=881584 CPUtime=1.25 /proc/32191/task/32197/stat : 32197 (java) S 32178 32177 29493 34817 29493 4202560 9181 0 0 0 120 5 0 0 15 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=881584 CPUtime=1.14 /proc/32191/task/32198/stat : 32198 (java) S 32178 32177 29493 34817 29493 4202560 8311 0 0 0 114 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.59 Current children cumulated vsize (KiB) 886720 [startup+5.1059 s] /proc/loadavg: 1.78 1.68 1.52 3/45 32200 /proc/meminfo: memFree=6500/1048576 swapFree=0/0 [pid=32177] ppid=32176 vsize=2564 CPUtime=0 /proc/32177/stat : 32177 (p2cudf-paranoid) S 32176 32177 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 18 0 1 0 75898456 2625536 269 1283457024 134512640 135304128 4293161072 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32177/statm: 641 269 230 194 0 28 0 [pid=32178] ppid=32177 vsize=2572 CPUtime=0.03 /proc/32178/stat : 32178 (p2cudf) S 32177 32177 29493 34817 29493 4202496 393 3010 0 1 0 0 0 3 25 0 1 0 75898456 2633728 280 1283457024 134512640 135304128 4294545888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/32178/statm: 643 280 239 194 0 30 0 [pid=32191] ppid=32178 vsize=881584 CPUtime=4.56 /proc/32191/stat : 32191 (java) S 32178 32177 29493 34817 29493 4202496 84484 0 1 0 430 26 0 0 25 0 11 0 75898459 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/32191/statm: 220396 73772 1754 10 0 217487 0 [pid=32191/tid=32192] ppid=32178 vsize=881584 CPUtime=1.8 /proc/32191/task/32192/stat : 32192 (java) S 32178 32177 29493 34817 29493 4202560 50195 0 1 0 164 16 0 0 25 0 11 0 75898460 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32193] ppid=32178 vsize=881584 CPUtime=0.34 /proc/32191/task/32193/stat : 32193 (java) S 32178 32177 29493 34817 29493 4202560 15718 0 0 0 31 3 0 0 15 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32194] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32194/stat : 32194 (java) S 32178 32177 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32195] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32195/stat : 32195 (java) S 32178 32177 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75898462 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32196] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32196/stat : 32196 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32197] ppid=32178 vsize=881584 CPUtime=1.25 /proc/32191/task/32197/stat : 32197 (java) S 32178 32177 29493 34817 29493 4202560 9181 0 0 0 120 5 0 0 15 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32198] ppid=32178 vsize=881584 CPUtime=1.14 /proc/32191/task/32198/stat : 32198 (java) S 32178 32177 29493 34817 29493 4202560 8311 0 0 0 114 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32199] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32199/stat : 32199 (java) S 32178 32177 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=32191/tid=32200] ppid=32178 vsize=881584 CPUtime=0 /proc/32191/task/32200/stat : 32200 (java) S 32178 32177 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75898465 902742016 73772 1283457024 134512640 134550932 4289340288 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.59 Current children cumulated vsize (KiB) 886720 Child status: 0 Real time (s): 5.20057 CPU time (s): 4.63629 CPU user time (s): 4.31227 CPU system time (s): 0.32402 CPU usage (%): 89.1496 Max. virtual memory (cumulated for all children) (KiB): 891420 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.31227 system time used= 0.32402 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 88286 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= 402 involuntary context switches= 387 runsolver used 0 second user time and 0 second system time The end