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: /home/misc2010/bin/runsolver -s SIGUSR1 -M 1124 -C 290 -d 10 -w /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/rand986.sarge-etch-lenny-squeeze-sid.cudf.sarge-etch-lenny-squeeze-sid.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/icse/sarge-etch-lenny-squeeze-sid/rand986.sarge-etch-lenny-squeeze-sid.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/rand986.sarge-etch-lenny-squeeze-sid.cudf.sarge-etch-lenny-squeeze-sid.result Enforcing CPUTime limit (soft limit, will send signal-name then SIGKILL): 290 seconds Enforcing CPUTime limit (hard limit, will send SIGXCPU): 320 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.68 1.45 1.28 1/45 26140 /proc/meminfo: memFree=892204/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=3024 CPUtime=0 /proc/26140/stat : 26140 (runsolver) D 26139 26140 2059 34821 2220 4202560 72 0 0 0 0 0 0 0 25 0 1 0 876239137 3096576 93 1283457024 134512640 134586868 4290763984 18446744073709551615 4294960130 0 0 0 24578 18446744071564332000 0 0 17 0 0 0 0 /proc/26140/statm: 756 93 62 19 0 54 0 [startup+0.129465 s] /proc/loadavg: 1.68 1.45 1.28 1/45 26140 /proc/meminfo: memFree=892204/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+0.205445 s] /proc/loadavg: 1.68 1.45 1.28 1/45 26140 /proc/meminfo: memFree=892204/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+0.305458 s] /proc/loadavg: 1.68 1.45 1.28 1/45 26140 /proc/meminfo: memFree=892204/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+0.708322 s] /proc/loadavg: 1.68 1.45 1.28 1/45 26140 /proc/meminfo: memFree=892204/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 2572 [startup+1.51389 s] /proc/loadavg: 1.68 1.45 1.28 2/57 26163 /proc/meminfo: memFree=869316/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=867672 CPUtime=0.54 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 9488 0 108 0 51 3 0 0 22 0 10 0 876239141 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 216918 8304 1734 10 0 214008 0 [pid=26154/tid=26155] ppid=26142 vsize=867672 CPUtime=0.19 /proc/26154/task/26155/stat : 26155 (java) R 26142 26140 2059 34821 2220 4202560 4485 0 16 0 17 2 0 0 24 0 10 0 876239173 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=867672 CPUtime=0 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 17 0 10 0 876239182 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=867672 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 25 0 10 0 876239183 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=867672 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 7 0 0 0 0 0 0 0 25 0 10 0 876239183 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=867672 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876239194 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=867672 CPUtime=0.1 /proc/26154/task/26160/stat : 26160 (java) R 26142 26140 2059 34821 2220 4202560 702 0 2 0 10 0 0 0 17 0 10 0 876239194 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4151887911 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=867672 CPUtime=0.23 /proc/26154/task/26161/stat : 26161 (java) R 26142 26140 2059 34821 2220 4202560 3269 0 6 0 23 0 0 0 21 0 10 0 876239194 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4151785521 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=867672 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876239194 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=867672 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) S 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 17 0 10 0 876239194 888496128 8304 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 0.57 Current children cumulated vsize (KiB) 872820 [startup+3.10162 s] /proc/loadavg: 1.62 1.44 1.28 3/57 26163 /proc/meminfo: memFree=822340/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=871696 CPUtime=2.08 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 48179 0 108 0 194 14 0 0 22 0 10 0 876239141 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 217924 43951 1741 10 0 215014 0 [pid=26154/tid=26155] ppid=26142 vsize=871696 CPUtime=0.98 /proc/26154/task/26155/stat : 26155 (java) R 26142 26140 2059 34821 2220 4202560 38599 0 16 0 86 12 0 0 25 0 10 0 876239173 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4095778400 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=871696 CPUtime=0 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 10 0 876239182 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=871696 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 25 0 10 0 876239183 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=871696 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 7 0 0 0 0 0 0 0 25 0 10 0 876239183 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=871696 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876239194 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=871696 CPUtime=0.26 /proc/26154/task/26160/stat : 26160 (java) S 26142 26140 2059 34821 2220 4202560 1584 0 2 0 26 0 0 0 16 0 10 0 876239194 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=871696 CPUtime=0.83 /proc/26154/task/26161/stat : 26161 (java) S 26142 26140 2059 34821 2220 4202560 6964 0 6 0 81 2 0 0 16 0 10 0 876239194 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=871696 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876239194 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=871696 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) S 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 876239194 892616704 43951 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 2.11 Current children cumulated vsize (KiB) 876844 heavy processes: [startup+6.31633 s] /proc/loadavg: 1.62 1.44 1.28 2/57 26163 /proc/meminfo: memFree=588876/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=871888 CPUtime=5.06 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 91150 0 109 0 480 26 0 0 22 0 10 0 876239141 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 217972 84137 1769 10 0 215062 0 [pid=26154/tid=26155] ppid=26142 vsize=871888 CPUtime=2.23 /proc/26154/task/26155/stat : 26155 (java) R 26142 26140 2059 34821 2220 4202560 50432 0 16 0 204 19 0 0 25 0 10 0 876239173 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=871888 CPUtime=0.64 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 28211 0 0 0 61 3 0 0 16 0 10 0 876239182 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=871888 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 18 0 10 0 876239183 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=871888 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 10 0 876239183 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=871888 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876239194 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=871888 CPUtime=0.26 /proc/26154/task/26160/stat : 26160 (java) S 26142 26140 2059 34821 2220 4202560 1584 0 2 0 26 0 0 0 16 0 10 0 876239194 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=871888 CPUtime=1.92 /proc/26154/task/26161/stat : 26161 (java) S 26142 26140 2059 34821 2220 4202560 9894 0 7 0 188 4 0 0 15 0 10 0 876239194 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=871888 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 10 0 876239194 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=871888 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) S 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 10 0 876239194 892813312 84137 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.09 Current children cumulated vsize (KiB) 877036 Solver just ended. Dumping a history of the last processes samples [startup+6.40398 s] /proc/loadavg: 1.62 1.44 1.28 2/57 26163 /proc/meminfo: memFree=588876/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=872212 CPUtime=5.14 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 91166 0 109 0 488 26 0 0 22 0 11 0 876239141 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 218053 84153 1772 10 0 215143 0 [pid=26154/tid=26155] ppid=26142 vsize=872212 CPUtime=2.26 /proc/26154/task/26155/stat : 26155 (java) R 26142 26140 2059 34821 2220 4202560 50439 0 16 0 207 19 0 0 25 0 11 0 876239173 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=872212 CPUtime=0.64 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 28211 0 0 0 61 3 0 0 16 0 11 0 876239182 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=872212 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 18 0 11 0 876239183 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=872212 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876239183 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=872212 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876239194 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=872212 CPUtime=0.26 /proc/26154/task/26160/stat : 26160 (java) S 26142 26140 2059 34821 2220 4202560 1584 0 2 0 26 0 0 0 16 0 11 0 876239194 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=872212 CPUtime=1.97 /proc/26154/task/26161/stat : 26161 (java) S 26142 26140 2059 34821 2220 4202560 9903 0 7 0 193 4 0 0 15 0 11 0 876239194 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=872212 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876239194 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=872212 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) S 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 876239194 893145088 84153 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.17 Current children cumulated vsize (KiB) 877360 [startup+7.21182 s] /proc/loadavg: 1.57 1.43 1.28 2/58 26164 /proc/meminfo: memFree=531712/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=871388 CPUtime=5.94 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 91291 0 109 0 568 26 0 0 22 0 13 0 876239141 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 217847 83890 1790 10 0 214937 0 [pid=26154/tid=26155] ppid=26142 vsize=871388 CPUtime=2.58 /proc/26154/task/26155/stat : 26155 (java) R 26142 26140 2059 34821 2220 4202560 50446 0 16 0 239 19 0 0 25 0 13 0 876239173 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=871388 CPUtime=0.64 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 28211 0 0 0 61 3 0 0 15 0 13 0 876239182 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=871388 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 18 0 13 0 876239183 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=871388 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 13 0 876239183 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=871388 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 13 0 876239194 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=871388 CPUtime=0.26 /proc/26154/task/26160/stat : 26160 (java) S 26142 26140 2059 34821 2220 4202560 1584 0 2 0 26 0 0 0 16 0 13 0 876239194 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=871388 CPUtime=2.44 /proc/26154/task/26161/stat : 26161 (java) R 26142 26140 2059 34821 2220 4202560 10017 0 7 0 240 4 0 0 16 0 13 0 876239194 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4148217778 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=871388 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 13 0 876239194 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=871388 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) R 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 13 0 876239194 892301312 83890 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.97 Current children cumulated vsize (KiB) 876536 [startup+8.00593 s] /proc/loadavg: 1.57 1.43 1.28 1/58 26171 /proc/meminfo: memFree=531652/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=871712 CPUtime=6.39 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 91322 0 109 0 613 26 0 0 22 0 11 0 876239141 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 217928 83921 1792 10 0 215018 0 [pid=26154/tid=26155] ppid=26142 vsize=871712 CPUtime=2.97 /proc/26154/task/26155/stat : 26155 (java) S 26142 26140 2059 34821 2220 4202560 50454 0 16 0 278 19 0 0 25 0 11 0 876239173 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=871712 CPUtime=0.64 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 28211 0 0 0 61 3 0 0 16 0 11 0 876239182 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 18 0 11 0 876239183 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876239183 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876239194 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=871712 CPUtime=0.26 /proc/26154/task/26160/stat : 26160 (java) S 26142 26140 2059 34821 2220 4202560 1584 0 2 0 26 0 0 0 16 0 11 0 876239194 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=871712 CPUtime=2.51 /proc/26154/task/26161/stat : 26161 (java) S 26142 26140 2059 34821 2220 4202560 10028 0 7 0 247 4 0 0 15 0 11 0 876239194 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876239194 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) S 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 876239194 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26171] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26171/stat : 26171 (java) S 26142 26140 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 876239903 892633088 83921 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.42 Current children cumulated vsize (KiB) 876860 [startup+8.20895 s] /proc/loadavg: 1.57 1.43 1.28 1/58 26171 /proc/meminfo: memFree=531652/1048576 swapFree=2078744/2097144 [pid=26140] ppid=26139 vsize=2572 CPUtime=0 /proc/26140/stat : 26140 (p2cudf-paranoid) S 26139 26140 2059 34821 2220 4202496 376 0 0 0 0 0 0 0 25 0 1 0 876239137 2633728 273 1283457024 134512640 135304128 4293755040 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26140/statm: 643 273 233 194 0 29 0 [pid=26142] ppid=26140 vsize=2576 CPUtime=0.03 /proc/26142/stat : 26142 (p2cudf) S 26140 26140 2059 34821 2220 4202496 394 2999 0 1 0 0 2 1 25 0 1 0 876239138 2637824 283 1283457024 134512640 135304128 4286732800 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/26142/statm: 644 283 241 194 0 30 0 [pid=26154] ppid=26142 vsize=871712 CPUtime=6.43 /proc/26154/stat : 26154 (java) S 26142 26140 2059 34821 2220 4202496 91324 0 109 0 617 26 0 0 22 0 11 0 876239141 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/26154/statm: 217928 83923 1792 10 0 215018 0 [pid=26154/tid=26155] ppid=26142 vsize=871712 CPUtime=2.97 /proc/26154/task/26155/stat : 26155 (java) S 26142 26140 2059 34821 2220 4202560 50454 0 16 0 278 19 0 0 25 0 11 0 876239173 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26156] ppid=26142 vsize=871712 CPUtime=0.64 /proc/26154/task/26156/stat : 26156 (java) S 26142 26140 2059 34821 2220 4202560 28211 0 0 0 61 3 0 0 16 0 11 0 876239182 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 0 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26157] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26157/stat : 26157 (java) S 26142 26140 2059 34821 2220 4202560 5 0 1 0 0 0 0 0 18 0 11 0 876239183 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26158] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26158/stat : 26158 (java) S 26142 26140 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 18 0 11 0 876239183 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26159] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26159/stat : 26159 (java) S 26142 26140 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876239194 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26160] ppid=26142 vsize=871712 CPUtime=0.26 /proc/26154/task/26160/stat : 26160 (java) S 26142 26140 2059 34821 2220 4202560 1584 0 2 0 26 0 0 0 16 0 11 0 876239194 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26161] ppid=26142 vsize=871712 CPUtime=2.53 /proc/26154/task/26161/stat : 26161 (java) S 26142 26140 2059 34821 2220 4202560 10030 0 7 0 249 4 0 0 15 0 11 0 876239194 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26162] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26162/stat : 26162 (java) S 26142 26140 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 25 0 11 0 876239194 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26163] ppid=26142 vsize=871712 CPUtime=0 /proc/26154/task/26163/stat : 26163 (java) S 26142 26140 2059 34821 2220 4202560 3 0 0 0 0 0 0 0 15 0 11 0 876239194 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 18446612132680194816 0 0 -1 0 0 0 0 [pid=26154/tid=26171] ppid=26142 vsize=871712 CPUtime=0.02 /proc/26154/task/26171/stat : 26171 (java) R 26142 26140 2059 34821 2220 4202560 0 0 0 0 2 0 0 0 20 0 11 0 876239903 892633088 83923 1283457024 134512640 134550932 4289313552 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 6.46 Current children cumulated vsize (KiB) 876860 Child status: 0 Real time (s): 8.28838 CPU time (s): 6.53641 CPU user time (s): 6.21239 CPU system time (s): 0.32402 CPU usage (%): 78.8623 Max. virtual memory (cumulated for all children) (KiB): 881980 getrusage(RUSAGE_CHILDREN,...) data: user time used= 6.21239 system time used= 0.32402 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 95119 page faults= 110 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1576 involuntary context switches= 1036 runsolver used 0.004 second user time and 0.012 second system time The end