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: runsolver -s SIGUSR1 -M 1124 -C 150 -d 10 -w /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/embedded/real/56e31304-c17a-11df-b070-00163e3d3b7c.cudf.log.runsolver /home/competition/p2cudf-basic-1.15/p2cudf-basic-1.15 /home/competition/data/real/56e31304-c17a-11df-b070-00163e3d3b7c.cudf /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/embedded/real/56e31304-c17a-11df-b070-00163e3d3b7c.cudf.result -sum(solution,installedsize),-count(removed) Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 150 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 180 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 [startup+0 s] /proc/loadavg: 2.00 1.36 1.06 2/59 6668 /proc/meminfo: memFree=490612/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=3152 CPUtime=0 /proc/6668/stat : 6668 (runsolver) R 6667 1750 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 115989175 3227648 33 18446744073709551615 134512640 134586868 4290979760 4290977808 4152210480 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.170662 s] /proc/loadavg: 2.00 1.36 1.06 2/59 6668 /proc/meminfo: memFree=490612/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.200283 s] /proc/loadavg: 2.00 1.36 1.06 2/59 6668 /proc/meminfo: memFree=490612/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.300276 s] /proc/loadavg: 2.00 1.36 1.06 2/59 6668 /proc/meminfo: memFree=490612/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.700245 s] /proc/loadavg: 2.00 1.36 1.06 2/59 6668 /proc/meminfo: memFree=490612/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+1.50057 s] /proc/loadavg: 2.08 1.39 1.07 4/70 6680 /proc/meminfo: memFree=375940/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1050140 CPUtime=1.46 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 42942 0 0 0 132 14 0 0 20 0 10 0 115989175 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262535 37766 2348 9 0 249885 0 [pid=6671/tid=6672] ppid=6668 vsize=1050140 CPUtime=0.61 /proc/6671/task/6672/stat : 6672 (java) R 6668 6668 1750 0 -1 4202560 22048 0 0 0 53 8 0 0 20 0 10 0 115989176 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200126960320 140200024459116 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 115989178 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 115989178 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 115989178 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115989181 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1050140 CPUtime=0.41 /proc/6671/task/6677/stat : 6677 (java) R 6668 6668 1750 0 -1 4202560 9670 0 0 0 38 3 0 0 20 0 10 0 115989181 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200015395600 140200106107290 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1050140 CPUtime=0.4 /proc/6671/task/6678/stat : 6678 (java) R 6668 6668 1750 0 -1 4202560 9933 0 0 0 38 2 0 0 20 0 10 0 115989181 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200014342928 140200103372928 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115989181 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115989181 1075343360 37766 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.46 Current children cumulated vsize (KiB) 1059312 [startup+3.10057 s] /proc/loadavg: 2.08 1.39 1.07 2/70 6680 /proc/meminfo: memFree=205316/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1050140 CPUtime=3.03 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 98532 0 0 0 277 26 0 0 20 0 10 0 115989175 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262535 93356 2369 9 0 249885 0 [pid=6671/tid=6672] ppid=6668 vsize=1050140 CPUtime=1.56 /proc/6671/task/6672/stat : 6672 (java) R 6668 6668 1750 0 -1 4202560 47407 0 0 0 143 13 0 0 20 0 10 0 115989176 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200126960320 140200024776699 0 4 0 16800975 0 0 0 -1 0 0 0 4 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1050140 CPUtime=0.46 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 30187 0 0 0 40 6 0 0 20 0 10 0 115989178 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115989178 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115989178 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115989181 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1050140 CPUtime=0.49 /proc/6671/task/6677/stat : 6677 (java) S 6668 6668 1750 0 -1 4202560 9683 0 0 0 46 3 0 0 20 0 10 0 115989181 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200015395856 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1050140 CPUtime=0.47 /proc/6671/task/6678/stat : 6678 (java) S 6668 6668 1750 0 -1 4202560 9964 0 0 0 45 2 0 0 20 0 10 0 115989181 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200014343184 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115989181 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1050140 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115989181 1075343360 93356 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.03 Current children cumulated vsize (KiB) 1059312 [startup+6.30058 s] /proc/loadavg: 2.15 1.41 1.08 4/70 6680 /proc/meminfo: memFree=33328/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1048748 CPUtime=6.08 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 117519 0 0 0 573 35 0 0 20 0 11 0 115989175 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262187 110385 2377 9 0 249537 0 [pid=6671/tid=6672] ppid=6668 vsize=1048748 CPUtime=2.84 /proc/6671/task/6672/stat : 6672 (java) R 6668 6668 1750 0 -1 4202560 47770 0 0 0 268 16 0 0 20 0 11 0 115989176 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200126968008 140200024480221 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1048748 CPUtime=0.81 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 46726 0 0 0 70 11 0 0 20 0 11 0 115989178 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1048748 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115989178 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1048748 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115989178 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1048748 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115989181 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1048748 CPUtime=1.22 /proc/6671/task/6677/stat : 6677 (java) R 6668 6668 1750 0 -1 4202560 10571 0 0 0 118 4 0 0 20 0 11 0 115989181 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200015395600 140200107777040 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1048748 CPUtime=1.17 /proc/6671/task/6678/stat : 6678 (java) R 6668 6668 1750 0 -1 4202560 11155 0 0 0 113 4 0 0 20 0 11 0 115989181 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200014342928 140200107776349 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1048748 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1048748 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1073917952 110385 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.08 Current children cumulated vsize (KiB) 1057920 Solver just ended. Dumping a history of the last processes samples [startup+6.40075 s] /proc/loadavg: 2.15 1.41 1.08 4/70 6680 /proc/meminfo: memFree=33328/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1049776 CPUtime=6.17 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 117539 0 0 0 582 35 0 0 20 0 11 0 115989175 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262444 110403 2377 9 0 249794 0 [pid=6671/tid=6672] ppid=6668 vsize=1049776 CPUtime=2.87 /proc/6671/task/6672/stat : 6672 (java) S 6668 6668 1750 0 -1 4202560 47781 0 0 0 271 16 0 0 20 0 11 0 115989176 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200126968752 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1049776 CPUtime=0.81 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 46726 0 0 0 70 11 0 0 20 0 11 0 115989178 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1049776 CPUtime=1.26 /proc/6671/task/6677/stat : 6677 (java) S 6668 6668 1750 0 -1 4202560 10573 0 0 0 122 4 0 0 20 0 11 0 115989181 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200015395856 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1049776 CPUtime=1.21 /proc/6671/task/6678/stat : 6678 (java) R 6668 6668 1750 0 -1 4202560 11156 0 0 0 117 4 0 0 20 0 11 0 115989181 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200014342928 140200107135498 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110403 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.17 Current children cumulated vsize (KiB) 1058948 [startup+6.80085 s] /proc/loadavg: 2.15 1.41 1.08 1/71 6682 /proc/meminfo: memFree=35932/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1049776 CPUtime=6.18 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 117541 0 0 0 583 35 0 0 20 0 11 0 115989175 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262444 110405 2377 9 0 249794 0 [pid=6671/tid=6672] ppid=6668 vsize=1049776 CPUtime=2.87 /proc/6671/task/6672/stat : 6672 (java) S 6668 6668 1750 0 -1 4202560 47781 0 0 0 271 16 0 0 20 0 11 0 115989176 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200126968752 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1049776 CPUtime=0.81 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 46726 0 0 0 70 11 0 0 20 0 11 0 115989178 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1049776 CPUtime=1.26 /proc/6671/task/6677/stat : 6677 (java) S 6668 6668 1750 0 -1 4202560 10573 0 0 0 122 4 0 0 20 0 11 0 115989181 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200015395856 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1049776 CPUtime=1.21 /proc/6671/task/6678/stat : 6678 (java) S 6668 6668 1750 0 -1 4202560 11158 0 0 0 117 4 0 0 20 0 11 0 115989181 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200014343184 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6682] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6682/stat : 6682 (java) S 6668 6668 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 115989812 1074970624 110405 18446744073709551615 4194304 4228012 140733581243856 140200009988272 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.18 Current children cumulated vsize (KiB) 1058948 [startup+7.00076 s] /proc/loadavg: 2.15 1.41 1.08 1/71 6682 /proc/meminfo: memFree=35932/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1049776 CPUtime=6.29 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 117543 0 0 0 592 37 0 0 20 0 11 0 115989175 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262444 110407 2377 9 0 249794 0 [pid=6671/tid=6672] ppid=6668 vsize=1049776 CPUtime=2.87 /proc/6671/task/6672/stat : 6672 (java) S 6668 6668 1750 0 -1 4202560 47781 0 0 0 271 16 0 0 20 0 11 0 115989176 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200126968752 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1049776 CPUtime=0.81 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 46726 0 0 0 70 11 0 0 20 0 11 0 115989178 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1049776 CPUtime=1.3 /proc/6671/task/6677/stat : 6677 (java) S 6668 6668 1750 0 -1 4202560 10573 0 0 0 126 4 0 0 20 0 11 0 115989181 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200015395856 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1049776 CPUtime=1.22 /proc/6671/task/6678/stat : 6678 (java) S 6668 6668 1750 0 -1 4202560 11158 0 0 0 118 4 0 0 20 0 11 0 115989181 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200014343184 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6682] ppid=6668 vsize=1049776 CPUtime=0.06 /proc/6671/task/6682/stat : 6682 (java) R 6668 6668 1750 0 -1 4202560 8 0 0 0 4 2 0 0 20 0 11 0 115989812 1074970624 110407 18446744073709551615 4194304 4228012 140733581243856 140200009979184 140200024479843 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.29 Current children cumulated vsize (KiB) 1058948 [startup+7.10091 s] /proc/loadavg: 2.15 1.41 1.08 1/71 6682 /proc/meminfo: memFree=35932/1022884 swapFree=0/0 [pid=6668] ppid=6667 vsize=9172 CPUtime=0 /proc/6668/stat : 6668 (p2cudf-basic-1.) S 6667 6668 1750 0 -1 4202496 461 290 0 0 0 0 0 0 20 0 1 0 115989175 9392128 332 18446744073709551615 4194304 5129932 140735339553264 140735339551408 140490168550494 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/6668/statm: 2293 332 280 229 0 53 0 [pid=6671] ppid=6668 vsize=1049776 CPUtime=6.4 /proc/6671/stat : 6671 (java) S 6668 6668 1750 0 -1 4202496 117545 0 0 0 602 38 0 0 20 0 11 0 115989175 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140733581234880 140200120434293 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/6671/statm: 262444 110409 2378 9 0 249794 0 [pid=6671/tid=6672] ppid=6668 vsize=1049776 CPUtime=2.87 /proc/6671/task/6672/stat : 6672 (java) S 6668 6668 1750 0 -1 4202560 47781 0 0 0 271 16 0 0 20 0 11 0 115989176 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200126968752 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 16 0 0 [pid=6671/tid=6673] ppid=6668 vsize=1049776 CPUtime=0.81 /proc/6671/task/6673/stat : 6673 (java) S 6668 6668 1750 0 -1 4202560 46726 0 0 0 70 11 0 0 20 0 11 0 115989178 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200019606656 140200120448587 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6674] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6674/stat : 6674 (java) S 6668 6668 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200018552848 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6675] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6675/stat : 6675 (java) S 6668 6668 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115989178 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200017500064 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6676] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6676/stat : 6676 (java) S 6668 6668 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200016448496 140200120456224 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6677] ppid=6668 vsize=1049776 CPUtime=1.31 /proc/6671/task/6677/stat : 6677 (java) R 6668 6668 1750 0 -1 4202560 10573 0 0 0 127 4 0 0 20 0 11 0 115989181 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200015395600 140200106590782 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6678] ppid=6668 vsize=1049776 CPUtime=1.23 /proc/6671/task/6678/stat : 6678 (java) R 6668 6668 1750 0 -1 4202560 11159 0 0 0 119 4 0 0 20 0 11 0 115989181 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200014342928 140200104307102 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6679] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6679/stat : 6679 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200013290704 140200120447700 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6680] ppid=6668 vsize=1049776 CPUtime=0 /proc/6671/task/6680/stat : 6680 (java) S 6668 6668 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115989181 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200012238208 140200120448587 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=6671/tid=6682] ppid=6668 vsize=1049776 CPUtime=0.14 /proc/6671/task/6682/stat : 6682 (java) R 6668 6668 1750 0 -1 4202560 9 0 0 0 11 3 0 0 20 0 11 0 115989812 1074970624 110409 18446744073709551615 4194304 4228012 140733581243856 140200009979264 140200120459565 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.4 Current children cumulated vsize (KiB) 1058948 Child status: 0 Real time (s): 7.19053 CPU time (s): 6.4884 CPU user time (s): 6.04438 CPU system time (s): 0.444027 CPU usage (%): 90.2354 Max. virtual memory (cumulated for all children) (KiB): 1061004 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.04438 system time used= 0.444027 maximum resident set size= 444488 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 118319 page faults= 0 swaps= 0 block input operations= 41992 block output operations= 232 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 855 involuntary context switches= 1964 runsolver used 0.012 second user time and 0.056003 second system time The end