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/9bb87ab4-4c36-11df-bc1e-00163e7a6f5e.cudf.debian.log.runsolver ./p2cudf-paranoid-1.12 /home/misc2010/data/misc4/debian//9bb87ab4-4c36-11df-bc1e-00163e7a6f5e.cudf /home/misc2010/tmp/201103031013/p2cudf-paranoid-1.12/9bb87ab4-4c36-11df-bc1e-00163e7a6f5e.cudf.debian.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.07 1.04 5/37 24123 /proc/meminfo: memFree=644664/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2572 CPUtime=0 /proc/24122/stat : 24122 (p2cudf) R 24121 24121 29493 34817 29493 4202496 345 0 0 0 0 0 0 0 25 0 1 0 75528192 2633728 271 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/24122/statm: 643 271 231 194 0 30 0 [pid=24123] ppid=24122 vsize=2572 CPUtime=0 /proc/24123/stat : 24123 (p2cudf) R 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 1 0 75528192 2633728 40 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/24123/statm: 643 40 0 194 0 30 0 [startup+0.189204 s] /proc/loadavg: 1.07 1.07 1.04 5/37 24123 /proc/meminfo: memFree=644664/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+0.209199 s] /proc/loadavg: 1.07 1.07 1.04 5/37 24123 /proc/meminfo: memFree=644664/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+0.309213 s] /proc/loadavg: 1.07 1.07 1.04 5/37 24123 /proc/meminfo: memFree=644664/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+0.709289 s] /proc/loadavg: 1.07 1.07 1.04 5/37 24123 /proc/meminfo: memFree=644664/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+1.50943 s] /proc/loadavg: 1.23 1.10 1.05 4/47 24145 /proc/meminfo: memFree=595840/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=885676 CPUtime=1.46 /proc/24135/stat : 24135 (java) S 24122 24121 29493 34817 29493 4202496 19872 0 1 0 134 12 0 0 25 0 10 0 75528196 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24135/statm: 221419 16477 1727 10 0 218510 0 [pid=24135/tid=24136] ppid=24122 vsize=885676 CPUtime=0.79 /proc/24135/task/24136/stat : 24136 (java) R 24122 24121 29493 34817 29493 4202560 8245 0 1 0 73 6 0 0 25 0 10 0 75528197 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4095464716 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24135/tid=24137] ppid=24122 vsize=885676 CPUtime=0 /proc/24135/task/24137/stat : 24137 (java) S 24122 24121 29493 34817 29493 4202560 7 0 0 0 0 0 0 0 16 0 10 0 75528198 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24138] ppid=24122 vsize=885676 CPUtime=0 /proc/24135/task/24138/stat : 24138 (java) S 24122 24121 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 25 0 10 0 75528199 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24139] ppid=24122 vsize=885676 CPUtime=0 /proc/24135/task/24139/stat : 24139 (java) S 24122 24121 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 10 0 75528199 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24140] ppid=24122 vsize=885676 CPUtime=0 /proc/24135/task/24140/stat : 24140 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75528201 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24141] ppid=24122 vsize=885676 CPUtime=0.43 /proc/24135/task/24141/stat : 24141 (java) R 24122 24121 29493 34817 29493 4202560 8473 0 0 0 38 5 0 0 25 0 10 0 75528201 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24135/tid=24142] ppid=24122 vsize=885676 CPUtime=0.23 /proc/24135/task/24142/stat : 24142 (java) R 24122 24121 29493 34817 29493 4202560 2074 0 0 0 22 1 0 0 18 0 10 0 75528201 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4152845902 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24135/tid=24143] ppid=24122 vsize=885676 CPUtime=0 /proc/24135/task/24143/stat : 24143 (java) S 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75528201 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24144] ppid=24122 vsize=885676 CPUtime=0 /proc/24135/task/24144/stat : 24144 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75528201 906932224 16477 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 890824 [startup+3.10979 s] /proc/loadavg: 1.23 1.10 1.05 2/47 24145 /proc/meminfo: memFree=502716/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=882140 CPUtime=3.07 /proc/24135/stat : 24135 (java) S 24122 24121 29493 34817 29493 4202496 74834 0 1 0 275 32 0 0 25 0 10 0 75528196 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24135/statm: 220535 70709 1739 10 0 217626 0 [pid=24135/tid=24136] ppid=24122 vsize=882140 CPUtime=1.86 /proc/24135/task/24136/stat : 24136 (java) R 24122 24121 29493 34817 29493 4202560 50040 0 1 0 166 20 0 0 25 0 10 0 75528197 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24135/tid=24137] ppid=24122 vsize=882140 CPUtime=0.27 /proc/24135/task/24137/stat : 24137 (java) S 24122 24121 29493 34817 29493 4202560 12836 0 0 0 23 4 0 0 16 0 10 0 75528198 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24138] ppid=24122 vsize=882140 CPUtime=0 /proc/24135/task/24138/stat : 24138 (java) S 24122 24121 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 10 0 75528199 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24139] ppid=24122 vsize=882140 CPUtime=0 /proc/24135/task/24139/stat : 24139 (java) S 24122 24121 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 10 0 75528199 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24140] ppid=24122 vsize=882140 CPUtime=0 /proc/24135/task/24140/stat : 24140 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 10 0 75528201 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24141] ppid=24122 vsize=882140 CPUtime=0.51 /proc/24135/task/24141/stat : 24141 (java) S 24122 24121 29493 34817 29493 4202560 8537 0 0 0 46 5 0 0 16 0 10 0 75528201 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24142] ppid=24122 vsize=882140 CPUtime=0.4 /proc/24135/task/24142/stat : 24142 (java) S 24122 24121 29493 34817 29493 4202560 2346 0 0 0 39 1 0 0 16 0 10 0 75528201 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24143] ppid=24122 vsize=882140 CPUtime=0 /proc/24135/task/24143/stat : 24143 (java) S 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 10 0 75528201 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24144] ppid=24122 vsize=882140 CPUtime=0 /proc/24135/task/24144/stat : 24144 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 10 0 75528201 903311360 70709 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 887288 [startup+6.31054 s] /proc/loadavg: 1.29 1.11 1.06 2/47 24145 /proc/meminfo: memFree=314112/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=882624 CPUtime=6.27 /proc/24135/stat : 24135 (java) S 24122 24121 29493 34817 29493 4202496 90007 0 1 0 589 38 0 0 25 0 11 0 75528196 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24135/statm: 220656 84267 1774 10 0 217747 0 [pid=24135/tid=24136] ppid=24122 vsize=882624 CPUtime=3.18 /proc/24135/task/24136/stat : 24136 (java) R 24122 24121 29493 34817 29493 4202560 50259 0 1 0 296 22 0 0 25 0 11 0 75528197 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4096481410 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24135/tid=24137] ppid=24122 vsize=882624 CPUtime=0.57 /proc/24135/task/24137/stat : 24137 (java) S 24122 24121 29493 34817 29493 4202560 25891 0 0 0 49 8 0 0 16 0 11 0 75528198 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24138] ppid=24122 vsize=882624 CPUtime=0 /proc/24135/task/24138/stat : 24138 (java) S 24122 24121 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75528199 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24139] ppid=24122 vsize=882624 CPUtime=0 /proc/24135/task/24139/stat : 24139 (java) S 24122 24121 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75528199 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24140] ppid=24122 vsize=882624 CPUtime=0 /proc/24135/task/24140/stat : 24140 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75528201 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24141] ppid=24122 vsize=882624 CPUtime=2.09 /proc/24135/task/24141/stat : 24141 (java) S 24122 24121 29493 34817 29493 4202560 10432 0 0 0 203 6 0 0 16 0 11 0 75528201 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24142] ppid=24122 vsize=882624 CPUtime=0.4 /proc/24135/task/24142/stat : 24142 (java) S 24122 24121 29493 34817 29493 4202560 2346 0 0 0 39 1 0 0 16 0 11 0 75528201 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24143] ppid=24122 vsize=882624 CPUtime=0 /proc/24135/task/24143/stat : 24143 (java) S 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 75528201 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24144] ppid=24122 vsize=882624 CPUtime=0 /proc/24135/task/24144/stat : 24144 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75528201 903806976 84267 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.29 Current children cumulated vsize (KiB) 887772 Solver just ended. Dumping a history of the last processes samples [startup+7.11085 s] /proc/loadavg: 1.29 1.11 1.06 2/48 24146 /proc/meminfo: memFree=313608/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=880888 CPUtime=7.07 /proc/24135/stat : 24135 (java) S 24122 24121 29493 34817 29493 4202496 90031 0 1 0 669 38 0 0 25 0 11 0 75528196 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24135/statm: 220222 83882 1779 10 0 217313 0 [pid=24135/tid=24136] ppid=24122 vsize=880888 CPUtime=3.85 /proc/24135/task/24136/stat : 24136 (java) R 24122 24121 29493 34817 29493 4202560 50260 0 1 0 363 22 0 0 25 0 11 0 75528197 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4096498501 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=24135/tid=24137] ppid=24122 vsize=880888 CPUtime=0.57 /proc/24135/task/24137/stat : 24137 (java) S 24122 24121 29493 34817 29493 4202560 25891 0 0 0 49 8 0 0 16 0 11 0 75528198 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24138] ppid=24122 vsize=880888 CPUtime=0 /proc/24135/task/24138/stat : 24138 (java) S 24122 24121 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 11 0 75528199 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24139] ppid=24122 vsize=880888 CPUtime=0 /proc/24135/task/24139/stat : 24139 (java) S 24122 24121 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 11 0 75528199 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24140] ppid=24122 vsize=880888 CPUtime=0 /proc/24135/task/24140/stat : 24140 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 11 0 75528201 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24141] ppid=24122 vsize=880888 CPUtime=2.22 /proc/24135/task/24141/stat : 24141 (java) S 24122 24121 29493 34817 29493 4202560 10455 0 0 0 216 6 0 0 15 0 11 0 75528201 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24142] ppid=24122 vsize=880888 CPUtime=0.4 /proc/24135/task/24142/stat : 24142 (java) S 24122 24121 29493 34817 29493 4202560 2346 0 0 0 39 1 0 0 16 0 11 0 75528201 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24143] ppid=24122 vsize=880888 CPUtime=0 /proc/24135/task/24143/stat : 24143 (java) S 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 11 0 75528201 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24144] ppid=24122 vsize=880888 CPUtime=0 /proc/24135/task/24144/stat : 24144 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 11 0 75528201 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24146] ppid=24122 vsize=880888 CPUtime=0 /proc/24135/task/24146/stat : 24146 (java) S 24122 24121 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 11 0 75528774 902029312 83882 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.09 Current children cumulated vsize (KiB) 886036 [startup+7.50601 s] /proc/loadavg: 1.29 1.11 1.06 2/48 24146 /proc/meminfo: memFree=313608/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=881212 CPUtime=7.24 /proc/24135/stat : 24135 (java) S 24122 24121 29493 34817 29493 4202496 90050 0 1 0 686 38 0 0 25 0 12 0 75528196 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24135/statm: 220303 83901 1779 10 0 217394 0 [pid=24135/tid=24136] ppid=24122 vsize=881212 CPUtime=3.95 /proc/24135/task/24136/stat : 24136 (java) S 24122 24121 29493 34817 29493 4202560 50268 0 1 0 373 22 0 0 25 0 12 0 75528197 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24137] ppid=24122 vsize=881212 CPUtime=0.57 /proc/24135/task/24137/stat : 24137 (java) S 24122 24121 29493 34817 29493 4202560 25891 0 0 0 49 8 0 0 16 0 12 0 75528198 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24138] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24138/stat : 24138 (java) S 24122 24121 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 75528199 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24139] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24139/stat : 24139 (java) S 24122 24121 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 75528199 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24140] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24140/stat : 24140 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75528201 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24141] ppid=24122 vsize=881212 CPUtime=2.29 /proc/24135/task/24141/stat : 24141 (java) S 24122 24121 29493 34817 29493 4202560 10462 0 0 0 223 6 0 0 15 0 12 0 75528201 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24142] ppid=24122 vsize=881212 CPUtime=0.4 /proc/24135/task/24142/stat : 24142 (java) S 24122 24121 29493 34817 29493 4202560 2346 0 0 0 39 1 0 0 16 0 12 0 75528201 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24143] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24143/stat : 24143 (java) S 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 12 0 75528201 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24144] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24144/stat : 24144 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 75528201 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24146] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24146/stat : 24146 (java) S 24122 24121 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 75528774 902361088 83901 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.26 Current children cumulated vsize (KiB) 886360 [startup+7.90405 s] /proc/loadavg: 1.29 1.11 1.06 1/49 24147 /proc/meminfo: memFree=314592/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=881212 CPUtime=7.34 /proc/24135/stat : 24135 (java) S 24122 24121 29493 34817 29493 4202496 90054 0 1 0 694 40 0 0 25 0 12 0 75528196 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/24135/statm: 220303 83905 1779 10 0 217394 0 [pid=24135/tid=24136] ppid=24122 vsize=881212 CPUtime=3.95 /proc/24135/task/24136/stat : 24136 (java) S 24122 24121 29493 34817 29493 4202560 50268 0 1 0 373 22 0 0 25 0 12 0 75528197 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24137] ppid=24122 vsize=881212 CPUtime=0.57 /proc/24135/task/24137/stat : 24137 (java) S 24122 24121 29493 34817 29493 4202560 25891 0 0 0 49 8 0 0 16 0 12 0 75528198 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 0 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24138] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24138/stat : 24138 (java) S 24122 24121 29493 34817 29493 4202560 8 0 0 0 0 0 0 0 18 0 12 0 75528199 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24139] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24139/stat : 24139 (java) S 24122 24121 29493 34817 29493 4202560 6 0 0 0 0 0 0 0 18 0 12 0 75528199 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24140] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24140/stat : 24140 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 25 0 12 0 75528201 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24141] ppid=24122 vsize=881212 CPUtime=2.32 /proc/24135/task/24141/stat : 24141 (java) S 24122 24121 29493 34817 29493 4202560 10465 0 0 0 226 6 0 0 15 0 12 0 75528201 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24142] ppid=24122 vsize=881212 CPUtime=0.4 /proc/24135/task/24142/stat : 24142 (java) S 24122 24121 29493 34817 29493 4202560 2346 0 0 0 39 1 0 0 16 0 12 0 75528201 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24143] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24143/stat : 24143 (java) S 24122 24121 29493 34817 29493 4202560 0 0 0 0 0 0 0 0 25 0 12 0 75528201 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24144] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24144/stat : 24144 (java) S 24122 24121 29493 34817 29493 4202560 1 0 0 0 0 0 0 0 15 0 12 0 75528201 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24146] ppid=24122 vsize=881212 CPUtime=0 /proc/24135/task/24146/stat : 24146 (java) S 24122 24121 29493 34817 29493 4202560 4 0 0 0 0 0 0 0 25 0 12 0 75528774 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 18446612132467745728 0 0 -1 0 0 0 0 [pid=24135/tid=24147] ppid=24122 vsize=881212 CPUtime=0.08 /proc/24135/task/24147/stat : 24147 (java) R 24122 24121 29493 34817 29493 4202560 5 0 0 0 6 2 0 0 20 0 12 0 75528921 902361088 83905 1283457024 134512640 134550932 4289524576 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 7.36 Current children cumulated vsize (KiB) 886360 [startup+8.01283 s] /proc/loadavg: 1.29 1.11 1.06 1/49 24147 /proc/meminfo: memFree=314592/1048576 swapFree=0/0 [pid=24121] ppid=24120 vsize=2568 CPUtime=0 /proc/24121/stat : 24121 (p2cudf-paranoid) S 24120 24121 29493 34817 29493 4202496 374 0 0 0 0 0 0 0 18 0 1 0 75528192 2629632 270 1283457024 134512640 135304128 4292505488 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24121/statm: 642 270 230 194 0 29 0 [pid=24122] ppid=24121 vsize=2580 CPUtime=0.02 /proc/24122/stat : 24122 (p2cudf) S 24121 24121 29493 34817 29493 4202496 394 3010 0 1 0 0 0 2 25 0 1 0 75528192 2641920 281 1283457024 134512640 135304128 4286861888 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/24122/statm: 645 281 239 194 0 32 0 [pid=24135] ppid=24122 vsize=0 CPUtime=7.44 /proc/24135/stat : 24135 (java) R 24122 24121 29493 34817 29493 4203524 90064 0 1 0 699 45 0 0 18 0 1 0 75528196 0 0 1283457024 0 0 0 0 0 0 0 0 16800975 0 0 0 17 0 0 0 0 /proc/24135/statm: 0 0 0 0 0 0 0 Current children cumulated CPU time (s) 7.46 Current children cumulated vsize (KiB) 5148 Child status: 0 Real time (s): 8.03708 CPU time (s): 7.50047 CPU user time (s): 6.99644 CPU system time (s): 0.504031 CPU usage (%): 93.3233 Max. virtual memory (cumulated for all children) (KiB): 891460 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.99644 system time used= 0.504031 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 93861 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= 942 involuntary context switches= 941 runsolver used 0 second user time and 0 second system time The end