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/1109.cudf.caixa.log.runsolver ./p2cudf-paranoid-1.12 /home/misc2010/data/misc4/caixa//1109.cudf /home/misc2010/tmp/201103031013/p2cudf-paranoid-1.12/1109.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.07 1.06 1.07 5/39 6198 /proc/meminfo: memFree=566420/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2568 CPUtime=0 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 352 0 0 0 0 0 0 0 24 0 1 0 75689707 2629632 271 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/6195/statm: 642 271 231 194 0 29 0 [pid=6196] ppid=6195 vsize=2568 CPUtime=0 /proc/6196/stat : 6196 (p2cudf) R 6195 6194 29493 34817 29493 4202560 116 0 0 0 0 0 0 0 25 0 1 0 75689707 2629632 125 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/6196/statm: 642 125 84 194 0 29 0 [pid=6197] ppid=6196 vsize=1652 CPUtime=0 /proc/6197/stat : 6197 (java) R 6196 6194 29493 34817 29493 4194304 356 0 0 0 0 0 0 0 25 0 1 0 75689707 1691648 85 1283457024 134512640 134550932 4293898496 18446744073709551615 4159012272 0 0 0 0 0 0 0 17 0 0 0 0 /proc/6197/statm: 413 85 64 10 0 14 0 [pid=6198] ppid=6196 vsize=2568 CPUtime=0 /proc/6198/stat : 6198 (p2cudf) R 6196 6194 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75689707 2629632 41 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/6198/statm: 642 41 0 194 0 29 0 [startup+0.185713 s] /proc/loadavg: 1.07 1.06 1.07 5/39 6198 /proc/meminfo: memFree=566420/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+0.205712 s] /proc/loadavg: 1.07 1.06 1.07 5/39 6198 /proc/meminfo: memFree=566420/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+0.305734 s] /proc/loadavg: 1.07 1.06 1.07 5/39 6198 /proc/meminfo: memFree=566420/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+0.70582 s] /proc/loadavg: 1.07 1.06 1.07 5/39 6198 /proc/meminfo: memFree=566420/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 Current children cumulated CPU time (s) 0.04 Current children cumulated vsize (KiB) 5144 [startup+1.50602 s] /proc/loadavg: 1.23 1.10 1.08 5/47 6217 /proc/meminfo: memFree=524512/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=882616 CPUtime=1.46 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 17616 0 1 0 144 2 0 0 25 0 10 0 75689710 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220654 13803 1725 10 0 217745 0 [pid=6208/tid=6209] ppid=6195 vsize=882616 CPUtime=0.62 /proc/6208/task/6209/stat : 6209 (java) R 6195 6194 29493 34817 29493 4202560 5875 0 1 0 61 1 0 0 25 0 10 0 75689711 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=882616 CPUtime=0 /proc/6208/task/6210/stat : 6210 (java) S 6195 6194 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 15 0 10 0 75689712 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=882616 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75689713 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=882616 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75689713 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=882616 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689715 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=882616 CPUtime=0.55 /proc/6208/task/6214/stat : 6214 (java) R 6195 6194 29493 34817 29493 4202560 7947 0 0 0 54 1 0 0 21 0 10 0 75689715 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4147483009 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=882616 CPUtime=0.28 /proc/6208/task/6215/stat : 6215 (java) R 6195 6194 29493 34817 29493 4202560 2713 0 0 0 28 0 0 0 24 0 10 0 75689715 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4151574033 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=882616 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689716 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=882616 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) S 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75689716 903798784 13803 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 887760 [startup+3.10644 s] /proc/loadavg: 1.23 1.10 1.08 4/47 6217 /proc/meminfo: memFree=457552/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=883316 CPUtime=3.06 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 59419 0 1 0 288 18 0 0 25 0 10 0 75689710 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220829 53791 1743 10 0 217920 0 [pid=6208/tid=6209] ppid=6195 vsize=883316 CPUtime=1.48 /proc/6208/task/6209/stat : 6209 (java) R 6195 6194 29493 34817 29493 4202560 45526 0 1 0 134 14 0 0 25 0 10 0 75689711 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6210/stat : 6210 (java) S 6195 6194 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 15 0 10 0 75689712 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75689713 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75689713 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689715 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=883316 CPUtime=1.11 /proc/6208/task/6214/stat : 6214 (java) R 6195 6194 29493 34817 29493 4202560 9196 0 0 0 110 1 0 0 18 0 10 0 75689715 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4153172337 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=883316 CPUtime=0.45 /proc/6208/task/6215/stat : 6215 (java) S 6195 6194 29493 34817 29493 4202560 3616 0 0 0 43 2 0 0 25 0 10 0 75689715 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689716 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) S 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75689716 904515584 53791 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 888460 Solver just ended. Dumping a history of the last processes samples [startup+3.22651 s] /proc/loadavg: 1.23 1.10 1.08 4/47 6217 /proc/meminfo: memFree=457552/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=883316 CPUtime=3.17 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 65199 0 1 0 298 19 0 0 25 0 10 0 75689710 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220829 59571 1744 10 0 217920 0 [pid=6208/tid=6209] ppid=6195 vsize=883316 CPUtime=1.53 /proc/6208/task/6209/stat : 6209 (java) R 6195 6194 29493 34817 29493 4202560 50068 0 1 0 139 14 0 0 25 0 10 0 75689711 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=883316 CPUtime=0.03 /proc/6208/task/6210/stat : 6210 (java) R 6195 6194 29493 34817 29493 4202560 1238 0 0 0 2 1 0 0 15 0 10 0 75689712 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4149208541 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75689713 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75689713 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689715 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=883316 CPUtime=1.15 /proc/6208/task/6214/stat : 6214 (java) S 6195 6194 29493 34817 29493 4202560 9202 0 0 0 114 1 0 0 17 0 10 0 75689715 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=883316 CPUtime=0.45 /proc/6208/task/6215/stat : 6215 (java) S 6195 6194 29493 34817 29493 4202560 3616 0 0 0 43 2 0 0 25 0 10 0 75689715 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689716 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=883316 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) S 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75689716 904515584 59571 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.21 Current children cumulated vsize (KiB) 888460 [startup+4.0066 s] /proc/loadavg: 1.23 1.10 1.08 3/47 6217 /proc/meminfo: memFree=316192/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=883164 CPUtime=3.95 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 81897 0 1 0 368 27 0 0 25 0 10 0 75689710 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220791 74664 1748 10 0 217882 0 [pid=6208/tid=6209] ppid=6195 vsize=883164 CPUtime=1.62 /proc/6208/task/6209/stat : 6209 (java) R 6195 6194 29493 34817 29493 4202560 50191 0 1 0 148 14 0 0 25 0 10 0 75689711 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4095050974 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=883164 CPUtime=0.36 /proc/6208/task/6210/stat : 6210 (java) S 6195 6194 29493 34817 29493 4202560 16181 0 0 0 28 8 0 0 16 0 10 0 75689712 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 10 0 75689713 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75689713 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689715 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=883164 CPUtime=1.5 /proc/6208/task/6214/stat : 6214 (java) S 6195 6194 29493 34817 29493 4202560 10831 0 0 0 148 2 0 0 15 0 10 0 75689715 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=883164 CPUtime=0.45 /proc/6208/task/6215/stat : 6215 (java) S 6195 6194 29493 34817 29493 4202560 3616 0 0 0 43 2 0 0 25 0 10 0 75689715 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689716 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) S 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75689716 904359936 74664 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.99 Current children cumulated vsize (KiB) 888308 [startup+4.40669 s] /proc/loadavg: 1.23 1.10 1.08 2/47 6217 /proc/meminfo: memFree=273536/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=883164 CPUtime=4.35 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 81929 0 1 0 408 27 0 0 25 0 10 0 75689710 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220791 74696 1754 10 0 217882 0 [pid=6208/tid=6209] ppid=6195 vsize=883164 CPUtime=1.79 /proc/6208/task/6209/stat : 6209 (java) R 6195 6194 29493 34817 29493 4202560 50199 0 1 0 165 14 0 0 25 0 10 0 75689711 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4095050884 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=883164 CPUtime=0.36 /proc/6208/task/6210/stat : 6210 (java) S 6195 6194 29493 34817 29493 4202560 16181 0 0 0 28 8 0 0 16 0 10 0 75689712 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 10 0 75689713 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75689713 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689715 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=883164 CPUtime=1.74 /proc/6208/task/6214/stat : 6214 (java) S 6195 6194 29493 34817 29493 4202560 10855 0 0 0 172 2 0 0 15 0 10 0 75689715 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=883164 CPUtime=0.45 /proc/6208/task/6215/stat : 6215 (java) S 6195 6194 29493 34817 29493 4202560 3616 0 0 0 43 2 0 0 25 0 10 0 75689715 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75689716 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=883164 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) S 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 10 0 75689716 904359936 74696 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.39 Current children cumulated vsize (KiB) 888308 [startup+4.80601 s] /proc/loadavg: 1.23 1.10 1.08 2/47 6217 /proc/meminfo: memFree=273536/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=883488 CPUtime=4.36 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 81936 0 1 0 409 27 0 0 25 0 11 0 75689710 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220872 74703 1754 10 0 217963 0 [pid=6208/tid=6209] ppid=6195 vsize=883488 CPUtime=1.79 /proc/6208/task/6209/stat : 6209 (java) S 6195 6194 29493 34817 29493 4202560 50202 0 1 0 165 14 0 0 25 0 11 0 75689711 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=883488 CPUtime=0.36 /proc/6208/task/6210/stat : 6210 (java) S 6195 6194 29493 34817 29493 4202560 16181 0 0 0 28 8 0 0 15 0 11 0 75689712 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75689713 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75689713 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75689715 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=883488 CPUtime=1.75 /proc/6208/task/6214/stat : 6214 (java) S 6195 6194 29493 34817 29493 4202560 10855 0 0 0 173 2 0 0 15 0 11 0 75689715 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=883488 CPUtime=0.45 /proc/6208/task/6215/stat : 6215 (java) S 6195 6194 29493 34817 29493 4202560 3616 0 0 0 43 2 0 0 25 0 11 0 75689715 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75689716 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) R 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 75689716 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.4 Current children cumulated vsize (KiB) 888632 [startup+4.90601 s] /proc/loadavg: 1.23 1.10 1.08 2/47 6217 /proc/meminfo: memFree=273536/1048576 swapFree=0/0 [pid=6194] ppid=6193 vsize=2568 CPUtime=0 /proc/6194/stat : 6194 (p2cudf-paranoid) S 6193 6194 29493 34817 29493 4202496 373 0 0 0 0 0 0 0 24 0 1 0 75689706 2629632 270 1283457024 134512640 135304128 4293798160 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6194/statm: 642 270 230 194 0 29 0 [pid=6195] ppid=6194 vsize=2576 CPUtime=0.04 /proc/6195/stat : 6195 (p2cudf) S 6194 6194 29493 34817 29493 4202496 394 3014 0 1 0 0 2 2 25 0 1 0 75689707 2637824 281 1283457024 134512640 135304128 4294509008 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/6195/statm: 644 281 239 194 0 31 0 [pid=6208] ppid=6195 vsize=883488 CPUtime=4.36 /proc/6208/stat : 6208 (java) S 6195 6194 29493 34817 29493 4202496 81936 0 1 0 409 27 0 0 25 0 11 0 75689710 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/6208/statm: 220872 74703 1754 10 0 217963 0 [pid=6208/tid=6209] ppid=6195 vsize=883488 CPUtime=1.79 /proc/6208/task/6209/stat : 6209 (java) S 6195 6194 29493 34817 29493 4202560 50202 0 1 0 165 14 0 0 25 0 11 0 75689711 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6210] ppid=6195 vsize=883488 CPUtime=0.36 /proc/6208/task/6210/stat : 6210 (java) S 6195 6194 29493 34817 29493 4202560 16181 0 0 0 28 8 0 0 15 0 11 0 75689712 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 0 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6211] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6211/stat : 6211 (java) S 6195 6194 29493 34817 29493 4202560 9 0 0 0 0 0 0 0 18 0 11 0 75689713 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6212] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6212/stat : 6212 (java) S 6195 6194 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75689713 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6213] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6213/stat : 6213 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75689715 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6214] ppid=6195 vsize=883488 CPUtime=1.75 /proc/6208/task/6214/stat : 6214 (java) S 6195 6194 29493 34817 29493 4202560 10855 0 0 0 173 2 0 0 15 0 11 0 75689715 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6215] ppid=6195 vsize=883488 CPUtime=0.45 /proc/6208/task/6215/stat : 6215 (java) S 6195 6194 29493 34817 29493 4202560 3616 0 0 0 43 2 0 0 25 0 11 0 75689715 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6216] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6216/stat : 6216 (java) S 6195 6194 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75689716 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 [pid=6208/tid=6217] ppid=6195 vsize=883488 CPUtime=0 /proc/6208/task/6217/stat : 6217 (java) S 6195 6194 29493 34817 29493 4202560 2 0 0 0 0 0 0 0 15 0 11 0 75689716 904691712 74703 1283457024 134512640 134550932 4286726400 18446744073709551615 4294960130 0 4 0 16800975 18446612132467747520 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.4 Current children cumulated vsize (KiB) 888632 Child status: 0 Real time (s): 5.00535 CPU time (s): 4.44028 CPU user time (s): 4.11626 CPU system time (s): 0.32402 CPU usage (%): 88.7106 Max. virtual memory (cumulated for all children) (KiB): 892072 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.11626 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= 85744 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= 413 involuntary context switches= 389 runsolver used 0 second user time and 0 second system time The end