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/201012061533/p2cudf-paranoid-1.9/rand5619c8.cudf.easy.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/2010/easy//rand5619c8.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/rand5619c8.cudf.easy.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.13 1.08 1.06 5/46 15748 /proc/meminfo: memFree=673100/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2572 CPUtime=0 /proc/15748/stat : 15748 (p2cudf) R 15747 15747 2059 34821 2220 4202496 349 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 275 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/15748/statm: 643 275 234 194 0 29 0 [pid=15749] ppid=15748 vsize=2572 CPUtime=0 /proc/15749/stat : 15749 (p2cudf) R 15748 15747 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 41 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/15749/statm: 643 41 0 194 0 29 0 [startup+0.191871 s] /proc/loadavg: 1.13 1.08 1.06 5/46 15748 /proc/meminfo: memFree=673100/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+0.211882 s] /proc/loadavg: 1.13 1.08 1.06 5/46 15748 /proc/meminfo: memFree=673100/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+0.31189 s] /proc/loadavg: 1.13 1.08 1.06 5/46 15748 /proc/meminfo: memFree=673100/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+0.703391 s] /proc/loadavg: 1.13 1.08 1.06 5/46 15748 /proc/meminfo: memFree=673100/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5148 [startup+1.50362 s] /proc/loadavg: 1.13 1.08 1.06 5/57 15770 /proc/meminfo: memFree=631476/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=871488 CPUtime=1.46 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 17174 0 1 0 136 10 0 0 25 0 10 0 875651446 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 217872 14785 1741 10 0 214962 0 [pid=15761/tid=15762] ppid=15748 vsize=871488 CPUtime=0.66 /proc/15761/task/15762/stat : 15762 (java) R 15748 15747 2059 34821 2220 4202560 9595 0 1 0 62 4 0 0 25 0 10 0 875651446 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4095729949 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=871488 CPUtime=0 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 18 0 10 0 875651448 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=871488 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 875651448 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=871488 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 875651448 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=871488 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=871488 CPUtime=0.26 /proc/15761/task/15767/stat : 15767 (java) S 15748 15747 2059 34821 2220 4202560 2621 0 0 0 24 2 0 0 25 0 10 0 875651451 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=871488 CPUtime=0.51 /proc/15761/task/15768/stat : 15768 (java) R 15748 15747 2059 34821 2220 4202560 3841 0 0 0 49 2 0 0 20 0 10 0 875651451 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4149552665 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=871488 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=871488 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) S 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 875651451 892403712 14785 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 876636 [startup+3.10396 s] /proc/loadavg: 1.13 1.08 1.06 3/57 15770 /proc/meminfo: memFree=529512/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=872920 CPUtime=3.04 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 46231 0 1 0 282 22 0 0 25 0 10 0 875651446 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 218230 42131 1762 10 0 215320 0 [pid=15761/tid=15762] ppid=15748 vsize=872920 CPUtime=1.36 /proc/15761/task/15762/stat : 15762 (java) R 15748 15747 2059 34821 2220 4202560 36437 0 1 0 120 16 0 0 25 0 10 0 875651446 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 17 0 10 0 875651448 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 875651448 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 875651448 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=872920 CPUtime=1.11 /proc/15761/task/15767/stat : 15767 (java) R 15748 15747 2059 34821 2220 4202560 4800 0 0 0 108 3 0 0 17 0 10 0 875651451 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4150329616 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=872920 CPUtime=0.56 /proc/15761/task/15768/stat : 15768 (java) S 15748 15747 2059 34821 2220 4202560 3877 0 0 0 54 2 0 0 20 0 10 0 875651451 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) S 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 875651451 893870080 42131 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.06 Current children cumulated vsize (KiB) 878068 Solver just ended. Dumping a history of the last processes samples [startup+3.21397 s] /proc/loadavg: 1.13 1.08 1.06 3/57 15770 /proc/meminfo: memFree=529512/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=872920 CPUtime=3.15 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 47031 0 1 0 292 23 0 0 25 0 10 0 875651446 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 218230 42931 1763 10 0 215320 0 [pid=15761/tid=15762] ppid=15748 vsize=872920 CPUtime=1.4 /proc/15761/task/15762/stat : 15762 (java) R 15748 15747 2059 34821 2220 4202560 37231 0 1 0 123 17 0 0 25 0 10 0 875651446 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 17 0 10 0 875651448 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 875651448 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 875651448 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=872920 CPUtime=1.17 /proc/15761/task/15767/stat : 15767 (java) R 15748 15747 2059 34821 2220 4202560 4806 0 0 0 114 3 0 0 17 0 10 0 875651451 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4148485981 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=872920 CPUtime=0.56 /proc/15761/task/15768/stat : 15768 (java) S 15748 15747 2059 34821 2220 4202560 3877 0 0 0 54 2 0 0 20 0 10 0 875651451 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=872920 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) S 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 875651451 893870080 42931 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.17 Current children cumulated vsize (KiB) 878068 [startup+4.00804 s] /proc/loadavg: 1.28 1.11 1.07 3/57 15770 /proc/meminfo: memFree=492932/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=873244 CPUtime=3.93 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 53805 0 1 0 366 27 0 0 25 0 10 0 875651446 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 218311 49705 1783 10 0 215401 0 [pid=15761/tid=15762] ppid=15748 vsize=873244 CPUtime=1.66 /proc/15761/task/15762/stat : 15762 (java) R 15748 15747 2059 34821 2220 4202560 43932 0 1 0 145 21 0 0 25 0 10 0 875651446 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=873244 CPUtime=0.01 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 1 0 0 0 16 0 10 0 875651448 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=873244 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 875651448 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=873244 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 875651448 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=873244 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=873244 CPUtime=1.68 /proc/15761/task/15767/stat : 15767 (java) R 15748 15747 2059 34821 2220 4202560 4875 0 0 0 165 3 0 0 16 0 10 0 875651451 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4150534029 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=873244 CPUtime=0.56 /proc/15761/task/15768/stat : 15768 (java) S 15748 15747 2059 34821 2220 4202560 3877 0 0 0 54 2 0 0 20 0 10 0 875651451 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=873244 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 875651451 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=873244 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) S 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 875651451 894201856 49705 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.95 Current children cumulated vsize (KiB) 878392 [startup+4.40606 s] /proc/loadavg: 1.28 1.11 1.07 2/58 15774 /proc/meminfo: memFree=468124/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=873568 CPUtime=4.21 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 54878 0 1 0 394 27 0 0 25 0 11 0 875651446 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 218392 50778 1787 10 0 215482 0 [pid=15761/tid=15762] ppid=15748 vsize=873568 CPUtime=1.76 /proc/15761/task/15762/stat : 15762 (java) S 15748 15747 2059 34821 2220 4202560 44973 0 1 0 155 21 0 0 25 0 11 0 875651446 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=873568 CPUtime=0.01 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 1 0 0 0 16 0 11 0 875651448 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 875651448 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 875651448 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 875651451 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=873568 CPUtime=1.86 /proc/15761/task/15767/stat : 15767 (java) S 15748 15747 2059 34821 2220 4202560 4903 0 0 0 183 3 0 0 15 0 11 0 875651451 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=873568 CPUtime=0.56 /proc/15761/task/15768/stat : 15768 (java) S 15748 15747 2059 34821 2220 4202560 3877 0 0 0 54 2 0 0 20 0 11 0 875651451 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 875651451 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) R 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 875651451 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15774] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15774/stat : 15774 (java) S 15748 15747 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 875651871 894533632 50778 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.23 Current children cumulated vsize (KiB) 878716 [startup+4.81491 s] /proc/loadavg: 1.28 1.11 1.07 2/58 15774 /proc/meminfo: memFree=468124/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=873568 CPUtime=4.21 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 54904 0 1 0 394 27 0 0 25 0 11 0 875651446 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 218392 50804 1787 10 0 215482 0 [pid=15761/tid=15762] ppid=15748 vsize=873568 CPUtime=1.76 /proc/15761/task/15762/stat : 15762 (java) S 15748 15747 2059 34821 2220 4202560 44973 0 1 0 155 21 0 0 25 0 11 0 875651446 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=873568 CPUtime=0.01 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 1 0 0 0 16 0 11 0 875651448 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 875651448 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 875651448 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 875651451 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=873568 CPUtime=1.87 /proc/15761/task/15767/stat : 15767 (java) S 15748 15747 2059 34821 2220 4202560 4903 0 0 0 184 3 0 0 15 0 11 0 875651451 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=873568 CPUtime=0.56 /proc/15761/task/15768/stat : 15768 (java) S 15748 15747 2059 34821 2220 4202560 3877 0 0 0 54 2 0 0 20 0 11 0 875651451 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 875651451 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) S 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 875651451 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15774] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15774/stat : 15774 (java) R 15748 15747 2059 34821 2220 4202560 26 0 0 0 0 0 0 0 20 0 11 0 875651871 894533632 50804 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.23 Current children cumulated vsize (KiB) 878716 [startup+4.91008 s] /proc/loadavg: 1.28 1.11 1.07 2/58 15774 /proc/meminfo: memFree=468124/1048576 swapFree=2078752/2097144 [pid=15747] ppid=15746 vsize=2572 CPUtime=0 /proc/15747/stat : 15747 (p2cudf-paranoid) S 15746 15747 2059 34821 2220 4202496 377 0 0 0 0 0 0 0 25 0 1 0 875651442 2633728 273 1283457024 134512640 135304128 4293513408 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15747/statm: 643 273 233 194 0 29 0 [pid=15748] ppid=15747 vsize=2576 CPUtime=0.02 /proc/15748/stat : 15748 (p2cudf) S 15747 15747 2059 34821 2220 4202496 394 2993 0 1 0 0 2 0 25 0 1 0 875651442 2637824 283 1283457024 134512640 135304128 4289053840 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/15748/statm: 644 283 241 194 0 30 0 [pid=15761] ppid=15748 vsize=873568 CPUtime=4.31 /proc/15761/stat : 15761 (java) S 15748 15747 2059 34821 2220 4202496 55111 0 1 0 400 31 0 0 25 0 11 0 875651446 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/15761/statm: 218392 51011 1787 10 0 215482 0 [pid=15761/tid=15762] ppid=15748 vsize=873568 CPUtime=1.76 /proc/15761/task/15762/stat : 15762 (java) S 15748 15747 2059 34821 2220 4202560 44973 0 1 0 155 21 0 0 25 0 11 0 875651446 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15763] ppid=15748 vsize=873568 CPUtime=0.01 /proc/15761/task/15763/stat : 15763 (java) S 15748 15747 2059 34821 2220 4202560 5 0 0 0 1 0 0 0 16 0 11 0 875651448 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 0 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15764] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15764/stat : 15764 (java) S 15748 15747 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 875651448 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15765] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15765/stat : 15765 (java) S 15748 15747 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 875651448 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15766] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15766/stat : 15766 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 875651451 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15767] ppid=15748 vsize=873568 CPUtime=1.89 /proc/15761/task/15767/stat : 15767 (java) R 15748 15747 2059 34821 2220 4202560 4906 0 0 0 186 3 0 0 16 0 11 0 875651451 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4149208288 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=15761/tid=15768] ppid=15748 vsize=873568 CPUtime=0.56 /proc/15761/task/15768/stat : 15768 (java) S 15748 15747 2059 34821 2220 4202560 3877 0 0 0 54 2 0 0 20 0 11 0 875651451 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15769] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15769/stat : 15769 (java) S 15748 15747 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 875651451 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15770] ppid=15748 vsize=873568 CPUtime=0 /proc/15761/task/15770/stat : 15770 (java) S 15748 15747 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 875651451 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 18446612133359097920 0 0 -1 0 0 0 0 [pid=15761/tid=15774] ppid=15748 vsize=873568 CPUtime=0.08 /proc/15761/task/15774/stat : 15774 (java) R 15748 15747 2059 34821 2220 4202560 230 0 0 0 4 4 0 0 20 0 11 0 875651871 894533632 51011 1283457024 134512640 134550932 4289110848 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.33 Current children cumulated vsize (KiB) 878716 Child status: 0 Real time (s): 4.98964 CPU time (s): 4.38027 CPU user time (s): 4.04825 CPU system time (s): 0.33202 CPU usage (%): 87.7873 Max. virtual memory (cumulated for all children) (KiB): 880496 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.04825 system time used= 0.33202 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 58941 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= 721 involuntary context switches= 729 runsolver used 0.012 second user time and 0 second system time The end