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/rand376.cudf.s-e.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/incremental/s-e/rand376.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/rand376.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.41 1.29 1.11 4/35 12954 /proc/meminfo: memFree=333544/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2564 CPUtime=0 /proc/12953/stat : 12953 (p2cudf) R 12952 12952 4778 34817 4778 4202496 345 0 0 0 0 0 0 0 25 0 1 0 10590619 2625536 271 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/12953/statm: 641 271 231 194 0 28 0 [pid=12954] ppid=12953 vsize=2564 CPUtime=0 /proc/12954/stat : 12954 (p2cudf) R 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 10590619 2625536 40 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/12954/statm: 641 40 0 194 0 28 0 [startup+0.14966 s] /proc/loadavg: 1.41 1.29 1.11 4/35 12954 /proc/meminfo: memFree=333544/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.209674 s] /proc/loadavg: 1.41 1.29 1.11 4/35 12954 /proc/meminfo: memFree=333544/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.309688 s] /proc/loadavg: 1.41 1.29 1.11 4/35 12954 /proc/meminfo: memFree=333544/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+0.709771 s] /proc/loadavg: 1.41 1.29 1.11 4/35 12954 /proc/meminfo: memFree=333544/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5136 [startup+1.50993 s] /proc/loadavg: 1.62 1.33 1.13 4/45 12975 /proc/meminfo: memFree=285068/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=887976 CPUtime=1.47 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 21541 0 1 0 141 6 0 0 25 0 10 0 10590623 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221994 18867 1719 10 0 219085 0 [pid=12966/tid=12967] ppid=12953 vsize=887976 CPUtime=0.8 /proc/12966/task/12967/stat : 12967 (java) R 12953 12952 4778 34817 4778 4202560 10221 0 1 0 76 4 0 0 25 0 10 0 10590623 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4095566830 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=887976 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 16 0 10 0 10590625 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=887976 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 10 0 10590625 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=887976 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10590625 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=887976 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10590628 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=887976 CPUtime=0.28 /proc/12966/task/12972/stat : 12972 (java) R 12953 12952 4778 34817 4778 4202560 2038 0 0 0 27 1 0 0 25 0 10 0 10590628 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=887976 CPUtime=0.38 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 8202 0 0 0 38 0 0 0 25 0 10 0 10590628 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=887976 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10590628 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=887976 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10590628 909287424 18867 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 893112 [startup+3.11025 s] /proc/loadavg: 1.62 1.33 1.13 3/45 12975 /proc/meminfo: memFree=188720/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=885824 CPUtime=3.08 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 49669 0 1 0 292 16 0 0 25 0 10 0 10590623 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221456 46167 1744 10 0 218547 0 [pid=12966/tid=12967] ppid=12953 vsize=885824 CPUtime=1.45 /proc/12966/task/12967/stat : 12967 (java) R 12953 12952 4778 34817 4778 4202560 37311 0 1 0 132 13 0 0 25 0 10 0 10590623 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 15 0 10 0 10590625 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 10 0 10590625 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10590625 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10590628 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=885824 CPUtime=0.59 /proc/12966/task/12972/stat : 12972 (java) R 12953 12952 4778 34817 4778 4202560 2070 0 0 0 58 1 0 0 21 0 10 0 10590628 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4150441836 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=885824 CPUtime=1.01 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 9208 0 0 0 101 0 0 0 17 0 10 0 10590628 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10590628 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10590628 907083776 46167 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.1 Current children cumulated vsize (KiB) 890960 Solver just ended. Dumping a history of the last processes samples [startup+3.21027 s] /proc/loadavg: 1.62 1.33 1.13 3/45 12975 /proc/meminfo: memFree=188720/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=885824 CPUtime=3.18 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 50190 0 1 0 302 16 0 0 25 0 10 0 10590623 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221456 46688 1744 10 0 218547 0 [pid=12966/tid=12967] ppid=12953 vsize=885824 CPUtime=1.47 /proc/12966/task/12967/stat : 12967 (java) R 12953 12952 4778 34817 4778 4202560 37824 0 1 0 134 13 0 0 25 0 10 0 10590623 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 15 0 10 0 10590625 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 10 0 10590625 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10590625 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10590628 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=885824 CPUtime=0.67 /proc/12966/task/12972/stat : 12972 (java) R 12953 12952 4778 34817 4778 4202560 2078 0 0 0 66 1 0 0 21 0 10 0 10590628 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4151954246 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=885824 CPUtime=1.01 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 9208 0 0 0 101 0 0 0 17 0 10 0 10590628 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 10590628 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=885824 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 10590628 907083776 46688 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.2 Current children cumulated vsize (KiB) 890960 [startup+4.01043 s] /proc/loadavg: 1.62 1.33 1.13 3/45 12975 /proc/meminfo: memFree=145320/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=886148 CPUtime=3.98 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 59732 0 1 0 378 20 0 0 25 0 11 0 10590623 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221537 56230 1767 10 0 218628 0 [pid=12966/tid=12967] ppid=12953 vsize=886148 CPUtime=1.9 /proc/12966/task/12967/stat : 12967 (java) R 12953 12952 4778 34817 4778 4202560 47294 0 1 0 173 17 0 0 25 0 11 0 10590623 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4095568066 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=886148 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 16 0 11 0 10590625 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=886148 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 11 0 10590625 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=886148 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10590625 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=886148 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10590628 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=886148 CPUtime=1.04 /proc/12966/task/12972/stat : 12972 (java) S 12953 12952 4778 34817 4778 4202560 2146 0 0 0 103 1 0 0 18 0 11 0 10590628 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=886148 CPUtime=1.01 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 9208 0 0 0 101 0 0 0 17 0 11 0 10590628 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=886148 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10590628 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=886148 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10590628 907415552 56230 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4 Current children cumulated vsize (KiB) 891284 [startup+4.4039 s] /proc/loadavg: 1.62 1.33 1.13 1/46 12977 /proc/meminfo: memFree=111336/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=886472 CPUtime=4.27 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 60598 0 1 0 407 20 0 0 25 0 11 0 10590623 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221618 57096 1770 10 0 218709 0 [pid=12966/tid=12967] ppid=12953 vsize=886472 CPUtime=2.02 /proc/12966/task/12967/stat : 12967 (java) S 12953 12952 4778 34817 4778 4202560 48137 0 1 0 185 17 0 0 25 0 11 0 10590623 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 16 0 11 0 10590625 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 11 0 10590625 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10590625 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=886472 CPUtime=1.21 /proc/12966/task/12972/stat : 12972 (java) S 12953 12952 4778 34817 4778 4202560 2164 0 0 0 120 1 0 0 16 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=886472 CPUtime=1.01 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 9208 0 0 0 101 0 0 0 17 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12977] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12977/stat : 12977 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10591051 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.29 Current children cumulated vsize (KiB) 891608 [startup+4.80791 s] /proc/loadavg: 1.62 1.33 1.13 1/46 12977 /proc/meminfo: memFree=111336/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=886472 CPUtime=4.27 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 60598 0 1 0 407 20 0 0 25 0 11 0 10590623 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221618 57096 1770 10 0 218709 0 [pid=12966/tid=12967] ppid=12953 vsize=886472 CPUtime=2.02 /proc/12966/task/12967/stat : 12967 (java) S 12953 12952 4778 34817 4778 4202560 48137 0 1 0 185 17 0 0 25 0 11 0 10590623 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 16 0 11 0 10590625 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 11 0 10590625 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10590625 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=886472 CPUtime=1.21 /proc/12966/task/12972/stat : 12972 (java) S 12953 12952 4778 34817 4778 4202560 2164 0 0 0 120 1 0 0 16 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=886472 CPUtime=1.01 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 9208 0 0 0 101 0 0 0 17 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10590628 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12977] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12977/stat : 12977 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10591051 907747328 57096 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.29 Current children cumulated vsize (KiB) 891608 [startup+4.90992 s] /proc/loadavg: 1.62 1.33 1.13 1/46 12977 /proc/meminfo: memFree=111336/1048576 swapFree=0/0 [pid=12952] ppid=12951 vsize=2568 CPUtime=0 /proc/12952/stat : 12952 (p2cudf-paranoid) S 12951 12952 4778 34817 4778 4202496 373 0 0 0 0 0 0 0 24 0 1 0 10590619 2629632 270 1283457024 134512640 135304128 4289416400 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12952/statm: 642 270 230 194 0 29 0 [pid=12953] ppid=12952 vsize=2568 CPUtime=0.02 /proc/12953/stat : 12953 (p2cudf) S 12952 12952 4778 34817 4778 4202496 389 3019 0 1 0 0 2 0 25 0 1 0 10590619 2629632 280 1283457024 134512640 135304128 4287265504 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/12953/statm: 642 280 239 194 0 29 0 [pid=12966] ppid=12953 vsize=886472 CPUtime=4.35 /proc/12966/stat : 12966 (java) S 12953 12952 4778 34817 4778 4202496 60742 0 1 0 413 22 0 0 25 0 11 0 10590623 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/12966/statm: 221618 57240 1771 10 0 218709 0 [pid=12966/tid=12967] ppid=12953 vsize=886472 CPUtime=2.02 /proc/12966/task/12967/stat : 12967 (java) S 12953 12952 4778 34817 4778 4202560 48137 0 1 0 185 17 0 0 25 0 11 0 10590623 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12968] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12968/stat : 12968 (java) S 12953 12952 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 16 0 11 0 10590625 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12969] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12969/stat : 12969 (java) S 12953 12952 4778 34817 4778 4202560 9 0 0 0 0 0 0 0 25 0 11 0 10590625 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12970] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12970/stat : 12970 (java) S 12953 12952 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 10590625 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12971] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12971/stat : 12971 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10590628 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12972] ppid=12953 vsize=886472 CPUtime=1.24 /proc/12966/task/12972/stat : 12972 (java) S 12953 12952 4778 34817 4778 4202560 2168 0 0 0 123 1 0 0 15 0 11 0 10590628 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12973] ppid=12953 vsize=886472 CPUtime=1.01 /proc/12966/task/12973/stat : 12973 (java) S 12953 12952 4778 34817 4778 4202560 9208 0 0 0 101 0 0 0 17 0 11 0 10590628 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12974] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12974/stat : 12974 (java) S 12953 12952 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 10590628 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12975] ppid=12953 vsize=886472 CPUtime=0 /proc/12966/task/12975/stat : 12975 (java) S 12953 12952 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 10590628 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=12966/tid=12977] ppid=12953 vsize=886472 CPUtime=0.05 /proc/12966/task/12977/stat : 12977 (java) R 12953 12952 4778 34817 4778 4202560 144 0 0 0 3 2 0 0 21 0 11 0 10591051 907747328 57240 1283457024 134512640 134550932 4292865744 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.37 Current children cumulated vsize (KiB) 891608 Child status: 0 Real time (s): 4.9754 CPU time (s): 4.40027 CPU user time (s): 4.15226 CPU system time (s): 0.248015 CPU usage (%): 88.4407 Max. virtual memory (cumulated for all children) (KiB): 893112 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.15226 system time used= 0.248015 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 64561 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= 626 involuntary context switches= 616 runsolver used 0 second user time and 0.004 second system time The end