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/rand408.cudf.user-upgrades.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/user-upgrades/rand408.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/rand408.cudf.user-upgrades.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.16 1.03 1.01 5/37 16239 /proc/meminfo: memFree=331380/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2568 CPUtime=0 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 353 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/16237/statm: 642 271 231 194 0 29 0 [pid=16238] ppid=16237 vsize=2568 CPUtime=0 /proc/16238/stat : 16238 (p2cudf) R 16237 16236 4778 34817 4778 4202560 94 0 0 0 0 0 0 0 25 0 1 0 10969682 2629632 118 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/16238/statm: 642 118 77 194 0 29 0 [pid=16239] ppid=16238 vsize=2568 CPUtime=0 /proc/16239/stat : 16239 (p2cudf) R 16238 16236 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 10969682 2629632 41 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/16239/statm: 642 41 0 194 0 29 0 [startup+0.155763 s] /proc/loadavg: 1.16 1.03 1.01 5/37 16239 /proc/meminfo: memFree=331380/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.20576 s] /proc/loadavg: 1.16 1.03 1.01 5/37 16239 /proc/meminfo: memFree=331380/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.305775 s] /proc/loadavg: 1.16 1.03 1.01 5/37 16239 /proc/meminfo: memFree=331380/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.705829 s] /proc/loadavg: 1.16 1.03 1.01 5/37 16239 /proc/meminfo: memFree=331380/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+1.50594 s] /proc/loadavg: 1.15 1.03 1.01 5/46 16259 /proc/meminfo: memFree=282264/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 [pid=16250] ppid=16237 vsize=893720 CPUtime=1.47 /proc/16250/stat : 16250 (java) S 16237 16236 4778 34817 4778 4202496 21793 0 1 0 138 9 0 0 25 0 10 0 10969685 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/16250/statm: 223430 18680 1724 10 0 220521 0 [pid=16250/tid=16251] ppid=16237 vsize=893720 CPUtime=0.79 /proc/16250/task/16251/stat : 16251 (java) R 16237 16236 4778 34817 4778 4202560 8450 0 1 0 75 4 0 0 25 0 10 0 10969685 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4094782216 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16252] ppid=16237 vsize=893720 CPUtime=0 /proc/16250/task/16252/stat : 16252 (java) S 16237 16236 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 16 0 10 0 10969687 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16253] ppid=16237 vsize=893720 CPUtime=0 /proc/16250/task/16253/stat : 16253 (java) S 16237 16236 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 10969687 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16254] ppid=16237 vsize=893720 CPUtime=0 /proc/16250/task/16254/stat : 16254 (java) S 16237 16236 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 10969687 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16255] ppid=16237 vsize=893720 CPUtime=0 /proc/16250/task/16255/stat : 16255 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16256] ppid=16237 vsize=893720 CPUtime=0.38 /proc/16250/task/16256/stat : 16256 (java) R 16237 16236 4778 34817 4778 4202560 5848 0 0 0 37 1 0 0 21 0 10 0 10969690 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4149683085 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16257] ppid=16237 vsize=893720 CPUtime=0.26 /proc/16250/task/16257/stat : 16257 (java) R 16237 16236 4778 34817 4778 4202560 6416 0 0 0 24 2 0 0 25 0 10 0 10969690 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4152174770 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16258] ppid=16237 vsize=893720 CPUtime=0 /proc/16250/task/16258/stat : 16258 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16259] ppid=16237 vsize=893720 CPUtime=0 /proc/16250/task/16259/stat : 16259 (java) S 16237 16236 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10969690 915169280 18680 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 898860 [startup+3.10615 s] /proc/loadavg: 1.15 1.03 1.01 2/46 16259 /proc/meminfo: memFree=182816/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 [pid=16250] ppid=16237 vsize=891620 CPUtime=3.07 /proc/16250/stat : 16250 (java) S 16237 16236 4778 34817 4778 4202496 74507 0 1 0 278 29 0 0 25 0 10 0 10969685 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/16250/statm: 222905 70999 1732 10 0 219996 0 [pid=16250/tid=16251] ppid=16237 vsize=891620 CPUtime=1.83 /proc/16250/task/16251/stat : 16251 (java) R 16237 16236 4778 34817 4778 4202560 50039 0 1 0 163 20 0 0 25 0 10 0 10969685 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4095077605 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16252] ppid=16237 vsize=891620 CPUtime=0.23 /proc/16250/task/16252/stat : 16252 (java) S 16237 16236 4778 34817 4778 4202560 10771 0 0 0 19 4 0 0 16 0 10 0 10969687 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16253] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16253/stat : 16253 (java) S 16237 16236 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10969687 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16254] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16254/stat : 16254 (java) S 16237 16236 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10969687 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16255] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16255/stat : 16255 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16256] ppid=16237 vsize=891620 CPUtime=0.63 /proc/16250/task/16256/stat : 16256 (java) S 16237 16236 4778 34817 4778 4202560 6018 0 0 0 62 1 0 0 15 0 10 0 10969690 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16257] ppid=16237 vsize=891620 CPUtime=0.34 /proc/16250/task/16257/stat : 16257 (java) S 16237 16236 4778 34817 4778 4202560 6604 0 0 0 32 2 0 0 25 0 10 0 10969690 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16258] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16258/stat : 16258 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 913018880 70999 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16259] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16259/stat : 16259 (java) S 16237 16236 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10969690 913018880 70999 1283457024 134512640 134550932 4286777600 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) 896760 Solver just ended. Dumping a history of the last processes samples [startup+3.21619 s] /proc/loadavg: 1.15 1.03 1.01 2/46 16259 /proc/meminfo: memFree=182816/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 [pid=16250] ppid=16237 vsize=891620 CPUtime=3.17 /proc/16250/stat : 16250 (java) S 16237 16236 4778 34817 4778 4202496 74524 0 1 0 288 29 0 0 25 0 10 0 10969685 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/16250/statm: 222905 71016 1740 10 0 219996 0 [pid=16250/tid=16251] ppid=16237 vsize=891620 CPUtime=1.88 /proc/16250/task/16251/stat : 16251 (java) R 16237 16236 4778 34817 4778 4202560 50054 0 1 0 168 20 0 0 25 0 10 0 10969685 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16252] ppid=16237 vsize=891620 CPUtime=0.23 /proc/16250/task/16252/stat : 16252 (java) S 16237 16236 4778 34817 4778 4202560 10771 0 0 0 19 4 0 0 16 0 10 0 10969687 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16253] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16253/stat : 16253 (java) S 16237 16236 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10969687 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16254] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16254/stat : 16254 (java) S 16237 16236 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10969687 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16255] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16255/stat : 16255 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16256] ppid=16237 vsize=891620 CPUtime=0.69 /proc/16250/task/16256/stat : 16256 (java) R 16237 16236 4778 34817 4778 4202560 6020 0 0 0 68 1 0 0 15 0 10 0 10969690 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4149683850 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16257] ppid=16237 vsize=891620 CPUtime=0.34 /proc/16250/task/16257/stat : 16257 (java) S 16237 16236 4778 34817 4778 4202560 6604 0 0 0 32 2 0 0 25 0 10 0 10969690 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16258] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16258/stat : 16258 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 913018880 71016 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16259] ppid=16237 vsize=891620 CPUtime=0 /proc/16250/task/16259/stat : 16259 (java) S 16237 16236 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10969690 913018880 71016 1283457024 134512640 134550932 4286777600 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) 896760 [startup+4.00629 s] /proc/loadavg: 1.15 1.03 1.01 3/46 16259 /proc/meminfo: memFree=53236/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 [pid=16250] ppid=16237 vsize=893560 CPUtime=3.97 /proc/16250/stat : 16250 (java) S 16237 16236 4778 34817 4778 4202496 76276 0 1 0 366 31 0 0 25 0 10 0 10969685 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/16250/statm: 223390 71717 1746 10 0 220481 0 [pid=16250/tid=16251] ppid=16237 vsize=893560 CPUtime=1.98 /proc/16250/task/16251/stat : 16251 (java) R 16237 16236 4778 34817 4778 4202560 50214 0 1 0 178 20 0 0 25 0 10 0 10969685 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16252] ppid=16237 vsize=893560 CPUtime=0.23 /proc/16250/task/16252/stat : 16252 (java) S 16237 16236 4778 34817 4778 4202560 10771 0 0 0 19 4 0 0 15 0 10 0 10969687 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16253] ppid=16237 vsize=893560 CPUtime=0 /proc/16250/task/16253/stat : 16253 (java) S 16237 16236 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 10 0 10969687 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16254] ppid=16237 vsize=893560 CPUtime=0 /proc/16250/task/16254/stat : 16254 (java) S 16237 16236 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 10 0 10969687 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16255] ppid=16237 vsize=893560 CPUtime=0 /proc/16250/task/16255/stat : 16255 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16256] ppid=16237 vsize=893560 CPUtime=1.39 /proc/16250/task/16256/stat : 16256 (java) R 16237 16236 4778 34817 4778 4202560 7612 0 0 0 136 3 0 0 16 0 10 0 10969690 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4148303104 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=16250/tid=16257] ppid=16237 vsize=893560 CPUtime=0.34 /proc/16250/task/16257/stat : 16257 (java) S 16237 16236 4778 34817 4778 4202560 6604 0 0 0 32 2 0 0 25 0 10 0 10969690 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16258] ppid=16237 vsize=893560 CPUtime=0 /proc/16250/task/16258/stat : 16258 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 10969690 915005440 71717 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16259] ppid=16237 vsize=893560 CPUtime=0 /proc/16250/task/16259/stat : 16259 (java) S 16237 16236 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 10 0 10969690 915005440 71717 1283457024 134512640 134550932 4286777600 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) 898700 [startup+4.80603 s] /proc/loadavg: 1.15 1.03 1.01 2/46 16259 /proc/meminfo: memFree=51500/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 [pid=16250] ppid=16237 vsize=892668 CPUtime=4.64 /proc/16250/stat : 16250 (java) S 16237 16236 4778 34817 4778 4202496 76420 0 1 0 432 32 0 0 25 0 11 0 10969685 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/16250/statm: 223167 71476 1761 10 0 220258 0 [pid=16250/tid=16251] ppid=16237 vsize=892668 CPUtime=2.3 /proc/16250/task/16251/stat : 16251 (java) S 16237 16236 4778 34817 4778 4202560 50284 0 1 0 209 21 0 0 25 0 11 0 10969685 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16252] ppid=16237 vsize=892668 CPUtime=0.23 /proc/16250/task/16252/stat : 16252 (java) S 16237 16236 4778 34817 4778 4202560 10771 0 0 0 19 4 0 0 16 0 11 0 10969687 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16253] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16253/stat : 16253 (java) S 16237 16236 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 10969687 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16254] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16254/stat : 16254 (java) S 16237 16236 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 10969687 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16255] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16255/stat : 16255 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10969690 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16256] ppid=16237 vsize=892668 CPUtime=1.73 /proc/16250/task/16256/stat : 16256 (java) S 16237 16236 4778 34817 4778 4202560 7676 0 0 0 170 3 0 0 15 0 11 0 10969690 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16257] ppid=16237 vsize=892668 CPUtime=0.34 /proc/16250/task/16257/stat : 16257 (java) S 16237 16236 4778 34817 4778 4202560 6604 0 0 0 32 2 0 0 25 0 11 0 10969690 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16258] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16258/stat : 16258 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10969690 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16259] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16259/stat : 16259 (java) S 16237 16236 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 10969690 914092032 71476 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.67 Current children cumulated vsize (KiB) 897808 [startup+5.21057 s] /proc/loadavg: 1.15 1.03 1.01 2/46 16259 /proc/meminfo: memFree=51500/1048576 swapFree=0/0 [pid=16236] ppid=16235 vsize=2568 CPUtime=0 /proc/16236/stat : 16236 (p2cudf-paranoid) S 16235 16236 4778 34817 4778 4202496 377 0 0 0 0 0 0 0 18 0 1 0 10969681 2629632 271 1283457024 134512640 135304128 4290266528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16236/statm: 642 271 230 194 0 29 0 [pid=16237] ppid=16236 vsize=2572 CPUtime=0.03 /proc/16237/stat : 16237 (p2cudf) S 16236 16236 4778 34817 4778 4202496 391 3018 0 1 0 0 2 1 25 0 1 0 10969681 2633728 280 1283457024 134512640 135304128 4293486784 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/16237/statm: 643 280 239 194 0 30 0 [pid=16250] ppid=16237 vsize=892668 CPUtime=4.66 /proc/16250/stat : 16250 (java) S 16237 16236 4778 34817 4778 4202496 76424 0 1 0 434 32 0 0 25 0 11 0 10969685 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/16250/statm: 223167 71480 1762 10 0 220258 0 [pid=16250/tid=16251] ppid=16237 vsize=892668 CPUtime=2.3 /proc/16250/task/16251/stat : 16251 (java) S 16237 16236 4778 34817 4778 4202560 50284 0 1 0 209 21 0 0 25 0 11 0 10969685 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16252] ppid=16237 vsize=892668 CPUtime=0.23 /proc/16250/task/16252/stat : 16252 (java) S 16237 16236 4778 34817 4778 4202560 10771 0 0 0 19 4 0 0 15 0 11 0 10969687 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 0 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16253] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16253/stat : 16253 (java) S 16237 16236 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 18 0 11 0 10969687 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16254] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16254/stat : 16254 (java) S 16237 16236 4778 34817 4778 4202560 6 0 0 0 0 0 0 0 18 0 11 0 10969687 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16255] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16255/stat : 16255 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10969690 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16256] ppid=16237 vsize=892668 CPUtime=1.75 /proc/16250/task/16256/stat : 16256 (java) S 16237 16236 4778 34817 4778 4202560 7680 0 0 0 172 3 0 0 15 0 11 0 10969690 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16257] ppid=16237 vsize=892668 CPUtime=0.34 /proc/16250/task/16257/stat : 16257 (java) S 16237 16236 4778 34817 4778 4202560 6604 0 0 0 32 2 0 0 25 0 11 0 10969690 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16258] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16258/stat : 16258 (java) S 16237 16236 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 10969690 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 [pid=16250/tid=16259] ppid=16237 vsize=892668 CPUtime=0 /proc/16250/task/16259/stat : 16259 (java) S 16237 16236 4778 34817 4778 4202560 2 0 0 0 0 0 0 0 15 0 11 0 10969690 914092032 71480 1283457024 134512640 134550932 4286777600 18446744073709551615 4294960130 0 4 0 16800975 18446612133155119552 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.69 Current children cumulated vsize (KiB) 897808 Child status: 0 Real time (s): 5.29691 CPU time (s): 4.7643 CPU user time (s): 4.40427 CPU system time (s): 0.360022 CPU usage (%): 89.9448 Max. virtual memory (cumulated for all children) (KiB): 899704 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.40428 system time used= 0.360022 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 80236 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= 511 involuntary context switches= 490 runsolver used 0 second user time and 0 second system time The end