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/rand230.cudf.s-e.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/incremental/s-e/rand230.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/rand230.cudf.s-e.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.17 1.05 1.01 4/37 8659 /proc/meminfo: memFree=357612/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2564 CPUtime=0 /proc/8658/stat : 8658 (p2cudf) R 8657 8657 4778 34817 4778 4202496 344 0 0 0 0 0 0 0 25 0 1 0 10325853 2625536 270 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/8658/statm: 641 270 231 194 0 28 0 [pid=8659] ppid=8658 vsize=2564 CPUtime=0 /proc/8659/stat : 8659 (p2cudf) R 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 10325854 2625536 39 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/8659/statm: 641 39 0 194 0 28 0 [startup+0.16452 s] /proc/loadavg: 1.17 1.05 1.01 4/37 8659 /proc/meminfo: memFree=357612/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.204514 s] /proc/loadavg: 1.17 1.05 1.01 4/37 8659 /proc/meminfo: memFree=357612/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.304526 s] /proc/loadavg: 1.17 1.05 1.01 4/37 8659 /proc/meminfo: memFree=357612/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.704594 s] /proc/loadavg: 1.17 1.05 1.01 4/37 8659 /proc/meminfo: memFree=357612/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+1.50485 s] /proc/loadavg: 1.17 1.05 1.01 4/47 8680 /proc/meminfo: memFree=307828/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=894156 CPUtime=1.46 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 24910 0 1 0 140 6 0 0 25 0 10 0 10325857 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223539 21269 1724 10 0 220630 0 [pid=8671/tid=8672] ppid=8658 vsize=894156 CPUtime=0.77 /proc/8671/task/8672/stat : 8672 (java) R 8658 8657 4778 34817 4778 4202560 11105 0 1 0 73 4 0 0 25 0 10 0 10325857 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4095341408 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=894156 CPUtime=0 /proc/8671/task/8673/stat : 8673 (java) S 8658 8657 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 17 0 10 0 10325859 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=894156 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 10325859 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=894156 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10325859 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=894156 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10325862 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=894156 CPUtime=0.43 /proc/8671/task/8677/stat : 8677 (java) R 8658 8657 4778 34817 4778 4202560 8929 0 0 0 43 0 0 0 25 0 10 0 10325862 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=894156 CPUtime=0.24 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 3798 0 0 0 23 1 0 0 22 0 10 0 10325862 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=894156 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10325862 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=894156 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10325862 915615744 21269 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 899292 [startup+3.10539 s] /proc/loadavg: 1.17 1.05 1.01 2/47 8680 /proc/meminfo: memFree=214084/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=892568 CPUtime=3.06 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 52927 0 1 0 288 18 0 0 25 0 10 0 10325857 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223142 46624 1744 10 0 220233 0 [pid=8671/tid=8672] ppid=8658 vsize=892568 CPUtime=1.63 /proc/8671/task/8672/stat : 8672 (java) R 8658 8657 4778 34817 4778 4202560 36246 0 1 0 148 15 0 0 25 0 10 0 10325857 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=892568 CPUtime=0 /proc/8671/task/8673/stat : 8673 (java) S 8658 8657 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 10 0 10325859 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=892568 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 10325859 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=892568 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10325859 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=892568 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10325862 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=892568 CPUtime=0.69 /proc/8671/task/8677/stat : 8677 (java) R 8658 8657 4778 34817 4778 4202560 9692 0 0 0 67 2 0 0 24 0 10 0 10325862 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4151723566 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=892568 CPUtime=0.73 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 5911 0 0 0 72 1 0 0 15 0 10 0 10325862 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=892568 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10325862 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=892568 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10325862 913989632 46624 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 897704 Solver just ended. Dumping a history of the last processes samples [startup+3.20543 s] /proc/loadavg: 1.17 1.05 1.01 2/47 8680 /proc/meminfo: memFree=214084/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=893956 CPUtime=3.16 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 53243 0 1 0 298 18 0 0 25 0 10 0 10325857 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223489 46940 1744 10 0 220580 0 [pid=8671/tid=8672] ppid=8658 vsize=893956 CPUtime=1.63 /proc/8671/task/8672/stat : 8672 (java) R 8658 8657 4778 34817 4778 4202560 36246 0 1 0 148 15 0 0 25 0 10 0 10325857 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=893956 CPUtime=0 /proc/8671/task/8673/stat : 8673 (java) S 8658 8657 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 10 0 10325859 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=893956 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 10325859 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=893956 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10325859 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=893956 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10325862 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=893956 CPUtime=0.79 /proc/8671/task/8677/stat : 8677 (java) R 8658 8657 4778 34817 4778 4202560 10008 0 0 0 77 2 0 0 24 0 10 0 10325862 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4148563972 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=893956 CPUtime=0.73 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 5911 0 0 0 72 1 0 0 15 0 10 0 10325862 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=893956 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10325862 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=893956 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10325862 915410944 46940 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.18 Current children cumulated vsize (KiB) 899092 [startup+4.80597 s] /proc/loadavg: 1.31 1.09 1.02 4/47 8681 /proc/meminfo: memFree=128284/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=893156 CPUtime=4.76 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 66642 0 1 0 454 22 0 0 25 0 11 0 10325857 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223289 59637 1768 10 0 220380 0 [pid=8671/tid=8672] ppid=8658 vsize=893156 CPUtime=2.33 /proc/8671/task/8672/stat : 8672 (java) R 8658 8657 4778 34817 4778 4202560 48963 0 1 0 214 19 0 0 25 0 11 0 10325857 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4095050308 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8673/stat : 8673 (java) S 8658 8657 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 11 0 10325859 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 10325859 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10325859 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10325862 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=893156 CPUtime=1.3 /proc/8671/task/8677/stat : 8677 (java) R 8658 8657 4778 34817 4778 4202560 10643 0 0 0 128 2 0 0 24 0 11 0 10325862 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4149502030 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=893156 CPUtime=1.12 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 5954 0 0 0 111 1 0 0 16 0 11 0 10325862 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10325862 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10325862 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8681] ppid=8658 vsize=893156 CPUtime=0 /proc/8671/task/8681/stat : 8681 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10326278 914591744 59637 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.78 Current children cumulated vsize (KiB) 898292 [startup+5.50786 s] /proc/loadavg: 1.31 1.09 1.02 1/47 8682 /proc/meminfo: memFree=121960/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=893480 CPUtime=5.11 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 67460 0 1 0 489 22 0 0 25 0 11 0 10325857 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223370 60455 1772 10 0 220461 0 [pid=8671/tid=8672] ppid=8658 vsize=893480 CPUtime=2.54 /proc/8671/task/8672/stat : 8672 (java) S 8658 8657 4778 34817 4778 4202560 49760 0 1 0 235 19 0 0 25 0 11 0 10325857 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8673/stat : 8673 (java) S 8658 8657 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 11 0 10325859 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 10325859 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10325859 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10325862 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=893480 CPUtime=1.33 /proc/8671/task/8677/stat : 8677 (java) S 8658 8657 4778 34817 4778 4202560 10646 0 0 0 131 2 0 0 24 0 11 0 10325862 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=893480 CPUtime=1.23 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 5967 0 0 0 122 1 0 0 16 0 11 0 10325862 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10325862 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10325862 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8682] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8682/stat : 8682 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10326369 914923520 60455 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.13 Current children cumulated vsize (KiB) 898616 [startup+5.71194 s] /proc/loadavg: 1.31 1.09 1.02 1/47 8682 /proc/meminfo: memFree=121960/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=893480 CPUtime=5.16 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 69538 0 1 0 494 22 0 0 25 0 11 0 10325857 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223370 62533 1773 10 0 220461 0 [pid=8671/tid=8672] ppid=8658 vsize=893480 CPUtime=2.54 /proc/8671/task/8672/stat : 8672 (java) S 8658 8657 4778 34817 4778 4202560 49760 0 1 0 235 19 0 0 25 0 11 0 10325857 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=893480 CPUtime=0.05 /proc/8671/task/8673/stat : 8673 (java) R 8658 8657 4778 34817 4778 4202560 2085 0 0 0 5 0 0 0 16 0 11 0 10325859 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4149199224 0 0 0 16800975 0 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 10325859 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10325859 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10325862 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=893480 CPUtime=1.33 /proc/8671/task/8677/stat : 8677 (java) S 8658 8657 4778 34817 4778 4202560 10646 0 0 0 131 2 0 0 24 0 11 0 10325862 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=893480 CPUtime=1.23 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 5967 0 0 0 122 1 0 0 16 0 11 0 10325862 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10325862 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10325862 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8682] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8682/stat : 8682 (java) R 8658 8657 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 20 0 11 0 10326369 914923520 62533 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.18 Current children cumulated vsize (KiB) 898616 [startup+5.80791 s] /proc/loadavg: 1.31 1.09 1.02 1/47 8682 /proc/meminfo: memFree=121960/1048576 swapFree=0/0 [pid=8657] ppid=8656 vsize=2568 CPUtime=0 /proc/8657/stat : 8657 (p2cudf-paranoid) S 8656 8657 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 25 0 1 0 10325853 2629632 269 1283457024 134512640 135304128 4290006368 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8657/statm: 642 269 230 194 0 29 0 [pid=8658] ppid=8657 vsize=2568 CPUtime=0.02 /proc/8658/stat : 8658 (p2cudf) S 8657 8657 4778 34817 4778 4202496 387 3014 0 1 0 0 2 0 25 0 1 0 10325853 2629632 279 1283457024 134512640 135304128 4291661584 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/8658/statm: 642 279 239 194 0 29 0 [pid=8671] ppid=8658 vsize=893480 CPUtime=5.24 /proc/8671/stat : 8671 (java) S 8658 8657 4778 34817 4778 4202496 69661 0 1 0 498 26 0 0 25 0 10 0 10325857 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/8671/statm: 223370 62656 1774 10 0 220461 0 [pid=8671/tid=8672] ppid=8658 vsize=893480 CPUtime=2.54 /proc/8671/task/8672/stat : 8672 (java) S 8658 8657 4778 34817 4778 4202560 49760 0 1 0 235 19 0 0 19 0 10 0 10325857 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8673] ppid=8658 vsize=893480 CPUtime=0.05 /proc/8671/task/8673/stat : 8673 (java) S 8658 8657 4778 34817 4778 4202560 2203 0 0 0 5 0 0 0 16 0 10 0 10325859 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8674] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8674/stat : 8674 (java) S 8658 8657 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 18 0 10 0 10325859 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8675] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8675/stat : 8675 (java) S 8658 8657 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10325859 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8676] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8676/stat : 8676 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10325862 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8677] ppid=8658 vsize=893480 CPUtime=1.33 /proc/8671/task/8677/stat : 8677 (java) S 8658 8657 4778 34817 4778 4202560 10647 0 0 0 131 2 0 0 18 0 10 0 10325862 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8678] ppid=8658 vsize=893480 CPUtime=1.23 /proc/8671/task/8678/stat : 8678 (java) S 8658 8657 4778 34817 4778 4202560 5967 0 0 0 122 1 0 0 16 0 10 0 10325862 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8679] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8679/stat : 8679 (java) S 8658 8657 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10325862 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=8671/tid=8680] ppid=8658 vsize=893480 CPUtime=0 /proc/8671/task/8680/stat : 8680 (java) S 8658 8657 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10325862 914923520 62656 1283457024 134512640 134550932 4290327648 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.26 Current children cumulated vsize (KiB) 898616 Child status: 0 Real time (s): 5.83624 CPU time (s): 5.28833 CPU user time (s): 5.00431 CPU system time (s): 0.284017 CPU usage (%): 90.6119 Max. virtual memory (cumulated for all children) (KiB): 901156 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.00431 system time used= 0.284017 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 73461 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= 601 involuntary context switches= 598 runsolver used 0 second user time and 0.008 second system time The end