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/slowlink/upgrade/easy/rand393.cudf.log.runsolver /home/competition/p2cudf-full-1.15/p2cudf-full-1.15 /home/competition/data/upgrade/easy/rand393.cudf /tmp/misc2012/2012-09-02-22:42/full/p2cudf-full-1.15/slowlink/upgrade/easy/rand393.cudf.result -sum(changed,installedsize),-count(removed),-notuptodate(solution),-count(changed) 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 [startup+0 s] /proc/loadavg: 1.22 1.17 1.08 2/59 22803 /proc/meminfo: memFree=604172/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=3152 CPUtime=0 /proc/22803/stat : 22803 (runsolver) R 22802 1750 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 1 0 120894061 3227648 32 18446744073709551615 134512640 134586868 4289023296 4289021344 4151465008 0 0 16781316 24578 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.186994 s] /proc/loadavg: 1.22 1.17 1.08 2/59 22803 /proc/meminfo: memFree=604172/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+0.200377 s] /proc/loadavg: 1.22 1.17 1.08 2/59 22803 /proc/meminfo: memFree=604172/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+0.300407 s] /proc/loadavg: 1.22 1.17 1.08 2/59 22803 /proc/meminfo: memFree=604172/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+0.700259 s] /proc/loadavg: 1.22 1.17 1.08 2/59 22803 /proc/meminfo: memFree=604172/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 9168 [startup+1.50075 s] /proc/loadavg: 1.20 1.16 1.08 1/70 22815 /proc/meminfo: memFree=588956/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=953688 CPUtime=0.37 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 6211 0 24 0 32 5 0 0 20 0 10 0 120894062 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 238422 5843 2315 9 0 225772 0 [pid=22806/tid=22807] ppid=22803 vsize=953688 CPUtime=0.23 /proc/22806/task/22807/stat : 22807 (java) R 22803 22803 1750 0 -1 4202560 3593 0 12 0 20 3 0 0 20 0 10 0 120894074 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543777260440 140543674768687 0 4 0 16800975 0 0 0 -1 0 0 0 101 0 0 [pid=22806/tid=22808] ppid=22803 vsize=953688 CPUtime=0 /proc/22806/task/22808/stat : 22808 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120894088 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543669898368 140543770740299 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=953688 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120894098 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=953688 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 120894098 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22811] ppid=22803 vsize=953688 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120894113 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=953688 CPUtime=0.06 /proc/22806/task/22812/stat : 22812 (java) S 22803 22803 1750 0 -1 4202560 1153 0 0 0 6 0 0 0 20 0 10 0 120894113 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543665687568 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=953688 CPUtime=0.06 /proc/22806/task/22813/stat : 22813 (java) R 22803 22803 1750 0 -1 4202560 188 0 0 0 6 0 0 0 20 0 10 0 120894113 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543664634640 140543757416366 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=953688 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=953688 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 976576512 5843 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 0.37 Current children cumulated vsize (KiB) 962856 [startup+3.10075 s] /proc/loadavg: 1.20 1.16 1.08 4/70 22815 /proc/meminfo: memFree=553988/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1058536 CPUtime=1.95 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 38934 0 24 0 177 18 0 0 20 0 10 0 120894062 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 264634 35230 2349 9 0 251984 0 [pid=22806/tid=22807] ppid=22803 vsize=1058536 CPUtime=0.87 /proc/22806/task/22807/stat : 22807 (java) R 22803 22803 1750 0 -1 4202560 18696 0 12 0 78 9 0 0 20 0 10 0 120894074 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543777252000 140543675110041 0 4 0 16800975 0 0 0 -1 0 0 0 101 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1058536 CPUtime=0 /proc/22806/task/22808/stat : 22808 (java) S 22803 22803 1750 0 -1 4202560 3 0 0 0 0 0 0 0 20 0 10 0 120894088 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543669898368 140543770740299 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1058536 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 15 0 0 0 0 0 0 0 20 0 10 0 120894098 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1058536 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 10 0 120894098 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1058536 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120894113 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1058536 CPUtime=0.53 /proc/22806/task/22812/stat : 22812 (java) R 22803 22803 1750 0 -1 4202560 10749 0 0 0 48 5 0 0 20 0 10 0 120894113 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543665675144 140543755030724 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1058536 CPUtime=0.54 /proc/22806/task/22813/stat : 22813 (java) R 22803 22803 1750 0 -1 4202560 8210 0 0 0 52 2 0 0 20 0 10 0 120894113 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543664634640 140543756874946 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1058536 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1058536 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1083940864 35230 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.95 Current children cumulated vsize (KiB) 1067704 heavy processes: [startup+6.30083 s] /proc/loadavg: 1.20 1.16 1.08 2/70 22815 /proc/meminfo: memFree=223652/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1047644 CPUtime=5.08 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 105424 0 24 0 460 48 0 0 20 0 10 0 120894062 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 261911 98169 2370 9 0 249261 0 [pid=22806/tid=22807] ppid=22803 vsize=1047644 CPUtime=2.76 /proc/22806/task/22807/stat : 22807 (java) S 22803 22803 1750 0 -1 4202560 47384 0 12 0 252 24 0 0 20 0 10 0 120894074 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543777260112 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 104 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1047644 CPUtime=0.91 /proc/22806/task/22808/stat : 22808 (java) R 22803 22803 1750 0 -1 4202560 37470 0 0 0 77 14 0 0 20 0 10 0 120894088 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543669897744 140543756905257 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120894098 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 120894098 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1047644 CPUtime=0.66 /proc/22806/task/22812/stat : 22812 (java) S 22803 22803 1750 0 -1 4202560 10990 0 0 0 61 5 0 0 20 0 10 0 120894113 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543665687568 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1047644 CPUtime=0.7 /proc/22806/task/22813/stat : 22813 (java) S 22803 22803 1750 0 -1 4202560 8301 0 0 0 68 2 0 0 20 0 10 0 120894113 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543664634896 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 98169 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 5.08 Current children cumulated vsize (KiB) 1056812 Solver just ended. Dumping a history of the last processes samples [startup+6.4011 s] /proc/loadavg: 1.20 1.16 1.08 2/70 22815 /proc/meminfo: memFree=223652/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1047644 CPUtime=5.18 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 108875 0 24 0 468 50 0 0 20 0 10 0 120894062 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 261911 101620 2370 9 0 249261 0 [pid=22806/tid=22807] ppid=22803 vsize=1047644 CPUtime=2.76 /proc/22806/task/22807/stat : 22807 (java) S 22803 22803 1750 0 -1 4202560 47384 0 12 0 252 24 0 0 20 0 10 0 120894074 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543777260112 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 104 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1047644 CPUtime=1.01 /proc/22806/task/22808/stat : 22808 (java) R 22803 22803 1750 0 -1 4202560 40921 0 0 0 85 16 0 0 20 0 10 0 120894088 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543669897744 140543754849511 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120894098 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 120894098 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1047644 CPUtime=0.66 /proc/22806/task/22812/stat : 22812 (java) S 22803 22803 1750 0 -1 4202560 10990 0 0 0 61 5 0 0 20 0 10 0 120894113 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543665687568 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1047644 CPUtime=0.7 /proc/22806/task/22813/stat : 22813 (java) S 22803 22803 1750 0 -1 4202560 8301 0 0 0 68 2 0 0 20 0 10 0 120894113 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543664634896 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 101620 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 5.18 Current children cumulated vsize (KiB) 1056812 [startup+9.60082 s] /proc/loadavg: 1.18 1.16 1.08 4/70 22815 /proc/meminfo: memFree=128296/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1047644 CPUtime=8.16 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 121269 0 24 0 761 55 0 0 20 0 10 0 120894062 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 261911 114014 2375 9 0 249261 0 [pid=22806/tid=22807] ppid=22803 vsize=1047644 CPUtime=3.97 /proc/22806/task/22807/stat : 22807 (java) R 22803 22803 1750 0 -1 4202560 47685 0 12 0 371 26 0 0 20 0 10 0 120894074 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543777259776 140543674772304 0 4 0 16800975 0 0 0 -1 0 0 0 125 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1047644 CPUtime=1.31 /proc/22806/task/22808/stat : 22808 (java) S 22803 22803 1750 0 -1 4202560 52860 0 0 0 112 19 0 0 20 0 10 0 120894088 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543669898368 140543770740299 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 10 0 120894098 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 10 0 120894098 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1047644 CPUtime=1.36 /proc/22806/task/22812/stat : 22812 (java) R 22803 22803 1750 0 -1 4202560 11120 0 0 0 130 6 0 0 20 0 10 0 120894113 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543665687312 140543754554072 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1047644 CPUtime=1.47 /proc/22806/task/22813/stat : 22813 (java) R 22803 22803 1750 0 -1 4202560 8325 0 0 0 145 2 0 0 20 0 10 0 120894113 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543664634640 140543755005558 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1047644 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 10 0 120894113 1072787456 114014 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 8.16 Current children cumulated vsize (KiB) 1056812 [startup+11.2008 s] /proc/loadavg: 1.17 1.16 1.08 3/71 22816 /proc/meminfo: memFree=136852/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1038412 CPUtime=9.69 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 123496 0 24 0 912 57 0 0 20 0 11 0 120894062 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 259603 111775 2376 9 0 246953 0 [pid=22806/tid=22807] ppid=22803 vsize=1038412 CPUtime=4.6 /proc/22806/task/22807/stat : 22807 (java) R 22803 22803 1750 0 -1 4202560 47751 0 12 0 434 26 0 0 20 0 11 0 120894074 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543777259608 140543674681815 0 4 0 16800975 0 0 0 -1 0 0 0 130 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1038412 CPUtime=1.32 /proc/22806/task/22808/stat : 22808 (java) S 22803 22803 1750 0 -1 4202560 52860 0 0 0 112 20 0 0 20 0 11 0 120894088 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543669898368 140543770740299 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1038412 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120894098 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1038412 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 120894098 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1038412 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120894113 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1038412 CPUtime=1.8 /proc/22806/task/22812/stat : 22812 (java) R 22803 22803 1750 0 -1 4202560 13215 0 0 0 173 7 0 0 20 0 11 0 120894113 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543665687312 140543755002213 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1038412 CPUtime=1.94 /proc/22806/task/22813/stat : 22813 (java) R 22803 22803 1750 0 -1 4202560 8385 0 0 0 192 2 0 0 20 0 11 0 120894113 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543664634640 140543756575140 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1038412 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120894113 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1038412 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120894113 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22816] ppid=22803 vsize=1038412 CPUtime=0 /proc/22806/task/22816/stat : 22816 (java) S 22803 22803 1750 0 -1 4202560 6 0 0 0 0 0 0 0 20 0 11 0 120895155 1063333888 111775 18446744073709551615 4194304 4228012 140736258536816 140543661332320 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 9.69 Current children cumulated vsize (KiB) 1047580 [startup+12.0008 s] /proc/loadavg: 1.17 1.16 1.08 3/71 22816 /proc/meminfo: memFree=136852/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1039440 CPUtime=10.35 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 123555 0 24 0 977 58 0 0 20 0 11 0 120894062 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 259860 111832 2379 9 0 247210 0 [pid=22806/tid=22807] ppid=22803 vsize=1039440 CPUtime=4.8 /proc/22806/task/22807/stat : 22807 (java) S 22803 22803 1750 0 -1 4202560 47772 0 12 0 453 27 0 0 20 0 11 0 120894074 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543777260464 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 130 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1039440 CPUtime=1.32 /proc/22806/task/22808/stat : 22808 (java) S 22803 22803 1750 0 -1 4202560 52861 0 0 0 112 20 0 0 20 0 11 0 120894088 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543669898368 140543770740299 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120894098 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 120894098 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120894113 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1039440 CPUtime=2.04 /proc/22806/task/22812/stat : 22812 (java) S 22803 22803 1750 0 -1 4202560 13237 0 0 0 197 7 0 0 20 0 11 0 120894113 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543665687568 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1039440 CPUtime=2.15 /proc/22806/task/22813/stat : 22813 (java) S 22803 22803 1750 0 -1 4202560 8394 0 0 0 213 2 0 0 20 0 11 0 120894113 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543664634896 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120894113 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120894113 1064386560 111832 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 10.35 Current children cumulated vsize (KiB) 1048608 [startup+12.4008 s] /proc/loadavg: 1.17 1.16 1.08 1/71 22817 /proc/meminfo: memFree=136472/1022884 swapFree=0/0 [pid=22803] ppid=22802 vsize=9168 CPUtime=0 /proc/22803/stat : 22803 (p2cudf-full-1.1) S 22802 22803 1750 0 -1 4202496 455 291 0 0 0 0 0 0 20 0 1 0 120894061 9388032 331 18446744073709551615 4194304 5129932 140733557190832 140733557188976 140340873180254 0 65536 16781316 65538 0 0 0 17 0 0 0 0 0 0 /proc/22803/statm: 2292 331 280 229 0 52 0 [pid=22806] ppid=22803 vsize=1039440 CPUtime=10.45 /proc/22806/stat : 22806 (java) S 22803 22803 1750 0 -1 4202496 123558 0 24 0 984 61 0 0 20 0 11 0 120894062 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140736258527840 140543770726005 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 10 0 0 /proc/22806/statm: 259860 111835 2379 9 0 247210 0 [pid=22806/tid=22807] ppid=22803 vsize=1039440 CPUtime=4.8 /proc/22806/task/22807/stat : 22807 (java) S 22803 22803 1750 0 -1 4202560 47772 0 12 0 453 27 0 0 20 0 11 0 120894074 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543777260464 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 130 0 0 [pid=22806/tid=22808] ppid=22803 vsize=1039440 CPUtime=1.32 /proc/22806/task/22808/stat : 22808 (java) S 22803 22803 1750 0 -1 4202560 52861 0 0 0 112 20 0 0 20 0 11 0 120894088 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543669898368 140543770740299 0 0 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22809] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22809/stat : 22809 (java) S 22803 22803 1750 0 -1 4202560 16 0 0 0 0 0 0 0 20 0 11 0 120894098 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543668844560 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22810] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22810/stat : 22810 (java) S 22803 22803 1750 0 -1 4202560 8 0 0 0 0 0 0 0 20 0 11 0 120894098 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543667791776 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 1 0 0 [pid=22806/tid=22811] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22811/stat : 22811 (java) S 22803 22803 1750 0 -1 4202560 1 0 0 0 0 0 0 0 20 0 11 0 120894113 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543666740208 140543770747936 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22812] ppid=22803 vsize=1039440 CPUtime=2.05 /proc/22806/task/22812/stat : 22812 (java) S 22803 22803 1750 0 -1 4202560 13237 0 0 0 198 7 0 0 20 0 11 0 120894113 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543665687568 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22813] ppid=22803 vsize=1039440 CPUtime=2.16 /proc/22806/task/22813/stat : 22813 (java) S 22803 22803 1750 0 -1 4202560 8394 0 0 0 214 2 0 0 20 0 11 0 120894113 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543664634896 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22814] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22814/stat : 22814 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120894113 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543663582416 140543770739412 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22815] ppid=22803 vsize=1039440 CPUtime=0 /proc/22806/task/22815/stat : 22815 (java) S 22803 22803 1750 0 -1 4202560 0 0 0 0 0 0 0 0 20 0 11 0 120894113 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543662529920 140543770740299 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 [pid=22806/tid=22817] ppid=22803 vsize=1039440 CPUtime=0.08 /proc/22806/task/22817/stat : 22817 (java) R 22803 22803 1750 0 -1 4202560 9 0 0 0 4 4 0 0 20 0 11 0 120895240 1064386560 111835 18446744073709551615 4194304 4228012 140736258536816 140543660270784 140543674740160 0 4 0 16800975 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 10.45 Current children cumulated vsize (KiB) 1048608 Child status: 0 Real time (s): 12.4615 CPU time (s): 10.5287 CPU user time (s): 9.87262 CPU system time (s): 0.656041 CPU usage (%): 84.4894 Max. virtual memory (cumulated for all children) (KiB): 1067704 getrusage(RUSAGE_CHILDREN,...) data: user time used= 9.87262 system time used= 0.656041 maximum resident set size= 461332 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 124327 page faults= 24 swaps= 0 block input operations= 56256 block output operations= 176 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1388 involuntary context switches= 2934 runsolver used 0.052003 second user time and 0.100006 second system time The end