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 300 -d 10 -w /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/real/8680dd8a-8600-11e0-b285-00163e1e087d.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/real/8680dd8a-8600-11e0-b285-00163e1e087d.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/trendy-size/real/8680dd8a-8600-11e0-b285-00163e1e087d.cudf.result -count(removed),-sum(solution,installedsize),-notuptodate(solution),-unsat_recommends(solution),-count(new) Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 300 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 330 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.81 1.66 1.30 2/59 19015 /proc/meminfo: memFree=466484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9144 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) R 19014 19015 1750 0 -1 4202496 161 0 0 0 0 0 0 0 20 0 1 0 119844635 9363456 82 18446744073709551615 4194304 5129932 140735691456624 140735691456080 140732197399261 0 0 16781316 0 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2286 82 60 229 0 40 0 [startup+0.114244 s] /proc/loadavg: 1.81 1.66 1.30 2/59 19015 /proc/meminfo: memFree=466484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.200328 s] /proc/loadavg: 1.81 1.66 1.30 2/59 19015 /proc/meminfo: memFree=466484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.300273 s] /proc/loadavg: 1.81 1.66 1.30 2/59 19015 /proc/meminfo: memFree=466484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+0.700241 s] /proc/loadavg: 1.81 1.66 1.30 2/59 19015 /proc/meminfo: memFree=466484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9172 [startup+1.50076 s] /proc/loadavg: 1.81 1.66 1.30 3/70 19027 /proc/meminfo: memFree=428436/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1042096 CPUtime=1.45 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 27682 0 0 0 132 13 0 0 20 0 10 0 119844636 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 260524 24863 2349 9 0 247874 0 [pid=19018/tid=19019] ppid=19015 vsize=1042096 CPUtime=0.68 /proc/19018/task/19019/stat : 19019 (java) R 19015 19015 1750 0 -1 4202560 11838 0 0 0 60 8 0 0 20 0 10 0 119844637 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931914138272 139931811972524 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1042096 CPUtime=0 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 119844639 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1042096 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 119844640 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1042096 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 119844640 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1042096 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1042096 CPUtime=0.37 /proc/19018/task/19024/stat : 19024 (java) R 19015 19015 1750 0 -1 4202560 7272 0 0 0 35 2 0 0 20 0 10 0 119844643 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931802557832 139931900190131 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1042096 CPUtime=0.38 /proc/19018/task/19025/stat : 19025 (java) R 19015 19015 1750 0 -1 4202560 7281 0 0 0 35 3 0 0 20 0 10 0 119844643 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931801506632 139931893278962 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1042096 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1042096 CPUtime=0 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119844643 1067106304 24863 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.45 Current children cumulated vsize (KiB) 1051268 [startup+3.10079 s] /proc/loadavg: 1.91 1.68 1.31 4/70 19027 /proc/meminfo: memFree=333948/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1045932 CPUtime=3.04 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 76770 0 0 0 268 36 0 0 20 0 10 0 119844636 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 261483 72349 2369 9 0 248833 0 [pid=19018/tid=19019] ppid=19015 vsize=1045932 CPUtime=1.42 /proc/19018/task/19019/stat : 19019 (java) R 19015 19015 1750 0 -1 4202560 47428 0 0 0 116 26 0 0 20 0 10 0 119844637 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931914138304 139931811972231 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1045932 CPUtime=0.18 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 8371 0 0 0 14 4 0 0 20 0 10 0 119844639 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1045932 CPUtime=0.75 /proc/19018/task/19024/stat : 19024 (java) S 19015 19015 1750 0 -1 4202560 10416 0 0 0 72 3 0 0 20 0 10 0 119844643 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931802573840 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1045932 CPUtime=0.66 /proc/19018/task/19025/stat : 19025 (java) S 19015 19015 1750 0 -1 4202560 9263 0 0 0 63 3 0 0 20 0 10 0 119844643 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931801521168 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 72349 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.04 Current children cumulated vsize (KiB) 1055104 [startup+6.3008 s] /proc/loadavg: 1.91 1.68 1.31 3/70 19027 /proc/meminfo: memFree=50484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1045932 CPUtime=6.19 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 107335 0 0 0 569 50 0 0 20 0 10 0 119844636 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 261483 101275 2372 9 0 248833 0 [pid=19018/tid=19019] ppid=19015 vsize=1045932 CPUtime=3.28 /proc/19018/task/19019/stat : 19019 (java) R 19015 19015 1750 0 -1 4202560 47496 0 0 0 301 27 0 0 20 0 10 0 119844637 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931914145528 139931894380951 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1045932 CPUtime=0.92 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 38772 0 0 0 76 16 0 0 20 0 10 0 119844639 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1045932 CPUtime=1.01 /proc/19018/task/19024/stat : 19024 (java) R 19015 19015 1750 0 -1 4202560 10431 0 0 0 98 3 0 0 20 0 10 0 119844643 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931802573584 139931890531094 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1045932 CPUtime=0.93 /proc/19018/task/19025/stat : 19025 (java) R 19015 19015 1750 0 -1 4202560 9344 0 0 0 90 3 0 0 20 0 10 0 119844643 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931801509832 139931900856289 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 101275 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.19 Current children cumulated vsize (KiB) 1055104 Solver just ended. Dumping a history of the last processes samples [startup+6.40099 s] /proc/loadavg: 1.91 1.68 1.31 3/70 19027 /proc/meminfo: memFree=50484/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1045932 CPUtime=6.29 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 107340 0 0 0 579 50 0 0 20 0 10 0 119844636 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 261483 101280 2372 9 0 248833 0 [pid=19018/tid=19019] ppid=19015 vsize=1045932 CPUtime=3.32 /proc/19018/task/19019/stat : 19019 (java) R 19015 19015 1750 0 -1 4202560 47496 0 0 0 305 27 0 0 20 0 10 0 119844637 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931914145528 139931893748977 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1045932 CPUtime=0.92 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 38772 0 0 0 76 16 0 0 20 0 10 0 119844639 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1045932 CPUtime=1.04 /proc/19018/task/19024/stat : 19024 (java) R 19015 19015 1750 0 -1 4202560 10432 0 0 0 101 3 0 0 20 0 10 0 119844643 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931802573584 139931890452046 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1045932 CPUtime=0.97 /proc/19018/task/19025/stat : 19025 (java) R 19015 19015 1750 0 -1 4202560 9348 0 0 0 94 3 0 0 20 0 10 0 119844643 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931801520912 139931894119639 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 101280 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 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) 1055104 [startup+9.60079 s] /proc/loadavg: 2.00 1.70 1.31 4/70 19027 /proc/meminfo: memFree=13656/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1045932 CPUtime=9.44 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 116507 0 0 0 892 52 0 0 20 0 10 0 119844636 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 261483 110447 2376 9 0 248833 0 [pid=19018/tid=19019] ppid=19015 vsize=1045932 CPUtime=4.37 /proc/19018/task/19019/stat : 19019 (java) R 19015 19015 1750 0 -1 4202560 47733 0 0 0 410 27 0 0 20 0 10 0 119844637 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931914145656 139931892121307 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1045932 CPUtime=1.18 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 47507 0 0 0 100 18 0 0 20 0 10 0 119844639 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 10 0 119844640 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1045932 CPUtime=1.95 /proc/19018/task/19024/stat : 19024 (java) R 19015 19015 1750 0 -1 4202560 10488 0 0 0 191 4 0 0 20 0 10 0 119844643 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931802573584 139931894299238 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1045932 CPUtime=1.9 /proc/19018/task/19025/stat : 19025 (java) R 19015 19015 1750 0 -1 4202560 9487 0 0 0 187 3 0 0 20 0 10 0 119844643 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931801520912 139931894642047 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1045932 CPUtime=0 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 119844643 1071034368 110447 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 9.44 Current children cumulated vsize (KiB) 1055104 [startup+11.2008 s] /proc/loadavg: 2.00 1.70 1.31 4/71 19028 /proc/meminfo: memFree=15260/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1047988 CPUtime=11.02 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 116660 0 0 0 1049 53 0 0 20 0 11 0 119844636 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 261997 109940 2379 9 0 249347 0 [pid=19018/tid=19019] ppid=19015 vsize=1047988 CPUtime=5.03 /proc/19018/task/19019/stat : 19019 (java) S 19015 19015 1750 0 -1 4202560 47826 0 0 0 476 27 0 0 20 0 11 0 119844637 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931914146736 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1047988 CPUtime=1.18 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 47508 0 0 0 100 18 0 0 20 0 11 0 119844639 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119844640 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119844640 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119844643 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1047988 CPUtime=2.41 /proc/19018/task/19024/stat : 19024 (java) R 19015 19015 1750 0 -1 4202560 10527 0 0 0 237 4 0 0 20 0 11 0 119844643 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931802573584 139931893501036 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1047988 CPUtime=2.34 /proc/19018/task/19025/stat : 19025 (java) R 19015 19015 1750 0 -1 4202560 9495 0 0 0 231 3 0 0 20 0 11 0 119844643 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931801520912 139931891886323 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119844643 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1047988 CPUtime=0.01 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 1 0 0 0 20 0 11 0 119844643 1073139712 109940 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 11.02 Current children cumulated vsize (KiB) 1057160 [startup+11.6008 s] /proc/loadavg: 2.00 1.70 1.31 4/71 19028 /proc/meminfo: memFree=15260/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=1047988 CPUtime=11.21 /proc/19018/stat : 19018 (java) S 19015 19015 1750 0 -1 4202496 116683 0 0 0 1068 53 0 0 20 0 11 0 119844636 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 140734112946384 139931907612277 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 261997 109963 2380 9 0 249347 0 [pid=19018/tid=19019] ppid=19015 vsize=1047988 CPUtime=5.03 /proc/19018/task/19019/stat : 19019 (java) S 19015 19015 1750 0 -1 4202560 47826 0 0 0 476 27 0 0 20 0 11 0 119844637 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931914146736 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 2 0 0 [pid=19018/tid=19020] ppid=19015 vsize=1047988 CPUtime=1.18 /proc/19018/task/19020/stat : 19020 (java) S 19015 19015 1750 0 -1 4202560 47508 0 0 0 100 18 0 0 20 0 11 0 119844639 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931806784640 139931907626571 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19021] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19021/stat : 19021 (java) S 19015 19015 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 119844640 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931805730832 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19022] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19022/stat : 19022 (java) S 19015 19015 1750 0 -1 4202560 9 0 0 0 0 0 0 0 20 0 11 0 119844640 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931804678048 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19023] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19023/stat : 19023 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119844643 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931803626480 139931907634208 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19024] ppid=19015 vsize=1047988 CPUtime=2.5 /proc/19018/task/19024/stat : 19024 (java) S 19015 19015 1750 0 -1 4202560 10537 0 0 0 246 4 0 0 20 0 11 0 119844643 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931802573840 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19025] ppid=19015 vsize=1047988 CPUtime=2.45 /proc/19018/task/19025/stat : 19025 (java) S 19015 19015 1750 0 -1 4202560 9508 0 0 0 242 3 0 0 20 0 11 0 119844643 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931801521168 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19026] ppid=19015 vsize=1047988 CPUtime=0 /proc/19018/task/19026/stat : 19026 (java) S 19015 19015 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 119844643 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931800468688 139931907625684 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=19018/tid=19027] ppid=19015 vsize=1047988 CPUtime=0.01 /proc/19018/task/19027/stat : 19027 (java) S 19015 19015 1750 0 -1 4202560 1 0 0 0 1 0 0 0 20 0 11 0 119844643 1073139712 109963 18446744073709551615 4194304 4228012 140734112955360 139931799416192 139931907626571 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 11.21 Current children cumulated vsize (KiB) 1057160 [startup+12.0105 s] /proc/loadavg: 2.00 1.70 1.31 4/71 19028 /proc/meminfo: memFree=15260/1022884 swapFree=0/0 [pid=19015] ppid=19014 vsize=9172 CPUtime=0 /proc/19015/stat : 19015 (p2cudf-full-1.1) S 19014 19015 1750 0 -1 4202496 457 293 0 0 0 0 0 0 20 0 1 0 119844635 9392128 332 18446744073709551615 4194304 5129932 140735691456624 140735691454768 140732189975646 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/19015/statm: 2293 332 280 229 0 53 0 [pid=19018] ppid=19015 vsize=0 CPUtime=11.53 /proc/19018/stat : 19018 (java) Z 19015 19015 1750 0 -1 4203532 116706 0 0 0 1093 60 0 0 20 0 2 0 119844636 0 0 18446744073709551615 0 0 0 0 0 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/19018/statm: 0 0 0 0 0 0 0 Current children cumulated CPU time (s) 11.53 Current children cumulated vsize (KiB) 9172 Child status: 0 Real time (s): 12.0332 CPU time (s): 11.5687 CPU user time (s): 10.9407 CPU system time (s): 0.628039 CPU usage (%): 96.1401 Max. virtual memory (cumulated for all children) (KiB): 1059108 getrusage(RUSAGE_CHILDREN,...) data: user time used= 10.9407 system time used= 0.628039 maximum resident set size= 441892 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 117469 page faults= 0 swaps= 0 block input operations= 38344 block output operations= 368 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1003 involuntary context switches= 3357 runsolver used 0.048003 second user time and 0.092005 second system time The end