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 30 -d 5 -w /tmp/misc2012/2012-09-02-16:34/paranoid/p2cudf-paranoid-1.15/paranoid/real/301cbe92-a79c-11e0-9181-00163e1e087d.cudf.log.runsolver /home/competition/p2cudf-paranoid-1.15/p2cudf-paranoid-1.15 /home/competition/data/real/301cbe92-a79c-11e0-9181-00163e1e087d.cudf /tmp/misc2012/2012-09-02-16:34/paranoid/p2cudf-paranoid-1.15/paranoid/real/301cbe92-a79c-11e0-9181-00163e1e087d.cudf.result -count(removed),-count(changed) Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 30 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 60 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: 1.50 1.32 0.76 2/61 5525 /proc/meminfo: memFree=426868/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=3152 CPUtime=0 /proc/5525/stat : 5525 (runsolver) R 5524 32685 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 36690128 3227648 32 18446744073709551615 134512640 134586868 4293041296 4293039344 4151309360 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.188163 s] /proc/loadavg: 1.50 1.32 0.76 2/61 5525 /proc/meminfo: memFree=426868/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.200261 s] /proc/loadavg: 1.50 1.32 0.76 2/61 5525 /proc/meminfo: memFree=426868/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.300254 s] /proc/loadavg: 1.50 1.32 0.76 2/61 5525 /proc/meminfo: memFree=426868/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.700202 s] /proc/loadavg: 1.50 1.32 0.76 2/61 5525 /proc/meminfo: memFree=426868/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+1.50056 s] /proc/loadavg: 1.50 1.32 0.76 4/72 5537 /proc/meminfo: memFree=317776/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1044252 CPUtime=1.47 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 42490 0 0 0 132 15 0 0 20 0 10 0 36690129 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261063 37346 2347 9 0 248413 0 [pid=5528/tid=5529] ppid=5525 vsize=1044252 CPUtime=0.61 /proc/5528/task/5529/stat : 5529 (java) R 5525 5525 32685 0 -1 4202560 22587 0 0 0 54 7 0 0 20 0 10 0 36690129 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140591093781184 140590991660619 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5530/stat : 5530 (java) S 5525 5525 32685 0 -1 4202560 4 0 0 0 0 0 0 0 20 0 10 0 36690131 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590986427520 140591087269451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 36690132 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 36690132 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 36690134 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1044252 CPUtime=0.41 /proc/5528/task/5534/stat : 5534 (java) S 5525 5525 32685 0 -1 4202560 9081 0 0 0 37 4 0 0 20 0 10 0 36690134 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590982216720 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1044252 CPUtime=0.43 /proc/5528/task/5535/stat : 5535 (java) R 5525 5525 32685 0 -1 4202560 9530 0 0 0 40 3 0 0 20 0 10 0 36690134 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590981163792 140591070404262 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1069314048 37346 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 1053424 [startup+3.10056 s] /proc/loadavg: 1.46 1.31 0.76 2/72 5537 /proc/meminfo: memFree=132644/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1044252 CPUtime=3.05 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 99074 0 0 0 273 32 0 0 20 0 10 0 36690129 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261063 93930 2369 9 0 248413 0 [pid=5528/tid=5529] ppid=5525 vsize=1044252 CPUtime=1.62 /proc/5528/task/5529/stat : 5529 (java) R 5525 5525 32685 0 -1 4202560 47415 0 0 0 147 15 0 0 20 0 10 0 36690129 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140591093789208 140591087279706 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1044252 CPUtime=0.51 /proc/5528/task/5530/stat : 5530 (java) R 5525 5525 32685 0 -1 4202560 31753 0 0 0 42 9 0 0 20 0 10 0 36690131 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590986426896 140591071582619 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 36690132 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 36690132 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 36690134 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1044252 CPUtime=0.44 /proc/5528/task/5534/stat : 5534 (java) S 5525 5525 32685 0 -1 4202560 9085 0 0 0 40 4 0 0 20 0 10 0 36690134 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590982216720 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1044252 CPUtime=0.46 /proc/5528/task/5535/stat : 5535 (java) S 5525 5525 32685 0 -1 4202560 9530 0 0 0 43 3 0 0 20 0 10 0 36690134 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590981164048 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1044252 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1069314048 93930 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.05 Current children cumulated vsize (KiB) 1053424 [startup+6.30091 s] /proc/loadavg: 1.46 1.31 0.76 4/72 5537 /proc/meminfo: memFree=9084/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1045952 CPUtime=6.12 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 118760 0 0 0 574 38 0 0 20 0 10 0 36690129 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261488 113167 2375 9 0 248838 0 [pid=5528/tid=5529] ppid=5525 vsize=1045952 CPUtime=2.87 /proc/5528/task/5529/stat : 5529 (java) R 5525 5525 32685 0 -1 4202560 47734 0 0 0 271 16 0 0 20 0 10 0 36690129 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140591093789384 140591087279706 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1045952 CPUtime=0.96 /proc/5528/task/5530/stat : 5530 (java) S 5525 5525 32685 0 -1 4202560 50012 0 0 0 82 14 0 0 20 0 10 0 36690131 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590986427520 140591087269451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 36690132 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 36690132 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 36690134 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1045952 CPUtime=1.08 /proc/5528/task/5534/stat : 5534 (java) S 5525 5525 32685 0 -1 4202560 9178 0 0 0 104 4 0 0 20 0 10 0 36690134 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590982216720 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1045952 CPUtime=1.18 /proc/5528/task/5535/stat : 5535 (java) S 5525 5525 32685 0 -1 4202560 10545 0 0 0 115 3 0 0 20 0 10 0 36690134 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590981164048 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1071054848 113167 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.12 Current children cumulated vsize (KiB) 1055124 Solver just ended. Dumping a history of the last processes samples [startup+6.40079 s] /proc/loadavg: 1.46 1.31 0.76 4/72 5537 /proc/meminfo: memFree=9084/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1045952 CPUtime=6.22 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 118764 0 0 0 583 39 0 0 20 0 10 0 36690129 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261488 113171 2375 9 0 248838 0 [pid=5528/tid=5529] ppid=5525 vsize=1045952 CPUtime=2.94 /proc/5528/task/5529/stat : 5529 (java) R 5525 5525 32685 0 -1 4202560 47738 0 0 0 277 17 0 0 20 0 10 0 36690129 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140591093789080 140591087279706 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1045952 CPUtime=0.96 /proc/5528/task/5530/stat : 5530 (java) S 5525 5525 32685 0 -1 4202560 50012 0 0 0 82 14 0 0 20 0 10 0 36690131 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590986427520 140591087269451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 36690132 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 36690132 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 36690134 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1045952 CPUtime=1.1 /proc/5528/task/5534/stat : 5534 (java) R 5525 5525 32685 0 -1 4202560 9178 0 0 0 106 4 0 0 20 0 10 0 36690134 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590982216464 140591069505293 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1045952 CPUtime=1.2 /proc/5528/task/5535/stat : 5535 (java) R 5525 5525 32685 0 -1 4202560 10545 0 0 0 117 3 0 0 20 0 10 0 36690134 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590981163792 140591074569198 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1045952 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 36690134 1071054848 113171 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.22 Current children cumulated vsize (KiB) 1055124 [startup+6.80083 s] /proc/loadavg: 1.46 1.31 0.76 4/72 5537 /proc/meminfo: memFree=11068/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1046980 CPUtime=6.46 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 118851 0 0 0 607 39 0 0 20 0 11 0 36690129 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261745 113258 2376 9 0 249095 0 [pid=5528/tid=5529] ppid=5525 vsize=1046980 CPUtime=3.04 /proc/5528/task/5529/stat : 5529 (java) S 5525 5525 32685 0 -1 4202560 47774 0 0 0 287 17 0 0 20 0 11 0 36690129 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140591093789616 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1046980 CPUtime=0.96 /proc/5528/task/5530/stat : 5530 (java) S 5525 5525 32685 0 -1 4202560 50012 0 0 0 82 14 0 0 20 0 11 0 36690131 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590986427520 140591087269451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 36690132 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 36690132 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1046980 CPUtime=1.16 /proc/5528/task/5534/stat : 5534 (java) S 5525 5525 32685 0 -1 4202560 9214 0 0 0 112 4 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590982216720 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1046980 CPUtime=1.27 /proc/5528/task/5535/stat : 5535 (java) S 5525 5525 32685 0 -1 4202560 10554 0 0 0 124 3 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590981164048 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.46 Current children cumulated vsize (KiB) 1056152 [startup+7.00081 s] /proc/loadavg: 1.46 1.31 0.76 4/72 5537 /proc/meminfo: memFree=11068/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1046980 CPUtime=6.46 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 118851 0 0 0 607 39 0 0 20 0 11 0 36690129 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261745 113258 2376 9 0 249095 0 [pid=5528/tid=5529] ppid=5525 vsize=1046980 CPUtime=3.04 /proc/5528/task/5529/stat : 5529 (java) S 5525 5525 32685 0 -1 4202560 47774 0 0 0 287 17 0 0 20 0 11 0 36690129 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140591093789616 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1046980 CPUtime=0.96 /proc/5528/task/5530/stat : 5530 (java) S 5525 5525 32685 0 -1 4202560 50012 0 0 0 82 14 0 0 20 0 11 0 36690131 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590986427520 140591087269451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 36690132 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 36690132 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1046980 CPUtime=1.16 /proc/5528/task/5534/stat : 5534 (java) S 5525 5525 32685 0 -1 4202560 9214 0 0 0 112 4 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590982216720 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1046980 CPUtime=1.27 /proc/5528/task/5535/stat : 5535 (java) S 5525 5525 32685 0 -1 4202560 10554 0 0 0 124 3 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590981164048 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.46 Current children cumulated vsize (KiB) 1056152 [startup+7.10083 s] /proc/loadavg: 1.46 1.31 0.76 4/72 5537 /proc/meminfo: memFree=11068/1022884 swapFree=0/0 [pid=5525] ppid=5524 vsize=9172 CPUtime=0 /proc/5525/stat : 5525 (p2cudf-paranoid) S 5524 5525 32685 0 -1 4202496 461 292 0 0 0 0 0 0 20 0 1 0 36690128 9392128 332 18446744073709551615 4194304 5129932 140736504662928 140736504661072 140499304567902 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/5525/statm: 2293 332 280 229 0 53 0 [pid=5528] ppid=5525 vsize=1046980 CPUtime=6.46 /proc/5528/stat : 5528 (java) S 5525 5525 32685 0 -1 4202496 118851 0 0 0 607 39 0 0 20 0 11 0 36690129 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140734299005120 140591087255157 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/5528/statm: 261745 113258 2376 9 0 249095 0 [pid=5528/tid=5529] ppid=5525 vsize=1046980 CPUtime=3.04 /proc/5528/task/5529/stat : 5529 (java) S 5525 5525 32685 0 -1 4202560 47774 0 0 0 287 17 0 0 20 0 11 0 36690129 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140591093789616 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=5528/tid=5530] ppid=5525 vsize=1046980 CPUtime=0.96 /proc/5528/task/5530/stat : 5530 (java) S 5525 5525 32685 0 -1 4202560 50012 0 0 0 82 14 0 0 20 0 11 0 36690131 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590986427520 140591087269451 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5531] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5531/stat : 5531 (java) S 5525 5525 32685 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 36690132 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590985373712 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5532] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5532/stat : 5532 (java) S 5525 5525 32685 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 36690132 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590984320928 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5533] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5533/stat : 5533 (java) S 5525 5525 32685 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590983269360 140591087277088 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5534] ppid=5525 vsize=1046980 CPUtime=1.16 /proc/5528/task/5534/stat : 5534 (java) S 5525 5525 32685 0 -1 4202560 9214 0 0 0 112 4 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590982216720 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5535] ppid=5525 vsize=1046980 CPUtime=1.27 /proc/5528/task/5535/stat : 5535 (java) S 5525 5525 32685 0 -1 4202560 10554 0 0 0 124 3 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590981164048 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5536] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5536/stat : 5536 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590980111568 140591087268564 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=5528/tid=5537] ppid=5525 vsize=1046980 CPUtime=0 /proc/5528/task/5537/stat : 5537 (java) S 5525 5525 32685 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 36690134 1072107520 113258 18446744073709551615 4194304 4228012 140734299014096 140590979059072 140591087269451 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.46 Current children cumulated vsize (KiB) 1056152 Child status: 0 Real time (s): 7.19431 CPU time (s): 6.52441 CPU user time (s): 6.10438 CPU system time (s): 0.420026 CPU usage (%): 90.6884 Max. virtual memory (cumulated for all children) (KiB): 1058400 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.10438 system time used= 0.420026 maximum resident set size= 453712 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 119628 page faults= 0 swaps= 0 block input operations= 41408 block output operations= 80 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 684 involuntary context switches= 1443 runsolver used 0.020001 second user time and 0.048003 second system time The end