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/eeee44ce-5407-11df-b11f-00163e7a6f5e.cudf.dudf-real.log.runsolver ./p2cudf-paranoid-1.13 /home/misc2010/data/2011/dudf-real//eeee44ce-5407-11df-b11f-00163e7a6f5e.cudf /home/misc2010/tmp/201108251442/p2cudf-paranoid-1.13/eeee44ce-5407-11df-b11f-00163e7a6f5e.cudf.dudf-real.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.27 1.40 1.35 5/35 30882 /proc/meminfo: memFree=269312/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2568 CPUtime=0 /proc/30881/stat : 30881 (p2cudf) R 30880 30880 4778 34817 4778 4202496 346 0 0 0 0 0 0 0 25 0 1 0 11268220 2629632 272 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/30881/statm: 642 272 231 194 0 29 0 [pid=30882] ppid=30881 vsize=2568 CPUtime=0 /proc/30882/stat : 30882 (p2cudf) R 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 1 0 11268220 2629632 41 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65538 4 65536 0 0 0 17 0 0 0 0 /proc/30882/statm: 642 41 0 194 0 29 0 [startup+0.185906 s] /proc/loadavg: 1.27 1.40 1.35 5/35 30882 /proc/meminfo: memFree=269312/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.205899 s] /proc/loadavg: 1.27 1.40 1.35 5/35 30882 /proc/meminfo: memFree=269312/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.30591 s] /proc/loadavg: 1.27 1.40 1.35 5/35 30882 /proc/meminfo: memFree=269312/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+0.705985 s] /proc/loadavg: 1.27 1.40 1.35 5/35 30882 /proc/meminfo: memFree=269312/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 Current children cumulated CPU time (s) 0.03 Current children cumulated vsize (KiB) 5140 [startup+1.50608 s] /proc/loadavg: 1.27 1.40 1.35 4/45 30903 /proc/meminfo: memFree=218692/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=885464 CPUtime=1.46 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 22335 0 1 0 140 6 0 0 25 0 10 0 11268224 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221366 19624 1730 10 0 218457 0 [pid=30894/tid=30895] ppid=30881 vsize=885464 CPUtime=0.7 /proc/30894/task/30895/stat : 30895 (java) R 30881 30880 4778 34817 4778 4202560 11047 0 1 0 66 4 0 0 25 0 10 0 11268224 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4095312756 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 18 0 10 0 11268226 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11268226 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11268226 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11268229 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=885464 CPUtime=0.46 /proc/30894/task/30900/stat : 30900 (java) R 30881 30880 4778 34817 4778 4202560 4710 0 0 0 46 0 0 0 25 0 10 0 11268229 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=885464 CPUtime=0.28 /proc/30894/task/30901/stat : 30901 (java) R 30881 30880 4778 34817 4778 4202560 5497 0 0 0 27 1 0 0 23 0 10 0 11268229 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4150162072 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11268229 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11268229 906715136 19624 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 890604 [startup+3.10636 s] /proc/loadavg: 1.27 1.40 1.35 3/45 30903 /proc/meminfo: memFree=118376/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=885464 CPUtime=3.06 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 47346 0 1 0 288 18 0 0 25 0 10 0 11268224 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221366 44635 1744 10 0 218457 0 [pid=30894/tid=30895] ppid=30881 vsize=885464 CPUtime=1.26 /proc/30894/task/30895/stat : 30895 (java) R 30881 30880 4778 34817 4778 4202560 35909 0 1 0 110 16 0 0 25 0 10 0 11268224 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 18 0 10 0 11268226 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11268226 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11268226 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11268229 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=885464 CPUtime=1.26 /proc/30894/task/30900/stat : 30900 (java) R 30881 30880 4778 34817 4778 4202560 4802 0 0 0 126 0 0 0 18 0 10 0 11268229 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4152555225 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=885464 CPUtime=0.53 /proc/30894/task/30901/stat : 30901 (java) R 30881 30880 4778 34817 4778 4202560 5554 0 0 0 52 1 0 0 19 0 10 0 11268229 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4151828490 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11268229 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11268229 906715136 44635 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 890604 Solver just ended. Dumping a history of the last processes samples [startup+3.20636 s] /proc/loadavg: 1.27 1.40 1.35 3/45 30903 /proc/meminfo: memFree=118376/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=885464 CPUtime=3.16 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 48157 0 1 0 298 18 0 0 25 0 10 0 11268224 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221366 45446 1744 10 0 218457 0 [pid=30894/tid=30895] ppid=30881 vsize=885464 CPUtime=1.32 /proc/30894/task/30895/stat : 30895 (java) R 30881 30880 4778 34817 4778 4202560 36698 0 1 0 116 16 0 0 25 0 10 0 11268224 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4095283364 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 10 0 11268226 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 10 0 11268226 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 10 0 11268226 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 10 0 11268229 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=885464 CPUtime=1.27 /proc/30894/task/30900/stat : 30900 (java) S 30881 30880 4778 34817 4778 4202560 4824 0 0 0 127 0 0 0 18 0 10 0 11268229 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=885464 CPUtime=0.55 /proc/30894/task/30901/stat : 30901 (java) S 30881 30880 4778 34817 4778 4202560 5554 0 0 0 54 1 0 0 19 0 10 0 11268229 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 10 0 11268229 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=885464 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 10 0 11268229 906715136 45446 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.19 Current children cumulated vsize (KiB) 890604 [startup+4.00651 s] /proc/loadavg: 1.27 1.40 1.35 3/45 30903 /proc/meminfo: memFree=90352/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=885788 CPUtime=3.96 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 57904 0 1 0 374 22 0 0 25 0 11 0 11268224 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221447 55193 1758 10 0 218538 0 [pid=30894/tid=30895] ppid=30881 vsize=885788 CPUtime=1.74 /proc/30894/task/30895/stat : 30895 (java) R 30881 30880 4778 34817 4778 4202560 46390 0 1 0 154 20 0 0 25 0 11 0 11268224 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=885788 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 11 0 11268226 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=885788 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 11268226 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=885788 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 11268226 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=885788 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11268229 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=885788 CPUtime=1.27 /proc/30894/task/30900/stat : 30900 (java) S 30881 30880 4778 34817 4778 4202560 4824 0 0 0 127 0 0 0 18 0 11 0 11268229 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=885788 CPUtime=0.93 /proc/30894/task/30901/stat : 30901 (java) R 30881 30880 4778 34817 4778 4202560 5605 0 0 0 92 1 0 0 16 0 11 0 11268229 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4150187602 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=885788 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11268229 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=885788 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11268229 907046912 55193 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.99 Current children cumulated vsize (KiB) 890928 [startup+4.40615 s] /proc/loadavg: 1.27 1.40 1.35 1/46 30905 /proc/meminfo: memFree=54268/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=886112 CPUtime=4 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 58018 0 1 0 378 22 0 0 25 0 11 0 11268224 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221528 55307 1759 10 0 218619 0 [pid=30894/tid=30895] ppid=30881 vsize=886112 CPUtime=1.76 /proc/30894/task/30895/stat : 30895 (java) S 30881 30880 4778 34817 4778 4202560 46496 0 1 0 156 20 0 0 25 0 11 0 11268224 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 11 0 11268226 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 11268226 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 11268226 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11268229 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=886112 CPUtime=1.27 /proc/30894/task/30900/stat : 30900 (java) S 30881 30880 4778 34817 4778 4202560 4824 0 0 0 127 0 0 0 18 0 11 0 11268229 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=886112 CPUtime=0.96 /proc/30894/task/30901/stat : 30901 (java) S 30881 30880 4778 34817 4778 4202560 5608 0 0 0 95 1 0 0 16 0 11 0 11268229 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11268229 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11268229 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30905] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30905/stat : 30905 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 11268625 907378688 55307 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.03 Current children cumulated vsize (KiB) 891252 [startup+4.61013 s] /proc/loadavg: 1.27 1.40 1.35 1/46 30905 /proc/meminfo: memFree=54268/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=886112 CPUtime=4.05 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 58088 0 1 0 382 23 0 0 25 0 11 0 11268224 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221528 55377 1760 10 0 218619 0 [pid=30894/tid=30895] ppid=30881 vsize=886112 CPUtime=1.76 /proc/30894/task/30895/stat : 30895 (java) S 30881 30880 4778 34817 4778 4202560 46496 0 1 0 156 20 0 0 25 0 11 0 11268224 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 11 0 11268226 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 11268226 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 11268226 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11268229 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=886112 CPUtime=1.27 /proc/30894/task/30900/stat : 30900 (java) S 30881 30880 4778 34817 4778 4202560 4824 0 0 0 127 0 0 0 18 0 11 0 11268229 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=886112 CPUtime=0.99 /proc/30894/task/30901/stat : 30901 (java) S 30881 30880 4778 34817 4778 4202560 5611 0 0 0 98 1 0 0 15 0 11 0 11268229 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11268229 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11268229 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30905] ppid=30881 vsize=886112 CPUtime=0.01 /proc/30894/task/30905/stat : 30905 (java) R 30881 30880 4778 34817 4778 4202560 71 0 0 0 0 1 0 0 20 0 11 0 11268625 907378688 55377 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.08 Current children cumulated vsize (KiB) 891252 [startup+4.71016 s] /proc/loadavg: 1.27 1.40 1.35 1/46 30905 /proc/meminfo: memFree=54268/1048576 swapFree=0/0 [pid=30880] ppid=30879 vsize=2568 CPUtime=0 /proc/30880/stat : 30880 (p2cudf-paranoid) S 30879 30880 4778 34817 4778 4202496 375 0 0 0 0 0 0 0 18 0 1 0 11268220 2629632 271 1283457024 134512640 135304128 4292659120 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30880/statm: 642 271 230 194 0 29 0 [pid=30881] ppid=30880 vsize=2572 CPUtime=0.03 /proc/30881/stat : 30881 (p2cudf) S 30880 30880 4778 34817 4778 4202496 390 3013 0 1 1 0 1 1 25 0 1 0 11268220 2633728 281 1283457024 134512640 135304128 4290774528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/30881/statm: 643 281 239 194 0 30 0 [pid=30894] ppid=30881 vsize=886112 CPUtime=4.15 /proc/30894/stat : 30894 (java) S 30881 30880 4778 34817 4778 4202496 58265 0 1 0 390 25 0 0 25 0 11 0 11268224 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/30894/statm: 221528 55554 1761 10 0 218619 0 [pid=30894/tid=30895] ppid=30881 vsize=886112 CPUtime=1.76 /proc/30894/task/30895/stat : 30895 (java) S 30881 30880 4778 34817 4778 4202560 46496 0 1 0 156 20 0 0 25 0 11 0 11268224 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30896] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30896/stat : 30896 (java) S 30881 30880 4778 34817 4778 4202560 7 0 0 0 0 0 0 0 16 0 11 0 11268226 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 0 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30897] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30897/stat : 30897 (java) S 30881 30880 4778 34817 4778 4202560 8 0 0 0 0 0 0 0 25 0 11 0 11268226 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30898] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30898/stat : 30898 (java) S 30881 30880 4778 34817 4778 4202560 4 0 0 0 0 0 0 0 25 0 11 0 11268226 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30899] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30899/stat : 30899 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 25 0 11 0 11268229 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30900] ppid=30881 vsize=886112 CPUtime=1.27 /proc/30894/task/30900/stat : 30900 (java) S 30881 30880 4778 34817 4778 4202560 4824 0 0 0 127 0 0 0 18 0 11 0 11268229 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30901] ppid=30881 vsize=886112 CPUtime=1.05 /proc/30894/task/30901/stat : 30901 (java) R 30881 30880 4778 34817 4778 4202560 5618 0 0 0 104 1 0 0 16 0 11 0 11268229 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4149385448 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=30894/tid=30902] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30902/stat : 30902 (java) S 30881 30880 4778 34817 4778 4202560 0 0 0 0 0 0 0 0 25 0 11 0 11268229 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30903] ppid=30881 vsize=886112 CPUtime=0 /proc/30894/task/30903/stat : 30903 (java) S 30881 30880 4778 34817 4778 4202560 1 0 0 0 0 0 0 0 15 0 11 0 11268229 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 18446612133393238784 0 0 -1 0 0 0 0 [pid=30894/tid=30905] ppid=30881 vsize=886112 CPUtime=0.05 /proc/30894/task/30905/stat : 30905 (java) R 30881 30880 4778 34817 4778 4202560 241 0 0 0 2 3 0 0 20 0 11 0 11268625 907378688 55554 1283457024 134512640 134550932 4286922992 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.18 Current children cumulated vsize (KiB) 891252 Child status: 0 Real time (s): 4.75307 CPU time (s): 4.22426 CPU user time (s): 3.94025 CPU system time (s): 0.284017 CPU usage (%): 88.8744 Max. virtual memory (cumulated for all children) (KiB): 891500 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.94025 system time used= 0.284017 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 62085 page faults= 2 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 626 involuntary context switches= 613 runsolver used 0 second user time and 0 second system time The end