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/ca8f656c-db9e-11df-b9cf-00163e3d3b7c.cudf.log.runsolver /home/competition/p2cudf-basic-1.15/p2cudf-basic-1.15 /home/competition/data/real/ca8f656c-db9e-11df-b9cf-00163e3d3b7c.cudf /tmp/misc2012/2012-09-02-17:21/basic/p2cudf-basic-1.15/embedded/real/ca8f656c-db9e-11df-b9cf-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 Current StackSize limit: 8192 KiB [startup+0 s] /proc/loadavg: 1.46 1.18 1.00 2/59 24173 /proc/meminfo: memFree=527184/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 427 0 0 0 0 0 0 0 20 0 1 0 115985406 9392128 327 18446744073709551615 4194304 5129932 140733455931360 140733455929032 140719645812512 0 0 16781316 65536 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 327 275 229 0 53 0 [startup+0.168653 s] /proc/loadavg: 1.46 1.18 1.00 2/59 24173 /proc/meminfo: memFree=527184/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.20038 s] /proc/loadavg: 1.46 1.18 1.00 2/59 24173 /proc/meminfo: memFree=527184/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.300322 s] /proc/loadavg: 1.46 1.18 1.00 2/59 24173 /proc/meminfo: memFree=527184/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.70023 s] /proc/loadavg: 1.46 1.18 1.00 2/59 24173 /proc/meminfo: memFree=527184/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+1.50077 s] /proc/loadavg: 1.46 1.18 1.00 4/70 24185 /proc/meminfo: memFree=486292/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1049400 CPUtime=1.44 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 29325 0 0 0 130 14 0 0 20 0 10 0 115985407 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 262350 26924 2349 9 0 249700 0 [pid=24176/tid=24177] ppid=24173 vsize=1049400 CPUtime=0.68 /proc/24176/task/24177/stat : 24177 (java) R 24173 24173 1733 0 -1 4202560 12882 0 0 0 60 8 0 0 20 0 10 0 115985408 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639199695520 140639097179216 0 4 0 16800975 0 0 0 -1 0 0 0 3 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1049400 CPUtime=0 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 115985410 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1049400 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 115985411 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1049400 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 115985411 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1049400 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115985415 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1049400 CPUtime=0.37 /proc/24176/task/24182/stat : 24182 (java) R 24173 24173 1733 0 -1 4202560 8325 0 0 0 34 3 0 0 20 0 10 0 115985415 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639088116792 140639179689819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1049400 CPUtime=0.36 /proc/24176/task/24183/stat : 24183 (java) R 24173 24173 1733 0 -1 4202560 6824 0 0 0 35 1 0 0 20 0 10 0 115985415 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639087063624 140639178840246 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1049400 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1049400 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1074585600 26924 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.44 Current children cumulated vsize (KiB) 1058572 [startup+3.10082 s] /proc/loadavg: 1.46 1.18 1.00 3/70 24185 /proc/meminfo: memFree=368492/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1054936 CPUtime=2.95 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 85572 0 0 0 263 32 0 0 20 0 10 0 115985407 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263734 81561 2370 9 0 251084 0 [pid=24176/tid=24177] ppid=24173 vsize=1054936 CPUtime=1.4 /proc/24176/task/24177/stat : 24177 (java) S 24173 24173 1733 0 -1 4202560 47414 0 0 0 120 20 0 0 20 0 10 0 115985408 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639199703472 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 11 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1054936 CPUtime=0.31 /proc/24176/task/24178/stat : 24178 (java) R 24173 24173 1733 0 -1 4202560 17887 0 0 0 26 5 0 0 20 0 10 0 115985410 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639092341264 140639180044368 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1054936 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115985411 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1054936 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115985411 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1054936 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115985415 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1054936 CPUtime=0.6 /proc/24176/task/24182/stat : 24182 (java) S 24173 24173 1733 0 -1 4202560 10970 0 0 0 56 4 0 0 20 0 10 0 115985415 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639088131088 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1054936 CPUtime=0.61 /proc/24176/task/24183/stat : 24183 (java) S 24173 24173 1733 0 -1 4202560 8006 0 0 0 60 1 0 0 20 0 10 0 115985415 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639087078416 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1054936 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1054936 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1080254464 81561 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.95 Current children cumulated vsize (KiB) 1064108 [startup+6.30084 s] /proc/loadavg: 1.42 1.17 1.00 3/70 24185 /proc/meminfo: memFree=145416/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1052084 CPUtime=6.09 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 99476 0 0 0 571 38 0 0 20 0 10 0 115985407 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263021 94198 2374 9 0 250371 0 [pid=24176/tid=24177] ppid=24173 vsize=1052084 CPUtime=2.75 /proc/24176/task/24177/stat : 24177 (java) R 24173 24173 1733 0 -1 4202560 47713 0 0 0 252 23 0 0 20 0 10 0 115985408 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639199704040 140639097996436 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1052084 CPUtime=0.61 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 30209 0 0 0 53 8 0 0 20 0 10 0 115985410 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115985411 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115985411 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115985415 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1052084 CPUtime=1.26 /proc/24176/task/24182/stat : 24182 (java) R 24173 24173 1733 0 -1 4202560 12133 0 0 0 122 4 0 0 20 0 10 0 115985415 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639088130832 140639180509295 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1052084 CPUtime=1.42 /proc/24176/task/24183/stat : 24183 (java) R 24173 24173 1733 0 -1 4202560 8126 0 0 0 141 1 0 0 20 0 10 0 115985415 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639087063912 140639177474040 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1077334016 94198 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.09 Current children cumulated vsize (KiB) 1061256 Solver just ended. Dumping a history of the last processes samples [startup+6.40104 s] /proc/loadavg: 1.42 1.17 1.00 3/70 24185 /proc/meminfo: memFree=145416/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1052084 CPUtime=6.18 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 99481 0 0 0 580 38 0 0 20 0 10 0 115985407 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263021 94203 2375 9 0 250371 0 [pid=24176/tid=24177] ppid=24173 vsize=1052084 CPUtime=2.79 /proc/24176/task/24177/stat : 24177 (java) R 24173 24173 1733 0 -1 4202560 47713 0 0 0 256 23 0 0 20 0 10 0 115985408 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639199704040 140639097125299 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1052084 CPUtime=0.61 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 30209 0 0 0 53 8 0 0 20 0 10 0 115985410 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 115985411 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 115985411 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 115985415 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1052084 CPUtime=1.3 /proc/24176/task/24182/stat : 24182 (java) R 24173 24173 1733 0 -1 4202560 12138 0 0 0 125 5 0 0 20 0 10 0 115985415 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639088130832 140639179221878 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1052084 CPUtime=1.45 /proc/24176/task/24183/stat : 24183 (java) R 24173 24173 1733 0 -1 4202560 8126 0 0 0 144 1 0 0 20 0 10 0 115985415 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639087063912 140639177473698 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1052084 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 115985415 1077334016 94203 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 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) 1061256 [startup+7.20078 s] /proc/loadavg: 1.42 1.17 1.00 3/70 24185 /proc/meminfo: memFree=144920/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1053112 CPUtime=6.98 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 99605 0 0 0 659 39 0 0 20 0 11 0 115985407 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263278 94327 2378 9 0 250628 0 [pid=24176/tid=24177] ppid=24173 vsize=1053112 CPUtime=3.11 /proc/24176/task/24177/stat : 24177 (java) R 24173 24173 1733 0 -1 4202560 47782 0 0 0 288 23 0 0 20 0 11 0 115985408 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639199703768 140639097215656 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1053112 CPUtime=0.61 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 30210 0 0 0 53 8 0 0 20 0 11 0 115985410 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1053112 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115985411 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1053112 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115985411 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1053112 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115985415 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1053112 CPUtime=1.51 /proc/24176/task/24182/stat : 24182 (java) R 24173 24173 1733 0 -1 4202560 12150 0 0 0 146 5 0 0 20 0 11 0 115985415 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639088130832 140639180506256 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1053112 CPUtime=1.72 /proc/24176/task/24183/stat : 24183 (java) R 24173 24173 1733 0 -1 4202560 8162 0 0 0 171 1 0 0 20 0 11 0 115985415 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639087078160 140639179010250 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1053112 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1053112 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1078386688 94327 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.98 Current children cumulated vsize (KiB) 1062284 [startup+7.60083 s] /proc/loadavg: 1.42 1.17 1.00 3/70 24185 /proc/meminfo: memFree=144920/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1054140 CPUtime=7.01 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 99626 0 0 0 662 39 0 0 20 0 11 0 115985407 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263535 94346 2378 9 0 250885 0 [pid=24176/tid=24177] ppid=24173 vsize=1054140 CPUtime=3.11 /proc/24176/task/24177/stat : 24177 (java) S 24173 24173 1733 0 -1 4202560 47795 0 0 0 288 23 0 0 20 0 11 0 115985408 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639199703984 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1054140 CPUtime=0.61 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 30210 0 0 0 53 8 0 0 20 0 11 0 115985410 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115985411 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115985411 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1054140 CPUtime=1.53 /proc/24176/task/24182/stat : 24182 (java) S 24173 24173 1733 0 -1 4202560 12152 0 0 0 148 5 0 0 20 0 11 0 115985415 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639088131088 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1054140 CPUtime=1.73 /proc/24176/task/24183/stat : 24183 (java) S 24173 24173 1733 0 -1 4202560 8162 0 0 0 172 1 0 0 20 0 11 0 115985415 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639087078416 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94346 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 7.01 Current children cumulated vsize (KiB) 1063312 [startup+7.80366 s] /proc/loadavg: 1.42 1.17 1.00 1/71 24187 /proc/meminfo: memFree=144408/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1054140 CPUtime=7.08 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 99628 0 0 0 668 40 0 0 20 0 11 0 115985407 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263535 94348 2378 9 0 250885 0 [pid=24176/tid=24177] ppid=24173 vsize=1054140 CPUtime=3.11 /proc/24176/task/24177/stat : 24177 (java) S 24173 24173 1733 0 -1 4202560 47795 0 0 0 288 23 0 0 20 0 11 0 115985408 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639199703984 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1054140 CPUtime=0.61 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 30210 0 0 0 53 8 0 0 20 0 11 0 115985410 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115985411 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115985411 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1054140 CPUtime=1.53 /proc/24176/task/24182/stat : 24182 (java) R 24173 24173 1733 0 -1 4202560 12152 0 0 0 148 5 0 0 20 0 11 0 115985415 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639088130832 140639179317856 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1054140 CPUtime=1.75 /proc/24176/task/24183/stat : 24183 (java) S 24173 24173 1733 0 -1 4202560 8162 0 0 0 174 1 0 0 20 0 11 0 115985415 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639087078416 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24187] ppid=24173 vsize=1054140 CPUtime=0.04 /proc/24176/task/24187/stat : 24187 (java) R 24173 24173 1733 0 -1 4202560 8 0 0 0 3 1 0 0 20 0 11 0 115986129 1079439360 94348 18446744073709551615 4194304 4228012 140735122449712 140639082714248 140639193194074 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 7.08 Current children cumulated vsize (KiB) 1063312 [startup+7.90063 s] /proc/loadavg: 1.42 1.17 1.00 1/71 24187 /proc/meminfo: memFree=144408/1022884 swapFree=0/0 [pid=24173] ppid=24172 vsize=9172 CPUtime=0 /proc/24173/stat : 24173 (p2cudf-basic-1.) S 24172 24173 1733 0 -1 4202496 463 289 0 0 0 0 0 0 20 0 1 0 115985406 9392128 332 18446744073709551615 4194304 5129932 140733455931360 140733455929504 140719645668446 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/24173/statm: 2293 332 280 229 0 53 0 [pid=24176] ppid=24173 vsize=1054140 CPUtime=7.18 /proc/24176/stat : 24176 (java) S 24173 24173 1733 0 -1 4202496 99630 0 0 0 675 43 0 0 20 0 11 0 115985407 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140735122440736 140639193169525 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24176/statm: 263535 94350 2379 9 0 250885 0 [pid=24176/tid=24177] ppid=24173 vsize=1054140 CPUtime=3.11 /proc/24176/task/24177/stat : 24177 (java) S 24173 24173 1733 0 -1 4202560 47795 0 0 0 288 23 0 0 20 0 11 0 115985408 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639199703984 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 13 0 0 [pid=24176/tid=24178] ppid=24173 vsize=1054140 CPUtime=0.61 /proc/24176/task/24178/stat : 24178 (java) S 24173 24173 1733 0 -1 4202560 30210 0 0 0 53 8 0 0 20 0 11 0 115985410 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639092341888 140639193183819 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24179] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24179/stat : 24179 (java) S 24173 24173 1733 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 115985411 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639091288080 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24180] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24180/stat : 24180 (java) S 24173 24173 1733 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 115985411 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639090235296 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24181] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24181/stat : 24181 (java) S 24173 24173 1733 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639089183728 140639193191456 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24182] ppid=24173 vsize=1054140 CPUtime=1.55 /proc/24176/task/24182/stat : 24182 (java) R 24173 24173 1733 0 -1 4202560 12152 0 0 0 150 5 0 0 20 0 11 0 115985415 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639088130832 140639179870765 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24183] ppid=24173 vsize=1054140 CPUtime=1.77 /proc/24176/task/24183/stat : 24183 (java) R 24173 24173 1733 0 -1 4202560 8163 0 0 0 176 1 0 0 20 0 11 0 115985415 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639087078160 140639174784288 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24184] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24184/stat : 24184 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639086025936 140639193182932 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24185] ppid=24173 vsize=1054140 CPUtime=0 /proc/24176/task/24185/stat : 24185 (java) S 24173 24173 1733 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 115985415 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639084973440 140639193183819 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=24176/tid=24187] ppid=24173 vsize=1054140 CPUtime=0.1 /proc/24176/task/24187/stat : 24187 (java) R 24173 24173 1733 0 -1 4202560 9 0 0 0 6 4 0 0 20 0 11 0 115986129 1079439360 94350 18446744073709551615 4194304 4228012 140735122449712 140639082706304 140639193194797 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 7.18 Current children cumulated vsize (KiB) 1063312 Child status: 0 Real time (s): 7.95846 CPU time (s): 7.25245 CPU user time (s): 6.78842 CPU system time (s): 0.464029 CPU usage (%): 91.1289 Max. virtual memory (cumulated for all children) (KiB): 1064108 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.78842 system time used= 0.464029 maximum resident set size= 377436 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 100404 page faults= 0 swaps= 0 block input operations= 27176 block output operations= 288 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 756 involuntary context switches= 2320 runsolver used 0.012 second user time and 0.080005 second system time The end